Telepathy sunshine does not connect - failed to create connection error

[Dawid Wróbel]

I configured a new gadugadu account today and on attempt to connect I get the following errors:

sunshine/connection-ERROR: 31.03.2012 11:17:14.977991: Failed to create Connection
sunshine/sunshine-INFO: 31.03.2012 11:17:19.966427: No connection received - quitting
sunshine/connectionmanager-INFO: 31.03.2012 11:17:19.966675: Connection manager quitting

I tried checking "Use SSL" or "Use specified server" options, but it's still the same. I have no idea where to look further.
Password is OK, I double checked.

ProblemType: Bug
DistroRelease: Ubuntu 12.04
Package: telepathy-sunshine 0.1.8-2ubuntu1
ProcVersionSignature: Ubuntu 3.2.0-21.34-generic 3.2.13
Uname: Linux 3.2.0-21-generic x86_64
ApportVersion: 2.0-0ubuntu2
Architecture: amd64
Date: Sat Mar 31 11:24:54 2012
InstallationMedia: Kubuntu 10.10 "Maverick Meerkat" - Beta amd64 (20100902.1)
PackageArchitecture: all
SourcePackage: telepathy-sunshine
UpgradeStatus: Upgraded to precise on 2012-03-07 (23 days ago)

[Dawid Wróbel (dawidw)]

I did a dmesg researching something else and I noticed telepathy-sunshine related apparmor errors:

[15886.616933] type=1400 audit(1333190248.908:629): apparmor="DENIED" operation="open" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/home/cromo/.Xauthority" pid=30663 comm="telepathy-sunsh" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
[15886.712798] type=1400 audit(1333190249.004:630): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/__init__.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
[15886.713515] type=1400 audit(1333190249.004:631): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/connection_manager.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
[15886.718802] type=1400 audit(1333190249.008:632): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/connection.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
[15886.720544] type=1400 audit(1333190249.012:633): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/util/__init__.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
[15886.721604] type=1400 audit(1333190249.012:634): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/util/config.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
[15886.721790] type=1400 audit(1333190249.012:635): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/lqsoft/__init__.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
[15886.721863] type=1400 audit(1333190249.012:636): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/lqsoft/pygadu/__init__.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
[15886.732606] type=1400 audit(1333190249.024:637): apparmor="DENIED" operation="mknod" parent=30662 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/lqsoft/pygadu/twisted_protocol.pyc" pid=30663 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000

[Dawid Wróbel (dawidw)]

This may be a duplicate of #878048

[Launchpad Janitor (janitor)]

Status changed to 'Confirmed' because the bug affects multiple users.

[Jamie Strandboge (jdstrand)]

Dawid, thanks for this information, but the denials are actually just noise-- apparmor is blocking write access to *.pyc files in /usr/lib, which would be denied to your user any way. Can you try creating the gadugadu account again, and then afterwards attach the output of the following command:
$ grep apparmor /var/log/kern.log

[Dawid Wróbel (dawidw)]

This is the result of the kern.log grep when attempting to add new gadugadu account:

"May 3 18:18:56 dell kernel: [30390.457304] type=1400 audit(1336061936.317:321): apparmor="DENIED" operation="open" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/home/cromo/.Xauthority" pid=9088 comm="telepathy-sunsh" requested_mask="r" denied_mask="r" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.532841] type=1400 audit(1336061936.393:322): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/__init__.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.533971] type=1400 audit(1336061936.393:323): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/connection_manager.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.539749] type=1400 audit(1336061936.397:324): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/connection.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.543269] type=1400 audit(1336061936.401:325): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/util/__init__.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.544635] type=1400 audit(1336061936.405:326): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/util/config.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.545025] type=1400 audit(1336061936.405:327): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/lqsoft/__init__.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.545264] type=1400 audit(1336061936.405:328): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/lqsoft/pygadu/__init__.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.548511] type=1400 audit(1336061936.409:329): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-packages/sunshine/lqsoft/pygadu/twisted_protocol.pyc" pid=9088 comm="telepathy-sunsh" requested_mask="c" denied_mask="c" fsuid=1000 ouid=1000
May 3 18:18:56 dell kernel: [30390.548882] type=1400 audit(1336061936.409:330): apparmor="DENIED" operation="mknod" parent=9087 profile="/usr/lib/telepathy/telepathy-*" name="/usr/lib/python2.7/dist-pa...

[Jamie Strandboge (jdstrand)]

Dawid, I'm betting that is also just noise. Can you:
* add the following to /etc/apparmor.d/local/usr.lib.telepathy:
  owner @{HOME}/.Xauthority r,
  deny /usr/lib/**/sunshine/**.pyc w,
* run the following command:
$ sudo apparmor_parser -r /etc/apparmor.d/usr.lib.telepathy
* try to create the user again and report back