klippy.log

Stats 852.4: gcodein=0  mcu: mcu_awake=0.006 mcu_task_avg=0.000011 mcu_task_stddev=0.000014 bytes_write=22137 bytes_read=23515 bytes_retransmit=9 bytes_invalid=0 send_seq=1215 receive_seq=1215 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=168009107 toolhead: mcu_awake=0.001 mcu_task_avg=0.000006 mcu_task_stddev=0.000004 bytes_write=1496 bytes_read=9811 bytes_retransmit=0 bytes_invalid=0 send_seq=165 receive_seq=165 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=1 freq=12000936 adj=12000278  main_mcu: temp=36.8 beacon: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=1534 bytes_read=17747 bytes_retransmit=0 bytes_invalid=0 send_seq=164 receive_seq=164 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=32001950 adj=32000207 coil_temp=19.5 refs=0 mcu_temp=23.56 supply_voltage=3.018 heater_bed: target=0 temp=20.4 pwm=0.000 sysload=0.27 cputime=3.009 memavail=14696344 print_time=50.324 buffer_time=0.000 print_stall=0 extruder: target=0 temp=22.0 pwm=0.000
Stats 853.4: gcodein=0  mcu: mcu_awake=0.007 mcu_task_avg=0.000010 mcu_task_stddev=0.000011 bytes_write=24603 bytes_read=26469 bytes_retransmit=9 bytes_invalid=0 send_seq=1383 receive_seq=1383 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=168009100 toolhead: mcu_awake=0.001 mcu_task_avg=0.000006 mcu_task_stddev=0.000004 bytes_write=1496 bytes_read=9811 bytes_retransmit=0 bytes_invalid=0 send_seq=165 receive_seq=165 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=2 freq=12000936 adj=12000286  main_mcu: temp=36.8 beacon: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=3143 bytes_read=20630 bytes_retransmit=0 bytes_invalid=0 send_seq=298 receive_seq=298 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=32001950 adj=32000228 coil_temp=19.5 refs=0 mcu_temp=23.56 supply_voltage=3.018 heater_bed: target=0 temp=20.6 pwm=0.000 sysload=0.27 cputime=3.163 memavail=14695828 print_time=51.324 buffer_time=0.000 print_stall=0 extruder: target=0 temp=22.0 pwm=0.000
Stats 854.4: gcodein=0  mcu: mcu_awake=0.007 mcu_task_avg=0.000010 mcu_task_stddev=0.000011 bytes_write=27020 bytes_read=29303 bytes_retransmit=9 bytes_invalid=0 send_seq=1555 receive_seq=1555 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=168009091 toolhead: mcu_awake=0.001 mcu_task_avg=0.000006 mcu_task_stddev=0.000004 bytes_write=1496 bytes_read=9811 bytes_retransmit=0 bytes_invalid=0 send_seq=165 receive_seq=165 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=3 freq=12000936 adj=12000289  main_mcu: temp=36.9 beacon: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=4745 bytes_read=23527 bytes_retransmit=0 bytes_invalid=0 send_seq=432 receive_seq=432 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=32001949 adj=32000240 coil_temp=19.5 refs=0 mcu_temp=23.56 supply_voltage=3.018 heater_bed: target=0 temp=20.5 pwm=0.000 sysload=0.27 cputime=3.303 memavail=14709128 print_time=52.324 buffer_time=0.000 print_stall=0 extruder: target=0 temp=22.0 pwm=0.000
Stats 855.4: gcodein=0  mcu: mcu_awake=0.007 mcu_task_avg=0.000010 mcu_task_stddev=0.000011 bytes_write=29526 bytes_read=32251 bytes_retransmit=9 bytes_invalid=0 send_seq=1724 receive_seq=1724 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=168009083 toolhead: mcu_awake=0.001 mcu_task_avg=0.000006 mcu_task_stddev=0.000004 bytes_write=1496 bytes_read=9811 bytes_retransmit=0 bytes_invalid=0 send_seq=165 receive_seq=165 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=4 freq=12000936 adj=12000293  main_mcu: temp=36.9 beacon: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=6359 bytes_read=26415 bytes_retransmit=0 bytes_invalid=0 send_seq=567 receive_seq=567 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=32001948 adj=32000244 coil_temp=19.5 refs=0 mcu_temp=23.56 supply_voltage=3.018 heater_bed: target=0 temp=20.6 pwm=0.000 sysload=0.25 cputime=3.411 memavail=14705656 print_time=53.324 buffer_time=0.000 print_stall=0 extruder: target=0 temp=22.0 pwm=0.000
Timeout with MCU 'toolhead' (eventtime=856.407311)
Transition to shutdown state: Lost communication with MCU 'toolhead'
Dumping gcode input 0 blocks
Dumping 20 requests for client 130067651160992
Received 816.279198: b'{"id":126261257740000,"method":"gcode/script","params":{"script":"HYPERLAPSE ACTION=STOP"}}'
Received 816.284009: b'{"id":126261257741392,"method":"info","params":{}}'
Received 816.284009: b'{"id":126261257740096,"method":"objects/list","params":{}}'
Received 816.284009: b'{"id":126261257742496,"method":"objects/list","params":{}}'
Received 816.284009: b'{"id":126261257743312,"method":"info","params":{}}'
Received 816.284009: b'{"id":126261257743504,"method":"objects/list","params":{}}'
Received 816.285887: b'{"id":126261259157088,"method":"objects/query","params":{"objects":{"gcode_move":null,"print_stats":null,"virtual_sdcard":null,"display_status":null,"gcode_macro TIMELAPSE_TAKE_FRAME":null,"toolhead":null}}}'
Received 816.294803: b'{"id":126261257739136,"method":"objects/subscribe","params":{"objects":{"gcode":null,"webhooks":null,"configfile":null,"mcu":null,"mcu toolhead":null,"gcode_move":null,"print_stats":null,"virtual_sdcard":null,"pause_resume":null,"display_status":null,"gcode_macro CANCEL_PRINT":null,"gcode_macro PAUSE":null,"gcode_macro RESUME":null,"gcode_macro SET_PAUSE_NEXT_LAYER":null,"gcode_macro SET_PAUSE_AT_LAYER":null,"gcode_macro SET_PRINT_STATS_INFO":null,"gcode_macro _TOOLHEAD_PARK_PAUSE_CANCEL":null,"gcode_macro _CLIENT_EXTRUDE":null,"gcode_macro _CLIENT_RETRACT":null,"gcode_macro _CLIENT_LINEAR_MOVE":null,"gcode_macro TEST_SPEED":null,"gcode_macro DUMP_VARIABLES":null,"gcode_macro OFF":null,"gcode_macro PARKFRONT":null,"gcode_macro PARKFRONTLOW":null,"gcode_macro PARKREAR":null,"gcode_macro PARKCENTER":null,"gcode_macro PARKBED":null,"gcode_macro CG28":null,"gcode_macro G32":null,"gcode_macro PRINT_START":null,"gcode_macro PRINT_END":null,"gcode_macro Park_Toolhead":null,"gcode_macro M600":null,"gcode_macro SWAP_RESUME":null,"gcode_macro QUERY_ENDSTOP_LOOP":null,"gcode_macro FAST_QGL":null,"gcode_macro DUMP_PARAMETERS":null,"gcode_macro M109":null,"gcode_macro M190":null,"gcode_macro BED_MESH_CALIBRATE":null,"gcode_macro VORON_PURGE":null,"gcode_macro SMART_PARK":null,"gcode_macro _KAMP_Settings":null,"gcode_macro GET_TIMELAPSE_SETUP":null,"gcode_macro _SET_TIMELAPSE_SETUP":null,"gcode_macro TIMELAPSE_TAKE_FRAME":null,"gcode_macro _TIMELAPSE_NEW_FRAME":null,"gcode_macro HYPERLAPSE":null,"gcode_macro TIMELAPSE_RENDER":null,"gcode_macro TEST_STREAM_DELAY":null,"stepper_enable":null,"tmc2209 extruder":null,"fan":null,"heaters":null,"heater_fan hotend_fan":null,"temperature_fan toolhead_mcu":null,"neopixel sb_leds":null,"gcode_macro _sb_vars":null,"gcode_macro _set_sb_leds":null,"gcode_macro _set_sb_leds_by_name":null,"gcode_macro _set_logo_leds":null,"gcode_macro _set_nozzle_leds":null,"gcode_macro set_logo_leds_off":null,"gcode_macro status_off":null,"gcode_macro status_ready":null,"gcode_macro status_busy":null,"gcode_macro status_heating":null,"gcode_macro status_leveling":null,"gcode_macro status_homing":null,"gcode_macro status_cleaning":null,"gcode_macro status_meshing":null,"gcode_macro status_calibrating_z":null,"gcode_macro status_printing":null,"temperature_sensor main_mcu":null,"exclude_object":null,"beacons":null,"bed_mesh":null,"mcu beacon":null,"quad_gantry_level":null,"probe":null,"temperature_sensor beacon_coil":null,"beacon":null,"tmc5160 stepper_y":null,"tmc5160 stepper_x":null,"tmc5160 stepper_z":null,"tmc5160 stepper_z1":null,"tmc5160 stepper_z2":null,"tmc5160 stepper_z3":null,"controller_fan driver_fan":null,"heater_bed":null,"heater_fan nevermore":null,"idle_timeout":null,"firmware_retraction":null,"output_pin caselight":null,"heater_fan exhaust_fan":null,"telemetry":null,"motion_report":null,"query_endstops":null,"system_stats":null,"manual_probe":null,"toolhead":null,"extruder":null},"response_template":{"method":"process_status_update"}}}'
Received 816.527863: b'{"id":126261257738512,"method":"objects/subscribe","params":{"objects":{"gcode":null,"webhooks":null,"configfile":null,"mcu":null,"mcu toolhead":null,"gcode_move":null,"print_stats":null,"virtual_sdcard":null,"pause_resume":null,"display_status":null,"gcode_macro CANCEL_PRINT":null,"gcode_macro PAUSE":null,"gcode_macro RESUME":null,"gcode_macro SET_PAUSE_NEXT_LAYER":null,"gcode_macro SET_PAUSE_AT_LAYER":null,"gcode_macro SET_PRINT_STATS_INFO":null,"gcode_macro _TOOLHEAD_PARK_PAUSE_CANCEL":null,"gcode_macro _CLIENT_EXTRUDE":null,"gcode_macro _CLIENT_RETRACT":null,"gcode_macro _CLIENT_LINEAR_MOVE":null,"gcode_macro TEST_SPEED":null,"gcode_macro DUMP_VARIABLES":null,"gcode_macro OFF":null,"gcode_macro PARKFRONT":null,"gcode_macro PARKFRONTLOW":null,"gcode_macro PARKREAR":null,"gcode_macro PARKCENTER":null,"gcode_macro PARKBED":null,"gcode_macro CG28":null,"gcode_macro G32":null,"gcode_macro PRINT_START":null,"gcode_macro PRINT_END":null,"gcode_macro Park_Toolhead":null,"gcode_macro M600":null,"gcode_macro SWAP_RESUME":null,"gcode_macro QUERY_ENDSTOP_LOOP":null,"gcode_macro FAST_QGL":null,"gcode_macro DUMP_PARAMETERS":null,"gcode_macro M109":null,"gcode_macro M190":null,"gcode_macro BED_MESH_CALIBRATE":null,"gcode_macro VORON_PURGE":null,"gcode_macro SMART_PARK":null,"gcode_macro _KAMP_Settings":null,"gcode_macro GET_TIMELAPSE_SETUP":null,"gcode_macro _SET_TIMELAPSE_SETUP":null,"gcode_macro TIMELAPSE_TAKE_FRAME":null,"gcode_macro _TIMELAPSE_NEW_FRAME":null,"gcode_macro HYPERLAPSE":null,"gcode_macro TIMELAPSE_RENDER":null,"gcode_macro TEST_STREAM_DELAY":null,"stepper_enable":null,"tmc2209 extruder":null,"fan":null,"heaters":null,"heater_fan hotend_fan":null,"temperature_fan toolhead_mcu":null,"neopixel sb_leds":null,"gcode_macro _sb_vars":null,"gcode_macro _set_sb_leds":null,"gcode_macro _set_sb_leds_by_name":null,"gcode_macro _set_logo_leds":null,"gcode_macro _set_nozzle_leds":null,"gcode_macro set_logo_leds_off":null,"gcode_macro status_off":null,"gcode_macro status_ready":null,"gcode_macro status_busy":null,"gcode_macro status_heating":null,"gcode_macro status_leveling":null,"gcode_macro status_homing":null,"gcode_macro status_cleaning":null,"gcode_macro status_meshing":null,"gcode_macro status_calibrating_z":null,"gcode_macro status_printing":null,"temperature_sensor main_mcu":null,"exclude_object":null,"beacons":null,"bed_mesh":null,"mcu beacon":null,"quad_gantry_level":null,"probe":null,"temperature_sensor beacon_coil":null,"beacon":null,"tmc5160 stepper_y":null,"tmc5160 stepper_x":null,"tmc5160 stepper_z":null,"tmc5160 stepper_z1":null,"tmc5160 stepper_z2":null,"tmc5160 stepper_z3":null,"controller_fan driver_fan":null,"heater_bed":null,"heater_fan nevermore":null,"idle_timeout":null,"firmware_retraction":null,"output_pin caselight":null,"heater_fan exhaust_fan":null,"telemetry":null,"motion_report":null,"query_endstops":null,"system_stats":null,"manual_probe":null,"toolhead":null,"extruder":null},"response_template":{"method":"process_status_update"}}}'
Received 816.778020: b'{"id":126261240296704,"method":"objects/subscribe","params":{"objects":{"webhooks":null,"print_stats":null,"temperature_fan toolhead_mcu":null,"temperature_sensor main_mcu":null,"temperature_sensor beacon_coil":null,"heater_bed":null,"extruder":null,"toolhead":null,"fan":null,"gcode":null,"configfile":null,"mcu":null,"mcu toolhead":null,"gcode_move":null,"virtual_sdcard":null,"pause_resume":null,"display_status":null,"gcode_macro CANCEL_PRINT":null,"gcode_macro PAUSE":null,"gcode_macro RESUME":null,"gcode_macro SET_PAUSE_NEXT_LAYER":null,"gcode_macro SET_PAUSE_AT_LAYER":null,"gcode_macro SET_PRINT_STATS_INFO":null,"gcode_macro _TOOLHEAD_PARK_PAUSE_CANCEL":null,"gcode_macro _CLIENT_EXTRUDE":null,"gcode_macro _CLIENT_RETRACT":null,"gcode_macro _CLIENT_LINEAR_MOVE":null,"gcode_macro TEST_SPEED":null,"gcode_macro DUMP_VARIABLES":null,"gcode_macro OFF":null,"gcode_macro PARKFRONT":null,"gcode_macro PARKFRONTLOW":null,"gcode_macro PARKREAR":null,"gcode_macro PARKCENTER":null,"gcode_macro PARKBED":null,"gcode_macro CG28":null,"gcode_macro G32":null,"gcode_macro PRINT_START":null,"gcode_macro PRINT_END":null,"gcode_macro Park_Toolhead":null,"gcode_macro M600":null,"gcode_macro SWAP_RESUME":null,"gcode_macro QUERY_ENDSTOP_LOOP":null,"gcode_macro FAST_QGL":null,"gcode_macro DUMP_PARAMETERS":null,"gcode_macro M109":null,"gcode_macro M190":null,"gcode_macro BED_MESH_CALIBRATE":null,"gcode_macro VORON_PURGE":null,"gcode_macro SMART_PARK":null,"gcode_macro _KAMP_Settings":null,"gcode_macro GET_TIMELAPSE_SETUP":null,"gcode_macro _SET_TIMELAPSE_SETUP":null,"gcode_macro TIMELAPSE_TAKE_FRAME":null,"gcode_macro _TIMELAPSE_NEW_FRAME":null,"gcode_macro HYPERLAPSE":null,"gcode_macro TIMELAPSE_RENDER":null,"gcode_macro TEST_STREAM_DELAY":null,"stepper_enable":null,"tmc2209 extruder":null,"heaters":null,"heater_fan hotend_fan":null,"neopixel sb_leds":null,"gcode_macro _sb_vars":null,"gcode_macro _set_sb_leds":null,"gcode_macro _set_sb_leds_by_name":null,"gcode_macro _set_logo_leds":null,"gcode_macro _set_nozzle_leds":null,"gcode_macro set_logo_leds_off":null,"gcode_macro status_off":null,"gcode_macro status_ready":null,"gcode_macro status_busy":null,"gcode_macro status_heating":null,"gcode_macro status_leveling":null,"gcode_macro status_homing":null,"gcode_macro status_cleaning":null,"gcode_macro status_meshing":null,"gcode_macro status_calibrating_z":null,"gcode_macro status_printing":null,"exclude_object":null,"beacons":null,"bed_mesh":null,"mcu beacon":null,"quad_gantry_level":null,"probe":null,"beacon":null,"tmc5160 stepper_y":null,"tmc5160 stepper_x":null,"tmc5160 stepper_z":null,"tmc5160 stepper_z1":null,"tmc5160 stepper_z2":null,"tmc5160 stepper_z3":null,"controller_fan driver_fan":null,"heater_fan nevermore":null,"idle_timeout":null,"firmware_retraction":null,"output_pin caselight":null,"heater_fan exhaust_fan":null,"telemetry":null,"motion_report":null,"query_endstops":null,"system_stats":null,"manual_probe":null},"response_template":{"method":"process_status_update"}}}'
Received 816.886503: b'{"id":126261258766240,"method":"objects/query","params":{"objects":{"extruder":["can_extrude"]}}}'
Received 817.029137: b'{"id":126261257739376,"method":"objects/subscribe","params":{"objects":{"webhooks":null,"print_stats":null,"temperature_fan toolhead_mcu":null,"temperature_sensor main_mcu":null,"temperature_sensor beacon_coil":null,"heater_bed":null,"extruder":null,"toolhead":null,"fan":null,"gcode":null,"configfile":null,"mcu":null,"mcu toolhead":null,"gcode_move":null,"virtual_sdcard":null,"pause_resume":null,"display_status":null,"gcode_macro CANCEL_PRINT":null,"gcode_macro PAUSE":null,"gcode_macro RESUME":null,"gcode_macro SET_PAUSE_NEXT_LAYER":null,"gcode_macro SET_PAUSE_AT_LAYER":null,"gcode_macro SET_PRINT_STATS_INFO":null,"gcode_macro _TOOLHEAD_PARK_PAUSE_CANCEL":null,"gcode_macro _CLIENT_EXTRUDE":null,"gcode_macro _CLIENT_RETRACT":null,"gcode_macro _CLIENT_LINEAR_MOVE":null,"gcode_macro TEST_SPEED":null,"gcode_macro DUMP_VARIABLES":null,"gcode_macro OFF":null,"gcode_macro PARKFRONT":null,"gcode_macro PARKFRONTLOW":null,"gcode_macro PARKREAR":null,"gcode_macro PARKCENTER":null,"gcode_macro PARKBED":null,"gcode_macro CG28":null,"gcode_macro G32":null,"gcode_macro PRINT_START":null,"gcode_macro PRINT_END":null,"gcode_macro Park_Toolhead":null,"gcode_macro M600":null,"gcode_macro SWAP_RESUME":null,"gcode_macro QUERY_ENDSTOP_LOOP":null,"gcode_macro FAST_QGL":null,"gcode_macro DUMP_PARAMETERS":null,"gcode_macro M109":null,"gcode_macro M190":null,"gcode_macro BED_MESH_CALIBRATE":null,"gcode_macro VORON_PURGE":null,"gcode_macro SMART_PARK":null,"gcode_macro _KAMP_Settings":null,"gcode_macro GET_TIMELAPSE_SETUP":null,"gcode_macro _SET_TIMELAPSE_SETUP":null,"gcode_macro TIMELAPSE_TAKE_FRAME":null,"gcode_macro _TIMELAPSE_NEW_FRAME":null,"gcode_macro HYPERLAPSE":null,"gcode_macro TIMELAPSE_RENDER":null,"gcode_macro TEST_STREAM_DELAY":null,"stepper_enable":null,"tmc2209 extruder":null,"heaters":null,"heater_fan hotend_fan":null,"neopixel sb_leds":null,"gcode_macro _sb_vars":null,"gcode_macro _set_sb_leds":null,"gcode_macro _set_sb_leds_by_name":null,"gcode_macro _set_logo_leds":null,"gcode_macro _set_nozzle_leds":null,"gcode_macro set_logo_leds_off":null,"gcode_macro status_off":null,"gcode_macro status_ready":null,"gcode_macro status_busy":null,"gcode_macro status_heating":null,"gcode_macro status_leveling":null,"gcode_macro status_homing":null,"gcode_macro status_cleaning":null,"gcode_macro status_meshing":null,"gcode_macro status_calibrating_z":null,"gcode_macro status_printing":null,"exclude_object":null,"beacons":null,"bed_mesh":null,"mcu beacon":null,"quad_gantry_level":null,"probe":null,"beacon":null,"tmc5160 stepper_y":null,"tmc5160 stepper_x":null,"tmc5160 stepper_z":null,"tmc5160 stepper_z1":null,"tmc5160 stepper_z2":null,"tmc5160 stepper_z3":null,"controller_fan driver_fan":null,"heater_fan nevermore":null,"idle_timeout":null,"firmware_retraction":null,"output_pin caselight":null,"heater_fan exhaust_fan":null,"telemetry":null,"motion_report":null,"query_endstops":null,"system_stats":null,"manual_probe":null},"response_template":{"method":"process_status_update"}}}'
Received 817.279752: b'{"id":126261257398768,"method":"objects/subscribe","params":{"objects":{"gcode_move":null,"print_stats":null,"virtual_sdcard":null,"display_status":null,"gcode_macro TIMELAPSE_TAKE_FRAME":null,"toolhead":null,"webhooks":null,"extruder":null,"heater_bed":null,"fan":null,"gcode":null,"configfile":null,"mcu":null,"mcu toolhead":null,"pause_resume":null,"gcode_macro CANCEL_PRINT":null,"gcode_macro PAUSE":null,"gcode_macro RESUME":null,"gcode_macro SET_PAUSE_NEXT_LAYER":null,"gcode_macro SET_PAUSE_AT_LAYER":null,"gcode_macro SET_PRINT_STATS_INFO":null,"gcode_macro _TOOLHEAD_PARK_PAUSE_CANCEL":null,"gcode_macro _CLIENT_EXTRUDE":null,"gcode_macro _CLIENT_RETRACT":null,"gcode_macro _CLIENT_LINEAR_MOVE":null,"gcode_macro TEST_SPEED":null,"gcode_macro DUMP_VARIABLES":null,"gcode_macro OFF":null,"gcode_macro PARKFRONT":null,"gcode_macro PARKFRONTLOW":null,"gcode_macro PARKREAR":null,"gcode_macro PARKCENTER":null,"gcode_macro PARKBED":null,"gcode_macro CG28":null,"gcode_macro G32":null,"gcode_macro PRINT_START":null,"gcode_macro PRINT_END":null,"gcode_macro Park_Toolhead":null,"gcode_macro M600":null,"gcode_macro SWAP_RESUME":null,"gcode_macro QUERY_ENDSTOP_LOOP":null,"gcode_macro FAST_QGL":null,"gcode_macro DUMP_PARAMETERS":null,"gcode_macro M109":null,"gcode_macro M190":null,"gcode_macro BED_MESH_CALIBRATE":null,"gcode_macro VORON_PURGE":null,"gcode_macro SMART_PARK":null,"gcode_macro _KAMP_Settings":null,"gcode_macro GET_TIMELAPSE_SETUP":null,"gcode_macro _SET_TIMELAPSE_SETUP":null,"gcode_macro _TIMELAPSE_NEW_FRAME":null,"gcode_macro HYPERLAPSE":null,"gcode_macro TIMELAPSE_RENDER":null,"gcode_macro TEST_STREAM_DELAY":null,"stepper_enable":null,"tmc2209 extruder":null,"heaters":null,"heater_fan hotend_fan":null,"temperature_fan toolhead_mcu":null,"neopixel sb_leds":null,"gcode_macro _sb_vars":null,"gcode_macro _set_sb_leds":null,"gcode_macro _set_sb_leds_by_name":null,"gcode_macro _set_logo_leds":null,"gcode_macro _set_nozzle_leds":null,"gcode_macro set_logo_leds_off":null,"gcode_macro status_off":null,"gcode_macro status_ready":null,"gcode_macro status_busy":null,"gcode_macro status_heating":null,"gcode_macro status_leveling":null,"gcode_macro status_homing":null,"gcode_macro status_cleaning":null,"gcode_macro status_meshing":null,"gcode_macro status_calibrating_z":null,"gcode_macro status_printing":null,"temperature_sensor main_mcu":null,"exclude_object":null,"beacons":null,"bed_mesh":null,"mcu beacon":null,"quad_gantry_level":null,"probe":null,"temperature_sensor beacon_coil":null,"beacon":null,"tmc5160 stepper_y":null,"tmc5160 stepper_x":null,"tmc5160 stepper_z":null,"tmc5160 stepper_z1":null,"tmc5160 stepper_z2":null,"tmc5160 stepper_z3":null,"controller_fan driver_fan":null,"heater_fan nevermore":null,"idle_timeout":null,"firmware_retraction":null,"output_pin caselight":null,"heater_fan exhaust_fan":null,"telemetry":null,"motion_report":null,"query_endstops":null,"system_stats":null,"manual_probe":null},"response_template":{"method":"process_status_update"}}}'
Received 817.913987: b'{"id":126261257872512,"method":"objects/query","params":{"objects":{"extruder":["can_extrude"]}}}'
Received 830.445032: b'{"id":126261257866032,"method":"objects/query","params":{"objects":{"print_stats":["state","message","filename","total_duration","print_duration","filament_used","info"],"display_status":["message","progress"],"toolhead":["position"],"system_stats":["sysload","memavail"],"mcu":["last_stats"],"mcu toolhead":["last_stats"],"gcode_move":["speed_factor"],"fan":["speed"],"heater_fan hotend_fan":["speed"],"temperature_fan toolhead_mcu":["temperature"],"temperature_sensor main_mcu":["temperature"],"mcu beacon":["last_stats"],"temperature_sensor beacon_coil":["temperature"],"controller_fan driver_fan":["speed"],"heater_bed":["temperature","target","power"],"heater_fan nevermore":["speed"],"heater_fan exhaust_fan":["speed"],"extruder":["temperature","target","power"],"output_pin caselight":["value"],"neopixel sb_leds":["color_data"],"status":["update_available"],"q":["u"]}}}'
Received 830.577504: b'{"id":126261257867616,"method":"info","params":{}}'
Received 830.579401: b'{"id":126261257864208,"method":"objects/query","params":{"objects":{"print_stats":["state","message","filename","total_duration","print_duration","filament_used","info"],"display_status":["message","progress"],"toolhead":["position"],"system_stats":["sysload","memavail"],"mcu":["last_stats"],"mcu toolhead":["last_stats"],"gcode_move":["speed_factor"],"fan":["speed"],"heater_fan hotend_fan":["speed"],"temperature_fan toolhead_mcu":["temperature"],"temperature_sensor main_mcu":["temperature"],"mcu beacon":["last_stats"],"temperature_sensor beacon_coil":["temperature"],"controller_fan driver_fan":["speed"],"heater_bed":["temperature","target","power"],"heater_fan nevermore":["speed"],"heater_fan exhaust_fan":["speed"],"extruder":["temperature","target","power"],"output_pin caselight":["value"],"neopixel sb_leds":["color_data"],"status":["update_available"],"q":["u"]}}}'
Received 832.060264: b'{"id":126261257877936,"method":"gcode/script","params":{"script":"G28"}}'
Received 834.996360: b'{"id":126261257865504,"method":"objects/list","params":{}}'
Received 834.999138: b'{"id":126261257873232,"method":"objects/query","params":{"objects":{"gcode_move":null,"print_stats":null,"virtual_sdcard":null,"display_status":null,"gcode_macro TIMELAPSE_TAKE_FRAME":null,"toolhead":null}}}'
gcode state: absolute_coord=True absolute_extrude=True base_position=[0.0, 0.0, 0.0, 0.0] last_position=[175.0, 141.042, 374.0, 0.0] homing_position=[0.0, 0.0, 0.0, 0.0] speed_factor=0.016666666666666666 extrude_factor=1.0 speed=25.0
Reactor garbage collection: (853.095911594, 0.0, 0.0)
Stats 856.4: gcodein=0  mcu: mcu_awake=0.007 mcu_task_avg=0.000010 mcu_task_stddev=0.000011 bytes_write=32062 bytes_read=35241 bytes_retransmit=9 bytes_invalid=0 send_seq=1893 receive_seq=1893 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=168009074 toolhead: mcu_awake=0.001 mcu_task_avg=0.000006 mcu_task_stddev=0.000004 bytes_write=1496 bytes_read=9811 bytes_retransmit=0 bytes_invalid=0 send_seq=165 receive_seq=165 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=5 freq=12000936 adj=12000297  main_mcu: temp=36.7 beacon: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=7961 bytes_read=29312 bytes_retransmit=0 bytes_invalid=0 send_seq=701 receive_seq=701 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=32001946 adj=32000252 coil_temp=19.5 refs=0 mcu_temp=23.56 supply_voltage=3.018 heater_bed: target=0 temp=20.5 pwm=0.000 sysload=0.25 cputime=3.567 memavail=14712840 print_time=54.324 buffer_time=0.000 print_stall=0 extruder: target=0 temp=22.0 pwm=0.000
Dumping stepper 'stepper_z' (mcu) 5 queue_step:
queue_step 0: t=9072752507 p=-89291 i=7000 c=-1200 a=0
queue_step 1: t=9081152507 p=-90491 i=7000 c=-1200 a=0
queue_step 2: t=9089552507 p=-91691 i=7000 c=-1200 a=0
queue_step 3: t=9097952507 p=-92891 i=7000 c=-1200 a=0
queue_step 4: t=9106352507 p=-94091 i=7000 c=-1200 a=0
Dumping stepper 'stepper_z1' (mcu) 5 queue_step:
queue_step 0: t=9072752507 p=-89291 i=7000 c=-1200 a=0
queue_step 1: t=9081152507 p=-90491 i=7000 c=-1200 a=0
queue_step 2: t=9089552507 p=-91691 i=7000 c=-1200 a=0
queue_step 3: t=9097952507 p=-92891 i=7000 c=-1200 a=0
queue_step 4: t=9106352507 p=-94091 i=7000 c=-1200 a=0
Dumping stepper 'stepper_z2' (mcu) 5 queue_step:
queue_step 0: t=9072752507 p=-89291 i=7000 c=-1200 a=0
queue_step 1: t=9081152507 p=-90491 i=7000 c=-1200 a=0
queue_step 2: t=9089552507 p=-91691 i=7000 c=-1200 a=0
queue_step 3: t=9097952507 p=-92891 i=7000 c=-1200 a=0
queue_step 4: t=9106352507 p=-94091 i=7000 c=-1200 a=0
Dumping stepper 'stepper_z3' (mcu) 5 queue_step:
queue_step 0: t=9072752507 p=-89291 i=7000 c=-1200 a=0
queue_step 1: t=9081152507 p=-90491 i=7000 c=-1200 a=0
queue_step 2: t=9089552507 p=-91691 i=7000 c=-1200 a=0
queue_step 3: t=9097952507 p=-92891 i=7000 c=-1200 a=0
queue_step 4: t=9106352507 p=-94091 i=7000 c=-1200 a=0
Dumping trapq 'toolhead' 1 moves:
move 0: pt=49.660191 mt=24.714286 sv=15.000000 a=0.000000 sp=(175.000000,141.042000,373.357143) ar=(0.000000,0.000000,-1.000000)
Requested toolhead position at shutdown time 54.131803: (175.0, 141.042, 306.2829719475528)
MCU 'beacon' shutdown: Emergency stop
clocksync state: mcu_freq=32000000 last_clock=27992883037 clock_est=(833.966 27303979500 32001946.469) min_half_rtt=0.000184 min_rtt_time=854.509 time_avg=833.966(214.906) clock_avg=27303979500.435(6877411760.725) pred_variance=188304151.593 clock_adj=(-821.555 32000251.500)
Dumping serial stats: bytes_write=7977 bytes_read=29342 bytes_retransmit=0 bytes_invalid=0 send_seq=703 receive_seq=703 retransmit_seq=0 srtt=0.001 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0
Dumping send queue 100 messages
Sent 0 855.675547 855.675547 12: seq: 1b, trsync_set_timeout oid=0 clock=2229695394
Sent 1 855.682802 855.682802 12: seq: 1c, trsync_set_timeout oid=0 clock=2229935395
Sent 2 855.690377 855.690377 12: seq: 1d, trsync_set_timeout oid=0 clock=2230175396
Sent 3 855.697831 855.697831 12: seq: 1e, trsync_set_timeout oid=0 clock=2230415398
Sent 4 855.705308 855.705308 12: seq: 1f, trsync_set_timeout oid=0 clock=2230655400
Sent 5 855.713015 855.713015 12: seq: 10, trsync_set_timeout oid=0 clock=2230895401
Sent 6 855.720379 855.720379 12: seq: 11, trsync_set_timeout oid=0 clock=2231135403
Sent 7 855.728035 855.728035 12: seq: 12, trsync_set_timeout oid=0 clock=2231375404
Sent 8 855.735543 855.735543 12: seq: 13, trsync_set_timeout oid=0 clock=2231615406
Sent 9 855.743039 855.743039 12: seq: 14, trsync_set_timeout oid=0 clock=2231855408
Sent 10 855.750556 855.750556 12: seq: 15, trsync_set_timeout oid=0 clock=2232095410
Sent 11 855.758008 855.758008 12: seq: 16, trsync_set_timeout oid=0 clock=2232335411
Sent 12 855.765545 855.765545 12: seq: 17, trsync_set_timeout oid=0 clock=2232575413
Sent 13 855.773043 855.773043 12: seq: 18, trsync_set_timeout oid=0 clock=2232815414
Sent 14 855.780541 855.780541 12: seq: 19, trsync_set_timeout oid=0 clock=2233055415
Sent 15 855.788022 855.788022 12: seq: 1a, trsync_set_timeout oid=0 clock=2233295417
Sent 16 855.795537 855.795537 12: seq: 1b, trsync_set_timeout oid=0 clock=2233535419
Sent 17 855.803033 855.803033 12: seq: 1c, trsync_set_timeout oid=0 clock=2233775421
Sent 18 855.810528 855.810528 12: seq: 1d, trsync_set_timeout oid=0 clock=2234015422
Sent 19 855.818030 855.818030 12: seq: 1e, trsync_set_timeout oid=0 clock=2234255424
Sent 20 855.825520 855.825520 12: seq: 1f, trsync_set_timeout oid=0 clock=2234495425
Sent 21 855.832971 855.832971 12: seq: 10, trsync_set_timeout oid=0 clock=2234735427
Sent 22 855.840526 855.840526 12: seq: 11, trsync_set_timeout oid=0 clock=2234975429
Sent 23 855.847887 855.847887 12: seq: 12, trsync_set_timeout oid=0 clock=2235215433
Sent 24 855.855514 855.855514 12: seq: 13, trsync_set_timeout oid=0 clock=2235455432
Sent 25 855.863028 855.863028 12: seq: 14, trsync_set_timeout oid=0 clock=2235695433
Sent 26 855.870351 855.870351 12: seq: 15, trsync_set_timeout oid=0 clock=2235935435
Sent 27 855.878030 855.878030 12: seq: 16, trsync_set_timeout oid=0 clock=2236175437
Sent 28 855.885459 855.885459 12: seq: 17, trsync_set_timeout oid=0 clock=2236415441
Sent 29 855.892788 855.892788 12: seq: 18, trsync_set_timeout oid=0 clock=2236655440
Sent 30 855.900293 855.900293 12: seq: 19, trsync_set_timeout oid=0 clock=2236895442
Sent 31 855.908020 855.908020 12: seq: 1a, trsync_set_timeout oid=0 clock=2237135443
Sent 32 855.915307 855.915307 12: seq: 1b, trsync_set_timeout oid=0 clock=2237375445
Sent 33 855.923007 855.923007 12: seq: 1c, trsync_set_timeout oid=0 clock=2237615449
Sent 34 855.930514 855.930514 12: seq: 1d, trsync_set_timeout oid=0 clock=2237855448
Sent 35 855.938025 855.938025 12: seq: 1e, trsync_set_timeout oid=0 clock=2238095450
Sent 36 855.945511 855.945511 12: seq: 1f, trsync_set_timeout oid=0 clock=2238335451
Sent 37 855.953021 855.953021 12: seq: 10, trsync_set_timeout oid=0 clock=2238575453
Sent 38 855.960507 855.960507 12: seq: 11, trsync_set_timeout oid=0 clock=2238815455
Sent 39 855.968025 855.968025 12: seq: 12, trsync_set_timeout oid=0 clock=2239055456
Sent 40 855.975520 855.975520 12: seq: 13, trsync_set_timeout oid=0 clock=2239295459
Sent 41 855.983030 855.983030 12: seq: 14, trsync_set_timeout oid=0 clock=2239535459
Sent 42 855.990515 855.990515 12: seq: 15, trsync_set_timeout oid=0 clock=2239775461
Sent 43 855.998015 855.998015 12: seq: 16, trsync_set_timeout oid=0 clock=2240015463
Sent 44 856.005511 856.005511 12: seq: 17, trsync_set_timeout oid=0 clock=2240255464
Sent 45 856.013009 856.013009 12: seq: 18, trsync_set_timeout oid=0 clock=2240495466
Sent 46 856.020407 856.020407 12: seq: 19, trsync_set_timeout oid=0 clock=2240735467
Sent 47 856.028024 856.028024 12: seq: 1a, trsync_set_timeout oid=0 clock=2240975469
Sent 48 856.035502 856.035502 12: seq: 1b, trsync_set_timeout oid=0 clock=2241215471
Sent 49 856.043010 856.043010 12: seq: 1c, trsync_set_timeout oid=0 clock=2241455473
Sent 50 856.050520 856.050520 12: seq: 1d, trsync_set_timeout oid=0 clock=2241695475
Sent 51 856.058019 856.058019 12: seq: 1e, trsync_set_timeout oid=0 clock=2241935476
Sent 52 856.065506 856.065506 12: seq: 1f, trsync_set_timeout oid=0 clock=2242175478
Sent 53 856.073002 856.073002 12: seq: 10, trsync_set_timeout oid=0 clock=2242415479
Sent 54 856.080507 856.080507 12: seq: 11, trsync_set_timeout oid=0 clock=2242655483
Sent 55 856.087888 856.087888 12: seq: 12, trsync_set_timeout oid=0 clock=2242895482
Sent 56 856.095321 856.095321 12: seq: 13, trsync_set_timeout oid=0 clock=2243135484
Sent 57 856.102847 856.102847 12: seq: 14, trsync_set_timeout oid=0 clock=2243375486
Sent 58 856.110501 856.110501 12: seq: 15, trsync_set_timeout oid=0 clock=2243615487
Sent 59 856.117982 856.117982 12: seq: 16, trsync_set_timeout oid=0 clock=2243855489
Sent 60 856.125490 856.125490 12: seq: 17, trsync_set_timeout oid=0 clock=2244095490
Sent 61 856.133003 856.133003 12: seq: 18, trsync_set_timeout oid=0 clock=2244335492
Sent 62 856.140361 856.140361 12: seq: 19, trsync_set_timeout oid=0 clock=2244575493
Sent 63 856.147768 856.147768 12: seq: 1a, trsync_set_timeout oid=0 clock=2244815495
Sent 64 856.155509 856.155509 12: seq: 1b, trsync_set_timeout oid=0 clock=2245055497
Sent 65 856.163015 856.163015 12: seq: 1c, trsync_set_timeout oid=0 clock=2245295498
Sent 66 856.170433 856.170433 12: seq: 1d, trsync_set_timeout oid=0 clock=2245535500
Sent 67 856.177997 856.177997 12: seq: 1e, trsync_set_timeout oid=0 clock=2245775502
Sent 68 856.185347 856.185347 12: seq: 1f, trsync_set_timeout oid=0 clock=2246015503
Sent 69 856.193016 856.193016 12: seq: 10, trsync_set_timeout oid=0 clock=2246254881
Sent 70 856.200512 856.200512 12: seq: 11, trsync_set_timeout oid=0 clock=2246494884
Sent 71 856.207963 856.207963 12: seq: 12, trsync_set_timeout oid=0 clock=2246734885
Sent 72 856.215507 856.215507 12: seq: 13, trsync_set_timeout oid=0 clock=2246974886
Sent 73 856.223006 856.223006 12: seq: 14, trsync_set_timeout oid=0 clock=2247214888
Sent 74 856.230510 856.230510 12: seq: 15, trsync_set_timeout oid=0 clock=2247454889
Sent 75 856.238001 856.238001 12: seq: 16, trsync_set_timeout oid=0 clock=2247694891
Sent 76 856.245510 856.245510 12: seq: 17, trsync_set_timeout oid=0 clock=2247934893
Sent 77 856.252977 856.252977 12: seq: 18, trsync_set_timeout oid=0 clock=2248174897
Sent 78 856.260499 856.260499 12: seq: 19, trsync_set_timeout oid=0 clock=2248414896
Sent 79 856.268007 856.268007 12: seq: 1a, trsync_set_timeout oid=0 clock=2248654898
Sent 80 856.275512 856.275512 12: seq: 1b, trsync_set_timeout oid=0 clock=2248894900
Sent 81 856.283027 856.283027 12: seq: 1c, trsync_set_timeout oid=0 clock=2249134901
Sent 82 856.290485 856.290485 12: seq: 1d, trsync_set_timeout oid=0 clock=2249374906
Sent 83 856.297973 856.297973 12: seq: 1e, trsync_set_timeout oid=0 clock=2249614905
Sent 84 856.305490 856.305490 12: seq: 1f, trsync_set_timeout oid=0 clock=2249854906
Sent 85 856.312991 856.312991 12: seq: 10, trsync_set_timeout oid=0 clock=2250094907
Sent 86 856.320419 856.320419 12: seq: 11, trsync_set_timeout oid=0 clock=2250334909
Sent 87 856.328004 856.328004 12: seq: 12, trsync_set_timeout oid=0 clock=2250574914
Sent 88 856.335489 856.335489 12: seq: 13, trsync_set_timeout oid=0 clock=2250814912
Sent 89 856.342856 856.342856 12: seq: 14, trsync_set_timeout oid=0 clock=2251054915
Sent 90 856.350504 856.350504 12: seq: 15, trsync_set_timeout oid=0 clock=2251294916
Sent 91 856.357929 856.357929 12: seq: 16, trsync_set_timeout oid=0 clock=2251534918
Sent 92 856.365479 856.365479 12: seq: 17, trsync_set_timeout oid=0 clock=2251774923
Sent 93 856.372855 856.372855 12: seq: 18, trsync_set_timeout oid=0 clock=2252014921
Sent 94 856.380484 856.380484 12: seq: 19, trsync_set_timeout oid=0 clock=2252254922
Sent 95 856.388003 856.388003 12: seq: 1a, trsync_set_timeout oid=0 clock=2252494924
Sent 96 856.395283 856.395283 12: seq: 1b, trsync_set_timeout oid=0 clock=2252734925
Sent 97 856.402834 856.402834 12: seq: 1c, trsync_set_timeout oid=0 clock=2252974928
Sent 98 856.409750 856.409750 8: seq: 1d, emergency_stop, beacon_stream en=0
Sent 99 856.411110 856.411110 8: seq: 1e, trsync_trigger oid=0 reason=2
Dumping receive queue 100 messages
Receive: 0 855.769398 855.765545 14: seq: 18, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2231894089
Receive: 1 855.769440 855.765545 16: seq: 18, beacon_status mcu_temp=44416 supply_voltage=49360 coil_temp=56255 status=1
Receive: 2 855.776880 855.773043 14: seq: 19, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2232133379
Receive: 3 855.784233 855.780541 14: seq: 1a, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2232374087
Receive: 4 855.791874 855.788022 14: seq: 1b, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2232613379
Receive: 5 855.799125 855.795537 14: seq: 1c, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2232854089
Receive: 6 855.806869 855.803033 14: seq: 1d, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2233093379
Receive: 7 855.814341 855.810528 14: seq: 1e, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2233334094
Receive: 8 855.820361 855.818030 18: seq: 1f, beacon_data samples=1 start_clock=2233494092 delta_clock=0 data=b'\x82\x91:\x1d'
Receive: 9 855.821868 855.818030 14: seq: 1f, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2233573379
Receive: 10 855.829342 855.825520 14: seq: 10, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2233814103
Receive: 11 855.836843 855.832971 14: seq: 11, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2234053379
Receive: 12 855.844361 855.840526 14: seq: 12, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2234294080
Receive: 13 855.851853 855.847887 14: seq: 13, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2234533379
Receive: 14 855.859360 855.855514 14: seq: 14, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2234774156
Receive: 15 855.866857 855.863028 14: seq: 15, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2235013379
Receive: 16 855.869873 855.863028 16: seq: 15, beacon_status mcu_temp=44416 supply_voltage=49360 coil_temp=56255 status=1
Receive: 17 855.874343 855.870351 14: seq: 16, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2235254253
Receive: 18 855.881704 855.878030 14: seq: 17, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2235493379
Receive: 19 855.889167 855.885459 14: seq: 18, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2235734265
Receive: 20 855.896583 855.892788 14: seq: 19, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2235973379
Receive: 21 855.904358 855.900293 14: seq: 1a, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2236214269
Receive: 22 855.911868 855.908020 14: seq: 1b, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2236453379
Receive: 23 855.919353 855.915307 14: seq: 1c, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2236694270
Receive: 24 855.920230 855.915307 18: seq: 1c, beacon_data samples=1 start_clock=2236694898 delta_clock=0 data=b'\x82\x91:\x1d'
Receive: 25 855.926856 855.923007 14: seq: 1d, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2236933379
Receive: 26 855.934234 855.930514 14: seq: 1e, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2237174265
Receive: 27 855.941859 855.938025 14: seq: 1f, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2237413379
Receive: 28 855.949359 855.945511 14: seq: 10, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2237654267
Receive: 29 855.956868 855.953021 14: seq: 11, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2237893379
Receive: 30 855.964355 855.960507 14: seq: 12, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2238134267
Receive: 31 855.970060 855.968025 16: seq: 13, beacon_status mcu_temp=44416 supply_voltage=49360 coil_temp=56255 status=1
Receive: 32 855.971865 855.968025 14: seq: 13, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2238373379
Receive: 33 855.979364 855.975520 14: seq: 14, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2238614264
Receive: 34 855.986849 855.983030 14: seq: 15, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2238853379
Receive: 35 855.994350 855.990515 14: seq: 16, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2239094270
Receive: 36 856.001875 855.998015 14: seq: 17, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2239333379
Receive: 37 856.009353 856.005511 14: seq: 18, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2239574267
Receive: 38 856.016852 856.013009 14: seq: 19, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2239813379
Receive: 39 856.020379 856.013009 18: seq: 19, beacon_data samples=1 start_clock=2239895695 delta_clock=0 data=b'\x82\x917{'
Receive: 40 856.024313 856.020407 14: seq: 1a, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2240053911
Receive: 41 856.031666 856.028024 14: seq: 1b, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2240293379
Receive: 42 856.039352 856.035502 14: seq: 1c, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2240533920
Receive: 43 856.046857 856.043010 14: seq: 1d, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2240773379
Receive: 44 856.054358 856.050520 14: seq: 1e, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2241013953
Receive: 45 856.061841 856.058019 14: seq: 1f, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2241253379
Receive: 46 856.069338 856.065506 14: seq: 10, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2241494060
Receive: 47 856.070141 856.065506 16: seq: 10, beacon_status mcu_temp=44416 supply_voltage=49360 coil_temp=56255 status=1
Receive: 48 856.076844 856.073002 14: seq: 11, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2241733379
Receive: 49 856.084339 856.080507 14: seq: 12, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2241974075
Receive: 50 856.091831 856.087888 14: seq: 13, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2242213379
Receive: 51 856.099123 856.095321 14: seq: 14, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2242454075
Receive: 52 856.106821 856.102847 14: seq: 15, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2242693379
Receive: 53 856.114352 856.110501 14: seq: 16, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2242934078
Receive: 54 856.120364 856.117982 18: seq: 17, beacon_data samples=1 start_clock=2243096492 delta_clock=0 data=b'\x82\x917{'
Receive: 55 856.121854 856.117982 14: seq: 17, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2243173379
Receive: 56 856.129333 856.125490 14: seq: 18, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2243414071
Receive: 57 856.136822 856.133003 14: seq: 19, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2243653379
Receive: 58 856.144123 856.140361 14: seq: 1a, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2243894062
Receive: 59 856.151629 856.147768 14: seq: 1b, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2244133379
Receive: 60 856.159347 856.155509 14: seq: 1c, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2244374124
Receive: 61 856.166840 856.163015 14: seq: 1d, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2244613379
Receive: 62 856.170405 856.163015 16: seq: 1d, beacon_status mcu_temp=44416 supply_voltage=49360 coil_temp=56255 status=1
Receive: 63 856.174355 856.170433 14: seq: 1e, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2244854220
Receive: 64 856.181839 856.177997 14: seq: 1f, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2245093379
Receive: 65 856.189361 856.185347 14: seq: 10, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2245333869
Receive: 66 856.196844 856.193016 14: seq: 11, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2245573379
Receive: 67 856.204361 856.200512 14: seq: 12, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2245813865
Receive: 68 856.211844 856.207963 14: seq: 13, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2246053379
Receive: 69 856.219334 856.215507 14: seq: 14, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2246293866
Receive: 70 856.220398 856.215507 18: seq: 14, beacon_data samples=1 start_clock=2246297285 delta_clock=0 data=b'\x82\x917{'
Receive: 71 856.226829 856.223006 14: seq: 15, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2246533379
Receive: 72 856.234356 856.230510 14: seq: 16, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2246773864
Receive: 73 856.241823 856.238001 14: seq: 17, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2247013379
Receive: 74 856.249215 856.245510 14: seq: 18, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2247253869
Receive: 75 856.256839 856.252977 14: seq: 19, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2247493379
Receive: 76 856.264355 856.260499 14: seq: 1a, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2247733855
Receive: 77 856.270631 856.268007 16: seq: 1b, beacon_status mcu_temp=44416 supply_voltage=49360 coil_temp=56255 status=1
Receive: 78 856.271840 856.268007 14: seq: 1b, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2247973379
Receive: 79 856.279164 856.275512 14: seq: 1c, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2248213673
Receive: 80 856.286812 856.283027 14: seq: 1d, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2248453379
Receive: 81 856.294363 856.290485 14: seq: 1e, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2248693788
Receive: 82 856.301824 856.297973 14: seq: 1f, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2248933379
Receive: 83 856.309342 856.305490 14: seq: 10, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2249173901
Receive: 84 856.316820 856.312991 14: seq: 11, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2249413379
Receive: 85 856.320394 856.312991 18: seq: 11, beacon_data samples=1 start_clock=2249498086 delta_clock=0 data=b'\x82\x91:\x1d'
Receive: 86 856.324341 856.320419 14: seq: 12, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2249653920
Receive: 87 856.331806 856.328004 14: seq: 13, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2249893379
Receive: 88 856.339362 856.335489 14: seq: 14, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2250133920
Receive: 89 856.346806 856.342856 14: seq: 15, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2250373379
Receive: 90 856.354351 856.350504 14: seq: 16, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2250613923
Receive: 91 856.361657 856.357929 14: seq: 17, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2250853379
Receive: 92 856.369353 856.365479 14: seq: 18, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2251093913
Receive: 93 856.370711 856.365479 16: seq: 18, beacon_status mcu_temp=44416 supply_voltage=49360 coil_temp=56256 status=1
Receive: 94 856.376694 856.372855 14: seq: 19, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2251333379
Receive: 95 856.384351 856.380484 14: seq: 1a, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2251573905
Receive: 96 856.391651 856.388003 14: seq: 1b, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2251813379
Receive: 97 856.399189 856.395283 14: seq: 1c, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2252053963
Receive: 98 856.406782 856.402834 14: seq: 1d, trsync_state oid=0 can_trigger=1 trigger_reason=0 clock=2252293379
Receive: 99 856.410613 856.409750 12: seq: 1e, shutdown clock=2252416222 static_string_id=Emergency stop
MCU 'mcu' shutdown: Command request
clocksync state: mcu_freq=168000000 last_clock=9056421197 clock_est=(834.199 5362497109 168009074.585) min_half_rtt=0.000046 min_rtt_time=817.788 time_avg=834.199(233.250) clock_avg=5362497109.810(39188166179.560) pred_variance=4852048343.857
Dumping serial stats: bytes_write=32068 bytes_read=35253 bytes_retransmit=9 bytes_invalid=0 send_seq=1894 receive_seq=1894 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0
Dumping send queue 100 messages
Sent 0 855.851957 855.851957 12: seq: 12, trsync_set_timeout oid=13 clock=414599761
Sent 1 855.859466 855.859466 12: seq: 13, trsync_set_timeout oid=13 clock=415863832
Sent 2 855.866973 855.866973 12: seq: 14, trsync_set_timeout oid=13 clock=417119744
Sent 3 855.874462 855.874462 12: seq: 15, trsync_set_timeout oid=13 clock=418384324
Sent 4 855.881799 855.881799 12: seq: 16, trsync_set_timeout oid=13 clock=419639727
Sent 5 855.883274 855.883274 33: seq: 17, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 6 855.889267 855.889267 12: seq: 18, trsync_set_timeout oid=13 clock=420904370
Sent 7 855.896670 855.896670 12: seq: 19, trsync_set_timeout oid=13 clock=422159710
Sent 8 855.904466 855.904466 12: seq: 1a, trsync_set_timeout oid=13 clock=423424374
Sent 9 855.911977 855.911977 12: seq: 1b, trsync_set_timeout oid=13 clock=424679693
Sent 10 855.919461 855.919461 12: seq: 1c, trsync_set_timeout oid=13 clock=425944362
Sent 11 855.926962 855.926962 12: seq: 1d, trsync_set_timeout oid=13 clock=427199676
Sent 12 855.933882 855.933882 33: seq: 1e, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 13 855.934270 855.934270 12: seq: 1f, trsync_set_timeout oid=13 clock=428464319
Sent 14 855.941964 855.941964 12: seq: 10, trsync_set_timeout oid=13 clock=429719659
Sent 15 855.949466 855.949466 12: seq: 11, trsync_set_timeout oid=13 clock=430984312
Sent 16 855.956967 855.956967 12: seq: 12, trsync_set_timeout oid=13 clock=432239642
Sent 17 855.964458 855.964458 12: seq: 13, trsync_set_timeout oid=13 clock=433504295
Sent 18 855.971967 855.971967 12: seq: 14, trsync_set_timeout oid=13 clock=434759625
Sent 19 855.979470 855.979470 12: seq: 15, trsync_set_timeout oid=13 clock=436024263
Sent 20 855.983975 855.983975 33: seq: 16, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 21 855.986957 855.986957 12: seq: 17, trsync_set_timeout oid=13 clock=437279608
Sent 22 855.994461 855.994461 12: seq: 18, trsync_set_timeout oid=13 clock=438544277
Sent 23 856.001990 856.001990 12: seq: 19, trsync_set_timeout oid=13 clock=439799591
Sent 24 856.009457 856.009457 12: seq: 1a, trsync_set_timeout oid=13 clock=441064244
Sent 25 856.016964 856.016964 12: seq: 1b, trsync_set_timeout oid=13 clock=442319574
Sent 26 856.024427 856.024427 12: seq: 1c, trsync_set_timeout oid=13 clock=443582358
Sent 27 856.031796 856.031796 12: seq: 1d, trsync_set_timeout oid=13 clock=444839557
Sent 28 856.033242 856.033242 33: seq: 1e, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 29 856.039460 856.039460 12: seq: 1f, trsync_set_timeout oid=13 clock=446102388
Sent 30 856.046965 856.046965 12: seq: 10, trsync_set_timeout oid=13 clock=447359540
Sent 31 856.054468 856.054468 12: seq: 11, trsync_set_timeout oid=13 clock=448622545
Sent 32 856.061960 856.061960 12: seq: 12, trsync_set_timeout oid=13 clock=449879523
Sent 33 856.069453 856.069453 12: seq: 13, trsync_set_timeout oid=13 clock=451143089
Sent 34 856.076965 856.076965 12: seq: 14, trsync_set_timeout oid=13 clock=452399506
Sent 35 856.083092 856.083092 33: seq: 15, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 36 856.084455 856.084455 12: seq: 16, trsync_set_timeout oid=13 clock=453663151
Sent 37 856.091940 856.091940 12: seq: 17, trsync_set_timeout oid=13 clock=454919489
Sent 38 856.097396 856.097396 21: seq: 18, spi_send oid=2 data=b'o\x00\x00\x00\x00', spi_transfer oid=2 data=b'o\x00\x00\x00\x00'
Sent 39 856.097620 856.097620 21: seq: 19, spi_send oid=3 data=b'o\x00\x00\x00\x00', spi_transfer oid=3 data=b'o\x00\x00\x00\x00'
Sent 40 856.097737 856.097737 13: seq: 1a, spi_send oid=4 data=b'o\x00\x00\x00\x00'
Sent 41 856.097949 856.097949 13: seq: 1b, spi_transfer oid=4 data=b'o\x00\x00\x00\x00'
Sent 42 856.098208 856.098208 21: seq: 1c, spi_send oid=5 data=b'o\x00\x00\x00\x00', spi_transfer oid=5 data=b'o\x00\x00\x00\x00'
Sent 43 856.098448 856.098448 21: seq: 1d, spi_send oid=2 data=b'\x01\x00\x00\x00\x00', spi_transfer oid=2 data=b'\x01\x00\x00\x00\x00'
Sent 44 856.098963 856.098963 13: seq: 1e, spi_send oid=3 data=b'\x01\x00\x00\x00\x00'
Sent 45 856.099133 856.099133 20: seq: 1f, spi_transfer oid=3 data=b'\x01\x00\x00\x00\x00', trsync_set_timeout oid=13 clock=456183134
Sent 46 856.099429 856.099429 13: seq: 10, spi_send oid=4 data=b'\x01\x00\x00\x00\x00'
Sent 47 856.099469 856.099469 13: seq: 11, spi_transfer oid=4 data=b'\x01\x00\x00\x00\x00'
Sent 48 856.099639 856.099639 21: seq: 12, spi_send oid=5 data=b'\x01\x00\x00\x00\x00', spi_transfer oid=5 data=b'\x01\x00\x00\x00\x00'
Sent 49 856.106916 856.106916 12: seq: 13, trsync_set_timeout oid=13 clock=457439472
Sent 50 856.114460 856.114460 12: seq: 14, trsync_set_timeout oid=13 clock=458703133
Sent 51 856.121957 856.121957 12: seq: 15, trsync_set_timeout oid=13 clock=459959455
Sent 52 856.129448 856.129448 12: seq: 16, trsync_set_timeout oid=13 clock=461223079
Sent 53 856.134094 856.134094 33: seq: 17, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 54 856.136915 856.136915 12: seq: 18, trsync_set_timeout oid=13 clock=462479437
Sent 55 856.144206 856.144206 12: seq: 19, trsync_set_timeout oid=13 clock=463743015
Sent 56 856.151711 856.151711 12: seq: 1a, trsync_set_timeout oid=13 clock=464999420
Sent 57 856.159455 856.159455 12: seq: 1b, trsync_set_timeout oid=13 clock=466263323
Sent 58 856.166943 856.166943 12: seq: 1c, trsync_set_timeout oid=13 clock=467519403
Sent 59 856.174460 856.174460 12: seq: 1d, trsync_set_timeout oid=13 clock=468783810
Sent 60 856.181942 856.181942 12: seq: 1e, trsync_set_timeout oid=13 clock=470039386
Sent 61 856.183754 856.183754 33: seq: 1f, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 62 856.184985 856.184985 6: seq: 10, get_clock
Sent 63 856.189476 856.189476 12: seq: 11, trsync_set_timeout oid=13 clock=471301950
Sent 64 856.196943 856.196943 12: seq: 12, trsync_set_timeout oid=13 clock=472562643
Sent 65 856.204467 856.204467 12: seq: 13, trsync_set_timeout oid=13 clock=473825186
Sent 66 856.211945 856.211945 12: seq: 14, trsync_set_timeout oid=13 clock=475082626
Sent 67 856.219447 856.219447 12: seq: 15, trsync_set_timeout oid=13 clock=476345174
Sent 68 856.226935 856.226935 12: seq: 16, trsync_set_timeout oid=13 clock=477602608
Sent 69 856.233101 856.233101 33: seq: 17, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 70 856.234464 856.234464 12: seq: 18, trsync_set_timeout oid=13 clock=478865146
Sent 71 856.241925 856.241925 12: seq: 19, trsync_set_timeout oid=13 clock=480122591
Sent 72 856.247408 856.247408 21: seq: 1a, spi_send oid=0 data=b'o\x00\x00\x00\x00', spi_transfer oid=0 data=b'o\x00\x00\x00\x00'
Sent 73 856.248177 856.248177 21: seq: 1b, spi_send oid=0 data=b'\x01\x00\x00\x00\x00', spi_transfer oid=0 data=b'\x01\x00\x00\x00\x00'
Sent 74 856.249310 856.249310 12: seq: 1c, trsync_set_timeout oid=13 clock=481385155
Sent 75 856.256944 856.256944 12: seq: 1d, trsync_set_timeout oid=13 clock=482642574
Sent 76 856.264459 856.264459 12: seq: 1e, trsync_set_timeout oid=13 clock=483905064
Sent 77 856.271948 856.271948 12: seq: 1f, trsync_set_timeout oid=13 clock=485162557
Sent 78 856.279247 856.279247 12: seq: 10, trsync_set_timeout oid=13 clock=486424092
Sent 79 856.283960 856.283960 33: seq: 11, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 80 856.286910 856.286910 12: seq: 12, trsync_set_timeout oid=13 clock=487682540
Sent 81 856.294467 856.294467 12: seq: 13, trsync_set_timeout oid=13 clock=488944678
Sent 82 856.301930 856.301930 12: seq: 14, trsync_set_timeout oid=13 clock=490202523
Sent 83 856.309460 856.309460 12: seq: 15, trsync_set_timeout oid=13 clock=491465254
Sent 84 856.316922 856.316922 12: seq: 16, trsync_set_timeout oid=13 clock=492722505
Sent 85 856.324452 856.324452 12: seq: 17, trsync_set_timeout oid=13 clock=493985337
Sent 86 856.331910 856.331910 12: seq: 18, trsync_set_timeout oid=13 clock=495242488
Sent 87 856.333250 856.333250 33: seq: 19, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 88 856.339468 856.339468 12: seq: 1a, trsync_set_timeout oid=13 clock=496505320
Sent 89 856.346910 856.346910 12: seq: 1b, trsync_set_timeout oid=13 clock=497762471
Sent 90 856.354458 856.354458 12: seq: 1c, trsync_set_timeout oid=13 clock=499025318
Sent 91 856.361755 856.361755 12: seq: 1d, trsync_set_timeout oid=13 clock=500282454
Sent 92 856.369459 856.369459 12: seq: 1e, trsync_set_timeout oid=13 clock=501545249
Sent 93 856.376747 856.376747 12: seq: 1f, trsync_set_timeout oid=13 clock=502802437
Sent 94 856.383505 856.383505 33: seq: 10, queue_step oid=12 interval=7000 count=1200 add=0, queue_step oid=14 interval=7000 count=1200 add=0, queue_step oid=15 interval=7000 count=1200 add=0, queue_step oid=16 interval=7000 count=1200 add=0
Sent 95 856.384461 856.384461 12: seq: 11, trsync_set_timeout oid=13 clock=504065190
Sent 96 856.391766 856.391766 12: seq: 12, trsync_set_timeout oid=13 clock=505322420
Sent 97 856.399264 856.399264 12: seq: 13, trsync_set_timeout oid=13 clock=506585477
Sent 98 856.406888 856.406888 12: seq: 14, trsync_set_timeout oid=13 clock=507842402
Sent 99 856.409494 856.409494 6: seq: 15, emergency_stop
Dumping receive queue 100 messages
Receive: 0 855.780438 855.776994 14: seq: 17, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=398440533
Receive: 1 855.787920 855.784324 14: seq: 19, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=399700534
Receive: 2 855.795436 855.791980 14: seq: 1a, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=400960534
Receive: 3 855.802921 855.799235 14: seq: 1b, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=402220538
Receive: 4 855.810416 855.806973 14: seq: 1c, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=403480534
Receive: 5 855.817924 855.814460 14: seq: 1d, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=404740534
Receive: 6 855.825418 855.821973 14: seq: 1e, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=406000534
Receive: 7 855.832905 855.829459 14: seq: 1f, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=407260534
Receive: 8 855.840416 855.836906 14: seq: 11, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=408520534
Receive: 9 855.847795 855.844477 14: seq: 12, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=409780550
Receive: 10 855.855410 855.851957 14: seq: 13, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=411040534
Receive: 11 855.862916 855.859466 14: seq: 14, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=412300534
Receive: 12 855.870256 855.866973 14: seq: 15, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=413560534
Receive: 13 855.877914 855.874462 14: seq: 16, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=414820538
Receive: 14 855.885355 855.883274 14: seq: 18, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=416080550
Receive: 15 855.892740 855.889267 14: seq: 19, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=417340534
Receive: 16 855.900205 855.896670 14: seq: 1a, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=418600538
Receive: 17 855.907917 855.904466 14: seq: 1b, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=419860534
Receive: 18 855.915207 855.911977 14: seq: 1c, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=421120534
Receive: 19 855.922886 855.919461 14: seq: 1d, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=422380550
Receive: 20 855.930408 855.926962 14: seq: 1e, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=423640534
Receive: 21 855.937909 855.934270 14: seq: 10, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=424900534
Receive: 22 855.945392 855.941964 14: seq: 11, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=426160534
Receive: 23 855.952903 855.949466 14: seq: 12, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=427420534
Receive: 24 855.960392 855.956967 14: seq: 13, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=428680534
Receive: 25 855.967919 855.964458 14: seq: 14, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=429940534
Receive: 26 855.975414 855.971967 14: seq: 15, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=431200538
Receive: 27 855.982907 855.979470 14: seq: 16, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=432460534
Receive: 28 855.990409 855.986957 14: seq: 18, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=433720534
Receive: 29 855.997913 855.994461 14: seq: 19, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=434980538
Receive: 30 856.005408 856.001990 14: seq: 1a, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=436240534
Receive: 31 856.012906 856.009457 14: seq: 1b, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=437500534
Receive: 32 856.020379 856.016964 14: seq: 1c, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=438760534
Receive: 33 856.027918 856.024427 14: seq: 1d, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=440020534
Receive: 34 856.035390 856.033242 14: seq: 1f, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=441280534
Receive: 35 856.042907 856.039460 14: seq: 10, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=442540538
Receive: 36 856.050413 856.046965 14: seq: 11, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=443800538
Receive: 37 856.055337 856.054468 14: seq: 12, analog_in_state oid=17 next_clock=493825408 value=7708
Receive: 38 856.057918 856.054468 14: seq: 12, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=445060537
Receive: 39 856.065389 856.061960 14: seq: 13, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=446320538
Receive: 40 856.072891 856.069453 14: seq: 14, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=447580535
Receive: 41 856.075358 856.069453 15: seq: 14, analog_in_state oid=19 next_clock=497185408 value=31535
Receive: 42 856.080406 856.076965 14: seq: 15, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=448840549
Receive: 43 856.087798 856.084455 14: seq: 17, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=450100534
Receive: 44 856.095276 856.091940 14: seq: 18, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=451360534
Receive: 45 856.097632 856.097396 13: seq: 19, spi_transfer_response oid=2 response=b'p\x00\x1f\x00\x17'
Receive: 46 856.097960 856.097620 13: seq: 1a, spi_transfer_response oid=3 response=b'\xf0\x00\x1f\x00\x15'
Receive: 47 856.098220 856.097949 13: seq: 1c, spi_transfer_response oid=4 response=b'p\x00\x1f\x00\x1e'
Receive: 48 856.098459 856.098208 13: seq: 1d, spi_transfer_response oid=5 response=b'\xf0\x00\x1f\x00\x11'
Receive: 49 856.098748 856.098448 13: seq: 1e, spi_transfer_response oid=2 response=b'0\x00\x00\x00\x00'
Receive: 50 856.099379 856.099133 13: seq: 10, spi_transfer_response oid=3 response=b'\xb0\x00\x00\x00\x00'
Receive: 51 856.099651 856.099469 13: seq: 12, spi_transfer_response oid=4 response=b'0\x00\x00\x00\x00'
Receive: 52 856.099853 856.099639 13: seq: 13, spi_transfer_response oid=5 response=b'\xf0\x00\x00\x00\x00'
Receive: 53 856.102759 856.099639 14: seq: 13, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=452620538
Receive: 54 856.110389 856.106916 14: seq: 14, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=453880534
Receive: 55 856.117873 856.114460 14: seq: 15, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=455140534
Receive: 56 856.125393 856.121957 14: seq: 16, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=456400534
Receive: 57 856.132899 856.129448 14: seq: 17, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=457660534
Receive: 58 856.140257 856.136915 14: seq: 19, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=458920534
Receive: 59 856.147670 856.144206 14: seq: 1a, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=460180534
Receive: 60 856.155406 856.151711 14: seq: 1b, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=461440534
Receive: 61 856.162915 856.159455 14: seq: 1c, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=462700534
Receive: 62 856.170405 856.166943 14: seq: 1d, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=463960534
Receive: 63 856.177891 856.174460 14: seq: 1e, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=465220538
Receive: 64 856.185248 856.183754 14: seq: 10, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=466480534
Receive: 65 856.185268 856.184985 11: seq: 11, clock clock=466486605
Receive: 66 856.192912 856.189476 14: seq: 12, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=467740534
Receive: 67 856.200406 856.196943 14: seq: 13, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=469000538
Receive: 68 856.207856 856.204467 14: seq: 14, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=470260534
Receive: 69 856.215394 856.211945 14: seq: 15, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=471520534
Receive: 70 856.222885 856.219447 14: seq: 16, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=472780534
Receive: 71 856.230404 856.226935 14: seq: 17, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=474040534
Receive: 72 856.237883 856.234464 14: seq: 19, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=475300534
Receive: 73 856.245405 856.241925 14: seq: 1a, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=476560534
Receive: 74 856.247645 856.247408 13: seq: 1b, spi_transfer_response oid=0 response=b'|\x81\x1f\x00<'
Receive: 75 856.248410 856.248177 13: seq: 1c, spi_transfer_response oid=0 response=b'|\x00\x00\x00\x00'
Receive: 76 856.252872 856.249310 14: seq: 1d, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=477820550
Receive: 77 856.260386 856.256944 14: seq: 1e, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=479080534
Receive: 78 856.267904 856.264459 14: seq: 1f, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=480340534
Receive: 79 856.275408 856.271948 14: seq: 10, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=481600538
Receive: 80 856.282906 856.279247 14: seq: 11, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=482860534
Receive: 81 856.290379 856.286910 14: seq: 13, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=484120550
Receive: 82 856.297867 856.294467 14: seq: 14, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=485380538
Receive: 83 856.305386 856.301930 14: seq: 15, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=486640534
Receive: 84 856.312883 856.309460 14: seq: 16, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=487900534
Receive: 85 856.320394 856.316922 14: seq: 17, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=489160534
Receive: 86 856.327895 856.324452 14: seq: 18, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=490420550
Receive: 87 856.335383 856.333250 14: seq: 1a, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=491680534
Receive: 88 856.342764 856.339468 14: seq: 1b, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=492940538
Receive: 89 856.350387 856.346910 14: seq: 1c, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=494200538
Receive: 90 856.355348 856.354458 14: seq: 1d, analog_in_state oid=17 next_clock=544225408 value=7707
Receive: 91 856.357823 856.354458 14: seq: 1d, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=495460537
Receive: 92 856.365375 856.361755 14: seq: 1e, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=496720554
Receive: 93 856.372765 856.369459 14: seq: 1f, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=497980535
Receive: 94 856.375222 856.369459 15: seq: 1f, analog_in_state oid=19 next_clock=547585408 value=31536
Receive: 95 856.380379 856.376747 14: seq: 10, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=499240533
Receive: 96 856.387897 856.384461 14: seq: 12, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=500500534
Receive: 97 856.395195 856.391766 14: seq: 13, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=501760534
Receive: 98 856.402737 856.399264 14: seq: 14, trsync_state oid=13 can_trigger=1 trigger_reason=0 clock=503020538
Receive: 99 856.410075 856.409494 12: seq: 16, shutdown clock=504208251 static_string_id=Command request
Homing failed due to printer shutdown