Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Interesting log triggered by exception in a thread {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X96.6 Y88.058 E.04262' confirmed {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102346 G1 X96.47 Y88.513 E.01625 *123#012 sent {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102346 G1 X96.47 Y88.513 E.01625 *123\n' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G92 E0.0 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G92 E0.0' enqueued. to the front {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 X96.6 Y88.058 E.04262 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X96.47 Y88.513 E.01625' confirmed {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102347 G1 X97.076 Y89.118 E.02941 *73#012 sent {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102347 G1 X97.076 Y89.118 E.02941 *73\n' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 F8640 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 F8640' enqueued. to the front {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 X96.47 Y88.513 E.01625 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 X96.47 Y88.513 E-.19764 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X96.47 Y88.513 E-.19764' enqueued. to the front {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X97.076 Y89.118 E.02941' confirmed {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Buffer is fed, threshold: 0.14405763149261475, value: 0.2273406982421875 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Allowing a non-important instruction through {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: M117 OK: 192.168.4.44#012 sent {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'M117 OK: 192.168.4.44\n' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: G1 X97.076 Y89.118 E.02941 confirmed {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'LCD status changed' {excepthook():37} Jun 9 23:08:03 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'LCD status changed' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^LCD status changed$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048673968, 3045324688): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'M117 OK: 192.168.4.44' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102348 G92 E0.0 *69#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printed: 'OK: 192.168.4.44' on the LCD. {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Wait for message {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 X96.6 Y88.058 E-.10923 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102348 G92 E0.0 *69\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X96.6 Y88.058 E-.10923' enqueued. to the front {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G92 E0.0' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102349 G1 F8640 *89#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: G92 E0.0 confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 X97.477 Y88.935 E-.2864 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102349 G1 F8640 *89\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X97.477 Y88.935 E-.2864' enqueued. to the front {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'T:239.8 /240.0 B:75.5 /75.0 T0:239.8 /240.0 @:57 B@:0 P:0.0 A:36.0' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'T:239.8 /240.0 B:75.5 /75.0 T0:239.8 /240.0 @:57 B@:0 P:0.0 A:36.0' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^T:(?P-?\d+\.\d+) /(?P-?\d+\.\d+) B:(?P-?\d+\.\d+) /(?P-?\d+\.\d+) T0:(-?\d+\.\d+) /(-?\d+\.\d+) @:(?P-?\d+) B@:(?P-?\d+) P:(?P-?\d+\.\d+) A:(?P-?\d+\.\d+)$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3047816112, 3044025344): >, 3044627696: . at 0xb57954f0>} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'X:97.08 Y:89.12 Z:19.80 E:0.00 Count X: 99.37 Y:88.67 Z:20.17 E:31.11' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'X:97.08 Y:89.12 Z:19.80 E:0.00 Count X: 99.37 Y:88.67 Z:20.17 E:31.11' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^X:(?P-?\d+\.\d+) Y:(?P-?\d+\.\d+) Z:(?P-?\d+\.\d+) E:(?P-?\d+\.\d+) Count X: (?P-?\d+\.\d+) Y:(?P-?\d+\.\d+) Z:(?P-?\d+\.\d+) E:(?P-?\d+\.\d+)$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3047816256, 3044025344): >, 3044627768: . at 0xb5795538>} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'E0:4140 RPM PRN1:2580 RPM E0@:255 PRN1@:76' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'E0:4140 RPM PRN1:2580 RPM E0@:255 PRN1@:76' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched E0:(?P\d+) RPM PRN1:(?P\d+) RPM E0@:(?P\d+) PRN1@:(?P\d+) {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3047816400, 3044025344): >, 3044627840: . at 0xb5795580>} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 F8640' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Buffer is fed, threshold: 0.1441248655319214, value: 0.22764968872070312 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Allowing a non-important instruction through {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: M27 P#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: G1 F8640 confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 X98.061 Y88.935 E-.13493 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'M27 P\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'Not SD printing' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'Not SD printing' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^(?P/.*\..*)|(?PNot SD printing)|(?PPrint saved)|(?PSD print paused)|(?PSD printing byte (?P\d+)/(?P\d+))|(?P(?P\d+):(?P\d{2}))$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X98.061 Y88.935 E-.13493' enqueued. to the front {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'M221' enqueued. {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'M27 P' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102350 G1 X96.47 Y88.513 E-.19764 *92#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102350 G1 X96.47 Y88.513 E-.19764 *92\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X96.47 Y88.513 E-.19764' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102351 G1 X96.6 Y88.058 E-.10923 *102#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: G1 X96.47 Y88.513 E-.19764 confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102351 G1 X96.6 Y88.058 E-.10923 *102\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 X97.382 Y88.256 E-.2218 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X97.382 Y88.256 E-.2218' enqueued. to the front {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X96.6 Y88.058 E-.10923' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102352 G1 X97.477 Y88.935 E-.2864 *85#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: G1 X96.6 Y88.058 E-.10923 confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102352 G1 X97.477 Y88.935 E-.2864 *85\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X97.477 Y88.935 E-.2864' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102353 G1 X98.061 Y88.935 E-.13493 *108#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 E-.05 F2100 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102353 G1 X98.061 Y88.935 E-.13493 *108\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 E-.05 F2100' enqueued. to the front {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 X97.477 Y88.935 E-.2864 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X98.061 Y88.935 E-.13493' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102354 G1 X97.382 Y88.256 E-.2218 *81#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 Z20 F720 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102354 G1 X97.382 Y88.256 E-.2218 *81\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 X97.382 Y88.256 E-.2218' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102355 G1 E-.05 F2100 *62#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[INFO]: 192.168.4.243 - "GET /api/printer HTTP/1.1" 200 536 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102355 G1 E-.05 F2100 *62\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 Z20 F720' enqueued. to the front {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 X98.061 Y88.935 E-.13493 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 X97.382 Y88.256 E-.2218 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 E-.05 F2100' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102356 G1 Z20 F720 *16#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102356 G1 Z20 F720 *16\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[INFO]: 192.168.4.243 - "GET /api/connection HTTP/1.1" 200 444 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 Z20 F720' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: M221#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'M221\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[INFO]: 192.168.4.243 - "GET /api/job HTTP/1.1" 200 542 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[WARNING]: Reported file sizes differ 13822128 vs 2940957 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: '95%' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: '95%' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^(?P\d{0,3})%$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'M221' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[INFO]: 192.168.4.243 - "GET /api/printer HTTP/1.1" 200 536 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: USB enqueuing gcode: G1 Z20.2 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 Z20.2' enqueued. to the front {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: N102357 G1 Z20.2 *94#012 sent {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'N102357 G1 Z20.2 *94\n' {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 E-.05 F2100 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 Z20 F720 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Throwing out trash G1 Z20.2 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'G1 Z20.2' confirmed {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread MainThread is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/bin/prusa-link", line 8, in #012 sys.exit(main())#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/__main__.py", line 239, in main#012 retval = daemon.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/daemon.py", line 79, in run#012 self.prusa_link.stopped_event.wait()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 574, in wait#012 signaled = self._cond.wait(timeout)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 312, in wait#012 waiter.acquire()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread http is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/web/__init__.py", line 70, in run_http#012 httpd.serve_forever()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socketserver.py", line 232, in serve_forever#012 ready = selector.select(poll_interval)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/selectors.py", line 416, in select#012 fd_event_list = self._selector.poll(timeout)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread serial_read_thread is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_adapter.py", line 127, in _read_continually#012 self.serial_parser.decide(line)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_parser.py", line 77, in decide#012 signal.send(self, match=match)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/blinker/base.py", line 266, in send#012 return [(receiver, receiver(sender, **kwargs))#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/blinker/base.py", line 266, in #012 return [(receiver, receiver(sender, **kwargs))#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_queue.py", line 322, in _confirmation_handler#012 self._confirmed()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_queue.py", line 601, in _confirmed#012 super()._confirmed(force=force)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_queue.py", line 403, in _confirmed#012 self._try_writing()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_queue.py", line 169, in _try_writing#012 with self.write_lock:#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread sq_stall_recovery is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_queue.py", line 570, in keep_monitoring#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 56, in run_slowly_die_fast#012 sleep(min(check_exit_in, run_again_in))#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread command_runner is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/sdk_augmentation/command_handler.py", line 31, in handle_commands#012 if self.sdk_command.new_cmd_evt.wait(QUIT_INTERVAL):#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/multiprocessing/synchronize.py", line 349, in wait#012 self._cond.wait(timeout)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/multiprocessing/synchronize.py", line 261, in wait#012 return self._wait_semaphore.acquire(True, timeout)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread temp_ensurer is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/updatable.py", line 60, in __keep_updating#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 56, in run_slowly_die_fast#012 sleep(min(check_exit_in, run_again_in))#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread printer_info_refresher is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/structures/item_updater.py", line 461, in _refresher#012 item = self.refresh_queue.get(timeout=self.quit_interval)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/queue.py", line 183, in get#012 return item#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 324, in wait#012 self._waiters.remove(waiter)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread printer_info_invalidator is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/structures/item_updater.py", line 479, in _process_invalidations#012 invalidate_at, item = self.invalidate_timers.get(#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/queue.py", line 183, in get#012 return item#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 324, in wait#012 self._waiters.remove(waiter)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread printer_info_timeout is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/structures/item_updater.py", line 504, in _process_timeouts#012 times_out_at, item = self.timeout_timers.get(#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/queue.py", line 183, in get#012 return item#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 324, in wait#012 self._waiters.remove(waiter)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread telemetry is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/updatable.py", line 60, in __keep_updating#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 48, in run_slowly_die_fast#012 to_run(*args, **kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/informers/telemetry_gatherer.py", line 102, in update#012 instruction = enqueue_matchable(self.serial_queue, gcode,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/helpers.py", line 64, in enqueue_matchable#012 queue.enqueue_one(instruction, to_front=to_front)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/serial/serial_queue.py", line 290, in enqueue_one#012 log.debug("%s enqueued. %s", instruction,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 126, in debug#012 self.log_rotator.process_log_entry(self.isEnabledFor(DEBUG), DEBUG,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 61, in process_log_entry#012 with self.log_lock:#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/multiprocessing/synchronize.py", line 95, in __enter__#012 return self._semlock.__enter__()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread sd_updater is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/updatable.py", line 60, in __keep_updating#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 56, in run_slowly_die_fast#012 sleep(min(check_exit_in, run_again_in))#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread fs_mounts_thread is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/updatable.py", line 60, in __keep_updating#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 48, in run_slowly_die_fast#012 to_run(*args, **kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/informers/filesystem/mounts.py", line 77, in update#012 self.data.mounted_set = new_mount_set#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/informers/filesystem/mounts.py", line 207, in get_mountpoints#012 return self.data.mounted_set#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread dir_mounts_thread is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/updatable.py", line 60, in __keep_updating#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 56, in run_slowly_die_fast#012 sleep(min(check_exit_in, run_again_in))#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: Thread ip_updater is executing this right now: {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:04 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/updatable.py", line 60, in __keep_updating#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 56, in run_slowly_die_fast#012 sleep(min(check_exit_in, run_again_in))#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread LCDMessage is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/lcd_printer.py", line 106, in show_status#012 self.wait_until(message_grace_end)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/input_output/lcd_printer.py", line 115, in wait_until#012 sleep(max(0.0, to_sleep))#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread command_queue is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/command_queue.py", line 92, in process_queue#012 adapter: CommandAdapter = self.command_queue.get(#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/queue.py", line 183, in get#012 return item#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 324, in wait#012 self._waiters.remove(waiter)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread telemetry_passer is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/prusa_link.py", line 730, in keep_sending_telemetry#012 run_slowly_die_fast(lambda: self.running, QUIT_INTERVAL,#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/util.py", line 56, in run_slowly_die_fast#012 sleep(min(check_exit_in, run_again_in))#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread loop is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/sdk_augmentation/printer.py", line 142, in loop#012 super().loop()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/connect/printer/__init__.py", line 624, in loop#012 item = self.queue.get(timeout=const.TIMESTAMP_PRECISION)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 590, in post#012 return self.request('POST', url, data=data, json=json, **kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 544, in request#012 return resp#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 699, in send#012 return r#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3/dist-packages/requests/adapters.py", line 533, in send#012 return self.build_response(request, resp)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 862, in urlopen#012 return response#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3/dist-packages/urllib3/connectionpool.py", line 474, in _make_request#012 return httplib_response#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/http/client.py", line 1361, in getresponse#012 return response#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/http/client.py", line 378, in begin#012 self.will_close = True#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/http/client.py", line 298, in _read_status#012 return version, status, reason#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socket.py", line 704, in readinto#012 return self._sock.recv_into(b)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/ssl.py", line 1241, in recv_into#012 return self.read(nbytes, buffer)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/ssl.py", line 1099, in read#012 return self._sslobj.read(len, buffer)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread inotify is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/sdk_augmentation/printer.py", line 150, in inotify_loop#012 sleep(0.2)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread download is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/sdk_augmentation/printer.py", line 158, in download_loop#012 self.download_mgr.loop()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/connect/printer/download.py", line 146, in loop#012 time.sleep(self.LOOP_INTERVAL)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread file_print is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 956, in _bootstrap_inner#012 self._invoke_excepthook(self)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 1214, in invoke_excepthook#012 hook(args)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/__main__.py", line 171, in #012 threading.excepthook = lambda exc_args: excepthook(exc_args, args, argv)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/__main__.py", line 37, in excepthook#012 InterestingLogRotator.trigger("exception in a thread")#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 85, in trigger#012 InterestingLogRotator.get_instance().instance_trigger(by_what)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 106, in instance_trigger#012 stack = traceback.extract_stack(current_frame)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread Thread-116190 is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socketserver.py", line 650, in process_request_thread#012 self.finish_request(request, client_address)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socketserver.py", line 360, in finish_request#012 self.RequestHandlerClass(request, client_address, self)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socketserver.py", line 720, in __init__#012 self.handle()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/web/lib/classes.py", line 74, in handle#012 handler.run(self.server.get_app())#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/wsgiref/handlers.py", line 137, in run#012 self.result = application(self.environ, self.start_response)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/poorwsgi/wsgi.py", line 1144, in __call__#012 return self.__request__(env, start_response)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/poorwsgi/request.py", line 697, in __del__#012 log.debug("Request: Hasta la vista, baby.")#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 126, in debug#012 self.log_rotator.process_log_entry(self.isEnabledFor(DEBUG), DEBUG,#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 61, in process_log_entry#012 with self.log_lock:#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/multiprocessing/synchronize.py", line 95, in __enter__#012 return self._semlock.__enter__()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Thread Thread-116191 is executing this right now: {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 912, in _bootstrap#012 self._bootstrap_inner()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 954, in _bootstrap_inner#012 self.run()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/threading.py", line 892, in run#012 self._target(*self._args, **self._kwargs)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socketserver.py", line 650, in process_request_thread#012 self.finish_request(request, client_address)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socketserver.py", line 360, in finish_request#012 self.RequestHandlerClass(request, client_address, self)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/socketserver.py", line 720, in __init__#012 self.handle()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/web/lib/classes.py", line 74, in handle#012 handler.run(self.server.get_app())#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/wsgiref/handlers.py", line 137, in run#012 self.result = application(self.environ, self.start_response)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/poorwsgi/wsgi.py", line 1144, in __call__#012 return self.__request__(env, start_response)#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/poorwsgi/request.py", line 697, in __del__#012 log.debug("Request: Hasta la vista, baby.")#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 126, in debug#012 self.log_rotator.process_log_entry(self.isEnabledFor(DEBUG), DEBUG,#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/home/pi/.local/lib/python3.9/site-packages/prusa/link/printer_adapter/interesting_logger.py", line 61, in process_log_entry#012 with self.log_lock:#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: File "/usr/lib/python3.9/multiprocessing/synchronize.py", line 95, in __enter__#012 return self._semlock.__enter__()#012 {excepthook():37} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'M220' enqueued. {enqueue_one():290} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {__del__():697} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {__del__():697} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: M220#012 sent {_send():271} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'M220\n' {write():152} Jun 9 23:08:05 PrusaLink prusa.link.web.lib.classes[468]: INFO: 192.168.4.243 - "GET /api/job HTTP/1.1" 200 542 {log_message():45} Jun 9 23:08:05 PrusaLink prusa.link.__main__[468]: ERROR: pop from an empty deque {excepthook():38} Jun 9 23:08:05 PrusaLink prusa.link.web.lib.classes[468]: INFO: 192.168.4.243 - "GET /api/download HTTP/1.1" 204 0 {log_message():45} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: '100%' {_read_continually():126} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: '100%' {decide():61} Jun 9 23:08:05 PrusaLink prusa.link.__main__[468]: WARNING: Caught unhandled exception, restarting Prusa Link {excepthook():45} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^(?P\d{0,3})%$ {decide():65} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3049442368, 3032651808): } {decide():76} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'ok' {_read_continually():126} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'ok' {decide():61} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^ok.*$ {decide():65} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3048720608, 3045150048): >} {decide():76} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {__del__():697} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Speed is 100% {speed_multiplier_result():281} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'M220' confirmed {_confirmed():387} Jun 9 23:08:05 PrusaLink prusa.link.web.lib.classes[468]: INFO: 192.168.4.243 - "GET /api/download HTTP/1.1" 204 0 {log_message():45} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'T:239.9 /240.0 B:75.7 /75.0 T0:239.9 /240.0 @:57 B@:0 P:0.0 A:36.1' {_read_continually():126} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'T:239.9 /240.0 B:75.7 /75.0 T0:239.9 /240.0 @:57 B@:0 P:0.0 A:36.1' {decide():61} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^T:(?P-?\d+\.\d+) /(?P-?\d+\.\d+) B:(?P-?\d+\.\d+) /(?P-?\d+\.\d+) T0:(-?\d+\.\d+) /(-?\d+\.\d+) @:(?P-?\d+) B@:(?P-?\d+) P:(?P-?\d+\.\d+) A:(?P-?\d+\.\d+)$ {decide():65} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3047816112, 3044025344): >, 3044627696: . at 0xb57954f0>} {decide():76} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'X:97.38 Y:88.26 Z:20.20 E:-0.95 Count X: 97.33 Y:88.47 Z:20.38 E:-0.95' {_read_continually():126} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'X:97.38 Y:88.26 Z:20.20 E:-0.95 Count X: 97.33 Y:88.47 Z:20.38 E:-0.95' {decide():61} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^X:(?P-?\d+\.\d+) Y:(?P-?\d+\.\d+) Z:(?P-?\d+\.\d+) E:(?P-?\d+\.\d+) Count X: (?P-?\d+\.\d+) Y:(?P-?\d+\.\d+) Z:(?P-?\d+\.\d+) E:(?P-?\d+\.\d+)$ {decide():65} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3047816256, 3044025344): >, 3044627768: . at 0xb5795538>} {decide():76} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Instruction 'M73' enqueued. {enqueue_one():290} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {__del__():697} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {__del__():697} Jun 9 23:08:05 PrusaLink prusa.link.web.lib.classes[468]: INFO: 192.168.4.243 - "GET /api/job HTTP/1.1" 200 542 {log_message():45} Jun 9 23:08:05 PrusaLink prusa.link.web.lib.classes[468]: INFO: 192.168.4.243 - "GET /api/printer HTTP/1.1" 200 536 {log_message():45} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Priority updated from 1654812485.6768181 to 1654812485.6768181 {add_handler():109} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: M73#012 sent {_send():271} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Sent to printer: b'M73\n' {write():152} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'E0:4140 RPM PRN1:2580 RPM E0@:255 PRN1@:76' {_read_continually():126} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'E0:4140 RPM PRN1:2580 RPM E0@:255 PRN1@:76' {decide():61} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched E0:(?P\d+) RPM PRN1:(?P\d+) RPM E0@:(?P\d+) PRN1@:(?P\d+) {decide():65} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: calling {(3047816400, 3044025344): >, 3044627840: . at 0xb5795580>} {decide():76} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Request: Hasta la vista, baby. {__del__():697} Jun 9 23:08:05 PrusaLink prusa.link.web.lib.classes[468]: INFO: 192.168.4.243 - "GET /api/download HTTP/1.1" 204 0 {log_message():45} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'NORMAL MODE: Percent done: 92; print time remaining in mins: 9; Change in mins: -1' {_read_continually():126} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'NORMAL MODE: Percent done: 92; print time remaining in mins: 9; Change in mins: -1' {decide():61} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Match not found {decide():72} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Printer says: 'SILENT MODE: Percent done: 92; print time remaining in mins: 9; Change in mins: -1' {_read_continually():126} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Deciding on handlers for line: 'SILENT MODE: Percent done: 92; print time remaining in mins: 9; Change in mins: -1' {decide():61} Jun 9 23:08:05 PrusaLink interesting_logger[468]: WARNING: Was[DEBUG]: Successfully matched ^SILENT MODE: Percent done: (?P-?\d+); print time remaining in mins: (?P