Subject: Re: btconfig: SIOCSBTFLAGS: Resource temporarily unavailable
To: Iain Hibbert <plunky@rya-online.net>
From: Berndt Josef Wulf <wulf@ping.net.au>
List: tech-kern
Date: 07/16/2006 16:32:17
--nextPart19880970.z9jRYQno91
Content-Type: multipart/mixed;
  boundary="Boundary-01=_5ReuETDtvdQQWEk"
Content-Transfer-Encoding: 7bit
Content-Disposition: inline

--Boundary-01=_5ReuETDtvdQQWEk
Content-Type: text/plain;
  charset="iso-8859-1"
Content-Transfer-Encoding: quoted-printable
Content-Disposition: inline

G'day,

I've downloaded hcidump and compiled a new kernel with DEBUG defines enable=
d.=20
=46ind boot log attached to this email.

The next snippet was generated upon execution of hcidump:

/var/log/message:
Jul 16 16:21:39  /netbsd: hci_usrreq: ATTACH
Jul 16 16:21:39  /netbsd: hci_ctloutput: req SETOPT
Jul 16 16:21:39  last message repeated 4 times
Jul 16 16:21:39  /netbsd: hci_usrreq: BIND

console output:

: {1} hcidump
HCIDump - HCI packet analyzer ver 1.5
device: any snap_len: 65540 filter: 0xffffffff

execution of "btconfig"
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D
console:
ubt0: bdaddr 00:00:00:00:00:00 flags=20
0xe0<INIT_BDADDR,INIT_BUFFER_SIZE,INIT_FEATURES>

/var/log/message:
Jul 16 16:24:44  /netbsd: hci_usrreq: ATTACH
Jul 16 16:24:44  /netbsd: hci_usrreq: CONTROL
Jul 16 16:24:44  /netbsd: hci_ioctl: cmd 0xc0386207
Jul 16 16:24:44  /netbsd: hci_usrreq: CONTROL
Jul 16 16:24:44  /netbsd: hci_ioctl: cmd 0xc0386207
Jul 16 16:24:44  /netbsd: hci_usrreq: DETACH

execution of "btconfig ubt0"
=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D=3D
console:
ubt0: bdaddr 00:00:00:00:00:00 flags=20
0xe0<INIT_BDADDR,INIT_BUFFER_SIZE,INIT_FEATURES>
        num_cmd =3D 0
        num_acl =3D 0, acl_mtu =3D 0
        num_sco =3D 0, sco_mtu =3D 0

/var/log/message:
Jul 16 16:25:23  /netbsd: hci_usrreq: ATTACH
Jul 16 16:25:23  /netbsd: hci_usrreq: CONTROL
Jul 16 16:25:23  /netbsd: hci_ioctl: cmd 0xc0386205
Jul 16 16:25:23  /netbsd: hci_usrreq: DETACH

execution of "btconfig ubt0 up"
console:
btconfig: SIOCSBTFLAGS: Resource temporarily unavailable

/var/log/message:
Jul 16 16:27:46  /netbsd: hci_usrreq: ATTACH
Jul 16 16:27:46  /netbsd: hci_usrreq: CONTROL
Jul 16 16:27:46  /netbsd: hci_ioctl: cmd 0xc0386205
Jul 16 16:27:46  /netbsd: hci_usrreq: CONTROL
Jul 16 16:27:46  /netbsd: hci_ioctl: cmd 0xc0386208
Jul 16 16:27:46  /netbsd: hci_send_cmd: (ubt0) opcode (003|0003)
Jul 16 16:27:46  /netbsd: hci_event: (ubt0) event COMMAND COMPLETE
Jul 16 16:27:46  /netbsd: hci_event_command_compl: (ubt0) opcode (003|0003)=
=20
num_cmd_pkts =3D 1
Jul 16 16:27:46  /netbsd: hci_send_cmd: (ubt0) opcode (004|0009)
Jul 16 16:27:46  /netbsd: hci_send_cmd: (ubt0) opcode (004|0005)
Jul 16 16:27:46  /netbsd: hci_send_cmd: (ubt0) opcode (004|0003)
Jul 16 16:27:47  /netbsd: ubt_recv_acl_complete: status=3DCANCELLED (6)
Jul 16 16:27:47  /netbsd: hci_usrreq: DETACH

Let me know if you want me to do any further testing...

cheerio Berndt

--Boundary-01=_5ReuETDtvdQQWEk
Content-Type: text/plain;
  charset="iso-8859-1";
  name="bootlog"
Content-Transfer-Encoding: 7bit
Content-Disposition: attachment;
	filename="bootlog"

Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005, 2006
    The NetBSD Foundation, Inc.  All rights reserved.
Copyright (c) 1982, 1986, 1989, 1991, 1993
    The Regents of the University of California.  All rights reserved.

NetBSD 3.99.21 (BAROSSA) #0: Sun Jul 16 08:21:29 CST 2006
	wulf@:/usr/src/sys/arch/i386/compile/BAROSSA
total memory = 1022 MB
avail memory = 995 MB
timecounter: Timecounters tick every 10.000 msec
timecounter: Timecounter "i8254" frequency 1193182 Hz quality 100
timecounter: selected timecounter "i8254" frequency 1193182 Hz quality 100
BIOS32 rev. 0 found at 0xffa10
mainbus0 (root)
cpu0 at mainbus0: apid 0 (boot processor)
cpu0: Intel Pentium Pro, II or III (686-class), 1995.12 MHz, id 0x6e8
cpu0: features bfe9fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu0: features bfe9fbff<PGE,MCA,CMOV,PAT,CFLUSH,DS,ACPI,MMX>
cpu0: features bfe9fbff<FXSR,SSE,SSE2,SS,HTT,TM,SBF>
cpu0: features2 c1a9<SSE3,MONITOR,VMX,EST,TM2,xTPR>
cpu0: "Genuine Intel(R) CPU           T2500  @ 2.00GHz"
cpu0: I-cache 32 KB 64B/line 8-way, D-cache 32 KB 64B/line 8-way
cpu0: L2 cache 2 MB 64B/line 8-way
cpu0: using thermal monitor 1
cpu0: Enhanced SpeedStep (1404 mV)  - unknown CPU or operating point.
cpu0: calibrating local timer
cpu0: apic clock running at 166 MHz
cpu0: 64 page colors
cpu1 at mainbus0: apid 1 (application processor)
cpu1: starting
cpu1: Intel Pentium Pro, II or III (686-class), 1995.00 MHz, id 0x6e8
cpu1: features bfe9fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR>
cpu1: features bfe9fbff<PGE,MCA,CMOV,PAT,CFLUSH,DS,ACPI,MMX>
cpu1: features bfe9fbff<FXSR,SSE,SSE2,SS,HTT,TM,SBF>
cpu1: features2 c1a9<SSE3,MONITOR,VMX,EST,TM2,xTPR>
cpu1: "Genuine Intel(R) CPU           T2500  @ 2.00GHz"
cpu1: I-cache 32 KB 64B/line 8-way, D-cache 32 KB 64B/line 8-way
cpu1: L2 cache 2 MB 64B/line 8-way
cpu1: using thermal monitor 1
cpu1: Enhanced SpeedStep (1404 mV)  - unknown CPU or operating point.
ioapic0 at mainbus0 apid 2 (I/O APIC)
ioapic0: pa 0xfec00000, version 20, 24 pins
ioapic0: misconfigured as apic 0
ioapic0: remapped to apic 2
acpi0 at mainbus0: Advanced Configuration and Power Interface
acpi0: using Intel ACPI CA subsystem version 20060217
acpi0: X/RSDT: OemId <DELL  , M07    ,27d60311>, AslId <ASL ,00000061>
acpi0: SCI interrupting at int 9
timecounter: Timecounter "ACPI-Safe" frequency 3579545 Hz quality 900
timecounter: selected timecounter "ACPI-Safe" frequency 3579545 Hz quality 900
ACPI-Safe 24-bit timer
mpacpi: could not get bus number, assuming bus 0
allocated pic ioapic0 type level pin 9 level 10 to cpu0 slot 16 idt entry 160
ACPI Object Type 'Processor' (0x0c) at acpi0 not configured
ACPI Object Type 'Processor' (0x0c) at acpi0 not configured
acpiacad0 at acpi0 (ACPI0003): ACPI AC Adapter
acpibat0 at acpi0 (PNP0C0A-1): ACPI Battery (Control Method)
acpilid0 at acpi0 (PNP0C0D): ACPI Lid Switch
acpibut0 at acpi0 (PNP0C0C): ACPI Power Button
acpibut1 at acpi0 (PNP0C0E): ACPI Sleep Button
PNP0C01 [System Board] at acpi0 not configured
PNP0A03 [PCI/PCI-X Host Bridge] at acpi0 not configured
PNP0C01 [System Board] at acpi0 not configured
PNP0C01 [System Board] at acpi0 not configured
pckbc0 at acpi0 (PNP0F13): aux port
pckbc0: irq 12
pckbc1 at acpi0 (PNP0303): kbd port
pckbc1: io 0x60,0x64,0x62,0x66 irq 1
PNP0B00 [AT Real-Time Clock] at acpi0 not configured
attimer1 at acpi0 (PNP0100): AT Timer
attimer1: io 0x40-0x43,0x50-0x53 irq 2
pcppi1 at acpi0 (PNP0800)
pcppi1: io 0x61,0x63,0x65,0x67
midi0 at pcppi1: PC speaker (CPU-intensive output)
sysbeep0 at pcppi1
PNP0C01 [System Board] at acpi0 not configured
PNP0000 [AT Interrupt Controller] at acpi0 not configured
PNP0200 [AT DMA Controller] at acpi0 not configured
PNP0C04 [Math Coprocessor] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
PNP0C0F [PCI interrupt link device] at acpi0 not configured
acpitz0 at acpi0: ACPI Thermal Zone
acpitz0: unable to get polling interval; using default of 30.0s
acpitz0: passive cooling mode only
acpitz0: critical 126.0C
pckbd0 at pckbc1 (kbd slot)
allocated pic ioapic0 type edge pin 1 level 9 to cpu0 slot 1 idt entry 144
pckbc1: using irq 1 for kbd slot
wskbd0 at pckbd0: console keyboard
pms0 at pckbc1 (aux slot)
allocated pic ioapic0 type edge pin 12 level 9 to cpu0 slot 12 idt entry 145
pckbc1: using irq 12 for aux slot
wsmouse0 at pms0 mux 0
pcppi1: attached to attimer1
pci0 at mainbus0 bus 0: configuration mode 1
pci0: i/o space, memory space enabled, rd/line, rd/mult, wr/inv ok
pchb0 at pci0 dev 0 function 0
pchb0: Intel 82945GM/PM/GMS Host Bridge (rev. 0x03)
ppb0 at pci0 dev 1 function 0: Intel product 0x27a1 (rev. 0x03)
pci1 at ppb0 bus 1
pci1: i/o space, memory space enabled, rd/line, wr/inv ok
vga0 at pci1 dev 0 function 0: ATI Technologies product 0x7145 (rev. 0x00)
wsdisplay0 at vga0 kbdmux 1: console (80x25, vt100 emulation), using wskbd0
wsmux1: connecting to wsdisplay0
azalia0 at pci0 dev 27 function 0: Generic High Definition Audio Controller
allocated pic ioapic0 type level pin 21 level 11 to cpu0 slot 11 idt entry 176
azalia0: interrupting at ioapic0 pin 21 (irq 11)
azalia0: host: Intel 82801GB/GR High Definition Audio Controller (rev. 1)
azalia0: host: High Definition Audio rev. 1.0
ppb1 at pci0 dev 28 function 0: Intel 82801GB/GR PCI Express Port #1 (rev. 0x01)
pci2 at ppb1 bus 11
pci2: i/o space, memory space enabled, rd/line, wr/inv ok
ppb2 at pci0 dev 28 function 1: Intel 82801GB/GR PCI Express Port #2 (rev. 0x01)
pci3 at ppb2 bus 12
pci3: i/o space, memory space enabled, rd/line, wr/inv ok
wpi0 at pci3 dev 0 function 0: Intel PRO/Wireless LAN 3945ABG Mini-PCI Adapter (rev. 0x02)
allocated pic ioapic0 type level pin 17 level 7 to cpu0 slot 5 idt entry 112
wpi0: interrupting at ioapic0 pin 17 (irq 5)
wpi0: 802.11 address 00:13:02:9c:e3:ba
wpi0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps
wpi0: 11g rates: 1Mbps 2Mbps 5.5Mbps 11Mbps 6Mbps 9Mbps 12Mbps 18Mbps 24Mbps 36Mbps 48Mbps 54Mbps
ppb3 at pci0 dev 28 function 3: Intel 82801GB/GR PCI Express Port #4 (rev. 0x01)
pci4 at ppb3 bus 13
pci4: i/o space, memory space enabled, rd/line, wr/inv ok
uhci0 at pci0 dev 29 function 0: Intel 82801GB/GR USB UHCI Controller (rev. 0x01)
allocated pic ioapic0 type level pin 20 level 6 to cpu0 slot 10 idt entry 96
uhci0: interrupting at ioapic0 pin 20 (irq 10)
usb0 at uhci0: USB revision 1.0
uhub0 at usb0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhci1 at pci0 dev 29 function 1: Intel 82801GB/GR USB UHCI Controller (rev. 0x01)
allocated pic ioapic0 type level pin 21 level 6 to cpu0 slot 11 idt entry 97
uhci1: interrupting at ioapic0 pin 21 (irq 11)
usb1 at uhci1: USB revision 1.0
uhub1 at usb1
uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2 at pci0 dev 29 function 2: Intel 82801GB/GR USB UHCI Controller (rev. 0x01)
allocated pic ioapic0 type level pin 22 level 6 to cpu0 slot 9 idt entry 98
uhci2: interrupting at ioapic0 pin 22 (irq 9)
usb2 at uhci2: USB revision 1.0
uhub2 at usb2
uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
uhci3 at pci0 dev 29 function 3: Intel 82801GB/GR USB UHCI Controller (rev. 0x01)
allocated pic ioapic0 type level pin 23 level 6 to cpu0 slot 7 idt entry 99
uhci3: interrupting at ioapic0 pin 23 (irq 7)
usb3 at uhci3: USB revision 1.0
uhub3 at usb3
uhub3: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub3: 2 ports with 2 removable, self powered
ehci0 at pci0 dev 29 function 7: Intel 82801GB/GR USB EHCI Controller (rev. 0x01)
allocated pic ioapic0 type level pin 20 level 6 to cpu0 slot 10 idt entry 96
ehci0: interrupting at ioapic0 pin 20 (irq 10)
ehci0: BIOS has given up ownership
ehci0: EHCI version 1.0
ehci0: companion controllers, 2 ports each: uhci0 uhci1 uhci2 uhci3
usb4 at ehci0: USB revision 2.0
uhub4 at usb4
uhub4: Intel EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub4: 8 ports with 8 removable, self powered
ppb4 at pci0 dev 30 function 0: Intel 82801BAM Hub-PCI Bridge (rev. 0xe1)
pci5 at ppb4 bus 3
pci5: i/o space, memory space enabled
bce0 at pci5 dev 0 function 0: Broadcom BCM4401-B0 10/100 Ethernet
allocated pic ioapic0 type level pin 17 level 7 to cpu0 slot 5 idt entry 112
bce0: interrupting at ioapic0 pin 17 (irq 5)
bmtphy0 at bce0 phy 1: BCM4401 10/100 media interface, rev. 0
bmtphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
bce0: Ethernet address 00:14:22:f7:28:8e
fwohci0 at pci5 dev 1 function 0: Ricoh product 0x0832 (rev. 0x00)
allocated pic ioapic0 type level pin 19 level 6 to cpu0 slot 3 idt entry 100
fwohci0: interrupting at ioapic0 pin 19 (irq 3)
fwohci0: OHCI version 1.10 (ROM=0)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 47:4f:c0:00:19:8e:44:38
fwohci0: Phy 1394a available S400, 1 ports.
fwohci0: Link S400, max_rec 2048 bytes.
ieee1394if0 at fwohci0: IEEE1394 bus
fwip0 at ieee1394if0: IP over IEEE1394
fwohci0: Initiate bus reset
Ricoh product 0x0822 (SD Host Controller system, interface 0x01, revision 0x19) at pci5 dev 1 function 1 not configured
Ricoh product 0x0843 (miscellaneous system, revision 0x01) at pci5 dev 1 function 2 not configured
Ricoh product 0x0592 (miscellaneous system, revision 0x0a) at pci5 dev 1 function 3 not configured
Ricoh product 0x0852 (miscellaneous system, revision 0x05) at pci5 dev 1 function 4 not configured
pcib0 at pci0 dev 31 function 0
pcib0: Intel 82801GBM LPC Interface Bridge (rev. 0x01)
piixide0 at pci0 dev 31 function 2
piixide0: Intel 82801GBM/GHM Serial ATA Controller (ICH7) (rev. 0x01)
piixide0: bus-master DMA support present
piixide0: primary channel wired to compatibility mode
allocated pic ioapic0 type edge pin 14 level 6 to cpu0 slot 14 idt entry 101
piixide0: primary channel interrupting at ioapic0 pin 14 (irq 14)
atabus0 at piixide0 channel 0
piixide0: secondary channel wired to compatibility mode
allocated pic ioapic0 type edge pin 15 level 6 to cpu0 slot 15 idt entry 102
piixide0: secondary channel interrupting at ioapic0 pin 15 (irq 15)
atabus1 at piixide0 channel 1
Intel 82801GB/GR SMBus Controller (SMBus serial bus, revision 0x01) at pci0 dev 31 function 3 not configured
isa0 at pcib0
isapnp0 at isa0 port 0x279: ISA Plug 'n Play device support
npx0 at isa0 port 0xf0-0xff
npx0: using exception 16
isapnp0: no ISA Plug 'n Play devices found
ioapic0: enabling
WARNING: Callback scheduled before sysmon task queue thread present.
fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER mode
ieee1394if0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
ieee1394if0: bus manager 0 (me)
timecounter: Timecounter "clockinterrupt" frequency 100 Hz quality 0
azalia0: codec[0]: Sigmatel STAC9220 (rev. 34.1)
azalia0: codec[0]: High Definition Audio rev. 1.0
azalia0: playback: max channels=2, encodings=1<PCM>
azalia0: playback: PCM formats=e07e0<24bit,20bit,16bit,192kHz,176.4kHz,96kHz,88.2kHz,48kHz,44.1kHz>
azalia0: recording: max channels=2, encodings=1<PCM>
azalia0: recording: PCM formats=e07e0<24bit,20bit,16bit,192kHz,176.4kHz,96kHz,88.2kHz,48kHz,44.1kHz>
azalia0: codec[1]: 0x14f1/0x2bfa (rev. 0.0)
azalia0: codec[1]: High Definition Audio rev. 0.9
azalia0: codec[1]: No support for modem function groups
azalia0: codec[1] has no audio function groups
audio0 at azalia0: full duplex, independent
Kernelized RAIDframe activated
uhub5 at uhub4 port 1
uhub5: Dell product 0xa005, class 9/0, rev 2.00/50.18, addr 2
uhub5: multiple transaction translators
uhub5: 4 ports with 1 removable, self powered
ubt0 at uhub5 port 4 configuration 1 interface 0
ubt0: Dell TrueMobile 350 Bluetooth USB Adapter, rev 2.00/24.22, addr 3
wd0 at atabus0 drive 0: <FUJITSU MHV2100BH>
wd0: drive supports 16-sector PIO transfers, LBA48 addressing
wd0: 93958 MB, 190899 cyl, 16 head, 63 sec, 512 bytes/sect x 192426570 sectors
wd0: 32-bit data port
wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd0(piixide0:0:0): using PIO mode 4, Ultra-DMA mode 5 (Ultra/100) (using DMA)
atapibus0 at atabus1: 2 targets
cd0 at atapibus0 drive 0: <_NEC DVD+/-RW ND-6650A, , 102C> cdrom removable
cd0: 32-bit data port
cd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 2 (Ultra/33)
cd0(piixide0:1:0): using PIO mode 4, Ultra-DMA mode 2 (Ultra/33) (using DMA)
ugen0 at uhub4 port 6
ugen0: Free Software Folks USRP Rev 2, rev 2.00/1.02, addr 4
boot device: wd0
root on wd0a dumps on wd0b
root file system type: ffs
cpu1: CPU 1 running
wpi0: could not read firmware file
wpi0: could not read firmware file
wpi0: could not read firmware file
wpi0: could not read firmware file
wsdisplay0: screen 1 added (80x25, vt100 emulation)
wsdisplay0: screen 2 added (80x25, vt100 emulation)
wsdisplay0: screen 3 added (80x25, vt100 emulation)
wsdisplay0: screen 4 added (80x25, vt100 emulation)
hci_usrreq: ATTACH
hci_usrreq: CONTROL
hci_ioctl: cmd 0xc0386207
hci_usrreq: CONTROL
hci_ioctl: cmd 0xc0386207
hci_usrreq: DETACH
hci_usrreq: ATTACH
hci_usrreq: CONTROL
hci_ioctl: cmd 0xc0386205
hci_usrreq: CONTROL
hci_ioctl: cmd 0xc0386208
hci_send_cmd: (ubt0) opcode (003|0003)
hci_event: (ubt0) event COMMAND COMPLETE
hci_event_command_compl: (ubt0) opcode (003|0003) num_cmd_pkts = 1
hci_send_cmd: (ubt0) opcode (004|0009)
hci_send_cmd: (ubt0) opcode (004|0005)
hci_send_cmd: (ubt0) opcode (004|0003)
ubt_recv_acl_complete: status=CANCELLED (6)
hci_usrreq: DETACH
hci_usrreq: ATTACH
hci_usrreq: BIND
hci_ctloutput: req SETOPT

--Boundary-01=_5ReuETDtvdQQWEk--

--nextPart19880970.z9jRYQno91
Content-Type: application/pgp-signature

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.4 (NetBSD)

iD8DBQBEueR9cUIHpeIRpjERArzFAJ4mQThuZ5pjvPXfofuV2xG/RWgKugCcC3z6
gNCjrARVFJ8EHDLPkJNKHLw=
=Cib/
-----END PGP SIGNATURE-----

--nextPart19880970.z9jRYQno91--