Hi guys, Any thoughts regarding this issue? Is it some sort of user error, or could it be a real bug? Thanks /Magnus On Sat, May 24, 2014 at 1:37 PM, Magnus Larsgården <larsgarden@xxxxxxxxx> wrote: > Hej Johan, > > On Sat, May 24, 2014 at 8:34 AM, Johan Hedberg <johan.hedberg@xxxxxxxxx> wrote: >> Hi Magnus, >> >> On Fri, May 23, 2014, Magnus Larsgården wrote: >>> The setup: >>> Raspberry Pi revB >>> OpenELEC 4.0.2 (Linux kernel 3.14.4) >>> >>> # /usr/lib/bluetooth/bluetoothd -v >>> 5.18 >>> >>> # bluetoothctl -v >>> 5.18 >>> >>> Bluetooth keyboard, Microsoft Wedge Keyboard >>> >>> >>> The problem: >>> The keyboard is detected, paired and connected, but after a couple of >>> seconds it gets disconnected. >>> >>> [bluetooth]# connect 7C:1E:52:18:7D:4F >>> Attempting to connect to 7C:1E:52:18:7D:4F >>> [CHG] Device 7C:1E:52:18:7D:4F Connected: yes >>> .... ~20 seconds pass..... >>> Failed to connect: org.bluez.Error.Failed >>> [CHG] Device 7C:1E:52:18:7D:4F Connected: no >>> >>> From bluetoothd: >>> >>> bluetoothd[513]: src/adapter.c:discovering_callback() hci0 type 1 discovering 0 >>> bluetoothd[513]: src/adapter.c:trigger_start_discovery() >>> bluetoothd[513]: 7C:1E:52:18:7D:4F: error updating services: >>> Input/output error (5) >>> bluetoothd[513]: src/device.c:device_svc_resolved() >>> /org/bluez/hci0/dev_7C_1E_52_18_7D_4F err -5 >>> bluetoothd[513]: src/adapter.c:dev_disconnected() Device >>> 7C:1E:52:18:7D:4F disconnected, reason 2 >>> bluetoothd[513]: src/adapter.c:adapter_remove_connection() >>> bluetoothd[513]: src/adapter.c:bonding_attempt_complete() hci0 bdaddr >>> 7C:1E:52:18:7D:4F type 0 status 0xe >>> bluetoothd[513]: src/device.c:device_bonding_complete() bonding (nil) >>> status 0x0e >>> bluetoothd[513]: src/device.c:device_bonding_failed() status 14 >>> bluetoothd[513]: src/adapter.c:resume_discovery() >>> >>> I would appreciate any help here. >> >> It would help analyze the issue if you could provide HCI-level logs for >> the disconnection. You can accomplish this by running btmon -w <filename> >> while performing the use case. >> >> Johan > > > Unfortunately btmon is not included in the OpenELEC 4.0.2 distro. > However, hcidump is present. Will that work? > > Use case: > > 1. Start with Raspberry Pi powered on. Keyboard off. > 2. Turn keyboard on, and set it in "discovery mode" > > At this point I can see: > > bluetoothctl > [NEW] Controller 00:02:72:C4:9A:E4 OpenELEC [default] > [NEW] Device 7C:1E:52:18:7D:4F Microsoft Wedge Mobile Keyboard > [CHG] Device 7C:1E:52:18:7D:4F Class: 0x000540 > [CHG] Device 7C:1E:52:18:7D:4F Icon: input-keyboard > [CHG] Device 7C:1E:52:18:7D:4F Connected: yes > [CHG] Device 7C:1E:52:18:7D:4F Connected: no > > and hcidump: > > HCI sniffer - Bluetooth packet analyzer ver 5.18 > device: hci0 snap_len: 1500 filter: 0xffffffff >> HCI Event: Connect Request (0x04) plen 10 > bdaddr 7C:1E:52:18:7D:4F class 0x000540 type ACL > < HCI Command: Accept Connection Request (0x01|0x0009) plen 7 > bdaddr 7C:1E:52:18:7D:4F role 0x00 > Role: Master >> HCI Event: Command Status (0x0f) plen 4 > Accept Connection Request (0x01|0x0009) status 0x00 ncmd 1 >> HCI Event: Role Change (0x12) plen 8 > status 0x00 bdaddr 7C:1E:52:18:7D:4F role 0x00 > Role: Master >> HCI Event: Connect Complete (0x03) plen 11 > status 0x00 handle 6 bdaddr 7C:1E:52:18:7D:4F type ACL encrypt 0x00 > < HCI Command: Read Remote Supported Features (0x01|0x001b) plen 2 > handle 6 >> HCI Event: Command Status (0x0f) plen 4 > Read Remote Supported Features (0x01|0x001b) status 0x00 ncmd 1 > < HCI Command: Change Connection Packet Type (0x01|0x000f) plen 4 > handle 6 ptype 0xcc18 > Packet type: DM1 DM3 DM5 DH1 DH3 DH5 >> HCI Event: Command Status (0x0f) plen 4 > Change Connection Packet Type (0x01|0x000f) status 0x00 ncmd 1 >> HCI Event: Read Remote Supported Features (0x0b) plen 11 > status 0x00 handle 6 > Features: 0xbc 0x06 0x86 0x78 0x18 0x1c 0x59 0x87 > < HCI Command: Remote Name Request (0x01|0x0019) plen 10 > bdaddr 7C:1E:52:18:7D:4F mode 2 clkoffset 0x0000 >> HCI Event: Command Status (0x0f) plen 4 > Remote Name Request (0x01|0x0019) status 0x00 ncmd 1 >> HCI Event: Connection Packet Type Changed (0x1d) plen 5 > status 0x34 handle 6 ptype 0x0008 > Error: Reserved Slot Violation >> ACL data: handle 6 flags 0x02 dlen 12 > L2CAP(s): Connect req: psm 17 scid 0x0040 > < ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 1 status 0 > Connection pending - No futher information available > < ACL data: handle 6 flags 0x02 dlen 10 > L2CAP(s): Info req: type 2 >> HCI Event: Remote Name Req Complete (0x07) plen 255 > status 0x00 bdaddr 7C:1E:52:18:7D:4F name 'Microsoft Wedge Mobile Keyboard' >> ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Info rsp: type 2 result 0 > Extended feature mask 0x0280 > Fixed Channels > Unicast Connectless Data Reception > < ACL data: handle 6 flags 0x02 dlen 10 > L2CAP(s): Info req: type 3 >> ACL data: handle 6 flags 0x02 dlen 20 > L2CAP(s): Info rsp: type 3 result 0 > Fixed channel list 0x00000006 > L2CAP Signalling Channel > L2CAP Connless > < ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 0 status 0 > Connection successful > < ACL data: handle 6 flags 0x02 dlen 12 > L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 0 >> ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 4 > MTU 48 > < ACL data: handle 6 flags 0x02 dlen 18 > L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 4 > MTU 48 >> ACL data: handle 6 flags 0x02 dlen 14 > L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 0 > Success >> ACL data: handle 6 flags 0x02 dlen 12 > L2CAP(s): Connect req: psm 19 scid 0x0041 > < ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Connect rsp: dcid 0x0041 scid 0x0041 result 1 status 2 > Connection pending - Authorization pending > < ACL data: handle 6 flags 0x02 dlen 5 > L2CAP(d): cid 0x0040 len 1 [psm 17] > HIDP: Control: Virtual cable unplug > < ACL data: handle 6 flags 0x02 dlen 12 > L2CAP(s): Disconn req: dcid 0x0040 scid 0x0040 > < ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Connect rsp: dcid 0x0041 scid 0x0041 result 3 status 0 > Connection refused - security block >> HCI Event: Disconn Complete (0x05) plen 4 > status 0x00 handle 6 reason 0x13 > Reason: Remote User Terminated Connection > > > 3. Try to manually connect to the keyboard. > > [bluetooth]# trust 7C:1E:52:18:7D:4F > Changing 7C:1E:52:18:7D:4F trust succeeded > [bluetooth]# pair 7C:1E:52:18:7D:4F > Attempting to pair with 7C:1E:52:18:7D:4F > Failed to pair: org.bluez.Error.AlreadyExists > [bluetooth]# connect 7C:1E:52:18:7D:4F > Attempting to connect to 7C:1E:52:18:7D:4F > [CHG] Device 7C:1E:52:18:7D:4F Connected: yes > [CHG] Device 7C:1E:52:18:7D:4F Paired: yes > > hcidump at this stage: > > < HCI Command: Create Connection (0x01|0x0005) plen 13 > bdaddr 7C:1E:52:18:7D:4F ptype 0xcc18 rswitch 0x01 clkoffset 0x0000 > Packet type: DM1 DM3 DM5 DH1 DH3 DH5 >> HCI Event: Command Status (0x0f) plen 4 > Create Connection (0x01|0x0005) status 0x00 ncmd 1 >> HCI Event: Connect Complete (0x03) plen 11 > status 0x00 handle 6 bdaddr 7C:1E:52:18:7D:4F type ACL encrypt 0x00 > < HCI Command: Read Remote Supported Features (0x01|0x001b) plen 2 > handle 6 >> HCI Event: Command Status (0x0f) plen 4 > Read Remote Supported Features (0x01|0x001b) status 0x00 ncmd 1 >> HCI Event: Read Remote Supported Features (0x0b) plen 11 > status 0x00 handle 6 > Features: 0xbc 0x06 0x86 0x78 0x18 0x1c 0x59 0x87 > < HCI Command: Remote Name Request (0x01|0x0019) plen 10 > bdaddr 7C:1E:52:18:7D:4F mode 2 clkoffset 0x0000 > < ACL data: handle 6 flags 0x02 dlen 10 > L2CAP(s): Info req: type 2 >> HCI Event: Command Status (0x0f) plen 4 > Remote Name Request (0x01|0x0019) status 0x00 ncmd 1 >> ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Info rsp: type 2 result 0 > Extended feature mask 0x0280 > Fixed Channels > Unicast Connectless Data Reception > < ACL data: handle 6 flags 0x02 dlen 10 > L2CAP(s): Info req: type 3 >> ACL data: handle 6 flags 0x02 dlen 20 > L2CAP(s): Info rsp: type 3 result 0 > Fixed channel list 0x00000006 > L2CAP Signalling Channel > L2CAP Connless > < ACL data: handle 6 flags 0x02 dlen 12 > L2CAP(s): Connect req: psm 1 scid 0x0040 >> ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Connect rsp: dcid 0x0042 scid 0x0040 result 0 status 0 > Connection successful > < ACL data: handle 6 flags 0x02 dlen 12 > L2CAP(s): Config req: dcid 0x0042 flags 0x00 clen 0 >> HCI Event: Remote Name Req Complete (0x07) plen 255 > status 0x00 bdaddr 7C:1E:52:18:7D:4F name 'Microsoft Wedge Mobile Keyboard' >> ACL data: handle 6 flags 0x02 dlen 16 > L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 4 > MTU 48 > < ACL data: handle 6 flags 0x02 dlen 18 > L2CAP(s): Config rsp: scid 0x0042 flags 0x00 result 0 clen 4 > MTU 48 >> ACL data: handle 6 flags 0x02 dlen 14 > L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 0 > Success > < ACL data: handle 6 flags 0x02 dlen 24 > L2CAP(d): cid 0x0042 len 20 [psm 1] > SDP SSA Req: tid 0x0 len 0xf > pat uuid-16 0x0100 (L2CAP) > max 65535 > aid(s) 0x0000 - 0xffff > cont 00 >> ACL data: handle 6 flags 0x02 dlen 27 >> ACL data: handle 6 flags 0x01 dlen 25 > L2CAP(d): cid 0x0040 len 48 [psm 1] > SDP SSA Rsp: tid 0x0 len 0x2b > count 38 > cont 02 00 26 > < ACL data: handle 6 flags 0x02 dlen 26 > L2CAP(d): cid 0x0042 len 22 [psm 1] > SDP SSA Req: tid 0x1 len 0x11 > pat uuid-16 0x0100 (L2CAP) > max 65535 > aid(s) 0x0000 - 0xffff > cont 02 00 26 >> ACL data: handle 6 flags 0x02 dlen 27 >> ACL data: handle 6 flags 0x01 dlen 25 > L2CAP(d): cid 0x0040 len 48 [psm 1] > SDP SSA Rsp: tid 0x1 len 0x2b > count 38 > cont 02 00 4C > < ACL data: handle 6 flags 0x02 dlen 26 > L2CAP(d): cid 0x0042 len 22 [psm 1] > SDP SSA Req: tid 0x2 len 0x11 > pat uuid-16 0x0100 (L2CAP) > max 65535 > aid(s) 0x0000 - 0xffff > cont 02 00 4C >> ACL data: handle 6 flags 0x02 dlen 27 >> ACL data: handle 6 flags 0x01 dlen 25 > L2CAP(d): cid 0x0040 len 48 [psm 1] > SDP SSA Rsp: tid 0x2 len 0x2b > count 38 > cont 02 00 72 > < ACL data: handle 6 flags 0x02 dlen 26 > L2CAP(d): cid 0x0042 len 22 [psm 1] > SDP SSA Req: tid 0x3 len 0x11 > pat uuid-16 0x0100 (L2CAP) > max 65535 > aid(s) 0x0000 - 0xffff > cont 02 00 72 >> ACL data: handle 6 flags 0x02 dlen 27 >> ACL data: handle 6 flags 0x01 dlen 25 > L2CAP(d): cid 0x0040 len 48 [psm 1] > SDP SSA Rsp: tid 0x3 len 0x2b > count 38 > cont 02 00 98 > < ACL data: handle 6 flags 0x02 dlen 26 > L2CAP(d): cid 0x0042 len 22 [psm 1] > SDP SSA Req: tid 0x4 len 0x11 > pat uuid-16 0x0100 (L2CAP) > max 65535 > aid(s) 0x0000 - 0xffff > cont 02 00 98 >> ACL data: handle 6 flags 0x02 dlen 27 >> ACL data: handle 6 flags 0x01 dlen 25 > L2CAP(d): cid 0x0040 len 48 [psm 1] > SDP SSA Rsp: tid 0x4 len 0x2b > count 38 > cont 02 00 BE > > > 4. Problem occurs after a couple of seconds. > > Failed to connect: org.bluez.Error.Failed > [CHG] Device 7C:1E:52:18:7D:4F Connected: no > > hcidump: > >> ACL data: handle 6 flags 0x02 dlen 12 > L2CAP(s): Disconn req: dcid 0x0040 scid 0x0042 > < HCI Command: Disconnect (0x01|0x0006) plen 3 > handle 6 reason 0x13 > Reason: Remote User Terminated Connection >> HCI Event: Command Status (0x0f) plen 4 > Disconnect (0x01|0x0006) status 0x00 ncmd 1 >> HCI Event: Disconn Complete (0x05) plen 4 > status 0x00 handle 6 reason 0x16 > Reason: Connection Terminated by Local Host > > Appreciate your help! > Cheers/Magnus -- To unsubscribe from this list: send the line "unsubscribe linux-bluetooth" in the body of a message to majordomo@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
