› ›

Connection closed by os during print

DanceMDanceM
in Questions & Answers
Repetier Server 1.4.1 / 1.4.2 on Rapsbery Pi.
Repetier firmware on Mega 2560 + RAMPS 1.4

For some reason I got my printer disconnected at random during last two weeks.  No errors except "Connection closed by os during print" It could be during printing or even saw two times when printer was on idle. It could work fine for a day then error next day. Here while I am writing this it happened again.

I tried various connection settings, bitrates, ping-pong mode, buffer size - all the same.
I replaced power supply (2A, I used it with RS for years but with older RS version) with brand new 3A - did not help.
The last thing for me left to try - different USB cable (testing in progress).
But I think it could be some bug in RS, idk.

I will be glad for any ideas and things to check.
Thank you.

Print log for one the fails:
Send:14:49:34.368: N31023 G1 X94.594 Y78.369 E0.6128
Recv:14:49:34.403: ok 31019
Send:14:49:34.403: N31024 G1 X94.122 Y78.520 E0.6404
Recv:14:49:34.432: ok 31020
Send:14:49:34.432: N31025 G1 X94.347 Y78.744 E0.6580
Recv:14:49:34.469: ok 31021
Send:14:49:34.470: N31026 G1 X93.809 Y78.829 E0.6883
Recv:14:49:34.472: ok 31022
Send:14:49:34.472: N31027 G1 X93.621 Y78.640 E0.7031
Recv:14:49:34.497: ok 31023
Send:14:49:34.497: N31028 G1 X93.563 Y78.654 E0.7064
Recv:14:49:34.534: ok 31024
Send:14:49:34.535: N31029 G1 X93.511 Y78.654 E0.7093
Recv:14:49:34.561: ok 31025
Send:14:49:34.561: N31030 G1 X93.055 Y78.541 E0.7354
Recv:14:49:34.566: ok 31026
Send:14:49:34.566: N31031 G1 X92.848 Y78.489 E0.7473
Recv:14:49:34.599: ok 31027
Send:14:49:34.599: N31032 G1 X93.079 Y78.721 E0.7655
Mesg:14:49:34.783: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
Mesg:14:49:36.844: Dtr: true Rts: true
Mesg:14:49:36.846: Connection continued
Send:14:49:36.856: N31033 M117 ETA 04:04:55 day 22
Send:14:49:36.856: N31034 G1 X92.957 Y78.672 E0.7729
Send:14:49:36.857: N31035 G1 X92.506 Y78.652 E0.7980
Send:14:49:36.857: N31036 G1 X92.324 Y78.589 E0.8086
Recv:14:49:37.760: Printer reset detected:start
Recv:14:49:38.181: start
Recv:14:49:38.182: Detected EEPROM version:20
Recv:14:49:38.182: Info:Autoleveling enabled
Recv:14:49:38.182: Transformation matrix: 0.999999 0.000000 0.001707 -0.000004 0.999998 0.002131 -0.001707 -0.002131 0.999996
Send:14:49:38.182: N0 M110 N0
Send:14:49:38.182: N1 M999 ; In case we did not reset recover from fatal state
Send:14:49:38.182: N0 M110 N0
Send:14:49:38.182: N1 M105 ; fetch first temperature
Send:14:49:38.182: N2 M105
Send:14:49:38.182: N3 M115 ; Check firmware and capabilities
Send:14:49:38.182: N4 M220 S100 ; set speed multiplier
Send:14:49:38.182: N5 M221 S100 ; set flow multiplier
Send:14:49:38.182: N6 M355 ; get light status
Send:14:49:38.182: N7 G92 E0 ; reset extruder position
Send:14:49:38.183: N8 G90 ; absolute coordinates
Recv:14:49:40.056: Free RAM:3369
Recv:14:49:40.056: SelectExtruder:0
Recv:14:49:40.060: FlowMultiply:100
Recv:14:49:40.060: ok
Recv:14:49:40.060: ok 1
Recv:14:49:40.060: DebugLevel:6
Send:14:49:40.060: N9 M105
Send:14:49:40.060: N10 M82 ; absolute E values
Send:14:49:40.061: N11 G21 ; units are mm
Mesg:14:49:40.061: info:Continue from fatal state
Recv:14:49:40.061: ok
Recv:14:49:40.061: ok 1
Send:14:49:40.061: N12 M114 ; check position
Recv:14:49:40.066: T:0.00 /0 B:0.00 /0 B@:0 @:0 T0:0.00 /0 @0:0 T1:0.00 /0 @1:0
Recv:14:49:40.066: ok 2
Send:14:49:40.066: N13 M111 S6 ; debug flags
Recv:14:49:40.068: T:0.00 /0 B:0.00 /0 B@:0 @:0 T0:0.00 /0 @0:0 T1:0.00 /0 @1:0
Recv:14:49:40.068: ok 3
Send:14:49:40.068: N14 M360 ; get configuration
Recv:14:49:40.077: FIRMWARE_NAME:Repetier_1.0.4 COMPILED:Sep 19 2022 FIRMWARE_URL:https://github.com/repetier/Repetier-Firmware/ PROTOCOL_VERSION:1.0 MACHINE_TYPE:Mendel EXTRUDER_COUNT:2 REPETIER_PROTOCOL:3
Recv:14:49:40.078: Cap:PROGRESS:1
Recv:14:49:40.078: Cap:AUTOREPORT_TEMP:1
Recv:14:49:40.079: Cap:HOST_RESCUE:1
Recv:14:49:40.080: Cap:EEPROM:1
Recv:14:49:40.080: Cap:AUTOLEVEL:1
Recv:14:49:40.081: Cap:Z_PROBE:1
Recv:14:49:40.081: Cap:SOFTWARE_POWER:1
Recv:14:49:40.082: Cap:TOGGLE_LIGHTS:0
Recv:14:49:40.082: Cap:PAUSESTOP:1
Recv:14:49:40.083: Cap:PREHEAT:1
Recv:14:49:40.085: Cap:EMERGENCY_PARSER:1
Recv:14:49:40.087: Printed filament:275.31m Printing time:2 days 15 hours 32 min
Recv:14:49:40.087: PrinterMode:FFF
Recv:14:49:40.088: ok 4
Recv:14:49:40.089: SpeedMultiply:100
Recv:14:49:40.089: ok 5
Send:14:49:40.089: N15 M539 S1
Send:14:49:40.089: @getip
Recv:14:49:41.091: FlowMultiply:100
Recv:14:49:41.091: ok 6
Recv:14:49:41.091: Info:No case lights
Recv:14:49:41.091: ok 7
Recv:14:49:41.091: ok 8
Recv:14:49:41.091: ok 9
Recv:14:49:41.091: T:0.00 /0 B:0.00 /0 B@:0 @:0 T0:0.00 /0 @0:0 T1:0.00 /0 @1:0
Recv:14:49:41.091: ok 10
Recv:14:49:41.091: ok 11
Recv:14:49:41.092: ok 12
Recv:14:49:41.092: X:0.00 Y:0.00 Z:0.000 E:0.0000
Recv:14:49:41.092: ok 13
Recv:14:49:41.092: DebugLevel:6
Recv:14:49:41.092: ok 14
Recv:14:49:41.092: Config:Baudrate:250000
Recv:14:49:41.092: Config:InputBuffer:127
Recv:14:49:41.092: Config:NumExtruder:2
Recv:14:49:41.092: Config:MixingExtruder:0

Syslog:

Sep 21 14:32:04 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:32:04 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:32:04 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:32:04 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:32:58 Repetier-Server wpa_supplicant[471]: wlan0: Failed to initiate sched scan
Sep 21 14:33:05 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:33:05 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:33:05 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:33:05 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:34:01 Repetier-Server dhcpcd[661]: wlan0: new hardware address: 06:07:a2:6e:50:c1
Sep 21 14:34:01 Repetier-Server NetworkManager[354]: <info>  [1663770841.5007] device (wlan0): set-hw-addr: set MAC address to 06:07:A2:6E:50:C1 (scanning)
Sep 21 14:34:01 Repetier-Server kernel: [26580.707253] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sep 21 14:34:01 Repetier-Server kernel: [26580.707263] brcmfmac: power management disabled
Sep 21 14:34:01 Repetier-Server NetworkManager[354]: <info>  [1663770841.6278] device (wlan0): supplicant interface state: inactive -> disconnected
Sep 21 14:34:01 Repetier-Server NetworkManager[354]: <info>  [1663770841.6647] device (wlan0): supplicant interface state: disconnected -> inactive
Sep 21 14:34:01 Repetier-Server wpa_supplicant[471]: wlan0: Reject scan trigger since one is already pending
Sep 21 14:34:06 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:34:06 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:34:06 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:34:06 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:35:07 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:35:07 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:35:07 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:35:07 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:36:08 Repetier-Server wpa_supplicant[471]: wlan0: Failed to initiate sched scan
Sep 21 14:36:09 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:36:09 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:36:09 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:36:09 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:37:10 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:37:10 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:37:10 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:37:10 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:37:11 Repetier-Server wpa_supplicant[471]: wlan0: Failed to initiate sched scan
Sep 21 14:38:11 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:38:11 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:38:11 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:38:11 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:38:14 Repetier-Server wpa_supplicant[471]: wlan0: Failed to initiate sched scan
Sep 21 14:39:13 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:39:13 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:39:13 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:39:13 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:39:17 Repetier-Server dhcpcd[661]: wlan0: new hardware address: 3e:bb:2d:75:4a:8a
Sep 21 14:39:17 Repetier-Server NetworkManager[354]: <info>  [1663771157.4509] device (wlan0): set-hw-addr: set MAC address to 3E:BB:2D:75:4A:8A (scanning)
Sep 21 14:39:17 Repetier-Server kernel: [26896.658829] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Sep 21 14:39:17 Repetier-Server kernel: [26896.658874] brcmfmac: power management disabled
Sep 21 14:40:14 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:40:14 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:40:14 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:40:14 Repetier-Server systemd[1]: Started Time & Date Service.
Sep 21 14:41:15 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Sep 21 14:41:15 Repetier-Server systemd[1]: Starting Time & Date Service...
Sep 21 14:41:15 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
Sep 21 14:41:15 Repetier-Server systemd[1]: Started Time & Date Service.
(Also why it does "Starting Time & Date Service..." every minute? Is it okay?)

Server Log:
2022-09-21 13:31:08: Updating info for /var/lib/Repetier-Server/printer/Piper_Belt/jobs/00000003_burpBrog_optimized_600p.g printer Piper_Belt
2022-09-21 13:31:22: Time analysing /var/lib/Repetier-Server/printer/Piper_Belt/jobs/00000003_burpBrog_optimized_600p.g:13304210 us
2022-09-21 13:32:30: Websocket: Client closed connection unexpectedly
2022-09-21 14:49:34: error: Reading serial conection failed: End of file. Closing connection.
2022-09-21 14:49:34: Connection closed during print ... trying reconnect for 10 seconds to continue ...
2022-09-21 14:49:34: Port closed for Piper Belt
2022-09-21 14:49:34: Connection closed: Piper Belt
2022-09-21 14:49:36: Connection continued: Piper Belt
2022-09-21 14:49:42: Sending push message failed: Exception: Temporary DNS error while resolving: informer.repetier-apps.com
2022-09-21 16:25:37: Websocket opened
2022-09-21 16:28:12: Websocket: Client closed connection unexpectedly

Comments

  • RepetierRepetier
    Since it is os closing connection the answer is as you probably guessed in syslog. But from timestamp
    Mesg:14:49:34.783: Connection closed by os during print ... trying reconnect for 10 seconds to continue ...
    you see your syslog is missing the crucial part where it disconnected, so please check around that timestamp and make sure both use same time zone.

    In case you need to check it on system not that syslog rotates dayly so on 22. Sep it is /var/log/syslog.0 eventually already gzipped.
  • DanceMDanceM
    Sorry, my bad. Here it is:
    Sep 21 14:47:22 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
    Sep 21 14:47:22 Repetier-Server systemd[1]: Starting Time & Date Service...
    Sep 21 14:47:22 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Sep 21 14:47:22 Repetier-Server systemd[1]: Started Time & Date Service.
    Sep 21 14:47:43 Repetier-Server wpa_supplicant[471]: wlan0: Failed to initiate sched scan
    Sep 21 14:48:23 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
    Sep 21 14:48:23 Repetier-Server systemd[1]: Starting Time & Date Service...
    Sep 21 14:48:23 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Sep 21 14:48:23 Repetier-Server systemd[1]: Started Time & Date Service.
    Sep 21 14:48:46 Repetier-Server wpa_supplicant[471]: wlan0: Failed to initiate sched scan
    Sep 21 14:49:24 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
    Sep 21 14:49:24 Repetier-Server systemd[1]: Starting Time & Date Service...
    Sep 21 14:49:24 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Sep 21 14:49:24 Repetier-Server systemd[1]: Started Time & Date Service.
    Sep 21 14:49:34 Repetier-Server dhclient[472]: receive_packet failed on eth0: Network is down
    Sep 21 14:49:34 Repetier-Server dhclient[472]: DHCPREQUEST of 192.168.1.121 on eth0 to 192.168.1.1 port 67
    Sep 21 14:49:34 Repetier-Server dhcpcd[661]: eth0: carrier lost
    Sep 21 14:49:34 Repetier-Server dhclient[472]: send_packet: Network is unreachable
    Sep 21 14:49:34 Repetier-Server NetworkManager[354]: <info>  [1663771774.6811] device (eth0): state change: activated -> unmanaged (reason 'removed') [100 10 36]
    Sep 21 14:49:34 Repetier-Server kernel: [27513.884540] usb usb1-port1: disabled by hub (EMI?), re-enabling...
    Sep 21 14:49:34 Repetier-Server kernel: [27513.884567] usb 1-1: USB disconnect, device number 2
    Sep 21 14:49:34 Repetier-Server kernel: [27513.884579] usb 1-1.1: USB disconnect, device number 3
    Sep 21 14:49:34 Repetier-Server kernel: [27513.885073] smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet
    Sep 21 14:49:34 Repetier-Server kernel: [27513.885251] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
    Sep 21 14:49:34 Repetier-Server dhclient[472]: send_packet: please consult README file regarding broadcast address.
    Sep 21 14:49:34 Repetier-Server dhclient[472]: dhclient.c:2612: Failed to send 300 byte long packet over fallback interface.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Interface eth0.IPv6 no longer relevant for mDNS.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::e21a:ac6a:b026:fb4c.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Interface eth0.IPv4 no longer relevant for mDNS.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.121.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Withdrawing address record for fe80::e21a:ac6a:b026:fb4c on eth0.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Withdrawing address record for 192.168.1.121 on eth0.
    Sep 21 14:49:34 Repetier-Server ModemManager[330]: <info>  (net/eth0): released by modem /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1
    Sep 21 14:49:34 Repetier-Server NetworkManager[354]: <info>  [1663771774.7267] dhcp4 (eth0): canceled DHCP transaction, DHCP client pid 472
    Sep 21 14:49:34 Repetier-Server NetworkManager[354]: <info>  [1663771774.7269] dhcp4 (eth0): state changed bound -> done
    Sep 21 14:49:34 Repetier-Server NetworkManager[354]: <info>  [1663771774.7289] dns-mgr: Removing DNS information from /sbin/resolvconf
    Sep 21 14:49:34 Repetier-Server MJPG-streamer [442]: Error grabbing frames
    Sep 21 14:49:34 Repetier-Server kernel: [27513.955524] usb 1-1.3: USB disconnect, device number 4
    Sep 21 14:49:34 Repetier-Server kernel: [27513.980711] usb 1-1.4: USB disconnect, device number 5
    Sep 21 14:49:34 Repetier-Server MJPG-streamer [417]: Error grabbing frames
    Sep 21 14:49:34 Repetier-Server kernel: [27513.987123] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
    Sep 21 14:49:34 Repetier-Server kernel: [27513.987200] ch341 1-1.4:1.0: device disconnected
    Sep 21 14:49:34 Repetier-Server kernel: [27513.989603] usb 1-1.5: USB disconnect, device number 6
    Sep 21 14:49:34 Repetier-Server dhcpcd[661]: eth0: deleting address fe80::ecb0:1d52:f5b4:b27
    Sep 21 14:49:34 Repetier-Server dhcpcd[661]: eth0: deleting default route via 192.168.1.1
    Sep 21 14:49:34 Repetier-Server dhcpcd[661]: eth0: deleting route to 192.168.1.0/24
    Sep 21 14:49:35 Repetier-Server kernel: [27514.204448] Indeed it is in host mode hprt0 = 00001501
    Sep 21 14:49:35 Repetier-Server dhcpcd[661]: eth0: removing interface
    Sep 21 14:49:35 Repetier-Server kernel: [27514.414414] usb 1-1: new high-speed USB device number 7 using dwc_otg
    Sep 21 14:49:35 Repetier-Server kernel: [27514.414493] Indeed it is in host mode hprt0 = 00001101
    Sep 21 14:49:35 Repetier-Server kernel: [27514.654564] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
    Sep 21 14:49:35 Repetier-Server kernel: [27514.654571] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    Sep 21 14:49:35 Repetier-Server kernel: [27514.654959] hub 1-1:1.0: USB hub found
    Sep 21 14:49:35 Repetier-Server kernel: [27514.655006] hub 1-1:1.0: 5 ports detected
    Sep 21 14:49:35 Repetier-Server kernel: [27514.974439] usb 1-1.1: new high-speed USB device number 8 using dwc_otg
    Sep 21 14:49:35 Repetier-Server kernel: [27515.104865] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
    Sep 21 14:49:35 Repetier-Server kernel: [27515.104881] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    Sep 21 14:49:35 Repetier-Server NetworkManager[354]: Too few arguments.
    Sep 21 14:49:35 Repetier-Server kernel: [27515.115593] smsc95xx v1.0.6
    Sep 21 14:49:35 Repetier-Server mtp-probe: checking bus 1, device 8: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1"
    Sep 21 14:49:35 Repetier-Server NetworkManager[354]: Too few arguments.
    Sep 21 14:49:35 Repetier-Server NetworkManager[354]: <info>  [1663771775.9550] manager: NetworkManager state is now CONNECTED_LOCAL
    Sep 21 14:49:35 Repetier-Server NetworkManager[354]: <info>  [1663771775.9585] manager: NetworkManager state is now DISCONNECTED
    Sep 21 14:49:35 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
    Sep 21 14:49:35 Repetier-Server systemd[1]: Starting Network Manager Script Dispatcher Service...
    Sep 21 14:49:36 Repetier-Server mtp-probe: bus: 1, device: 8 was not an MTP device
    Sep 21 14:49:36 Repetier-Server kernel: [27515.208532] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:a3:d9:e1
    Sep 21 14:49:36 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
    Sep 21 14:49:36 Repetier-Server systemd[1]: Started Network Manager Script Dispatcher Service.
    Sep 21 14:49:36 Repetier-Server nm-dispatcher: req:1 'connectivity-change': new request (1 scripts)
    Sep 21 14:49:36 Repetier-Server nm-dispatcher: req:1 'connectivity-change': start running ordered scripts...
    Sep 21 14:49:36 Repetier-Server dhcpcd[661]: eth0: waiting for carrier
    Sep 21 14:49:36 Repetier-Server kernel: [27515.375518] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
    Sep 21 14:49:36 Repetier-Server kernel: [27515.375745] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    Sep 21 14:49:36 Repetier-Server kernel: [27515.514420] usb 1-1.3: new high-speed USB device number 9 using dwc_otg
    Sep 21 14:49:36 Repetier-Server nm-dispatcher: req:2 'down' [eth0]: new request (1 scripts)
    Sep 21 14:49:36 Repetier-Server nm-dispatcher: req:2 'down' [eth0]: start running ordered scripts...
    Sep 21 14:49:36 Repetier-Server NetworkManager[354]: <info>  [1663771776.3560] devices removed (path: /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1:1.0/net/eth0, iface: eth0)
    Sep 21 14:49:36 Repetier-Server NetworkManager[354]: <info>  [1663771776.3595] devices added (path: /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1:1.0/net/eth0, iface: eth0)
    Sep 21 14:49:36 Repetier-Server NetworkManager[354]: <info>  [1663771776.3596] device added (path: /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1:1.0/net/eth0, iface: eth0): no ifupdown configuration found.
    Sep 21 14:49:36 Repetier-Server NetworkManager[354]: <info>  [1663771776.3703] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/4)
    Sep 21 14:49:36 Repetier-Server NetworkManager[354]: <info>  [1663771776.3744] keyfile: add connection in-memory (fe595fac-107f-3bf6-8482-4db4be1ade3e,"Wired connection 1")
    Sep 21 14:49:36 Repetier-Server NetworkManager[354]: <info>  [1663771776.3768] settings: (eth0): created default wired connection 'Wired connection 1'
    Sep 21 14:49:36 Repetier-Server NetworkManager[354]: <info>  [1663771776.3804] device (eth0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
    Sep 21 14:49:36 Repetier-Server kernel: [27515.591550] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    Sep 21 14:49:36 Repetier-Server kernel: [27515.674912] usb 1-1.3: New USB device found, idVendor=1908, idProduct=2311
    Sep 21 14:49:36 Repetier-Server kernel: [27515.674922] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
    Sep 21 14:49:36 Repetier-Server kernel: [27515.674926] usb 1-1.3: Product: USB2.0 PC CAMERA
    Sep 21 14:49:36 Repetier-Server kernel: [27515.674930] usb 1-1.3: Manufacturer: Generic
    Sep 21 14:49:36 Repetier-Server kernel: [27515.678881] uvcvideo: Found UVC 1.00 device USB2.0 PC CAMERA (1908:2311)
    Sep 21 14:49:36 Repetier-Server kernel: [27515.679323] uvcvideo 1-1.3:1.0: Entity type for entity Processing 2 was not initialized!
    Sep 21 14:49:36 Repetier-Server kernel: [27515.679330] uvcvideo 1-1.3:1.0: Entity type for entity Camera 1 was not initialized!
    Sep 21 14:49:36 Repetier-Server kernel: [27515.679792] input: USB2.0 PC CAMERA: USB2.0 PC CAM as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/input/input2
    Sep 21 14:49:36 Repetier-Server mtp-probe: checking bus 1, device 9: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3"
    Sep 21 14:49:36 Repetier-Server mtp-probe: bus: 1, device: 9 was not an MTP device
    Sep 21 14:49:36 Repetier-Server systemd[1]: Stopped mjpg_streamer for picam.
    Sep 21 14:49:36 Repetier-Server systemd[1]: Stopping mjpg_streamer for picam...
    Sep 21 14:49:36 Repetier-Server systemd[1]: Started mjpg_streamer for picam.
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: Start from directory /usr/local/Repetier-Setup/bin
    Sep 21 14:49:36 Repetier-Server kernel: [27515.774500] usb 1-1.4: new full-speed USB device number 10 using dwc_otg
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: Testing active webcams in /dev/v4l/by-id/*
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: Starting webcam /dev/v4l/by-id/usb-Generic_USB2.0_PC_CAMERA-video-index0
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: Webcam does not support MJPG - using slower YUYV instead!
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: Best resolution: 640 x 480 at 30
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: Starting usb-Generic_USB2.0_PC_CAMERA-video-index0 on port 8080
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: /usr/local/bin/mjpg_streamer -i "/usr/local/lib/mjpg-streamer/input_uvc.so -d /dev/video0 --fps 30 -q 85 -r 640x480 -y " -o "/usr/local/lib/mjpg-streamer/output_http.so -p 8080 -w /usr/local/share/mjpg-streamer/www" -b
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26822]: starting application
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26822]: enabling daemon mode
    Sep 21 14:49:36 Repetier-Server startAllCams[26801]: enabling daemon modeforked to background (26824)
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: MJPG Streamer Version: git rev: 821c330ea6bbb5fbed98d48e00aac156e923161b
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: Using V4L2 device.: /dev/video0
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: Desired Resolution: 640 x 480
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: Frames Per Second.: 30
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: Format............: YUYV
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: JPEG Quality......: 85
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: TV-Norm...........: DEFAULT
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: www-folder-path......: /usr/local/share/mjpg-streamer/www/
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: HTTP TCP port........: 8080
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: HTTP Listen Address..: (null)
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: username:password....: disabled
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: commands.............: enabled
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: starting input plugin /usr/local/lib/mjpg-streamer/input_uvc.so
    Sep 21 14:49:36 Repetier-Server MJPG-streamer [26824]: starting output plugin: /usr/local/lib/mjpg-streamer/output_http.so (ID: 00)
    Sep 21 14:49:36 Repetier-Server kernel: [27515.917520] usb 1-1.4: New USB device found, idVendor=1a86, idProduct=7523
    Sep 21 14:49:36 Repetier-Server kernel: [27515.917527] usb 1-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    Sep 21 14:49:36 Repetier-Server kernel: [27515.917531] usb 1-1.4: Product: USB2.0-Serial
    Sep 21 14:49:36 Repetier-Server kernel: [27515.918826] ch341 1-1.4:1.0: ch341-uart converter detected
    Sep 21 14:49:36 Repetier-Server kernel: [27515.921672] usb 1-1.4: ch341-uart converter now attached to ttyUSB0
    Sep 21 14:49:36 Repetier-Server mtp-probe: checking bus 1, device 10: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4"
    Sep 21 14:49:36 Repetier-Server mtp-probe: bus: 1, device: 10 was not an MTP device
    Sep 21 14:49:36 Repetier-Server systemd-udevd[26794]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
    Sep 21 14:49:36 Repetier-Server kernel: [27516.014486] usb 1-1.5: new high-speed USB device number 11 using dwc_otg
    Sep 21 14:49:37 Repetier-Server kernel: [27516.356434] usb 1-1.5: New USB device found, idVendor=046d, idProduct=0825
    Sep 21 14:49:37 Repetier-Server kernel: [27516.356443] usb 1-1.5: New USB device strings: Mfr=0, Product=0, SerialNumber=2
    Sep 21 14:49:37 Repetier-Server kernel: [27516.356448] usb 1-1.5: SerialNumber: 7BB5DAE0
    Sep 21 14:49:37 Repetier-Server kernel: [27516.358188] uvcvideo: Found UVC 1.00 device <unnamed> (046d:0825)
    Sep 21 14:49:37 Repetier-Server kernel: [27516.448378] uvcvideo 1-1.5:1.0: Entity type for entity Extension 4 was not initialized!
    Sep 21 14:49:37 Repetier-Server kernel: [27516.448388] uvcvideo 1-1.5:1.0: Entity type for entity Extension 6 was not initialized!
    Sep 21 14:49:37 Repetier-Server kernel: [27516.448394] uvcvideo 1-1.5:1.0: Entity type for entity Extension 7 was not initialized!
    Sep 21 14:49:37 Repetier-Server kernel: [27516.448399] uvcvideo 1-1.5:1.0: Entity type for entity Processing 2 was not initialized!
    Sep 21 14:49:37 Repetier-Server kernel: [27516.448404] uvcvideo 1-1.5:1.0: Entity type for entity Extension 3 was not initialized!
    Sep 21 14:49:37 Repetier-Server kernel: [27516.448409] uvcvideo 1-1.5:1.0: Entity type for entity Camera 1 was not initialized!
    Sep 21 14:49:37 Repetier-Server kernel: [27516.449012] input: UVC Camera (046d:0825) as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/input/input3
    Sep 21 14:49:37 Repetier-Server dhcpcd[661]: eth0: carrier acquired
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.5712] device (eth0): link connected
    Sep 21 14:49:37 Repetier-Server kernel: [27516.778665] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
    Sep 21 14:49:37 Repetier-Server kernel: [27516.781020] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC1E1
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.5808] device (eth0): state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40]
    Sep 21 14:49:37 Repetier-Server dhcpcd[661]: eth0: IAID eb:a3:d9:e1
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.5856] policy: auto-activating connection 'Wired connection 1'
    Sep 21 14:49:37 Repetier-Server dhcpcd[661]: eth0: adding address fe80::ecb0:1d52:f5b4:b27
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.5935] device (eth0): Activation: starting connection 'Wired connection 1' (fe595fac-107f-3bf6-8482-4db4be1ade3e)
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.5969] device (eth0): state change: disconnected -> prepare (reason 'none') [30 40 0]
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.5979] manager: NetworkManager state is now CONNECTING
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.6040] device (eth0): state change: prepare -> config (reason 'none') [40 50 0]
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.6088] device (eth0): state change: config -> ip-config (reason 'none') [50 70 0]
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.6125] dhcp4 (eth0): activation: beginning transaction (timeout in 45 seconds)
    Sep 21 14:49:37 Repetier-Server NetworkManager[354]: <info>  [1663771777.6224] dhcp4 (eth0): dhclient started with pid 26834
    Sep 21 14:49:37 Repetier-Server dhcpcd[661]: eth0: soliciting an IPv6 router
    Sep 21 14:49:37 Repetier-Server dhclient[26834]: DHCPREQUEST of 192.168.1.121 on eth0 to 255.255.255.255 port 67
    Sep 21 14:49:37 Repetier-Server dhcpcd[661]: eth0: rebinding lease of 192.168.1.121
    Sep 21 14:49:38 Repetier-Server ModemManager[330]: <info>  Couldn't check support for device at '/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1': not supported by any plugin
    Sep 21 14:49:38 Repetier-Server kernel: [27517.790848] usb 1-1.5: set resolution quirk: cval->res = 384
    Sep 21 14:49:38 Repetier-Server mtp-probe: checking bus 1, device 11: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5"
    Sep 21 14:49:38 Repetier-Server mtp-probe: bus: 1, device: 11 was not an MTP device
    Sep 21 14:49:38 Repetier-Server systemd[1]: Stopping mjpg_streamer for picam...
    Sep 21 14:49:38 Repetier-Server systemd[1]: Stopped mjpg_streamer for picam.
    Sep 21 14:49:38 Repetier-Server systemd[1]: Started mjpg_streamer for picam.
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Start from directory /usr/local/Repetier-Setup/bin
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Testing active webcams in /dev/v4l/by-id/*
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Starting webcam /dev/v4l/by-id/usb-046d_0825_7BB5DAE0-video-index0
    Sep 21 14:49:38 Repetier-Server systemd-udevd[26845]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Best resolution: 640 x 480 at 30
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Starting usb-046d_0825_7BB5DAE0-video-index0 on port 8081
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: /usr/local/bin/mjpg_streamer -i "/usr/local/lib/mjpg-streamer/input_uvc.so -d /dev/video1 --fps 30 -q 85 -r 640x480 " -o "/usr/local/lib/mjpg-streamer/output_http.so -p 8081 -w /usr/local/share/mjpg-streamer/www" -b
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26877]: starting application
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26877]: enabling daemon mode
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: enabling daemon modeStarting webcam /dev/v4l/by-id/usb-Generic_USB2.0_PC_CAMERA-video-index0
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: forked to background (26879)
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26879]: MJPG Streamer Version: git rev: 821c330ea6bbb5fbed98d48e00aac156e923161b
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26879]: Using V4L2 device.: /dev/video1
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26879]: Desired Resolution: 640 x 480
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26879]: Frames Per Second.: 30
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26879]: Format............: JPEG
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26879]: TV-Norm...........: DEFAULT
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Webcam does not support MJPG - using slower YUYV instead!
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Best resolution: 640 x 480 at 30
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: Starting usb-Generic_USB2.0_PC_CAMERA-video-index0 on port 8080
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: /usr/local/bin/mjpg_streamer -i "/usr/local/lib/mjpg-streamer/input_uvc.so -d /dev/video0 --fps 30 -q 85 -r 640x480 -y " -o "/usr/local/lib/mjpg-streamer/output_http.so -p 8080 -w /usr/local/share/mjpg-streamer/www" -b
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26891]: starting application
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26891]: enabling daemon mode
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: MJPG Streamer Version: git rev: 821c330ea6bbb5fbed98d48e00aac156e923161b
    Sep 21 14:49:38 Repetier-Server startAllCams[26855]: enabling daemon modeforked to background (26893)
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: Using V4L2 device.: /dev/video0
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: Desired Resolution: 640 x 480
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: Frames Per Second.: 30
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: Format............: YUYV
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: JPEG Quality......: 85
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: TV-Norm...........: DEFAULT
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: www-folder-path......: /usr/local/share/mjpg-streamer/www/
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: HTTP TCP port........: 8080
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: HTTP Listen Address..: (null)
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: username:password....: disabled
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: commands.............: enabled
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: starting input plugin /usr/local/lib/mjpg-streamer/input_uvc.so
    Sep 21 14:49:38 Repetier-Server MJPG-streamer [26893]: starting output plugin: /usr/local/lib/mjpg-streamer/output_http.so (ID: 00)
    Sep 21 14:49:39 Repetier-Server MJPG-streamer [26879]: www-folder-path......: /usr/local/share/mjpg-streamer/www/
    Sep 21 14:49:39 Repetier-Server MJPG-streamer [26879]: HTTP TCP port........: 8081
    Sep 21 14:49:39 Repetier-Server MJPG-streamer [26879]: HTTP Listen Address..: (null)
    Sep 21 14:49:39 Repetier-Server MJPG-streamer [26879]: username:password....: disabled
    Sep 21 14:49:39 Repetier-Server MJPG-streamer [26879]: commands.............: enabled
    Sep 21 14:49:39 Repetier-Server MJPG-streamer [26879]: starting input plugin /usr/local/lib/mjpg-streamer/input_uvc.so
    Sep 21 14:49:39 Repetier-Server MJPG-streamer [26879]: starting output plugin: /usr/local/lib/mjpg-streamer/output_http.so (ID: 00)
    Sep 21 14:49:39 Repetier-Server avahi-daemon[367]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::e21a:ac6a:b026:fb4c.
    Sep 21 14:49:39 Repetier-Server avahi-daemon[367]: New relevant interface eth0.IPv6 for mDNS.
    Sep 21 14:49:39 Repetier-Server avahi-daemon[367]: Registering new address record for fe80::e21a:ac6a:b026:fb4c on eth0.*.
    Sep 21 14:49:42 Repetier-Server dhcpcd[661]: eth0: probing address 192.168.1.121/24
    Sep 21 14:49:42 Repetier-Server dhclient[26834]: DHCPREQUEST of 192.168.1.121 on eth0 to 255.255.255.255 port 67
    Sep 21 14:49:42 Repetier-Server dhclient[26834]: DHCPACK of 192.168.1.121 from 192.168.1.1
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4429] dhcp4 (eth0):   address 192.168.1.121
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4430] dhcp4 (eth0):   plen 24 (255.255.255.0)
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4431] dhcp4 (eth0):   gateway 192.168.1.1
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4431] dhcp4 (eth0):   server identifier 192.168.1.1
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4432] dhcp4 (eth0):   lease time 86400
    Sep 21 14:49:42 Repetier-Server avahi-daemon[367]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.121.
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4432] dhcp4 (eth0):   hostname 'Pi3iS_LAN'
    Sep 21 14:49:42 Repetier-Server avahi-daemon[367]: New relevant interface eth0.IPv4 for mDNS.
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4433] dhcp4 (eth0):   nameserver '192.168.1.1'
    Sep 21 14:49:42 Repetier-Server avahi-daemon[367]: Registering new address record for 192.168.1.121 on eth0.IPv4.
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4433] dhcp4 (eth0):   domain name 'homemesh'
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4434] dhcp4 (eth0): state changed unknown -> bound
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4482] device (eth0): state change: ip-config -> ip-check (reason 'none') [70 80 0]
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4521] device (eth0): state change: ip-check -> secondaries (reason 'none') [80 90 0]
    Sep 21 14:49:42 Repetier-Server nm-dispatcher: req:3 'connectivity-change': new request (1 scripts)
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4543] device (eth0): state change: secondaries -> activated (reason 'none') [90 100 0]
    Sep 21 14:49:42 Repetier-Server nm-dispatcher: req:3 'connectivity-change': start running ordered scripts...
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4554] manager: NetworkManager state is now CONNECTED_LOCAL
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4804] manager: NetworkManager state is now CONNECTED_GLOBAL
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4811] policy: set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.4823] dns-mgr: Writing DNS information to /sbin/resolvconf
    Sep 21 14:49:42 Repetier-Server dhclient[26834]: bound to 192.168.1.121 -- renewal in 42422 seconds.
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: Too few arguments.
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: Too few arguments.
    Sep 21 14:49:42 Repetier-Server NetworkManager[354]: <info>  [1663771782.5953] device (eth0): Activation: successful, device activated.
    Sep 21 14:49:42 Repetier-Server nm-dispatcher: req:4 'up' [eth0]: new request (1 scripts)
    Sep 21 14:49:42 Repetier-Server nm-dispatcher: req:4 'up' [eth0]: start running ordered scripts...
    Sep 21 14:49:42 Repetier-Server systemd[1]: Reloading OpenBSD Secure Shell server.
    Sep 21 14:49:43 Repetier-Server systemd[1]: Reloaded OpenBSD Secure Shell server.
    Sep 21 14:49:43 Repetier-Server systemd[1]: Reloading OpenBSD Secure Shell server.
    Sep 21 14:49:43 Repetier-Server systemd[1]: Reloaded OpenBSD Secure Shell server.
    Sep 21 14:49:47 Repetier-Server dhcpcd[661]: eth0: leased 192.168.1.121 for 86400 seconds
    Sep 21 14:49:47 Repetier-Server dhcpcd[661]: eth0: adding route to 192.168.1.0/24
    Sep 21 14:49:47 Repetier-Server dhcpcd[661]: eth0: adding default route via 192.168.1.1
    Sep 21 14:49:49 Repetier-Server NetworkManager[354]: <info>  [1663771789.4602] device (wlan0): set-hw-addr: set MAC address to E2:E2:38:77:39:F8 (scanning)
    Sep 21 14:49:49 Repetier-Server dhcpcd[661]: wlan0: new hardware address: e2:e2:38:77:39:f8
    Sep 21 14:49:49 Repetier-Server kernel: [27528.671052] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    Sep 21 14:49:49 Repetier-Server kernel: [27528.671064] brcmfmac: power management disabled
    Sep 21 14:49:49 Repetier-Server NetworkManager[354]: <info>  [1663771789.5662] device (wlan0): supplicant interface state: inactive -> disconnected
    Sep 21 14:49:49 Repetier-Server NetworkManager[354]: <info>  [1663771789.6016] device (wlan0): supplicant interface state: disconnected -> inactive
    Sep 21 14:49:49 Repetier-Server wpa_supplicant[471]: wlan0: Reject scan trigger since one is already pending
    Sep 21 14:49:51 Repetier-Server dhcpcd[661]: eth0: no IPv6 Routers available
    Sep 21 14:50:25 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
    Sep 21 14:50:25 Repetier-Server systemd[1]: Starting Time & Date Service...
    Sep 21 14:50:25 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Sep 21 14:50:25 Repetier-Server systemd[1]: Started Time & Date Service.
    Sep 21 14:51:26 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
    Sep 21 14:51:26 Repetier-Server systemd[1]: Starting Time & Date Service...
    Sep 21 14:51:26 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Sep 21 14:51:26 Repetier-Server systemd[1]: Started Time & Date Service.
    Sep 21 14:51:56 Repetier-Server wpa_supplicant[471]: wlan0: Failed to initiate sched scan
    Sep 21 14:52:27 Repetier-Server dbus[335]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
    Sep 21 14:52:27 Repetier-Server systemd[1]: Starting Time & Date Service...
    Sep 21 14:52:27 Repetier-Server dbus[335]: [system] Successfully activated service 'org.freedesktop.timedate1'
    Sep 21 14:52:27 Repetier-Server systemd[1]: Started Time & Date Service.

  • RepetierRepetier
    Ok, here condensed to important lines
    Sep 21 14:49:34 Repetier-Server kernel: [27513.884540] usb usb1-port1: disabled by hub (EMI?), re-enabling...
    Sep 21 14:49:34 Repetier-Server kernel: [27513.884567] usb 1-1: USB disconnect, device number 2
    Sep 21 14:49:34 Repetier-Server kernel: [27513.884579] usb 1-1.1: USB disconnect, device number 3
    Sep 21 14:49:34 Repetier-Server kernel: [27513.885073] smsc95xx 1-1.1:1.0 eth0: unregister 'smsc95xx' usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet
    Sep 21 14:49:34 Repetier-Server kernel: [27513.885251] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
    Sep 21 14:49:34 Repetier-Server dhclient[472]: send_packet: please consult README file regarding broadcast address.
    Sep 21 14:49:34 Repetier-Server dhclient[472]: dhclient.c:2612: Failed to send 300 byte long packet over fallback interface.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Interface eth0.IPv6 no longer relevant for mDNS.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::e21a:ac6a:b026:fb4c.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Interface eth0.IPv4 no longer relevant for mDNS.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.121.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Withdrawing address record for fe80::e21a:ac6a:b026:fb4c on eth0.
    Sep 21 14:49:34 Repetier-Server avahi-daemon[367]: Withdrawing address record for 192.168.1.121 on eth0.
    Sep 21 14:49:34 Repetier-Server ModemManager[330]: <info>  (net/eth0): released by modem /sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1
    Sep 21 14:49:34 Repetier-Server NetworkManager[354]: <info>  [1663771774.7267] dhcp4 (eth0): canceled DHCP transaction, DHCP client pid 472
    Sep 21 14:49:34 Repetier-Server NetworkManager[354]: <info>  [1663771774.7269] dhcp4 (eth0): state changed bound -> done
    Sep 21 14:49:34 Repetier-Server NetworkManager[354]: <info>  [1663771774.7289] dns-mgr: Removing DNS information from /sbin/resolvconf
    Sep 21 14:49:34 Repetier-Server MJPG-streamer [442]: Error grabbing frames
    Sep 21 14:49:34 Repetier-Server kernel: [27513.955524] usb 1-1.3: USB disconnect, device number 4
    Sep 21 14:49:34 Repetier-Server kernel: [27513.980711] usb 1-1.4: USB disconnect, device number 5
    Sep 21 14:49:34 Repetier-Server MJPG-streamer [417]: Error grabbing frames
    Sep 21 14:49:34 Repetier-Server kernel: [27513.987123] ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
    Sep 21 14:49:34 Repetier-Server kernel: [27513.987200] ch341 1-1.4:1.0: device disconnected
    Sep 21 14:49:34 Repetier-Server kernel: [27513.989603] usb 1-1.5: USB disconnect, device number 6
    Sep 21 14:49:34 Repetier-Server dhcpcd[661]: eth0: deleting address fe80::ecb0:1d52:f5b4:b27
    Sep 21 14:49:34 Repetier-Server dhcpcd[661]: eth0: deleting default route via 192.168.1.1
    Sep 21 14:49:34 Repetier-Server dhcpcd[661]: eth0: deleting route to 192.168.1.0/24
    Sep 21 14:49:35 Repetier-Server kernel: [27514.204448] Indeed it is in host mode hprt0 = 00001501
    Sep 21 14:49:35 Repetier-Server dhcpcd[661]: eth0: removing interface
    Sep 21 14:49:35 Repetier-Server kernel: [27514.414414] usb 1-1: new high-speed USB device number 7 using dwc_otg
    Sep 21 14:49:35 Repetier-Server kernel: [27514.414493] Indeed it is in host mode hprt0 = 00001101
    Sep 21 14:49:35 Repetier-Server kernel: [27514.654564] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
    Sep 21 14:49:35 Repetier-Server kernel: [27514.654571] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    Sep 21 14:49:35 Repetier-Server kernel: [27514.654959] hub 1-1:1.0: USB hub found
    Sep 21 14:49:35 Repetier-Server kernel: [27514.655006] hub 1-1:1.0: 5 ports detected
    Sep 21 14:49:35 Repetier-Server kernel: [27514.974439] usb 1-1.1: new high-speed USB device number 8 using dwc_otg
    Sep 21 14:49:35 Repetier-Server kernel: [27515.104865] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
    Sep 21 14:49:35 Repetier-Server kernel: [27515.104881] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    Most important line 1:
    usb usb1-port1: disabled by hub (EMI?), re-enabling...
    so this is just a hardware issue. As you see a hub that also connects the webcam disabled usb devices and linux assumes EMI (Electro Magnetic Intererence). Aftwerwards it reconnects everything but of course print was stopped. EMI is not really dependent on power. It can be just a cable that is now closer to a usb part or usb ground causing too much change due to induction.

    You might want to try connecting the printer to one of the usb 2 ports and see if it gets better.

    You should also try the serial connection option to reconnect and continue print after short interruption. Will not prevent the issue as that is hardware related, but tries to continue print without lost of moves. Works on many printers but not all - depends on serial driver if it resets printer or not on reconnect.


  • DanceMDanceM
    I tried to swap the USB ports but it did not help.
    Hm.. first USB cable have a ferrite bead on it but it did not help as well :)

    The reconnect option did not work, I tried it.

    Okay, thank you for information. I will continue the testing and maybe replace Rapsberry Pi with Intel NUC with Linux on it to get more power and stability.
  • DanceMDanceM
    It looks like new USB cable (cheaper and longer) solved the problem, there were zero disconnects in three days of various printing tests.
    And I moved Repetier-Server to old Intel NUC after all, for better stability.

Sign In or Register to comment.