[Bug 1148317] New: Cannot mount external usb disk 125f:a94a because of "Invalid command operation code"
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317 Bug ID: 1148317 Summary: Cannot mount external usb disk 125f:a94a because of "Invalid command operation code" Classification: openSUSE Product: openSUSE Tumbleweed Version: Current Hardware: x86-64 OS: Other Status: NEW Severity: Normal Priority: P5 - None Component: Kernel Assignee: kernel-maintainers@forge.provo.novell.com Reporter: jzl@volny.cz QA Contact: qa-bugs@suse.de Found By: --- Blocker: --- User-Agent: Mozilla/5.0 (X11; Linux x86_64; rv:60.9) Goanna/4.3 NewMoon/28.6.1 Build Identifier: I have 2 similar external usb disks ADATA CH94 (125f:a94a). One of them works OK, other is attached with errors in new kernel and cannot be mounted. In older kernals both works OK. The disks differ in capacity and bcdDevice. My system is current openSUSE Tumbleweed jizbox:~ # uname -a Linux jizbox 5.2.9-1-default #1 SMP Fri Aug 16 20:25:11 UTC 2019 (80c0ffe) x86_64 x86_64 x86_64 GNU/Linux Excerptions from journal. Disk with attach errors: Aug 27 11:59:20 jizbox kernel: usb 2-2.2.2: new high-speed USB device number 12 using xhci_hcd Aug 27 11:59:20 jizbox kernel: usb 2-2.2.2: New USB device found, idVendor=125f, idProduct=a94a, bcdDevice= 1.60 Aug 27 11:59:20 jizbox kernel: usb 2-2.2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Aug 27 11:59:20 jizbox kernel: usb 2-2.2.2: Product: Portable HDD CH94 Aug 27 11:59:20 jizbox kernel: usb 2-2.2.2: Manufacturer: ADATA Aug 27 11:59:20 jizbox kernel: usb 2-2.2.2: SerialNumber: CH0150805213 Aug 27 11:59:20 jizbox kernel: usb-storage 2-2.2.2:1.0: USB Mass Storage device detected Aug 27 11:59:20 jizbox kernel: scsi host7: usb-storage 2-2.2.2:1.0 Aug 27 11:59:20 jizbox mtp-probe[3547]: checking bus 2, device 12: "/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2" Aug 27 11:59:20 jizbox mtp-probe[3547]: bus: 2, device: 12 was not an MTP device Aug 27 11:59:21 jizbox kdeinit5[2171]: UdevQt: unhandled device action "bind" Aug 27 11:59:21 jizbox org_kde_powerdevil[2301]: UdevQt: unhandled device action "bind" Aug 27 11:59:21 jizbox plasmashell[2255]: UdevQt: unhandled device action "bind" Aug 27 11:59:21 jizbox mtp-probe[3569]: checking bus 2, device 12: "/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2" Aug 27 11:59:21 jizbox mtp-probe[3569]: bus: 2, device: 12 was not an MTP device Aug 27 11:59:21 jizbox kdeinit5[2171]: UdevQt: unhandled device action "bind" Aug 27 11:59:21 jizbox org_kde_powerdevil[2301]: UdevQt: unhandled device action "bind" Aug 27 11:59:21 jizbox plasmashell[2255]: UdevQt: unhandled device action "bind" Aug 27 11:59:21 jizbox kernel: scsi 7:0:0:0: Direct-Access ADATA HDD CH94 PQ: 0 ANSI: 0 Aug 27 11:59:21 jizbox kernel: sd 7:0:0:0: Attached scsi generic sg3 type 0 Aug 27 11:59:21 jizbox kernel: sd 7:0:0:0: [sdd] 1250263728 512-byte logical blocks: (640 GB/596 GiB) Aug 27 11:59:21 jizbox kernel: sd 7:0:0:0: [sdd] Write Protect is off Aug 27 11:59:21 jizbox kernel: sd 7:0:0:0: [sdd] Mode Sense: 03 00 00 00 Aug 27 11:59:21 jizbox kernel: sd 7:0:0:0: [sdd] No Caching mode page found Aug 27 11:59:21 jizbox kernel: sd 7:0:0:0: [sdd] Assuming drive cache: write through Aug 27 11:59:21 jizbox kernel: sdd: sdd1 Aug 27 11:59:21 jizbox kernel: sd 7:0:0:0: [sdd] Attached SCSI disk Aug 27 11:59:45 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE Aug 27 11:59:45 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 Sense Key : Illegal Request [current] Aug 27 11:59:45 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 Add. Sense: Invalid command operation code Aug 27 11:59:45 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 CDB: Read(10) 28 00 4a 85 6e af 00 00 08 00 Aug 27 11:59:45 jizbox kernel: print_req_error: critical target error, dev sdd, sector 1250258607 flags 80700 Aug 27 11:59:50 jizbox kio_http_cache_cleaner[2416]: QIODevice::skip (QBuffer): WriteOnly device Aug 27 11:59:56 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE Aug 27 11:59:56 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 Sense Key : Illegal Request [current] Aug 27 11:59:56 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 Add. Sense: Invalid command operation code Aug 27 11:59:56 jizbox kernel: sd 7:0:0:0: [sdd] tag#0 CDB: Read(10) 28 00 4a 85 6e af 00 00 08 00 Aug 27 11:59:56 jizbox kernel: print_req_error: critical target error, dev sdd, sector 1250258607 flags 0 Aug 27 11:59:56 jizbox kernel: Buffer I/O error on dev sdd1, logical block 625129272, async page read Aug 27 11:59:56 jizbox kernel: Buffer I/O error on dev sdd1, logical block 625129273, async page read Aug 27 11:59:56 jizbox kernel: Buffer I/O error on dev sdd1, logical block 625129274, async page read Aug 27 11:59:56 jizbox kernel: Buffer I/O error on dev sdd1, logical block 625129275, async page read For comparision with disk which works OK: Aug 27 12:03:27 jizbox kernel: usb 2-2.2.2: new high-speed USB device number 13 using xhci_hcd Aug 27 12:03:27 jizbox kernel: usb 2-2.2.2: New USB device found, idVendor=125f, idProduct=a94a, bcdDevice= 2.00 Aug 27 12:03:27 jizbox kernel: usb 2-2.2.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Aug 27 12:03:27 jizbox kernel: usb 2-2.2.2: Product: Portable HDD CH94 Aug 27 12:03:27 jizbox kernel: usb 2-2.2.2: Manufacturer: ADATA Aug 27 12:03:27 jizbox kernel: usb 2-2.2.2: SerialNumber: 034000002187 Aug 27 12:03:27 jizbox kernel: usb-storage 2-2.2.2:1.0: USB Mass Storage device detected Aug 27 12:03:27 jizbox kernel: scsi host7: usb-storage 2-2.2.2:1.0 Aug 27 12:03:27 jizbox mtp-probe[3766]: checking bus 2, device 13: "/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2" Aug 27 12:03:27 jizbox mtp-probe[3766]: bus: 2, device: 13 was not an MTP device Aug 27 12:03:27 jizbox plasmashell[2255]: UdevQt: unhandled device action "bind" Aug 27 12:03:27 jizbox kdeinit5[2171]: UdevQt: unhandled device action "bind" Aug 27 12:03:27 jizbox org_kde_powerdevil[2301]: UdevQt: unhandled device action "bind" Aug 27 12:03:27 jizbox mtp-probe[3788]: checking bus 2, device 13: "/sys/devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2" Aug 27 12:03:27 jizbox mtp-probe[3788]: bus: 2, device: 13 was not an MTP device Aug 27 12:03:27 jizbox org_kde_powerdevil[2301]: UdevQt: unhandled device action "bind" Aug 27 12:03:27 jizbox plasmashell[2255]: UdevQt: unhandled device action "bind" Aug 27 12:03:27 jizbox kdeinit5[2171]: UdevQt: unhandled device action "bind" Aug 27 12:03:28 jizbox kernel: scsi 7:0:0:0: Direct-Access ADATA HDD CH94 PQ: 0 ANSI: 0 Aug 27 12:03:28 jizbox kernel: sd 7:0:0:0: Attached scsi generic sg3 type 0 Aug 27 12:03:28 jizbox kernel: sd 7:0:0:0: [sdd] 1953525167 512-byte logical blocks: (1.00 TB/932 GiB) Aug 27 12:03:28 jizbox kernel: sd 7:0:0:0: [sdd] Write Protect is off Aug 27 12:03:28 jizbox kernel: sd 7:0:0:0: [sdd] Mode Sense: 03 00 00 00 Aug 27 12:03:28 jizbox kernel: sd 7:0:0:0: [sdd] No Caching mode page found Aug 27 12:03:28 jizbox kernel: sd 7:0:0:0: [sdd] Assuming drive cache: write through Aug 27 12:03:28 jizbox kernel: sdd: sdd1 Aug 27 12:03:28 jizbox kernel: sd 7:0:0:0: [sdd] Attached SCSI disk Aug 27 12:03:28 jizbox udisksd[2186]: Mounted /dev/sdd1 at /run/media/jzl/videojzl on behalf of uid 1955 Aug 27 12:03:28 jizbox kernel: EXT4-fs (sdd1): mounted filesystem with ordered data mode. Opts: (null) And log excerption on another computer with openSUSE 15.2 in current state (4.12.14-lp151.28.13-default) for first disk which on tumbleweed shows errors: srp 27 12:08:50 jizhp440 kernel: usb 1-1: new high-speed USB device number 7 using xhci_hcd srp 27 12:08:50 jizhp440 kernel: usb 1-1: New USB device found, idVendor=125f, idProduct=a94a srp 27 12:08:50 jizhp440 kernel: usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 srp 27 12:08:50 jizhp440 kernel: usb 1-1: Product: Portable HDD CH94 srp 27 12:08:50 jizhp440 kernel: usb 1-1: Manufacturer: ADATA srp 27 12:08:50 jizhp440 kernel: usb 1-1: SerialNumber: CH0150805213 srp 27 12:08:50 jizhp440 kernel: usb-storage 1-1:1.0: USB Mass Storage device detected srp 27 12:08:50 jizhp440 kernel: scsi host3: usb-storage 1-1:1.0 srp 27 12:08:50 jizhp440 mtp-probe[4380]: checking bus 1, device 7: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-1" srp 27 12:08:50 jizhp440 mtp-probe[4380]: bus: 1, device: 7 was not an MTP device srp 27 12:08:51 jizhp440 kernel: scsi 3:0:0:0: Direct-Access ADATA HDD CH94 PQ: 0 ANSI: 0 srp 27 12:08:51 jizhp440 kernel: sd 3:0:0:0: [sdc] 1250263728 512-byte logical blocks: (640 GB/596 GiB) srp 27 12:08:51 jizhp440 kernel: sd 3:0:0:0: [sdc] Write Protect is off srp 27 12:08:51 jizhp440 kernel: sd 3:0:0:0: [sdc] Mode Sense: 03 00 00 00 srp 27 12:08:51 jizhp440 kernel: sd 3:0:0:0: [sdc] No Caching mode page found srp 27 12:08:51 jizhp440 kernel: sd 3:0:0:0: [sdc] Assuming drive cache: write through srp 27 12:08:51 jizhp440 kernel: sdc: sdc1 srp 27 12:08:51 jizhp440 kernel: sd 3:0:0:0: [sdc] Attached SCSI disk srp 27 12:09:21 jizhp440 ntfs-3g[4440]: Version 2016.2.22 external FUSE 29 srp 27 12:09:21 jizhp440 ntfs-3g[4440]: Mounted /dev/sdc1 (Read-Write, label "JzlAdata", NTFS 3.1) srp 27 12:09:21 jizhp440 ntfs-3g[4440]: Cmdline options: rw,nodev,nosuid,uid=1955,gid=100,uhelper=udisks2 srp 27 12:09:21 jizhp440 ntfs-3g[4440]: Mount options: rw,nodev,nosuid,uhelper=udisks2,allow_other,nonempty,relatime,default_permissions,fsname=/dev/sdc1,blkdev,blksize=4096 srp 27 12:09:21 jizhp440 ntfs-3g[4440]: Global ownership and permissions enforced, configuration type 7 srp 27 12:09:21 jizhp440 udisksd[1646]: Mounted /dev/sdc1 at /run/media/jzl/JzlAdata on behalf of uid 1955 usb-storage.quirks=125f:a94a:u didnt help. Reproducible: Always Steps to Reproduce: 1. Attach extarnal usb disk "idVendor=125f, idProduct=a94a, bcdDevice= 1.60" 2. Show logs for errors 3. Actual Results: Unaccessible disk Expected Results: Disk mounted -- You are receiving this mail because: You are on the CC list for the bug.
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317#c1
Takashi Iwai
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317#c2
--- Comment #2 from Jiří Zlonický
Which kernel did work in the past?
Im not shure. It is rarly used disk. Hope logs from other computer with 4.12.14-lp151.28.13-default will help. -- You are receiving this mail because: You are on the CC list for the bug.
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317#c3
--- Comment #3 from Takashi Iwai
(In reply to Takashi Iwai from comment #1)
Which kernel did work in the past?
Im not shure. It is rarly used disk. Hope logs from other computer with 4.12.14-lp151.28.13-default will help.
I thought you meant it worked in the previous Tumbleweed. Was it meant Leap 15.1? -- You are receiving this mail because: You are on the CC list for the bug.
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317#c4
--- Comment #4 from Jiří Zlonický
(In reply to Jiří Zlonický from comment #2)
(In reply to Takashi Iwai from comment #1)
Which kernel did work in the past?
Im not shure. It is rarly used disk. Hope logs from other computer with 4.12.14-lp151.28.13-default will help.
I thought you meant it worked in the previous Tumbleweed. Was it meant Leap 15.1?
Yes, at Leap 15.1 it worked shure. And may be also in old Tumbleweed. (Now it works at Leap 15.2 on another computer.) -- You are receiving this mail because: You are on the CC list for the bug.
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317#c5
--- Comment #5 from Jiří Zlonický
(In reply to Takashi Iwai from comment #3)
(In reply to Jiří Zlonický from comment #2)
(In reply to Takashi Iwai from comment #1)
Which kernel did work in the past?
Im not shure. It is rarly used disk. Hope logs from other computer with 4.12.14-lp151.28.13-default will help.
I thought you meant it worked in the previous Tumbleweed. Was it meant Leap 15.1?
Yes, at Leap 15.1 it worked shure. And may be also in old Tumbleweed. (Now it works at Leap 15.2 on another computer.)
Uff and great sorry, it is Leap 15.1 (not 15.2) where disk works. My strange insanity. -- You are receiving this mail because: You are on the CC list for the bug.
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317#c6
--- Comment #6 from Takashi Iwai
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317
http://bugzilla.opensuse.org/show_bug.cgi?id=1148317#c7
--- Comment #7 from Jiří Zlonický
If the old TW worked, it'd be great if you can figure out which kernel started regression. You can find some old packages in the OBS history repo, http://download.opensuse.org/history/
Try to install the old kernel package on the working system, and retest with it.
I tested the oldest kernel from history: kernel-default-5.1.15-1.1.x86_64.rpm. It gives the same error. Next a tested kernel from Leap 15.1: kernel-default-4.12.14-lp151.27.3.x86_64.rpm It gives the same error. To exclude HW problem I booted from external usb flash with instalation iso Leap 15.0 ("rescue"). Than I connected problematic disk and it worked OK. Than accidently leav problematic disk connected in usb port and rebooted. It works OK (with kernel 5.2.9-1-default). When I disconnect it form port and reconnect again - same error. I try: udevadm monitor -s block -k -u -p and see: monitor will print the received events for: UDEV - the event which udev sends out after rule processing KERNEL - the kernel uevent KERNEL[174.795316] add /devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2/2-2.2.2:1.0/host7/target7:0:0/7:0:0:0/block/sdd (block) ACTION=add DEVPATH=/devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2/2-2.2.2:1.0/host7/target7:0:0/7:0:0:0/block/sdd SUBSYSTEM=block DEVNAME=/dev/sdd DEVTYPE=disk SEQNUM=7306 MAJOR=8 MINOR=48 KERNEL[174.795411] add /devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2/2-2.2.2:1.0/host7/target7:0:0/7:0:0:0/block/sdd/sdd1 (block) ACTION=add DEVPATH=/devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2/2-2.2.2:1.0/host7/target7:0:0/7:0:0:0/block/sdd/sdd1 SUBSYSTEM=block DEVNAME=/dev/sdd1 DEVTYPE=partition PARTN=1 SEQNUM=7307 MAJOR=8 MINOR=49 UDEV [175.107492] add /devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2/2-2.2.2:1.0/host7/target7:0:0/7:0:0:0/block/sdd (block) ACTION=add DEVPATH=/devices/pci0000:00/0000:00:14.0/usb2/2-2/2-2.2/2-2.2.2/2-2.2.2:1.0/host7/target7:0:0/7:0:0:0/block/sdd SUBSYSTEM=block DEVNAME=/dev/sdd DEVTYPE=disk SEQNUM=7306 USEC_INITIALIZED=175106785 DONT_DEL_PART_NODES=1 ID_VENDOR=ADATA ID_VENDOR_ENC=ADATA\x20\x20\x20 ID_VENDOR_ID=125f ID_MODEL=HDD_CH94 ID_MODEL_ENC=HDD\x20CH94\x20\x20\x20\x20\x20\x20\x20\x20 ID_MODEL_ID=a94a ID_REVISION=0160 ID_SERIAL=ADATA_HDD_CH94_CH0150805213-0:0 ID_SERIAL_SHORT=CH0150805213 ID_TYPE=disk ID_INSTANCE=0:0 ID_BUS=usb ID_USB_INTERFACES=:080650: ID_USB_INTERFACE_NUM=00 ID_USB_DRIVER=usb-storage ID_PATH=pci-0000:00:14.0-usb-0:2.2.2:1.0-scsi-0:0:0:0 ID_PATH_TAG=pci-0000_00_14_0-usb-0_2_2_2_1_0-scsi-0_0_0_0 .SYSFS_PATH=/sys/class/scsi_device/7:0:0:0/device .INQUIRY_DEV=/dev/sdd SCSI_TPGS=0 SCSI_TYPE=disk SCSI_VENDOR=ADATA SCSI_VENDOR_ENC=ADATA\x20\x20\x20 SCSI_MODEL=HDD_CH94 SCSI_MODEL_ENC=HDD\x20CH94\x20\x20\x20\x20\x20\x20\x20\x20 SCSI_REVISION=00 ID_SCSI=1 MPATH_SBIN_PATH=/sbin .SAVED_FM_WAIT_UNTIL= DM_MULTIPATH_DEVICE_PATH=0 FC_TARGET_LUN=0 .IS_MQ=1 ID_PART_TABLE_UUID=7cc57d55 ID_PART_TABLE_TYPE=dos COMPAT_SYMLINK_GENERATION=1 .ID_FS_TYPE_NEW= ID_FS_TYPE= MAJOR=8 MINOR=48 DEVLINKS=/dev/disk/by-path/pci-0000:00:14.0-usb-0:2.2.2:1.0-scsi-0:0:0:0 /dev/disk/by-id/usb-ADATA_HDD_CH94_CH0150805213-0:0 TAGS=:systemd: Comaprision with Leap 15.1 (on other computer) gives: Missing last message in add event: USEC_INITIALIZED= and difference in COMPAT_SYMLINK_GENERATION (on Leap 15.1 it is 2) and MINOR (on Leap 15.1 it is 32) What can I test next? -- You are receiving this mail because: You are on the CC list for the bug.
participants (1)
-
bugzilla_noreply@novell.com