Hide Forgot
Created attachment 549895 [details] Full system log Description of problem: While using a DVD burner/printer robot, the Linux kernel reports that a USB hub goes offline. The device presents itself as a printer and two DVD writers. It has an internal hub. The error message is as follows: Dec 29 12:05:02 callisto kernel: [ 3018.235739] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:03 callisto kernel: [ 3019.245081] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:04 callisto kernel: [ 3020.254432] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:05 callisto kernel: [ 3021.263781] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:06 callisto kernel: [ 3022.273132] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:06 callisto kernel: [ 3022.273135] hub 1-1.2:1.0: Cannot enable port 3. Maybe the USB cable is bad? Dec 29 12:05:07 callisto kernel: [ 3023.282482] hub 1-1.2:1.0: cannot disable port 3 (err = -110) Dec 29 12:05:08 callisto kernel: [ 3024.291831] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:09 callisto kernel: [ 3025.301182] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:10 callisto kernel: [ 3026.310529] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:11 callisto kernel: [ 3027.319882] hub 1-1.2:1.0: cannot reset port 3 (err = -110) Dec 29 12:05:12 callisto kernel: [ 3028.329232] hub 1-1.2:1.0: cannot reset port 3 (err = -110) *repeats a few times* Eventually the device comes back: Dec 29 12:05:27 callisto kernel: [ 3042.460157] sr 10:0:0:0: Device offlined - not ready after error recovery Dec 29 12:05:28 callisto kernel: [ 3043.469481] hub 1-1.2:1.0: cannot reset port 4 (err = -110) Dec 29 12:05:29 callisto kernel: [ 3044.478837] hub 1-1.2:1.0: cannot reset port 4 (err = -110) Dec 29 12:05:29 callisto kernel: [ 3044.501073] usb 1-1.2.3: USB disconnect, device number 5 Dec 29 12:05:29 callisto kernel: [ 3044.501623] cdrom: issuing MRW background format suspend Dec 29 12:05:30 callisto kernel: [ 3045.756011] usb 1-1.2.4: reset high speed USB device number 6 using ehci_hcd Dec 29 12:05:36 callisto kernel: [ 3051.704339] usb 1-1.2.3: new high speed USB device number 8 using ehci_hcd Dec 29 12:05:40 callisto kernel: [ 3056.113243] usb 1-1.2.3: New USB device found, idVendor=0dbf, idProduct=0700 Dec 29 12:05:40 callisto kernel: [ 3056.113248] usb 1-1.2.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Dec 29 12:05:40 callisto kernel: [ 3056.113251] usb 1-1.2.3: Product: USB to SATA Bridge Dec 29 12:05:40 callisto kernel: [ 3056.113253] usb 1-1.2.3: Manufacturer: TSI Dec 29 12:05:40 callisto kernel: [ 3056.113255] usb 1-1.2.3: SerialNumber: TSI100506000002e Dec 29 12:05:42 callisto kernel: [ 3058.088823] scsi12 : usb-storage 1-1.2.3:1.0 Dec 29 12:05:42 callisto mtp-probe: checking bus 1, device 8: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2.3" Dec 29 12:05:42 callisto mtp-probe: bus: 1, device: 8 was not an MTP device Dec 29 12:05:43 callisto kernel: [ 3059.137791] scsi 12:0:0:0: CD-ROM PIONEER DVD-RW DVR-218L 9.00 PQ: 0 ANSI: 0 Dec 29 12:05:43 callisto kernel: [ 3059.184618] sr1: scsi3-mmc drive: 40x/40x writer dvd-ram cd/rw xa/form2 cdda tray Dec 29 12:05:43 callisto kernel: [ 3059.185286] sr 12:0:0:0: Attached scsi generic sg7 type 5 Dec 29 12:16:30 callisto kernel: [ 3705.545413] sr2: CDROM not ready. Make sure there is a disc in the drive. Dec 29 12:17:16 callisto kernel: [ 3751.678134] cdrom: This disc doesn't have any tracks I recognize! Dec 29 12:17:17 callisto kernel: [ 3752.557619] cdrom: This disc doesn't have any tracks I recognize! Dec 29 12:28:36 callisto kernel: [ 4430.550413] UDF-fs: Partition marked readonly; forcing readonly mount Dec 29 12:28:36 callisto kernel: [ 4430.566577] UDF-fs INFO UDF: Mounting volume 'CDROM', timestamp 2011/12/29 01:15 (1000) Dec 29 12:37:49 callisto kernel: [ 4983.615533] sr2: CDROM not ready. Make sure there is a disc in the drive. Dec 29 12:38:35 callisto kernel: [ 5029.532967] cdrom: This disc doesn't have any tracks I recognize! Dec 29 12:38:36 callisto kernel: [ 5030.286472] cdrom: This disc doesn't have any tracks I recognize! Dec 29 12:50:05 callisto kernel: [ 5718.810590] UDF-fs: Partition marked readonly; forcing readonly mount Dec 29 12:50:05 callisto kernel: [ 5718.830536] UDF-fs INFO UDF: Mounting volume 'CDROM', timestamp 2011/12/29 01:15 (1000) Dec 29 13:00:21 callisto kernel: [ 6335.077293] hub 1-1.2:1.0: cannot reset port 4 (err = -110) Dec 29 13:00:22 callisto kernel: [ 6336.086644] hub 1-1.2:1.0: cannot reset port 4 (err = -110) Dec 29 13:00:23 callisto kernel: [ 6337.095992] hub 1-1.2:1.0: cannot reset port 4 (err = -110) Dec 29 13:00:24 callisto kernel: [ 6338.105337] hub 1-1.2:1.0: cannot reset port 4 (err = -110) Dec 29 13:00:25 callisto kernel: [ 6339.114692] hub 1-1.2:1.0: cannot reset port 4 (err = -110) Dec 29 13:00:25 callisto kernel: [ 6339.114695] hub 1-1.2:1.0: Cannot enable port 4. Maybe the USB cable is bad? Dec 29 13:00:26 callisto kernel: [ 6340.124045] hub 1-1.2:1.0: cannot disable port 4 (err = -110) Dec 29 13:00:54 callisto kernel: [ 6367.941392] usb 1-1.2.4: USB disconnect, device number 6 Dec 29 13:00:54 callisto kernel: [ 6368.010312] usb 1-1.2.3: reset high speed USB device number 8 using ehci_hcd Dec 29 13:01:01 callisto kernel: [ 6374.513737] usb 1-1.2.4: new high speed USB device number 9 using ehci_hcd Dec 29 13:01:01 callisto kernel: [ 6374.694595] usb 1-1.2.4: New USB device found, idVendor=0dbf, idProduct=0700 Dec 29 13:01:01 callisto kernel: [ 6374.694600] usb 1-1.2.4: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Dec 29 13:01:01 callisto kernel: [ 6374.694604] usb 1-1.2.4: Product: USB to Serial-ATA bridge Dec 29 13:01:01 callisto kernel: [ 6374.694606] usb 1-1.2.4: Manufacturer: Sunplus Technology Co.,Ltd. Dec 29 13:01:01 callisto kernel: [ 6374.694609] usb 1-1.2.4: SerialNumber: FFFFEEF0DFFBFC034346FF Dec 29 13:01:01 callisto kernel: [ 6374.699940] scsi13 : usb-storage 1-1.2.4:1.0 Dec 29 13:01:01 callisto mtp-probe: checking bus 1, device 9: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2.4" Dec 29 13:01:01 callisto mtp-probe: bus: 1, device: 9 was not an MTP device When the device went offline, I unplugged the device and the /dev/sr* devices came back. The lp device becomes the next device number ie /dev/lp1. Even though the devices are working again, the following errors keep filling up my syslog forever (until reboot): Dec 29 13:36:46 callisto kernel: [ 8518.195610] hub 1-1.2:1.0: hub_port_status failed (err = -110) Dec 29 13:36:47 callisto kernel: [ 8519.724648] sr 12:0:0:0: rejecting I/O to offline device Dec 29 13:36:48 callisto kernel: [ 8520.236270] sr 13:0:0:0: rejecting I/O to offline device Dec 29 13:36:49 callisto kernel: [ 8521.771297] sr 12:0:0:0: rejecting I/O to offline device Dec 29 13:36:50 callisto kernel: [ 8522.282985] sr 13:0:0:0: rejecting I/O to offline device As this device is a DVD robot capable of burning 50 discs at a time, it's rendered practically useless by it going offline all the time (the device node changes). This device previously worked flawlessly with F14 and F15. It also works with RHEL5 and RHEL6. Version-Release number of selected component (if applicable): [root@callisto mark]# uname -a Linux callisto.inet.mknowles.com.au 3.1.6-1.fc16.x86_64 #1 SMP Wed Dec 21 22:41:17 UTC 2011 x86_64 x86_64 x86_64 GNU/Linux How reproducible: Within the timeframe of burning/printing about 10 DVD discs with this device. Steps to Reproduce: 1. Ask DVD robot to burn 50 discs 2. Wait until the device is offlined 3. Start DVD burning script again with different lp device node or reboot Actual results: Hub, DVD-ROM and printer device nodes all disappear due to the USB hub disappearing. The lp device comes back as the next device node /dev/lp1. This disrupts any scripts that are using /dev/lp0. The kernel error messages repeat forever, filling up the syslog. Expected results: The USB devices should stay connected and not go offline. Also they should keep their device nodes. Additional info: I'm also experiencing failures with my internal SATA controller whereby drives become unusable. I've currently got my HDDs in an external eSATA controller as the internal ports do not seem to work properly. I've attached syslog messages that show the problem in case it's related. Attached: lspci, lsusb, lsmod, syslog messages from the SATA problem I'm having and a full unabbreviated syslog dump from around where the problem starts (tailed). Thanks and please let me know if you need any more information.
Created attachment 549896 [details] lsmod
Created attachment 549897 [details] lspci
Created attachment 549898 [details] lsusb
Created attachment 549899 [details] SATA problems from syslog
[mass update] kernel-3.3.0-4.fc16 has been pushed to the Fedora 16 stable repository. Please retest with this update.
I saw something like this with an external USB disk under 3.3.8-1.fc16 x86-64. Jun 27 14:36:47 xpc1 kernel: [793273.236333] hub 1-1:1.0: cannot reset port 2 (err = -110) Jun 27 14:36:48 xpc1 kernel: [793274.244434] hub 1-1:1.0: cannot reset port 2 (err = -110) Jun 27 14:36:49 xpc1 kernel: [793275.252440] hub 1-1:1.0: cannot reset port 2 (err = -110) Jun 27 14:36:50 xpc1 kernel: [793276.260552] hub 1-1:1.0: cannot reset port 2 (err = -110) Jun 27 14:36:51 xpc1 kernel: [793277.268635] hub 1-1:1.0: cannot reset port 2 (err = -110) Jun 27 14:36:51 xpc1 kernel: [793277.268638] hub 1-1:1.0: Cannot enable port 2. Maybe the USB cable is bad? Jun 27 14:36:52 xpc1 kernel: [793278.276647] hub 1-1:1.0: cannot disable port 2 (err = -110) Jun 27 14:36:53 xpc1 kernel: [793279.284682] hub 1-1:1.0: cannot reset port 2 (err = -110) ... Jun 27 14:37:11 xpc1 kernel: [793297.429958] sd 10:0:0:0: Device offlined - not ready after error recovery Jun 27 14:37:11 xpc1 kernel: [793297.429966] sd 10:0:0:0: [sdf] Unhandled error code Jun 27 14:37:11 xpc1 kernel: [793297.429968] sd 10:0:0:0: [sdf] Result: hostbyte=DID_ABORT driverbyte=DRIVER_OK Jun 27 14:37:11 xpc1 kernel: [793297.429971] sd 10:0:0:0: [sdf] CDB: Read(10): 28 00 1e 67 23 28 00 00 f0 00 00 00 Jun 27 14:37:11 xpc1 kernel: [793297.429977] end_request: I/O error, dev sdf, sector 510075688 Jun 27 14:37:11 xpc1 kernel: [793297.429989] sd 10:0:0:0: rejecting I/O to offline device Jun 27 14:37:11 xpc1 kernel: [793297.429992] sd 10:0:0:0: [sdf] killing request Jun 27 14:37:11 xpc1 kernel: [793297.429998] sd 10:0:0:0: rejecting I/O to offline device I tried replugging the device into the USB socket, but this caused all by USB devices (keyboard, mouse) to stop responding. The USB disk was a Icy Box IB-318StU3-B (which has the crazy USB ID 1234:5678). It is a USB3 device plugged into a USB2 socket. It was plugged directly into an Intel DP55WB motherboard.
# Mass update to all open bugs. Kernel 3.6.2-1.fc16 has just been pushed to updates. This update is a significant rebase from the previous version. Please retest with this kernel, and let us know if your problem has been fixed. In the event that you have upgraded to a newer release and the bug you reported is still present, please change the version field to the newest release you have encountered the issue with. Before doing so, please ensure you are testing the latest kernel update in that release and attach any new and relevant information you may have gathered. If you are not the original bug reporter and you still experience this bug, please file a new report, as it is possible that you may be seeing a different problem. (Please don't clone this bug, a fresh bug referencing this bug in the comment is sufficient).
With no response, we are closing this bug under the assumption that it is no longer an issue. If you still experience this bug, please feel free to reopen the bug report.