› ›

Druck bricht nach langer Zeit ab, USB Kommunikation wird getrennt.

Matthias_AurichMatthias_Aurich
in Questions & Answers
Hallo zusammen ich versuche grade etwas mit Druckzeit >24h zu drucken. Leider bricht der Druck immer noch 16-20h ab.

Anbei mal das Logfile:

2021-04-10 14:39:41: Websocket opened
2021-04-10 14:51:04: Websocket: Client closed connection unexpectedly
2021-04-10 22:44:07: Websocket opened
2021-04-10 22:50:33: Websocket: Client closed connection unexpectedly
2021-04-11 08:15:03: error: Reading serial conection failed: End of file. Closing connection.
2021-04-11 08:15:03: Connection closed during print ... trying reconnect for 10 seconds to continue ...
2021-04-11 08:15:03: Port closed for Sidewinder X1
2021-04-11 08:15:03: Connection closed: Sidewinder X1
2021-04-11 08:15:12: Connection continued: Sidewinder X1
2021-04-11 08:16:55: error: Reading serial conection failed: End of file. Closing connection.
2021-04-11 08:16:55: Connection closed during print ... trying reconnect for 10 seconds to continue ...
2021-04-11 08:16:55: Port closed for Sidewinder X1
2021-04-11 08:16:55: Connection closed: Sidewinder X1
2021-04-11 08:17:17: Connection started: Sidewinder X1
2021-04-11 08:17:17: Reset printer Sidewinder X1

Apr 11 08:15:03 RepetierServer kernel: [75582.369126] usb 1-1.2: USB disconnect, device number 3
Apr 11 08:15:03 RepetierServer kernel: [75582.370302] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Apr 11 08:15:03 RepetierServer kernel: [75582.370377] ch341 1-1.2:1.0: device disconnected
Apr 11 08:15:11 RepetierServer kernel: [75590.756812] usb 1-1.2: new full-speed USB device number 4 using xhci_hcd
Apr 11 08:15:11 RepetierServer kernel: [75590.912884] usb 1-1.2: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
Apr 11 08:15:11 RepetierServer kernel: [75590.912902] usb 1-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
Apr 11 08:15:11 RepetierServer kernel: [75590.912916] usb 1-1.2: Product: USB Serial
Apr 11 08:15:11 RepetierServer kernel: [75590.919993] ch341 1-1.2:1.0: ch341-uart converter detected
Apr 11 08:15:11 RepetierServer kernel: [75590.925081] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
Apr 11 08:15:11 RepetierServer mtp-probe: checking bus 1, device 4: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2"
Apr 11 08:15:12 RepetierServer mtp-probe: bus: 1, device: 4 was not an MTP device
Apr 11 08:15:12 RepetierServer mtp-probe: checking bus 1, device 4: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2"
Apr 11 08:15:12 RepetierServer mtp-probe: bus: 1, device: 4 was not an MTP device
Apr 11 08:15:15 RepetierServer wpa_supplicant[387]: RRM: Ignoring radio measurement request: Not RRM network
Apr 11 08:15:21 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2488' (uid=0 pid=21446 comm="timedatectl ")
Apr 11 08:15:21 RepetierServer systemd[1]: Starting Time & Date Service...
Apr 11 08:15:21 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
Apr 11 08:15:21 RepetierServer systemd[1]: Started Time & Date Service.
Apr 11 08:15:29 RepetierServer rngd[401]: stats: bits received from HRNG source: 680064
Apr 11 08:15:29 RepetierServer rngd[401]: stats: bits sent to kernel pool: 633824
Apr 11 08:15:29 RepetierServer rngd[401]: stats: entropy added to kernel pool: 633824
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS 140-2 successes: 34
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS 140-2 failures: 0
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS 140-2(2001-10-10) Poker: 0
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS 140-2(2001-10-10) Runs: 0
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS 140-2(2001-10-10) Long run: 0
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Apr 11 08:15:29 RepetierServer rngd[401]: stats: HRNG source speed: (min=185.125; avg=472.459; max=543.289)Kibits/s
Apr 11 08:15:29 RepetierServer rngd[401]: stats: FIPS tests speed: (min=6.702; avg=13.698; max=31.319)Mibits/s
Apr 11 08:15:29 RepetierServer rngd[401]: stats: Lowest ready-buffers level: 2
Apr 11 08:15:29 RepetierServer rngd[401]: stats: Entropy starvations: 0
Apr 11 08:15:29 RepetierServer rngd[401]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Apr 11 08:15:51 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
Apr 11 08:16:22 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2490' (uid=0 pid=21587 comm="timedatectl ")
Apr 11 08:16:22 RepetierServer systemd[1]: Starting Time & Date Service...
Apr 11 08:16:22 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
Apr 11 08:16:22 RepetierServer systemd[1]: Started Time & Date Service.
Apr 11 08:16:52 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
Apr 11 08:16:55 RepetierServer kernel: [75694.663676] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
Apr 11 08:16:55 RepetierServer kernel: [75694.669265] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
Apr 11 08:16:55 RepetierServer kernel: [75694.864807] usb 1-1.2: USB disconnect, device number 4
Apr 11 08:16:55 RepetierServer kernel: [75694.865869] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Apr 11 08:16:55 RepetierServer kernel: [75694.865935] ch341 1-1.2:1.0: device disconnected
Apr 11 08:17:01 RepetierServer CRON[21635]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Apr 11 08:17:16 RepetierServer kernel: [75715.208386] usb 1-1.2: new full-speed USB device number 5 using xhci_hcd
Apr 11 08:17:16 RepetierServer kernel: [75715.344554] usb 1-1.2: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
Apr 11 08:17:16 RepetierServer kernel: [75715.344573] usb 1-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
Apr 11 08:17:16 RepetierServer kernel: [75715.344589] usb 1-1.2: Product: USB Serial
Apr 11 08:17:16 RepetierServer kernel: [75715.351899] ch341 1-1.2:1.0: ch341-uart converter detected
Apr 11 08:17:16 RepetierServer kernel: [75715.365422] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
Apr 11 08:17:16 RepetierServer mtp-probe: checking bus 1, device 5: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2"
Apr 11 08:17:16 RepetierServer mtp-probe: bus: 1, device: 5 was not an MTP device
Apr 11 08:17:16 RepetierServer mtp-probe: checking bus 1, device 5: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2"
Apr 11 08:17:16 RepetierServer mtp-probe: bus: 1, device: 5 was not an MTP device

Woran kann dies liegen? Mir ist klar das es an der Verbindung zwischen PI und Drucker liegt aber warum?

Drucker ist der Sidewinder X1 mit der neusten Marlin 2.0.5.1 vom Pinguinpfleger, Repetier löauft auf einen PI4 mit 4GB neuste Version.

Das ganze ist nun schon 3 mal passiert.

Freu mich auf eure Hilfe.

Comments

  • RepetierRepetier
    2021-04-11 08:15:03: error: Reading serial conection failed: End of file. Closing connection.
    Drucker wurde vom Betriebssystem getrennt. Syslog fängt eventuell etwas spät an, da steht
    Apr 11 08:15:03 RepetierServer kernel: [75582.369126] usb 1-1.2: USB disconnect, device number 3
    davor steht vielleicht noch ein grund.

    Später steht:
    Apr 11 08:16:55 RepetierServer kernel: [75694.663676] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
    ist ein Fehler im Treiber unter linux. Wenn dieser passiert geht nichts mehr und der drucker wird zwangsgetrennt.

    Der CH341 hat so seine eigentheiten was zu einigen optionen im server geführt hat. Hab mir sogar selbst dahe reinen Sidewinder X1 gekauft, aber der läuft absolut fehlerfrei Mit der original Firmware und dem original USB Kabel an eine Pi 4.
    Zuerst die Frage ob server Unterspannung angezeigt hat im Blitz-Menu. Wenn das passiert ist alles möglich und das sollte zuerst abgeschafft werden. Und bitte nicht denken nur weil man ein 3A Netzteil hat ist das kein Problem - ist es wenn das Gerät den Strom nicht schnell genug nachregeln kann oder Kabel zu dünn sind. Wichtig ist was Linux denkt, weil linux im Bedarfsfall usb Geräte trennt.

    Bitte möglichst kurze USB Kabel möglichst geschirmt nutzen. Je länger dest wahrscheinlicher treten Fehler auf und da scheint es Fälle zu geben die den Treiber überfordern.

    Im server automatische Rettungen aktivieren, so dass er versucht den Druck mit neu verbinden zu retten. Hier mal meine Einstellung, aber wie gesagt für original firmware. Sollte aber auch mit Marlin 2 gehen.

    https://cln.sh/sGf9HR

    Zum test bei einem Testdruck usb Kabel kurz ausziehen und wieder einstecken. Muss innerhalb von 10 Sekunden erfolgen. Wenn er normal Weiterdruckt klappt das Drucken nach schneller Neuverbindung immerhin. Ist für den Fall gedacht wenn linux eine Verbindung stoppt die kurz danach neu gestartet wird. Wir versuchen dann ohne reset neu zu verbinden und machen weiter wo wir aufgehört haben. Das mit dem USB Reconnect trennt usb um das zu simmulieren. Ist dazu gedacht wenn der Treiber noch läuft aber abgestürzt ist. Dadurch wird er neu gestartet.
  • Matthias_AurichMatthias_Aurich
    Repetier said:
    2021-04-11 08:15:03: error: Reading serial conection failed: End of file. Closing connection.
    Drucker wurde vom Betriebssystem getrennt. Syslog fängt eventuell etwas spät an, da steht
    Apr 11 08:15:03 RepetierServer kernel: [75582.369126] usb 1-1.2: USB disconnect, device number 3
    davor steht vielleicht noch ein grund.
    Viel steht dort nicht davor:

    Apr 11 08:04:06 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2466' (uid=0 pid=20343 comm="timedatectl ")
    Apr 11 08:04:06 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:04:06 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:04:06 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:04:36 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:05:07 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2468' (uid=0 pid=20432 comm="timedatectl ")
    Apr 11 08:05:07 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:05:07 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:05:07 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:05:37 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:06:08 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2470' (uid=0 pid=20529 comm="timedatectl ")
    Apr 11 08:06:08 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:06:08 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:06:08 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:06:38 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:07:10 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2472' (uid=0 pid=20655 comm="timedatectl ")
    Apr 11 08:07:10 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:07:10 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:07:10 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:07:40 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:08:11 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2474' (uid=0 pid=20748 comm="timedatectl ")
    Apr 11 08:08:11 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:08:11 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:08:11 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:08:41 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:09:12 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2476' (uid=0 pid=20837 comm="timedatectl ")
    Apr 11 08:09:12 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:09:13 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:09:13 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:09:43 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:10:14 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2478' (uid=0 pid=20929 comm="timedatectl ")
    Apr 11 08:10:14 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:10:14 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:10:14 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:10:44 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:11:15 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2480' (uid=0 pid=21021 comm="timedatectl ")
    Apr 11 08:11:15 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:11:15 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:11:15 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:11:45 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:12:11 RepetierServer wpa_supplicant[387]: RRM: Ignoring radio measurement request: Not RRM network
    Apr 11 08:12:17 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2482' (uid=0 pid=21116 comm="timedatectl ")
    Apr 11 08:12:17 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:12:17 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:12:17 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:12:47 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:13:18 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2484' (uid=0 pid=21212 comm="timedatectl ")
    Apr 11 08:13:18 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:13:18 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:13:18 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:13:48 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:14:19 RepetierServer dbus-daemon[379]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.2486' (uid=0 pid=21306 comm="timedatectl ")
    Apr 11 08:14:19 RepetierServer systemd[1]: Starting Time & Date Service...
    Apr 11 08:14:19 RepetierServer dbus-daemon[379]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Apr 11 08:14:19 RepetierServer systemd[1]: Started Time & Date Service.
    Apr 11 08:14:49 RepetierServer systemd[1]: systemd-timedated.service: Succeeded.
    Apr 11 08:15:03 RepetierServer kernel: [75582.369126] usb 1-1.2: USB disconnect, device number 3
    Apr 11 08:15:03 RepetierServer kernel: [75582.370302] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
    Apr 11 08:15:03 RepetierServer kernel: [75582.370377] ch341 1-1.2:1.0: device disconnected
    Apr 11 08:15:11 RepetierServer kernel: [75590.756812] usb 1-1.2: new full-speed USB device number 4 using xhci_hcd
    Apr 11 08:15:11 RepetierServer kernel: [75590.912884] usb 1-1.2: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
    Apr 11 08:15:11 RepetierServer kernel: [75590.912902] usb 1-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    Apr 11 08:15:11 RepetierServer kernel: [75590.912916] usb 1-1.2: Product: USB Serial
    Apr 11 08:15:11 RepetierServer kernel: [75590.919993] ch341 1-1.2:1.0: ch341-uart converter detected
    Apr 11 08:15:11 RepetierServer kernel: [75590.925081] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
    Apr 11 08:15:11 RepetierServer mtp-probe: checking bus 1, device 4: "/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2"

    Repetier said:

    Zuerst die Frage ob server Unterspannung angezeigt hat im Blitz-Menu. Wenn das passiert ist alles möglich und das sollte zuerst abgeschafft werden. Und bitte nicht denken nur weil man ein 3A Netzteil hat ist das kein Problem - ist es wenn das Gerät den Strom nicht schnell genug nachregeln kann oder Kabel zu dünn sind. Wichtig ist was Linux denkt, weil linux im Bedarfsfall usb Geräte trennt.

    Bitte möglichst kurze USB Kabel möglichst geschirmt nutzen. Je länger dest wahrscheinlicher treten Fehler auf und da scheint es Fälle zu geben die den Treiber überfordern.

    Im server automatische Rettungen aktivieren, so dass er versucht den Druck mit neu verbinden zu retten. Hier mal meine Einstellung, aber wie gesagt für original firmware. Sollte aber auch mit Marlin 2 gehen.

    https://cln.sh/sGf9HR

    Zum test bei einem Testdruck usb Kabel kurz ausziehen und wieder einstecken. Muss innerhalb von 10 Sekunden erfolgen. Wenn er normal Weiterdruckt klappt das Drucken nach schneller Neuverbindung immerhin. Ist für den Fall gedacht wenn linux eine Verbindung stoppt die kurz danach neu gestartet wird. Wir versuchen dann ohne reset neu zu verbinden und machen weiter wo wir aufgehört haben. Das mit dem USB Reconnect trennt usb um das zu simmulieren. Ist dazu gedacht wenn der Treiber noch läuft aber abgestürzt ist. Dadurch wird er neu gestartet.
    Unterspannung wurde nicht angezeigt, ich werde nun noch mal das USB Kabel tauschen und das automatische Rettung aktivieren.

    Ich melde mich wieder wenn dies nicht hilft.

    Danke für die schnelle Hilfe.
  • RepetierRepetier
    Aktivier auch das loggen von Drucken. Da steht zumindest drin wenn der Server wegen Kommunikationsprblemen die Verbindung trennt, also die USB Trennung ausführt.

    Im syslog steht echt nichts weiter drin warum usb getrennt wurde.
Sign In or Register to comment.