[BUG] uas: regression preventing some drives from being used

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Hi,

I use two hard drives in an enclosure connected to my PC using UAS. The
enclosure is a JMicron JMS56x (152d:0562); the drives are a Fujitsu MHZ2160BH
G2 (2"5, 160GB) and a Western Digital EFRX-68N32N0 (3"5, 4TB).

Using a USB2 port, I can see both drives as expected. I used to be able to use
both drives using a USB3 port (and UAS) in Linux 4.11 and 4.12, but I am
experiencing a bug with Linux 4.14.13.

When I plug the USB cable, I can see the following output in dmesg:

[Tue Feb  6 22:17:49 2018] usb 4-2: new SuperSpeed USB device number 10 using xhci_hcd
[Tue Feb  6 22:17:49 2018] usb 4-2: New USB device found, idVendor=152d, idProduct=0562
[Tue Feb  6 22:17:49 2018] usb 4-2: New USB device strings: Mfr=1, Product=2, SerialNumber=5
[Tue Feb  6 22:17:49 2018] usb 4-2: Product: JMS56x Series
[Tue Feb  6 22:17:49 2018] usb 4-2: Manufacturer: JMicron
[Tue Feb  6 22:17:49 2018] usb 4-2: SerialNumber: RANDOM__8A4D7F833EEF
[Tue Feb  6 22:17:49 2018] scsi host6: uas
[Tue Feb  6 22:17:50 2018] scsi 6:0:0:0: Direct-Access     FUJITSU  MHZ2160BH G2     0104 PQ: 0 ANSI: 6
[Tue Feb  6 22:17:50 2018] scsi 6:0:0:1: Direct-Access     WDC WD40 EFRX-68N32N0     0104 PQ: 0 ANSI: 6
[Tue Feb  6 22:17:50 2018] sd 6:0:0:0: Attached scsi generic sg2 type 0
[Tue Feb  6 22:17:50 2018] sd 6:0:0:1: Attached scsi generic sg3 type 0
[Tue Feb  6 22:17:57 2018] sd 6:0:0:0: [sdb] 312581808 512-byte logical blocks: (160 GB/149 GiB)
[Tue Feb  6 22:17:57 2018] sd 6:0:0:1: [sdc] 7814037168 512-byte logical blocks: (4.00 TB/3.64 TiB)
[Tue Feb  6 22:17:57 2018] sd 6:0:0:0: [sdb] Write Protect is off
[Tue Feb  6 22:17:57 2018] sd 6:0:0:0: [sdb] Mode Sense: 67 00 10 08
[Tue Feb  6 22:17:57 2018] sd 6:0:0:1: [sdc] Write Protect is off
[Tue Feb  6 22:17:57 2018] sd 6:0:0:1: [sdc] Mode Sense: 67 00 10 08
[Tue Feb  6 22:17:57 2018] sd 6:0:0:0: [sdb] Write cache: enabled, read cache: enabled, supports DPO and FUA
[Tue Feb  6 22:17:57 2018] sd 6:0:0:1: [sdc] Write cache: enabled, read cache: enabled, supports DPO and FUA
[Tue Feb  6 22:17:57 2018]  sdb: sdb1
[Tue Feb  6 22:17:57 2018] sd 6:0:0:0: [sdb] Attached SCSI disk
[Tue Feb  6 22:18:27 2018] sd 6:0:0:1: tag#1 uas_eh_abort_handler 0 uas-tag 2 inflight: IN 
[Tue Feb  6 22:18:27 2018] sd 6:0:0:1: tag#1 CDB: Inquiry 12 01 00 00 40 00
[Tue Feb  6 22:18:27 2018] scsi host6: uas_eh_device_reset_handler start
[Tue Feb  6 22:18:28 2018] usb 4-2: reset SuperSpeed USB device number 10 using xhci_hcd
[Tue Feb  6 22:18:28 2018] usb 4-2: device firmware changed
[Tue Feb  6 22:18:28 2018] scsi host6: uas_post_reset: alloc streams error -19 after reset
[Tue Feb  6 22:18:28 2018] usb 4-2: USB disconnect, device number 10
[Tue Feb  6 22:20:29 2018] iwlwifi 0000:03:00.0: Radio type=0x0-0x3-0x1
[Tue Feb  6 22:20:29 2018] iwlwifi 0000:03:00.0: Radio type=0x0-0x3-0x1
[Tue Feb  6 22:20:29 2018] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[Tue Feb  6 22:20:30 2018] INFO: task kworker/0:3:191 blocked for more than 120 seconds.
[Tue Feb  6 22:20:30 2018]       Not tainted 4.14.0-3-amd64 #1 Debian 4.14.13-1
[Tue Feb  6 22:20:30 2018] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[Tue Feb  6 22:20:30 2018] kworker/0:3     D    0   191      2 0x80000000
[Tue Feb  6 22:20:30 2018] Workqueue: usb_hub_wq hub_event [usbcore]
[Tue Feb  6 22:20:30 2018] Call Trace:
[Tue Feb  6 22:20:30 2018]  ? __schedule+0x28e/0x880
[Tue Feb  6 22:20:30 2018]  schedule+0x28/0x80
[Tue Feb  6 22:20:30 2018]  schedule_preempt_disabled+0xa/0x10
[Tue Feb  6 22:20:30 2018]  __mutex_lock.isra.1+0x1a0/0x4e0
[Tue Feb  6 22:20:30 2018]  ? _dev_info+0x64/0x80
[Tue Feb  6 22:20:30 2018]  ? usb_disconnect+0x57/0x260 [usbcore]
[Tue Feb  6 22:20:30 2018]  usb_disconnect+0x57/0x260 [usbcore]
[Tue Feb  6 22:20:30 2018]  hub_event+0x52b/0x15d0 [usbcore]
[Tue Feb  6 22:20:30 2018]  process_one_work+0x185/0x380
[Tue Feb  6 22:20:30 2018]  worker_thread+0x2e/0x390
[Tue Feb  6 22:20:30 2018]  ? process_one_work+0x380/0x380
[Tue Feb  6 22:20:30 2018]  kthread+0x118/0x130
[Tue Feb  6 22:20:30 2018]  ? kthread_create_on_node+0x70/0x70
[Tue Feb  6 22:20:30 2018]  ret_from_fork+0x1f/0x30
[Tue Feb  6 22:20:30 2018] INFO: task scsi_eh_6:9856 blocked for more than 120 seconds.
[Tue Feb  6 22:20:30 2018]       Not tainted 4.14.0-3-amd64 #1 Debian 4.14.13-1
[Tue Feb  6 22:20:30 2018] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[Tue Feb  6 22:20:30 2018] scsi_eh_6       D    0  9856      2 0x80000000
[Tue Feb  6 22:20:30 2018] Call Trace:
[Tue Feb  6 22:20:30 2018]  ? __schedule+0x28e/0x880
[Tue Feb  6 22:20:30 2018]  schedule+0x28/0x80
[Tue Feb  6 22:20:30 2018]  async_synchronize_cookie_domain+0x96/0x140
[Tue Feb  6 22:20:30 2018]  ? finish_wait+0x80/0x80
[Tue Feb  6 22:20:30 2018]  sd_remove+0x48/0xc0 [sd_mod]
[Tue Feb  6 22:20:30 2018]  device_release_driver_internal+0x157/0x210
[Tue Feb  6 22:20:30 2018]  bus_remove_device+0xe2/0x150
[Tue Feb  6 22:20:30 2018]  device_del+0x1c9/0x340
[Tue Feb  6 22:20:30 2018]  ? attribute_container_device_trigger+0x75/0xd0
[Tue Feb  6 22:20:30 2018]  __scsi_remove_device+0xf0/0x120 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  scsi_forget_host+0x20/0x60 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  scsi_remove_host+0x72/0x100 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  uas_disconnect+0x80/0xa0 [uas]
[Tue Feb  6 22:20:30 2018]  usb_unbind_interface+0x71/0x290 [usbcore]
[Tue Feb  6 22:20:30 2018]  device_release_driver_internal+0x157/0x210
[Tue Feb  6 22:20:30 2018]  usb_forced_unbind_intf+0x25/0x50 [usbcore]
[Tue Feb  6 22:20:30 2018]  unbind_marked_interfaces.isra.13+0x47/0x60 [usbcore]
[Tue Feb  6 22:20:30 2018]  usb_unbind_and_rebind_marked_interfaces+0x15/0x30 [usbcore]
[Tue Feb  6 22:20:30 2018]  usb_reset_device+0x198/0x270 [usbcore]
[Tue Feb  6 22:20:30 2018]  ? scsi_eh_get_sense+0x240/0x240 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  uas_eh_device_reset_handler+0xac/0x180 [uas]
[Tue Feb  6 22:20:30 2018]  ? scsi_try_target_reset+0x90/0x90 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  scsi_eh_ready_devs+0x353/0xe20 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  ? __pm_runtime_resume+0x54/0x70
[Tue Feb  6 22:20:30 2018]  ? scsi_try_target_reset+0x90/0x90 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  ? scsi_eh_get_sense+0x240/0x240 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  scsi_error_handler+0x4c6/0x5b0 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  ? scsi_eh_get_sense+0x240/0x240 [scsi_mod]
[Tue Feb  6 22:20:30 2018]  kthread+0x118/0x130
[Tue Feb  6 22:20:30 2018]  ? kthread_create_on_node+0x70/0x70
[Tue Feb  6 22:20:30 2018]  ret_from_fork+0x1f/0x30

Both disks seem to be detected at some point, but I cannot list them using
"fdisk -l". 

I cannot reproduce this issue with a Western Digital WD10JPVT-00A1YT0 (1TB)
drive and a JMicron JMS566 (357d:7788) enclosure: everything works as expected.

My configs for the 4.12 and 4.14 kernels are available at
https://perso.aquilenet.fr/~steap/linux/ .

Regards,
Cyril Roelandt.
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html



[Index of Archives]     [Linux Media]     [Linux Input]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]     [Old Linux USB Devel Archive]

  Powered by Linux