2009-05-07 21:01:02

by Brock Denson

[permalink] [raw]

Subject: bluez 4.x questions, SCO stops

---

Hi,
I am having a problem with SCO stopping after 1 minute that I would
really appreciate some help with. I can connect to the headset, query
responses, etc, but when I start sco either via a dbus-send command or
with the test-telephony play <> command then after 30 seconds I get a
dbus timeout and then 30 more seconds later the SCO will stop. I have
air-traced the session and can see that the host is sending silence
while SCO is being transmitted, so it seems functional, but I am sure I
am missing something. I have included just about everything I can think
of below in the hopes that someone has a few minutes to point out the
problem. Distribution is FC10.

---- asoundrc file (from bluez source)
pcm.headset {
type bluetooth
device "00:0b:2e:85:1e:53"
profile voice
}

ctl.headset {
type bluetooth
}

---- audio.conf -------------------
[General]
SCORouting=PCM
[Headset]
HFP=true
MaxConnections=1

---- Userland ---------------------
[brockd@demo3 bluez]$ test/test-telephony play 00:0b:2e:85:1e:53
Traceback (most recent call last):
File "test/test-telephony", line 91, in <module>
headset.Play()
File "/usr/lib/python2.5/site-packages/dbus/proxies.py", line 68, in
__call__
return self._proxy_method(*args, **keywords)
File "/usr/lib/python2.5/site-packages/dbus/proxies.py", line 140, in
__call__
**keywords)
File "/usr/lib/python2.5/site-packages/dbus/connection.py", line 630,
in call_blocking
message, timeout)
dbus.exceptions.DBusException: org.freedesktop.DBus.Error.NoReply: Did
not receive a reply. Possible causes include: the remote application did
not send a reply, the message bus security policy blocked the reply, the
reply timeout expired, or the network connection was broken.


---- HCI land ---------------------
HCI sniffer - Bluetooth packet analyzer ver 1.42
device: hci0 snap_len: 1028 filter: 0xffffffff
< HCI Command: Disconnect (0x01|0x0006) plen 3
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Disconn Complete (0x05) plen 4
> HCI Event: Max Slots Change (0x1b) plen 3
> HCI Event: Mode Change (0x14) plen 6
> HCI Event: Mode Change (0x14) plen 6
< HCI Command: Add SCO Connection (0x01|0x0007) plen 4
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Max Slots Change (0x1b) plen 3
> HCI Event: Connect Complete (0x03) plen 11
> ACL data: handle 38 flags 0x02 dlen 17
> ACL data: handle 38 flags 0x01 dlen 2
L2CAP(d): cid 0x0040 len 15 [psm 0]
< ACL data: handle 38 flags 0x02 dlen 14
L2CAP(d): cid 0x0041 len 10 [psm 0]
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Mode Change (0x14) plen 6
> HCI Event: Mode Change (0x14) plen 6
< HCI Command: Disconnect (0x01|0x0006) plen 3
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Disconn Complete (0x05) plen 4
> HCI Event: Max Slots Change (0x1b) plen 3
> HCI Event: Mode Change (0x14) plen 6
> HCI Event: Mode Change (0x14) plen 6
< HCI Command: Add SCO Connection (0x01|0x0007) plen 4
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Max Slots Change (0x1b) plen 3
> HCI Event: Connect Complete (0x03) plen 11
> ACL data: handle 38 flags 0x02 dlen 17
> ACL data: handle 38 flags 0x01 dlen 2
L2CAP(d): cid 0x0040 len 15 [psm 0]
< ACL data: handle 38 flags 0x02 dlen 14
L2CAP(d): cid 0x0041 len 10 [psm 0]
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Mode Change (0x14) plen 6
> HCI Event: Mode Change (0x14) plen 6
< HCI Command: Disconnect (0x01|0x0006) plen 3
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Disconn Complete (0x05) plen 4
> HCI Event: Max Slots Change (0x1b) plen 3
> HCI Event: Mode Change (0x14) plen 6
> HCI Event: Mode Change (0x14) plen 6
> ACL data: handle 38 flags 0x02 dlen 8
L2CAP(d): cid 0x0040 len 4 [psm 0]
< ACL data: handle 38 flags 0x02 dlen 8
L2CAP(d): cid 0x0041 len 4 [psm 0]
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 12
L2CAP(s): Disconn req: dcid 0x0040 scid 0x0041
< ACL data: handle 38 flags 0x02 dlen 12
L2CAP(s): Disconn rsp: dcid 0x0040 scid 0x0041
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Mode Change (0x14) plen 6
> HCI Event: Disconn Complete (0x05) plen 4
> HCI Event: Connect Request (0x04) plen 10
< HCI Command: Accept Connection Request (0x01|0x0009) plen 7
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Connect Complete (0x03) plen 11
> HCI Event: Page Scan Repetition Mode Change (0x20) plen 7
< HCI Command: Read Remote Supported Features (0x01|0x001b) plen 2
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Max Slots Change (0x1b) plen 3
> HCI Event: Command Status (0x0f) plen 4
< HCI Command: Remote Name Request (0x01|0x0019) plen 10
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Read Remote Supported Features (0x0b) plen 11
> HCI Event: Remote Name Req Complete (0x07) plen 255
> HCI Event: Link Key Request (0x17) plen 6
< HCI Command: Link Key Request Reply (0x01|0x000b) plen 22
> HCI Event: Command Complete (0x0e) plen 10
> ACL data: handle 38 flags 0x02 dlen 12
L2CAP(s): Connect req: psm 1 scid 0x0040
< ACL data: handle 38 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 38 flags 0x02 dlen 10
L2CAP(s): Info req: type 2
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 16
L2CAP(s): Info rsp: type 2 result 0
Extended feature mask 0x0000
< ACL data: handle 38 flags 0x02 dlen 16
L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 0 status 0
Connection successful
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 16
L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 4
MTU 48
< ACL data: handle 38 flags 0x02 dlen 18
L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 4
MTU 48
< ACL data: handle 38 flags 0x02 dlen 12
L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 0
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 14
L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 0
Success
> ACL data: handle 38 flags 0x02 dlen 24
L2CAP(d): cid 0x0040 len 20 [psm 1]
SDP SSA Req: tid 0x1 len 0xf
pat uuid-32 0x111f (Handsfree AG)
max 38
aid(s) 0x0004 (ProtocolDescList)
cont 00
< ACL data: handle 38 flags 0x02 dlen 33
L2CAP(d): cid 0x0040 len 29 [psm 1]
SDP SSA Rsp: tid 0x1 len 0x18
count 21
record #0
aid 0x0004 (ProtocolDescList)
< < uuid-16 0x0100 (L2CAP) > <
uuid-16 0x0003 (RFCOMM) uint 0xd > >
cont 00
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 12
L2CAP(s): Disconn req: dcid 0x0040 scid 0x0040
< ACL data: handle 38 flags 0x02 dlen 12
L2CAP(s): Disconn rsp: dcid 0x0040 scid 0x0040
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 12
L2CAP(s): Connect req: psm 3 scid 0x0041
< ACL data: handle 38 flags 0x02 dlen 16
L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0041 result 0 status 0
Connection successful
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 16
L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 4
MTU 132
< ACL data: handle 38 flags 0x02 dlen 18
L2CAP(s): Config rsp: scid 0x0041 flags 0x00 result 0 clen 4
MTU 132
< ACL data: handle 38 flags 0x02 dlen 16
L2CAP(s): Config req: dcid 0x0041 flags 0x00 clen 4
MTU 1013
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 18
L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 4
MTU 1013
> ACL data: handle 38 flags 0x02 dlen 8
L2CAP(d): cid 0x0040 len 4 [psm 3]
RFCOMM(s): SABM: cr 1 dlci 0 pf 1 ilen 0 fcs 0x1c
< ACL data: handle 38 flags 0x02 dlen 8
L2CAP(d): cid 0x0041 len 4 [psm 3]
RFCOMM(s): UA: cr 1 dlci 0 pf 1 ilen 0 fcs 0xd7
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 18
L2CAP(d): cid 0x0040 len 14 [psm 3]
RFCOMM(s): PN CMD: cr 1 dlci 0 pf 0 ilen 10 fcs 0x70 mcc_len 8
dlci 26 frame_type 0 credit_flow 15 pri 0 ack_timer 0
frame_size 126 max_retrans 0 credits 0
< ACL data: handle 38 flags 0x02 dlen 18
L2CAP(d): cid 0x0041 len 14 [psm 3]
RFCOMM(s): PN RSP: cr 0 dlci 0 pf 0 ilen 10 fcs 0xaa mcc_len 8
dlci 26 frame_type 0 credit_flow 14 pri 0 ack_timer 0
frame_size 126 max_retrans 0 credits 7
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 8
L2CAP(d): cid 0x0040 len 4 [psm 3]
RFCOMM(s): SABM: cr 1 dlci 26 pf 1 ilen 0 fcs 0xe7
< HCI Command: Authentication Requested (0x01|0x0011) plen 2
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Auth Complete (0x06) plen 3
< HCI Command: Set Connection Encryption (0x01|0x0013) plen 3
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Encrypt Change (0x08) plen 4
< ACL data: handle 38 flags 0x02 dlen 8
L2CAP(d): cid 0x0041 len 4 [psm 3]
RFCOMM(s): UA: cr 1 dlci 26 pf 1 ilen 0 fcs 0x2c
< HCI Command: Switch Role (0x02|0x000b) plen 7
< ACL data: handle 38 flags 0x02 dlen 12
L2CAP(d): cid 0x0041 len 8 [psm 3]
RFCOMM(s): MSC CMD: cr 0 dlci 0 pf 0 ilen 4 fcs 0xaa mcc_len 2
dlci 26 fc 0 rtc 1 rtr 1 ic 0 dv 1 b1 1 b2 1 b3 0 len 0
> HCI Event: Command Status (0x0f) plen 4
> HCI Event: Role Change (0x12) plen 8
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 12
L2CAP(d): cid 0x0040 len 8 [psm 3]
RFCOMM(s): MSC RSP: cr 1 dlci 0 pf 0 ilen 4 fcs 0x70 mcc_len 2
dlci 26 fc 0 rtc 1 rtr 1 ic 0 dv 1 b1 1 b2 1 b3 0 len 0
> ACL data: handle 38 flags 0x02 dlen 12
L2CAP(d): cid 0x0040 len 8 [psm 3]
RFCOMM(s): MSC CMD: cr 1 dlci 0 pf 0 ilen 4 fcs 0x70 mcc_len 2
dlci 26 fc 0 rtc 1 rtr 1 ic 0 dv 0 b1 1 b2 1 b3 0 len 0
> ACL data: handle 38 flags 0x02 dlen 9
L2CAP(d): cid 0x0040 len 5 [psm 3]
RFCOMM(d): UIH: cr 1 dlci 26 pf 1 ilen 0 fcs 0xf8 credits 15
< ACL data: handle 38 flags 0x02 dlen 12
L2CAP(d): cid 0x0041 len 8 [psm 3]
RFCOMM(s): MSC RSP: cr 0 dlci 0 pf 0 ilen 4 fcs 0xaa mcc_len 2
dlci 26 fc 0 rtc 1 rtr 1 ic 0 dv 0 b1 1 b2 1 b3 0 len 0
< ACL data: handle 38 flags 0x02 dlen 9
L2CAP(d): cid 0x0041 len 5 [psm 3]
RFCOMM(d): UIH: cr 0 dlci 26 pf 1 ilen 0 fcs 0x22 credits 33
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Number of Completed Packets (0x13) plen 5
> ACL data: handle 38 flags 0x02 dlen 19
L2CAP(d): cid 0x0040 len 15 [psm 3]
RFCOMM(d): UIH: cr 1 dlci 26 pf 0 ilen 11 fcs 0xe4
< ACL data: handle 38 flags 0x02 dlen 22
L2CAP(d): cid 0x0041 len 18 [psm 3]
RFCOMM(d): UIH: cr 0 dlci 26 pf 0 ilen 14 fcs 0x3e
< ACL data: handle 38 flags 0x02 dlen 14
L2CAP(d): cid 0x0041 len 10 [psm 3]
RFCOMM(d): UIH: cr 0 dlci 26 pf 0 ilen 6 fcs 0x3e
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Number of Completed Packets (0x13) plen 5
> HCI Event: Mode Change (0x14) plen 6
> HCI Event: Disconn Complete (0x05) plen 4


---- Bluetoothd ------------------
[brockd@demo3 bluez]$ sudo bluetoothd -n -d
bluetoothd[25721]: Bluetooth daemon
bluetoothd[25721]: Enabling debug information
bluetoothd[25721]: parsing main.conf
bluetoothd[25721]: discovto=0
bluetoothd[25721]: pairto=0
bluetoothd[25721]: pageto=8192
bluetoothd[25721]: name=%h-%d
bluetoothd[25721]: class=0x000100
bluetoothd[25721]: inqmode=0
bluetoothd[25721]: Key file does not have key 'DeviceID'
bluetoothd[25721]: Starting SDP server
bluetoothd[25721]: Loading plugins /usr/lib/bluetooth/plugins
bluetoothd[25721]: register_interface: path /org/bluez/25721/any
bluetoothd[25721]: Registered interface org.bluez.Service on path
/org/bluez/25721/any
bluetoothd[25721]: Parsing /etc/bluetooth/network.conf failed: No such
file or directory
bluetoothd[25721]: Config options: InterfacePrefix=bnep%d,
PANU_Script=(null), GN_Script=(null), NAP_Script=(null),
GN_Interface=pan0, NAP_Interface=pan1, Security=true
bluetoothd[25721]: bridge pan0 created
bluetoothd[25721]: Parsing /etc/bluetooth/input.conf failed: No such
file or directory
bluetoothd[25721]: Unix socket created: 13
bluetoothd[25721]: audio.conf: Key file does not have key 'MaxConnected'
bluetoothd[25721]: Telephony plugin initialized
bluetoothd[25721]: HFP AG features: "Ability to reject a call" "Enhanced
call status" "Extended Error Result Codes"
bluetoothd[25721]: HCI dev 0 registered
bluetoothd[25721]: child 25724 forked
bluetoothd[25721]: Agent registered for hci0 at
:1.32:/org/bluez/agent/hci0
bluetoothd[25721]: HCI dev 0 up
bluetoothd[25721]: Starting security manager 0
bluetoothd[25721]: register_interface: path /org/bluez/25721/hci0
bluetoothd[25721]: Registered interface org.bluez.Service on path
/org/bluez/25721/hci0
bluetoothd[25721]: network_server_probe: path /org/bluez/25721/hci0
bluetoothd[25721]: Adding record with handle 0x10000
bluetoothd[25721]: Record pattern UUID 0000000f-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001115-0000-1000-8000-00805f9
bluetoothd[25721]: register_server_record: got record id 0x10000
bluetoothd[25721]: Registered interface org.bluez.NetworkPeer on path
/org/bluez/25721/hci0
bluetoothd[25721]: network_server_probe: path /org/bluez/25721/hci0
bluetoothd[25721]: Adding record with handle 0x10001
bluetoothd[25721]: Record pattern UUID 0000000f-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001117-0000-1000-8000-00805f9
bluetoothd[25721]: register_server_record: got record id 0x10001
bluetoothd[25721]: Registered interface org.bluez.NetworkHub on path
/org/bluez/25721/hci0
bluetoothd[25721]: network_server_probe: path /org/bluez/25721/hci0
bluetoothd[25721]: Adding record with handle 0x10002
bluetoothd[25721]: Record pattern UUID 0000000f-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001116-0000-1000-8000-00805f9
bluetoothd[25721]: register_server_record: got record id 0x10002
bluetoothd[25721]: Registered interface org.bluez.NetworkRouter on path
/org/bluez/25721/hci0
bluetoothd[25721]: proxy_probe: path /org/bluez/25721/hci0
bluetoothd[25721]: Registered interface org.bluez.SerialProxyManager on
path /org/bluez/25721/hci0
bluetoothd[25721]: headset_server_probe: path /org/bluez/25721/hci0
bluetoothd[25721]: audio.conf: Key file does not have key 'Master'
bluetoothd[25721]: Adding record with handle 0x10003
bluetoothd[25721]: Record pattern UUID 00000003-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001108-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001112-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001203-0000-1000-8000-00805f9
bluetoothd[25721]: Adding record with handle 0x10004
bluetoothd[25721]: Record pattern UUID 00000003-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 0000111e-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 0000111f-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001203-0000-1000-8000-00805f9
bluetoothd[25721]: a2dp_server_probe: path /org/bluez/25721/hci0
bluetoothd[25721]: audio.conf: Key file does not have key 'Disable'
bluetoothd[25721]: audio.conf: Key file does not have group 'A2DP'
bluetoothd[25721]: audio.conf: Key file does not have group 'A2DP'
bluetoothd[25721]: audio.conf: Key file does not have group 'A2DP'
bluetoothd[25721]: audio.conf: Key file does not have group 'A2DP'
bluetoothd[25721]: audio.conf: Key file does not have key 'Master'
bluetoothd[25721]: SEP 0x179b5b0 registered: type:0 codec:0 seid:1
bluetoothd[25721]: Adding record with handle 0x10005
bluetoothd[25721]: Record pattern UUID 00000019-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 0000110a-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 0000110d-0000-1000-8000-00805f9
bluetoothd[25721]: avrcp_server_probe: path /org/bluez/25721/hci0
bluetoothd[25721]: audio.conf: Key file does not have key 'Master'
bluetoothd[25721]: Adding record with handle 0x10006
bluetoothd[25721]: Record pattern UUID 00000017-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 0000110c-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 0000110e-0000-1000-8000-00805f9
bluetoothd[25721]: Adding record with handle 0x10007
bluetoothd[25721]: Record pattern UUID 00000017-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00000100-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 00001002-0000-1000-8000-00805f9
bluetoothd[25721]: Record pattern UUID 0000110e-0000-1000-8000-00805f9
bluetoothd[25721]: Creating device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Probe drivers for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
00001108-0000-1000-8000-00805f9b34fb
bluetoothd[25721]: Registered interface org.bluez.Serial on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
0000111E-0000-1000-8000-00805F9B34FB
bluetoothd[25721]: headset_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Registered interface org.bluez.Input on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: Found Headset record
bluetoothd[25721]: Discovered Headset service on RFCOMM channel 2
bluetoothd[25721]: Registered interface org.bluez.Headset on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Found Handsfree record
bluetoothd[25721]: Discovered Handsfree service on RFCOMM channel 1
bluetoothd[25721]: Adapter /org/bluez/25721/hci0 has been enabled
bluetoothd[25721]: child 25724 exited
bluetoothd[25721]: Computer is classified as laptop
bluetoothd[25721]: Current device class is 0x4a010c
bluetoothd[25721]: Setting 0x00010c for major/minor device class
bluetoothd[25721]: Removing device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Serial on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: input_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Input on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Headset on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: create_device(00:0b:2e:85:1e:53)
bluetoothd[25721]: adapter_create_device(00:0b:2e:85:1e:53)
bluetoothd[25721]: Creating device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: error
updating services: Host is down (112)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: link_key_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: kernel auth requirements = 0x00
bluetoothd[25721]: pin_code_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
requesting agent authentication
bluetoothd[25721]: link_key_notify (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53, type=0)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: local auth 0x00 and remote auth 0xff
bluetoothd[25721]: storing link key of type 0x00
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: setting timer for reverse service discovery
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: hcid_dbus_bonding_process_complete: no pending auth
request
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: Probe drivers for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
0000111E-0000-1000-8000-00805F9B34FB
bluetoothd[25721]: Registered interface org.bluez.Serial on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Found Handsfree record
bluetoothd[25721]: Discovered Handsfree service on RFCOMM channel 1
bluetoothd[25721]: Registered interface org.bluez.Headset on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_DISCONNECTED
-> HEADSET_STATE_CONNECT_IN_PROGRESS
bluetoothd[25721]: authorize request was sent for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Probe drivers for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
0000111e-0000-1000-8000-00805f9b34fb
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
00001108-0000-1000-8000-00805f9b34fb
bluetoothd[25721]: headset_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Registered interface org.bluez.Input on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Found Headset record
bluetoothd[25721]: Found Handsfree record
bluetoothd[25721]: Discovered Handsfree service on RFCOMM channel 1
bluetoothd[25721]: Accepted headset connection from 00:0B:2E:85:1E:53
for /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Received AT+BRSF=26
bluetoothd[25721]: HFP HF features: "Call waiting and 3-way calling"
"Voice recognition activation" "Remote volume control"
bluetoothd[25721]: Received AT+CIND=?
bluetoothd[25721]: Received AT+CIND?
bluetoothd[25721]: Received AT+CMER=3, 0, 0, 1
bluetoothd[25721]: Event reporting (CMER): mode=3, ind=1
bluetoothd[25721]: HFP Service Level Connection established
bluetoothd[25721]: telephony-dummy: device 0x179c5e8 connected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_CONNECT_IN_PROGRESS -> HEADSET_STATE_CONNECTED
bluetoothd[25721]: Received AT+VGS=08
bluetoothd[25721]: telephony-dummy: device 0x179c5e8 disconnected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_CONNECTED ->
HEADSET_STATE_DISCONNECTED
bluetoothd[25721]: Disconnected from 00:0B:2E:85:1E:53,
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: link_key_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: kernel auth requirements = 0x00
bluetoothd[25721]: stored link key type = 0x00
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: hcid_dbus_bonding_process_complete: no pending auth
request
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_DISCONNECTED
-> HEADSET_STATE_CONNECT_IN_PROGRESS
bluetoothd[25721]: authorize request was sent for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Accepted headset connection from 00:0B:2E:85:1E:53
for /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Received AT+BRSF=26
bluetoothd[25721]: HFP HF features: "Call waiting and 3-way calling"
"Voice recognition activation" "Remote volume control"
bluetoothd[25721]: telephony-dummy: device 0x179c5e8 disconnected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_CONNECT_IN_PROGRESS -> HEADSET_STATE_DISCONNECTED
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: link_key_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: kernel auth requirements = 0x00
bluetoothd[25721]: stored link key type = 0x00
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: hcid_dbus_bonding_process_complete: no pending auth
request
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_DISCONNECTED
-> HEADSET_STATE_CONNECT_IN_PROGRESS
bluetoothd[25721]: authorize request was sent for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Accepted headset connection from 00:0B:2E:85:1E:53
for /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Received AT+BRSF=26
bluetoothd[25721]: HFP HF features: "Call waiting and 3-way calling"
"Voice recognition activation" "Remote volume control"
bluetoothd[25721]: Removing device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Serial on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Headset unregistered while device was connected!
bluetoothd[25721]: telephony-dummy: device 0x179c5e8 disconnected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_CONNECT_IN_PROGRESS -> HEADSET_STATE_DISCONNECTED
bluetoothd[25721]: Unregistered interface org.bluez.Headset on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: input_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Input on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: No matching connection found for handle 38
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: adapter_create_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: Creating device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: link_key_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: kernel auth requirements = 0x00
bluetoothd[25721]: pin_code_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
requesting agent authentication
bluetoothd[25721]: /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
canceling authentication request
bluetoothd[25721]: Removing temporary device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Removing device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: adapter_create_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: Creating device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: link_key_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: kernel auth requirements = 0x00
bluetoothd[25721]: pin_code_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
requesting agent authentication
bluetoothd[25721]: link_key_notify (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53, type=0)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: local auth 0x00 and remote auth 0xff
bluetoothd[25721]: storing link key of type 0x00
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: setting timer for reverse service discovery
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: hcid_dbus_bonding_process_complete: no pending auth
request
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: Probe drivers for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
0000111E-0000-1000-8000-00805F9B34FB
bluetoothd[25721]: Registered interface org.bluez.Serial on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Found Handsfree record
bluetoothd[25721]: Discovered Handsfree service on RFCOMM channel 1
bluetoothd[25721]: Registered interface org.bluez.Headset on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_DISCONNECTED
-> HEADSET_STATE_CONNECT_IN_PROGRESS
bluetoothd[25721]: authorize request was sent for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Probe drivers for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
0000111e-0000-1000-8000-00805f9b34fb
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
00001108-0000-1000-8000-00805f9b34fb
bluetoothd[25721]: headset_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Registered interface org.bluez.Input on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Found Headset record
bluetoothd[25721]: Found Handsfree record
bluetoothd[25721]: Discovered Handsfree service on RFCOMM channel 1
bluetoothd[25721]: Accepted headset connection from 00:0B:2E:85:1E:53
for /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Received AT+BRSF=26
bluetoothd[25721]: HFP HF features: "Call waiting and 3-way calling"
"Voice recognition activation" "Remote volume control"
bluetoothd[25721]: Received AT+CIND=?
bluetoothd[25721]: Received AT+CIND?
bluetoothd[25721]: Received AT+CMER=3, 0, 0, 1
bluetoothd[25721]: Event reporting (CMER): mode=3, ind=1
bluetoothd[25721]: HFP Service Level Connection established
bluetoothd[25721]: telephony-dummy: device 0x17960d0 connected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_CONNECT_IN_PROGRESS -> HEADSET_STATE_CONNECTED
bluetoothd[25721]: Received AT+VGS=08
bluetoothd[25721]: telephony-dummy: device 0x17960d0 disconnected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_CONNECTED ->
HEADSET_STATE_DISCONNECTED
bluetoothd[25721]: Removing device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Serial on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Headset on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: input_remove: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Unregistered interface org.bluez.Input on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: adapter_create_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: Creating device
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: link_key_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: kernel auth requirements = 0x00
bluetoothd[25721]: pin_code_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
requesting agent authentication
bluetoothd[25721]: link_key_notify (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53, type=0)
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: local auth 0x00 and remote auth 0xff
bluetoothd[25721]: storing link key of type 0x00
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: setting timer for reverse service discovery
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: hcid_dbus_bonding_process_complete: no pending auth
request
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: Probe drivers for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
0000111E-0000-1000-8000-00805F9B34FB
bluetoothd[25721]: Registered interface org.bluez.Serial on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Found Handsfree record
bluetoothd[25721]: Discovered Handsfree service on RFCOMM channel 1
bluetoothd[25721]: Registered interface org.bluez.Headset on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_DISCONNECTED
-> HEADSET_STATE_CONNECT_IN_PROGRESS
bluetoothd[25721]: authorize request was sent for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Probe drivers for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
0000111e-0000-1000-8000-00805f9b34fb
bluetoothd[25721]: serial_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
00001108-0000-1000-8000-00805f9b34fb
bluetoothd[25721]: headset_probe: path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Registered interface org.bluez.Input on path
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Found Headset record
bluetoothd[25721]: Found Handsfree record
bluetoothd[25721]: Discovered Handsfree service on RFCOMM channel 1
bluetoothd[25721]: Accepted headset connection from 00:0B:2E:85:1E:53
for /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Received AT+BRSF=26
bluetoothd[25721]: HFP HF features: "Call waiting and 3-way calling"
"Voice recognition activation" "Remote volume control"
bluetoothd[25721]: Received AT+CIND=?
bluetoothd[25721]: Received AT+CIND?
bluetoothd[25721]: Received AT+CMER=3, 0, 0, 1
bluetoothd[25721]: Event reporting (CMER): mode=3, ind=1
bluetoothd[25721]: HFP Service Level Connection established
bluetoothd[25721]: telephony-dummy: device 0x1794b48 connected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_CONNECT_IN_PROGRESS -> HEADSET_STATE_CONNECTED
bluetoothd[25721]: Received AT+VGS=08
bluetoothd[25721]: Received AT+VGS=09
bluetoothd[25721]: Received AT+VGS=11
bluetoothd[25721]: Received AT+VGS=09
bluetoothd[25721]: Received AT+VGS=07
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_CONNECTED ->
HEADSET_STATE_PLAY_IN_PROGRESS
bluetoothd[25721]: Received AT+VGS=07
bluetoothd[25721]: connect(): Connection timed out (110)
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_PLAY_IN_PROGRESS -> HEADSET_STATE_CONNECTED
bluetoothd[25721]: No matching connection found for handle 42
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_CONNECTED ->
HEADSET_STATE_PLAY_IN_PROGRESS
bluetoothd[25721]: Received AT+VGS=07
bluetoothd[25721]: connect(): Connection timed out (110)
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_PLAY_IN_PROGRESS -> HEADSET_STATE_CONNECTED
bluetoothd[25721]: No matching connection found for handle 42
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_CONNECTED ->
HEADSET_STATE_PLAY_IN_PROGRESS
bluetoothd[25721]: Received AT+VGS=07
bluetoothd[25721]: connect(): Connection timed out (110)
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_PLAY_IN_PROGRESS -> HEADSET_STATE_CONNECTED
bluetoothd[25721]: No matching connection found for handle 42
bluetoothd[25721]: telephony-dummy: device 0x1794b48 disconnected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_CONNECTED ->
HEADSET_STATE_DISCONNECTED
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: link_key_request (sba=00:02:5B:00:F3:00,
dba=00:0B:2E:85:1E:53)
bluetoothd[25721]: kernel auth requirements = 0x00
bluetoothd[25721]: stored link key type = 0x00
bluetoothd[25721]: hcid_dbus_bonding_process_complete: status=00
bluetoothd[25721]: adapter_get_device(00:0B:2E:85:1E:53)
bluetoothd[25721]: hcid_dbus_bonding_process_complete: no pending auth
request
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53: HEADSET_STATE_DISCONNECTED
-> HEADSET_STATE_CONNECT_IN_PROGRESS
bluetoothd[25721]: authorize request was sent for
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Accepted headset connection from 00:0B:2E:85:1E:53
for /org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53
bluetoothd[25721]: Received AT+BRSF=26
bluetoothd[25721]: HFP HF features: "Call waiting and 3-way calling"
"Voice recognition activation" "Remote volume control"
bluetoothd[25721]: telephony-dummy: device 0x1794b48 disconnected
bluetoothd[25721]: State changed
/org/bluez/25721/hci0/dev_00_0B_2E_85_1E_53:
HEADSET_STATE_CONNECT_IN_PROGRESS -> HEADSET_STATE_DISCONNECTED

2009-05-11 18:51:33

by Marcel Holtmann

[permalink] [raw]

Subject: Re: [PATCH] Fix introspection issue in gdbus

---

Hi Denis,

please don't hijack older threads :)

> Attached is a patch to fix a small issue with introspection when using gdbus
> library. Basically the introspection data for '/' can never be invalidated,
> which leads to funny results when objects are added & removed from '/'
> hierarchy.
>
> Attached patch should fix the issue.

patch has been applied to BlueZ, obexd, ConnMan and oFono. Thanks.

Regards

Marcel

2009-05-11 18:45:49

by Denis Kenzior

[permalink] [raw]

Subject: [PATCH] Fix introspection issue in gdbus

---

Hi Marcel,

Attached is a patch to fix a small issue with introspection when using gdbus
library. Basically the introspection data for '/' can never be invalidated,
which leads to funny results when objects are added & removed from '/'
hierarchy.

Attached patch should fix the issue.

Regards,
-Denis

---

Attachments:

(No filename) (306.00 B)
0001-Make-the-parent-path-invalidateable.patch (723.00 B)
Download all attachments

2009-05-07 23:59:07

by Brock Denson

[permalink] [raw]

Subject: RE: bluez 4.x questions, SCO stops

---

-----Original Message-----
From: Johan Hedberg [mailto:[email protected]]
Sent: Thursday, May 07, 2009 4:25 PM
To: Brock Denson
Cc: [email protected]
Subject: Re: bluez 4.x questions, SCO stops

Hi Brock,

On Thu, May 07, 2009, Brock Denson wrote:
> I am having a problem with SCO stopping after 1 minute that I would
> really appreciate some help with. I can connect to the headset, query
> responses, etc, but when I start sco either via a dbus-send command or
> with the test-telephony play <> command then after 30 seconds I get a
> dbus timeout and then 30 more seconds later the SCO will stop. I have
> air-traced the session and can see that the host is sending silence
> while SCO is being transmitted, so it seems functional, but I am sure
I
> am missing something. I have included just about everything I can
think
> of below in the hopes that someone has a few minutes to point out the
> problem. Distribution is FC10.

You've got several occurences of the following in the logs:
bluetoothd[25721]: connect(): Connection timed out (110)

I'm pretty sure that's coming for the SCO socket and I recall there
being
a related kernel bug which got fixed some time back. You might want to
try
with the latest 2.6.30-rc kernel or one of Marcel's git trees (e.g.
bluetooth-testing). Those should have the issue fixed.

Johan

[mbd] So I went back and took the changes from Marcel's commit
c89b6e6bda... and this appears to fix the problem I was seeing. Thanks
a lot for your help.

To report this email as spam click
https://www.mailcontrol.com/sr/rAh+GXBN20nTndxI!oX7UsdpzMR7Bo2KTOUX18r2s
UgefAez5Mzz7yd1bhrfSNyTdIWXn92SVA2toBNtFUgrMg== .

2009-05-07 22:00:49

by Brock Denson

[permalink] [raw]

Subject: RE: bluez 4.x questions, SCO stops

---

Hi Johan,
Changing kernels would be very difficult for me now. Would this be in
reference to 'Fix SCO state handling for incoming connections' commit
from Marcel Holtmann on 15 Jan 2009? I can relatively easily put in
this change.

Brock

-----Original Message-----
From: Johan Hedberg [mailto:[email protected]]
Sent: Thursday, May 07, 2009 4:25 PM
To: Brock Denson
Cc: [email protected]
Subject: Re: bluez 4.x questions, SCO stops

Hi Brock,

On Thu, May 07, 2009, Brock Denson wrote:
> I am having a problem with SCO stopping after 1 minute that I would
> really appreciate some help with. I can connect to the headset, query
> responses, etc, but when I start sco either via a dbus-send command or
> with the test-telephony play <> command then after 30 seconds I get a
> dbus timeout and then 30 more seconds later the SCO will stop. I have
> air-traced the session and can see that the host is sending silence
> while SCO is being transmitted, so it seems functional, but I am sure
I
> am missing something. I have included just about everything I can
think
> of below in the hopes that someone has a few minutes to point out the
> problem. Distribution is FC10.

You've got several occurences of the following in the logs:
bluetoothd[25721]: connect(): Connection timed out (110)

I'm pretty sure that's coming for the SCO socket and I recall there
being
a related kernel bug which got fixed some time back. You might want to
try
with the latest 2.6.30-rc kernel or one of Marcel's git trees (e.g.
bluetooth-testing). Those should have the issue fixed.

Johan


To report this email as spam click
https://www.mailcontrol.com/sr/rAh+GXBN20nTndxI!oX7UsdpzMR7Bo2KTOUX18r2s
UgefAez5Mzz7yd1bhrfSNyTdIWXn92SVA2toBNtFUgrMg== .

2009-05-07 21:25:08

by Johan Hedberg

[permalink] [raw]

Subject: Re: bluez 4.x questions, SCO stops

---

Hi Brock,

On Thu, May 07, 2009, Brock Denson wrote:
> I am having a problem with SCO stopping after 1 minute that I would
> really appreciate some help with. I can connect to the headset, query
> responses, etc, but when I start sco either via a dbus-send command or
> with the test-telephony play <> command then after 30 seconds I get a
> dbus timeout and then 30 more seconds later the SCO will stop. I have
> air-traced the session and can see that the host is sending silence
> while SCO is being transmitted, so it seems functional, but I am sure I
> am missing something. I have included just about everything I can think
> of below in the hopes that someone has a few minutes to point out the
> problem. Distribution is FC10.

You've got several occurences of the following in the logs:
bluetoothd[25721]: connect(): Connection timed out (110)

I'm pretty sure that's coming for the SCO socket and I recall there being
a related kernel bug which got fixed some time back. You might want to try
with the latest 2.6.30-rc kernel or one of Marcel's git trees (e.g.
bluetooth-testing). Those should have the issue fixed.

Johan