›

Nach Update auf 1.0.4 Verbindungsabbrüche

CyrgaCyrga
in Repetier-Server
Hallo, 

in der Version 0.94 hatte ich seinerzeit mit Verbindungsabbrüchen zu meinem Drucker (Sidewinder X1 mit originalem Board, Marlin FW 1.1.9) zu kämpfen. In 1.0.3 funktionierte es wieder. Seit dem Update auf 1.0.4 bricht wieder die Verbindung nach einer unbestimmten Zeit ab. Nach dem Update habe ich nochmals einen Konfigurationstest der Verbindung durchlaufen lassen. Bis auf das letzte Ergebnis, denke das waren lange Befehle, waren alle mit einer Wiederholungsrate von 0,0% gekennzeichnet. Derzeit nutze ich einen Buffer von 63, ohne Ping-Pong Modus. Auch mit Ping-Pong oder mit einem Buffer von 127 kann ich das Problem nicht lösen. Umgesteckt habe ich das USB Kabel auch und als Port nutze ich gerade 

/dev/serial/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.2:1.0-port0

Testweise habe ich jetzt 6 STLs geprüft und das Problem tritt bei allen auf. Hier ist mir aber aufgefallen, das einfachere Modelle länger gedruckt werden als komplexere.

Der Drucklog sieht so aus:
Send: 9:01:15.370: N978181 G1 X143.68 Y62.143 E283.3662
Recv: 9:01:15.426: ok
Send: 9:01:15.427: N978182 G1 X143.741 Y64.025 E283.4148
Mesg: 9:01:15.595: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
Mesg: 9:01:16.202: Dtr: true Rts: true
Mesg: 9:01:16.207: Connection continued
Send: 9:01:16.218: N978183 G1 X143.682 Y65.023 E283.4406
Mesg: 9:01:20.225: Warning: Communication timeout - resetting communication buffer.
Mesg: 9:01:20.225: Connection status: Buffered:41, Manual Commands: 0, Job Commands: 5000
Mesg: 9:01:20.226: Buffer used:41 Enforced free byte:43 lines stored:1
Send: 9:01:20.226: N978184 G1 X143.567 Y66.862 E283.48816
Mesg: 9:01:23.218: Reconnecting usb port to fix serial driver problems ...
Mesg: 9:01:23.323: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
Mesg: 9:01:23.632: Dtr: true Rts: true
Mesg: 9:01:23.634: Connection continued
Send: 9:01:23.645: M117 ETA 09:08:25 day 21
Mesg: 9:01:27.654: Warning: Communication timeout - resetting communication buffer.
Mesg: 9:01:27.654: Connection status: Buffered:25, Manual Commands: 0, Job Commands: 5000
Mesg: 9:01:27.654: Buffer used:25 Enforced free byte:42 lines stored:1
Send: 9:01:27.655: N978185 G1 X143.407 Y67.892 E283.51506
Mesg: 9:01:30.653: Reconnecting usb port to fix serial driver problems ...
Mesg: 9:01:30.747: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
Mesg: 9:01:31.062: Dtr: true Rts: true
Mesg: 9:01:31.068: Connection continued
Send: 9:01:31.080: N978186 G1 X143.116 Y69.709 E283.56256
Mesg: 9:01:35.083: Warning: Communication timeout - resetting communication buffer.
Mesg: 9:01:35.083: Connection status: Buffered:42, Manual Commands: 1, Job Commands: 5000
Mesg: 9:01:35.083: Buffer used:42 Enforced free byte:41 lines stored:1
Send: 9:01:35.083: M117 ETE 00:07:03
Mesg: 9:01:38.075: Reconnecting usb port to fix serial driver problems ...
Mesg: 9:01:38.176: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
Mesg: 9:01:38.484: Dtr: true Rts: true
Mesg: 9:01:38.489: Connection continued
Send: 9:01:38.501: N978187 G1 X142.5 Y72.098 E283.62623

Das ist der Serverlog:
2021-03-21 08:03:32: Websocket opened
2021-03-21 08:03:45: Connection started: SX1 - 0.8
2021-03-21 08:03:45: Reset printer SX1 - 0.8
2021-03-21 08:03:59: Job created: /var/lib/Repetier-Server/printer/SX1__11/jobs/00000001_CFFFP_NeckBolt.u
2021-03-21 08:03:59: finish job creation /var/lib/Repetier-Server/printer/SX1__11/jobs/00000001_CFFFP_NeckBolt.u
2021-03-21 08:03:59: start printjob CFFFP_NeckBolt on printer SX1 - 0.8
2021-03-21 09:01:15: error: Reading serial conection failed: End of file. Closing connection.
2021-03-21 09:01:15: Connection closed during print ... trying reconnect for 10 seconds to continue ...
2021-03-21 09:01:15: Port closed for SX1 - 0.4
2021-03-21 09:01:15: Connection closed: SX1 - 0.4
2021-03-21 09:01:16: Connection continued: SX1 - 0.4
2021-03-21 09:01:23: usbreset: /usr/bin/sudo /usr/local/Repetier-Server/bin/usbreset /dev/serial/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.2:1.0-port0
2021-03-21 09:01:23: error: Reading serial conection failed: End of file. Closing connection.
2021-03-21 09:01:23: Connection closed during print ... trying reconnect for 10 seconds to continue ...
2021-03-21 09:01:23: Port closed for SX1 - 0.4
2021-03-21 09:01:23: Connection closed: SX1 - 0.4
2021-03-21 09:01:23: Connection continued: SX1 - 0.4
2021-03-21 09:01:30: usbreset: /usr/bin/sudo /usr/local/Repetier-Server/bin/usbreset /dev/serial/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.2:1.0-port0
2021-03-21 09:01:30: error: Reading serial conection failed: End of file. Closing connection.
2021-03-21 09:01:30: Connection closed during print ... trying reconnect for 10 seconds to continue ...
2021-03-21 09:01:30: Port closed for SX1 - 0.4
2021-03-21 09:01:30: Connection closed: SX1 - 0.4
2021-03-21 09:01:31: Connection continued: SX1 - 0.4
2021-03-21 09:01:38: usbreset: /usr/bin/sudo /usr/local/Repetier-Server/bin/usbreset /dev/serial/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.2:1.0-port0
2021-03-21 09:01:38: error: Reading serial conection failed: End of file. Closing connection.
2021-03-21 09:01:38: Connection closed during print ... trying reconnect for 10 seconds to continue ...

und der Syslog:
Mar 21 09:01:15 Repetier-Server kernel: [81933.832500] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
Mar 21 09:01:15 Repetier-Server kernel: [81933.832987] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
Mar 21 09:01:15 Repetier-Server kernel: [81933.980777] usb 1-1.2: USB disconnect, device number 3
Mar 21 09:01:15 Repetier-Server kernel: [81933.982140] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 21 09:01:15 Repetier-Server kernel: [81933.982214] ch341 1-1.2:1.0: device disconnected
Mar 21 09:01:15 Repetier-Server kernel: [81934.337986] usb 1-1.2: new full-speed USB device number 5 using xhci_hcd
Mar 21 09:01:16 Repetier-Server kernel: [81934.483971] usb 1-1.2: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
Mar 21 09:01:16 Repetier-Server kernel: [81934.483987] usb 1-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
Mar 21 09:01:16 Repetier-Server kernel: [81934.484000] usb 1-1.2: Product: USB Serial
Mar 21 09:01:16 Repetier-Server kernel: [81934.490760] ch341 1-1.2:1.0: ch341-uart converter detected
Mar 21 09:01:16 Repetier-Server kernel: [81934.498193] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
Mar 21 09:01:16 Repetier-Server 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"
Mar 21 09:01:16 Repetier-Server mtp-probe: bus: 1, device: 5 was not an MTP device
Mar 21 09:01:16 Repetier-Server 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"
Mar 21 09:01:16 Repetier-Server mtp-probe: bus: 1, device: 5 was not an MTP device
Mar 21 09:01:23 Repetier-Server kernel: [81941.721038] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 21 09:01:23 Repetier-Server kernel: [81941.721117] ch341 1-1.2:1.0: device disconnected
Mar 21 09:01:23 Repetier-Server kernel: [81941.828421] usb 1-1.2: reset full-speed USB device number 5 using xhci_hcd
Mar 21 09:01:23 Repetier-Server kernel: [81941.963597] ch341 1-1.2:1.0: ch341-uart converter detected
Mar 21 09:01:23 Repetier-Server kernel: [81941.967130] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
Mar 21 09:01:30 Repetier-Server kernel: [81949.141005] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 21 09:01:30 Repetier-Server kernel: [81949.141084] ch341 1-1.2:1.0: device disconnected
Mar 21 09:01:30 Repetier-Server kernel: [81949.248556] usb 1-1.2: reset full-speed USB device number 5 using xhci_hcd
Mar 21 09:01:30 Repetier-Server kernel: [81949.393892] ch341 1-1.2:1.0: ch341-uart converter detected
Mar 21 09:01:31 Repetier-Server kernel: [81949.401566] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
Mar 21 09:01:33 Repetier-Server systemd[1]: systemd-timedated.service: Succeeded.
Mar 21 09:01:38 Repetier-Server kernel: [81956.562490] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 21 09:01:38 Repetier-Server kernel: [81956.562566] ch341 1-1.2:1.0: device disconnected
Mar 21 09:01:38 Repetier-Server kernel: [81956.668708] usb 1-1.2: reset full-speed USB device number 5 using xhci_hcd
Mar 21 09:01:38 Repetier-Server kernel: [81956.813931] ch341 1-1.2:1.0: ch341-uart converter detected
Mar 21 09:01:38 Repetier-Server kernel: [81956.822183] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
Mar 21 09:01:45 Repetier-Server kernel: [81963.992851] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 21 09:01:45 Repetier-Server kernel: [81963.993768] ch341 1-1.2:1.0: device disconnected
Mar 21 09:01:45 Repetier-Server kernel: [81964.098795] usb 1-1.2: reset full-speed USB device number 5 using xhci_hcd
Mar 21 09:01:45 Repetier-Server kernel: [81964.244005] ch341 1-1.2:1.0: ch341-uart converter detected
Mar 21 09:01:45 Repetier-Server kernel: [81964.255608] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
Mar 21 09:01:53 Repetier-Server kernel: [81971.409377] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 21 09:01:53 Repetier-Server kernel: [81971.409440] ch341 1-1.2:1.0: device disconnected

Comments

  • RepetierRepetier
    Zufällig hab ich einen Sidewinder X1 mit original Firmware als Testdrucker und der hat schon 40 Stunden Problemlos gedruckt. Du scheint aber sehr aggressiv usb neu zu verbinden - also bei jedem timeout. Hier mal meine Einstellungen, die bisher super funktionieren:

    https://cln.sh/1l2rhh


  • CyrgaCyrga
    Repetier said:
    Zufällig hab ich einen Sidewinder X1 mit original Firmware als Testdrucker und der hat schon 40 Stunden Problemlos gedruckt. Du scheint aber sehr aggressiv usb neu zu verbinden - also bei jedem timeout. Hier mal meine Einstellungen, die bisher super funktionieren:

    https://cln.sh/1l2rhh


    Ich habe die Einstellungen mal übernommen und hilft leider auch nicht. Hier gab es schon nach ca 1,5 Std. den Abbruch.

    Send:12:40:21.042: N30783 G1 X108.62 Y91.7 E1643.77871
    Mesg:12:40:21.145: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
    Mesg:12:40:21.853: Dtr: true Rts: true
    Mesg:12:40:21.855: Connection continued
    Send:12:40:21.866: N30784 G1 X108.935 Y91.156 E1643.79493
    Send:12:40:21.867: N30785 G1 X109.988 Y89.9 E1643.83724
    Mesg:12:40:25.868: Warning: Communication timeout - resetting communication buffer.
    Mesg:12:40:25.868: Connection status: Buffered:84, Manual Commands: 0, Job Commands: 5000
    Mesg:12:40:25.868: Buffer used:84 Enforced free byte:43 lines stored:2
    Send:12:40:25.869: N30786 G1 X110.736 Y89.013 E1643.86718
    Send:12:40:25.869: N30787 G1 X110.773 Y88.985 E1643.86838
    Mesg:12:40:29.872: Warning: Communication timeout - resetting communication buffer.
    Mesg:12:40:29.872: Connection status: Buffered:85, Manual Commands: 0, Job Commands: 5000
    Mesg:12:40:29.872: Buffer used:85 Enforced free byte:41 lines stored:2
    Send:12:40:29.872: N30788 G1 X111.52 Y88.412 E1643.89268
    Send:12:40:29.873: N30789 G1 X112.698 Y87.478 E1643.93148
    Mesg:12:40:32.864: Reconnecting usb port to fix serial driver problems ...
    Mesg:12:40:32.982: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
    Mesg:12:40:33.288: Dtr: true Rts: true
    Mesg:12:40:33.291: Connection continued

    2021-03-21 13:00:09: Connection closed: SX1 - 0.4
    2021-03-21 13:00:09: Connection continued: SX1 - 0.4
    2021-03-21 13:00:20: usbreset: /usr/bin/sudo /usr/local/Repetier-Server/bin/usbreset /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0
    2021-03-21 13:00:20: error: Reading serial conection failed: End of file. Closing connection.
    2021-03-21 13:00:20: Connection closed during print ... trying reconnect for 10 seconds to continue ...
    2021-03-21 13:00:20: Port closed for SX1 - 0.4
    2021-03-21 13:00:20: Connection closed: SX1 - 0.4
    2021-03-21 13:00:21: Connection continued: SX1 - 0.4
    2021-03-21 13:00:32: usbreset: /usr/bin/sudo /usr/local/Repetier-Server/bin/usbreset /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0
    2021-03-21 13:00:32: error: Reading serial conection failed: End of file. Closing connection.
    2021-03-21 13:00:32: Connection closed during print ... trying reconnect for 10 seconds to continue ...
    2021-03-21 13:00:32: Port closed for SX1 - 0.4
    2021-03-21 13:00:32: Connection closed: SX1 - 0.4
    2021-03-21 13:00:32: Connection continued: SX1 - 0.4
    2021-03-21 13:00:43: usbreset: /usr/bin/sudo /usr/local/Repetier-Server/bin/usbreset /dev/serial/by-id/usb-1a86_USB_Serial-if00-port0
    2021-03-21 13:00:43: error: Reading serial conection failed: End of file. Closing connection.
    2021-03-21 13:00:43: Connection closed during print ... trying reconnect for 10 seconds to continue ...
    2021-03-21 13:00:43: Port closed for SX1 - 0.4
    2021-03-21 13:00:43: Connection closed: SX1 - 0.4
    2021-03-21 13:00:43: Connection continued: SX1 - 0.4

    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.334834] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.335421] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.377768] usb 1-1.2: USB disconnect, device number 3
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.379424] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.379518] ch341 1-1.2:1.0: device disconnected
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.826555] usb 1-1.2: new full-speed USB device number 4 using xhci_hcd
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.962713] usb 1-1.2: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.962730] usb 1-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.962742] usb 1-1.2: Product: USB Serial
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.967419] ch341 1-1.2:1.0: ch341-uart converter detected
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.972886] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
    Mar 21 12:40:21 Repetier-Server 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"
    Mar 21 12:40:21 Repetier-Server mtp-probe: bus: 1, device: 4 was not an MTP device
    Mar 21 12:40:21 Repetier-Server 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"
    Mar 21 12:40:21 Repetier-Server mtp-probe: bus: 1, device: 4 was not an MTP device
    Mar 21 12:40:32 Repetier-Server kernel: [ 3122.219382] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
    Mar 21 12:40:32 Repetier-Server kernel: [ 3122.219451] ch341 1-1.2:1.0: device disconnected
    Mar 21 12:40:33 Repetier-Server kernel: [ 3122.317183] usb 1-1.2: reset full-speed USB device number 4 using xhci_hcd
    Mar 21 12:40:33 Repetier-Server kernel: [ 3122.462887] ch341 1-1.2:1.0: ch341-uart converter detected
    Mar 21 12:40:33 Repetier-Server kernel: [ 3122.468084] usb 1-1.2: ch341-uart converter now attached to ttyUSB0
    Mar 21 12:40:39 Repetier-Server systemd[1]: systemd-timedated.service: Succeeded.
    Mar 21 12:40:44 Repetier-Server kernel: [ 3133.629084] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
  • RepetierRepetier
    Mesg:12:40:21.145: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...

    shows linux did disconnect serial. This happens if this error appears:
    Mar 21 12:40:21 Repetier-Server kernel: [ 3110.334834] ch341-uart ttyUSB0: usb_serial_generic_read_bulk_callback - urb stopped: -32

    So the initial problem is not inside the server. Unfortunately CH341 is known to be a bit troublesome when there are some errors on the line I guess. This can go that far that communication complete blocks due to crashed driver as I think. That is why I programmed the usb reset to restart the driver.

    Would be great if you can provide the log when it happens. What you paste are most important messages in console, but that is only a fraction of what is going on. And there the problem is more visible.

    I'm wondering where 
    Mar 21 12:40:21 Repetier-Server mtp-probe: bus: 1, device: 4 was not an MTP device
    comes from. There is a program that tries to connect to every serial device on appeareance and will break server connect for first 10-20 seconds. So please check if modemmanager is installed. If so uninstall it
    sudo apt remove modemmanager

    The executeable is I think ModemManager with big M. Or just try uninstalling it. On newer images we have removed it by default since we have found that problem.
Sign In or Register to comment.