On 08/06/2011 12:50 PM, Peter Hurley wrote:
On Sat, 2011-08-06 at 11:33 -0400, Colin Beckingham wrote:Hi Peter:...<snip>...I ran # hciconfig hci0 sspmode 1 to force the adapter into a secure attempt. I downloaded and installed the latest hcidump which identifies itself (hcidump -v) as 2.0 even though it is marked as 2.1 version on the webpage. Made another attempt to connect, here is the syslog # tail -n 100 /var/log/messages | grep bluetoothd Aug 6 05:15:39 linux-c96h bluetoothd[1246]: Audio connection got disconnected Aug 6 11:23:29 linux-c96h bluetoothd[1246]: Rejecting request: remote device can't provide MITM Aug 6 11:23:56 linux-c96h bluetoothd[1246]: Discovery session 0x7f801d0a6ca0 with :1.4178 activated Aug 6 11:24:01 linux-c96h bluetoothd[1246]: Stopping discovery Aug 6 11:24:13 linux-c96h bluetoothd[1246]: Permission denied (13) and a binary hcidump is attached.Hi Colin, That makes a lot more sense! Would you please repeat the experiment with bluetoothd in debug mode, though? That would give me a lot more information to work with about how bluetoothd got to that point. As before, please capture hcidump binary at the same time. Also please include every bluetoothd syslog message starting from the start of the experiment. I appreciate your patience helping me to track down this bug. Regards, Peter Hurley
Sorry, forgot to set bluetoothd in debug mode.Attached are my latest logs, 2 files, wep475a.*. Note in the syslog that the first entry is timestamped way before the experiment was launched, so the log should have everything that bluetoothd wrote for the current experiment.
-- --- Colin Beckingham
Attachment:
wep475a.hci
Description: Binary data
# tail -n 1000 /var/log/messages | grep bluetoothd Aug 6 11:38:55 linux-c96h bluetoothd[1246]: Audio connection got disconnected Aug 6 13:09:02 linux-c96h bluetoothd[22268]: Bluetooth daemon 4.96 Aug 6 13:09:02 linux-c96h bluetoothd[22269]: Starting SDP server Aug 6 13:09:02 linux-c96h bluetoothd[22269]: Parsing /etc/bluetooth/input.conf failed: No such file or directory Aug 6 13:09:02 linux-c96h bluetoothd[22269]: Parsing /etc/bluetooth/audio.conf failed: No such file or directory Aug 6 13:09:02 linux-c96h bluetoothd[22269]: Listening for HCI events on hci0 Aug 6 13:09:02 linux-c96h bluetoothd[22269]: HCI dev 0 up Aug 6 13:09:02 linux-c96h bluetoothd[22269]: Parsing /etc/bluetooth/serial.conf failed: No such file or directory Aug 6 13:09:02 linux-c96h bluetoothd[22269]: Adapter /org/bluez/22268/hci0 has been enabled Aug 6 13:09:02 linux-c96h bluetoothd[22269]: Inquiry Cancel Failed with status 0x12 Aug 6 13:10:00 linux-c96h bluetoothd[22293]: Bluetooth daemon 4.96 Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() parsing main.conf Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() discovto=0 Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() pairto=0 Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() pageto=8192 Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() name=%h-%d Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() class=0x000100 Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() discov_interval=30 Aug 6 13:10:00 linux-c96h bluetoothd[22293]: src/main.c:parse_config() Key file does not have key 'DeviceID' Aug 6 13:10:00 linux-c96h bluetoothd[22294]: Starting SDP server Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:plugin_init() Loading builtin plugins Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading audio plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading input plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading serial plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading network plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading service plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading hciops plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading mgmtops plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading formfactor plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading storage plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:add_plugin() Loading adaptername plugin Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/plugin.c:plugin_init() Loading plugins /usr/local/lib/bluetooth/plugins Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/service.c:register_interface() path /org/bluez/22293/any Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/service.c:register_interface() Registered interface org.bluez.Service on path /org/bluez/22293/any Aug 6 13:10:00 linux-c96h bluetoothd[22294]: network/manager.c:read_config() Config options: Security=true Aug 6 13:10:00 linux-c96h bluetoothd[22294]: Parsing /etc/bluetooth/input.conf failed: No such file or directory Aug 6 13:10:00 linux-c96h bluetoothd[22294]: Parsing /etc/bluetooth/audio.conf failed: No such file or directory Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/unix.c:unix_init() Unix socket created: 8 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_init() Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_setup() Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/main.c:main() Entering main loop Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/rfkill.c:rfkill_event() RFKILL event idx 0 type 2 op 0 soft 0 hard 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:init_known_adapters() Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:init_device() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: Listening for HCI events on hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:init_conn_list() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: HCI dev 0 up Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:device_devup_setup() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:read_local_version_complete() Got version for hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:btd_adapter_ref() 0x7ff17c4bb300: ref=1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_read_bdaddr() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-database.c:sdp_init_services_list() Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/service.c:register_interface() path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/service.c:register_interface() Registered interface org.bluez.Service on path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: network/manager.c:network_server_probe() path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:btd_adapter_ref() 0x7ff17c4bb300: ref=2 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: network/server.c:server_register() Registered interface org.bluez.NetworkServer on path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/manager.c:proxy_probe() path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:btd_adapter_ref() 0x7ff17c4bb300: ref=3 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/proxy.c:proxy_register() Registered interface org.bluez.SerialProxyManager on path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: Parsing /etc/bluetooth/serial.conf failed: No such file or directory Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:btd_adapter_ref() 0x7ff17c4bb300: ref=4 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:headset_server_probe() path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:btd_adapter_ref() 0x7ff17c4bb300: ref=5 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:audio_adapter_ref() 0x7ff17c4bb8f0: ref=1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:state_changed() /org/bluez/22293/hci0 powered on Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/telephony.c:telephony_init() Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/headset.c:telephony_ready_ind() Telephony plugin initialized Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/headset.c:print_ag_features() HFP AG features: "Ability to reject a call" "Enhanced call status" "Extended Error Result Codes" Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10000 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000003-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001108-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001112-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001203-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10001 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 64 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000003-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000111e-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000111f-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001203-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:a2dp_server_probe() path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:audio_adapter_ref() 0x7ff17c4bb8f0: ref=2 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/avdtp.c:avdtp_register_sep() SEP 0x7ff17c4bd300 registered: type:0 codec:0 seid:1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10002 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 72 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000019-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110a-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110d-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:avrcp_server_probe() path /org/bluez/22293/hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:audio_adapter_ref() 0x7ff17c4bb8f0: ref=3 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10003 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 72 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110c-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Adding record with handle 0x10004 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 72 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000017-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00000100-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 00001002-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/sdpd-service.c:add_record_to_server() Record pattern UUID 0000110e-0000-1000-8000-00805f9 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/adaptername.c:adaptername_probe() Setting name 'linux-c96h-0' for device 'hci0' Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_set_name() hci0, name linux-c96h-0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/formfactor.c:formfactor_probe() Setting 0x000104 for major/minor device class Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_set_dev_class() hci0 major 1 minor 4 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_unblock_device() hci0 dba 00:00:00:00:00:00 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_create() Creating device /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_set_bonded() bonded 1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4bf280: ref=1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_probe_drivers() Probing drivers for 00:1A:45:32:E7:16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16: 00001108-0000-1000-8000-00805f9b34fb Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/port.c:create_serial_device() Registered interface org.bluez.Serial on path /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16: 0000111e-0000-1000-8000-00805f9b34fb Aug 6 13:10:00 linux-c96h bluetoothd[22294]: input/manager.c:headset_probe() path /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4bf280: ref=2 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: input/device.c:input_device_new() Registered interface org.bluez.Input on path /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:1A:45:32:E7:16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4bf280: ref=3 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/device.c:audio_device_register() Registered interface org.bluez.Audio on path /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Headset record Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/headset.c:headset_init() Registered interface org.bluez.Headset on path /org/bluez/22293/hci0/dev_00_1A_45_32_E7_16 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Handsfree record Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_create() Creating device /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_set_bonded() bonded 1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4c0c50: ref=1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:device_probe_drivers() Probing drivers for 00:0D:E6:6E:51:47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: 00001108-0000-1000-8000-00805f9b34fb Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/port.c:create_serial_device() Registered interface org.bluez.Serial on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: 0000111e-0000-1000-8000-00805f9b34fb Aug 6 13:10:00 linux-c96h bluetoothd[22294]: input/manager.c:headset_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4c0c50: ref=2 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: input/device.c:input_device_new() Registered interface org.bluez.Input on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4c0c50: ref=3 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/device.c:audio_device_register() Registered interface org.bluez.Audio on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Headset record Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/headset.c:headset_init() Registered interface org.bluez.Headset on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Handsfree record Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_load_keys() hci0 keys 2 debug_keys 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_get_conn_list() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/manager.c:btd_manager_register_adapter() Adapter /org/bluez/22293/hci0 registered Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:btd_adapter_ref() 0x7ff17c4bb300: ref=6 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_set_name() hci0, name linux-c96h-0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_set_dev_class() hci0 major 1 minor 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: audio/manager.c:state_changed() /org/bluez/22293/hci0 powered on Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_disable_cod_cache() hci0 cache_enable 1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_disable_cod_cache() hci0 current_cod 0x000000 wanted_cod 0x480100 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:write_class() hci0 class 0x480100 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: Adapter /org/bluez/22293/hci0 has been enabled Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_set_discoverable() hci0 discoverable 1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_set_pairable() hci0 pairable 1 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_stop_inquiry() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:btd_adapter_unref() 0x7ff17c4bb300: ref=5 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:read_tx_power_complete() hci0 status 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_add_uuid() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() hci0 value 88 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_service_classes() Changing service classes to 0x580100 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:write_class() hci0 class 0x580100 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: Inquiry Cancel Failed with status 0x12 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:read_local_name_complete() hci0 status 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:read_simple_pairing_mode_complete() hci0 status 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:read_local_name_complete() hci0 status 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:read_scan_complete() hci0 status 0 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_mode_changed() old 0x00 new 0x03 Aug 6 13:10:00 linux-c96h bluetoothd[22294]: src/adapter.c:set_mode_complete() Aug 6 13:11:27 linux-c96h bluetoothd[22294]: plugins/hciops.c:read_simple_pairing_mode_complete() hci0 status 0 Aug 6 13:11:27 linux-c96h bluetoothd[22294]: plugins/hciops.c:update_ext_inquiry_response() hci0 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 1 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: src/device.c:device_remove() Removing device /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: src/device.c:device_set_bonded() bonded 0 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_remove_bonding() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: serial/manager.c:serial_remove() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: serial/port.c:path_unregister() Unregistered interface org.bluez.Serial on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: input/manager.c:input_remove() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: input/device.c:device_unregister() Unregistered interface org.bluez.Input on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4c0c50: ref=2 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: audio/unix.c:unix_device_removed() unix_device_removed(0x7ff17c4c2450) Aug 6 13:11:46 linux-c96h bluetoothd[22294]: audio/headset.c:path_unregister() Unregistered interface org.bluez.Headset on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4c0c50: ref=1 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4c0c50: ref=0 Aug 6 13:11:46 linux-c96h bluetoothd[22294]: src/device.c:device_free() 0x7ff17c4c0c50 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:conn_complete() status 0x00 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_create_device() 00:0D:E6:6E:51:47 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: src/device.c:device_create() Creating device /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=1 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 1 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_features_information() hci0 status 0 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_name_information() hci0 status 0 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_auth_info() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x04 Aug 6 13:13:00 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() Matching key not found Aug 6 13:13:01 linux-c96h bluetoothd[22294]: plugins/hciops.c:io_capa_response() hci0 IO capability response from 00:0D:E6:6E:51:47 Aug 6 13:13:01 linux-c96h bluetoothd[22294]: plugins/hciops.c:io_capa_request() hci0 IO capability request for 00:0D:E6:6E:51:47 Aug 6 13:13:01 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_auth_info() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:01 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_io_cap() initial authentication requirement is 0x04 Aug 6 13:13:01 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_io_cap() final authentication requirement is 0x04 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:user_confirm_request() hci0 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:user_confirm_request() auto accept of confirmation Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_confirm_reply() hci0 dba 00:0D:E6:6E:51:47 success 1 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:simple_pairing_complete() hci0 status 0 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/event.c:btd_event_simple_pairing_complete() status=00 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_notify() hci0 dba 00:0D:E6:6E:51:47 type 4 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_notify() key type 0x04 old key type 0xff Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_notify() local auth 0x04 and remote auth 0x05 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/event.c:btd_event_link_key_notify() storing link key of type 0x04 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/device.c:device_set_bonded() bonded 1 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 0 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: plugins/hciops.c:bonding_complete() status 0x00 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/event.c:btd_event_bonding_complete() status 0x00 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/device.c:device_bonding_complete() bonding (nil) status 0x00 Aug 6 13:13:02 linux-c96h bluetoothd[22294]: src/device.c:device_bonding_complete() setting timer for reverse service discovery Aug 6 13:13:03 linux-c96h bluetoothd[22294]: plugins/hciops.c:disconn_complete() handle 11 status 0x00 Aug 6 13:13:03 linux-c96h bluetoothd[22294]: src/event.c:btd_event_disconn_complete() Aug 6 13:13:03 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_remove_connection() Aug 6 13:13:05 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=2 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: plugins/hciops.c:conn_complete() status 0x00 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_features_information() hci0 status 0 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_name_information() hci0 status 0 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: src/device.c:device_probe_drivers() Probing drivers for 00:0D:E6:6E:51:47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: 00001108-0000-1000-8000-00805f9b34fb Aug 6 13:13:06 linux-c96h bluetoothd[22294]: serial/port.c:create_serial_device() Registered interface org.bluez.Serial on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: 0000111e-0000-1000-8000-00805f9b34fb Aug 6 13:13:06 linux-c96h bluetoothd[22294]: input/manager.c:headset_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=3 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: input/device.c:input_device_new() Registered interface org.bluez.Input on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=4 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: audio/device.c:audio_device_register() Registered interface org.bluez.Audio on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Headset record Aug 6 13:13:06 linux-c96h bluetoothd[22294]: audio/headset.c:headset_init() Registered interface org.bluez.Headset on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:06 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Handsfree record Aug 6 13:13:06 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4d37a0: ref=3 Aug 6 13:13:10 linux-c96h bluetoothd[22294]: plugins/hciops.c:disconn_complete() handle 11 status 0x00 Aug 6 13:13:10 linux-c96h bluetoothd[22294]: src/event.c:btd_event_disconn_complete() Aug 6 13:13:10 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_remove_connection() Aug 6 13:13:15 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 1 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: src/device.c:device_remove() Removing device /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: src/device.c:device_set_bonded() bonded 0 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_remove_bonding() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: serial/manager.c:serial_remove() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: serial/port.c:path_unregister() Unregistered interface org.bluez.Serial on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: input/manager.c:input_remove() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: input/device.c:device_unregister() Unregistered interface org.bluez.Input on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4d37a0: ref=2 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: audio/unix.c:unix_device_removed() unix_device_removed(0x7ff17c4c4d10) Aug 6 13:13:15 linux-c96h bluetoothd[22294]: audio/headset.c:path_unregister() Unregistered interface org.bluez.Headset on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4d37a0: ref=1 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4d37a0: ref=0 Aug 6 13:13:15 linux-c96h bluetoothd[22294]: src/device.c:device_free() 0x7ff17c4d37a0 Aug 6 13:13:27 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_start_inquiry() hci0 length 8 Aug 6 13:13:27 linux-c96h bluetoothd[22294]: Discovery session 0x7ff17c4be670 with :1.4945 activated Aug 6 13:13:27 linux-c96h bluetoothd[22294]: src/adapter.c:session_ref() 0x7ff17c4be670: ref=1 Aug 6 13:13:27 linux-c96h bluetoothd[22294]: plugins/hciops.c:set_state() hci0: new state 1 Aug 6 13:13:27 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_set_state() hci0: new state 1 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/adapter.c:session_unref() 0x7ff17c4be670: ref=0 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/adapter.c:session_remove() Discovery session 0x7ff17c4be670 with :1.4945 deactivated Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/adapter.c:session_remove() Stopping discovery Aug 6 13:13:31 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_stop_discovery() index 0 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_stop_inquiry() hci0 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: Stopping discovery Aug 6 13:13:31 linux-c96h bluetoothd[22294]: plugins/hciops.c:set_state() hci0: new state 0 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_set_state() hci0: new state 0 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_create_device() 00:0D:E6:6E:51:47 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/device.c:device_create() Creating device /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=1 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 1 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/device.c:bonding_request_new() Requesting bonding for 00:0D:E6:6E:51:47 Aug 6 13:13:31 linux-c96h bluetoothd[22294]: src/device.c:bonding_request_new() Temporary agent registered for 00:0D:E6:6E:51:47 at :1.4945:/wizardAgent Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:conn_complete() status 0x00 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_features_information() hci0 status 0 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_name_information() hci0 status 0 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_auth_info() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x03 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() Matching key not found Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:io_capa_request() hci0 IO capability request for 00:0D:E6:6E:51:47 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_auth_info() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_io_cap() initial authentication requirement is 0x03 Aug 6 13:13:33 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_io_cap() final authentication requirement is 0x03 Aug 6 13:13:34 linux-c96h bluetoothd[22294]: plugins/hciops.c:io_capa_response() hci0 IO capability response from 00:0D:E6:6E:51:47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:user_confirm_request() hci0 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:user_confirm_request() auto accept of confirmation Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:hciops_confirm_reply() hci0 dba 00:0D:E6:6E:51:47 success 1 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:simple_pairing_complete() hci0 status 0 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/event.c:btd_event_simple_pairing_complete() status=00 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_notify() hci0 dba 00:0D:E6:6E:51:47 type 4 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_notify() key type 0x04 old key type 0xff Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_notify() local auth 0x03 and remote auth 0x02 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/event.c:btd_event_link_key_notify() storing link key of type 0x04 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:device_set_bonded() bonded 1 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:device_set_temporary() temporary 0 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:auth_complete() hci0 status 0 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: plugins/hciops.c:bonding_complete() status 0x00 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/event.c:btd_event_bonding_complete() status 0x00 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:device_bonding_complete() bonding 0x7ff17c4b06b0 status 0x00 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:device_bonding_complete() Proceeding with service discovery Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=2 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_resume_discovery() Resuming discovery Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/agent.c:agent_release() Releasing agent :1.4945, /wizardAgent Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:device_probe_drivers() Probing drivers for 00:0D:E6:6E:51:47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: 00001108-0000-1000-8000-00805f9b34fb Aug 6 13:13:35 linux-c96h bluetoothd[22294]: serial/port.c:create_serial_device() Registered interface org.bluez.Serial on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: serial/manager.c:serial_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: 0000111e-0000-1000-8000-00805f9b34fb Aug 6 13:13:35 linux-c96h bluetoothd[22294]: input/manager.c:headset_probe() path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=3 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: input/device.c:input_device_new() Registered interface org.bluez.Input on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:btd_device_ref() 0x7ff17c4d37a0: ref=4 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: audio/device.c:audio_device_register() Registered interface org.bluez.Audio on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Headset record Aug 6 13:13:35 linux-c96h bluetoothd[22294]: audio/headset.c:headset_init() Registered interface org.bluez.Headset on path /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47 Aug 6 13:13:35 linux-c96h bluetoothd[22294]: audio/manager.c:handle_uuid() Found Handsfree record Aug 6 13:13:35 linux-c96h bluetoothd[22294]: src/device.c:btd_device_unref() 0x7ff17c4d37a0: ref=3 Aug 6 13:13:39 linux-c96h bluetoothd[22294]: plugins/hciops.c:disconn_complete() handle 12 status 0x00 Aug 6 13:13:39 linux-c96h bluetoothd[22294]: src/event.c:btd_event_disconn_complete() Aug 6 13:13:39 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_remove_connection() Aug 6 13:13:41 linux-c96h bluetoothd[22294]: audio/headset.c:headset_set_state() State changed /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: HEADSET_STATE_DISCONNECTED -> HEADSET_STATE_CONNECTING Aug 6 13:13:42 linux-c96h bluetoothd[22294]: plugins/hciops.c:conn_complete() status 0x00 Aug 6 13:13:42 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_features_information() hci0 status 0 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:remote_name_information() hci0 status 0 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: audio/headset.c:headset_set_channel() Discovered Handsfree service on channel 2 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: audio/headset.c:rfcomm_connect() /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: Connecting to 00:0D:E6:6E:51:47 channel 2 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:get_auth_info() hci0 dba 00:0D:E6:6E:51:47 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() kernel auth requirements = 0x04 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() Matching key found Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:link_key_request() link key type 0x04 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:auth_complete() hci0 status 0 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:bonding_complete() status 0x00 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: src/event.c:btd_event_bonding_complete() status 0x00 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_get_device() 00:0D:E6:6E:51:47 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: src/device.c:device_bonding_complete() bonding (nil) status 0x00 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: Permission denied (13) Aug 6 13:13:43 linux-c96h bluetoothd[22294]: audio/headset.c:headset_set_state() State changed /org/bluez/22293/hci0/dev_00_0D_E6_6E_51_47: HEADSET_STATE_CONNECTING -> HEADSET_STATE_DISCONNECTED Aug 6 13:13:43 linux-c96h bluetoothd[22294]: plugins/hciops.c:disconn_complete() handle 11 status 0x00 Aug 6 13:13:43 linux-c96h bluetoothd[22294]: src/event.c:btd_event_disconn_complete() Aug 6 13:13:43 linux-c96h bluetoothd[22294]: src/adapter.c:adapter_remove_connection()
