UM 18.04 error message after safely removing drive

mdooley · 5 May 2018 18:05

After doing a fresh install of 18.04 on an old Dell laptop, I continue to get error messages subsequent to safely removing a Western Digital Passport usb drive. This morning, I plugged it in and then safely removed it at 9:19AM. At 10:30, this message appeared.

Got any ideas on troubleshooting? I usually unplug the drive late at night and am greeted with the error message when opening the lid in the morning.

Edit: I am reasonably sure that this is a bug in caja but not totally sure. Google-fu has not helped me...

Edit 2: Syslog says this about that -

May  5 09:18:50 D830 gvfs-udisks2-vo[1638]: gvfs_udisks2_drive_stop_finish: assertion 'g_async_result_is_tagged (result, gvfs_udisks2_drive_stop)' failed
May  5 09:18:50 D830 gvfs-udisks2-vo[1638]: g_dbus_method_invocation_return_gerror: assertion 'error != NULL' failed
May  5 09:18:50 D830 gvfs-udisks2-vo[1638]: g_error_free: assertion 'error != NULL' failed
May  5 09:18:50 D830 kernel: [99125.601129] usb 2-1: USB disconnect, device number 6
May  5 09:18:50 D830 upowerd[1781]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May  5 09:18:50 D830 upowerd[1781]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1

goinglinux · 5 May 2018 23:50

FYI, I am seeing this as well. Same error message, “Unable to stop drive Timeout was reached,” delayed for a period of time after safely removing a Western Digital USB drive. Also occurs with an HP USB drive. At first, I thought it had something to do with installing TLP. I changed the setting:

tlp USB_AUTOSUSPEND=0

but it did not prevent the error.

Now that I read @mdooley’s post, I know that was not the cause.

mdooley · 8 May 2018 16:28

More messages like this continue to appear. Does not appear to be a caja bug but instead related to something in the kernel. I think…

This morning, I plugged in and unplugged three external drives, a 2 Gb WD Passport Ultra, a 1 Gb WD Passport Ultra and a SSHD contained within an Inateck USB case. I got three Unable to stop drive messages after safely removing each of them. The two WD drives are NTFS formatted as received from the vendor and the Inateck is formatted ext4, with a 120 Gb Samsung 840 EVO flash drive within.

I’m posting just as an update. No support nor help expected. Below is more from Syslog - really too much to read. Knock yourselves out.

May  8 08:01:12 D830 kernel: [42096.460194] usb 2-1: new high-speed USB device number 7 using ehci-pci
May  8 08:01:12 D830 kernel: [42096.617457] usb 2-1: New USB device found, idVendor=1058, idProduct=0741
May  8 08:01:12 D830 kernel: [42096.617466] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=5
May  8 08:01:12 D830 kernel: [42096.617471] usb 2-1: Product: My Passport 0741
May  8 08:01:12 D830 kernel: [42096.617477] usb 2-1: Manufacturer: Western Digital
May  8 08:01:12 D830 kernel: [42096.617482] usb 2-1: SerialNumber: 575833314537334448584A32
May  8 08:01:12 D830 kernel: [42096.618389] usb-storage 2-1:1.0: USB Mass Storage device detected
May  8 08:01:12 D830 kernel: [42096.627699] scsi host4: usb-storage 2-1:1.0
May  8 08:01:12 D830 mtp-probe: checking bus 2, device 7: "/sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1"
May  8 08:01:12 D830 mtp-probe: bus: 2, device: 7 was not an MTP device
May  8 08:01:12 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May  8 08:01:12 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1
May  8 08:01:13 D830 kernel: [42097.632724] scsi 4:0:0:0: Direct-Access     WD       My Passport 0741 1025 PQ: 0 ANSI: 6
May  8 08:01:13 D830 kernel: [42097.633185] scsi 4:0:0:1: Enclosure         WD       SES Device       1025 PQ: 0 ANSI: 6
May  8 08:01:13 D830 kernel: [42097.640133] sd 4:0:0:0: Attached scsi generic sg2 type 0
May  8 08:01:13 D830 kernel: [42097.640318] ses 4:0:0:1: Attached Enclosure device
May  8 08:01:13 D830 kernel: [42097.640416] ses 4:0:0:1: Attached scsi generic sg3 type 13
May  8 08:01:18 D830 kernel: [42102.248095] ses 4:0:0:1: Wrong diagnostic page; asked for 1 got 8
May  8 08:01:18 D830 kernel: [42102.248104] ses 4:0:0:1: Failed to get diagnostic page 0x1
May  8 08:01:18 D830 kernel: [42102.248111] ses 4:0:0:1: Failed to bind enclosure -19
May  8 08:01:18 D830 kernel: [42102.250166] sd 4:0:0:0: [sdb] 1953458176 512-byte logical blocks: (1.00 TB/931 GiB)
May  8 08:01:18 D830 kernel: [42102.251146] sd 4:0:0:0: [sdb] Write Protect is off
May  8 08:01:18 D830 kernel: [42102.251149] sd 4:0:0:0: [sdb] Mode Sense: 47 00 10 08
May  8 08:01:18 D830 kernel: [42102.252192] sd 4:0:0:0: [sdb] No Caching mode page found
May  8 08:01:18 D830 kernel: [42102.252198] sd 4:0:0:0: [sdb] Assuming drive cache: write through
May  8 08:01:18 D830 kernel: [42102.271202]  sdb: sdb1
May  8 08:01:18 D830 kernel: [42102.274274] sd 4:0:0:0: [sdb] Attached SCSI disk
May  8 08:01:20 D830 ntfs-3g[10312]: Version 2017.3.23 integrated FUSE 28
May  8 08:01:20 D830 ntfs-3g[10312]: Mounted /dev/sdb1 (Read-Write, label "My Passport 1GB", NTFS 3.1)
May  8 08:01:20 D830 ntfs-3g[10312]: Cmdline options: rw,nodev,nosuid,uid=1000,gid=1000,uhelper=udisks2
May  8 08:01:20 D830 ntfs-3g[10312]: Mount options: rw,nodev,nosuid,uhelper=udisks2,allow_other,nonempty,relatime,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096
May  8 08:01:20 D830 ntfs-3g[10312]: Global ownership and permissions enforced, configuration type 7
May  8 08:01:20 D830 systemd[1]: Started Clean the /media/michael/My Passport 1GB mount point.
May  8 08:01:20 D830 udisksd[766]: Mounted /dev/sdb1 at /media/michael/My Passport 1GB on behalf of uid 1000
May  8 08:04:38 D830 udisksd[766]: Cleaning up mount point /media/michael/My Passport 1GB (device 8:17 is not mounted)
May  8 08:04:38 D830 systemd[1]: Stopping Clean the /media/michael/My Passport 1GB mount point...
May  8 08:04:38 D830 systemd[1]: Stopped Clean the /media/michael/My Passport 1GB mount point.
May  8 08:04:39 D830 ntfs-3g[10312]: Unmounting /dev/sdb1 (My Passport 1GB)
May  8 08:04:39 D830 udisksd[766]: Unmounted /dev/sdb1 on behalf of uid 1000
May  8 08:04:39 D830 udisksd[766]: Successfully sent SCSI command SYNCHRONIZE CACHE to /dev/sdb
May  8 08:04:40 D830 udisksd[766]: Successfully sent SCSI command START STOP UNIT to /dev/sdb
May  8 08:04:41 D830 kernel: [42305.676683] blk_partition_remap: fail for partition 1
May  8 08:04:41 D830 kernel: [42305.676690] blk_partition_remap: fail for partition 1
May  8 08:04:41 D830 kernel: [42305.676694] Buffer I/O error on dev sdb1, logical block 1, async page read
May  8 08:04:41 D830 systemd-udevd[10398]: inotify_add_watch(9, /dev/sdb1, 10) failed: No such file or directory
May  8 08:04:41 D830 udisksd[766]: g_udev_device_get_sysfs_path: assertion 'G_UDEV_IS_DEVICE (device)' failed
May  8 08:04:41 D830 udisksd[766]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
May  8 08:04:41 D830 udisksd[766]: Powered off /dev/sdb - successfully wrote to sysfs path /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/remove
May  8 08:04:41 D830 kernel: [42305.696632] usb 2-1: USB disconnect, device number 7
May  8 08:04:41 D830 gvfs-udisks2-vo[1635]: gvfs_udisks2_drive_stop_finish: assertion 'g_async_result_is_tagged (result, gvfs_udisks2_drive_stop)' failed
May  8 08:04:41 D830 gvfs-udisks2-vo[1635]: g_dbus_method_invocation_return_gerror: assertion 'error != NULL' failed
May  8 08:04:41 D830 gvfs-udisks2-vo[1635]: g_error_free: assertion 'error != NULL' failed
May  8 08:04:41 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May  8 08:04:41 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1
May  8 08:05:45 D830 kernel: [42369.216258] usb 2-2: new high-speed USB device number 8 using ehci-pci
May  8 08:05:45 D830 kernel: [42369.375554] usb 2-2: New USB device found, idVendor=1058, idProduct=0830
May  8 08:05:45 D830 kernel: [42369.375561] usb 2-2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
May  8 08:05:45 D830 kernel: [42369.375565] usb 2-2: Product: My Passport 0830
May  8 08:05:45 D830 kernel: [42369.375568] usb 2-2: Manufacturer: Western Digital
May  8 08:05:45 D830 kernel: [42369.375572] usb 2-2: SerialNumber: 575838314543343250354841
May  8 08:05:45 D830 kernel: [42369.376630] usb-storage 2-2:1.0: USB Mass Storage device detected
May  8 08:05:45 D830 kernel: [42369.380393] scsi host4: usb-storage 2-2:1.0
May  8 08:05:45 D830 mtp-probe: checking bus 2, device 8: "/sys/devices/pci0000:00/0000:00:1d.7/usb2/2-2"
May  8 08:05:45 D830 mtp-probe: bus: 2, device: 8 was not an MTP device
May  8 08:05:45 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-2/2-2:1.0
May  8 08:05:45 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-2
May  8 08:05:46 D830 kernel: [42370.401718] scsi 4:0:0:0: Direct-Access     WD       My Passport 0830 1065 PQ: 0 ANSI: 6
May  8 08:05:46 D830 kernel: [42370.402696] scsi 4:0:0:1: Enclosure         WD       SES Device       1065 PQ: 0 ANSI: 6
May  8 08:05:46 D830 kernel: [42370.407175] sd 4:0:0:0: Attached scsi generic sg2 type 0
May  8 08:05:46 D830 kernel: [42370.407352] ses 4:0:0:1: Attached Enclosure device
May  8 08:05:46 D830 kernel: [42370.407481] ses 4:0:0:1: Attached scsi generic sg3 type 13
May  8 08:05:46 D830 kernel: [42370.413935] ses 4:0:0:1: Failed to get diagnostic page 0x1
May  8 08:05:46 D830 kernel: [42370.413945] ses 4:0:0:1: Failed to bind enclosure -19
May  8 08:05:46 D830 kernel: [42370.417781] sd 4:0:0:0: [sdb] Spinning up disk...
May  8 08:05:48 D830 kernel: [42371.424179] .
May  8 08:05:49 D830 kernel: [42372.448310] .
May  8 08:05:50 D830 kernel: [42373.472194] .
May  8 08:05:51 D830 kernel: [42374.496167] .
May  8 08:05:51 D830 kernel: [42375.520309] .
May  8 08:05:51 D830 kernel: [42375.689299] ready
May  8 08:05:51 D830 kernel: [42375.689761] sd 4:0:0:0: [sdb] 3906963456 512-byte logical blocks: (2.00 TB/1.82 TiB)
May  8 08:05:51 D830 kernel: [42375.690970] sd 4:0:0:0: [sdb] Write Protect is off
May  8 08:05:51 D830 kernel: [42375.690973] sd 4:0:0:0: [sdb] Mode Sense: 53 00 10 08
May  8 08:05:51 D830 kernel: [42375.693503] sd 4:0:0:0: [sdb] No Caching mode page found
May  8 08:05:51 D830 kernel: [42375.693508] sd 4:0:0:0: [sdb] Assuming drive cache: write through
May  8 08:05:52 D830 kernel: [42375.866893]  sdb: sdb1
May  8 08:05:52 D830 kernel: [42375.870573] sd 4:0:0:0: [sdb] Attached SCSI disk
May  8 08:05:54 D830 ntfs-3g[10467]: Version 2017.3.23 integrated FUSE 28
May  8 08:05:54 D830 ntfs-3g[10467]: Mounted /dev/sdb1 (Read-Write, label "My Passport 2GB", NTFS 3.1)
May  8 08:05:54 D830 ntfs-3g[10467]: Cmdline options: rw,nodev,nosuid,uid=1000,gid=1000,uhelper=udisks2
May  8 08:05:54 D830 ntfs-3g[10467]: Mount options: rw,nodev,nosuid,uhelper=udisks2,allow_other,nonempty,relatime,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096
May  8 08:05:54 D830 ntfs-3g[10467]: Global ownership and permissions enforced, configuration type 7
May  8 08:05:54 D830 systemd[1]: Started Clean the /media/michael/My Passport 2GB mount point.
May  8 08:05:54 D830 udisksd[766]: Mounted /dev/sdb1 at /media/michael/My Passport 2GB on behalf of uid 1000
May  8 08:06:15 D830 udisksd[766]: Cleaning up mount point /media/michael/My Passport 2GB (device 8:17 is not mounted)
May  8 08:06:15 D830 systemd[1]: Stopping Clean the /media/michael/My Passport 2GB mount point...
May  8 08:06:15 D830 ntfs-3g[10467]: Unmounting /dev/sdb1 (My Passport 2GB)
May  8 08:06:15 D830 udisksd[766]: Unmounted /dev/sdb1 on behalf of uid 1000
May  8 08:06:15 D830 systemd[1]: Stopped Clean the /media/michael/My Passport 2GB mount point.
May  8 08:06:15 D830 udisksd[766]: Successfully sent SCSI command SYNCHRONIZE CACHE to /dev/sdb
May  8 08:06:16 D830 udisksd[766]: Successfully sent SCSI command START STOP UNIT to /dev/sdb
May  8 08:06:16 D830 kernel: [42400.804125] blk_partition_remap: fail for partition 1
May  8 08:06:16 D830 kernel: [42400.804131] blk_partition_remap: fail for partition 1
May  8 08:06:16 D830 kernel: [42400.804134] Buffer I/O error on dev sdb1, logical block 1, async page read
May  8 08:06:16 D830 systemd-udevd[10483]: inotify_add_watch(9, /dev/sdb1, 10) failed: No such file or directory
May  8 08:06:16 D830 udisksd[766]: g_udev_device_get_sysfs_path: assertion 'G_UDEV_IS_DEVICE (device)' failed
May  8 08:06:16 D830 udisksd[766]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
May  8 08:06:17 D830 udisksd[766]: Powered off /dev/sdb - successfully wrote to sysfs path /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-2/remove
May  8 08:06:17 D830 gvfs-udisks2-vo[1635]: gvfs_udisks2_drive_stop_finish: assertion 'g_async_result_is_tagged (result, gvfs_udisks2_drive_stop)' failed
May  8 08:06:17 D830 kernel: [42400.834157] usb 2-2: USB disconnect, device number 8
May  8 08:06:17 D830 gvfs-udisks2-vo[1635]: g_dbus_method_invocation_return_gerror: assertion 'error != NULL' failed
May  8 08:06:17 D830 gvfs-udisks2-vo[1635]: g_error_free: assertion 'error != NULL' failed
May  8 08:06:17 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-2/2-2:1.0
May  8 08:06:17 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-2
May  8 08:06:42 D830 kernel: [42426.476161] usb 2-1: new high-speed USB device number 9 using ehci-pci
May  8 08:06:42 D830 kernel: [42426.678568] usb 2-1: New USB device found, idVendor=0080, idProduct=a001
May  8 08:06:42 D830 kernel: [42426.678577] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
May  8 08:06:42 D830 kernel: [42426.678583] usb 2-1: Product: External USB 3.0
May  8 08:06:42 D830 kernel: [42426.678588] usb 2-1: Manufacturer: TOSHIBA
May  8 08:06:42 D830 kernel: [42426.678594] usb 2-1: SerialNumber: 20150331001E9
May  8 08:06:42 D830 kernel: [42426.680679] scsi host4: uas
May  8 08:06:42 D830 kernel: [42426.681957] scsi 4:0:0:0: Direct-Access     TO Exter nal USB 3.0      6101 PQ: 0 ANSI: 6
May  8 08:06:42 D830 kernel: [42426.683818] sd 4:0:0:0: Attached scsi generic sg2 type 0
May  8 08:06:42 D830 mtp-probe: checking bus 2, device 9: "/sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1"
May  8 08:06:42 D830 mtp-probe: bus: 2, device: 9 was not an MTP device
May  8 08:06:42 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May  8 08:06:42 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1
May  8 08:06:43 D830 kernel: [42427.565155] sd 4:0:0:0: [sdb] 234441648 512-byte logical blocks: (120 GB/112 GiB)
May  8 08:06:43 D830 kernel: [42427.565160] sd 4:0:0:0: [sdb] 4096-byte physical blocks
May  8 08:06:43 D830 kernel: [42427.565520] sd 4:0:0:0: [sdb] Write Protect is off
May  8 08:06:43 D830 kernel: [42427.565523] sd 4:0:0:0: [sdb] Mode Sense: 5f 00 00 08
May  8 08:06:43 D830 kernel: [42427.566146] sd 4:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
May  8 08:06:43 D830 kernel: [42427.572182]  sdb: sdb1
May  8 08:06:43 D830 kernel: [42427.575138] sd 4:0:0:0: [sdb] Attached SCSI disk
May  8 08:06:44 D830 kernel: [42428.131635] EXT4-fs (sdb1): mounted filesystem with ordered data mode. Opts: (null)
May  8 08:06:44 D830 systemd[1]: Started Clean the /media/michael/transfer mount point.
May  8 08:06:44 D830 udisksd[766]: Mounted /dev/sdb1 at /media/michael/transfer on behalf of uid 1000
May  8 08:07:20 D830 udisksd[766]: Cleaning up mount point /media/michael/transfer (device 8:17 is not mounted)
May  8 08:07:20 D830 systemd[1]: Stopping Clean the /media/michael/transfer mount point...
May  8 08:07:20 D830 systemd[1]: Stopped Clean the /media/michael/transfer mount point.
May  8 08:07:20 D830 udisksd[766]: Unmounted /dev/sdb1 on behalf of uid 1000
May  8 08:07:20 D830 udisksd[766]: Successfully sent SCSI command SYNCHRONIZE CACHE to /dev/sdb
May  8 08:07:21 D830 udisksd[766]: Successfully sent SCSI command START STOP UNIT to /dev/sdb
May  8 08:07:21 D830 kernel: [42464.828125] sd 4:0:0:0: tag#0 uas_zap_pending 0 uas-tag 1 inflight: CMD 
May  8 08:07:21 D830 kernel: [42464.828132] sd 4:0:0:0: tag#0 CDB: Test Unit Ready 00 00 00 00 00 00
May  8 08:07:21 D830 kernel: [42464.832918] sd 4:0:0:0: [sdb] Synchronizing SCSI cache
May  8 08:07:21 D830 kernel: [42465.076300] sd 4:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK
May  8 08:07:21 D830 udisksd[766]: Powered off /dev/sdb - successfully wrote to sysfs path /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/remove
May  8 08:07:21 D830 gvfs-udisks2-vo[1635]: gvfs_udisks2_drive_stop_finish: assertion 'g_async_result_is_tagged (result, gvfs_udisks2_drive_stop)' failed
May  8 08:07:21 D830 gvfs-udisks2-vo[1635]: g_dbus_method_invocation_return_gerror: assertion 'error != NULL' failed
May  8 08:07:21 D830 gvfs-udisks2-vo[1635]: g_error_free: assertion 'error != NULL' failed
May  8 08:07:21 D830 kernel: [42465.108929] usb 2-1: USB disconnect, device number 9
May  8 08:07:21 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May  8 08:07:21 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1
May  8 08:07:24 D830 kernel: [42467.948204] usb 2-1: new high-speed USB device number 10 using ehci-pci
May  8 08:07:24 D830 kernel: [42468.150293] usb 2-1: New USB device found, idVendor=0080, idProduct=a001
May  8 08:07:24 D830 kernel: [42468.150302] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
May  8 08:07:24 D830 kernel: [42468.150308] usb 2-1: Product: External USB 3.0
May  8 08:07:24 D830 kernel: [42468.150313] usb 2-1: Manufacturer: TOSHIBA
May  8 08:07:24 D830 kernel: [42468.150318] usb 2-1: SerialNumber: 20150331001E9
May  8 08:07:24 D830 kernel: [42468.158622] scsi host4: uas
May  8 08:07:24 D830 kernel: [42468.159905] scsi 4:0:0:0: Direct-Access     TO Exter nal USB 3.0      6101 PQ: 0 ANSI: 6
May  8 08:07:24 D830 kernel: [42468.162097] sd 4:0:0:0: Attached scsi generic sg2 type 0
May  8 08:07:24 D830 mtp-probe: checking bus 2, device 10: "/sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1"
May  8 08:07:24 D830 mtp-probe: bus: 2, device: 10 was not an MTP device
May  8 08:07:24 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May  8 08:07:24 D830 upowerd[1746]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1
May  8 08:07:25 D830 kernel: [42469.043507] sd 4:0:0:0: [sdb] 234441648 512-byte logical blocks: (120 GB/112 GiB)
May  8 08:07:25 D830 kernel: [42469.043511] sd 4:0:0:0: [sdb] 4096-byte physical blocks
May  8 08:07:25 D830 kernel: [42469.043866] sd 4:0:0:0: [sdb] Write Protect is off
May  8 08:07:25 D830 kernel: [42469.043871] sd 4:0:0:0: [sdb] Mode Sense: 5f 00 00 08
May  8 08:07:25 D830 kernel: [42469.044498] sd 4:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
May  8 08:07:25 D830 kernel: [42469.051261]  sdb: sdb1
May  8 08:07:25 D830 kernel: [42469.053859] sd 4:0:0:0: [sdb] Attached SCSI disk
May  8 08:07:25 D830 kernel: [42469.169062] usb 2-1: USB disconnect, device number 10
May  8 08:07:25 D830 kernel: [42469.169865] sd 4:0:0:0: [sdb] tag#3 uas_zap_pending 0 uas-tag 4 inflight: CMD 
May  8 08:07:25 D830 kernel: [42469.169869] sd 4:0:0:0: [sdb] tag#3 CDB: Read(10) 28 00 00 00 00 80 00 01 80 00
May  8 08:07:25 D830 kernel: [42469.169908] sd 4:0:0:0: [sdb] tag#3 FAILED Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
May  8 08:07:25 D830 kernel: [42469.169911] sd 4:0:0:0: [sdb] tag#3 CDB: Read(10) 28 00 00 00 00 80 00 01 80 00
May  8 08:07:25 D830 kernel: [42469.169914] print_req_error: I/O error, dev sdb, sector 128
May  8 08:07:25 D830 kernel: [42469.170637] sd 4:0:0:0: [sdb] Synchronizing SCSI cache
May  8 08:07:25 D830 systemd-udevd[10595]: inotify_add_watch(9, /dev/sdb, 10) failed: No such file or directory
May  8 08:07:25 D830 kernel: [42469.432261] sd 4:0:0:0: [sdb] Synchronize Cache(10) failed: Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK
May  8 08:07:25 D830 systemd-udevd[10597]: inotify_add_watch(9, /dev/sdb1, 10) failed: No such file or directory
May  8 08:07:25 D830 udisksd[766]: g_udev_device_get_sysfs_path: assertion 'G_UDEV_IS_DEVICE (device)' failed
May  8 08:07:25 D830 udisksd[766]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
May  8 08:07:25 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May  8 08:07:25 D830 upowerd[1746]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1

Spyder · 9 May 2018 14:39

I see it too, but only after a long time - at least 30 minutes after removing external drive. I doubt it is a bug in Caja, because I saw it in Thunar (from Xubuntu) also.

mdooley · 9 May 2018 22:31

Like you, I get this message long after I’ve removed the external drive. I no longer think its a bug in Caja but suspect that it is a kernel bug. I’ll go about reporting it after I’m finished with a server update to 18.04. Thanks @Spyder. And thanks to @goinglinux Larry too.

goinglinux · 10 May 2018 04:52

@mdooley, I can confirm that I get one message for each drive I connect and disconnect. I haven’t timed it, but there is about a 20-30 minute delay between disconnection and error message. Interestingly, when I put my laptop into suspend after disconnecting a drive, whether the computer is asleep for 20 minutes or 3 hours, the delay seems to be a countdown that is suspended while the laptop is suspended. This might support your suspicion that it is a kernel bug rather than a problem in the application layer.

mdooley · 12 May 2018 14:53

Removed a WD Passport USB drive last night and got the following from syslog1 this morning (after getting the error message):

May 11 22:32:36 D830 gvfs-udisks2-vo[1700]: gvfs_udisks2_drive_stop_finish: assertion 'g_async_result_is_tagged (result, gvfs_udisks2_drive_stop)' failed
May 11 22:32:36 D830 gvfs-udisks2-vo[1700]: g_dbus_method_invocation_return_gerror: assertion 'error != NULL' failed
May 11 22:32:36 D830 gvfs-udisks2-vo[1700]: g_error_free: assertion 'error != NULL' failed
May 11 22:32:36 D830 upowerd[1821]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1:1.0
May 11 22:32:36 D830 upowerd[1821]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:1d.7/usb2/2-1

Appears to be a failure of gvfs.

Edit: reported as bug - https://bugs.launchpad.net/ubuntu-mate/+bug/1770857

mdooley · 30 May 2018 16:55

Note that umount /media/michael/"drivename" seems to work in avoiding the “Unable to stop drive” message.

Edit: Also note that simply pulling the USB extension cable out of the USB port avoids the error message. For what it’s worth…

mdooley · 7 June 2018 15:36

I received a message this morning noting that this bug is a duplicate and that a patch is in the works. Please see - https://bugs.launchpad.net/ubuntu/+source/thunar/+bug/1762595

mdooley · 9 July 2018 19:51

gvfs 1.36.1-0ubuntu1.1 seems to have fixed this in Ubuntu MATE 18.04. To get this upstream patch, I enabled Pre-released updates (bionic proposed) in Software & Updates and then applied only the gvfs .debs in Synaptic. No more Timeout was reached messages.