Attachment 90134 Details for Bug 72251 – The last 2000 lines of test-service log when it fail

The last 2000 lines of test-service log when it fail

72251-test-service-last-2000.log (text/plain), 187.69 KB, created by Chengwei Yang on 2013-12-03 03:25:29 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Chengwei Yang

Created: 2013-12-03 03:25:29 UTC

Size: 187.69 KB

patch

obsolete

>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] fd = 3
>54137: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 0
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 1 on connection 0x80102d140 watch 0x8010170c0 fd = 3 outgoing messages exist 1
>54137: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>54137: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>54137: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>54137: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>54137: [dbus-connection.c(3592):_dbus_connection_flush_unlocked] doing iteration in
>54137: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>54137: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = -1
>54137: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>54137: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>54137: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>54137: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x7 timeout -1 connected = 1
>54137: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = readwrite timeout = -1 read_watch = 0x801017100 write_watch = 0x8010170c0 fd = 3
>54137: [dbus-transport-socket.c(1113):socket_do_iteration] unlock pre poll
>54137: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>54137: [dbus-transport-socket.c(1125):socket_do_iteration] lock post poll
>54137: [dbus-connection.c(390):_dbus_connection_lock] LOCK
>54137: [dbus-transport-socket.c(1142):socket_do_iteration] poll_fd.revents is 0x10, _DBUS_POLLIN is 0x1, _DBUS_POLLOUT is 0x4
>54137: [dbus-transport-socket.c(1148):socket_do_iteration] in iteration, need_read=0 need_write=0
>54137: [dbus-transport-socket.c(345):exchange_credentials] exchange_credentials: do_reading = 0, do_writing = 0
>54137: [dbus-transport-socket.c(437):do_authentication] send_credentials_pending = 1 receive_credentials_pending = 0
>54137: [dbus-transport-socket.c(180):check_read_watch] 52042: [dbus-spawn.c(673):_dbus_babysitter_kill_child] Got child PID 54137 for killing
>Failed to activate service 'org.freedesktop.DBus.TestSuiteEchoService': timed out
>52042: [dbus-connection.c(2952):dbus_connection_get_is_connected] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [connection.c(2271):bus_transaction_send_error_reply] Sending error reply org.freedesktop.DBus.Error.TimedOut "Activation of org.freedesktop.DBus.TestSuiteEchoService timed out"
>52042: [connection.c(2014):bus_transaction_send_from_driver] Sending (no interface) (no member) org.freedesktop.DBus.Error.TimedOut from driver
>52042: [policy.c(1068):bus_client_policy_check_can_receive]   (policy) checking receive rules, eavesdropping = 0
>52042: [policy.c(1081):bus_client_policy_check_can_receive]   (policy) skipping non-receive rule
>52042: [policy.c(1237):bus_client_policy_check_can_receive]   (policy) used rule, allow now = 1
>52042: [policy.c(1081):bus_client_policy_check_can_receive]   (policy) skipping non-receive rule
>52042: [dbus-connection.c(6216):dbus_connection_get_outgoing_size] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(6277):dbus_connection_get_outgoing_unix_fds] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [bus.c(1646):bus_context_check_security_policy] security policy allowing message
>52042: [dbus-connection.c(2952):dbus_connection_get_is_connected] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [connection.c(2060):bus_transaction_send]   trying to add error interface=(unset) member=(unset) error=org.freedesktop.DBus.Error.TimedOut to transaction
>52042: [dbus-connection.c(2952):dbus_connection_get_is_connected] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(3144):dbus_connection_preallocate_send] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [connection.c(2087):bus_transaction_send] about to prepend message
>52042: [connection.c(2095):bus_transaction_send] prepended message
>52042: [connection.c(2228):bus_transaction_execute_and_free] TRANSACTION: executing
>52042: [dbus-connection.c(3205):dbus_connection_send_preallocated] LOCK
>52042: [dbus-connection.c(2034):_dbus_connection_send_preallocated_unlocked_no_update] Message 0x80147f2c0 (error no path no interface no member 's') for :1.0 added to outgoing queue 0x801484140, 1 pending to send
>52042: [dbus-connection.c(2050):_dbus_connection_send_preallocated_unlocked_no_update] Message 0x80147f2c0 serial is 2497
>52042: [dbus-connection.c(1203):_dbus_connection_do_iteration_unlocked] start
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>52042: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = 0
>52042: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>52042: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>52042: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>52042: [dbus-transport.c(991):_dbus_transport_do_iteration] Transport iteration flags 0x1 timeout -1 connected = 1
>52042: [dbus-transport-socket.c(1040):socket_do_iteration]  iteration flags = write timeout = -1 read_watch = 0x801417900 write_watch = 0x8014178c0 fd = 7
>52042: [dbus-transport-socket.c(516):do_writing] do_writing(), have_messages = 1, fd = 7
>52042: [dbus-transport-socket.c(666):do_writing]  wrote 198 bytes of 198
>52042: [dbus-connection.c(660):_dbus_connection_message_sent_unlocked] Message 0x80147f2c0 (error no path no interface no member 's') removed from outgoing queue 0x801484140, 0 left to send
>52042: [dbus-transport-socket.c(165):check_write_watch] check_write_watch(): needed = 0 on connection 0x801484140 watch 0x8014178c0 fd = 7 outgoing messages exist 0
>52042: [dbus-transport-socket.c(1183):socket_do_iteration]  ... leaving do_iteration()
>52042: [dbus-transport.c(1005):_dbus_transport_do_iteration] end
>52042: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>52042: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>52042: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>52042: [dbus-connection.c(1236):_dbus_connection_do_iteration_unlocked] end
>52042: [dbus-connection.c(2081):_dbus_connection_send_preallocated_and_unlock] middle
>52042: [dbus-connection.c(4243):_dbus_connection_get_dispatch_status_unlocked] dispatch status = complete is_connected = 1
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-watch.c(348):_dbus_watch_list_set_functions] Removing all pre-existing watches
>52042: [dbus-socket-set-poll.c(221):socket_set_poll_remove] after removing fd 14 from 0x80141a120, 5 en/5 res/25 alloc
>52042: [dbus-spawn.c(511):close_socket_to_babysitter] Closing babysitter
>52042: [dbus-watch.c(629):dbus_watch_set_data] Setting watch fd -1 data to data = 0x0 function = 0x0 from data = 0x0 function = 0x0
>52042: [dbus-spawn.c(532):close_error_pipe_from_child] Closing child error
>52042: [dbus-spawn.c(377):_dbus_babysitter_unref] Reaped 54136, waiting for babysitter 54136
>52042: [dbus-spawn.c(381):_dbus_babysitter_unref] Babysitter exited with status 0
>52042: [test.c(251):bus_test_run_bus_loop] ---> Done dispatching on "server side"
>52042: [test.c(208):bus_test_run_clients_loop] ---> Dispatching on "client side"
>52042: [dbus-connection.c(1496):_dbus_connection_handle_watch] start
>52042: [dbus-connection.c(1498):_dbus_connection_handle_watch] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(1074):_dbus_connection_acquire_io_path] locking io_path_mutex
>52042: [dbus-connection.c(1078):_dbus_connection_acquire_io_path] start connection->io_path_acquired = 0 timeout = 1
>52042: [dbus-connection.c(1121):_dbus_connection_acquire_io_path] end connection->io_path_acquired = 1 we_acquired = 1
>52042: [dbus-connection.c(1123):_dbus_connection_acquire_io_path] unlocking io_path_mutex
>52042: [dbus-connection.c(1126):_dbus_connection_acquire_io_path] LOCK
>52042: [dbus-transport-socket.c(912):socket_handle_watch] handling read watch 0x801417840 flags = 1
>52042: [dbus-transport-socket.c(703):do_reading] fd = 6
>52042: [dbus-transport-socket.c(180):check_read_watch] fd = 6
>52042: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 1
>52042: [dbus-transport-socket.c(840):do_reading]  read 198 bytes
>52042: [dbus-marshal-header.c(747):_dbus_header_have_message_untrusted] have 198 bytes, need body 70 + header 128 = 198
>52042: [dbus-marshal-validate.c(723):_dbus_validate_body_with_reason] validating body from pos 0 len 198 sig 'yyyyuua(yv)'
>52042: [dbus-marshal-validate.c(548):validate_body_helper] p = 0x80148a85f end = 0x80148a8c6 claimed_len 1
>52042: [dbus-marshal-header.c(821):load_and_validate_field] initially caching field 6
>52042: [dbus-marshal-header.c(821):load_and_validate_field] initially caching field 4
>52042: [dbus-marshal-header.c(821):load_and_validate_field] initially caching field 5
>52042: [dbus-marshal-header.c(821):load_and_validate_field] initially caching field 8
>52042: [dbus-marshal-header.c(821):load_and_validate_field] initially caching field 7
>52042: [dbus-marshal-validate.c(723):_dbus_validate_body_with_reason] validating body from pos 128 len 70 sig 's'
>52042: [dbus-message.c(4254):load_message] Loaded message 0x80147f2c0
>52042: [dbus-transport.c(1159):_dbus_transport_queue_messages] queueing received message 0x80147f2c0
>queueing received message 0x80147f2c0(--> :1.0 (null) (null))
>52042: [dbus-transport-socket.c(180):check_read_watch] fd = 6
>52042: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 1
>52042: [dbus-connection.c(530):_dbus_connection_queue_received_message_link] Message 0x80147f2c0 (error no path no interface no member 's' reply to 2495) added to incoming queue 0x801484280, 1 incoming
>52042: [dbus-transport-socket.c(180):check_read_watch] fd = 6
>52042: [dbus-transport-socket.c(226):check_read_watch]   setting read watch enabled = 1
>52042: [dbus-connection.c(1147):_dbus_connection_release_io_path] locking io_path_mutex
>52042: [dbus-connection.c(1153):_dbus_connection_release_io_path] start connection->io_path_acquired = 1
>52042: [dbus-connection.c(1158):_dbus_connection_release_io_path] unlocking io_path_mutex
>52042: [dbus-connection.c(1515):_dbus_connection_handle_watch] middle
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(1522):_dbus_connection_handle_watch] end
>52042: [test.c(226):bus_test_run_clients_loop] ---> Done dispatching on "client side"
>52042: [dbus-connection.c(4359):dbus_connection_get_dispatch_status] start
>52042: [dbus-connection.c(4361):dbus_connection_get_dispatch_status] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(2952):dbus_connection_get_is_connected] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(4359):dbus_connection_get_dispatch_status] start
>52042: [dbus-connection.c(4361):dbus_connection_get_dispatch_status] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(4071):dbus_connection_pop_message] start
>52042: [dbus-connection.c(4359):dbus_connection_get_dispatch_status] start
>52042: [dbus-connection.c(4361):dbus_connection_get_dispatch_status] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(4080):dbus_connection_pop_message] LOCK
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dbus-connection.c(4111):_dbus_connection_acquire_dispatch] locking dispatch_mutex
>52042: [dbus-connection.c(4125):_dbus_connection_acquire_dispatch] unlocking dispatch_mutex
>52042: [dbus-connection.c(4128):_dbus_connection_acquire_dispatch] LOCK
>52042: [dbus-connection.c(3975):_dbus_connection_pop_message_link_unlocked] Message 0x80147f2c0 (error no path no interface no member sig:'s' serial:2497) removed from incoming queue 0x801484280, 0 incoming
>52042: [dbus-connection.c(4086):dbus_connection_pop_message] Returning popped message 0x80147f2c0
>52042: [dbus-connection.c(4144):_dbus_connection_release_dispatch] locking dispatch_mutex
>52042: [dbus-connection.c(4152):_dbus_connection_release_dispatch] unlocking dispatch_mutex
>52042: [dbus-connection.c(4243):_dbus_connection_get_dispatch_status_unlocked] dispatch status = complete is_connected = 1
>52042: [dbus-connection.c(406):_dbus_connection_unlock] UNLOCK
>52042: [dispatch.c(536):verbose_message_received] Received message interface "(unset)" member "(unset)" error name "org.freedesktop.DBus.Error.TimedOut" on 0x801484280
>52042: [dispatch.c(2510):check_existent_service_no_auto_start]   (after sending StartServiceByName)
>Did not expect error org.freedesktop.DBus.Error.TimedOut
>existent_service_no_auto_start failed during oom
>File "dispatch.c" line 4445 process 52042 should not have been reached: test failed
>  D-Bus not compiled with backtrace support so unable to print a backtrace

Actions: View

Attachments on bug 72251: 90134 | 90142

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.