On Sun, 13 Nov 2005 22:58:16 -0500 (EST) Alan Stern wrote: > On Sun, 13 Nov 2005, David Relson wrote: > > > Greetings, > > > > I'm running a 2.6.14 kernel and am getting error messages pointing at > > my USB hard drive. What's the recommended way for dealing with them? > > It would help if you could say what you were doing at the time. > > To get more useful debugging information, you can turn on the USB verbose > debugging and USB Mass Storage verbose debugging options in the kernel > configuration (CONFIG_USB_DEBUG and CONFIG_USB_STORAGE_DEBUG). Make sure > your syslog daemon is configured to capture debug-level messages from the > kernel -- a good way to do that is by putting a line like > > kern.* /var/log/kernel.log > > in /etc/syslog.conf. > > Alan Stern
Hi Alan, You're right, I didn't include much information. I have CONFIG_USB_DEBUG enabled and CONFIG_USB_STORAGE_DEBUG disabled. Attached are the boot time messages for the USB hard drive. I'm using BackupPC to backup my workstation (80GB, 70% full) to the USB drive. The problem doesn't appear immediately. It may be several hours into the backup process. At one time I tried building with CONFIG_USG_STORAGE_DEBUG=y and it seemed that _every_ I/O operation got logged, which presented a problem of too much data to store. As mentioned in my post a few days ago, on the occasions I've been running overnight backups, the next morning my workstation is either hung or has rebooted. If the backup isn't running the machine is fine in the morning. FWIW, the workstation has 1G RAM and (recently) I added a 1G swap file to supplement the 1G swap partition. With the 80GB HD and its 1M files, BackupPC uses approx 1G RAM. I hope this helps explain the situation. I much like the idea of backing up to a USB HD and am willing to do what I can to help determine what's going on. Regards, David
Nov 13 17:31:57 osage kernel: usbcore: registered new driver usbfs Nov 13 17:31:57 osage kernel: usbcore: registered new driver hub Nov 13 17:31:57 osage kernel: USB Universal Host Controller Interface driver v2.3 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: UHCI Host Controller Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: detected 2 ports Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: check_and_reset_hc: legsup = 0x2000 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: Performing full reset Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 1 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: irq 11, io base 0x0000e000 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: supports USB remote wakeup Nov 13 17:31:57 osage kernel: usb usb1: default language 0x0409 Nov 13 17:31:57 osage kernel: usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1 Nov 13 17:31:57 osage kernel: usb usb1: Product: UHCI Host Controller Nov 13 17:31:57 osage kernel: usb usb1: Manufacturer: Linux 2.6.14.6 uhci_hcd Nov 13 17:31:57 osage kernel: usb usb1: SerialNumber: 0000:00:10.0 Nov 13 17:31:57 osage kernel: usb usb1: hotplug Nov 13 17:31:57 osage kernel: usb usb1: adding 1-0:1.0 (config #1, interface 0) Nov 13 17:31:57 osage kernel: usb 1-0:1.0: hotplug Nov 13 17:31:57 osage kernel: hub 1-0:1.0: usb_probe_interface Nov 13 17:31:57 osage kernel: hub 1-0:1.0: usb_probe_interface - got id Nov 13 17:31:57 osage kernel: hub 1-0:1.0: USB hub found Nov 13 17:31:57 osage kernel: hub 1-0:1.0: 2 ports detected Nov 13 17:31:57 osage kernel: hub 1-0:1.0: standalone hub Nov 13 17:31:57 osage kernel: hub 1-0:1.0: no power switching (usb 1.0) Nov 13 17:31:57 osage kernel: hub 1-0:1.0: individual port over-current protection Nov 13 17:31:57 osage kernel: hub 1-0:1.0: power on to power good time: 2ms Nov 13 17:31:57 osage kernel: hub 1-0:1.0: local power source is good Nov 13 17:31:57 osage kernel: hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0000 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: port 1 portsc 018a,00 Nov 13 17:31:57 osage kernel: hub 1-0:1.0: port 1, status 0300, change 0003, 1.5 Mb/s Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: UHCI Host Controller Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: detected 2 ports Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: check_and_reset_hc: legsup = 0x2000 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: Performing full reset Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: new USB bus registered, assigned bus number 2 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: irq 10, io base 0x0000e400 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: supports USB remote wakeup Nov 13 17:31:57 osage kernel: usb usb2: default language 0x0409 Nov 13 17:31:57 osage kernel: usb usb2: new device strings: Mfr=3, Product=2, SerialNumber=1 Nov 13 17:31:57 osage kernel: usb usb2: Product: UHCI Host Controller Nov 13 17:31:57 osage kernel: usb usb2: Manufacturer: Linux 2.6.14.6 uhci_hcd Nov 13 17:31:57 osage kernel: usb usb2: SerialNumber: 0000:00:10.1 Nov 13 17:31:57 osage kernel: usb usb2: hotplug Nov 13 17:31:57 osage kernel: usb usb2: adding 2-0:1.0 (config #1, interface 0) Nov 13 17:31:57 osage kernel: usb 2-0:1.0: hotplug Nov 13 17:31:57 osage kernel: hub 2-0:1.0: usb_probe_interface Nov 13 17:31:57 osage kernel: hub 2-0:1.0: usb_probe_interface - got id Nov 13 17:31:57 osage kernel: hub 2-0:1.0: USB hub found Nov 13 17:31:57 osage kernel: hub 2-0:1.0: 2 ports detected Nov 13 17:31:57 osage kernel: hub 2-0:1.0: standalone hub Nov 13 17:31:57 osage kernel: hub 2-0:1.0: no power switching (usb 1.0) Nov 13 17:31:57 osage kernel: hub 2-0:1.0: individual port over-current protection Nov 13 17:31:57 osage kernel: hub 2-0:1.0: power on to power good time: 2ms Nov 13 17:31:57 osage kernel: hub 2-0:1.0: local power source is good Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: UHCI Host Controller Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: detected 2 ports Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: check_and_reset_hc: legsup = 0x2000 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: Performing full reset Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: new USB bus registered, assigned bus number 3 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: irq 10, io base 0x0000e800 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: supports USB remote wakeup Nov 13 17:31:57 osage kernel: usb usb3: default language 0x0409 Nov 13 17:31:57 osage kernel: usb usb3: new device strings: Mfr=3, Product=2, SerialNumber=1 Nov 13 17:31:57 osage kernel: usb usb3: Product: UHCI Host Controller Nov 13 17:31:57 osage kernel: usb usb3: Manufacturer: Linux 2.6.14.6 uhci_hcd Nov 13 17:31:57 osage kernel: usb usb3: SerialNumber: 0000:00:10.2 Nov 13 17:31:57 osage kernel: usb usb3: hotplug Nov 13 17:31:57 osage kernel: usb usb3: adding 3-0:1.0 (config #1, interface 0) Nov 13 17:31:57 osage kernel: usb 3-0:1.0: hotplug Nov 13 17:31:57 osage kernel: hub 3-0:1.0: usb_probe_interface Nov 13 17:31:57 osage kernel: hub 3-0:1.0: usb_probe_interface - got id Nov 13 17:31:57 osage kernel: hub 3-0:1.0: USB hub found Nov 13 17:31:57 osage kernel: hub 3-0:1.0: 2 ports detected Nov 13 17:31:57 osage kernel: hub 3-0:1.0: standalone hub Nov 13 17:31:57 osage kernel: hub 3-0:1.0: no power switching (usb 1.0) Nov 13 17:31:57 osage kernel: hub 3-0:1.0: individual port over-current protection Nov 13 17:31:57 osage kernel: hub 3-0:1.0: power on to power good time: 2ms Nov 13 17:31:57 osage kernel: hub 3-0:1.0: local power source is good Nov 13 17:31:57 osage kernel: hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x300 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: port 2 portsc 018a,00 Nov 13 17:31:57 osage kernel: hub 1-0:1.0: port 2, status 0300, change 0003, 1.5 Mb/s Nov 13 17:31:57 osage kernel: hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x300 Nov 13 17:31:57 osage kernel: hub 1-0:1.0: state 5 ports 2 chg 0000 evt 0000 Nov 13 17:31:57 osage kernel: hub 2-0:1.0: state 5 ports 2 chg 0000 evt 0006 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: port 1 portsc 008a,00 Nov 13 17:31:57 osage kernel: hub 2-0:1.0: port 1, status 0100, change 0003, 12 Mb/s Nov 13 17:31:57 osage kernel: ehci_hcd: block sizes: qh 128 qtd 96 itd 192 sitd 96 Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: EHCI Host Controller Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: reset hcs_params 0x3206 dbg=0 cc=3 pcc=2 ordered !ppc ports=6 Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: reset hcc_params 6872 thresh 7 uframes 256/512/1024 Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: capability 0001 at 68 Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: new USB bus registered, assigned bus number 4 Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: irq 5, io mem 0xdffffe00 Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: reset command 080002 (park)=0 ithresh=8 period=1024 Reset HALT Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: init command 010009 (park)=0 ithresh=1 period=256 RUN Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: USB 2.0 initialized, EHCI 1.00, driver 10 Dec 2004 Nov 13 17:31:57 osage kernel: ehci_hcd 0000:00:10.3: supports USB remote wakeup Nov 13 17:31:57 osage kernel: usb usb4: default language 0x0409 Nov 13 17:31:57 osage kernel: usb usb4: new device strings: Mfr=3, Product=2, SerialNumber=1 Nov 13 17:31:57 osage kernel: usb usb4: Product: EHCI Host Controller Nov 13 17:31:57 osage kernel: usb usb4: Manufacturer: Linux 2.6.14.6 ehci_hcd Nov 13 17:31:57 osage kernel: usb usb4: SerialNumber: 0000:00:10.3 Nov 13 17:31:57 osage kernel: usb usb4: hotplug Nov 13 17:31:57 osage kernel: usb usb4: adding 4-0:1.0 (config #1, interface 0) Nov 13 17:31:57 osage kernel: usb 4-0:1.0: hotplug Nov 13 17:31:57 osage kernel: hub 4-0:1.0: usb_probe_interface Nov 13 17:31:57 osage kernel: hub 4-0:1.0: usb_probe_interface - got id Nov 13 17:31:57 osage kernel: hub 4-0:1.0: USB hub found Nov 13 17:31:57 osage kernel: hub 4-0:1.0: 6 ports detected Nov 13 17:31:57 osage kernel: hub 4-0:1.0: standalone hub Nov 13 17:31:57 osage kernel: hub 4-0:1.0: no power switching (usb 1.0) Nov 13 17:31:57 osage kernel: hub 4-0:1.0: individual port over-current protection Nov 13 17:31:57 osage kernel: hub 4-0:1.0: Single TT Nov 13 17:31:57 osage kernel: hub 4-0:1.0: TT requires at most 8 FS bit times (666 ns) Nov 13 17:31:57 osage kernel: hub 4-0:1.0: power on to power good time: 20ms Nov 13 17:31:57 osage kernel: hub 4-0:1.0: local power source is good Nov 13 17:31:57 osage kernel: hub 2-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x100 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: port 2 portsc 008a,00 Nov 13 17:31:57 osage kernel: hub 2-0:1.0: port 2, status 0100, change 0003, 12 Mb/s Nov 13 17:31:57 osage kernel: hub 2-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100 Nov 13 17:31:57 osage kernel: hub 3-0:1.0: state 5 ports 2 chg 0000 evt 0006 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: port 1 portsc 008a,00 Nov 13 17:31:57 osage kernel: hub 3-0:1.0: port 1, status 0100, change 0003, 12 Mb/s Nov 13 17:31:57 osage kernel: hub 3-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x100 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: port 2 portsc 008a,00 Nov 13 17:31:57 osage kernel: hub 3-0:1.0: port 2, status 0100, change 0003, 12 Mb/s Nov 13 17:31:57 osage kernel: hub 3-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100 Nov 13 17:31:57 osage kernel: hub 2-0:1.0: state 5 ports 2 chg 0000 evt 0000 Nov 13 17:31:57 osage kernel: hub 4-0:1.0: state 5 ports 6 chg 0000 evt 0000 Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.0: suspend_rh (auto-stop) Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.1: suspend_rh (auto-stop) Nov 13 17:31:57 osage kernel: uhci_hcd 0000:00:10.2: suspend_rh (auto-stop) Nov 13 17:31:57 osage kernel: EXT3 FS on hda1, internal journal