The issue mentioned in the title seems like it’s been around for a while, i.e. I have seen posts
talking about this problem since 2017. There are solution that involve setting up the options
in: /etc/modules-load.d/usb-storage.conf
The options I set where as follows: options usb-storage quirks=174c:55aa:t
Has anyone experienced this problem and how did you solve it?
In my case I am trying to use a Sabrent Model: EC-DFFN to “dock” a SATA WD 22TB drive and use it. I want to use it for a large removable back up media. These resets seem to occur every 1/2 second or so, while I am trying to run a large backup tar job. This is causing the job to stop and partially crash my server. BUZ KILL…
This is an example of the dmesg messages showing reset SupeSpeed USB
[14431.566853] usb 4-2: reset SuperSpeed USB device number 6 using xhci_hcd
[14431.580315] scsi host8: uas_eh_device_reset_handler success
[14463.125578] sd 8:0:0:0: [sde] tag#21 uas_eh_abort_handler 0 uas-tag 22 inflight: CMD OUT
[14463.125585] sd 8:0:0:0: [sde] tag#21 CDB: Write(16) 8a 00 00 00 00 00 00 a8 c4 00 00 00 04 00 00 00
[14463.125676] sd 8:0:0:0: [sde] tag#20 uas_eh_abort_handler 0 uas-tag 21 inflight: CMD OUT
[14463.125679] sd 8:0:0:0: [sde] tag#20 CDB: Write(16) 8a 00 00 00 00 00 00 a8 c0 00 00 00 04 00 00 00
[14463.134185] sd 8:0:0:0: [sde] tag#19 uas_eh_abort_handler 0 uas-tag 20 inflight: CMD OUT
[14463.134191] sd 8:0:0:0: [sde] tag#19 CDB: Write(16) 8a 00 00 00 00 00 00 a8 bc 00 00 00 04 00 00 00
[14463.142614] sd 8:0:0:0: [sde] tag#18 uas_eh_abort_handler 0 uas-tag 19 inflight: CMD OUT
[14463.142621] sd 8:0:0:0: [sde] tag#18 CDB: Write(16) 8a 00 00 00 00 00 00 a8 b8 00 00 00 04 00 00 00
[14463.150991] sd 8:0:0:0: [sde] tag#17 uas_eh_abort_handler 0 uas-tag 18 inflight: CMD OUT
[14463.150997] sd 8:0:0:0: [sde] tag#17 CDB: Write(16) 8a 00 00 00 00 00 00 a8 b4 00 00 00 04 00 00 00
[14463.159366] sd 8:0:0:0: [sde] tag#16 uas_eh_abort_handler 0 uas-tag 17 inflight: CMD OUT
[14463.159373] sd 8:0:0:0: [sde] tag#16 CDB: Write(16) 8a 00 00 00 00 00 00 a8 b0 00 00 00 04 00 00 00
[14463.167742] sd 8:0:0:0: [sde] tag#15 uas_eh_abort_handler 0 uas-tag 16 inflight: CMD OUT
[14463.167749] sd 8:0:0:0: [sde] tag#15 CDB: Write(16) 8a 00 00 00 00 00 00 a8 ac 00 00 00 04 00 00 00
[14463.176177] sd 8:0:0:0: [sde] tag#14 uas_eh_abort_handler 0 uas-tag 15 inflight: CMD OUT
[14463.176183] sd 8:0:0:0: [sde] tag#14 CDB: Write(16) 8a 00 00 00 00 00 00 a8 a8 00 00 00 04 00 00 00
[14463.184610] sd 8:0:0:0: [sde] tag#13 uas_eh_abort_handler 0 uas-tag 6 inflight: CMD OUT
[14463.184617] sd 8:0:0:0: [sde] tag#13 CDB: Write(16) 8a 00 00 00 00 00 00 a8 a4 00 00 00 04 00 00 00
[14463.192973] sd 8:0:0:0: [sde] tag#12 uas_eh_abort_handler 0 uas-tag 5 inflight: CMD OUT
[14463.192980] sd 8:0:0:0: [sde] tag#12 CDB: Write(16) 8a 00 00 00 00 01 55 c0 09 00 00 00 04 00 00 00
[14463.201333] sd 8:0:0:0: [sde] tag#11 uas_eh_abort_handler 0 uas-tag 14 inflight: CMD OUT
[14463.201339] sd 8:0:0:0: [sde] tag#11 CDB: Write(16) 8a 00 00 00 00 00 00 a8 90 00 00 00 04 00 00 00
[14463.209540] sd 8:0:0:0: [sde] tag#10 uas_eh_abort_handler 0 uas-tag 13 inflight: CMD OUT
[14463.209547] sd 8:0:0:0: [sde] tag#10 CDB: Write(16) 8a 00 00 00 00 00 00 a8 8c 00 00 00 04 00 00 00
[14463.217647] sd 8:0:0:0: [sde] tag#9 uas_eh_abort_handler 0 uas-tag 12 inflight: CMD OUT
[14463.217653] sd 8:0:0:0: [sde] tag#9 CDB: Write(16) 8a 00 00 00 00 00 00 a8 88 00 00 00 04 00 00 00
[14463.226182] sd 8:0:0:0: [sde] tag#8 uas_eh_abort_handler 0 uas-tag 11 inflight: CMD OUT
[14463.226188] sd 8:0:0:0: [sde] tag#8 CDB: Write(16) 8a 00 00 00 00 00 00 a8 84 00 00 00 04 00 00 00
[14463.234612] sd 8:0:0:0: [sde] tag#3 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD OUT
[14463.234618] sd 8:0:0:0: [sde] tag#3 CDB: Write(16) 8a 00 00 00 00 00 00 a8 a0 00 00 00 04 00 00 00
[14463.242990] sd 8:0:0:0: [sde] tag#2 uas_eh_abort_handler 0 uas-tag 3 inflight: CMD OUT
[14463.242997] sd 8:0:0:0: [sde] tag#2 CDB: Write(16) 8a 00 00 00 00 00 00 a8 9c 00 00 00 04 00 00 00
[14463.251370] sd 8:0:0:0: [sde] tag#1 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD OUT
[14463.251377] sd 8:0:0:0: [sde] tag#1 CDB: Write(16) 8a 00 00 00 00 00 00 a8 98 00 00 00 04 00 00 00
[14463.259468] sd 8:0:0:0: [sde] tag#0 uas_eh_abort_handler 0 uas-tag 1 inflight: CMD OUT
[14463.259475] sd 8:0:0:0: [sde] tag#0 CDB: Write(16) 8a 00 00 00 00 00 00 a8 94 00 00 00 04 00 00 00
Eventually after a some time the com with the drive just stops ending as follows:
[14614.787390] usb 4-2: reset SuperSpeed USB device number 6 using xhci_hcd
[14614.801012] scsi host8: uas_eh_device_reset_handler success
[14614.801182] sd 8:0:0:0: [sde] tag#0 timing out command, waited 180s
[14614.807428] sd 8:0:0:0: [sde] tag#0 FAILED Result: hostbyte=DID_RESET driverbyte=DRIVER_OK cmd_age=181s
[14614.807430] sd 8:0:0:0: [sde] tag#0 Sense Key : Not Ready [current]
[14614.807432] sd 8:0:0:0: [sde] tag#0 Add. Sense: Logical unit is in process of becoming ready
[14614.807434] sd 8:0:0:0: [sde] tag#0 CDB: Write(16) 8a 00 00 00 00 00 00 a8 a0 00 00 00 04 00 00 00
[14614.807435] I/O error, dev sde, sector 11051008 op 0x1:(WRITE) flags 0x4000 phys_seg 128 prio class 2
[14614.816621] sd 8:0:0:0: [sde] tag#1 timing out command, waited 180s
[14614.822859] sd 8:0:0:0: [sde] tag#1 FAILED Result: hostbyte=DID_RESET driverbyte=DRIVER_OK cmd_age=181s
[14614.822861] sd 8:0:0:0: [sde] tag#1 CDB: Write(16) 8a 00 00 00 00 00 00 a8 9c 00 00 00 04 00 00 00
[14614.822862] I/O error, dev sde, sector 11049984 op 0x1:(WRITE) flags 0x4000 phys_seg 128 prio class 2
[14614.832033] sd 8:0:0:0: [sde] tag#2 timing out command, waited 180s
[14614.838272] sd 8:0:0:0: [sde] tag#2 FAILED Result: hostbyte=DID_RESET driverbyte=DRIVER_OK cmd_age=181s
[14614.838274] sd 8:0:0:0: [sde] tag#2 CDB: Write(16) 8a 00 00 00 00 00 00 a8 98 00 00 00 04 00 00 00
[14614.838275] I/O error, dev sde, sector 11048960 op 0x1:(WRITE) flags 0x4000 phys_seg 128 prio class 2
[14614.847446] sd 8:0:0:0: [sde] tag#3 timing out command, waited 180s
[14614.853683] sd 8:0:0:0: [sde] tag#3 FAILED Result: hostbyte=DID_RESET driverbyte=DRIVER_OK cmd_age=181s
[14614.853685] sd 8:0:0:0: [sde] tag#3 CDB: Write(16) 8a 00 00 00 00 00 00 a8 94 00 00 00 04 00 00 00
[14614.853686] I/O error, dev sde, sector 11047936 op 0x1:(WRITE) flags 0x4000 phys_seg 128 prio class 2
[14616.868429] sd 8:0:0:0: [sde] tag#4 timing out command, waited 180s
Then as more time passed by the disk transaction will just die:
dmesg log reports
[14622.891399] I/O error, dev sde, sector 11057152 op 0x1:(WRITE) flags 0x4000 phys_seg 128 prio class 2
[14623.877484] INFO: task kworker/u64:0:3794 blocked for more than 122 seconds.
[14623.884507] Tainted: G S 6.5.5-1366.native #1
[14623.890745] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[14623.898533] task:kworker/u64:0 state:D stack:0 pid:3794 ppid:2 flags:0x00004000
[14623.898537] Workqueue: writeback wb_workfn (flush-8:64)
[14623.898541] Call Trace:
[14623.898543]
[14623.898545] __schedule+0x372/0xb00
[14623.898548] ? __blk_flush_plug+0xf6/0x140
[14623.898551] ? __pfx_wbt_cleanup_cb+0x40/0x40
[14623.898553] ? __pfx_wbt_inflight_cb+0x40/0x40
[14623.898555] schedule+0x57/0x100
[14623.898556] io_schedule+0x45/0x80
[14623.898558] rq_qos_wait+0xac/0x140
[14623.898560] ? __pfx_rq_qos_wake_function+0x40/0x40
[14623.898562] ? __pfx_wbt_inflight_cb+0x40/0x40
[14623.898564] wbt_wait+0x9f/0x180
[14623.898565] __rq_qos_throttle+0x27/0x80
[14623.898567] blk_mq_submit_bio+0x2cc/0x600
[14623.898569] ? ext4_fill_raw_inode+0x3be/0x540
[14623.898572] __submit_bio+0x19/0x140
[14623.898574] submit_bio_noacct_nocheck+0x26e/0x380
[14623.898576] submit_bio_noacct+0x169/0x5c0
[14623.898578] submit_bio+0x5a/0x80
[14623.898579] ext4_io_submit+0x23/0x80
[14623.898582] ext4_do_writepages+0x3bd/0xcc0
[14623.898584] ext4_writepages+0x90/0x180
[14623.898586] do_writepages+0xa4/0x180
[14623.898589] __writeback_single_inode+0x3b/0x300
[14623.898591] ? inode_io_list_move_locked+0x76/0x140
[14623.898593] writeback_sb_inodes+0x1d6/0x500
[14623.898595] ? srso_alias_return_thunk+0x5/0x7f
[14623.898597] ? wb_over_bg_thresh+0xf1/0x2c0
[14623.898599] ? asm_common_interrupt+0x2b/0x40
[14623.898601] __writeback_inodes_wb+0x4f/0x100
[14623.898603] wb_writeback+0x281/0x300
[14623.898605] wb_workfn+0x322/0x4c0
[14623.898607] ? srso_alias_return_thunk+0x5/0x7f
[14623.898609] ? __switch_to+0x132/0x500
[14623.898611] process_one_work+0x24f/0x440
[14623.898614] worker_thread+0x52/0x500
[14623.898615] ? __pfx_worker_thread+0x40/0x40
[14623.898617] kthread+0xd9/0x100
[14623.898619] ? __pfx_kthread+0x40/0x40
[14623.898621] ret_from_fork+0x46/0x80
[14623.898622] ? __pfx_kthread+0x40/0x40
[14623.898624] ret_from_fork_asm+0x1b/0x80
[14623.898627]
[14623.898635] INFO: task jbd2/sde1-8:5316 blocked for more than 122 seconds.
[14623.905481] Tainted: G S 6.5.5-1366.native #1
[14623.911718] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[14623.919514] task:jbd2/sde1-8 state:D stack:0 pid:5316 ppid:2 flags:0x00004000
[14623.919517] Call Trace:
[14623.919518]
[14623.919519] __schedule+0x372/0xb00
[14623.919521] schedule+0x57/0x100
[14623.919523] jbd2_journal_wait_updates+0x60/0xc0
[14623.919524] ? __pfx_autoremove_wake_function+0x40/0x40
[14623.919527] jbd2_journal_commit_transaction+0x1e9/0x1a80
[14623.919529] ? srso_alias_return_thunk+0x5/0x7f
[14623.919531] ? pick_next_task_fair+0x55/0x900
[14623.919533] ? pick_next_task_idle+0x2c/0x80
[14623.919535] ? srso_alias_return_thunk+0x5/0x7f
[14623.919537] ? srso_alias_return_thunk+0x5/0x7f
[14623.919538] ? __schedule+0x37a/0xb00
[14623.919540] kjournald2+0x97/0x240
[14623.919542] ? __pfx_autoremove_wake_function+0x40/0x40
[14623.919544] ? __pfx_kjournald2+0x40/0x40
[14623.919546] kthread+0xd9/0x100
[14623.919547] ? __pfx_kthread+0x40/0x40
[14623.919549] ret_from_fork+0x46/0x80
[14623.919551] ? __pfx_kthread+0x40/0x40
[14623.919552] ret_from_fork_asm+0x1b/0x80
[14623.919555]
[14623.919556] INFO: task xz:5451 blocked for more than 122 seconds.
[14623.925621] Tainted: G S 6.5.5-1366.native #1
[14623.931857] “echo 0 > /proc/sys/kernel/hung_task_timeout_secs” disables this message.
[14623.939650] task:xz state:D stack:0 pid:5451 ppid:5450 flags:0x00004002
[14623.939653] Call Trace:
[14623.939654]