91: Filling in system bus address... 91: used default system bus "unix:path=/root/dbus-related/dbus-lib/dbus/var/run/dbus/system_bus_socket" 91: Filling in session bus address... 91: "unix:path=/tmp/dbus-6fS3Q1dC9R,guid=a6fa8cbb7ab01b8ee11aa62b386d441b" 91: Filling in activation bus address... 91: "none set" 91: opening shared connection to: unix:path=/tmp/dbus-6fS3Q1dC9R,guid=a6fa8cbb7ab01b8ee11aa62b386d441b 91: checking for existing connection 91: creating shared_connections hash table 91: successfully created shared_connections 91: connecting to unix socket /tmp/dbus-6fS3Q1dC9R abstract=0 91: socket fd 3 opened 91: Successfully connected to unix socket /tmp/dbus-6fS3Q1dC9R 91: client: going from state NeedSendAuth to state WaitingForData 91: Initialized transport on address unix:path=/tmp/dbus-6fS3Q1dC9R 91: LOCK: _dbus_connection_new_for_transport 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: check_read_watch: fd = 3 91: setting read watch enabled = 0 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: check_write_watch(): needed = 1 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 0 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: UNLOCK: _dbus_connection_new_for_transport 91: LOCK: _dbus_connection_open_internal 91: stored connection to a6fa8cbb7ab01b8ee11aa62b386d441b to be shared 91: UNLOCK: _dbus_connection_open_internal 91: Allocated slot 0 on allocator 0x2ab19b88 total 1 slots allocated 1 used 91: LOCK: dbus_connection_get_data 91: UNLOCK: dbus_connection_get_data 91: LOCK: dbus_connection_set_data 91: UNLOCK: dbus_connection_set_data 91: LOCK: dbus_connection_send_with_reply 91: Allocated slot 0 on allocator 0x2ab19be0 total 1 slots allocated 1 used 91: UNLOCK: protected_change_timeout 91: LOCK: protected_change_timeout 91: Message 0x402b88 (1 /org/freedesktop/DBus org.freedesktop.DBus Hello '') for org.freedesktop.DBus added to outgoing queue 0x402860, 1 pending to send 91: Message 0x402b88 serial is 1 91: _dbus_connection_do_iteration_unlocked start 91: UNLOCK: _dbus_connection_acquire_io_path 91: _dbus_connection_acquire_io_path locking io_path_mutex 91: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0 91: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 91: _dbus_connection_acquire_io_path unlocking io_path_mutex 91: LOCK: _dbus_connection_acquire_io_path 91: Transport iteration flags 0x1 timeout -1 connected = 1 91: iteration flags = write timeout = -1 read_watch = 0x4024f0 write_watch = 0x4024c8 fd = 3 91: in iteration, need_read=0 need_write=1 91: exchange_credentials: do_reading = 0, do_writing = 1 91: wrote credentials byte 91: client auth state: bytes to send 91: client: Sent 18 bytes of: AUTH EXTERNAL 30 91: exchange_credentials: do_reading = 0, do_writing = 1 91: client auth state: waiting for input 91: check_read_watch: fd = 3 91: setting read watch enabled = 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: check_write_watch(): needed = 0 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: Not authenticated, not writing anything 91: check_write_watch(): needed = 0 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: ... leaving do_iteration() 91: _dbus_transport_do_iteration end 91: _dbus_connection_release_io_path locking io_path_mutex 91: _dbus_connection_release_io_path start connection->io_path_acquired = 1 91: _dbus_connection_release_io_path unlocking io_path_mutex 91: _dbus_connection_do_iteration_unlocked end 91: dispatch status = complete is_connected = 1 91: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock 91: LOCK: dbus_connection_unref 91: UNLOCK: dbus_connection_unref 91: LOCK: _dbus_connection_lock 91: UNLOCK: _dbus_connection_unlock 91: LOCK: _dbus_connection_lock 91: UNLOCK: _dbus_connection_unlock 91: LOCK: _dbus_connection_lock 91: doing iteration in _dbus_connection_flush_unlocked 91: _dbus_connection_do_iteration_unlocked start 91: UNLOCK: _dbus_connection_acquire_io_path 91: _dbus_connection_acquire_io_path locking io_path_mutex 91: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1 91: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 91: _dbus_connection_acquire_io_path unlocking io_path_mutex 91: LOCK: _dbus_connection_acquire_io_path 91: Transport iteration flags 0x7 timeout -1 connected = 1 91: iteration flags = readwrite timeout = -1 read_watch = 0x4024f0 write_watch = 0x4024c8 fd = 3 91: unlock socket_do_iteration pre poll 91: UNLOCK: _dbus_connection_unlock 91: lock socket_do_iteration post poll 91: LOCK: _dbus_connection_lock 91: in iteration, need_read=1 need_write=0 91: exchange_credentials: do_reading = 1, do_writing = 0 91: client auth state: waiting for input 91: read 37 bytes in auth phase 91: client: got command "OK a6fa8cbb7ab01b8ee11aa62b386d441b" 91: Got GUID 'a6fa8cbb7ab01b8ee11aa62b386d441b' from the server 91: client: going from state WaitingForData to state Authenticated 91: exchange_credentials: do_reading = 1, do_writing = 0 91: client auth state: bytes to send 91: check_read_watch: fd = 3 91: setting read watch enabled = 0 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: check_write_watch(): needed = 1 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: do_reading: fd = 3 91: check_write_watch(): needed = 1 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: ... leaving do_iteration() 91: _dbus_transport_do_iteration end 91: _dbus_connection_release_io_path locking io_path_mutex 91: _dbus_connection_release_io_path start connection->io_path_acquired = 1 91: _dbus_connection_release_io_path unlocking io_path_mutex 91: _dbus_connection_do_iteration_unlocked end 91: doing iteration in _dbus_connection_flush_unlocked 91: _dbus_connection_do_iteration_unlocked start 91: UNLOCK: _dbus_connection_acquire_io_path 91: _dbus_connection_acquire_io_path locking io_path_mutex 91: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1 91: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 91: _dbus_connection_acquire_io_path unlocking io_path_mutex 91: LOCK: _dbus_connection_acquire_io_path 91: Transport iteration flags 0x7 timeout -1 connected = 1 91: iteration flags = readwrite timeout = -1 read_watch = 0x4024f0 write_watch = 0x4024c8 fd = 3 91: unlock socket_do_iteration pre poll 91: UNLOCK: _dbus_connection_unlock 91: lock socket_do_iteration post poll 91: LOCK: _dbus_connection_lock 91: in iteration, need_read=0 need_write=1 91: exchange_credentials: do_reading = 0, do_writing = 1 91: client auth state: bytes to send 91: client: Sent 7 bytes of: BEGIN 91: check_read_watch: fd = 3 91: setting read watch enabled = 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: check_write_watch(): needed = 1 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: check_write_watch(): needed = 1 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: ... leaving do_iteration() 91: _dbus_transport_do_iteration end 91: _dbus_connection_release_io_path locking io_path_mutex 91: _dbus_connection_release_io_path start connection->io_path_acquired = 1 91: _dbus_connection_release_io_path unlocking io_path_mutex 91: _dbus_connection_do_iteration_unlocked end 91: doing iteration in _dbus_connection_flush_unlocked 91: _dbus_connection_do_iteration_unlocked start 91: UNLOCK: _dbus_connection_acquire_io_path 91: _dbus_connection_acquire_io_path locking io_path_mutex 91: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1 91: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 91: _dbus_connection_acquire_io_path unlocking io_path_mutex 91: LOCK: _dbus_connection_acquire_io_path 91: Transport iteration flags 0x7 timeout -1 connected = 1 91: iteration flags = readwrite timeout = -1 read_watch = 0x4024f0 write_watch = 0x4024c8 fd = 3 91: unlock socket_do_iteration pre poll 91: UNLOCK: _dbus_connection_unlock 91: lock socket_do_iteration post poll 91: LOCK: _dbus_connection_lock 91: in iteration, need_read=0 need_write=1 91: do_writing(), have_messages = 1, fd = 3 91: wrote 128 bytes of 128 91: Message 0x402b88 (1 /org/freedesktop/DBus org.freedesktop.DBus Hello '') removed from outgoing queue 0x402860, 0 left to send 91: check_write_watch(): needed = 0 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 0 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: ... leaving do_iteration() 91: _dbus_transport_do_iteration end 91: _dbus_connection_release_io_path locking io_path_mutex 91: _dbus_connection_release_io_path start connection->io_path_acquired = 1 91: _dbus_connection_release_io_path unlocking io_path_mutex 91: _dbus_connection_do_iteration_unlocked end 91: _dbus_connection_flush_unlocked middle 91: 0 unused bytes sent to message loader 91: dispatch status = complete is_connected = 1 91: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 1 from 946685221 sec 23352 usec to 946685246 sec 23352 usec 91: _dbus_connection_do_iteration_unlocked start 91: UNLOCK: _dbus_connection_acquire_io_path 91: _dbus_connection_acquire_io_path locking io_path_mutex 91: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000 91: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 91: _dbus_connection_acquire_io_path unlocking io_path_mutex 91: LOCK: _dbus_connection_acquire_io_path 91: Transport iteration flags 0x6 timeout 25000 connected = 1 91: iteration flags = read timeout = 25000 read_watch = 0x4024f0 write_watch = 0x4024c8 fd = 3 91: unlock socket_do_iteration pre poll 91: UNLOCK: _dbus_connection_unlock 91: lock socket_do_iteration post poll 91: LOCK: _dbus_connection_lock 91: in iteration, need_read=1 need_write=0 91: do_reading: fd = 3 91: check_read_watch: fd = 3 91: setting read watch enabled = 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: read 258 bytes 91: have 258 bytes, need body 9 + header 80 = 89 91: validating body from pos 0 len 258 sig 'yyyyuua(yv)' 91: p = 0x40386f end = 0x403942 claimed_len 1 91: initially caching field 6 91: initially caching field 5 91: initially caching field 8 91: initially caching field 7 91: validating body from pos 80 len 9 sig 's' 91: Loaded message 0x404050 91: have 169 bytes, need body 9 + header 160 = 169 91: validating body from pos 0 len 169 sig 'yyyyuua(yv)' 91: p = 0x4038bf end = 0x4038e9 claimed_len 1 91: initially caching field 1 91: initially caching field 2 91: initially caching field 3 91: initially caching field 6 91: initially caching field 8 91: initially caching field 7 91: validating body from pos 160 len 9 sig 's' 91: Loaded message 0x4040c8 91: queueing received message 0x404050 91: UNLOCK: protected_change_timeout 91: LOCK: protected_change_timeout 91: Message 0x404050 (2 no path no interface no member 's' reply to 1) added to incoming queue 0x402860, 1 incoming 91: queueing received message 0x4040c8 91: Message 0x4040c8 (4 /org/freedesktop/DBus org.freedesktop.DBus NameAcquired 's' reply to 0) added to incoming queue 0x402860, 2 incoming 91: check_read_watch: fd = 3 91: setting read watch enabled = 1 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: check_write_watch(): needed = 0 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 0 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: ... leaving do_iteration() 91: _dbus_transport_do_iteration end 91: _dbus_connection_release_io_path locking io_path_mutex 91: _dbus_connection_release_io_path start connection->io_path_acquired = 1 91: _dbus_connection_release_io_path unlocking io_path_mutex 91: _dbus_connection_do_iteration_unlocked end 91: _dbus_connection_block_pending_call top of recheck 91: check_for_reply_and_update_dispatch_unlocked checked for reply 91: dbus_connection_send_with_reply_and_block(): got reply 91: handing message 0x404050 (method return) to pending call serial 1 91: UNLOCK: _dbus_connection_unlock 91: LOCK: free_pending_call_on_hash_removal 91: UNLOCK: _dbus_connection_unlock 91: LOCK: _dbus_connection_lock 91: UNLOCK: _dbus_connection_unlock 91: LOCK: check_for_reply_and_update_dispatch_unlocked 91: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock 91: LOCK: dbus_connection_unref 91: UNLOCK: dbus_connection_unref 91: LOCK: _dbus_connection_lock 91: UNLOCK: _dbus_connection_unlock 91: LOCK: _dbus_connection_lock 91: UNLOCK: _dbus_connection_unlock 91: LOCK: _dbus_connection_lock 91: UNLOCK: _dbus_connection_unlock 91: Freeing slot 0 on allocator 0x2ab19be0 total 1 allocated 1 used 91: LOCK: dbus_connection_unref 91: UNLOCK: dbus_connection_unref 91: LOCK: dbus_connection_set_exit_on_disconnect 91: UNLOCK: dbus_connection_set_exit_on_disconnect 91: LOCK: dbus_connection_get_data 91: UNLOCK: dbus_connection_get_data message content : TestSignal-@@@@@ 91: LOCK: dbus_connection_send 91: Message 0x402ce8 (4 /com/wiley/test com.wiley.test TestSignal 's') for com.wiley.test added to outgoing queue 0x402860, 1 pending to send 91: Message 0x402ce8 serial is 2 91: _dbus_connection_do_iteration_unlocked start 91: UNLOCK: _dbus_connection_acquire_io_path 91: _dbus_connection_acquire_io_path locking io_path_mutex 91: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0 91: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 91: _dbus_connection_acquire_io_path unlocking io_path_mutex 91: LOCK: _dbus_connection_acquire_io_path 91: Transport iteration flags 0x1 timeout -1 connected = 1 91: iteration flags = write timeout = -1 read_watch = 0x4024f0 write_watch = 0x4024c8 fd = 3 91: do_writing(), have_messages = 1, fd = 3 91: wrote 130 bytes of 130 91: Message 0x402ce8 (4 /com/wiley/test com.wiley.test TestSignal 's') removed from outgoing queue 0x402860, 0 left to send 91: check_write_watch(): needed = 0 on connection 0x402860 watch 0x4024c8 fd = 3 outgoing messages exist 0 91: UNLOCK: protected_change_watch 91: LOCK: protected_change_watch 91: ... leaving do_iteration() 91: _dbus_transport_do_iteration end 91: _dbus_connection_release_io_path locking io_path_mutex 91: _dbus_connection_release_io_path start connection->io_path_acquired = 1 91: _dbus_connection_release_io_path unlocking io_path_mutex 91: _dbus_connection_do_iteration_unlocked end 91: _dbus_connection_send_preallocated_and_unlock middle 91: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock 91: LOCK: dbus_connection_unref 91: UNLOCK: dbus_connection_unref 91: LOCK: dbus_connection_flush 91: _dbus_connection_flush_unlocked middle 91: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock 91: LOCK: dbus_connection_unref 91: UNLOCK: dbus_connection_unref 91: dbus_connection_flush end Sending signal to D-Bus 91: LOCK: dbus_connection_unref 91: UNLOCK: dbus_connection_unref signal sender=org.freedesktop.DBus -> dest=(null destination) serial=17 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged string ":1.6" string "" string ":1.6" method call sender=:1.6 -> dest=org.freedesktop.DBus serial=1 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=Hello signal sender=:1.6 -> dest=com.wiley.test serial=2 path=/com/wiley/test; interface=com.wiley.test; member=TestSignal string "@@@@@" 85: _dbus_connection_handle_watch start 85: LOCK: _dbus_connection_handle_watch 85: UNLOCK: _dbus_connection_acquire_io_path 85: _dbus_connection_acquire_io_path locking io_path_mutex 85: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1 85: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 85: _dbus_connection_acquire_io_path unlocking io_path_mutex 85: LOCK: _dbus_connection_acquire_io_path 85: handling read watch 0x4034e0 flags = 1 85: do_reading: fd = 3 85: check_read_watch: fd = 3 85: setting read watch enabled = 1 85: UNLOCK: protected_change_watch 85: LOCK: signal sender=org.freedesktop.DBus -> dest=(null destination) serial=18 path=/org/freedesktop/DBus; interface=org.freedesktop.DBus; member=NameOwnerChanged string ":1.6" string ":1.6" string "" protected_change_watch 85: read 146 bytes 85: have 146 bytes, need body 10 + header 136 = 146 85: validating body from pos 0 len 146 sig 'yyyyuua(yv)' 85: p = 0x4048a7 end = 0x4048c2 claimed_len 1 85: initially caching field 1 85: initially caching field 2 85: initially caching field 3 85: initially caching field 6 85: initially caching field 8 85: initially caching field 7 85: validating body from pos 136 len 10 sig 's' 85: Loaded message 0x4050b8 85: queueing received message 0x4050b8 85: Message 0x4050b8 (4 /com/wiley/test com.wiley.test TestSignal 's' reply to 0) added to incoming queue 0x403850, 1 incoming 85: check_read_watch: fd = 3 85: setting read watch enabled = 1 85: UNLOCK: protected_change_watch 85: LOCK: protected_change_watch 85: _dbus_connection_release_io_path locking io_path_mutex 85: _dbus_connection_release_io_path start connection->io_path_acquired = 1 85: _dbus_connection_release_io_path unlocking io_path_mutex 85: _dbus_connection_handle_watch middle 85: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock 85: LOCK: dbus_connection_unref 85: UNLOCK: dbus_connection_unref 85: _dbus_connection_handle_watch end 85: After sanitization, watch flags on fd 3 were 0 85: After sanitization, watch flags on fd 3 were 0 85: _dbus_connection_handle_watch start 85: LOCK: _dbus_connection_handle_watch 85: UNLOCK: _dbus_connection_acquire_io_path 85: _dbus_connection_acquire_io_path locking io_path_mutex 85: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1 85: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1 85: _dbus_connection_acquire_io_path unlocking io_path_mutex 85: LOCK: _dbus_connection_acquire_io_path 85: handling write watch, have_outgoing_messages = 0 85: do_writing(), have_messages = 0, fd = 3 85: check_write_watch(): needed = 0 on connection 0x403850 watch 0x4034b8 fd = 3 outgoing messages exist 0 85: UNLOCK: protected_change_watch 85: LOCK: protected_change_watch 85: _dbus_connection_release_io_path locking io_path_mutex 85: _dbus_connection_release_io_path start connection->io_path_acquired = 1 85: _dbus_connection_release_io_path unlocking io_path_mutex 85: _dbus_connection_handle_watch middle 85: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock 85: LOCK: dbus_connection_unref 85: UNLOCK: dbus_connection_unref 85: _dbus_connection_handle_watch end 85: dbus_connection_dispatch 85: LOCK: dbus_connection_dispatch 85: UNLOCK: _dbus_connection_acquire_dispatch 85: _dbus_connection_acquire_dispatch locking dispatch_mutex 85: _dbus_connection_acquire_dispatch unlocking dispatch_mutex 85: LOCK: _dbus_connection_acquire_dispatch 85: Message 0x4050b8 (4 /com/wiley/test com.wiley.test TestSignal 's') removed from incoming queue 0x403850, 0 incoming 85: dispatching message 0x4050b8 (4 com.wiley.test TestSignal 's') 85: UNLOCK: dbus_connection_dispatch 85: running filter on message 0x4050b8 Received TestSignal with value of: '@@@@@' 85: LOCK: dbus_connection_dispatch 85: filter handled message in dispatch 85: ... done dispatching in dbus_connection_dispatch 85: _dbus_connection_release_dispatch locking dispatch_mutex 85: _dbus_connection_release_dispatch unlocking dispatch_mutex 85: dbus_connection_dispatch before final status update 85: dispatch status = complete is_connected = 1 85: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock 85: LOCK: dbus_connection_unref 85: UNLOCK: dbus_connection_unref 85: LOCK: dbus_connection_unref 85: UNLOCK: dbus_connection_unref