[Pkg-bluetooth-maintainers] Bug#464501: bluez-audio: A bluetooth headset can't be used to record sound.
Arthur Korn
arthur at korn.ch
Mon Apr 14 16:41:06 UTC 2008
Arthur Korn schrieb:
> > 3.30 is in unstable, do you mind retrying? It should be fixed.
So here the status:
Linux turing.prv.korn.ch 2.6.24-1-686 #1 SMP Thu Mar 27 17:45:04 UTC 2008 i686 GNU/Linux
ii bluez-audio 3.30-1 Bluetooth audio support
ii bluez-utils 3.30-1 Bluetooth tools and daemons
ii libasound2 1.0.16-2 ALSA library
ii libbluetooth2 3.30-1 Library to use the BlueZ Linux
verbose logs of an aplay attempt, the headset started to play
quiet noise:
pr 14 18:18:33 turing kernel: usb 2-1: new full speed USB device using uhci_hcd and address 2
Apr 14 18:18:34 turing kernel: usb 2-1: configuration #1 chosen from 1 choice
Apr 14 18:18:35 turing kernel: Bluetooth: HCI USB driver ver 2.9
Apr 14 18:18:35 turing hcid[2999]: HCI dev 0 registered
Apr 14 18:18:35 turing kernel: usbcore: registered new interface driver hci_usb
Apr 14 18:18:35 turing hcid[2999]: child 12626 exited
Apr 14 18:18:35 turing hcid[2999]: HCI dev 0 up
Apr 14 18:18:35 turing hcid[2999]: Device hci0 has been added
Apr 14 18:18:35 turing hcid[2999]: Starting security manager 0
Apr 14 18:18:35 turing hcid[2999]: Device hci0 has been activated
Apr 14 18:18:35 turing hcid[2999]: Creating device /hci0/dev_00_0D_44_5D_1D_A7
Apr 14 18:18:35 turing hcid[2999]: child 12646 exited
Apr 14 18:18:35 turing hcid[2999]: link_key_request (sba=00:09:DD:50:82:AA, dba=00:0D:44:5D:1D:A7)
Apr 14 18:18:36 turing hcid[2999]: Got a svc srch req
Apr 14 18:18:36 turing hcid[2999]: Seq type : 53
Apr 14 18:18:36 turing hcid[2999]: Data size : 5
Apr 14 18:18:36 turing hcid[2999]: Data type: 0x1a
Apr 14 18:18:36 turing hcid[2999]: No of elements : 1
Apr 14 18:18:36 turing hcid[2999]: Expected count: 40
Apr 14 18:18:36 turing hcid[2999]: Bytes scanned : 7
Apr 14 18:18:36 turing hcid[2999]: Continuation State size : 0
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x0
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x1
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10000
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10001
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10002
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10003
Apr 14 18:18:36 turing hcid[2999]: Match count: 0
Apr 14 18:18:36 turing hcid[2999]: Sending rsp. status 0
Apr 14 18:18:36 turing hcid[2999]: Bytes Sent : 10
Apr 14 18:18:36 turing hcid[2999]: Got a svc srch req
Apr 14 18:18:36 turing hcid[2999]: Seq type : 53
Apr 14 18:18:36 turing hcid[2999]: Data size : 5
Apr 14 18:18:36 turing hcid[2999]: Data type: 0x1a
Apr 14 18:18:36 turing hcid[2999]: No of elements : 1
Apr 14 18:18:36 turing hcid[2999]: Expected count: 40
Apr 14 18:18:36 turing hcid[2999]: Bytes scanned : 7
Apr 14 18:18:36 turing hcid[2999]: Continuation State size : 0
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x0
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x1
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10000
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10001
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10002
Apr 14 18:18:36 turing hcid[2999]: Checking svcRec : 0x10003
Apr 14 18:18:36 turing hcid[2999]: Match count: 1
Apr 14 18:18:36 turing hcid[2999]: Sending rsp. status 0
Apr 14 18:18:36 turing hcid[2999]: Bytes Sent : 14
Apr 14 18:18:36 turing hcid[2999]: Got a svc attr req
Apr 14 18:18:36 turing hcid[2999]: Seq type : 53
Apr 14 18:18:36 turing hcid[2999]: Data size : 3
Apr 14 18:18:36 turing hcid[2999]: Data type: 0x09
Apr 14 18:18:36 turing hcid[2999]: No of elements : 1
Apr 14 18:18:36 turing hcid[2999]: Continuation State size : 0
Apr 14 18:18:36 turing hcid[2999]: SvcRecHandle : 0x10000
Apr 14 18:18:36 turing hcid[2999]: max_rsp_size : 38
Apr 14 18:18:36 turing hcid[2999]: Entries in attr seq : 1
Apr 14 18:18:36 turing hcid[2999]: AttrDataType : 9
Apr 14 18:18:36 turing hcid[2999]: Sending rsp. status 0
Apr 14 18:18:36 turing hcid[2999]: Bytes Sent : 27
Apr 14 18:18:36 turing hcid[2999]: Selecting default device
Apr 14 18:18:36 turing hcid[2999]: Requesting authorization for device 00:0D:44:5D:1D:A7, UUID 00001112-0000-1000-8000-00805F9B34FB
Apr 14 18:18:36 turing hcid[2999]: State changed /org/bluez/audio/device289: HEADSET_STATE_DISCONNECTED -> HEADSET_STATE_CONNECT_IN_PROGRESS
Apr 14 18:18:36 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteServiceHandles()
Apr 14 18:18:36 turing hcid[2999]: no matching session found. creating a new one
Apr 14 18:18:36 turing hcid[2999]: /org/bluez: org.bluez.Database.RequestAuthorization()
Apr 14 18:18:36 turing hcid[2999]: handle_authorize_request
Apr 14 18:18:36 turing hcid[2999]: Found 00:0D:44:5D:1D:A7 connected to hci0
Apr 14 18:18:36 turing hcid[2999]: authorize request was forwarded
Apr 14 18:18:36 turing hcid[2999]: link_key_notify (sba=00:09:DD:50:82:AA, dba=00:0D:44:5D:1D:A7)
Apr 14 18:18:36 turing hcid[2999]: sdp session added to cache
Apr 14 18:18:36 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteServiceHandles()
Apr 14 18:18:36 turing hcid[2999]: found matching session, removing from list
Apr 14 18:18:36 turing hcid[2999]: sdp session added to cache
Apr 14 18:18:36 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteServiceHandles()
Apr 14 18:18:36 turing hcid[2999]: found matching session, removing from list
Apr 14 18:18:36 turing hcid[2999]: sdp session added to cache
Apr 14 18:18:36 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteServiceRecord()
Apr 14 18:18:36 turing hcid[2999]: found matching session, removing from list
Apr 14 18:18:36 turing hcid[2999]: sdp session added to cache
Apr 14 18:18:36 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteServiceRecord()
Apr 14 18:18:36 turing hcid[2999]: found matching session, removing from list
Apr 14 18:18:36 turing hcid[2999]: sdp session added to cache
Apr 14 18:18:36 turing hcid[2999]: Audio service discovery completed with success
Apr 14 18:18:36 turing hcid[2999]: Found Headset record
Apr 14 18:18:36 turing hcid[2999]: Discovered Headset service on RFCOMM channel 1
Apr 14 18:18:37 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.FinishRemoteServiceTransaction()
Apr 14 18:18:37 turing hcid[2999]: found matching session, removing from list
Apr 14 18:18:38 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.SetMinorClass()
Apr 14 18:18:38 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetMode()
Apr 14 18:18:38 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetName()
Apr 14 18:18:38 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteName()
Apr 14 18:18:38 turing hcid[2999]: /org/bluez/external_audio: org.bluez.Service.GetName()
Apr 14 18:18:49 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.SetTrusted()
Apr 14 18:18:49 turing hcid[2999]: authorize reply
Apr 14 18:18:49 turing hcid[2999]: successfull reply was sent
Apr 14 18:18:49 turing hcid[2999]: auth_agent_reply: returning
Apr 14 18:18:49 turing hcid[2999]: State changed /org/bluez/audio/device289: HEADSET_STATE_CONNECT_IN_PROGRESS -> HEADSET_STATE_CONNECTED
Apr 14 18:18:49 turing hcid[2999]: Accepted headset connection from 00:0D:44:5D:1D:A7 for /org/bluez/audio/device289
Apr 14 18:18:54 turing hcid[2999]: /org/bluez: org.bluez.Manager.ListServices()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_serial: org.bluez.Service.GetIdentifier()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_serial: org.bluez.Service.GetName()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_serial: org.bluez.Service.GetDescription()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_serial: org.bluez.Service.IsRunning()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_network: org.bluez.Service.GetIdentifier()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_network: org.bluez.Service.GetName()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_network: org.bluez.Service.GetDescription()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_network: org.bluez.Service.IsRunning()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_input: org.bluez.Service.GetIdentifier()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_input: org.bluez.Service.GetName()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_input: org.bluez.Service.GetDescription()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/service_input: org.bluez.Service.IsRunning()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez: org.bluez.Manager.ActivateService()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/external_audio: org.bluez.Service.GetIdentifier()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/external_audio: org.bluez.Service.GetName()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/external_audio: org.bluez.Service.GetDescription()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/external_audio: org.bluez.Service.IsRunning()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez: org.bluez.Manager.ActivateService()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/audio: org.bluez.audio.Manager.ListDevices()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez: org.bluez.Manager.DefaultAdapter()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez: org.bluez.Manager.ListAdapters()
Apr 14 18:18:54 turing input[3031]: /org/bluez/input: org.bluez.input.Manager.ListDevices()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetAddress()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetMode()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetDiscoverableTimeout()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetName()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetMajorClass()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetMinorClass()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.ListConnections()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteInfo()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.ListBondings()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteInfo()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.ListTrusts()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetRemoteInfo()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.ListAvailableModes()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetMode()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetDiscoverableTimeout()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetName()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetMajorClass()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/hci0: org.bluez.Adapter.GetMinorClass()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/audio/device289: org.bluez.audio.Device.GetAdapter()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/audio/device289: org.bluez.audio.Device.GetAddress()
Apr 14 18:18:54 turing hcid[2999]: /org/bluez/audio/device289: org.bluez.audio.Device.GetName()
Apr 14 18:19:07 turing fetchmail[3146]: awakened at Mon Apr 14 18:19:07 2008
Apr 14 18:19:07 turing fetchmail[3146]: sleeping at Mon Apr 14 18:19:07 2008 for 60 seconds
Apr 14 18:19:13 turing hcid[2999]: Accepted new client connection on unix socket (fd=16)
Apr 14 18:19:13 turing hcid[2999]: Audio API: received BT_GETCAPABILITIES_REQ
Apr 14 18:19:13 turing hcid[2999]: Audio API: sending BT_GETCAPABILITIES_RSP
Apr 14 18:19:13 turing hcid[2999]: Audio API: received BT_SETCONFIGURATION_REQ
Apr 14 18:19:13 turing hcid[2999]: config sco - device = 00:0D:44:5D:1D:A7 access_mode = 2
Apr 14 18:19:13 turing kernel: Bluetooth: SCO (Voice Link) ver 0.5
Apr 14 18:19:13 turing kernel: Bluetooth: SCO socket layer initialized
Apr 14 18:19:13 turing hcid[2999]: State changed /org/bluez/audio/device289: HEADSET_STATE_CONNECTED -> HEADSET_STATE_PLAY_IN_PROGRESS
Apr 14 18:19:14 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:19:15 turing last message repeated 159 times
Apr 14 18:19:15 turing hcid[2999]: Received AT+VGS=14
Apr 14 18:19:15 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:19:38 turing last message repeated 7916 times
Apr 14 18:19:39 turing dhclient: DHCPREQUEST on eth0 to 10.255.96.1 port 67
Apr 14 18:19:39 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:19:39 turing last message repeated 26 times
Apr 14 18:19:39 turing dhclient: DHCPACK from 10.255.96.1
Apr 14 18:19:39 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:19:39 turing last message repeated 159 times
Apr 14 18:19:39 turing dhclient: bound to 80.219.249.53 -- renewal in 2058 seconds.
Apr 14 18:19:39 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:19:53 turing last message repeated 4745 times
Apr 14 18:19:53 turing hcid[2999]: connect(): Connection timed out (110)
Apr 14 18:19:53 turing hcid[2999]: config failed
Apr 14 18:19:53 turing hcid[2999]: Audio API: sending BT_SETCONFIGURATION_RSP
Apr 14 18:19:53 turing hcid[2999]: State changed /org/bluez/audio/device289: HEADSET_STATE_PLAY_IN_PROGRESS -> HEADSET_STATE_CONNECTED
Apr 14 18:19:53 turing hcid[2999]: Unix client disconnected (fd=16)
Apr 14 18:19:53 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:20:07 turing last message repeated 4730 times
Apr 14 18:20:07 turing fetchmail[3146]: awakened at Mon Apr 14 18:20:07 2008
Apr 14 18:20:07 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:20:08 turing last message repeated 75 times
Apr 14 18:20:08 turing fetchmail[3146]: sleeping at Mon Apr 14 18:20:08 2008 for 60 seconds
Apr 14 18:20:08 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:20:39 turing last message repeated 10263 times
Apr 14 18:21:08 turing last message repeated 9743 times
Apr 14 18:21:08 turing fetchmail[3146]: awakened at Mon Apr 14 18:21:08 2008
Apr 14 18:21:08 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:21:08 turing last message repeated 122 times
Apr 14 18:21:08 turing fetchmail[3146]: sleeping at Mon Apr 14 18:21:08 2008 for 60 seconds
Apr 14 18:21:08 turing kernel: hci_scodata_packet: hci0 SCO packet for unknown connection handle 45
Apr 14 18:21:39 turing last message repeated 10140 times
Apr 14 18:22:07 turing last message repeated 9603 times
Apr 14 18:22:07 turing hcid[2999]: State changed /org/bluez/audio/device289: HEADSET_STATE_CONNECTED -> HEADSET_STATE_DISCONNECTED
--
Secure email, spread GPG, clearsign all mail. http://www.gnupg.org
.
The computer revolution is over. The computers won.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 189 bytes
Desc: Digital signature
Url : http://lists.alioth.debian.org/pipermail/pkg-bluetooth-maintainers/attachments/20080414/772b33a8/attachment-0001.pgp
More information about the Pkg-bluetooth-maintainers
mailing list