samovolne odpojovani disku na USB

Sobota Září 18 16:51:09 CEST 2021

Děkuji oběma.

Reakce na Pavla Čočka:

PC je notebook Dell Latitude E6540 připojený přes dockinu ke 130W zdroji. 
WD Elements je přímo do něj.

Ale je pravda, že kabel dostal v minulosti zabrat, zkusím ho vyměnit.

K logům:

Jde o syslog a asi jsem ho předtím vykostil moc, omlouvám se. V příloze je 
z dnešního incidentu. Je tam o pár řádků víc a žádné jiné relevantní 
události v logu nejsou. V journalctl není nic navíc.

Pro udevadm monitor jsem připojil a nechal spadnout ještě jednou, takže 
časy mezi přílohami nesedí. Co monitor vypsal během zmizení device, to je v 
mon.txt.

Předpokládám, že to stále neznačuje možnost poruchy kabelu, třeba zmizení 
napájení.

Děkuji,

Macek

On 18. 09. 21 13:07, Pavel Kankovsky wrote:
> On Fri, 17 Sep 2021, Vladimír Macek wrote:
>
>> leta pouzivany externi disk WD Elements zacal delat to, ze po
>> automatickem namountovani nekolik minut jede a pak najednou zmizi
>> device. Stane se to i treba behem aktivniho cteni/zapisu. Mam Xubuntu
>> 18.04. Mate nekdo radu, co s tim, prosim? Priloha je cast logu.
> Poněkud mi tam chybí nějaké hlášky mezi přimontování zařízení a oznámením,
> že zařízení sdb1 (8:17) přestalo existovat:
>
> Aug 24 09:35:33 udisksd[1269]: Mounted /dev/sdb1 at /media/NAS on behalf of uid 1000
> Aug 24 09:41:37 udisksd[1269]: Cleaning up mount point /media/NAS (device 8:17 no longer exists)
>
> Při podobných událostech je většinou log pozvracený stížnostmi na to, jak
> uhnilo USB, jak selhávají operace poslané na to zařízení atd., což by
> mohlo dost objasnit příčinu toho, proč se to odpojuje. Ale tady není vůbec
> nic. Prostě blokové zařízení zmizelo a hotovo.
>
> Tím, že by se hlášení od jádra psala někam jinam a v tom, odkud ten
> přiložený výpis byl pořízen, vůbec nebyla, to asi nebude, protože výpis je
> obsahuje na začátku.
>
> Myslím, že je žádoucí zjistit, zda ty takové stížnosti existují. Odkud
> pochází ten výpis? Je něco víc vidět ve /var/log? journalctl? dmesg? Co
> během toho odpojení hlásí udevadm monitor?
>
> PS: V každém případě platí, že byste měl počítat i s možností, že to
> zařízení odchází do věčných lovišť, tj. na něj neukládat žádná cenná data,
> a ujistit se, že cokoli cenného na něm už je, má zálohu někde jinde (a
> pokud ne, tak se to snažit zkopírovat, dokud to ještě jde).

------------- další část ---------------
Sep 18 14:56:10 Latitude kernel: [322482.858892] usb 4-5: new SuperSpeed USB device number 2 using xhci_hcd
Sep 18 14:56:10 Latitude kernel: [322482.879685] usb 4-5: New USB device found, idVendor=1058, idProduct=1042
Sep 18 14:56:10 Latitude kernel: [322482.879690] usb 4-5: New USB device strings: Mfr=1, Product=2, SerialNumber=5
Sep 18 14:56:10 Latitude kernel: [322482.879692] usb 4-5: Product: Elements 1042
Sep 18 14:56:10 Latitude kernel: [322482.879695] usb 4-5: Manufacturer: Western Digital
Sep 18 14:56:10 Latitude kernel: [322482.879697] usb 4-5: SerialNumber: 575836314138323936363637
Sep 18 14:56:10 Latitude (spawn)[23262]: failed to execute '/lib/udev/mtp-probe' 'mtp-probe /sys/devices/pci0000:00/0000:00:14.0/usb4/4-5 4 2': No such file or directory
Sep 18 14:56:11 Latitude kernel: [322483.432608] usb-storage 4-5:1.0: USB Mass Storage device detected
Sep 18 14:56:11 Latitude kernel: [322483.432724] scsi host5: usb-storage 4-5:1.0
Sep 18 14:56:11 Latitude kernel: [322483.432851] usbcore: registered new interface driver usb-storage
Sep 18 14:56:11 Latitude kernel: [322483.434899] usbcore: registered new interface driver uas
Sep 18 14:56:11 Latitude upowerd[2852]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:14.0/usb4/4-5
Sep 18 14:56:11 Latitude upowerd[2852]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0
Sep 18 14:56:12 Latitude kernel: [322484.456085] scsi 5:0:0:0: Direct-Access     WD       Elements 1042    1015 PQ: 0 ANSI: 6
Sep 18 14:56:12 Latitude kernel: [322484.456633] sd 5:0:0:0: Attached scsi generic sg1 type 0
Sep 18 14:56:12 Latitude kernel: [322484.457370] sd 5:0:0:0: [sdb] Spinning up disk...
Sep 18 14:56:14 Latitude kernel: [322485.478919] .
Sep 18 14:56:15 Latitude kernel: [322486.502861] .
Sep 18 14:56:16 Latitude kernel: [322487.526963] .
Sep 18 14:56:17 Latitude kernel: [322488.550904] .
Sep 18 14:56:18 Latitude kernel: [322489.575026] .
Sep 18 14:56:18 Latitude kernel: [322490.598971] .
Sep 18 14:56:18 Latitude kernel: [322490.599665] ready
Sep 18 14:56:18 Latitude kernel: [322490.599812] sd 5:0:0:0: [sdb] 1953519616 512-byte logical blocks: (1.00 TB/932 GiB)
Sep 18 14:56:18 Latitude kernel: [322490.600059] sd 5:0:0:0: [sdb] Write Protect is off
Sep 18 14:56:18 Latitude kernel: [322490.600061] sd 5:0:0:0: [sdb] Mode Sense: 47 00 10 08
Sep 18 14:56:18 Latitude kernel: [322490.600305] sd 5:0:0:0: [sdb] No Caching mode page found
Sep 18 14:56:18 Latitude kernel: [322490.600310] sd 5:0:0:0: [sdb] Assuming drive cache: write through
Sep 18 14:56:18 Latitude kernel: [322490.607559]  sdb: sdb1 sdb3
Sep 18 14:56:18 Latitude kernel: [322490.609163] sd 5:0:0:0: [sdb] Attached SCSI disk
Sep 18 14:56:24 Latitude ntfs-3g[23333]: Version 2017.3.23 integrated FUSE 28
Sep 18 14:56:24 Latitude ntfs-3g[23333]: Mounted /dev/sdb1 (Read-Write, label "NAS", NTFS 3.1)
Sep 18 14:56:24 Latitude ntfs-3g[23333]: Cmdline options: rw,nodev,nosuid,uid=1000,gid=1000,uhelper=udisks2
Sep 18 14:56:24 Latitude ntfs-3g[23333]: Mount options: rw,nodev,nosuid,uhelper=udisks2,allow_other,nonempty,relatime,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096
Sep 18 14:56:24 Latitude ntfs-3g[23333]: Global ownership and permissions enforced, configuration type 7
Sep 18 14:56:24 Latitude systemd[1]: Created slice system-clean\x2dmount\x2dpoint.slice.
Sep 18 14:56:24 Latitude systemd[1]: Started Clean the /media/NAS mount point.
Sep 18 14:56:24 Latitude udisksd[982]: Mounted /dev/sdb1 at /media/NAS on behalf of uid 1000
Sep 18 14:56:24 Latitude dbus-daemon[2684]: [session uid=1000 pid=2684] Activating via systemd: service name='org.gtk.vfs.Metadata' unit='gvfs-metadata.service' requested by ':1.12385' (uid=1000 pid=31810 comm="Thunar --daemon " label="unconfined")
Sep 18 14:56:24 Latitude systemd[2643]: Starting Virtual filesystem metadata service...
Sep 18 14:56:24 Latitude dbus-daemon[2684]: [session uid=1000 pid=2684] Successfully activated service 'org.gtk.vfs.Metadata'
Sep 18 14:56:24 Latitude systemd[2643]: Started Virtual filesystem metadata service.
Sep 18 14:59:56 Latitude dbus-daemon[985]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Sep 18 15:04:07 Latitude dbus-daemon[985]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'

Sep 18 15:16:01 Latitude kernel: [323673.242967] usb 4-5: USB disconnect, device number 2
Sep 18 15:16:01 Latitude udisksd[982]: Cleaning up mount point /media/NAS (device 8:17 no longer exists)
Sep 18 15:16:01 Latitude systemd[1]: Stopping Clean the /media/NAS mount point...
Sep 18 15:16:01 Latitude systemd[1]: Stopped Clean the /media/NAS mount point.
Sep 18 15:16:01 Latitude ntfs-3g[23333]: Unmounting /dev/sdb1 (NAS)
Sep 18 15:16:01 Latitude upowerd[2852]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0
Sep 18 15:16:01 Latitude upowerd[2852]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:14.0/usb4/4-5
------------- další část ---------------
KERNEL[329064.555781] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/bsg/5:0:0:0 (bsg)
KERNEL[329064.555869] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/scsi_generic/sg1 (scsi_generic)
KERNEL[329064.555893] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/scsi_device/5:0:0:0 (scsi_device)
KERNEL[329064.555998] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/scsi_disk/5:0:0:0 (scsi_disk)
KERNEL[329064.556154] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/block/sdb/sdb3 (block)
KERNEL[329064.556399] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/block/sdb/sdb1 (block)
KERNEL[329064.556533] remove   /devices/virtual/bdi/8:16 (bdi)
KERNEL[329064.556662] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/block/sdb (block)
KERNEL[329064.556680] unbind   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0 (scsi)
KERNEL[329064.556697] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0 (scsi)
UDEV  [329064.557834] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/bsg/5:0:0:0 (bsg)
UDEV  [329064.558330] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/scsi_generic/sg1 (scsi_generic)
UDEV  [329064.559022] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/scsi_device/5:0:0:0 (scsi_device)
UDEV  [329064.559283] remove   /devices/virtual/bdi/8:16 (bdi)
UDEV  [329064.561703] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/block/sdb/sdb3 (block)
UDEV  [329064.561755] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/scsi_disk/5:0:0:0 (scsi_disk)
UDEV  [329064.562356] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/block/sdb/sdb1 (block)
UDEV  [329064.562471] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0/block/sdb (block)
UDEV  [329064.563906] unbind   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0 (scsi)
UDEV  [329064.565144] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0/5:0:0:0 (scsi)
KERNEL[329064.584232] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0 (scsi)
KERNEL[329064.584274] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/scsi_host/host5 (scsi_host)
KERNEL[329064.584299] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5 (scsi)
KERNEL[329064.584346] unbind   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0 (usb)
KERNEL[329064.584376] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0 (usb)
KERNEL[329064.585043] unbind   /devices/pci0000:00/0000:00:14.0/usb4/4-5 (usb)
KERNEL[329064.585088] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5 (usb)
UDEV  [329064.586065] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/target5:0:0 (scsi)
UDEV  [329064.586303] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5/scsi_host/host5 (scsi_host)
UDEV  [329064.586934] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0/host5 (scsi)
UDEV  [329064.587819] unbind   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0 (usb)
UDEV  [329064.588681] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5/4-5:1.0 (usb)
UDEV  [329064.589519] unbind   /devices/pci0000:00/0000:00:14.0/usb4/4-5 (usb)
UDEV  [329064.593981] remove   /devices/pci0000:00/0000:00:14.0/usb4/4-5 (usb)
KERNEL[329064.600655] remove   /devices/virtual/bdi/8:17-fuseblk (bdi)
UDEV  [329064.601954] remove   /devices/virtual/bdi/8:17-fuseblk (bdi)
KERNEL[329064.672667] remove   /kmalloc-32(52127:NetworkManager-dispatcher.service) (cgroup)
KERNEL[329064.672710] remove   /cred_jar(52127:NetworkManager-dispatcher.service) (cgroup)
UDEV  [329064.674020] remove   /cred_jar(52127:NetworkManager-dispatcher.service) (cgroup)
UDEV  [329064.674053] remove   /kmalloc-32(52127:NetworkManager-dispatcher.service) (cgroup)