[Bluez-devel] No sound in bluetooth headset

Nel
2006-12-12T04:05:44Z

[-- Attachment #1.1: Type: text/plain, Size: 17245 bytes --]

Hello all,
I am trying the new bluetooth-alsa plugin as mentioned https://help.ubuntu.com/community/BluetoothAudio.

All goes fine but while playing a file via "aplay" i get absolutely no sound output.The previous way of a btsco kernel module worked out of the box.
any pointers ???. ..

herez the output:

1. output of headsetd -n
headsetd[267]: Bluetooth headset daemon version 0.42
headsetd[267]: Configuration phase ended: target bdaddr is 00:07:A4:5C:64:F0, timeout is 6000 ms
headsetd[267]: Changing state: Idle-->Paging
headsetd[267]: Changing state: Paging-->Connecting
Headset connected
headsetd[267]: Changing state: Connecting-->Ready
headsetd[267]: Changing state: Ready-->Opening
headsetd[267]: SCO channel opened handle=0x0031 mtu=64
headsetd[267]: Changing state: Opening-->Streaming
headsetd[267]: Appli closed socket
headsetd[267]: Changing state: Streaming-->Closewaiting
headsetd[267]: Nobody uses SCO channel anymore, closing it.
headsetd[267]: Changing state: Closewaiting-->Connected
headsetd[267]: Changing state: Connected-->Ready
headsetd[267]: Changing state: Ready-->Opening
headsetd[267]: SCO channel opened handle=0x002d mtu=64
headsetd[267]: Changing state: Opening-->Streaming
headsetd[267]: Appli closed socket
headsetd[267]: Changing state: Streaming-->Closewaiting
headsetd[267]: Nobody uses SCO channel anymore, closing it.
headsetd[267]: Changing state: Closewaiting-->Connected

2. output of hcidump-XV

HCI sniffer - Bluetooth packet analyzer ver 1.31
device: hci0 snap_len: 1028 filter: 0xffffffff
< HCI Command: Create Connection (0x01|0x0005) plen 13
bdaddr 00:07:A4:5C:64:F0 ptype 0xcc18 rswitch 0x00 clkoffset 0x0000
Packet type: DM1 DM3 DM5 DH1 DH3 DH5
> HCI Event: Command Status (0x0f) plen 4
Create Connection (0x01|0x0005) status 0x00 ncmd 1
> HCI Event: Link Key Request (0x17) plen 6
bdaddr 00:07:A4:5C:64:F0
< HCI Command: Link Key Request Reply (0x01|0x000b) plen 22
bdaddr 00:07:A4:5C:64:F0 key E714BA0E8E81C483415F5C4748E48386
> HCI Event: Command Complete (0x0e) plen 10
Link Key Request Reply (0x01|0x000b) ncmd 1
status 0x00 bdaddr 00:07:A4:5C:64:F0
> HCI Event: PIN Code Request (0x16) plen 6
bdaddr 00:07:A4:5C:64:F0
< HCI Command: PIN Code Request Reply (0x01|0x000d) plen 23
bdaddr 00:07:A4:5C:64:F0 len 4 pin '0000'
> HCI Event: Command Complete (0x0e) plen 10
PIN Code Request Reply (0x01|0x000d) ncmd 1
status 0x00 bdaddr 00:07:A4:5C:64:F0
> HCI Event: Link Key Notification (0x18) plen 23
bdaddr 00:07:A4:5C:64:F0 key AFB27ADC905E5234E28F51A6D5B9A4DE type 0
> HCI Event: Connect Complete (0x03) plen 11
status 0x00 handle 42 bdaddr 00:07:A4:5C:64:F0 type ACL encrypt 0x01
< ACL data: handle 42 flags 0x02 dlen 12
L2CAP(s): Connect req: psm 1 scid 0x0040
< HCI Command: Write Link Policy Settings (0x02|0x000d) plen 4
handle 42 policy 0x0f
Link policy: RSWITCH HOLD SNIFF PARK
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> HCI Event: Command Complete (0x0e) plen 6
Write Link Policy Settings (0x02|0x000d) ncmd 1
status 0x00 handle 42
> HCI Event: Page Scan Repetition Mode Change (0x20) plen 7
bdaddr 00:07:A4:5C:64:F0 mode 1
> ACL data: handle 42 flags 0x02 dlen 16
L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 1 status 2
Connection pending - Authorization pending
> ACL data: handle 42 flags 0x02 dlen 16
L2CAP(s): Connect rsp: dcid 0x0040 scid 0x0040 result 0 status 0
Connection successful
< ACL data: handle 42 flags 0x02 dlen 12
L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 0
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 flags 0x02 dlen 14
L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 0
Success
> ACL data: handle 42 flags 0x02 dlen 16
L2CAP(s): Config req: dcid 0x0040 flags 0x00 clen 4
MTU 48
< ACL data: handle 42 flags 0x02 dlen 14
L2CAP(s): Config rsp: scid 0x0040 flags 0x00 result 0 clen 0
Success
< ACL data: handle 42 flags 0x02 dlen 24
L2CAP(d): cid 0x0040 len 20 [psm 1]
SDP SSA Req: tid 0x0 len 0xf
pat uuid-16 0x1108 (Headset)
max 65535
aid(s) 0x0000 - 0xffff
cont 00
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 flags 0x02 dlen 17
> ACL data: handle 42 flags 0x01 dlen 17
> ACL data: handle 42 flags 0x01 dlen 17
> ACL data: handle 42 flags 0x01 dlen 1
L2CAP(d): cid 0x0040 len 48 [psm 1]
SDP SSA Rsp: tid 0x0 len 0x2b
count 38
cont 02 00 22
< ACL data: handle 42 flags 0x02 dlen 26
L2CAP(d): cid 0x0040 len 22 [psm 1]
SDP SSA Req: tid 0x1 len 0x11
pat uuid-16 0x1108 (Headset)
max 65535
aid(s) 0x0000 - 0xffff
cont 02 00 22
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 flags 0x02 dlen 17
> ACL data: handle 42 flags 0x01 dlen 17
> ACL data: handle 42 flags 0x01 dlen 12
L2CAP(d): cid 0x0040 len 42 [psm 1]
SDP SSA Rsp: tid 0x1 len 0x25
count 34
record #0
aid 0x0000 (SrvRecHndl)
uint 0x10000
aid 0x0001 (SrvClassIDList)
< uuid-16 0x1108 (Headset) uuid-16 0x1203 (Audio) >
aid 0x0004 (ProtocolDescList)
< < uuid-16 0x0100 (L2CAP) > <
uuid-16 0x0003 (RFCOMM) uint 0x1 > >
aid 0x0009 (BTProfileDescList)
< < uuid-16 0x1108 (Headset) uint 0x100 > >
aid 0x0100 (SrvName)
str "Headset"
aid 0x0302 (unknown)
bool 0x1
cont 00
< ACL data: handle 42 flags 0x02 dlen 12
L2CAP(s): Disconn req: dcid 0x0040 scid 0x0040
< ACL data: handle 42 flags 0x02 dlen 12
L2CAP(s): Connect req: psm 3 scid 0x0041
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 flags 0x02 dlen 12
L2CAP(s): Disconn rsp: dcid 0x0040 scid 0x0040
> ACL data: handle 42 flags 0x02 dlen 16
L2CAP(s): Connect rsp: dcid 0x0041 scid 0x0041 result 1 status 2
Connection pending - Authorization pending
> ACL data: handle 42 flags 0x02 dlen 16
L2CAP(s): Connect rsp: dcid 0x0041 scid 0x0041 result 0 status 0
Connection successful
< ACL data: handle 42 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
handle 42 packets 1
> ACL data: handle 42 flags 0x02 dlen 14
L2CAP(s): Config rsp: scid 0x0041 flags 0x00 result 0 clen 0
Success
> ACL data: handle 42 flags 0x02 dlen 16
L2CAP(s): Config req: dcid 0x0041 flags 0x00 clen 4
MTU 1013
< ACL data: handle 42 flags 0x02 dlen 14
L2CAP(s): Config rsp: scid 0x0041 flags 0x00 result 0 clen 0
Success
< ACL data: handle 42 flags 0x02 dlen 8
L2CAP(d): cid 0x0041 len 4 [psm 3]
RFCOMM(s): SABM: cr 1 dlci 0 pf 1 ilen 0 fcs 0x1c
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 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
< ACL data: handle 42 flags 0x02 dlen 18
L2CAP(d): cid 0x0041 len 14 [psm 3]
RFCOMM(s): PN CMD: cr 1 dlci 0 pf 0 ilen 10 fcs 0x70 mcc_len 8
dlci 2 frame_type 0 credit_flow 15 pri 7 ack_timer 0
frame_size 1008 max_retrans 0 credits 7
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 flags 0x02 dlen 17
> ACL data: handle 42 flags 0x01 dlen 1
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 2 frame_type 0 credit_flow 14 pri 7 ack_timer 0
frame_size 127 max_retrans 0 credits 0
< ACL data: handle 42 flags 0x02 dlen 8
L2CAP(d): cid 0x0041 len 4 [psm 3]
RFCOMM(s): SABM: cr 1 dlci 2 pf 1 ilen 0 fcs 0x59
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 flags 0x02 dlen 8
L2CAP(d): cid 0x0041 len 4 [psm 3]
RFCOMM(s): UA: cr 1 dlci 2 pf 1 ilen 0 fcs 0x92
< ACL data: handle 42 flags 0x02 dlen 12
L2CAP(d): cid 0x0041 len 8 [psm 3]
RFCOMM(s): MSC CMD: cr 1 dlci 0 pf 0 ilen 4 fcs 0x70 mcc_len 2
dlci 2 fc 0 rtc 1 rtr 1 ic 0 dv 1 b1 0 b2 0 b3 0 len 0
< HCI Command: Add SCO Connection (0x01|0x0007) plen 4
handle 42 ptype 0x00e0
Packet type: HV1 HV2 HV3
> ACL data: handle 42 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 2 fc 0 rtc 1 rtr 1 ic 0 dv 0 b1 0 b2 0 b3 0 len 0
< ACL data: handle 42 flags 0x02 dlen 12
L2CAP(d): cid 0x0041 len 8 [psm 3]
RFCOMM(s): MSC RSP: cr 1 dlci 0 pf 0 ilen 4 fcs 0x70 mcc_len 2
dlci 2 fc 0 rtc 1 rtr 1 ic 0 dv 0 b1 0 b2 0 b3 0 len 0
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> HCI Event: Command Status (0x0f) plen 4
Add SCO Connection (0x01|0x0007) status 0x00 ncmd 1
> HCI Event: Connect Complete (0x03) plen 11
status 0x00 handle 45 bdaddr 00:07:A4:5C:64:F0 type SCO encrypt 0x01
> HCI Event: Number of Completed Packets (0x13) plen 5
handle 42 packets 1
> ACL data: handle 42 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 2 fc 0 rtc 1 rtr 1 ic 0 dv 1 b1 0 b2 0 b3 0 len 0
< ACL data: handle 42 flags 0x02 dlen 9
L2CAP(d): cid 0x0041 len 5 [psm 3]
RFCOMM(d): UIH: cr 1 dlci 2 pf 1 ilen 0 fcs 0x86 credits 33
> SCO data: handle 45 dlen 48
0000: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
0010: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
0020: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ................
> SCO data: handle 45 dlen 48

*---------------------------
sco packets now

*---------------------------

3. output of aplay -D pcm.headset startup.wav
DEBUG: _snd_pcm_sco_open: Starting pcm_sco plugin.
DEBUG: _snd_pcm_sco_open: Open mode is for Playback.
DEBUG: _snd_pcm_sco_open: opened as ioplug=0x24848, pcm=0x24988, ioplug->callback = 0x402f1a10
Playing WAVE '/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo
DEBUG: sco_prepare: Preparing with io->period_size = 24, io->buffer_size = 4008
DEBUG: sco_pointer: returning bt_headset->hw_ptr=0
DEBUG: sco_pointer: returning bt_headset->hw_ptr=0
DEBUG: sco_pointer: returning bt_headset->hw_ptr=0
DEBUG: sco_write: areas->step=16, areas->first=0, offset=0, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=219822000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=24
DEBUG: sco_write: areas->step=16, areas->first=0, offset=24, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=264549000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=48
DEBUG: sco_pointer: returning bt_headset->hw_ptr=48
DEBUG: sco_pointer: returning bt_headset->hw_ptr=48
DEBUG: sco_write: areas->step=16, areas->first=0, offset=48, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=301765000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=72
DEBUG: sco_pointer: returning bt_headset->hw_ptr=72
DEBUG: sco_pointer: returning bt_headset->hw_ptr=72
DEBUG: sco_write: areas->step=16, areas->first=0, offset=72, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=325625000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=96
DEBUG: sco_pointer: returning bt_headset->hw_ptr=96
DEBUG: sco_pointer: returning bt_headset->hw_ptr=96
DEBUG: sco_write: areas->step=16, areas->first=0, offset=96, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=356110000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=120
DEBUG: sco_pointer: returning bt_headset->hw_ptr=120
DEBUG: sco_pointer: returning bt_headset->hw_ptr=120
DEBUG: sco_write: areas->step=16, areas->first=0, offset=120, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=385821000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=144
DEBUG: sco_pointer: returning bt_headset->hw_ptr=144
DEBUG: sco_pointer: returning bt_headset->hw_ptr=144
DEBUG: sco_write: areas->step=16, areas->first=0, offset=144, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=423014000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=168
DEBUG: sco_pointer: returning bt_headset->hw_ptr=168
DEBUG: sco_pointer: returning bt_headset->hw_ptr=168
DEBUG: sco_write: areas->step=16, areas->first=0, offset=168, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=448017000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=192
DEBUG: sco_pointer: returning bt_headset->hw_ptr=192
DEBUG: sco_pointer: returning bt_headset->hw_ptr=192
DEBUG: sco_write: areas->step=16, areas->first=0, offset=192, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=475980000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=216
DEBUG: sco_pointer: returning bt_headset->hw_ptr=216
DEBUG: sco_pointer: returning bt_headset->hw_ptr=216
DEBUG: sco_write: areas->step=16, areas->first=0, offset=216, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=515286000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=240
DEBUG: sco_pointer: returning bt_headset->hw_ptr=240
DEBUG: sco_pointer: returning bt_headset->hw_ptr=240
DEBUG: sco_write: areas->step=16, areas->first=0, offset=240, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=571482000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=264
DEBUG: sco_pointer: returning bt_headset->hw_ptr=264
DEBUG: sco_pointer: returning bt_headset->hw_ptr=264
DEBUG: sco_write: areas->step=16, areas->first=0, offset=264, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=604298000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=288
DEBUG: sco_pointer: returning bt_headset->hw_ptr=288
DEBUG: sco_pointer: returning bt_headset->hw_ptr=288
DEBUG: sco_write: areas->step=16, areas->first=0, offset=288, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=630276000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=312
DEBUG: sco_pointer: returning bt_headset->hw_ptr=312
DEBUG: sco_pointer: returning bt_headset->hw_ptr=312
DEBUG: sco_write: areas->step=16, areas->first=0, offset=312, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=657855000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=336
DEBUG: sco_pointer: returning bt_headset->hw_ptr=336
DEBUG: sco_pointer: returning bt_headset->hw_ptr=336
DEBUG: sco_write: areas->step=16, areas->first=0, offset=336, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=686221000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=360
DEBUG: sco_pointer: returning bt_headset->hw_ptr=360
DEBUG: sco_pointer: returning bt_headset->hw_ptr=360
DEBUG: sco_write: areas->step=16, areas->first=0, offset=360, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=734687000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=384
DEBUG: sco_pointer: returning bt_headset->hw_ptr=384
DEBUG: sco_pointer: returning bt_headset->hw_ptr=384
DEBUG: sco_write: areas->step=16, areas->first=0, offset=384, size=24, io->nonblock=0
DEBUG: sco_write: time: seconds=948068724 nseconds=765250000
DEBUG: sco_write: returning 24
DEBUG: sco_pointer: returning bt_headset->hw_ptr=408
DEBUG: sco_pointer: returning bt_headset->hw_ptr=408
Aborted by signal Interrupt...
DEBUG: sco_stop:
DEBUG: sco_close: closing ioplug=0x24848
DEBUG: sco_close: Closing Playback stream

*--------------------------
a series of such messages indicating data transfer

*--------------------------

What am i missing ???

thanks in advance ,

Cheers,
Nel

[-- Attachment #1.2: Type: text/html, Size: 21822 bytes --]

[-- Attachment #2: Type: text/plain, Size: 347 bytes --]

-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share your
opinions on IT & business topics through brief surveys - and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV

[-- Attachment #3: Type: text/plain, Size: 164 bytes --]