krunner freezes

[Manuel López-Ibáñez]

Krunner becomes unresponsive from time to time. This is the output of strace:

Process 2340 attached - interrupt to quit
restart_syscall(<... resuming interrupted call ...>) = 1
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\224\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13687, 607150004}) = 0
poll([{fd=5, events=POLLIN}], 1, -12955917) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\226\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13688, 318098812}) = 0
poll([{fd=5, events=POLLIN}], 1, -12956628) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\230\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13688, 893933285}) = 0
poll([{fd=5, events=POLLIN}], 1, -12957203) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\232\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13689, 87208710}) = 0
poll([{fd=5, events=POLLIN}], 1, -12957397) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\234\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13689, 757360004}) = 0
poll([{fd=5, events=POLLIN}], 1, -12958066) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\236\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13691, 181942944}) = 0
poll([{fd=5, events=POLLIN}], 1, -12959492) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\240\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13691, 965868432}) = 0
poll([{fd=5, events=POLLIN}], 1, -12960275) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\242\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13692, 589658544}) = 0
poll([{fd=5, events=POLLIN}], 1, -12960900) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\244\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13693, 718533882}) = 0
poll([{fd=5, events=POLLIN}], 1, -12962027) = 1 ([{fd=5, revents=POLLIN}])
recvmsg(5, {msg_name(0)=NULL, msg_iov(1)=[{"l\4\1\1(\0\0\0\246\3\0\0\205\0\0\0\1\1o\0\22\0\0\0/compone"..., 2048}], msg_controllen=0, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 192
recvmsg(5, 0xbfb64b98, MSG_CMSG_CLOEXEC) = -1 EAGAIN (Resource temporarily unavailable)
clock_gettime(CLOCK_MONOTONIC, {13694, 102009604}) = 0
poll([{fd=5, events=POLLIN}], 1, -12962412

The only work-around I found is to kill it completely and restart. Then, it works fine.

ProblemType: Bug
DistroRelease: Ubuntu 12.04
Package: kde-workspace-bin 4:4.8.5-0ubuntu0.2
ProcVersionSignature: Ubuntu 3.2.0-33.52-generic-pae 3.2.31
Uname: Linux 3.2.0-33-generic-pae i686
NonfreeKernelModules: fglrx
ApportVersion: 2.0.1-0ubuntu15
Architecture: i386
Date: Fri Nov 23 15:08:37 2012
EcryptfsInUse: Yes
ExecutablePath: /usr/bin/krunner
InstallationMedia: Kubuntu 12.04 LTS "Precise Pangolin" - Release i386 (20120423)
MarkForUpload: True
ProcEnviron:
 PATH=(custom, user)
 LANG=en_US.UTF-8
 SHELL=/bin/bash
SourcePackage: kde-workspace
UpgradeStatus: No upgrade log present (probably fresh install)

[Harald Sitter (apachelogger)]

A kdebug log would be useful:

kdebugdialog -> activate all debugging

killall krunner; sleep3; krunner --nofork &> debug.log

wait for the issue, upload debug.log

[Manuel López-Ibáñez (manuellopezibanez)]

It happened just after launching skype. krunner_ is the real krunner (I am using a wrapper script to save the debug output as you indicated). skype-desktop is:

[Desktop Entry]
Comment[en_US]=Skype Internet Telephony
Comment=Skype Internet Telephony
Exec=skype
GenericName[en_US]=
GenericName=
Icon=/usr/share/icons/skype.png
MimeType=
Name[en_US]=Skype
Name=Skype
Path=
StartupNotify=true
Terminal=false
TerminalOptions=
Type=Application
X-DBUS-ServiceName=
X-DBUS-StartupType=unique
X-KDE-SubstituteUID=false
X-KDE-Username=

[Harald Sitter (apachelogger)]

Thank you. The log seems inconspicuous enough so it seems most likely that one of the runners gets stuck so we'll need some additional data.

First please make sure that kde-workspace-dbg is installed.

Make krunner freeze and in a terminal do:

pidof krunner

note the number that spits out, then run

sudo gdb

this will open a debug prompt in which you enter

attach $THE_NUMBER_OF_THE_FIRST_COMMAND

wait a bit until the debugger is done loading (it's done when the prompt re-appears), then run:

thread apply all bt

this will print debug information for all running threads of krunner, you might have to hit return a couple of times to get all data listed. once all data is listed (i.e. prompt is active again) simply copy the output after the command and attach it to this bug report.

##

finally a couple of questions
- Once krunner is frozen, does it start working on its own again? If so, how long does it take approximately?
- Which plugins are loaded in krunner? (can be inspected via the wrench icon left of the input field)
- Does deactivating all plugins except for 'Applications' and 'Command Line' prevent the freezing? (possibly needs a restart to apply)
- When the freeze appears which kded modules are loaded (kcmshell4 kded)? Also please try to stop all services in the bottom list and see if doing so unfreezes krunner again.

[Manuel López-Ibáñez (manuellopezibanez)]

Hi Harald,

I think it is a problem with skype's launcher (or more precisely, with the X-DBUS settings). If I close skype, krunner unlocks and says:

KDEInit could not launch '/usr/bin/skype'

but I can perfectly launch that file from the command-line.

Moreover, if I delete the line:

X-DBUS-StartupType=unique

krunner does not lock up.

[Harald Sitter (apachelogger)]

That's weird.

The line essentially makes startup wait for skype to register on dbus....

What's the output of
qdbus |grep -i skype

[Harald Sitter (apachelogger)]

...after you started skype ;)

[Manuel López-Ibáñez (manuellopezibanez)]

 com.Skype.API

The output is the same with or without that line in the desktop file. But the lock-up always occurs.

Hum, this may also explain why the whole plasma-desktop locks up when using icon-only task manager and skype. Bug 1076964.

[Harald Sitter (apachelogger)]

Does skype take long to start?

Looks to me like skype takes ages to register on the bus.

[Launchpad Janitor (janitor)]

[Expired for kde-workspace (Ubuntu) because there has been no activity for 60 days.]