Druck bricht mittendrin einfach ab

Hallo,

ich habe seit der neusten Version von repetier-Server das Problem, das der Druck einfach mittendrin abbricht.

anbei das Log. Besten Dank

http://mscool.de/repetier-server/repetier.zip

Comments

  • Ok, hier mal ein Abbruch aus server.log:
    2022-07-15 01:22:35: error: Reading serial conection failed: End of file. Closing connection.
    2022-07-15 01:22:35: Port closed for Ender-3 Pro
    2022-07-15 01:22:35: Connection closed: Ender-3 Pro
    2022-07-15 15:31:25: Unkown job id 57 for rendered image
    2022-07-15 15:31:25: Unkown job id 56 for rendered image
    2022-07-15 15:31:25: Unkown job id 54 for rendered image
    2022-07-15 15:31:25: Unkown job id 55 for rendered image
    2022-07-15 15:31:25: Websocket opened
    2022-07-15 15:36:01: Connection started: Ender-3 Pro
    2022-07-15 15:36:01: Internal reset printer Ender-3 Pro
    2022-07-15 15:36:01: Reset printer Ender-3 Pro
    2022-07-15 15:36:11: No valid response seen within 10 seconds from printer, restarting connection
    2022-07-15 15:36:11: Port closed for Ender-3 Pro
    2022-07-15 15:36:11: Connection closed: Ender-3 Pro
    2022-07-15 15:36:13: Connection started: Ender-3 Pro
    2022-07-15 15:36:13: Internal reset printer Ender-3 Pro
    2022-07-15 15:36:13: Reset printer Ender-3 Pro
    2022-07-15 15:40:57: error: Reading serial conection failed: End of file. Closing connection.
    2022-07-15 15:40:57: Port closed for Ender-3 Pro
    2022-07-15 15:40:57: Connection closed: Ender-3 Pro
    2022-07-15 15:41:13: Connection started: Ender-3 Pro
    2022-07-15 15:41:13: Internal reset printer Ender-3 Pro
    2022-07-15 15:41:13: Reset printer Ender-3 Pro

    Linux hat hier die Verbindung geschlossen, dann 14 STunden später ist er wieder da.

    In syslog
    Jul 15 01:22:35 repetier-server kernel: [10256.513523] xhci_hcd 0000:02:1b.0: WARN Set TR Deq Ptr cmd failed due to incorrect slot or ep state.
    Jul 15 01:22:35 repetier-server kernel: [10256.514150] usb 2-3: USB disconnect, device number 2

    Das scheint mit xhci_hcd zusammen zu hängen was teil von usb3.0 ist wie ich vermute. Also könnte man zumindest denken das ein usb3 problem zum Trennen führte,

    Steht nur das die Verbindung getrennt wurde.
    Jul 15 01:22:35 repetier-server kernel: [10256.514150] usb 2-3: USB disconnect, device number 2
    Jul 15 15:36:01 repetier-server kernel: [61462.658052] usb 2-3: new full-speed USB device number 3 using xhci_hcd
    Jul 15 15:36:01 repetier-server kernel: [61462.808867] usb 2-3: New USB device found, idVendor=1d50, idProduct=6029, bcdDevice= 1.00
    Jul 15 15:36:01 repetier-server kernel: [61462.808885] usb 2-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
    Jul 15 15:36:01 repetier-server kernel: [61462.808886] usb 2-3: Product: Marlin USB Device
    Jul 15 15:36:01 repetier-server kernel: [61462.808889] usb 2-3: Manufacturer: marlinfw.org 
    Jul 15 15:36:01 repetier-server kernel: [61462.808890] usb 2-3: SerialNumber: 0F004005AF6998A65E4149E4F50020C4
    Jul 15 15:36:01 repetier-server kernel: [61462.810871] cdc_acm 2-3:1.0: ttyACM0: USB ACM device
    Jul 15 15:36:01 repetier-server kernel: [61462.811145] usb-storage 2-3:1.2: USB Mass Storage device detected
    Jul 15 15:36:01 repetier-server kernel: [61462.811409] scsi host3: usb-storage 2-3:1.2
    Jul 15 15:36:01 repetier-server mtp-probe: checking bus 2, device 3: "/sys/devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb2/2-3"
    Jul 15 15:36:01 repetier-server mtp-probe: checking bus 2, device 3: "/sys/devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb2/2-3"
    Jul 15 15:40:57 repetier-server kernel: [61758.851899] usb 2-3: USB disconnect, device number 3
    Jul 15 15:41:11 repetier-server kernel: [61773.287548] usb 2-3: new full-speed USB device number 4 using xhci_hcd
    Jul 15 15:41:11 repetier-server kernel: [61773.437894] usb 2-3: New USB device found, idVendor=1d50, idProduct=6029, bcdDevice= 1.00
    Jul 15 15:41:11 repetier-server kernel: [61773.437897] usb 2-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
    Jul 15 15:41:11 repetier-server kernel: [61773.437899] usb 2-3: Product: Marlin USB Device
    Jul 15 15:41:11 repetier-server kernel: [61773.437905] usb 2-3: Manufacturer: marlinfw.org 
    Jul 15 15:41:11 repetier-server kernel: [61773.437905] usb 2-3: SerialNumber: 0F004005AF6998A65E4149E4F50020C4
    Jul 15 15:41:11 repetier-server kernel: [61773.439057] cdc_acm 2-3:1.0: ttyACM0: USB ACM device
    Jul 15 15:41:11 repetier-server kernel: [61773.439306] usb-storage 2-3:1.2: USB Mass Storage device detected
    Jul 15 15:41:11 repetier-server mtp-probe: checking bus 2, device 4: "/sys/devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb2/2-3"
    Jul 15 15:41:11 repetier-server kernel: [61773.440204] scsi host3: usb-storage 2-3:1.2
    Jul 15 15:41:11 repetier-server mtp-probe: checking bus 2, device 4: "/sys/devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb2/2-3"
    Jul 15 17:30:22 repetier-server /usr/libexec/gdm-x-session[3038]: (II) event4  - QEMU QEMU USB Tablet: device removed


    Alternativ hast du den Drucker um 1 uhr abgeschaltet. Aber das ist die einzige trennung am 15.7 und da syslog nur einen Tag abdeckt kann ich nicht mehr sagen,

    Frage ist was war um 1:22? Hast du getrennt oder ist es das Probelm das du meinst? Du hattest leider keine exakte Uhrzeit genannt.

    Versuch mal mit usb 2 zu verbinden, ist eh gleich schnell weil drucker kein usb 3 können. Vielleicht ist der unempfindlicher gegen die Störung. Jedenfalls ist es kein Server Problem sondern hardware/linux seitig.

    Auch scheinst du ein recht altes image zu nutzen da noch NetworkManager läuft. Evtl. würde ein aktuelles image so einen Fehler besser korrigieren, muss aber nicht sein. Jedenfalls hat es einen neueren Linux kernel mit neueren Treibern und hoffentlich mehr fixes.
  • Hallo,

    besten Dank für die schnelle Rückmeldung.

    die Trennung erfolgte gegen 22:30, also fast am Ende des Logs. Ich muß noch erwähnen das RS als Ubuntu vm läuft. Ubuntu ist recht aktuell, ich habe die letzte verfügbare iso zur Installation genommen.
  • Um 22:30 gabs nur das hier:
    2022-07-15 21:46:46: Time analysing /var/lib/Repetier-Server/printer/Ender3_Pro/jobs/00000002_Fan_Mod_New_Version_59m_0.1mm_200C_PLA_ENDER3.g:40638 us
    2022-07-15 22:31:31: Stopping for signal 15
    2022-07-15 22:31:31: Stopping MQTT subsystem ...
    2022-07-15 22:31:31: Stopping lua runner ...
    2022-07-15 22:31:31: Stopping global cloud ...
    2022-07-15 22:31:31: Stopping open threads ...
    2022-07-15 22:31:31: Shutting down web server.
    2022-07-15 22:31:31: Closing server

    Signal 15 ist SIGTERM also wurde ein signal an den Server gesendet das er sich beenden soll. Wie du siehst fährt sich der Server danach auch wunschgemäß herunter. 10 Sekunden später kommt er wieder hoch:

    2022-07-15 22:31:43: Start logging...
    2022-07-15 22:31:43: Webdirectory: /usr/local/Repetier-Server/www/
    2022-07-15 22:31:43: Storage directory: /var/lib/Repetier-Server/

    Im syslog steht

    l 15 22:31:42 repetier-server systemd[1]: Starting Authorization Manager...
    Jul 15 22:31:42 repetier-server systemd[1]: Starting Power Profiles daemon...
    Jul 15 22:31:42 repetier-server systemd[1]: Started QEMU Guest Agent.
    Jul 15 22:31:42 repetier-server systemd[1]: Starting System Logging Service...
    Jul 15 22:31:42 repetier-server systemd[1]: Condition check resulted in Secure Boot updates for DB and DBX being skipped.
    Jul 15 22:31:42 repetier-server systemd[1]: Condition check resulted in Automatically repair incorrect owner/permissions on core devices being skipped.
    Jul 15 22:31:42 repetier-server systemd[1]: Condition check resulted in Wait for the Ubuntu Core chooser trigger being skipped.
    Jul 15 22:31:42 repetier-server systemd[1]: Starting Snap Daemon...
    Jul 15 22:31:42 repetier-server systemd[1]: Starting Switcheroo Control Proxy service...
    Jul 15 22:31:42 repetier-server systemd[1]: Starting User Login Management...
    Jul 15 22:31:42 repetier-server systemd[1]: Condition check resulted in Thermal Daemon Service being skipped.
    Jul 15 22:31:42 repetier-server systemd[1]: Condition check resulted in Ubuntu Advantage reboot cmds being skipped.
    Jul 15 22:31:42 repetier-server CRON[636]: (root) CMD (cp /root/.bash_history-bak /root/.bash_history)
    Jul 15 22:31:42 repetier-server systemd[1]: Starting Disk Manager...
    Jul 15 22:31:42 repetier-server avahi-daemon[590]: Successfully dropped root privileges.
    Jul 15 22:31:42 repetier-server systemd[1]: Starting WPA supplicant...
    Jul 15 22:31:42 repetier-server avahi-daemon[590]: avahi-daemon 0.8 starting up.
    Jul 15 22:31:42 repetier-server systemd[1]: anacron.service: Deactivated successfully.
    Jul 15 22:31:42 repetier-server CRON[641]: (rs-user) CMD (cp /home/rs-user/.bash_history-bak /home/rs-user/.bash_history)
    Jul 15 22:31:42 repetier-server systemd[1]: gpu-manager.service: Deactivated successfully.
    Jul 15 22:31:42 repetier-server systemd[1]: Finished Detect the available GPUs and deal with any system changes.
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] Linux version 5.13.0-52-generic (buildd@lcy02-amd64-046) (gcc (Ubuntu 11.2.0-7ubuntu2) 11.2.0, GNU ld (GNU Binutils for Ubuntu) 2.37) #59-Ubuntu SMP Wed Jun 15 20:17:13 UTC 2022 (Ubuntu 5.13.0-52.59-generic 5.13.19)
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-5.13.0-52-generic root=UUID=87f3cee3-4bf3-49c8-8e25-bc5c539934fd ro quiet splash vt.handoff=7
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] KERNEL supported cpus:
    Jul 15 22:31:42 repetier-server kernel: [    0.000000]   Intel GenuineIntel
    Jul 15 22:31:42 repetier-server kernel: [    0.000000]   AMD AuthenticAMD
    Jul 15 22:31:42 repetier-server kernel: [    0.000000]   Hygon HygonGenuine
    Jul 15 22:31:42 repetier-server kernel: [    0.000000]   Centaur CentaurHauls
    Jul 15 22:31:42 repetier-server kernel: [    0.000000]   zhaoxin   Shanghai  
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'
    Jul 15 22:31:42 repetier-server kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'

    Es erfolgte also ein reboot von Linux, hat nichts mit dem Server zu tun. Ursache bin ich nicht sicher. Siehe hier

    Jul 15 22:30:01 repetier-server CRON[14417]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)
    Jul 15 22:31:03 repetier-server systemd[1]: Started Run anacron jobs.
    Jul 15 22:31:03 repetier-server systemd[1]: anacron.service: Deactivated successfully.
    Jul 15 22:31:03 repetier-server anacron[14452]: Anacron 2.3 started on 2022-07-15
    Jul 15 22:31:03 repetier-server anacron[14452]: Normal exit (0 jobs run)
    Jul 15 22:31:31 repetier-server qemu-ga: info: guest-shutdown called, mode: (null)
    Jul 15 22:31:31 repetier-server systemd[1]: unattended-upgrades.service: Deactivated successfully.
    Jul 15 22:31:31 repetier-server systemd[815]: Stopped target GNOME X11 Session (session: ubuntu).
    Jul 15 22:31:31 repetier-server kernel: [17755.406892] audit: type=1400 audit(1657917091.041:66): apparmor="DENIED" operation="open" profile="/usr/sbin/cupsd" name="/proc/sys/kernel/osrelease" pid=697 comm="cupsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
    Jul 15 22:31:31 repetier-server kernel: [17755.406905] audit: type=1400 audit(1657917091.045:67): apparmor="DENIED" operation="open" profile="/usr/sbin/cupsd" name="/proc/1/environ" pid=697 comm="cupsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
    Jul 15 22:31:31 repetier-server kernel: [17755.406907] audit: type=1400 audit(1657917091.045:68): apparmor="DENIED" operation="open" profile="/usr/sbin/cupsd" name="/proc/cmdline" pid=697 comm="cupsd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
    Jul 15 22:31:42 repetier-server systemd-modules-load[251]: Inserted module 'lp'
    Jul 15 22:31:42 repetier-server systemd-modules-load[251]: Inserted module 'ppdev'
    Jul 15 22:31:42 repetier-server systemd-modules-load[251]: Inserted module 'parport_pc'
    Jul 15 22:31:42 repetier-server systemd[1]: Starting Flush Journal to Persistent Storage...
    Jul 15 22:31:42 repetier-server mtp-probe: checking bus 1, device 2: "/sys/devices/pci0000:00/0000:00:01.2/usb1/1-1"
    Jul 15 22:31:42 repetier-server systemd[1]: Finished Coldplug All udev Devices.
    Jul 15 22:31:42 repetier-server mtp-probe: bus: 1, device: 2 was not an MTP device
    Jul 15 22:31:42 repetier-server systemd[1]: Finished Set the console keyboard layout.
    Jul 15 22:31:42 repetier-server mtp-probe: checking bus 2, device 2: "/sys/devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb2/2-3"
    Jul 15 22:31:42 repetier-server systemd[1]: Finished Create System Users.
    Jul 15 22:31:42 repetier-server mtp-probe: bus: 2, device: 2 was not an MTP device
    Jul 15 22:31:42 repetier-server systemd[1]: Starting Create Static Device Nodes in /dev...
    Jul 15 22:31:42 repetier-server mtp-probe: checking bus 3, device 2: "/sys/devices/pci0000:00/0000:00:1e.0/0000:02:1b.0/usb3/3-1"

    Es fängt hier an:  qemu-ga: info: guest-shutdown called, mode: (null)
    sieht also aus als ob die vm heruntergefahren wurde.
  • Hallo,

    oh, bin ich blöd. Der Fehler lag mal wieder an dem der vor dem Bildschirm sitzt. Hatte vor Tagen an den Backupeinstellungen gearbeitet und dabei vergessen die vm wieder aus dem automatischen Backup zu entfernen. Jedenfalls scheint jetzt wieder alles so zu laufen wie gewohnt. Vielen Dank für die Bemühungen, ist mir echt peinlich 
Sign In or Register to comment.