BBB reset failed, TIMEOUT

[Matteo Beccati <php%beccati.com@localhost>]

Hi,

a few days ago I tried sending a report as a reply to kern/32626
(http://gnats.netbsd.org/32626), but apparently it wasn't recorded.
Since then I've tried to upgrade from 6.1RC2 to current, but the problem
is still there.

The current kernel now also outputs some extra lines to dmesg:

Apr 16 07:12:18 epia /netbsd: ehci_sync_hc: cv_timedwait() = 35
Apr 16 07:13:24 epia /netbsd: ehci_sync_hc: cv_timedwait() = 35
Apr 16 07:13:24 epia /netbsd: umass0: BBB reset failed, TIMEOUT
Apr 16 07:14:30 epia /netbsd: ehci_sync_hc: cv_timedwait() = 35
Apr 16 07:14:30 epia /netbsd: umass0: BBB bulk-in clear stall failed,
TIMEOUT
Apr 16 07:15:36 epia /netbsd: ehci_sync_hc: cv_timedwait() = 35
Apr 16 07:15:36 epia /netbsd: umass0: BBB bulk-out clear stall failed,
TIMEOUT
Apr 16 07:16:42 epia /netbsd: ehci_sync_hc: cv_timedwait() = 35
Apr 16 07:17:48 epia /netbsd: ehci_sync_hc: cv_timedwait() = 35
Apr 16 07:17:48 epia /netbsd: umass0: BBB reset failed, TIMEOUT

My previous report, with updated dmesg.

After upgrading to 6.1 RC2, I've started experiencing the same issue.
Every night, during the generation of the daily report, the USB hard
drive generates quite a lot of these errors:

umass0: BBB reset failed, TIMEOUT
umass0: BBB bulk-in clear stall failed, TIMEOUT
umass0: BBB bulk-out clear stall failed, TIMEOUT

After that I cannot cleanly shutdown, nor kill -9 some processes. I
don't recall this happening with 6.0, but maybe the drive just had less
data (i.e. there are backup copies of the old filesystems now).

Here's a dmesg output for reference:

NetBSD 6.99.19 (GENERIC) #0: Sat Apr 13 05:52:19 UTC 2013

builds%b7.netbsd.org@localhost:/home/builds/ab/HEAD/i386/201304122330Z-obj/home/builds/ab/HEAD/src/sys/arch/i386/compile/GENERIC
total memory = 2685 MB
avail memory = 2627 MB
timecounter: Timecounters tick every 10.000 msec
timecounter: Timecounter "i8254" frequency 1193182 Hz quality 100
System manufacturer System Product Name (System Version)
mainbus0 (root)
ACPI Warning: Optional field Pm2ControlBlock has zero address or length:
0x0000000000000000/0x1 (20110623/tbfadt-586)
cpu0 at mainbus0 apid 0: AMD E-350 Processor, id 0x500f10
cpu1 at mainbus0 apid 1: AMD E-350 Processor, id 0x500f10
ioapic0 at mainbus0 apid 0: pa 0xfec00000, version 21, 24 pins
acpi0 at mainbus0: Intel ACPICA 20110623
acpi0: X/RSDT: OemId <ALASKA,   A M I,01072009>, AslId <AMI ,00010013>
ACPI Error: [RAMB] Namespace lookup failure, AE_NOT_FOUND
(20110623/psargs-392)
ACPI Exception: AE_NOT_FOUND, Could not execute arguments for [RAMW]
(Region) (20110623/nsinit-380)
acpi0: SCI interrupting at int 9
timecounter: Timecounter "ACPI-Fast" frequency 3579545 Hz quality 1000
hpet0 at acpi0: high precision event timer (mem 0xfed00000-0xfed00400)
timecounter: Timecounter "hpet0" frequency 14318180 Hz quality 2000
AMDN (PNP0C01) at acpi0 not configured
S800 (PNP0C02) at acpi0 not configured
SIO1 (PNP0C02) at acpi0 not configured
attimer1 at acpi0 (TMR, PNP0100): io 0x40-0x43 irq 0
pcppi1 at acpi0 (SPKR, PNP0800): io 0x61
midi0 at pcppi1: PC speaker
sysbeep0 at pcppi1
RMSC (PNP0C02) at acpi0 not configured
npx1 at acpi0 (COPR, PNP0C04): io 0xf0-0xff irq 13
npx1: reported by CPUID; using exception 16
NBRM (PNP0C02) at acpi0 not configured
UAR1 (PNP0501) at acpi0 not configured
BROD (PNP0C02) at acpi0 not configured
acpibut0 at acpi0 (PWRB, PNP0C0C-170): ACPI Power Button
RMEM (PNP0C01) at acpi0 not configured
OMSC (PNP0C02) at acpi0 not configured
acpiwmi0 at acpi0 (AMW0, PNP0C14-ASUSWMI): ACPI WMI Interface
wmieeepc0 at acpiwmi0: Asus Eee PC WMI mappings
apm0 at acpi0: Power Management spec V1.2
attimer1: attached to pcppi1
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: vendor 0x1022 product 0x1510 (rev. 0x00)
vga1 at pci0 dev 1 function 0: vendor 0x1002 product 0x9802 (rev. 0x00)
wsdisplay0 at vga1 kbdmux 1: console (80x25, vt100 emulation)
wsmux1: connecting to wsdisplay0
drm at vga1 not configured
hdaudio0 at pci0 dev 1 function 1: HD Audio Controller
hdaudio0: interrupting at ioapic0 pin 19
hdafg0 at hdaudio0: ATI R6xx HDMI
hdafg0: HDMI00 2ch: Digital Out [Jack]
hdafg0: 2ch/0ch 32000Hz 44100Hz 48000Hz PCM16 AC3
ppb0 at pci0 dev 4 function 0: vendor 0x1022 product 0x1512 (rev. 0x00)
ppb0: PCI Express 2.0 <Root Port of PCI-E Root Complex> x4 @ 5.0Gb/s
pci1 at ppb0 bus 1
pci1: i/o space, memory space enabled, rd/line, wr/inv ok
ahcisata0 at pci0 dev 17 function 0: vendor 0x1002 product 0x4391 (rev.
0x40)
ahcisata0: interrupting at ioapic0 pin 19
ahcisata0: AHCI revision 1.20, 6 ports, 32 slots, CAP
0xf732ff05<PSC,SSC,PMD,SPM,ISS=0x3=Gen3,SCLO,SAL,SALP,SMPS,SSNTF,SNCQ,S64A>
atabus0 at ahcisata0 channel 0
atabus1 at ahcisata0 channel 1
atabus2 at ahcisata0 channel 2
atabus3 at ahcisata0 channel 3
atabus4 at ahcisata0 channel 4
atabus5 at ahcisata0 channel 5
ohci0 at pci0 dev 18 function 0: vendor 0x1002 product 0x4397 (rev. 0x00)
ohci0: interrupting at ioapic0 pin 18
ohci0: OHCI version 1.0, legacy support
usb0 at ohci0: USB revision 1.0
ehci0 at pci0 dev 18 function 2: vendor 0x1002 product 0x4396 (rev. 0x00)
ehci0: interrupting at ioapic0 pin 17
ehci0: dropped intr workaround enabled
ehci0: BIOS has given up ownership
ehci0: EHCI version 1.0
ehci0: companion controller, 5 ports each: ohci0
usb1 at ehci0: USB revision 2.0
ohci1 at pci0 dev 19 function 0: vendor 0x1002 product 0x4397 (rev. 0x00)
ohci1: interrupting at ioapic0 pin 18
ohci1: OHCI version 1.0, legacy support
usb2 at ohci1: USB revision 1.0
ehci1 at pci0 dev 19 function 2: vendor 0x1002 product 0x4396 (rev. 0x00)
ehci1: interrupting at ioapic0 pin 17
ehci1: dropped intr workaround enabled
ehci1: BIOS has given up ownership
ehci1: EHCI version 1.0
ehci1: companion controller, 5 ports each: ohci1
usb3 at ehci1: USB revision 2.0
piixpm0 at pci0 dev 20 function 0: vendor 0x1002 product 0x4385 (rev. 0x42)
piixpm0: polling (SB800)
iic0 at piixpm0: I2C bus
pcib0 at pci0 dev 20 function 3: vendor 0x1002 product 0x439d (rev. 0x40)
ppb1 at pci0 dev 20 function 4: vendor 0x1002 product 0x4384 (rev. 0x40)
pci2 at ppb1 bus 2
pci2: i/o space, memory space enabled
ohci2 at pci0 dev 20 function 5: vendor 0x1002 product 0x4399 (rev. 0x00)
ohci2: interrupting at ioapic0 pin 18
ohci2: OHCI version 1.0, legacy support
usb4 at ohci2: USB revision 1.0
ppb2 at pci0 dev 21 function 0: vendor 0x1002 product 0x43a0 (rev. 0x00)
ppb2: PCI Express 2.0 <Root Port of PCI-E Root Complex> x1 @ 5.0Gb/s
ppb2: link is x1 @ 2.5Gb/s
pci3 at ppb2 bus 3
pci3: i/o space, memory space enabled, rd/line, wr/inv ok
athn0 at pci3 dev 0 function 0: Atheros AR9285
athn0: rev 2 (1T1R), ROM rev 14, address 74:2f:68:4a:d4:85
athn0: interrupting at ioapic0 pin 16
athn0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps
athn0: 11g rates: 1Mbps 2Mbps 5.5Mbps 11Mbps 6Mbps 9Mbps 12Mbps 18Mbps
24Mbps 36Mbps 48Mbps 54Mbps
ppb3 at pci0 dev 21 function 1: vendor 0x1002 product 0x43a1 (rev. 0x00)
ppb3: PCI Express 2.0 <Root Port of PCI-E Root Complex> x1 @ 5.0Gb/s
ppb3: link is x1 @ 2.5Gb/s
pci4 at ppb3 bus 4
pci4: i/o space, memory space enabled, rd/line, wr/inv ok
re0 at pci4 dev 0 function 0: RealTek 8168/8111 PCIe Gigabit Ethernet
(rev. 0x06)
re0: interrupting at ioapic0 pin 17
re0: Ethernet address 14:da:e9:6e:3b:9d
re0: using 256 tx descriptors
rgephy0 at re0 phy 7: RTL8169S/8110S/8211 1000BASE-T media interface, rev. 4
rgephy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT,
1000baseT-FDX, auto
ppb4 at pci0 dev 21 function 2: vendor 0x1002 product 0x43a2 (rev. 0x00)
ppb4: PCI Express 2.0 <Root Port of PCI-E Root Complex> x1 @ 5.0Gb/s
pci5 at ppb4 bus 5
pci5: i/o space, memory space enabled, rd/line, wr/inv ok
vendor 0x1033 product 0x0194 (USB serial bus, interface 0x30, revision
0x03) at pci5 dev 0 function 0 not configured
ppb5 at pci0 dev 21 function 3: vendor 0x1002 product 0x43a3 (rev. 0x00)
ppb5: PCI Express 2.0 <Root Port of PCI-E Root Complex> x1 @ 5.0Gb/s
pci6 at ppb5 bus 6
pci6: i/o space, memory space enabled, rd/line, wr/inv ok
vendor 0x1033 product 0x0194 (USB serial bus, interface 0x30, revision
0x03) at pci6 dev 0 function 0 not configured
ohci3 at pci0 dev 22 function 0: vendor 0x1002 product 0x4397 (rev. 0x00)
ohci3: interrupting at ioapic0 pin 18
ohci3: OHCI version 1.0, legacy support
usb5 at ohci3: USB revision 1.0
ehci2 at pci0 dev 22 function 2: vendor 0x1002 product 0x4396 (rev. 0x00)
ehci2: interrupting at ioapic0 pin 17
ehci2: dropped intr workaround enabled
ehci2: BIOS has given up ownership
ehci2: EHCI version 1.0
ehci2: companion controller, 4 ports each: ohci3
usb6 at ehci2: USB revision 2.0
pchb1 at pci0 dev 24 function 0: vendor 0x1022 product 0x1700 (rev. 0x43)
pchb2 at pci0 dev 24 function 1: vendor 0x1022 product 0x1701 (rev. 0x00)
pchb3 at pci0 dev 24 function 2: vendor 0x1022 product 0x1702 (rev. 0x00)
amdnb_misc0 at pci0 dev 24 function 3: AMD NB Misc Configuration
amdtemp0 at amdnb_misc0: AMD CPU Temperature Sensors (Family14h)
pchb4 at pci0 dev 24 function 4: vendor 0x1022 product 0x1704 (rev. 0x00)
pchb5 at pci0 dev 24 function 5: vendor 0x1022 product 0x1718 (rev. 0x00)
pchb6 at pci0 dev 24 function 6: vendor 0x1022 product 0x1716 (rev. 0x00)
pchb7 at pci0 dev 24 function 7: vendor 0x1022 product 0x1719 (rev. 0x00)
isa0 at pcib0
com0 at isa0 port 0x3f8-0x3ff irq 4: ns16550a, working fifo
pckbc0 at isa0 port 0x60-0x64
acpicpu0 at cpu0: ACPI CPU
acpicpu0: C1: HLT, lat   0 us, pow     0 mW
acpicpu0: C2: I/O, lat 100 us, pow     0 mW
acpicpu0: P0: FFH, lat   1 us, pow  4940 mW, 1600 MHz
acpicpu0: P1: FFH, lat   1 us, pow  3282 mW, 1280 MHz
acpicpu0: P2: FFH, lat   1 us, pow  1400 mW,  800 MHz
acpicpu0: T0: I/O, lat   1 us, pow     0 mW, 100 %
acpicpu0: T1: I/O, lat   1 us, pow     0 mW,  88 %
acpicpu0: T2: I/O, lat   1 us, pow     0 mW,  76 %
acpicpu0: T3: I/O, lat   1 us, pow     0 mW,  64 %
acpicpu0: T4: I/O, lat   1 us, pow     0 mW,  52 %
acpicpu0: T5: I/O, lat   1 us, pow     0 mW,  40 %
acpicpu0: T6: I/O, lat   1 us, pow     0 mW,  28 %
acpicpu0: T7: I/O, lat   1 us, pow     0 mW,  16 %
acpicpu1 at cpu1: ACPI CPU
timecounter: Timecounter "clockinterrupt" frequency 100 Hz quality 0
timecounter: Timecounter "TSC" frequency 1600024320 Hz quality 3000
uhub0 at usb0: vendor 0x1002 OHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 5 ports with 5 removable, self powered
uhub1 at usb1: vendor 0x1002 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub1: 5 ports with 5 removable, self powered
uhub2 at usb2: vendor 0x1002 OHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 5 ports with 5 removable, self powered
uhub3 at usb3: vendor 0x1002 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub3: 5 ports with 5 removable, self powered
uhub4 at usb4: vendor 0x1002 OHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub4: 2 ports with 2 removable, self powered
uhub5 at usb5: vendor 0x1002 OHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub5: 4 ports with 4 removable, self powered
uhub6 at usb6: vendor 0x1002 EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
uhub6: 4 ports with 4 removable, self powered
ahcisata0 port 0: device present, speed: 3.0Gb/s
ehci2: handing over full speed device on port 3 to ohci3
wd0 at atabus0 drive 0
wd0: <KINGSTON SNV425S264GB>
wd0: drive supports 16-sector PIO transfers, LBA48 addressing
wd0: 61057 MB, 124053 cyl, 16 head, 63 sec, 512 bytes/sect x 125045424
sectors
wd0: drive supports PIO mode 4, DMA mode 2, Ultra-DMA mode 5 (Ultra/100)
wd0(ahcisata0:0:0): using PIO mode 4, DMA mode 2, Ultra-DMA mode 5
(Ultra/100) (using DMA)
umass0 at uhub1 port 3 configuration 1 interface 0
umass0: JMicron USB to ATA/ATAPI bridge, rev 2.00/1.00, addr 2
umass0: using SCSI over Bulk-Only
scsibus0 at umass0: 2 targets, 1 lun per target
sd0 at scsibus0 target 0 lun 0: <TOSHIBA, MK6459GSX, > disk fixed
sd0: 596 GB, 16383 cyl, 16 head, 63 sec, 512 bytes/sect x 1250263728 sectors
aubtfwl0 at uhub5 port 3
uhub7 at uhub1 port 5: vendor 0x0409 product 0x005a, class 9/0, rev
2.00/1.00, addr 3
uhub7: single transaction translator
uhub7: 4 ports with 4 removable, self powered
uhidev0 at uhub7 port 4 configuration 1 interface 0
uhidev0: Logitech USB Keyboard, rev 1.10/79.00, addr 4, iclass 3/1
ukbd0 at uhidev0: 8 modifier keys, 6 key codes
wskbd0 at ukbd0: console keyboard, using wsdisplay0
uhidev1 at uhub7 port 4 configuration 1 interface 1
uhidev1: Logitech USB Keyboard, rev 1.10/79.00, addr 4, iclass 3/0
uhidev1: 5 report ids
uhid0 at uhidev1 reportid 3: input=2, output=0, feature=0
uhid1 at uhidev1 reportid 4: input=1, output=0, feature=0
uhid2 at uhidev1 reportid 5: input=0, output=0, feature=5
uhidev2 at uhub7 port 4 configuration 1 interface 2
uhidev2: Logitech USB Keyboard, rev 1.10/79.00, addr 4, iclass 3/1
ums0 at uhidev2: 5 buttons and Z dir
wsmouse0 at ums0 mux 0
Kernelized RAIDframe activated
boot device: wd0
root on wd0a dumps on wd0b
/: replaying log to memory
root file system type: ffs
aubtfwl0: ath3k-1.fw open fail 2
/: replaying log to disk
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)


-- 
Matteo Beccati

Development & Consulting - http://www.beccati.com/