Actions
Bug #1987
closed
dbus taking 100% of cpu
Start date:
2012-01-15
Due date:
% Done:
100%
Estimated time:
Difficulty:
Medium
Tags:
dbus
Description
After my 151a machine had about 39 days of uptime with heavy X11 use, top noticed that dbus-daemon was taking 100% of the cpu time in one of my CPU cores. svcadm disable dbus did not seem to kill the rogue dbus-daemon; I had to kill it by pid manually.
I ran the Proc/dapptrace script from the DTrace toolkit. Here's the output that I got:
CALL(args) = return -> check_babysit_events(0x2CF8, 0x20, 0x1) <- check_babysit_events = 177 -> _dbus_poll(0x8047490, 0x2, 0xFFFFFFFF) <- _dbus_poll = 47 -> check_babysit_events(0x2CF8, 0x20, 0x1) <- check_babysit_events = 177 -> _dbus_poll(0x8047490, 0x2, 0xFFFFFFFF) <- _dbus_poll = 47 -> check_babysit_events(0x2CF8, 0x20, 0x1) <- check_babysit_events = 177 -> _dbus_poll(0x8047490, 0x2, 0xFFFFFFFF) <- _dbus_poll = 47 -> check_babysit_events(0x2CF8, 0x20, 0x1) <- check_babysit_events = 177 -> _dbus_poll(0x8047490, 0x2, 0xFFFFFFFF) <- _dbus_poll = 47 -> check_babysit_events(0x2CF8, 0x20, 0x1) <- check_babysit_events = 177 -> _dbus_poll(0x8047490, 0x2, 0xFFFFFFFF) <- _dbus_poll = 47
... ad nauseam
Files
Updated by Ryo Murakawa about 11 years ago
- Assignee changed from Ryo Murakawa to OI Userland
- Tags changed from needs-triage to dbus
Updated by Richard PALO about 11 years ago
In trying to debug https://www.illumos.org/issues/2653#change-6939 I'm experiencing this as one of the first steps in the problem evolution. dbus-daemon, and a number of other session processes from a logged out VNC user's session. (note VNC is in multiuser, inetd/wait=false)
here are the last few lines from dapptrace -ap <dbus-daemon-pid>
5904/1: 41992242 . . -> dbus-daemon:_dbus_poll(0x80474C0, 0x2, 0xFFFFFFFF) 5904/1: 41992243 . . -> libc.so.1:poll(0x80474C0, 0x2, 0xFFFFFFFF) 5904/1: 41992243 . . -> libc.so.1:_pollsys(0x80474C0, 0x2, 0x0) 5904/1: 41992244 . . -> libc.so.1:__pollsys(0x80474C0, 0x2, 0x0) 5904/1: 41992247 13 3 <- libc.so.1:__pollsys = 13 5904/1: 41992248 37 4 <- libc.so.1:_pollsys = 423 5904/1: 41992248 58 5 <- libc.so.1:poll = 83 5904/1: 41992249 80 6 <- dbus-daemon:_dbus_poll = 47 5904/1: 41992249 . . -> dbus-daemon:check_babysit_events(0x1711, 0x10, 0x1) 5904/1: 41992250 . . -> libc.so.1:waitpid(0x1711, 0x8047454, 0x40) 5904/1: 41992250 . . -> libc.so.1:waitid(0x0, 0x1711, 0x80473B0) 5904/1: 41992251 . . -> libc.so.1:__waitid(0x0, 0x1711, 0x80473B0) 5904/1: 41992253 12 2 <- libc.so.1:__waitid = 21 5904/1: 41992253 34 3 <- libc.so.1:waitid = 274 5904/1: 41992254 . . -> libc.so.1:wstat(0x0, 0x0, 0x80473B0) 5904/1: 41992254 9 0 <- libc.so.1:wstat = 79 5904/1: 41992255 75 5 <- libc.so.1:waitpid = 149 5904/1: 41992255 103 6 <- dbus-daemon:check_babysit_events = 177 5904/1: 41992256 . . -> dbus-daemon:_dbus_poll(0x80474C0, 0x2, 0xFFFFFFFF) 5904/1: 41992256 . . -> libc.so.1:poll(0x80474C0, 0x2, 0xFFFFFFFF) 5904/1: 41992257 . . -> libc.so.1:_pollsys(0x80474C0, 0x2, 0x0) dtrace: 523021 drops on CPU 7 CALL COUNT libc.so.1 __pollsys 1108 dbus-daemon _dbus_poll 1110 dbus-daemon check_babysit_events 1110 libc.so.1 __waitid 1110 libc.so.1 _pollsys 1110 libc.so.1 poll 1110 libc.so.1 waitid 1110 libc.so.1 waitpid 1110 libc.so.1 wstat 1111
I'm trying now to collect information on another step in the evolution, where xvnc goes pegs out at 100% cpu.
FWIW, the other processes hanging around are:
# ps -u <user> PID TTY TIME CMD 5771 ? 0:00 gvfsd-me 5764 ? 0:00 gvfs-hal 5759 ? 0:00 gvfsd-tr 5751 ? 0:00 gvfsd 5904 ? 871:32 dbus-dae 5905 ? 0:00 gconfd-2
Updated by Richard PALO about 11 years ago
by the way, when I kill the rogue user's dbus-daemon, things calm down naturally (all 6 processes go away)
Updated by Andrew Stormont about 11 years ago
Which version of DBus are you running?
Updated by Richard PALO about 11 years ago
Andrew Stormont wrote:
Which version of DBus are you running?
$ cat /etc/release
OpenIndiana Development oi_151.1.3 X86 (powered by illumos)
Copyright 2011 Oracle and/or its affiliates. All rights reserved.
Use is subject to license terms.
Assembled 24 March 2012
$ /usr/lib/dbus-daemon --version
D-Bus Message Bus Daemon 1.2.24
Copyright (C) 2002, 2003 Red Hat, Inc., CodeFactory AB, and others
This is free software; see the source for copying conditions.
There is NO warranty; not even for MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE
Updated by Richard PALO about 11 years ago
not sure this is entirely related, but I notice another anomaly involving dbus.
Namely, when I "ssh -X <host>" and run any gnome application, most frequently gedit, my session stalls,
that is simply exiting is blocked and needs to be abruptly stopped.
If I run a simple X program, like "xeyes", no problem.
Let me illustrate what is perfectly reproducible (here, anyway):
richard@shuttleX:~$ ssh -X <hostname> Last login: Mon May 7 10:02:51 2012 from <client> OpenIndiana (powered by illumos) SunOS 5.11 oi_151a3 March 2012 richard@smicro:~$ ps axuw | grep dbus richard 4813 0.0 0.0 2080 1408 pts/1 S 10:04:13 0:00 grep dbus root 358 0.0 0.0 3400 2076 ? S 08:43:55 0:00 /usr/lib/dbus-daemon --system gdm 3497 0.0 0.0 3508 1420 ? S 08:44:18 0:00 /usr/bin/dbus-launch --exit-with-session gdm 3498 0.0 0.0 3156 1976 ? S 08:44:18 0:00 /usr/lib/dbus-daemon --fork --print-pid 6 --print-address 8 --session richard@smicro:~$ xeyes richard@smicro:~$ ps axuw | grep dbus richard 4821 0.0 0.0 2080 1408 pts/1 S 10:04:22 0:00 grep dbus root 358 0.0 0.0 3400 2076 ? S 08:43:55 0:00 /usr/lib/dbus-daemon --system gdm 3497 0.0 0.0 3508 1420 ? S 08:44:18 0:00 /usr/bin/dbus-launch --exit-with-session gdm 3498 0.0 0.0 3156 1976 ? S 08:44:18 0:00 /usr/lib/dbus-daemon --fork --print-pid 6 --print-address 8 --session richard@smicro:~$ logout Connection to <hostname> closed.
up to here, no problem - but now:
richard@shuttleX:~$ ssh -X <hostname> Last login: Mon May 7 10:04:09 2012 from <client> OpenIndiana (powered by illumos) SunOS 5.11 oi_151a3 March 2012 richard@smicro:~$ gedit richard@smicro:~$ ps axuw | grep dbus richard 4850 0.0 0.0 2080 1408 pts/1 S 10:04:59 0:00 grep dbus root 358 0.0 0.0 3400 2076 ? S 08:43:55 0:00 /usr/lib/dbus-daemon --system gdm 3497 0.0 0.0 3508 1420 ? S 08:44:18 0:00 /usr/bin/dbus-launch --exit-with-session gdm 3498 0.0 0.0 3156 1976 ? S 08:44:18 0:00 /usr/lib/dbus-daemon --fork --print-pid 6 --print-address 8 --session richard 4841 0.0 0.0 3516 1460 pts/1 S 10:04:35 0:00 dbus-launch --autolaunch 29cf1aac9c32865f9da7044b4cf27d81 --binary-syn richard 4842 0.0 0.0 3068 1888 ? S 10:04:35 0:00 /usr/lib/dbus-daemon --fork --print-pid 6 --print-address 8 --session richard@smicro:~$ logout ^CInterrompu par le signal 2.
is this a general problem, or is autolaunch omitting --exit-with-session?
not too knowledgeable on dbus... if the session was VNC, could this be related to the problem?
Updated by Richard PALO about 11 years ago
- File pfiles.txt pfiles.txt added
captured the latest pstack and attached a pfiles.
$ pstack 8861 8861: /usr/lib/dbus-daemon --fork --print-pid 6 --print-address 8 --session fef13ad7 __pollsys (80474a0, 2, 0, 0, 8047434, 80bfa34) + 7 feea4f26 poll (80474a0, 2, ffffffff, 8097ef3) + 4c 08097f0b _dbus_poll (80474a0, 2, ffffffff, 809c582) + 27 0809c680 _dbus_spawn_async_with_babysitter (80c95d8, 80c0ce0, 80c80b8, 0, 80c1600, 8047630) + 2dc 08068cbf bus_activation_activate_service (80c1600, 80d3c20, 80c1228, 1, 80cae50, 80caf00) + 6c3 08071d7d bus_dispatch_message_filter (80d3c20, 80cae50, 0, 807da45) + 1f9 0807dd01 dbus_connection_dispatch (80d3c20, 0, 1, 809a6d1) + 2cd 0809a6fe _dbus_loop_dispatch (80c15b0, 1, 80d3c20, 809a798) + 3e 0809adb1 _dbus_loop_iterate (80c15b0, 1, 0, 809addd) + 62d 0809ae0d _dbus_loop_run (80c15b0) + 41 08079963 main (7, 8047c30, 8047c50, 8047bec) + 7d7 08066aad _start (7, 8047d20, 8047d35, 8047d3c, 8047d48, 8047d4a) + 7d
jah, tis fsck'd hup... need to find a solution, nobody else experiences this anymore?
btw, still on prestable2 for this server.
Updated by Richard PALO about 11 years ago
came across these sunsolve bugids:
http://wesunsolve.net/bugid/id/6950666
http://wesunsolve.net/bugid/id/6543577
so this is known for awhile now... symptoms are apparently the same.
Updated by Richard PALO about 11 years ago
interesting messages from gdm debug during a session logout that resulted in the famous dbus process eating cpu...
May 23 11:59:38 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.421 May 23 11:59:38 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.421', old_service_name=':1.421' new_service_name='' May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: get current session May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: uid = 202 May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: pid = 3508 May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: get session for unix process: 3508 May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: uid = 202 May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: pid = 3508 May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: get session for cookie May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: uid = 202 May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: pid = 3508 May 23 11:59:42 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: Found session '/org/freedesktop/ConsoleKit/Session66' May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: get current session May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: uid = 202 May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: pid = 3508 May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: get session for unix process: 3508 May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: uid = 202 May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: pid = 3508 May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: get session for cookie May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: uid = 202 May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: pid = 3508 May 23 11:59:43 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: CkManager: Found session '/org/freedesktop/ConsoleKit/Session66' May 23 11:59:44 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.418 May 23 11:59:44 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.418', old_service_name=':1.418' new_service_name='' May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.416 May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.416', old_service_name=':1.416' new_service_name='' May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: child (pid:3508) done (status:0) May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: Calling SessionExited May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): session_message_handler: destination=(null) obj_path=/org/gnome/DisplayManager/Session interface=org.gnome.DisplayManager.Session method=SessionExited May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionDirect: Emitting 'session-exited' signal with exit code '0' May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): Writing logout record May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): using ut_type DEAD_PROCESS May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): using ut_tv time 1337767195 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): using ut_pid 3508 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): using ut_id ::ff May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: uninitializing PAM May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): using ut_host ::ffff:127.0.0.1::ffff:127.0.0.1:0 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): using ut_line dtremote May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): Writing wtmp logout record to /var/adm/wtmpx May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): Removing utmp record May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSimpleSlave: session exited with code 0 May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): Copying user dmrc file to cache May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Trying script /etc/gdm/PostSession/::ffff:127.0.0.1:0 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script /etc/gdm/PostSession/::ffff:127.0.0.1:0 not found; skipping May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: Checking if /home/sonia/.dmrc should be copied to cache /var/cache/gdm/sonia/dmrc May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Trying script /etc/gdm/PostSession/::ffff:127.0.0.1 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script /etc/gdm/PostSession/::ffff:127.0.0.1 not found; skipping May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Trying script /etc/gdm/PostSession/Default May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): Destination file is newer May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Running process: /etc/gdm/PostSession/Default May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.415 May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): Not copying file /home/sonia/.dmrc to cache May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing session for cookie: 29cf1aac9c32865f9da7044b4cf27d81-1337753504.793880-2099813755 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: X_SERVERS=/var/gdm/::ffff:127.0.0.1:0.Xservers May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): Copying user face file to cache May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Emitting session-removed: /org/freedesktop/ConsoleKit/Session66 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: XAUTHORITY=/tmp/gdm-auth-cookies-CmaWDe/auth-for-gdm-3maWDe/database May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: LOGNAME=sonia May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: Checking if /home/sonia/.face should be copied to cache /var/cache/gdm/sonia/face May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: PWD=/home/sonia May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: HOME=/home/sonia May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: DISPLAY=::ffff:127.0.0.1:0 May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): File does not exist May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: REMOTE_HOST=::ffff:127.0.0.1 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: PATH=/usr/bin May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: SHELL=/usr/bin/bash May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): Not copying file /home/sonia/.face to cache May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: RUNNING_UNDER_GDM=true May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: USER=sonia May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: script environment: USERNAME=sonia May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: Checking if /home/sonia/.face.icon should be copied to cache /var/cache/gdm/sonia/face May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Running programs in /etc/ConsoleKit/run-session.d for action session_removed May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): File does not exist May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Running programs in /usr/lib/ConsoleKit/run-session.d for action session_removed May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): Not copying file /home/sonia/.face.icon to cache May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Got uid: 0 May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): Checking user's ~/.gnome/gdm file May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): File does not exist May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Writing log for event: 1337767195.554 type=SEAT_SESSION_REMOVED : seat-id='XDMCP__ffff_127_0_0_1_0' session-id='Session66' session-type='' display-type='' session-x11-display='' session-x11-display-device='' session-display-device='' session-remote-host-name='' session-is-local=FALSE session-unix-user=0 session-creation-time='2012-05-23T06:11:44.856368Z' May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.crit] GLib-GObject-CRITICAL: file gobject.c: line 2396: assertion `G_IS_OBJECT (object)' failed May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.415', old_service_name=':1.415' new_service_name='' May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmDisplayStore: Unreffing display: 80bf0d8 May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: state NONE May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Process exit status: 0 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): slave finished May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSimpleSlave: Stopping simple_slave May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Stopping slave May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Disconnected from display May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionDirect: Closing session May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorkerJob: Stopping job pid:3501 May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmCommon: sending signal 15 to process 3501 May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: obj_path=/org/freedesktop/DBus/Local interface=org.freedesktop.DBus.Local method=Disconnected May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorkerJob: Waiting on process 3501 May 23 11:59:55 smicro gdm-session-worker[3501]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorker: Got disconnected from the server May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.414 May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.414', old_service_name=':1.414' new_service_name='' May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmCommon: process (pid:3501) done (status:1) May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorkerJob: Wait on child process failed May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionWorkerJob: SessionWorkerJob died May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionDirect: Disposing session May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionDirect: Closing session May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSessionDirect: Disconnected from display May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSlave: Stopping slave May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): GdmSignalHandler: Finalizing signal handler May 23 11:59:55 smicro gdm-simple-slave[3459]: [ID 702911 daemon.warning] DEBUG(+): Slave finished May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.410 May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.410', old_service_name=':1.410' new_service_name='' May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmSlaveProxy: slave (pid:3459) done (status:0) May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmDisplay: Slave exited: 0 May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmDisplay: Finishing display: /org/gnome/DisplayManager/Display17 May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmXdmcpDisplayFactory: xdmcp display status changed: 3 May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmDisplay: finish display May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmDisplay: Unmanaging display May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmDisplay: unmanage display May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmSlaveProxy: Killing slave May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmSlaveProxy: Disposing slave proxy May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmSlaveProxy: Killing slave May 23 11:59:55 smicro gdm-binary[2286]: [ID 702911 daemon.warning] DEBUG(+): GdmXdmcpDisplayFactory: xdmcp display status changed: 0 May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.420 May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.420', old_service_name=':1.420' new_service_name='' May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: Removing sessions for service name: :1.419 May 23 11:59:55 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: NameOwnerChanged: service_name=':1.419', old_service_name=':1.419' new_service_name='' May 23 11:59:57 smicro console-kit-daemon[1928]: [ID 702911 daemon.warning] DEBUG: timeout for under request of session /org/freedesktop/ConsoleKit/Session55
this last line look suspicious...
Updated by Richard PALO about 11 years ago
happened to notice a hanging dbus and noticed that gconfd-2 may have something to do with things: (1579 & 1580 in contract 169, contract 180 is a currently active session)
~$ ptree -c sonia
[process contract 169]
23487 /usr/lib/gvfsd
23505 /usr/lib/gvfs-hal-volume-monitor
23509 /usr/lib/gvfsd-trash --spawner :1.9 /org/gtk/gvfs/exec_spaw/0
23875 /usr/lib/gvfsd-metadata
1579 /usr/lib/dbus-daemon --fork --print-pid 6 --print-address 8 --session
1580 /usr/lib/gconfd-2
[process contract 180]
14115 /usr/lib/gdm-simple-slave --display-id /org/gnome/DisplayManager/Display9
14154 /usr/lib/gdm-session-worker
14204 gnome-session
14238 /usr/bin/ssh-agent -- /usr/bin/gpg-agent --daemon --enable-ssh-support gnome-se
14239 /usr/bin/gpg-agent --daemon --enable-ssh-support gnome-session
14263 metacity
14264 gnome-panel
14265 nautilus
14268 gnome-power-manager
14269 /usr/perl5/bin/perl /usr/bin/desktop-print-management-applet
14273 python2.6 /usr/lib/system-config-printer/applet.py
14271 /usr/bin/xscreensaver -nosplash
14231 dbus-launch --exit-with-session --sh-syntax
14232 /usr/lib/dbus-daemon --fork --print-pid 6 --print-address 8 --session
14252 /usr/lib/gconfd-2
14259 /usr/bin/gnome-keyring-daemon --start --components=pkcs11
14260 /usr/lib/gnome-settings-daemon
14262 /usr/lib/gvfsd
14267 /usr/lib/bonobo-activation-server --ac-activate --ior-output-fd=21
14276 /usr/lib/wnck-applet --oaf-activate-iid=OAFIID:GNOME_Wncklet_Factory --oaf-ior-
14279 /usr/lib/gvfsd-trash --spawner :1.9 /org/gtk/gvfs/exec_spaw/0
14281 /usr/lib/trashapplet --oaf-activate-iid=OAFIID:GNOME_Panel_TrashApplet_Factory
14283 /usr/lib/gvfs-hal-volume-monitor
14287 /usr/lib/clock-applet --oaf-activate-iid=OAFIID:GNOME_ClockApplet_Factory --oaf
14289 /usr/lib/notification-area-applet --oaf-activate-iid=OAFIID:GNOME_NotificationA
14602 /bin/bash /usr/bin/thunderbird
14615 /bin/bash /usr/lib/thunderbird/run-mozilla.sh /usr/lib/thunderbird/thunderbird-
14619 /usr/lib/thunderbird/thunderbird-bin
14621 /usr/lib/gam_server
Updated by M Neus about 10 years ago
- File dbus-daemon.jpg dbus-daemon.jpg added
If it helps (or even matters), I've now encountered this twice within the past week after installing Virtualbox 4.2.10 and configuration/operating an Ubuntu VM on a headless server via xVNC, possibly confirming VNC or "heavy x11" usage as contributors. I note that Googling "dbus daemon 100 cpu" returns lots of hits on other (typically Linux) OSs, and reviewing those it appears errant applications that use the daemon (not the daemon itself) are the usual cause. Anyone know how to figure out what apps are calling/hanging the dbus daemon? Unfortunately, my knowledge of *nix is very limited.
OI info: OI-151a7 w/Gnome GUI.
Updated by Ken Mays almost 10 years ago
- Assignee changed from OI Userland to OI JDS
- Target version deleted (
oi_151_stable)
Updated by Ken Mays almost 10 years ago
- Assignee changed from OI JDS to OI illumos
Updated by Andrew Stormont over 9 years ago
I'm pretty sure that version of DBus (1.2.24) is bad. I had a similar experience with 1.2.26 - see my comments on #1061.
Updated by Ken Mays almost 9 years ago
- Status changed from New to Closed
- % Done changed from 0 to 100
Fixed in: system/library/dbus@1.2.30,5.11-0.151.1.8:20130406T221035Z
Actions