Drucker wird nach Kaltstart nicht wieder erkannt (seit 1.4.4?)
Hallo liebe Leute,
seit dem Update auf 1.4.4 und der Aktualisierung von Raspberry Pi OS wird der Drucker nicht mehr erkannt zu werden, wenn er aus war. Das hat vor den Updates immer wunderbar geklappt.
Repetier-Server Pro 1.4.4
Raspberry Pi 3B
Paspberry Pi OS Buster (latest Updates installed)
Rencforce RF1000 (FW 1.42)
Die Ports sind immer zu sehen, ist in der Repetier-Server-Konfig auch so konfiguriert.
/dev/serial/by-id/usb-FTDI_FT232R_USB_UART_AL00IL8N-if00-port0
/dev/serial/by-path/platform-3f980000.usb-usb-0:1.3:1.0-port0USB-Stecker raus und wieder rein hilft nicht.
Ein anderes Kabel unglücklicherweise auch nicht.
Systemfehlermeldung alle 11 Sekunden (ca.):
error: Reading serial conection failed: no data on read.Operation canceled
Könnte ein Update hier etwas zu Ungunsten verändert haben?
Was könnte / sollte ich noch prüfen?
Vielen Dank und alles Gute!
printmad
Comments
Siehst du das der Drucker neu startet (display) wenn Repetier-Server versucht zu verbinden?
Mesg:18:55:01.014: Printer reset requested false
Mesg:18:55:01.014: Dtr: false Rts: false
Mesg:18:55:01.036: Dtr: true Rts: true
Recv:18:55:04.038: Send init commands because we had no signal from a reset, assuming reset not available.
Mesg:18:55:12.263: Dtr: true Rts: true
Mesg:18:55:12.265: Printer reset requested false
Mesg:18:55:12.265: Dtr: false Rts: false
Mesg:18:55:12.286: Dtr: true Rts: true
Recv:18:55:15.290: Send init commands because we had no signal from a reset, assuming reset not available.
Mesg:18:55:23.629: Dtr: true Rts: true
Mesg:18:55:23.631: Printer reset requested false
Mesg:18:55:23.631: Dtr: false Rts: false
Mesg:18:55:23.652: Dtr: true Rts: true
M104 S0 Set Extruder Temperature=0
M140 S0 Set Bed Temperature=0
G91 Set to Relative Positioning
G1 E-5 F1800 Extrude=-5 Feedrate=1800
M400 Wait for current moves to finish
M3079 Output Object
M400 Wait for current moves to finish
M84 Stop idle hold (stop motors)
M3000 Turn Z-Compensation off
M106 S255 Fan on, maximum speed (255)
M109 S45 T0 Set Extruder Temperature 45° and wait
;@syncMotion ; Repetier Server: Wait for current moves to finish
;@execute Toggle_SWP4 Repetier Server: Switch Printer off (swp4)
2022-11-28 19:32:38: Executed webAction RF1000_schalten (ausgeschaltet)
2022-11-28 19:32:54: Connection reset: RF1000
2022-11-28 19:53:11: Executed webAction RF1000_schalten (wieder eingeschaltet)
2022-11-28 20:00:24: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:00:24: Port closed for RF1000
2022-11-28 20:00:36: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:00:47: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:00:58: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:01:10: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:01:21: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:01:33: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:01:45: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:01:57: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:02:09: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:02:21: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:02:33: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 20:02:44: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-28 22:05:58: Stopping for signal 15
2022-11-28 22:05:58: Stopping MQTT subsystem ...
2022-11-28 22:05:58: Stopping lua runner ...
2022-11-28 22:05:58: Stopping global cloud ...
2022-11-28 22:05:58: Signal to terminate process received.
2022-11-28 22:05:58: Stopping open threads ...
2022-11-28 22:05:58: Shutting down web server.
2022-11-28 22:05:58: error:WifiManager::scanNetworks:No permission
2022-11-28 22:05:59: Closing server
2022-11-28 22:05:59: Stopping work dispatcher.
2022-11-28 22:05:59: Work dispatcher thread stopped.
2022-11-28 22:05:59: Internal Work dispatcher thread stopped.
2022-11-28 22:05:59: Waiting for all threads to finish ...
2022-11-28 22:05:59: Worker threads finsihed.
2022-11-28 22:05:59: Stopping printer threads.
2022-11-28 22:05:59: Printer thread for RF1000 stopped
2022-11-28 22:05:59: Printjob manager thread for RF1000 stopped.
2022-11-28 22:05:59: Closing user database.
2022-11-28 22:05:59: Server is now down.
2022-11-28 22:05:59: Start logging...
2022-11-28 22:05:59: Repetier-Server 1.4.4
2022-11-28 22:05:59: Imported external command Toggle SWP4
2022-11-28 22:05:59: Imported external command Reboot Server
2022-11-28 22:05:59: Imported external command Shutdown Server
2022-11-28 22:05:59: Imported allowed execute command Toggle_SWP4
2022-11-28 22:05:59: Webdirectory: /usr/local/Repetier-Server/www/
2022-11-28 22:05:59: Storage directory: /var/lib/Repetier-Server/
2022-11-28 22:05:59: Configuration file: /usr/local/Repetier-Server/etc/RepetierServer.xml
2022-11-28 22:05:59: Directory for temporary files: /tmp/
2022-11-28 22:05:59: Reading firmware data ...
2022-11-28 22:05:59: Starting Network ...
2022-11-28 22:05:59: Active features:4095
2022-11-28 22:05:59: Reading printer configurations ...
2022-11-28 22:05:59: Reading printer config /var/lib/Repetier-Server/configs/RF1000.xml
2022-11-28 22:05:59: Reloading Timelapse Entries...
2022-11-28 22:05:59: Starting printjob manager thread for RF1000
2022-11-28 22:06:00: Starting printer threads ...
2022-11-28 22:06:00: Starting printer thread for RF1000
2022-11-28 22:06:00: Starting work dispatcher subsystem ...
2022-11-28 22:06:00: Starting user database ...
2022-11-28 22:06:00: Importing projects ...
2022-11-28 22:06:00: Importing wifi connections from /var/lib/Repetier-Server/database/repetier-network.xml
2022-11-28 22:06:00: Importing wifi connections from /var/lib/Repetier-Server/database/repetier-network-stored.xml
2022-11-28 22:06:00: Initializing LUA ...
2022-11-28 22:06:00: Starting wifi watcher ...
2022-11-28 22:06:00: Register LUA cloud services
2022-11-28 22:06:00: add G-Code-Renderer
2022-11-28 22:06:00: LUA initalization finished.
2022-11-28 22:06:00: Setting up MQTT Client ...
2022-11-28 22:06:00: Work dispatcher thread started.
2022-11-28 22:06:00: Starting web server ...
2022-11-28 22:06:00: Internal work dispatcher thread started.
2022-11-28 22:06:00: Webserver started.
2022-11-28 22:06:00: Websocket opened
2022-11-28 22:06:02: Connection started: RF1000
2022-11-28 22:06:02: Connection reset: RF1000
2022-11-28 22:06:03: Websocket opened
2022-11-28 22:06:05: New SSID Not Connected
M84 Stop idle hold (stop motors)
da fehlen kommentarzeich vor den Kommentaren. muss so aussehen:
M84 ; Stop idle hold (stop motors)
Hoffe da sist beim kopieren verloren gegangen, wer weiß was sonst draus wird.
Ansonsten ist das etwas rätselhaft aber auch schwer zu analysieren, da du die befehle und ack gefiltert hast und daher nicht alles sichtbar ist. Wenn logging aktiv ist steht das log beim verbinden in connected.log (logs Fenster).
So ist es korrekt:
Hier wird dtr gewechselt um ein reset zu erzeugen und an dem "start" sieht man das der Drucke rneu gestartet hat und nicht nur verbindet. Das ist das korrekte verhalten für deinen Drucker würde ich sagen. Man sieht auch das es ca. 1s dauert bis die Firmware reagiert, was normal ist.
Bei deinen hängern:
Mesg:18:55:01.010: Dtr: true Rts: true
Mesg:18:55:01.014: Printer reset requested false
Mesg:18:55:01.014: Dtr: false Rts: false
Mesg:18:55:01.036: Dtr: true Rts: true
Recv:18:55:04.038: Send init commands because we had no signal from a reset, assuming reset not available.
Wird reset ausgelöst (must du mal gegenprüfen, sieht man ja am drucker lcd ob er reagiert). Nach 3 Sekunden wurde kein "start" gesehen was ja schon falsch ist, aber es wird nicht aufgegeben sondern einfach auf verdacht Befehle gesendet - einfach weil nicht jeder Drucker ein Reset macht und diese damit abgefangen werden.
Aber auch da kommt offenbar nichts bis er aufgibt und dann neu probiert.
Wie viele Drucker hast du an dem Pi im Dashboard? Wenn du zwei mit gleichem Port nutzt kann es zu solchen problemen kommen, aber ich sehe nur RF1000 im server.log. Laufen sonst noch Programme die serielle Schnittstelle nutzen? Bei unserem image normal eher nicht außer evtl Modemmanager der war mal bei alten images drauf. Chech mal ob unter ssh mit
sudo apt-get remove modemmanager
er versucht das Paket zu deinstallieren. Sobald eine serielle schnittstelle erscheint versucht er sich damit zu verbinden falls es ein modem ist, was den drucker und server irritieren kann.
Wenn er in eine rschleife steckt versuch mal am drucker Reset zu drücken (falls er eine Reset Taste hat). Das unterbricht so eine schleife schon mal.
ls -l /dev/serial/by-id
sehen welche Seriellen schnittstellen verbunden sind. Wenn da keine sind, sieht linux sie nicht bzw. bietet sie nicht an.
> - Reset bzw. Kaltstart am Drucker hilft leider nix, Repetiert-Server reagiert überhaupt nicht. Keine Aktion, kein Eintrag im Log, nix. Wie tot. Nur Service oder Pi neu starten hilft.
was heist bei dir reagiert überhaupt nicht? Du kannst dir nicht mal einen g-code im viewer anscheuen oder die Drucker reagieren nicht? Wenn gar nichts mehr geht gibt es gerne einen Deadlock den zu finden recht schwierig ist, da sie oft von Timing, umgebung etc abhängen. Kann man aber nur mit einem debugger genau klären, wie hier beschreiben:
https://www.repetier-server.de/faq/debugging-crashes-hangs-on-linux/
Mit einem backlog aller threads kann ich das normalerweise schnell sagen. Ist aber nur was für jemanden der etwas IT erfahrung hat mit so was.
Frag mich was das mit deinen 30 minuten auf sich hat. Hast du irgend etwas im idle script und das auf 30 Minuten eingestellt?
ls -l /dev/serial/by-id
total 0
lrwxrwxrwx 1 root root 13 Nov 29 19:59 usb-FTDI_FT232R_USB_UART_AL00IL8N-if00-port0 -> ../../ttyUSB0
ls -l /dev/serial/by-path
total 0
lrwxrwxrwx 1 root root 13 Nov 29 19:59 platform-3f980000.usb-usb-0:1.3:1.0-port0 -> ../../ttyUSB0
Heute hat es über eine Stunde gedauert, bis in der ">_ Konsole" nix mehr protokolliert wird:
<Reboot: 20:14 Uhr>
...
Mesg:21:32:39.253: Dtr: true Rts: true
Recv:21:32:42.258: Send init commands because we had no signal from a reset, assuming reset not available.
Mesg:21:32:50.890: Dtr: true Rts: true
Mesg:21:32:50.893: Printer reset requested false
Mesg:21:32:50.893: Dtr: false Rts: false
Mesg:21:32:50.913: Dtr: true Rts: true
Recv:21:32:53.918: Send init commands because we had no signal from a reset, assuming reset not available.
Mesg:21:33:02.650: Dtr: true Rts: true
Mesg:21:33:02.652: Printer reset requested false
Mesg:21:33:02.652: Dtr: false Rts: false
Mesg:21:33:02.673: Dtr: true Rts: true
Recv:21:33:05.677: Send init commands because we had no signal from a reset, assuming reset not available.
<Ende des Protokolls, auch um 22:24 Uhr noch>
<Drucker wird nicht erkannt, Schnittstellen (oben) sind noch da. Mit und ohne Strom. ;-)>
Journalctl (Beispiel für die Laufzeit):
...
Nov 29 22:03:20 doof-rs sudo[14266]: repetierserver : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/local/Repetier-Setup/bin/manageWifiAccess info
Nov 29 22:03:20 doof-rs sudo[14266]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 29 22:03:20 doof-rs dbus-daemon[360]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.894' (uid=0 pid=14287 comm="timedatectl ")
Nov 29 22:03:20 doof-rs systemd[1]: Starting Time & Date Service...
Nov 29 22:03:20 doof-rs dbus-daemon[360]: [system] Successfully activated service 'org.freedesktop.timedate1'
Nov 29 22:03:20 doof-rs systemd[1]: Started Time & Date Service.
Nov 29 22:03:20 doof-rs sudo[14266]: pam_unix(sudo:session): session closed for user root
Nov 29 22:03:21 doof-rs sudo[14295]: repetierserver : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/local/Repetier-Setup/bin/manageWifiAccess scan
Nov 29 22:03:21 doof-rs sudo[14295]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 29 22:03:24 doof-rs sudo[14295]: pam_unix(sudo:session): session closed for user root
Nov 29 22:03:24 doof-rs sudo[14306]: repetierserver : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/local/Repetier-Setup/bin/manageWifiAccess listConnections
Nov 29 22:03:24 doof-rs sudo[14306]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 29 22:03:24 doof-rs sudo[14306]: pam_unix(sudo:session): session closed for user root
Nov 29 22:03:50 doof-rs systemd[1]: systemd-timedated.service: Succeeded.
Nov 29 22:04:21 doof-rs sudo[14359]: repetierserver : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/local/Repetier-Setup/bin/manageWifiAccess info
...
/var/lib/Repetier-Server/printer/RF1000/logs/connected.log (zur Laufzeit wie oben):
<leer>
<Drucker reagiert nicht auf Befehle.>
/var/lib/Repetier-Server/logs/server.log (endet auch um 21:33 Uhr):
...
2022-11-29 21:32:14: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-29 21:32:26: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-29 21:32:37: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-29 21:32:49: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-29 21:33:00: error: Reading serial conection failed: no data on read.Operation canceled
2022-11-29 21:33:12: error: Reading serial conection failed: no data on read.Operation canceled
Abgesehen von völlig ratlos:
- Die Installation basiert auf Repetier-Server-Image_1_2_0_v28.zip (glaube ich), plus Updates für Raspberry Pi OS und Repetier-Server.
- Es gibt einen Cron Job der eine Sicherung per SSHFS am Freitag startet, sonst läuft da nix. Fast original so zu sagen.
Ich werde einmal das aktuelle Image verwenden und versuchen die Konfiguration zu übernehmen.
Vorher teste ich noch das Image-Backup mit der letzten funktionierenden Version.
Danke und eine möglichst angenehme Woche!
Da du bisher der einzige mit dem Problem bist, bin ich zwar nicht ganz beunruhigt aber würst gerne warum. Mit so vielen unterschiedlichen Druckern gibt es ja immer mal einen der eine "Eigenart" hat, die unerwartet probleme macht. Aber ist schon komisch das er es ja am anfang kann und plötzlich nicht mehr.
Hattest du schon versucht im Server den Drucker im Druckermnü zu deaktivieren und neu zu aktivieren. Das stoppt diese verbindungs schleife und startet sie frisch initialisiert wie nach einem neustart neu. Wenn das klappt gibt es evtl. einen unterschied bei den Verbindungsversuchen de rhier maßgebend ist.