Deadlock in D-Bus when using gtk_dialog_run
John (J5) Palmieri
johnp at redhat.com
Wed Apr 6 08:59:48 PDT 2005
gnome-volume-manager is deadlocking dbus when trying to display the
import photo dialog. Attached is the relevant debugging output - a gdb
backtrace, relevant output of gnome-volume-manager with DBUS_VERBOSE
turned on and the output of top.
--
John (J5) Palmieri
Associate Software Engineer
Desktop Group
Red Hat, Inc.
Blog: http://martianrock.com
-------------- next part --------------
3383: Message 0x8ab4d60 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager DeviceAdded 's') removed from incoming queue 0x8aac180, 0 incoming
3383: dispatching message 0x8ab4d60 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: UNLOCK: dbus_connection_dispatch
3383: running filter on message 0x8ab4d60
manager.c/798: New Device: /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab4ce8 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab4ce8 serial is 35
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 270 bytes of 270
3383: Message 0x8ab4ce8 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 35 from 1112802545 sec 711010 usec to 1112802570 sec 711010 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 82 bytes
3383: have 82 bytes, need body 18 + header 64 = 82
3383: validating body from pos 0 len 82 sig 'yyyyuua(yv)'
3383: p = 0x8aadf80 end = 0x8aadfa2 claimed_len 2
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 18 sig 'as'
3383: Loaded message 0x8ab5580
3383: queueing received message 0x8ab5580
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab5580 (2 (null) no interface no member 'as' reply to 35) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab5580 (method return) to pending call serial 35
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab5580 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab5580 serial is 36
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 270 bytes of 270
3383: Message 0x8ab5580 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 36 from 1112802545 sec 715552 usec to 1112802570 sec 715552 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 82 bytes
3383: have 82 bytes, need body 18 + header 64 = 82
3383: validating body from pos 0 len 82 sig 'yyyyuua(yv)'
3383: p = 0x8aadf80 end = 0x8aadfa2 claimed_len 2
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 18 sig 'as'
3383: Loaded message 0x8ab57f8
3383: queueing received message 0x8ab57f8
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab57f8 (2 (null) no interface no member 'as' reply to 36) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab57f8 (method return) to pending call serial 36
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_dispatch
3383: running object path dispatch on message 0x8ab4d60 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: 0 handlers in the path tree for this message
3383: considering default Introspect() handler...
3383: unlock handle_default_introspect_and_unlock 640
3383: UNLOCK: _dbus_connection_unlock
3383: LOCK: dbus_connection_dispatch
3383: done dispatching 0x8ab4d60 (4 org.freedesktop.Hal.Manager DeviceAdded 's') on connection 0x8aac180
3383: ... done dispatching in dbus_connection_dispatch
3383: _dbus_connection_release_dispatch locking dispatch_mutex
3383: _dbus_connection_release_dispatch unlocking dispatch_mutex
3383: dbus_connection_dispatch before final status update
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_get_dispatch_status start
3383: LOCK: dbus_connection_get_dispatch_status
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: dbus_connection_get_dispatch_status
3383: LOCK: dbus_connection_ref
3383: UNLOCK: dbus_connection_ref
3383: _dbus_connection_handle_watch start
3383: LOCK: _dbus_connection_handle_watch
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: handling read watch 0x8aabe90 flags = 1
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 272 bytes
3383: have 272 bytes, need body 128 + header 144 = 272
3383: validating body from pos 0 len 272 sig 'yyyyuua(yv)'
3383: p = 0x8aadfcf end = 0x8aae060 claimed_len 1
3383: initially caching field 1
3383: initially caching field 2
3383: initially caching field 3
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 144 len 128 sig 's'
3383: Loaded message 0x8ab57f8
3383: queueing received message 0x8ab57f8
3383: Message 0x8ab57f8 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager DeviceAdded 's' reply to 0) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_handle_watch middle
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: _dbus_connection_handle_watch end
3383: dbus_connection_dispatch
3383: LOCK: dbus_connection_dispatch
3383: UNLOCK: _dbus_connection_acquire_dispatch
3383: _dbus_connection_acquire_dispatch locking dispatch_mutex
3383: _dbus_connection_acquire_dispatch unlocking dispatch_mutex
3383: LOCK: _dbus_connection_acquire_dispatch
3383: Message 0x8ab57f8 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager DeviceAdded 's') removed from incoming queue 0x8aac180, 0 incoming
3383: dispatching message 0x8ab57f8 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: UNLOCK: dbus_connection_dispatch
3383: running filter on message 0x8ab57f8
manager.c/798: New Device: /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host_scsi_device_lun0
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab5580 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host_scsi_device_lun0 org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab5580 serial is 37
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 286 bytes of 286
3383: Message 0x8ab5580 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host_scsi_device_lun0 org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 37 from 1112802549 sec 686279 usec to 1112802574 sec 686279 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 288 bytes
3383: have 288 bytes, need body 176 + header 112 = 288
3383: validating body from pos 0 len 288 sig 'yyyyuua(yv)'
3383: p = 0x8aadfaf end = 0x8aae070 claimed_len 1
3383: initially caching field 6
3383: initially caching field 4
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 112 len 176 sig 's'
3383: Loaded message 0x8ab56a0
3383: queueing received message 0x8ab56a0
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab56a0 (3 (null) no interface no member 's' reply to 37) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab56a0 (error) to pending call serial 37
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab56a0 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host_scsi_device_lun0 org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab56a0 serial is 38
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 286 bytes of 286
3383: Message 0x8ab56a0 (1 /org/freedesktop/Hal/devices/usb_device_5dc_80_______________03052700010003FE2CC100000000000_if0_scsi_host_scsi_device_lun0 org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 38 from 1112802549 sec 690465 usec to 1112802574 sec 690465 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 288 bytes
3383: have 288 bytes, need body 176 + header 112 = 288
3383: validating body from pos 0 len 288 sig 'yyyyuua(yv)'
3383: p = 0x8aadfaf end = 0x8aae070 claimed_len 1
3383: initially caching field 6
3383: initially caching field 4
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 112 len 176 sig 's'
3383: Loaded message 0x8ab5718
3383: queueing received message 0x8ab5718
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab5718 (3 (null) no interface no member 's' reply to 38) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab5718 (error) to pending call serial 38
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_dispatch
3383: running object path dispatch on message 0x8ab57f8 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: 0 handlers in the path tree for this message
3383: considering default Introspect() handler...
3383: unlock handle_default_introspect_and_unlock 640
3383: UNLOCK: _dbus_connection_unlock
3383: LOCK: dbus_connection_dispatch
3383: done dispatching 0x8ab57f8 (4 org.freedesktop.Hal.Manager DeviceAdded 's') on connection 0x8aac180
3383: ... done dispatching in dbus_connection_dispatch
3383: _dbus_connection_release_dispatch locking dispatch_mutex
3383: _dbus_connection_release_dispatch unlocking dispatch_mutex
3383: dbus_connection_dispatch before final status update
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_get_dispatch_status start
3383: LOCK: dbus_connection_get_dispatch_status
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: dbus_connection_get_dispatch_status
3383: LOCK: dbus_connection_ref
3383: UNLOCK: dbus_connection_ref
3383: _dbus_connection_handle_watch start
3383: LOCK: _dbus_connection_handle_watch
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: handling read watch 0x8aabe90 flags = 1
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 204 bytes
3383: have 204 bytes, need body 60 + header 144 = 204
3383: validating body from pos 0 len 204 sig 'yyyyuua(yv)'
3383: p = 0x8aadfcf end = 0x8aae01c claimed_len 1
3383: initially caching field 1
3383: initially caching field 2
3383: initially caching field 3
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 144 len 60 sig 's'
3383: Loaded message 0x8ab5718
3383: queueing received message 0x8ab5718
3383: Message 0x8ab5718 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager DeviceAdded 's' reply to 0) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_handle_watch middle
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: _dbus_connection_handle_watch end
3383: dbus_connection_dispatch
3383: LOCK: dbus_connection_dispatch
3383: UNLOCK: _dbus_connection_acquire_dispatch
3383: _dbus_connection_acquire_dispatch locking dispatch_mutex
3383: _dbus_connection_acquire_dispatch unlocking dispatch_mutex
3383: LOCK: _dbus_connection_acquire_dispatch
3383: Message 0x8ab5718 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager DeviceAdded 's') removed from incoming queue 0x8aac180, 0 incoming
3383: dispatching message 0x8ab5718 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: UNLOCK: dbus_connection_dispatch
3383: running filter on message 0x8ab5718
manager.c/798: New Device: /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab56a0 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab56a0 serial is 39
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 214 bytes of 214
3383: Message 0x8ab56a0 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 39 from 1112802549 sec 984408 usec to 1112802574 sec 984408 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 90 bytes
3383: have 90 bytes, need body 26 + header 64 = 90
3383: validating body from pos 0 len 90 sig 'yyyyuua(yv)'
3383: p = 0x8aadf80 end = 0x8aadfaa claimed_len 2
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 26 sig 'as'
3383: Loaded message 0x8ab6318
3383: queueing received message 0x8ab6318
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6318 (2 (null) no interface no member 'as' reply to 39) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab6318 (method return) to pending call serial 39
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6318 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6318 serial is 40
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 214 bytes of 214
3383: Message 0x8ab6318 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 40 from 1112802549 sec 988792 usec to 1112802574 sec 988792 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 90 bytes
3383: have 90 bytes, need body 26 + header 64 = 90
3383: validating body from pos 0 len 90 sig 'yyyyuua(yv)'
3383: p = 0x8aadf80 end = 0x8aadfaa claimed_len 2
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 26 sig 'as'
3383: Loaded message 0x8ab6508
3383: queueing received message 0x8ab6508
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6508 (2 (null) no interface no member 'as' reply to 40) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab6508 (method return) to pending call serial 40
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6508 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyBoolean 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6508 serial is 41
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 212 bytes of 212
3383: Message 0x8ab6508 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyBoolean 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 41 from 1112802549 sec 991735 usec to 1112802574 sec 991735 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 68 bytes
3383: have 68 bytes, need body 4 + header 64 = 68
3383: validating body from pos 0 len 68 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf94 claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 4 sig 'b'
3383: Loaded message 0x8ab6730
3383: queueing received message 0x8ab6730
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6730 (2 (null) no interface no member 'b' reply to 41) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab6730 (method return) to pending call serial 41
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_dispatch
3383: running object path dispatch on message 0x8ab5718 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: 0 handlers in the path tree for this message
3383: considering default Introspect() handler...
3383: unlock handle_default_introspect_and_unlock 640
3383: UNLOCK: _dbus_connection_unlock
3383: LOCK: dbus_connection_dispatch
3383: done dispatching 0x8ab5718 (4 org.freedesktop.Hal.Manager DeviceAdded 's') on connection 0x8aac180
3383: ... done dispatching in dbus_connection_dispatch
3383: _dbus_connection_release_dispatch locking dispatch_mutex
3383: _dbus_connection_release_dispatch unlocking dispatch_mutex
3383: dbus_connection_dispatch before final status update
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_get_dispatch_status start
3383: LOCK: dbus_connection_get_dispatch_status
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: dbus_connection_get_dispatch_status
3383: LOCK: dbus_connection_ref
3383: UNLOCK: dbus_connection_ref
3383: _dbus_connection_handle_watch start
3383: LOCK: _dbus_connection_handle_watch
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: handling read watch 0x8aabe90 flags = 1
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 199 bytes
3383: have 199 bytes, need body 55 + header 144 = 199
3383: validating body from pos 0 len 199 sig 'yyyyuua(yv)'
3383: p = 0x8aadfcf end = 0x8aae017 claimed_len 1
3383: initially caching field 1
3383: initially caching field 2
3383: initially caching field 3
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 144 len 55 sig 's'
3383: Loaded message 0x8ab6508
3383: queueing received message 0x8ab6508
3383: Message 0x8ab6508 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager DeviceAdded 's' reply to 0) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_handle_watch middle
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: _dbus_connection_handle_watch end
3383: dbus_connection_dispatch
3383: LOCK: dbus_connection_dispatch
3383: UNLOCK: _dbus_connection_acquire_dispatch
3383: _dbus_connection_acquire_dispatch locking dispatch_mutex
3383: _dbus_connection_acquire_dispatch unlocking dispatch_mutex
3383: LOCK: _dbus_connection_acquire_dispatch
3383: Message 0x8ab6508 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager DeviceAdded 's') removed from incoming queue 0x8aac180, 0 incoming
3383: dispatching message 0x8ab6508 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: UNLOCK: dbus_connection_dispatch
3383: running filter on message 0x8ab6508
manager.c/798: New Device: /org/freedesktop/Hal/devices/volume_uuid_2214_16EE
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6730 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6730 serial is 42
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 214 bytes of 214
3383: Message 0x8ab6730 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 42 from 1112802550 sec 240443 usec to 1112802575 sec 240443 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 90 bytes
3383: have 90 bytes, need body 26 + header 64 = 90
3383: validating body from pos 0 len 90 sig 'yyyyuua(yv)'
3383: p = 0x8aadf80 end = 0x8aadfaa claimed_len 2
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 26 sig 'as'
3383: Loaded message 0x8ab69c0
3383: queueing received message 0x8ab69c0
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab69c0 (2 (null) no interface no member 'as' reply to 42) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab69c0 (method return) to pending call serial 42
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab69c0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyStringList 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab69c0 serial is 43
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 214 bytes of 214
3383: Message 0x8ab69c0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyStringList 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 43 from 1112802550 sec 243014 usec to 1112802575 sec 243014 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 90 bytes
3383: have 90 bytes, need body 26 + header 64 = 90
3383: validating body from pos 0 len 90 sig 'yyyyuua(yv)'
3383: p = 0x8aadf80 end = 0x8aadfaa claimed_len 2
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 26 sig 'as'
3383: Loaded message 0x8ab6b88
3383: queueing received message 0x8ab6b88
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6b88 (2 (null) no interface no member 'as' reply to 43) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab6b88 (method return) to pending call serial 43
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyBoolean 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6b88 serial is 44
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 212 bytes of 212
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyBoolean 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 44 from 1112802550 sec 245087 usec to 1112802575 sec 245087 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 68 bytes
3383: have 68 bytes, need body 4 + header 64 = 68
3383: validating body from pos 0 len 68 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf94 claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 4 sig 'b'
3383: Loaded message 0x8ab6db0
3383: queueing received message 0x8ab6db0
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6db0 (2 (null) no interface no member 'b' reply to 44) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab6db0 (method return) to pending call serial 44
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6b88 serial is 45
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 209 bytes of 209
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 45 from 1112802550 sec 247104 usec to 1112802575 sec 247104 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 78 bytes
3383: have 78 bytes, need body 14 + header 64 = 78
3383: validating body from pos 0 len 78 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf9e claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 14 sig 's'
3383: Loaded message 0x8ab6f68
3383: queueing received message 0x8ab6f68
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6f68 (2 (null) no interface no member 's' reply to 45) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab6f68 (method return) to pending call serial 45
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6b88 serial is 46
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 217 bytes of 217
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 46 from 1112802550 sec 249121 usec to 1112802575 sec 249121 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 124 bytes
3383: have 124 bytes, need body 60 + header 64 = 124
3383: validating body from pos 0 len 124 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadfcc claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 60 sig 's'
3383: Loaded message 0x8ab7120
3383: queueing received message 0x8ab7120
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab7120 (2 (null) no interface no member 's' reply to 46) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab7120 (method return) to pending call serial 46
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyBoolean 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6b88 serial is 47
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 214 bytes of 214
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyBoolean 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 47 from 1112802550 sec 251136 usec to 1112802575 sec 251136 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 68 bytes
3383: have 68 bytes, need body 4 + header 64 = 68
3383: validating body from pos 0 len 68 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf94 claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 4 sig 'b'
3383: Loaded message 0x8ab7360
3383: queueing received message 0x8ab7360
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab7360 (2 (null) no interface no member 'b' reply to 47) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab7360 (method return) to pending call serial 47
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
manager.c/838: Changed: /dev/sdb1
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device PropertyExists 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6b88 serial is 48
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 200 bytes of 200
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device PropertyExists 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 48 from 1112802550 sec 253157 usec to 1112802575 sec 253157 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 68 bytes
3383: have 68 bytes, need body 4 + header 64 = 68
3383: validating body from pos 0 len 68 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf94 claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 4 sig 'b'
3383: Loaded message 0x8ab74e8
3383: queueing received message 0x8ab74e8
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab74e8 (2 (null) no interface no member 'b' reply to 48) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab74e8 (method return) to pending call serial 48
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyString 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab6b88 serial is 49
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 215 bytes of 215
3383: Message 0x8ab6b88 (1 /org/freedesktop/Hal/devices/storage_model_DIGITAL_FILM org.freedesktop.Hal.Device GetPropertyString 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 49 from 1112802550 sec 255157 usec to 1112802575 sec 255157 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 73 bytes
3383: have 73 bytes, need body 9 + header 64 = 73
3383: validating body from pos 0 len 73 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf99 claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 9 sig 's'
3383: Loaded message 0x8ab76a0
3383: queueing received message 0x8ab76a0
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab76a0 (2 (null) no interface no member 's' reply to 49) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab76a0 (method return) to pending call serial 49
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
manager.c/775: Added: /dev/sdb1
3383: LOCK: dbus_connection_dispatch
3383: running object path dispatch on message 0x8ab6508 (4 org.freedesktop.Hal.Manager DeviceAdded 's')
3383: 0 handlers in the path tree for this message
3383: considering default Introspect() handler...
3383: unlock handle_default_introspect_and_unlock 640
3383: UNLOCK: _dbus_connection_unlock
3383: LOCK: dbus_connection_dispatch
3383: done dispatching 0x8ab6508 (4 org.freedesktop.Hal.Manager DeviceAdded 's') on connection 0x8aac180
3383: ... done dispatching in dbus_connection_dispatch
3383: _dbus_connection_release_dispatch locking dispatch_mutex
3383: _dbus_connection_release_dispatch unlocking dispatch_mutex
3383: dbus_connection_dispatch before final status update
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_get_dispatch_status start
3383: LOCK: dbus_connection_get_dispatch_status
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: dbus_connection_get_dispatch_status
3383: LOCK: dbus_connection_ref
3383: UNLOCK: dbus_connection_ref
3383: _dbus_connection_handle_watch start
3383: LOCK: _dbus_connection_handle_watch
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = -1
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: handling read watch 0x8aabe90 flags = 1
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 256 bytes
3383: have 256 bytes, need body 72 + header 184 = 256
3383: validating body from pos 0 len 256 sig 'yyyyuua(yv)'
3383: p = 0x8aadff5 end = 0x8aae050 claimed_len 7
3383: initially caching field 1
3383: initially caching field 2
3383: initially caching field 3
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 184 len 72 sig 'ia(sbb)'
3383: Loaded message 0x8ab6b88
3383: queueing received message 0x8ab6b88
3383: Message 0x8ab6b88 (4 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device PropertyModified 'ia(sbb)' reply to 0) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_handle_watch middle
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: _dbus_connection_handle_watch end
3383: dbus_connection_dispatch
3383: LOCK: dbus_connection_dispatch
3383: UNLOCK: _dbus_connection_acquire_dispatch
3383: _dbus_connection_acquire_dispatch locking dispatch_mutex
3383: _dbus_connection_acquire_dispatch unlocking dispatch_mutex
3383: LOCK: _dbus_connection_acquire_dispatch
3383: Message 0x8ab6b88 (4 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device PropertyModified 'ia(sbb)') removed from incoming queue 0x8aac180, 0 incoming
3383: dispatching message 0x8ab6b88 (4 org.freedesktop.Hal.Device PropertyModified 'ia(sbb)')
3383: UNLOCK: dbus_connection_dispatch
3383: running filter on message 0x8ab6b88
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyBoolean 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab76a0 serial is 50
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 214 bytes of 214
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyBoolean 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 50 from 1112802550 sec 382669 usec to 1112802575 sec 382669 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 68 bytes
3383: have 68 bytes, need body 4 + header 64 = 68
3383: validating body from pos 0 len 68 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf94 claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 4 sig 'b'
3383: Loaded message 0x8ab7978
3383: queueing received message 0x8ab7978
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab7978 (2 (null) no interface no member 'b' reply to 50) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab7978 (method return) to pending call serial 50
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
manager.c/923: Mounted: /org/freedesktop/Hal/devices/volume_uuid_2214_16EE
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab76a0 serial is 51
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 209 bytes of 209
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 51 from 1112802550 sec 385915 usec to 1112802575 sec 385915 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 78 bytes
3383: have 78 bytes, need body 14 + header 64 = 78
3383: validating body from pos 0 len 78 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadf9e claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 14 sig 's'
3383: Loaded message 0x8ab7b10
3383: queueing received message 0x8ab7b10
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab7b10 (2 (null) no interface no member 's' reply to 51) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab7b10 (method return) to pending call serial 51
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab76a0 serial is 52
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 215 bytes of 215
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device GetPropertyString 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 52 from 1112802550 sec 389024 usec to 1112802575 sec 389024 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 86 bytes
3383: have 86 bytes, need body 22 + header 64 = 86
3383: validating body from pos 0 len 86 sig 'yyyyuua(yv)'
3383: p = 0x8aadf7f end = 0x8aadfa6 claimed_len 1
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 64 len 22 sig 's'
3383: Loaded message 0x8ab7cc8
3383: queueing received message 0x8ab7cc8
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab7cc8 (2 (null) no interface no member 's' reply to 52) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab7cc8 (method return) to pending call serial 52
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
manager.c/313: Photos detected: /media/USB_MEMORY/dcim
3383: LOCK: dbus_connection_send_with_reply
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device AddCapability 's') for org.freedesktop.Hal added to outgoing queue 0x8aac180, 1 pending to send
3383: Message 0x8ab76a0 serial is 53
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 0
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x1 timeout -1 connected = 1
3383: iteration flags = write timeout = -1 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: do_writing(), have_messages = 1, fd = 16
3383: wrote 203 bytes of 203
3383: Message 0x8ab76a0 (1 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device AddCapability 's') removed from outgoing queue 0x8aac180, 0 left to send
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: dbus_connection_send_with_reply middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: LOCK: dbus_connection_flush
3383: dbus_connection_flush middle
3383: dispatch status = complete is_connected = 1
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_flush end
3383: LOCK: _dbus_connection_block_pending_call
3383: dbus_connection_send_with_reply_and_block(): will block 25000 milliseconds for reply serial 53 from 1112802550 sec 421325 usec to 1112802575 sec 421325 usec
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 25000
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 25000 connected = 1
3383: iteration flags = read timeout = 25000 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 224 bytes
3383: have 224 bytes, need body 40 + header 184 = 224
3383: validating body from pos 0 len 224 sig 'yyyyuua(yv)'
3383: p = 0x8aadff5 end = 0x8aae030 claimed_len 7
3383: initially caching field 1
3383: initially caching field 2
3383: initially caching field 3
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 184 len 40 sig 'ia(sbb)'
3383: Loaded message 0x8ab7ef0
3383: queueing received message 0x8ab7ef0
3383: Message 0x8ab7ef0 (4 /org/freedesktop/Hal/devices/volume_uuid_2214_16EE org.freedesktop.Hal.Device PropertyModified 'ia(sbb)' reply to 0) added to incoming queue 0x8aac180, 1 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 219 bytes
3383: have 219 bytes, need body 75 + header 144 = 219
3383: validating body from pos 0 len 219 sig 'yyyyuua(yv)'
3383: p = 0x8aadfd0 end = 0x8aae02b claimed_len 2
3383: initially caching field 1
3383: initially caching field 2
3383: initially caching field 3
3383: initially caching field 8
3383: initially caching field 7
3383: validating body from pos 144 len 75 sig 'ss'
3383: Loaded message 0x8ab8060
3383: queueing received message 0x8ab8060
3383: Message 0x8ab8060 (4 /org/freedesktop/Hal/Manager org.freedesktop.Hal.Manager NewCapability 'ss' reply to 0) added to incoming queue 0x8aac180, 2 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: dbus_connection_send_with_reply_and_block(): 24996 milliseconds remain
3383: _dbus_connection_do_iteration_unlocked start
3383: UNLOCK: _dbus_connection_acquire_io_path
3383: _dbus_connection_acquire_io_path locking io_path_mutex
3383: _dbus_connection_acquire_io_path start connection->io_path_acquired = 0 timeout = 24996
3383: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1 we_acquired = 1
3383: _dbus_connection_acquire_io_path unlocking io_path_mutex
3383: LOCK: _dbus_connection_acquire_io_path
3383: Transport iteration flags 0x6 timeout 24996 connected = 1
3383: iteration flags = read timeout = 24996 read_watch = 0x8aabe90 write_watch = 0x8a9eb68 fd = 16
3383: unlock unix_do_iteration pre poll
3383: UNLOCK: _dbus_connection_unlock
3383: lock unix_do_iteration post poll
3383: LOCK: _dbus_connection_lock
3383: in iteration, need_read=1 need_write=0
3383: do_reading: fd = 16
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: read 56 bytes
3383: have 56 bytes, need body 0 + header 56 = 56
3383: validating body from pos 0 len 56 sig 'yyyyuua(yv)'
3383: initially caching field 6
3383: initially caching field 5
3383: initially caching field 7
3383: validating body from pos 56 len 0 sig ''
3383: Loaded message 0x8ab8200
3383: queueing received message 0x8ab8200
3383: UNLOCK: protected_change_timeout
3383: LOCK: protected_change_timeout
3383: Message 0x8ab8200 (2 (null) no interface no member '' reply to 53) added to incoming queue 0x8aac180, 3 incoming
3383: check_read_watch: fd = 16
3383: setting read watch enabled = 1
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: check_write_watch(): needed = 0 on connection 0x8aac180 watch 0x8a9eb68 fd = 16 outgoing messages exist 0
3383: UNLOCK: protected_change_watch
3383: LOCK: protected_change_watch
3383: ... leaving do_iteration()
3383: _dbus_transport_do_iteration end
3383: _dbus_connection_release_io_path locking io_path_mutex
3383: _dbus_connection_release_io_path start connection->io_path_acquired = 1
3383: _dbus_connection_release_io_path unlocking io_path_mutex
3383: _dbus_connection_do_iteration_unlocked end
3383: _dbus_connection_block_pending_call top of recheck
3383: _dbus_connection_block_pending_call checked for reply
3383: dbus_connection_send_with_reply_and_block(): got reply
3383: handing message 0x8ab8200 (method return) to pending call serial 53
3383: UNLOCK: _dbus_connection_detach_pending_call_and_unlock
3383: LOCK: _dbus_connection_block_pending_call
3383: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
3383: LOCK: dbus_connection_unref
3383: UNLOCK: dbus_connection_unref
3383: dbus_connection_get_dispatch_status start
3383: LOCK: dbus_connection_get_dispatch_status
3383: UNLOCK: dbus_connection_get_dispatch_status
3383: LOCK: dbus_connection_ref
3383: UNLOCK: dbus_connection_ref
3383: dbus_connection_dispatch
3383: LOCK: dbus_connection_dispatch
3383: UNLOCK: _dbus_connection_acquire_dispatch
3383: _dbus_connection_acquire_dispatch locking dispatch_mutex
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
3383: _dbus_connection_acquire_dispatch waiting for dispatch to be acquirable
(repeat thousands of times)
-------------- next part --------------
#0 0x00d034b1 in ?? () from /lib/libpthread.so.0
#1 0x0010b573 in dbus_gcondvar_wait (cond=0xfffffffc, mutex=0xfffffffc) at dbus-gthread.c:124
#2 0x06ee0a9a in _dbus_condvar_wait (cond=0x863e448, mutex=0x863e428) at dbus-threads.c:150
#3 0x06ebd18c in _dbus_connection_acquire_dispatch (connection=0x863e360)
at dbus-connection.c:3085
#4 0x06ebdcba in dbus_connection_dispatch (connection=0x863e360) at dbus-connection.c:3307
#5 0x00106057 in message_queue_dispatch (source=0xfffffffc, callback=0, user_data=0x0)
at dbus-gmain.c:107
#6 0x003a046e in g_main_context_dispatch () from /usr/lib/libglib-2.0.so.0
#7 0x003a3476 in g_main_context_check () from /usr/lib/libglib-2.0.so.0
#8 0x003a3763 in g_main_loop_run () from /usr/lib/libglib-2.0.so.0
#9 0x0066639a in gtk_dialog_run () from /usr/lib/libgtk-x11-2.0.so.0
#10 0x0804a9ee in gvm_check_photos (
udi=0x8648fd0 "/org/freedesktop/Hal/devices/volume_uuid_2214_16EE",
device=0x8647708 "/dev/sdb1", mount_point=0x86483e8 "/media/USB_MEMORY") at manager.c:344
#11 0x0804acdd in gvm_device_autorun (
udi=0x8648fd0 "/org/freedesktop/Hal/devices/volume_uuid_2214_16EE") at manager.c:443
#12 0x0804b972 in hal_property_modified (ctx=0x8630d38,
udi=0x8648fd0 "/org/freedesktop/Hal/devices/volume_uuid_2214_16EE",
key=0x8649b0c "volume.is_mounted", is_removed=0, is_added=0) at manager.c:943
#13 0x00372993 in filter_func (connection=0x863e360, message=0x8648e68, user_data=0x8630d38)
at libhal.c:725
#14 0x06ebe15a in dbus_connection_dispatch (connection=0x863e360) at dbus-connection.c:3399
#15 0x0010621e in io_handler_dispatch (source=0x863fc18, condition=0, data=0x863dda0)
at dbus-gmain.c:245
#16 0x003c5d4c in g_vasprintf () from /usr/lib/libglib-2.0.so.0
#17 0x003a046e in g_main_context_dispatch () from /usr/lib/libglib-2.0.so.0
#18 0x003a3476 in g_main_context_check () from /usr/lib/libglib-2.0.so.0
#19 0x003a3763 in g_main_loop_run () from /usr/lib/libglib-2.0.so.0
#20 0x006d4cd5 in gtk_main () from /usr/lib/libgtk-x11-2.0.so.0
#21 0x0804c316 in main (argc=1, argv=0xbfa57c84) at manager.c:1285
-------------- next part --------------
PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
3536 johnp 25 0 21612 9828 7064 R 99.9 1.0 0:15.66 gnome-volume-ma
More information about the dbus
mailing list