pi@duet3:~ $ sudo /opt/dsf/bin/DuetControlServer -l debug Duet Control Server v3.2.2 Written by Christian Hammacher for Duet3D Licensed under the terms of the GNU Public License Version 3 [info] Settings loaded [info] Environment initialized [info] Connection to Duet established [info] IPC socket created at /var/run/dsf/dcs.sock [debug] Connection to Linux established! [debug] Updated key limits [debug] Requesting update of key boards, seq 0 -> 0 [debug] Updated key boards [debug] Requesting update of key directories, seq 0 -> 0 [debug] Updated key directories [debug] Requesting update of key fans, seq 0 -> 2 [debug] Updated key fans [debug] Requesting update of key heat, seq 0 -> 12 [debug] Updated key heat [debug] Requesting update of key inputs, seq 0 -> 0 [debug] Updated key inputs [debug] Requesting update of key job, seq 0 -> 1 [debug] Updated key job [debug] Requesting update of key move, seq 0 -> 43 [debug] Updated key move [debug] Requesting update of key network, seq 0 -> 3 [debug] Updated key network [debug] Requesting update of key sensors, seq 0 -> 4 [debug] Updated key sensors [debug] Requesting update of key spindles, seq 0 -> 0 [debug] Updated key spindles [debug] Requesting update of key state, seq 0 -> 2 [debug] Updated key state [debug] Requesting update of key tools, seq 0 -> 5 [debug] Updated key tools [debug] Requesting update of key volumes, seq 0 -> 0 [debug] Updated key volumes [warn] Bad data CRC32 (expected 0x682df06b, got 0x5986aac1) [warn] Bad data CRC32 (expected 0x972004bc, got 0xa1108b40) [warn] Bad data CRC32 (expected 0xdb562d8d, got 0xa53eb15e) [warn] Bad data CRC32 (expected 0xdf985232, got 0x3c2524d9) [debug] IPC#2: Got new UNIX connection, checking permissions... [debug] IPC#2: Granting full DSF permissions to external plugin [debug] IPC#2: Subscription processor registered in Patch mode [debug] IPC#3: Got new UNIX connection, checking permissions... [debug] IPC#3: Granting full DSF permissions to external plugin [debug] IPC#3: Command processor added [debug] IPC#3: Received command ResolvePath [debug] IPC#4: Got new UNIX connection, checking permissions... [debug] IPC#4: Granting full DSF permissions to external plugin [debug] IPC#4: Subscription processor registered in Patch mode [debug] IPC#5: Got new UNIX connection, checking permissions... [debug] IPC#5: Granting full DSF permissions to external plugin [debug] IPC#5: Command processor added [debug] IPC#5: Received command AddUserSession [debug] IPC#6: Got new UNIX connection, checking permissions... [debug] IPC#6: Granting full DSF permissions to external plugin [debug] IPC#6: Command processor added [debug] IPC#6: Received command ResolvePath [debug] IPC#6: Connection closed [debug] IPC#7: Got new UNIX connection, checking permissions... [debug] IPC#7: Granting full DSF permissions to external plugin [debug] IPC#7: Command processor added [debug] IPC#7: Received command ResolvePath [debug] IPC#7: Connection closed [debug] IPC#8: Got new UNIX connection, checking permissions... [debug] IPC#8: Granting full DSF permissions to external plugin [debug] IPC#8: Command processor added [debug] IPC#8: Received command SimpleCode [debug] Waiting for execution of M106 P0 S0.46 [debug] Processing M106 P0 S0.46 [debug] HTTP: Sent M106 P0 S0.46, remaining space 1496, needed 40 [debug] Waiting for finish of M106 P0 S0.46 [debug] Completed M106 P0 S0.46 => [debug] IPC#8: Connection closed [debug] IPC#9: Got new UNIX connection, checking permissions... [debug] IPC#9: Granting full DSF permissions to external plugin [debug] IPC#9: Command processor added [debug] IPC#9: Received command SimpleCode [debug] Waiting for execution of M106 P0 S0.00 [debug] Processing M106 P0 S0.00 [debug] Waiting for finish of M106 P0 S0.00 [debug] HTTP: Sent M106 P0 S0.00, remaining space 1496, needed 40 [debug] Completed M106 P0 S0.00 => [debug] IPC#9: Connection closed [debug] IPC#10: Got new UNIX connection, checking permissions... [debug] IPC#10: Granting full DSF permissions to external plugin [debug] IPC#10: Command processor added [debug] IPC#10: Received command SimpleCode [debug] Waiting for execution of M106 P0 S0.91 [debug] Processing M106 P0 S0.91 [debug] Waiting for finish of M106 P0 S0.91 [debug] HTTP: Sent M106 P0 S0.91, remaining space 1496, needed 40 [debug] Completed M106 P0 S0.91 => [debug] IPC#10: Connection closed [debug] IPC#11: Got new UNIX connection, checking permissions... [debug] IPC#11: Granting full DSF permissions to external plugin [debug] IPC#11: Command processor added [debug] IPC#11: Received command SimpleCode [debug] Waiting for execution of M106 P0 S1.00 [debug] Processing M106 P0 S1.00 [debug] Waiting for finish of M106 P0 S1.00 [debug] HTTP: Sent M106 P0 S1.00, remaining space 1496, needed 40 [debug] Completed M106 P0 S1.00 => [debug] IPC#11: Connection closed [debug] IPC#12: Got new UNIX connection, checking permissions... [debug] IPC#12: Granting full DSF permissions to external plugin [debug] IPC#12: Command processor added [debug] IPC#12: Received command SimpleCode [debug] Waiting for execution of M106 P0 S0.00 [debug] Processing M106 P0 S0.00 [debug] Waiting for finish of M106 P0 S0.00 [debug] HTTP: Sent M106 P0 S0.00, remaining space 1496, needed 40 [debug] Completed M106 P0 S0.00 => [debug] IPC#12: Connection closed [debug] IPC#13: Got new UNIX connection, checking permissions... [debug] IPC#13: Granting full DSF permissions to external plugin [debug] IPC#13: Command processor added [debug] IPC#13: Received command SimpleCode [debug] Waiting for execution of M106 P0 S1.00 [debug] Processing M106 P0 S1.00 [debug] Waiting for finish of M106 P0 S1.00 [debug] HTTP: Sent M106 P0 S1.00, remaining space 1496, needed 40 [debug] Completed M106 P0 S1.00 => [debug] IPC#13: Connection closed [debug] IPC#14: Got new UNIX connection, checking permissions... [debug] IPC#14: Granting full DSF permissions to external plugin [debug] IPC#14: Command processor added [debug] IPC#14: Received command SimpleCode [debug] Waiting for execution of G28 [debug] Processing G28 [debug] Waiting for finish of G28 [debug] HTTP: Sent G28, remaining space 1512, needed 24 [debug] Requesting update of key move, seq 43 -> 44 [info] Starting macro file homeall.g on channel HTTP [debug] HTTP: ==> Starting code G28 [debug] Updated key move [debug] Waiting for execution of ; homeall.g (macro code) [debug] Processing ; homeall.g [debug] Waiting for execution of ; called to home all axes (macro code) [debug] Waiting for execution of ; (macro code) [debug] Waiting for execution of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) (macro code) [debug] Waiting for execution of G91 ; relative positioning (macro code) [debug] Waiting for finish of ; homeall.g [debug] Processing ; called to home all axes [debug] Waiting for finish of ; called to home all axes [debug] Processing ; [debug] Waiting for finish of ; [debug] Processing ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Waiting for finish of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Completed ; homeall.g [debug] Completed ; called to home all axes [debug] Completed ; [debug] Waiting for execution of G1 H2 Z15 F9000 ; lift Z relative to current position (macro code) [debug] Waiting for execution of G1 H1 X-420 Y-420 F4000 ; move quickly to X or Y endstop and stop there (first pass) (macro code) [debug] Processing G91 ; relative positioning [debug] Waiting for finish of G91 ; relative positioning [debug] Waiting for execution of G1 H1 X-420 ; home X axis (macro code) [debug] Completed ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Processing G1 H2 Z15 F9000 ; lift Z relative to current position [debug] Waiting for finish of G1 H2 Z15 F9000 ; lift Z relative to current position [debug] Processing G1 H1 X-420 Y-420 F4000 ; move quickly to X or Y endstop and stop there (first pass) [debug] Waiting for finish of G1 H1 X-420 Y-420 F4000 ; move quickly to X or Y endstop and stop there (first pass) [debug] Processing G1 H1 X-420 ; home X axis [debug] Waiting for finish of G1 H1 X-420 ; home X axis [debug] Waiting for execution of G1 H1 Y-420 ; home Y axis (macro code) [debug] Processing G1 H1 Y-420 ; home Y axis [debug] Waiting for finish of G1 H1 Y-420 ; home Y axis [debug] Waiting for execution of G1 X5 Y5 F9000 ; go back a few mm (macro code) [debug] Processing G1 X5 Y5 F9000 ; go back a few mm [debug] Waiting for execution of G1 H1 X-420 F360 ; move slowly to X axis endstop once more (second pass) (macro code) [debug] Waiting for finish of G1 X5 Y5 F9000 ; go back a few mm [debug] Waiting for execution of G1 H1 Y-420 ; then move slowly to Y axis endstop (macro code) [debug] Processing G1 H1 X-420 F360 ; move slowly to X axis endstop once more (second pass) [debug] Waiting for execution of G90 ; absolute positioning (macro code) [debug] Waiting for finish of G1 H1 X-420 F360 ; move slowly to X axis endstop once more (second pass) [debug] Waiting for execution of G1 X223 Y198 F9000 ; go to first bed probe point and home Z (macro code) [debug] Processing G1 H1 Y-420 ; then move slowly to Y axis endstop [debug] Waiting for execution of G30 ; home Z by probing the bed (macro code) [debug] Waiting for finish of G1 H1 Y-420 ; then move slowly to Y axis endstop [debug] Processing G90 ; absolute positioning [debug] Waiting for finish of G90 ; absolute positioning [debug] Processing G1 X223 Y198 F9000 ; go to first bed probe point and home Z [debug] Waiting for finish of G1 X223 Y198 F9000 ; go to first bed probe point and home Z [debug] Processing G30 ; home Z by probing the bed [debug] Waiting for execution of ; Uncomment the following lines to lift Z after probing (macro code) [debug] Waiting for finish of G30 ; home Z by probing the bed [debug] Waiting for execution of ;G91 ; relative positioning (macro code) [debug] Waiting for execution of ;G1 Z15 F150 ; lift Z relative to current position (macro code) [debug] Waiting for execution of ;G90 ; absolute positioning (macro code) [debug] Processing ; Uncomment the following lines to lift Z after probing [debug] Waiting for finish of ; Uncomment the following lines to lift Z after probing [debug] Processing ;G91 ; relative positioning [debug] Waiting for finish of ;G91 ; relative positioning [debug] HTTP: Sent G91 ; relative positioning, remaining space 1512, needed 24 [debug] Processing ;G1 Z15 F150 ; lift Z relative to current position [debug] Waiting for finish of ;G1 Z15 F150 ; lift Z relative to current position [debug] Processing ;G90 ; absolute positioning [debug] Waiting for finish of ;G90 ; absolute positioning [debug] HTTP: Sent G1 H2 Z15 F9000 ; lift Z relative to current position, remaining space 1464, needed 48 [debug] HTTP: Sent G1 H1 X-420 Y-420 F4000 ; move quickly to X or Y endstop and stop there (first pass), remaining space 1408, needed 56 [debug] HTTP: Sent G1 H1 X-420 ; home X axis, remaining space 1368, needed 40 [debug] HTTP: Sent G1 H1 Y-420 ; home Y axis, remaining space 1328, needed 40 [debug] HTTP: Sent G1 X5 Y5 F9000 ; go back a few mm, remaining space 1280, needed 48 [debug] HTTP: Sent G1 H1 X-420 F360 ; move slowly to X axis endstop once more (second pass), remaining space 1232, needed 48 [debug] HTTP: Sent G1 H1 Y-420 ; then move slowly to Y axis endstop, remaining space 1192, needed 40 [debug] HTTP: Sent G90 ; absolute positioning, remaining space 1168, needed 24 [debug] HTTP: Sent G1 X223 Y198 F9000 ; go to first bed probe point and home Z, remaining space 1120, needed 48 [debug] HTTP: Sent G30 ; home Z by probing the bed, remaining space 1096, needed 24 [debug] Completed G91 ; relative positioning [debug] Completed G1 H2 Z15 F9000 ; lift Z relative to current position [debug] Completed G1 H1 X-420 Y-420 F4000 ; move quickly to X or Y endstop and stop there (first pass) [debug] Requesting update of key move, seq 44 -> 45 [debug] Updated key move [debug] Completed G1 H1 X-420 ; home X axis [debug] Completed G1 H1 Y-420 ; home Y axis [debug] Completed G1 X5 Y5 F9000 ; go back a few mm [debug] Requesting update of key move, seq 45 -> 47 [debug] Updated key move [debug] Completed G1 H1 X-420 F360 ; move slowly to X axis endstop once more (second pass) [debug] Requesting update of key move, seq 47 -> 48 [debug] Updated key move [debug] Completed G90 ; absolute positioning [debug] Completed G1 H1 Y-420 ; then move slowly to Y axis endstop [debug] Completed G1 X223 Y198 F9000 ; go to first bed probe point and home Z [debug] Requesting update of key move, seq 48 -> 49 [debug] Updated key move [debug] Macro file deployprobe0.g not found [debug] HTTP: ==> Starting code G30 ; home Z by probing the bed [debug] HTTP: Disposing macro file deployprobe0.g [debug] HTTP: ==> Unfinished starting code: G30 ; home Z by probing the bed [info] Starting macro file deployprobe.g on channel HTTP [debug] HTTP: ==> Starting code G30 ; home Z by probing the bed [debug] Waiting for execution of ; deployprobe.g (macro code) [debug] Waiting for execution of ; called to deploy a physical Z probe (macro code) [debug] Waiting for execution of ; (macro code) [debug] Waiting for execution of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) (macro code) [debug] Waiting for execution of M280 P0 S10 ; deploy BLTouch (macro code) [debug] Processing ; deployprobe.g [debug] Waiting for finish of ; deployprobe.g [debug] Completed ; deployprobe.g [debug] Processing ; called to deploy a physical Z probe [debug] Waiting for finish of ; called to deploy a physical Z probe [debug] Processing ; [debug] Completed ; called to deploy a physical Z probe [debug] Waiting for finish of ; [debug] Completed ; [debug] Processing ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Waiting for finish of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Completed ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Processing M280 P0 S10 ; deploy BLTouch [debug] Waiting for finish of M280 P0 S10 ; deploy BLTouch [debug] HTTP: Sent M280 P0 S10 ; deploy BLTouch, remaining space 1496, needed 40 [debug] Completed M280 P0 S10 ; deploy BLTouch [debug] Finished codes from macro file deployprobe.g [info] Finished macro file deployprobe.g [debug] HTTP: ==> Unfinished starting code: G30 ; home Z by probing the bed [debug] HTTP: Disposing macro file deployprobe.g [debug] Macro file retractprobe0.g not found [debug] HTTP: ==> Starting code G30 ; home Z by probing the bed [debug] HTTP: Disposing macro file retractprobe0.g [debug] HTTP: ==> Unfinished starting code: G30 ; home Z by probing the bed [info] Starting macro file retractprobe.g on channel HTTP [debug] HTTP: ==> Starting code G30 ; home Z by probing the bed [debug] Waiting for execution of ; retractprobe.g (macro code) [debug] Processing ; retractprobe.g [debug] Waiting for execution of ; called to retract a physical Z probe (macro code) [debug] Waiting for finish of ; retractprobe.g [debug] Waiting for execution of ; (macro code) [debug] Completed ; retractprobe.g [debug] Processing ; called to retract a physical Z probe [debug] Waiting for execution of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) (macro code) [debug] Waiting for finish of ; called to retract a physical Z probe [debug] Completed ; called to retract a physical Z probe [debug] Waiting for execution of M280 P0 S90 ; retract BLTouch (macro code) [debug] Processing ; [debug] Waiting for finish of ; [debug] Completed ; [debug] Processing ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Waiting for finish of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Completed ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:17 GMT-0700 (Pacific Daylight Time) [debug] Processing M280 P0 S90 ; retract BLTouch [debug] Waiting for finish of M280 P0 S90 ; retract BLTouch [debug] HTTP: Sent M280 P0 S90 ; retract BLTouch, remaining space 1496, needed 40 [debug] Completed M280 P0 S90 ; retract BLTouch [debug] Finished codes from macro file retractprobe.g [info] Finished macro file retractprobe.g [debug] Requesting update of key move, seq 49 -> 50 [debug] Updated key move [debug] Requesting update of key sensors, seq 4 -> 5 [debug] HTTP: ==> Unfinished starting code: G30 ; home Z by probing the bed [debug] HTTP: Disposing macro file retractprobe.g [debug] Updated key sensors [warn] Bad data CRC32 (expected 0xf0444f81, got 0x07c47416) [debug] Completed G30 ; home Z by probing the bed [debug] Completed ; Uncomment the following lines to lift Z after probing [debug] Completed ;G91 ; relative positioning [debug] Completed ;G90 ; absolute positioning [debug] Completed ;G1 Z15 F150 ; lift Z relative to current position [debug] Finished codes from macro file homeall.g [info] Finished macro file homeall.g [debug] HTTP: ==> Unfinished starting code: G28 [debug] HTTP: Disposing macro file homeall.g [debug] Completed G28 => [debug] IPC#14: Connection closed [warn] Bad header CRC32 (expected 0x2cfcd960, got 0x5367e95f) [warn] Note: RepRapFirmware didn't receive valid data either (code 0x00000005) [debug] IPC#15: Got new UNIX connection, checking permissions... [debug] IPC#15: Granting full DSF permissions to external plugin [debug] IPC#15: Command processor added [debug] IPC#15: Received command SimpleCode [debug] Waiting for execution of M120 [debug] Processing M120 [debug] Waiting for execution of G91 [debug] Waiting for execution of G1 Z25 F6000 [debug] Waiting for execution of G90 [debug] Waiting for execution of M121 [debug] Waiting for finish of M120 [debug] Processing G91 [debug] Waiting for finish of G91 [debug] Processing G1 Z25 F6000 [debug] Waiting for finish of G1 Z25 F6000 [debug] Processing G90 [debug] Waiting for finish of G90 [debug] Processing M121 [debug] Waiting for finish of M121 [debug] HTTP: Sent M120, remaining space 1512, needed 24 [debug] HTTP: Sent G91, remaining space 1488, needed 24 [debug] HTTP: Sent G1 Z25 F6000, remaining space 1448, needed 40 [debug] HTTP: Sent G90, remaining space 1424, needed 24 [debug] HTTP: Sent M121, remaining space 1400, needed 24 [debug] Completed M120 => [debug] Completed G91 => [debug] Completed G1 Z25 F6000 => [debug] Completed G90 => [debug] Completed M121 => [debug] IPC#15: Connection closed [warn] Bad data CRC32 (expected 0x6d0e8691, got 0x417b1646) [debug] IPC#16: Got new UNIX connection, checking permissions... [debug] IPC#16: Granting full DSF permissions to external plugin [debug] IPC#16: Command processor added [debug] IPC#16: Received command SimpleCode [debug] Waiting for execution of M120 [debug] Waiting for execution of G91 [debug] Waiting for execution of G1 Z25 F6000 [debug] Waiting for execution of G90 [debug] Waiting for execution of M121 [debug] Processing M120 [debug] Waiting for finish of M120 [debug] Processing G91 [debug] Waiting for finish of G91 [debug] Processing G1 Z25 F6000 [debug] Waiting for finish of G1 Z25 F6000 [debug] Processing G90 [debug] Waiting for finish of G90 [debug] Processing M121 [debug] Waiting for finish of M121 [debug] HTTP: Sent M120, remaining space 1512, needed 24 [debug] HTTP: Sent G91, remaining space 1488, needed 24 [debug] HTTP: Sent G1 Z25 F6000, remaining space 1448, needed 40 [debug] HTTP: Sent G90, remaining space 1424, needed 24 [debug] HTTP: Sent M121, remaining space 1400, needed 24 [debug] Completed M120 => [debug] Completed G91 => [debug] Completed G1 Z25 F6000 => [debug] Completed G90 => [debug] Completed M121 => [debug] IPC#16: Connection closed [debug] IPC#17: Got new UNIX connection, checking permissions... [debug] IPC#17: Granting full DSF permissions to external plugin [debug] IPC#17: Command processor added [debug] IPC#17: Received command SimpleCode [debug] Waiting for execution of G10 P0 S205 [debug] Processing G10 P0 S205 [debug] Waiting for finish of G10 P0 S205 [debug] HTTP: Sent G10 P0 S205, remaining space 1496, needed 40 [debug] G10 P0 S205 [debug] Completed G10 P0 S205 => [debug] IPC#17: Connection closed [debug] IPC#18: Got new UNIX connection, checking permissions... [debug] IPC#18: Granting full DSF permissions to external plugin [debug] IPC#18: Command processor added [debug] IPC#18: Received command SimpleCode [debug] Waiting for execution of T0 [debug] Processing T0 [debug] Waiting for finish of T0 [debug] HTTP: Sent T0, remaining space 1512, needed 24 [info] Starting macro file tpre0.g on channel HTTP [debug] HTTP: ==> Starting code T0 [debug] Waiting for execution of ; tpre0.g (macro code) [debug] Processing ; tpre0.g [debug] Waiting for execution of ; called before tool 0 is selected (macro code) [debug] Waiting for finish of ; tpre0.g [debug] Waiting for execution of ; (macro code) [debug] Processing ; called before tool 0 is selected [debug] Completed ; tpre0.g [debug] Waiting for finish of ; called before tool 0 is selected [debug] Waiting for execution of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) (macro code) [debug] Completed ; called before tool 0 is selected [debug] Processing ; [debug] Waiting for finish of ; [debug] Completed ; [debug] Processing ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) [debug] Waiting for finish of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) [debug] Completed ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) [debug] Finished codes from macro file tpre0.g [info] Finished macro file tpre0.g [debug] HTTP: ==> Unfinished starting code: T0 [debug] HTTP: Disposing macro file tpre0.g [info] Starting macro file tpost0.g on channel HTTP [debug] HTTP: ==> Starting code T0 [debug] Waiting for execution of ; tpost0.g (macro code) [debug] Waiting for execution of ; called after tool 0 has been selected (macro code) [debug] Processing ; tpost0.g [debug] Waiting for execution of ; (macro code) [debug] Waiting for execution of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) (macro code) [debug] Waiting for execution of ; Wait for set temperatures to be reached (macro code) [debug] Waiting for execution of M116 P0 (macro code) [debug] Waiting for finish of ; tpost0.g [debug] Completed ; tpost0.g [debug] Processing ; called after tool 0 has been selected [debug] Waiting for finish of ; called after tool 0 has been selected [debug] Completed ; called after tool 0 has been selected [debug] Processing ; [debug] Waiting for finish of ; [debug] Completed ; [debug] Processing ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) [debug] Waiting for finish of ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) [debug] Completed ; generated by RepRapFirmware Configuration Tool v3.2.3 on Mon May 17 2021 20:10:18 GMT-0700 (Pacific Daylight Time) [debug] Processing ; Wait for set temperatures to be reached [debug] Waiting for finish of ; Wait for set temperatures to be reached [debug] Completed ; Wait for set temperatures to be reached [debug] Processing M116 P0 [debug] Waiting for finish of M116 P0 [debug] HTTP: Sent M116 P0, remaining space 1504, needed 32 [warn] Bad data CRC32 (expected 0x2fecf470, got 0x93b58d09) [warn] Bad data CRC32 (expected 0x3910f77b, got 0x3d33b5a6) [warn] Bad data CRC32 (expected 0x3910f77b, got 0xc0301e0d) [warn] Bad data CRC32 (expected 0xf8bde018, got 0xe97da245) [warn] Bad data CRC32 (expected 0x88986794, got 0x12becb9f) [warn] Bad data CRC32 (expected 0xcec1baad, got 0x489380fd) [warn] Bad data CRC32 (expected 0x8117ed76, got 0x05bccfd0) [warn] Bad data CRC32 (expected 0x572e6bee, got 0xc9cf7f5e) [debug] Completed M116 P0 [debug] Finished codes from macro file tpost0.g [info] Finished macro file tpost0.g [debug] HTTP: ==> Unfinished starting code: T0 [debug] HTTP: Disposing macro file tpost0.g [debug] Completed T0 => [debug] IPC#18: Connection closed [warn] Bad data CRC32 (expected 0xe8daee16, got 0x72a6e33e) [debug] IPC#19: Got new UNIX connection, checking permissions... [debug] IPC#19: Granting full DSF permissions to external plugin [debug] IPC#19: Command processor added [debug] IPC#19: Received command SimpleCode [debug] Waiting for execution of M120 [debug] Waiting for execution of M83 [debug] Waiting for execution of G1 E10 F300 [debug] Waiting for execution of M121 [debug] Processing M120 [debug] Waiting for finish of M120 [debug] Processing M83 [debug] Waiting for finish of M83 [debug] Processing G1 E10 F300 [debug] Waiting for finish of G1 E10 F300 [debug] Processing M121 [debug] Waiting for finish of M121 [debug] HTTP: Sent M120, remaining space 1512, needed 24 [debug] HTTP: Sent M83, remaining space 1488, needed 24 [debug] HTTP: Sent G1 E10 F300, remaining space 1448, needed 40 [debug] HTTP: Sent M121, remaining space 1424, needed 24 [debug] Completed M83 => [debug] Completed M120 => [debug] Completed M121 => [debug] Completed G1 E10 F300 => [debug] IPC#19: Connection closed [warn] Bad data CRC32 (expected 0x54ff05a2, got 0x44a839e2) [warn] Bad data CRC32 (expected 0xe5524b98, got 0x98420871) [warn] Bad data CRC32 (expected 0xd7c23992, got 0x8aa7bd4a) [warn] Bad data CRC32 (expected 0xd7c23992, got 0x8d0dd4a1) [debug] IPC#20: Got new UNIX connection, checking permissions... [debug] IPC#20: Granting full DSF permissions to external plugin [debug] IPC#20: Command processor added [debug] IPC#20: Received command SimpleCode [debug] Waiting for execution of M120 [debug] Waiting for execution of M83 [debug] Waiting for execution of G1 E100 F300 [debug] Processing M120 [debug] Waiting for execution of M121 [debug] Waiting for finish of M120 [debug] Processing M83 [debug] Waiting for finish of M83 [debug] Processing G1 E100 F300 [debug] Waiting for finish of G1 E100 F300 [debug] Processing M121 [debug] Waiting for finish of M121 [debug] HTTP: Sent M120, remaining space 1512, needed 24 [debug] HTTP: Sent M83, remaining space 1488, needed 24 [debug] HTTP: Sent G1 E100 F300, remaining space 1448, needed 40 [debug] HTTP: Sent M121, remaining space 1424, needed 24 [debug] Completed M120 => [debug] Completed M83 => [debug] Completed G1 E100 F300 => [debug] Completed M121 => [debug] IPC#20: Connection closed [warn] Bad data CRC32 (expected 0x6d2a6510, got 0x647fe3b9) [warn] Bad data CRC32 (expected 0x8524fec5, got 0xcc9c5433) [warn] Bad data CRC32 (expected 0x580a290b, got 0x3ed8fac4) [warn] Bad data CRC32 (expected 0x60ce8d98, got 0x22301bfe) [warn] Bad data CRC32 (expected 0xf4a10c91, got 0x209ad689) [warn] Bad header CRC32 (expected 0x7e7a235c, got 0x9fde0817) [warn] Note: RepRapFirmware didn't receive valid data either (code 0x00000005) [warn] Bad data CRC32 (expected 0x0cd696d8, got 0x94246e7a) [warn] Bad data CRC32 (expected 0x4771797f, got 0x481874f9) [warn] Bad data CRC32 (expected 0x4771797f, got 0xe59ac8d5) [warn] Bad data CRC32 (expected 0x4771797f, got 0x6fc93e18) [warn] Restarting transfer because the number of maximum retries has been exceeded [warn] Bad data CRC32 (expected 0x5f4b4635, got 0x56f0478d) [warn] Bad data CRC32 (expected 0xe70e3696, got 0x55994b71) [fatal] Abnormal program termination [fatal] SPI task faulted System.Exception: RepRapFirmware refused message format at DuetControlServer.SPI.DataTransfer.ExchangeHeader() in /home/christian/Duet3D/DuetSoftwareFramework/src/DuetControlServer/SPI/DataTransfer.cs:line 1283 at DuetControlServer.SPI.DataTransfer.PerformFullTransfer(Boolean connecting) in /home/christian/Duet3D/DuetSoftwareFramework/src/DuetControlServer/SPI/DataTransfer.cs:line 163 at DuetControlServer.SPI.Interface.Run() in /home/christian/Duet3D/DuetSoftwareFramework/src/DuetControlServer/SPI/Interface.cs:line 903 [debug] IPC#4: Subscriber connection requested to terminate [debug] IPC#2: Subscriber connection requested to terminate [debug] IPC#2: Subscription processor unregistered [fatal] SPI task faulted System.Exception: RepRapFirmware refused message format at DuetControlServer.SPI.DataTransfer.ExchangeHeader() in /home/christian/Duet3D/DuetSoftwareFramework/src/DuetControlServer/SPI/DataTransfer.cs:line 1283 at DuetControlServer.SPI.DataTransfer.PerformFullTransfer(Boolean connecting) in /home/christian/Duet3D/DuetSoftwareFramework/src/DuetControlServer/SPI/DataTransfer.cs:line 163 at DuetControlServer.SPI.Interface.Run() in /home/christian/Duet3D/DuetSoftwareFramework/src/DuetControlServer/SPI/Interface.cs:line 903 [debug] IPC#4: Subscription processor unregistered [debug] IPC#2: Connection closed [debug] Job task terminated [debug] Periodic updater task terminated [debug] IPC#3: Connection closed [debug] IPC#4: Connection closed [debug] IPC#5: Connection closed [debug] IPC task terminated [debug] Update task terminated [debug] Stopping plugins and saving their execution state [info] Application has shut down