DBUS disconnect issue ( Disconnected from remote app)
Ajay EP
Ajay.EP at lntinfotech.com
Fri Aug 1 05:39:17 PDT 2008
HI ,
I am running DBUS on ARM 9 . The DBUS successfully creates the system bus
socket and I have the messagebus.pid system_bus_socket created in the
/var/run/dbus folder.
However when I run bluetooth hcid or any test application to use DBUS ,
it disconnects soon after connecting.
Attached is the verbose enabled logs. Any help in debugging is
appreciated.
Thanks
Ajay
------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------
cd /opt/bluez/sbin/-/bin/sh-2.05b# -/bin/sh-2.05b#
./hcid -n -d 1
hcid[785]: Bluetooth HCI daemon
hcid[785]: Enabling debug information
hcid[785]: Parsing /opt/bluez/etc/bluetooth/main.conf failed: No such file
or di
rectory
785: Filling in system bus address...
785: used default system bus
"unix:path=/opt/bluez/var/run/dbus/system_bus_soc
ket"
785: Filling in session bus address...
785:
"unix:abstract=/tmp/dbus-RBN05TvXCo,guid=66aa1a8946a63ced7da9e60000001399
"
785: Filling in activation bus address...
785: "none set"
785: opening shared connection to:
unix:path=/opt/bluez/var/run/dbus/system_bus_
socket
785: checking for existing connection
785: creating shared_connections hash table
785: successfully created shared_connections
785: connecting to unix socket /opt/bluez/var/run/dbus/system_bus_socket
abstrac
t=0
785: Successfully connected to unix socket
/opt/bluez/var/run/dbus/system_bus_so
cket
785: client: going from state NeedSendAuth to state WaitingForData
785: Initialized transport on address
unix:path=/opt/bluez/var/run/dbus/system_b
us_socket
785: LOCK: _dbus_connection_new_for_transport
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: check_read_watch: fd = 6
785: setting read watch enabled = 0
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: check_write_watch(): needed = 1 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 0
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: UNLOCK: _dbus_connection_new_for_transport
785: LOCK: _dbus_connection_open_internal
785: UNLOCK: _dbus_connection_open_internal
785: LOCK: dbus_connection_set_exit_on_disconnect
785: UNLOCK: dbus_connection_set_exit_on_disconnect
785: Allocated slot 0 on allocator 0x4010ccb0 total 1 slots allocated 1
used
785: LOCK: dbus_connection_get_data
785: UNLOCK: dbus_connection_get_data
785: LOCK: dbus_connection_set_data
785: UNLOCK: dbus_connection_set_data
785: LOCK: dbus_connection_send_with_reply
785: Allocated slot 0 on allocator 0x4010ccfc total 1 slots allocated 1
used
785: UNLOCK: protected_change_timeout
785: LOCK: protected_change_timeout
785: Message 0x5d500 (1 /org/freedesktop/DBus org.freedesktop.DBus Hello
'') for
org.freedesktop.DBus added to outgoing queue 0x5d2d0, 1 pending to send
785: Message 0x5d500 serial is 1
785: _dbus_connection_do_iteration_unlocked start
785: UNLOCK: _dbus_connection_acquire_io_path
785: _dbus_connection_acquire_io_path locking io_path_mutex
785: _dbus_connection_acquire_io_path start connection->io_path_acquired =
0 tim
eout = 0
785: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1
we_ac
quired = 1
785: _dbus_connection_acquire_io_path unlocking io_path_mutex
785: LOCK: _dbus_connection_acquire_io_path
785: Transport iteration flags 0x1 timeout -1 connected = 1
785: iteration flags = write timeout = -1 read_watch = 0x5cfc0
write_watch = 0x
5cf98 fd = 6
785: in iteration, need_read=0 need_write=1
785: exchange_credentials: do_reading = 0, do_writing = 1
785: wrote credentials byte
785: client auth state: bytes to send
785: client: Sent 18 bytes of: AUTH EXTERNAL 30
785: exchange_credentials: do_reading = 0, do_writing = 1
785: client auth state: waiting for input
785: check_read_watch: fd = 6
785: setting read watch enabled = 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: check_write_watch(): needed = 0 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: Not authenticated, not writing anything
785: check_write_watch(): needed = 0 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: ... leaving do_iteration()
785: _dbus_transport_do_iteration end
785: _dbus_connection_release_io_path locking io_path_mutex
785: _dbus_connection_release_io_path start connection->io_path_acquired =
1
785: _dbus_connection_release_io_path unlocking io_path_mutex
785: _dbus_connection_do_iteration_unlocked end
785: dispatch status = complete is_connected = 1
785: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
785: LOCK: dbus_connection_unref
785: UNLOCK: dbus_connection_unref
785: LOCK: _dbus_connection_lock
785: UNLOCK: _dbus_connection_unlock
785: LOCK: _dbus_connection_lock
785: UNLOCK: _dbus_connection_unlock
785: LOCK: _dbus_connection_lock
785: doing iteration in _dbus_connection_flush_unlocked
785: _dbus_connection_do_iteration_unlocked start
785: UNLOCK: _dbus_connection_acquire_io_path
785: _dbus_connection_acquire_io_path locking io_path_mutex
785: _dbus_connection_acquire_io_path start connection->io_path_acquired =
0 tim
eout = -1
785: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1
we_ac
quired = 1
785: _dbus_connection_acquire_io_path unlocking io_path_mutex
785: LOCK: _dbus_connection_acquire_io_path
785: Transport iteration flags 0x7 timeout -1 connected = 1
785: iteration flags = readwrite timeout = -1 read_watch = 0x5cfc0
write_watch
= 0x5cf98 fd = 6
785: unlock socket_do_iteration pre poll
785: UNLOCK: _dbus_connection_unlock
785: lock socket_do_iteration post poll
785: LOCK: _dbus_connection_lock
785: in iteration, need_read=1 need_write=0
785: exchange_credentials: do_reading = 1, do_writing = 0
785: client auth state: waiting for input
785: read 37 bytes in auth phase
785: client: got command "OK 4eeff5879c2ddf01ff99b00000002254"
785: Got GUID '4eeff5879c2ddf01ff99b00000002254' from the server
785: client: going from state WaitingForData to state Authenticated
785: exchange_credentials: do_reading = 1, do_writing = 0
785: client auth state: bytes to send
785: check_read_watch: fd = 6
785: setting read watch enabled = 0
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: check_write_watch(): needed = 1 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: do_reading: fd = 6
785: check_write_watch(): needed = 1 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: ... leaving do_iteration()
785: _dbus_transport_do_iteration end
785: _dbus_connection_release_io_path locking io_path_mutex
785: _dbus_connection_release_io_path start connection->io_path_acquired =
1
785: _dbus_connection_release_io_path unlocking io_path_mutex
785: _dbus_connection_do_iteration_unlocked end
785: doing iteration in _dbus_connection_flush_unlocked
785: _dbus_connection_do_iteration_unlocked start
785: UNLOCK: _dbus_connection_acquire_io_path
785: _dbus_connection_acquire_io_path locking io_path_mutex
785: _dbus_connection_acquire_io_path start connection->io_path_acquired =
0 tim
eout = -1
785: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1
we_ac
quired = 1
785: _dbus_connection_acquire_io_path unlocking io_path_mutex
785: LOCK: _dbus_connection_acquire_io_path
785: Transport iteration flags 0x7 timeout -1 connected = 1
785: iteration flags = readwrite timeout = -1 read_watch = 0x5cfc0
write_watch
= 0x5cf98 fd = 6
785: unlock socket_do_iteration pre poll
785: UNLOCK: _dbus_connection_unlock
785: lock socket_do_iteration post poll
785: LOCK: _dbus_connection_lock
785: in iteration, need_read=0 need_write=1
785: exchange_credentials: do_reading = 0, do_writing = 1
785: client auth state: bytes to send
785: client: Sent 7 bytes of: BEGIN
785: check_read_watch: fd = 6
785: setting read watch enabled = 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: check_write_watch(): needed = 1 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: check_write_watch(): needed = 1 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: ... leaving do_iteration()
785: _dbus_transport_do_iteration end
785: _dbus_connection_release_io_path locking io_path_mutex
785: _dbus_connection_release_io_path start connection->io_path_acquired =
1
785: _dbus_connection_release_io_path unlocking io_path_mutex
785: _dbus_connection_do_iteration_unlocked end
785: doing iteration in _dbus_connection_flush_unlocked
785: _dbus_connection_do_iteration_unlocked start
785: UNLOCK: _dbus_connection_acquire_io_path
785: _dbus_connection_acquire_io_path locking io_path_mutex
785: _dbus_connection_acquire_io_path start connection->io_path_acquired =
0 tim
eout = -1
785: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1
we_ac
quired = 1
785: _dbus_connection_acquire_io_path unlocking io_path_mutex
785: LOCK: _dbus_connection_acquire_io_path
785: Transport iteration flags 0x7 timeout -1 connected = 1
785: iteration flags = readwrite timeout = -1 read_watch = 0x5cfc0
write_watch
= 0x5cf98 fd = 6
785: unlock socket_do_iteration pre poll
785: UNLOCK: _dbus_connection_unlock
785: lock socket_do_iteration post poll
785: LOCK: _dbus_connection_lock
785: in iteration, need_read=0 need_write=1
785: do_writing(), have_messages = 1, fd = 6
785: wrote 128 bytes of 128
785: Message 0x5d500 (1 /org/freedesktop/DBus org.freedesktop.DBus Hello
'') rem
oved from outgoing queue 0x5d2d0, 0 left to send
785: check_write_watch(): needed = 0 on connection 0x5d2d0 watch 0x5cf98
fd = 6
outgoing messages exist 0
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: ... leaving do_iteration()
785: _dbus_transport_do_iteration end
785: _dbus_connection_release_io_path locking io_path_mutex
785: _dbus_connection_release_io_path start connection->io_path_acquired =
1
785: _dbus_connection_release_io_path unlocking io_path_mutex
785: _dbus_connection_do_iteration_unlocked end
785: _dbus_connection_flush_unlocked middle
785: 0 unused bytes sent to message loader
785: dispatch status = complete is_connected = 1
785: dbus_connection_send_with_reply_and_block(): will block 25000
milliseconds
for reply serial 1 from 8866 sec 446068 usec to 8891 sec 446068 usec
785: _dbus_connection_do_iteration_unlocked start
785: UNLOCK: _dbus_connection_acquire_io_path
785: _dbus_connection_acquire_io_path locking io_path_mutex
785: _dbus_connection_acquire_io_path start connection->io_path_acquired =
0 tim
eout = 25000
785: _dbus_connection_acquire_io_path end connection->io_path_acquired = 1
we_ac
quired = 1
785: _dbus_connection_acquire_io_path unlocking io_path_mutex
785: LOCK: _dbus_connection_acquire_io_path
785: Transport iteration flags 0x6 timeout 25000 connected = 1
785: iteration flags = read timeout = 25000 read_watch = 0x5cfc0
write_watch =
0x5cf98 fd = 6
785: unlock socket_do_iteration pre poll
785: UNLOCK: _dbus_connection_unlock
785: lock socket_do_iteration post poll
785: LOCK: _dbus_connection_lock
785: in iteration, need_read=1 need_write=0
785: do_reading: fd = 6
785: check_read_watch: fd = 6
785: setting read watch enabled = 1
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: Disconnected from remote app
785: _dbus_transport_disconnect start
785: socket_disconnect
785: free_watches start
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: Setting watch fd -1 data to data = (nil) function = (nil) from data =
(nil)
function = (nil)
785: UNLOCK: protected_change_watch
785: LOCK: protected_change_watch
785: Setting watch fd -1 data to data = (nil) function = (nil) from data =
(nil)
function = (nil)
785: free_watches end
785: _dbus_transport_disconnect end
785: ... leaving do_iteration()
785: _dbus_transport_do_iteration end
785: _dbus_connection_release_io_path locking io_path_mutex
785: _dbus_connection_release_io_path start connection->io_path_acquired =
1
785: _dbus_connection_release_io_path unlocking io_path_mutex
785: _dbus_connection_do_iteration_unlocked end
785: _dbus_connection_block_pending_call top of recheck
785: dispatch status = complete is_connected = 0
785: Sending disconnect message from
notify_disconnected_and_dispatch_complete_u
nlocked
785: Synthesized message 0x5d660 added to incoming queue 0x5d2d0, 1
incoming
785: UNLOCK: protected_change_timeout
785: LOCK: protected_change_timeout
785: UNLOCK: _dbus_connection_unlock
785: LOCK: free_pending_call_on_hash_removal
785: UNLOCK: _dbus_connection_unlock
785: LOCK: connection_timeout_and_complete_all_pending_calls_unlocked
785: Synthesized message 0x5d358 added to incoming queue 0x5d2d0, 2
incoming
785: check_for_reply_and_update_dispatch_unlocked checked for reply
785: dbus_connection_send_with_reply_and_block(): got reply
785: handing message 0x5d660 (error) to pending call serial 1
785: UNLOCK: _dbus_connection_unlock
785: LOCK: _dbus_connection_lock
785: UNLOCK: _dbus_connection_unlock
785: LOCK: check_for_reply_and_update_dispatch_unlocked
785: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
785: LOCK: dbus_connection_unref
785: UNLOCK: dbus_connection_unref
785: LOCK: _dbus_connection_lock
785: UNLOCK: _dbus_connection_unlock
785: LOCK: _dbus_connection_lock
785: UNLOCK: _dbus_connection_unlock
785: LOCK: _dbus_connection_lock
785: UNLOCK: _dbus_connection_unlock
785: Freeing slot 0 on allocator 0x4010ccfc total 1 allocated 1 used
785: LOCK: dbus_connection_unref
785: UNLOCK: dbus_connection_unref
785: LOCK: _dbus_connection_close_possibly_shared
785: Disconnecting 0x5d2d0
785: _dbus_transport_disconnect start
785: UNLOCK: _dbus_connection_update_dispatch_status_and_unlock
785: LOCK: dbus_connection_unref
785: UNLOCK: dbus_connection_unref
785: LOCK: dbus_connection_unref
785: UNLOCK: dbus_connection_unref
785: LOCK: dbus_connection_unref
785: UNLOCK: dbus_connection_unref
785: opening shared connection to:
unix:path=/opt/bluez/var/run/dbus/system_bus_
socket
785: checking for existing connection
785: connecting to unix socket /opt/bluez/var/run/dbus/system_bus_socket
abstrac
t=0
hcid[785]: Unable to get on D-Bus
---------------------------------------------------------------------------------------------------------------------
______________________________________________________________________
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freedesktop.org/archives/dbus/attachments/20080801/03bc1a3a/attachment-0001.htm
More information about the dbus
mailing list