[solved] Jessie->Stretch: Digicam crasht am USB

[whiizy]

Habe eine Workstation von Jessie auf Stretch hochgezogen. Hat gut geklappt, aber jetzt bin ich auf ein unerwartetes Problem gestossen. Eine ältere Casio Exilim Digicam lässt sich am USB-Port plötzlich nicht mehr als usb-storage verbinden. Habe ich unter Jessie häufig genutzt, um Fotos auf den PC zu holen.

Beim Einschalten der verbundenen Camera stürzt diese jetzt sogar ab. Das Display geht an und friert mit einem zufälligen Muster ein.

Unter Jessie blinkte sonst kurz die gruene Status-LED, die Cam wurde vom LXDE-Desktop gefunden und bot sie mir zum Mount im filemanager an. Das Display der Cam blieb dabei immer aus.

Das syslog zeigt auch, daß nun etwas schiefgeht. CASIO wird noch identifiziert und nach errors wird anscheinend noch ein "power cycle" versucht - aber das war es dann auch.

Bevor ich jetzt wieder das halbe Internet abgrase, wollte ich zunächst mal nachfragen, ob jemand das USB-Problem vielleicht schon bekannt vorkommt:

Code: Alles auswählen

syslog

Jun 23 09:27:29 ds kernel: [  108.029238] usb 1-1.1: new full-speed USB device number 3 using ehci-pci
Jun 23 09:27:29 ds kernel: [  108.141636] usb 1-1.1: New USB device found, idVendor=07cf, idProduct=1004
Jun 23 09:27:29 ds kernel: [  108.141643] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jun 23 09:27:29 ds kernel: [  108.141646] usb 1-1.1: Product: CASIO QV DIGITAL
Jun 23 09:27:29 ds kernel: [  108.141648] usb 1-1.1: Manufacturer: CASIO COMPUTER
Jun 23 09:27:29 ds kernel: [  108.142331] usb-storage 1-1.1:1.0: USB Mass Storage device detected
Jun 23 09:27:29 ds kernel: [  108.142908] scsi host5: usb-storage 1-1.1:1.0
Jun 23 09:27:29 ds mtp-probe: checking bus 1, device 3: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.1"
Jun 23 09:27:29 ds mtp-probe: bus: 1, device: 3 was not an MTP device
Jun 23 09:27:46 ds dbus[364]: [system] Activating via systemd: service name='org.freedesktop.ConsoleKit' unit='console-kit-daemon.service'
Jun 23 09:27:46 ds systemd[1]: Starting Console Manager...
Jun 23 09:27:46 ds udev-acl.ck[1580]: g_slice_set_config: assertion 'sys_page_size == 0' failed
Jun 23 09:27:46 ds console-kit-daemon[1517]: missing action
Jun 23 09:27:46 ds dbus[364]: [system] Successfully activated service 'org.freedesktop.ConsoleKit'
Jun 23 09:27:46 ds systemd[1]: Started Console Manager.
Jun 23 09:28:09 ds kernel: [  148.292806] usb-storage 1-1.1:1.0: USB Mass Storage device detected
Jun 23 09:28:09 ds kernel: [  148.293134] scsi host5: usb-storage 1-1.1:1.0
Jun 23 09:28:42 ds kernel: [  181.077011] usb 1-1.1: reset full-speed USB device number 3 using ehci-pci
Jun 23 09:28:58 ds kernel: [  196.425847] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:29:13 ds kernel: [  212.042623] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:29:13 ds kernel: [  212.230684] usb 1-1.1: reset full-speed USB device number 3 using ehci-pci
Jun 23 09:29:29 ds kernel: [  227.660058] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:29:44 ds kernel: [  243.277140] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:29:45 ds kernel: [  243.465152] usb 1-1.1: reset full-speed USB device number 3 using ehci-pci
Jun 23 09:29:50 ds kernel: [  248.576710] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:29:55 ds kernel: [  253.953104] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:29:55 ds kernel: [  254.137771] usb 1-1.1: reset full-speed USB device number 3 using ehci-pci
Jun 23 09:30:00 ds kernel: [  259.329323] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:30:06 ds kernel: [  264.705673] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:30:06 ds kernel: [  264.810758] usb 1-1.1: USB disconnect, device number 3
Jun 23 09:30:06 ds kernel: [  264.890344] usb 1-1.1: new full-speed USB device number 4 using ehci-pci
Jun 23 09:30:22 ds kernel: [  280.399013] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:30:37 ds kernel: [  296.015643] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:30:37 ds kernel: [  296.203964] usb 1-1.1: new full-speed USB device number 5 using ehci-pci
Jun 23 09:30:53 ds kernel: [  311.632257] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:31:08 ds kernel: [  327.248915] usb 1-1.1: device descriptor read/64, error -110
Jun 23 09:31:08 ds kernel: [  327.357042] usb 1-1-port1: attempt power cycle
Jun 23 09:31:09 ds kernel: [  327.960943] usb 1-1.1: new full-speed USB device number 6 using ehci-pci
Jun 23 09:31:14 ds kernel: [  333.060424] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:31:20 ds kernel: [  338.436617] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:31:20 ds kernel: [  338.621323] usb 1-1.1: new full-speed USB device number 7 using ehci-pci
Jun 23 09:31:25 ds kernel: [  343.812798] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:31:30 ds kernel: [  349.189021] usb 1-1.1: device descriptor read/8, error -110
Jun 23 09:31:30 ds kernel: [  349.293955] usb 1-1-port1: unable to enumerate USB device

PS: Die Kamera selbst ist noch intakt, an einem anderen PC lässt sie sich weiterhin mit demselben USB-Kabel verbinden und mounten.

PPS: Sorry vielmals, habe jetzt gerade noch das richtige syslog eingepastet!

[DeletedUserReAsG]

Hast du an dem Rechner einen USB2-Port? Dann könntest du versuchen, das Gerät darüber zu verbinden. (Bezog sich auf den vorher geposteten Logauszug mit xhci).

[whiizy]

Hallo niemand,
ich musste gerade noch das richtige syslog von der betroffenen Maschine einpasten, nachdem du schon geantwortet hattest. Nachträgliche Korrekturen mache ich normalerweise sonst nicht, sorry please.

Was deine Frage angeht, muß ich mal sehen, was die Shuttle-Workstation genau für USB-Ports hat. Ich hatte drei freie USB-Ports probiert, alle mit dem gleichen Fehler (ob alle USB1 oder2 muss ich noch kontrollieren). Den freien, blauen USB3 Port habe ich noch nicht versucht.

PS: Sind USB2 und USB3 Ports:

Code: Alles auswählen

# lsusb
Bus 005 Device 003: ID 05e3:0723 Genesys Logic, Inc. GL827L SD/MMC/MS Flash Card Reader
Bus 005 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 005 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 006 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 004 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 003 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 002: ID 04b4:8081 Cypress Semiconductor Corp. 
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 002 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

[whiizy]

Am USB3-Port crasht die Cam auch, nur als Ergänzung.
Ein Hardware-Defekt der USB-Ports scheidet demnach schonmal aus.

[whiizy]

Erster Teilerfolg!

Ich habe mal gleich nach dem Rechnerstart den grafischen LXDE-Desktop gestoppt (systemctl stop lightdm.service) und nur die Textkonsole benutzt.

Beim Einschalten der Cam hatte ich sofort wieder Zugriff auf ihren Speicher! Auf diese Weise also wieder bestens! ->

Code: Alles auswählen

syslog
Jun 24 17:10:25 ds kernel: [  665.046136] usb 2-1.1: new full-speed USB device number 3 using ehci-pci
Jun 24 17:10:25 ds kernel: [  665.158454] usb 2-1.1: New USB device found, idVendor=07cf, idProduct=1004
Jun 24 17:10:25 ds kernel: [  665.160321] usb 2-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jun 24 17:10:25 ds kernel: [  665.162202] usb 2-1.1: Product: CASIO QV DIGITAL
Jun 24 17:10:25 ds kernel: [  665.163954] usb 2-1.1: Manufacturer: CASIO COMPUTER
Jun 24 17:10:25 ds kernel: [  665.164478] usb-storage 2-1.1:1.0: USB Mass Storage device detected
Jun 24 17:10:25 ds kernel: [  665.166943] scsi host5: usb-storage 2-1.1:1.0
Jun 24 17:10:25 ds mtp-probe: checking bus 2, device 3: "/sys/devices/pci0000:00/0000:00:1a.0/usb2/2-1/2-1.1"
Jun 24 17:10:25 ds mtp-probe: bus: 2, device: 3 was not an MTP device
Jun 24 17:10:26 ds kernel: [  666.191749] scsi 5:0:0:0: Direct-Access     CASIO    DIGITAL_CAMERA   1.00 PQ: 0 ANSI: 0 CCS
Jun 24 17:10:26 ds kernel: [  666.195260] sd 5:0:0:0: Attached scsi generic sg2 type 0
Jun 24 17:10:26 ds kernel: [  666.196359] sd 5:0:0:0: [sdc] 3970048 512-byte logical blocks: (2.03 GB/1.89 GiB)
Jun 24 17:10:26 ds kernel: [  666.197735] sd 5:0:0:0: [sdc] Write Protect is off
Jun 24 17:10:26 ds kernel: [  666.197739] sd 5:0:0:0: [sdc] Mode Sense: 00 46 02 00
Jun 24 17:10:26 ds kernel: [  666.198731] sd 5:0:0:0: [sdc] No Caching mode page found
Jun 24 17:10:26 ds kernel: [  666.198733] sd 5:0:0:0: [sdc] Assuming drive cache: write through
Jun 24 17:10:26 ds kernel: [  666.210251]  sdc: sdc1
Jun 24 17:10:26 ds kernel: [  666.219741] sd 5:0:0:0: [sdc] Attached SCSI removable disk

Wenn ich die Cam jedoch wieder bei laufendem LXDE anschließe, habe ich auch wieder den bemängelten Crash.

Jetzt gilt es herauszufinden, was da von LXDE unter Stretch gestartet wird, daß mir den "Attached scsi generic sg2" verhagelt.

Beim einem ersten flüchtigen Blick ins syslog fallen mir zwei aktivierte systemd services auf, die vom Namen her etwas mit Photographie zu tun haben könnten:

Code: Alles auswählen

[...]
Jun 24 17:19:49 ds dbus-daemon[2662]: Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service'
Jun 24 17:19:49 ds systemd[2645]: Starting Virtual filesystem service - digital camera monitor...
Jun 24 17:19:49 ds dbus-daemon[2662]: Successfully activated service 'org.gnome.Identity'
Jun 24 17:19:49 ds dbus-daemon[2662]: Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
Jun 24 17:19:49 ds systemd[2645]: Started Virtual filesystem service - digital camera monitor.
Jun 24 17:19:49 ds dbus-daemon[2662]: Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service'
Jun 24 17:19:49 ds systemd[2645]: Starting Virtual filesystem service - Media Transfer Protocol monitor...
Jun 24 17:19:49 ds dbus-daemon[2662]: Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
Jun 24 17:19:49 ds systemd[2645]: Started Virtual filesystem service - Media Transfer Protocol monitor.
[...]

Könnte das schon eine richtige Spur sein?

[whiizy]

Es läuft jetzt wieder!

Ich habe im Umfeld von udisks2 und gvfs-backends herumgesucht, da diese mit der Geräteverwaltung auf dem Desktop zu tun haben. Nach der Deinstallation von gvfs-backends ließ sich die Cam auch vom Desktop aus wieder wie gewohnt ansprechen.

Möglicherweise beißen beide Pakete sich oder gvfs-backends hat vielleicht gerade einen bug. Auf einem andere Laptop mit Buster und Gnome habe ich übrigens dasselbe Problem mit der Cam und dort läuft ebenfalls gvfs-backends, was wohl kein Zufall ist. Nur kann man gvfs-backends unter Gnome nicht einfach deinstallieren, da es einem dann Kernkomponenten von gnome mitweghaut.

Eventuell ist mir gvfs-backends mal in LXDE gerutscht, obwohl es normalerweise in Gnome gehört. Mein Filemanager pcmanfm in LXDE hat allerdings ein Recommend auf gvfs-backends, was das Paket aber auch wieder nicht als Fremdkörper erscheinen lässt.

Nun ja.

Zustand ist jetzt jedenfalls wieder:
USB-verbundene Cam einschalten
Pop-up Fenster "Removable medium is inserted"
Please select the action you want to perform
-> Open in FileManager
PCManFM öffnet sich
Das Camera Volume wird als gemountet angezeigt
Kann später wieder ejected werden.

Es wirken dabei folgende Auto-mount Optionen von PCmanFM
"Mount removable media automatically when they are inserted"
"Show available options for removable media when they are inserted"