All of lore.kernel.org
 help / color / mirror / Atom feed
* bluez 4.x questions, SCO stops
@ 2009-05-07 21:01 Brock Denson
  2009-05-07 21:25 ` Johan Hedberg
  0 siblings, 1 reply; 6+ messages in thread
From: Brock Denson @ 2009-05-07 21:01 UTC (permalink / raw)
  To: linux-bluetooth

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


^ permalink raw reply	[flat|nested] 6+ messages in thread
* Re: bluez 4.x questions, SCO stops
  2009-05-07 21:01 bluez 4.x questions, SCO stops Brock Denson
@ 2009-05-07 21:25 ` Johan Hedberg
  2009-05-07 22:00   ` Brock Denson
  2009-05-07 23:59   ` Brock Denson
  0 siblings, 2 replies; 6+ messages in thread
From: Johan Hedberg @ 2009-05-07 21:25 UTC (permalink / raw)
  To: Brock Denson; +Cc: linux-bluetooth

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

^ permalink raw reply	[flat|nested] 6+ messages in thread
* RE: bluez 4.x questions, SCO stops
  2009-05-07 21:25 ` Johan Hedberg
@ 2009-05-07 22:00   ` Brock Denson
  2009-05-07 23:59   ` Brock Denson
  1 sibling, 0 replies; 6+ messages in thread
From: Brock Denson @ 2009-05-07 22:00 UTC (permalink / raw)
  To: Johan Hedberg; +Cc: linux-bluetooth

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:johan.hedberg@gmail.com] 
Sent: Thursday, May 07, 2009 4:25 PM
To: Brock Denson
Cc: linux-bluetooth@vger.kernel.org
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== .

^ permalink raw reply	[flat|nested] 6+ messages in thread
* RE: bluez 4.x questions, SCO stops
  2009-05-07 21:25 ` Johan Hedberg
  2009-05-07 22:00   ` Brock Denson
@ 2009-05-07 23:59   ` Brock Denson
  2009-05-11 18:45     ` [PATCH] Fix introspection issue in gdbus Denis Kenzior
  1 sibling, 1 reply; 6+ messages in thread
From: Brock Denson @ 2009-05-07 23:59 UTC (permalink / raw)
  To: Johan Hedberg; +Cc: linux-bluetooth

-----Original Message-----
From: Johan Hedberg [mailto:johan.hedberg@gmail.com] 
Sent: Thursday, May 07, 2009 4:25 PM
To: Brock Denson
Cc: linux-bluetooth@vger.kernel.org
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== .

^ permalink raw reply	[flat|nested] 6+ messages in thread
* [PATCH] Fix introspection issue in gdbus
  2009-05-07 23:59   ` Brock Denson
@ 2009-05-11 18:45     ` Denis Kenzior
  2009-05-11 18:51       ` Marcel Holtmann
  0 siblings, 1 reply; 6+ messages in thread
From: Denis Kenzior @ 2009-05-11 18:45 UTC (permalink / raw)
  To: linux-bluetooth

[-- Attachment #1: Type: text/plain, Size: 306 bytes --]

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

[-- Attachment #2: 0001-Make-the-parent-path-invalidateable.patch --]
[-- Type: text/x-patch, Size: 752 bytes --]

From 3cd7a2c3483ceac8950c32d0c96786b15e05946f Mon Sep 17 00:00:00 2001
From: Denis Kenzior <denis.kenzior@intel.com>
Date: Wed, 1 Apr 2009 16:56:53 -0500
Subject: [PATCH] Make the parent path invalidateable

---
 gdbus/object.c |    6 +++++-
 1 files changed, 5 insertions(+), 1 deletions(-)

diff --git a/gdbus/object.c b/gdbus/object.c
index 2823054..3186921 100644
--- a/gdbus/object.c
+++ b/gdbus/object.c
@@ -307,7 +307,11 @@ static void invalidate_parent_data(DBusConnection *conn, const char *child_path)
 	if (!slash)
 		goto done;
 
-	*slash = '\0';
+	if (slash == parent_path && parent_path[1] != '\0')
+		parent_path[1] = '\0';
+	else
+		*slash = '\0';
+
 	if (!strlen(parent_path))
 		goto done;
 
-- 
1.6.0.6


^ permalink raw reply related	[flat|nested] 6+ messages in thread
* Re: [PATCH] Fix introspection issue in gdbus
  2009-05-11 18:45     ` [PATCH] Fix introspection issue in gdbus Denis Kenzior
@ 2009-05-11 18:51       ` Marcel Holtmann
  0 siblings, 0 replies; 6+ messages in thread
From: Marcel Holtmann @ 2009-05-11 18:51 UTC (permalink / raw)
  To: Denis Kenzior; +Cc: linux-bluetooth

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



^ permalink raw reply	[flat|nested] 6+ messages in thread
end of thread, other threads:[~2009-05-11 18:51 UTC | newest]

Thread overview: 6+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2009-05-07 21:01 bluez 4.x questions, SCO stops Brock Denson
2009-05-07 21:25 ` Johan Hedberg
2009-05-07 22:00   ` Brock Denson
2009-05-07 23:59   ` Brock Denson
2009-05-11 18:45     ` [PATCH] Fix introspection issue in gdbus Denis Kenzior
2009-05-11 18:51       ` Marcel Holtmann

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.