MCU 'mcu' shutdown: Timer too close still present

[RNGIllSkillz]

K-Shake&Tune module branch

• [X] I confirm using the main branch

Version

v4.0.1-0-g69ad2283

Describe the bug and expected behavior

i belive problem appeared after first axis check

`` BatchBulkHelper batch callback error Traceback (most recent call last): File "/home/klipper/klipper/klippy/mcu.py", line 71, in _do_send return xh.get_response(cmds, self._cmd_queue, minclock, reqclock) File "/home/klipper/klipper/klippy/serialhdl.py", line 327, in get_response raise error("Unable to obtain '%s' response" % (self.name,)) serialhdl.error: Unable to obtain 'sensor_bulk_status' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 72, in _proc_batch msg = self.batch_cb(eventtime) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 294, in _process_batch samples = self.ffreader.pull_samples() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 270, in pull_samples self._update_clock() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 242, in _update_clock params = self.query_status_cmd.send([self.oid]) File "/home/klipper/klipper/klippy/mcu.py", line 75, in send return self._do_send([self._cmd.encode(data)], minclock, reqclock) File "/home/klipper/klipper/klippy/mcu.py", line 73, in _do_send raise self._error(str(e)) gcode.CommandError: Unable to obtain 'sensor_bulk_status' response BatchBulkHelper stop callback error Traceback (most recent call last): File "/home/klipper/klipper/klippy/mcu.py", line 71, in _do_send return xh.get_response(cmds, self._cmd_queue, minclock, reqclock) File "/home/klipper/klipper/klippy/serialhdl.py", line 327, in get_response raise error("Unable to obtain '%s' response" % (self.name,)) serialhdl.error: Unable to obtain 'sensor_bulk_status' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 72, in _proc_batch msg = self.batch_cb(eventtime) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 294, in _process_batch samples = self.ffreader.pull_samples() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 270, in pull_samples self._update_clock() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 242, in _update_clock params = self.query_status_cmd.send([self.oid]) File "/home/klipper/klipper/klippy/mcu.py", line 75, in send return self._do_send([self._cmd.encode(data)], minclock, reqclock) File "/home/klipper/klipper/klippy/mcu.py", line 73, in _do_send raise self._error(str(e)) gcode.CommandError: Unable to obtain 'sensor_bulk_status' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/mcu.py", line 71, in _do_send return xh.get_response(cmds, self._cmd_queue, minclock, reqclock) File "/home/klipper/klipper/klippy/serialhdl.py", line 327, in get_response raise error("Unable to obtain '%s' response" % (self.name,)) serialhdl.error: Unable to obtain 'spi_transfer_response' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 62, in _stop self.stop_cb() File "/home/klipper/klipper/klippy/extras/adxl345.py", line 289, in _finish_measurements self.set_reg(REG_POWER_CTL, 0x00) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 232, in set_reg stored_val = self.read_reg(reg) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 227, in read_reg params = self.spi.spi_transfer([reg | REG_MOD_READ, 0x00]) File "/home/klipper/klipper/klippy/extras/bus.py", line 98, in spi_transfer return self.spi_transfer_cmd.send([self.oid, data], File "/home/klipper/klipper/klippy/mcu.py", line 75, in send return self._do_send([self._cmd.encode(data)], minclock, reqclock) File "/home/klipper/klipper/klippy/mcu.py", line 73, in _do_send raise self._error(str(e)) ``

after that i decided to run test again, but new error appeared. idk if thats related

`Internal error on command:"_COMPARE_BELTS_RESPONSES" Traceback (most recent call last): File "/home/klipper/klipper/klippy/gcode.py", line 211, in _process_commands handler(gcmd) File "/home/klipper/klipper/klippy/gcode.py", line 137, in func = lambda params: origfunc(self._get_extended_params(params)) File "/home/klipper/klipper/klippy/extras/shaketune/shaketune.py", line 148, in cmd_COMPARE_BELTS_RESPONSES compare_belts_responses(gcmd, self._pconfig, st_process) File "/home/klipper/klipper/klippy/extras/shaketune/commands/compare_belts_responses.py", line 105, in compare_belts_responses vibrate_axis(toolhead, gcode, config['direction'], min_freq, max_freq, hz_per_sec, accel_per_hz) File "/home/klipper/klipper/klippy/extras/shaketune/helpers/resonance_test.py", line 48, in vibrate_axis toolhead.move([X, Y, Z, E], max_v) File "/home/klipper/klipper/klippy/toolhead.py", line 473, in move self.lookahead.add_move(move) File "/home/klipper/klipper/klippy/toolhead.py", line 187, in add_move self.flush(lazy=True) File "/home/klipper/klipper/klippy/toolhead.py", line 176, in flush self.toolhead._process_moves(queue[:flush_count]) File "/home/klipper/klipper/klippy/toolhead.py", line 362, in _process_moves self._advance_move_time(next_move_time) File "/home/klipper/klipper/klippy/toolhead.py", line 320, in _advance_move_time self._advance_flush_time(flush_time) File "/home/klipper/klipper/klippy/toolhead.py", line 300, in _advance_flush_time sg(sg_flush_time) File "/home/klipper/klipper/klippy/stepper.py", line 228, in generate_steps raise error("Internal error in stepcompress") stepper.error: Internal error in stepcompress Internal error on command:"_COMPARE_BELTS_RESPONSES" Internal error on command:"COMPARE_BELTS_RESPONSES" Traceback (most recent call last): File "/home/klipper/klipper/klippy/gcode.py", line 211, in _process_commands handler(gcmd) File "/home/klipper/klipper/klippy/gcode.py", line 137, in func = lambda params: origfunc(self._get_extended_params(params)) File "/home/klipper/klipper/klippy/extras/gcode_macro.py", line 189, in cmd self.template.run_gcode_from_command(kwparams) File "/home/klipper/klipper/klippy/extras/gcode_macro.py", line 68, in run_gcode_from_command self.gcode.run_script_from_command(self.render(context)) File "/home/klipper/klipper/klippy/gcode.py", line 226, in run_script_from_command self._process_commands(script.split('\n'), need_ack=False) File "/home/klipper/klipper/klippy/gcode.py", line 211, in _process_commands handler(gcmd) File "/home/klipper/klipper/klippy/gcode.py", line 137, in func = lambda params: origfunc(self._get_extended_params(params)) File "/home/klipper/klipper/klippy/extras/shaketune/shaketune.py", line 148, in cmd_COMPARE_BELTS_RESPONSES compare_belts_responses(gcmd, self._pconfig, st_process) File "/home/klipper/klipper/klippy/extras/shaketune/commands/compare_belts_responses.py", line 105, in compare_belts_responses vibrate_axis(toolhead, gcode, config['direction'], min_freq, max_freq, hz_per_sec, accel_per_hz) File "/home/klipper/klipper/klippy/extras/shaketune/helpers/resonance_test.py", line 48, in vibrate_axis toolhead.move([X, Y, Z, E], max_v) File "/home/klipper/klipper/klippy/toolhead.py", line 473, in move self.lookahead.add_move(move) File "/home/klipper/klipper/klippy/toolhead.py", line 187, in add_move self.flush(lazy=True) File "/home/klipper/klipper/klippy/toolhead.py", line 176, in flush self.toolhead._process_moves(queue[:flush_count]) File "/home/klipper/klipper/klippy/toolhead.py", line 362, in _process_moves self._advance_move_time(next_move_time) File "/home/klipper/klipper/klippy/toolhead.py", line 320, in _advance_move_time self._advance_flush_time(flush_time) File "/home/klipper/klipper/klippy/toolhead.py", line 300, in _advance_flush_time sg(sg_flush_time) File "/home/klipper/klipper/klippy/stepper.py", line 228, in generate_steps raise error("Internal error in stepcompress") stepper.error: Internal error in stepcompress Internal error on command:"COMPARE_BELTS_RESPONSES" Internal Error on WebRequest: gcode/script Traceback (most recent call last): File "/home/klipper/klipper/klippy/webhooks.py", line 256, in _process_request func(web_request) File "/home/klipper/klipper/klippy/webhooks.py", line 436, in _handle_script self.gcode.run_script(web_request.get_str('script')) File "/home/klipper/klipper/klippy/gcode.py", line 229, in run_script self._process_commands(script.split('\n'), need_ack=False) File "/home/klipper/klipper/klippy/gcode.py", line 211, in _process_commands handler(gcmd) File "/home/klipper/klipper/klippy/gcode.py", line 137, in func = lambda params: origfunc(self._get_extended_params(params)) File "/home/klipper/klipper/klippy/extras/gcode_macro.py", line 189, in cmd self.template.run_gcode_from_command(kwparams) File "/home/klipper/klipper/klippy/extras/gcode_macro.py", line 68, in run_gcode_from_command self.gcode.run_script_from_command(self.render(context)) File "/home/klipper/klipper/klippy/gcode.py", line 226, in run_script_from_command self._process_commands(script.split('\n'), need_ack=False) File "/home/klipper/klipper/klippy/gcode.py", line 211, in _process_commands handler(gcmd) File "/home/klipper/klipper/klippy/gcode.py", line 137, in func = lambda params: origfunc(self._get_extended_params(params)) File "/home/klipper/klipper/klippy/extras/shaketune/shaketune.py", line 148, in cmd_COMPARE_BELTS_RESPONSES compare_belts_responses(gcmd, self._pconfig, st_process) File "/home/klipper/klipper/klippy/extras/shaketune/commands/compare_belts_responses.py", line 105, in compare_belts_responses vibrate_axis(toolhead, gcode, config['direction'], min_freq, max_freq, hz_per_sec, accel_per_hz) File "/home/klipper/klipper/klippy/extras/shaketune/helpers/resonance_test.py", line 48, in vibrate_axis toolhead.move([X, Y, Z, E], max_v) File "/home/klipper/klipper/klippy/toolhead.py", line 473, in move self.lookahead.add_move(move) File "/home/klipper/klipper/klippy/toolhead.py", line 187, in add_move self.flush(lazy=True) File "/home/klipper/klipper/klippy/toolhead.py", line 176, in flush self.toolhead._process_moves(queue[:flush_count]) File "/home/klipper/klipper/klippy/toolhead.py", line 362, in _process_moves self._advance_move_time(next_move_time) File "/home/klipper/klipper/klippy/toolhead.py", line 320, in _advance_move_time self._advance_flush_time(flush_time) File "/home/klipper/klipper/klippy/toolhead.py", line 300, in _advance_flush_time sg(sg_flush_time) File "/home/klipper/klipper/klippy/stepper.py", line 228, in generate_steps raise error("Internal error in stepcompress") stepper.error: Internal error in stepcompress Stats 96145.0: gcodein=0 mcu: mcu_awake=0.117 mcu_task_avg=0.000005 mcu_task_stddev=0.000003 bytes_write=155027 bytes_read=57210 bytes_retransmit=0 bytes_invalid=0 send_seq=4091 receive_seq=4091 retransmit_seq=0 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=17 upcoming_bytes=0 freq=168003344 TH0: mcu_awake=0.773 mcu_task_avg=0.000028 mcu_task_stddev=0.000030 bytes_write=10084 bytes_read=549769 bytes_retransmit=0 bytes_invalid=52 send_seq=984 receive_seq=984 retransmit_seq=0 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=12000117 adj=11999778 TH1: mcu_awake=0.002 mcu_task_avg=0.000012 mcu_task_stddev=0.000019 bytes_write=1863 bytes_read=12075 bytes_retransmit=0 bytes_invalid=0 send_seq=224 receive_seq=224 retransmit_seq=0 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=12000165 adj=12000114 TH2: mcu_awake=0.002 mcu_task_avg=0.000011 mcu_task_stddev=0.000010 bytes_write=1887 bytes_read=12059 bytes_retransmit=0 bytes_invalid=0 send_seq=225 receive_seq=225 retransmit_seq=0 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=64000435 adj=63998482 Arduino_MEGA: mcu_awake=0.068 mcu_task_avg=0.000216 mcu_task_stddev=0.000086 bytes_write=1815 bytes_read=50172 bytes_retransmit=9 bytes_invalid=6 send_seq=189 receive_seq=189 retransmit_seq=2 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=15882018 adj=15881628 TH0_temp: temp=36.3 TH1_temp: temp=37.1 TH2_temp: temp=37.8 OrangePi_temp: temp=53.5 Driver_Z0: temp=42.4 Driver_Z1: temp=57.8 Driver_Z2: temp=63.4 Driver_Z3: temp=57.4 Driver_A: temp=56.5 Driver_B: temp=54.4 Motor_Z0: temp=42.9 Motor_Z1: temp=49.6 Motor_Z2: temp=38.6 Motor_Z3: temp=46.4 chamber: temp=31.2 heater_bed: target=60 temp=60.1 pwm=0.206 sysload=0.86 cputime=249.610 memavail=382288 print_time=73.119 buffer_time=2.310 print_stall=0 extruder: target=150 temp=149.5 pwm=0.316 extruder1: target=0 temp=30.2 pwm=0.000 extruder2: target=0 temp=28.9 pwm=0.000 BatchBulkHelper batch callback error Traceback (most recent call last): File "/home/klipper/klipper/klippy/mcu.py", line 71, in _do_send return xh.get_response(cmds, self._cmd_queue, minclock, reqclock) File "/home/klipper/klipper/klippy/serialhdl.py", line 327, in get_response raise error("Unable to obtain '%s' response" % (self.name,)) serialhdl.error: Unable to obtain 'sensor_bulk_status' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 72, in _proc_batch msg = self.batch_cb(eventtime) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 294, in _process_batch samples = self.ffreader.pull_samples() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 270, in pull_samples self._update_clock() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 242, in _update_clock params = self.query_status_cmd.send([self.oid]) File "/home/klipper/klipper/klippy/mcu.py", line 75, in send return self._do_send([self._cmd.encode(data)], minclock, reqclock) File "/home/klipper/klipper/klippy/mcu.py", line 73, in _do_send raise self._error(str(e)) gcode.CommandError: Unable to obtain 'sensor_bulk_status' response BatchBulkHelper stop callback error Traceback (most recent call last): File "/home/klipper/klipper/klippy/mcu.py", line 71, in _do_send return xh.get_response(cmds, self._cmd_queue, minclock, reqclock) File "/home/klipper/klipper/klippy/serialhdl.py", line 327, in get_response raise error("Unable to obtain '%s' response" % (self.name,)) serialhdl.error: Unable to obtain 'sensor_bulk_status' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 72, in _proc_batch msg = self.batch_cb(eventtime) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 294, in _process_batch samples = self.ffreader.pull_samples() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 270, in pull_samples self._update_clock() File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 242, in _update_clock params = self.query_status_cmd.send([self.oid]) File "/home/klipper/klipper/klippy/mcu.py", line 75, in send return self._do_send([self._cmd.encode(data)], minclock, reqclock) File "/home/klipper/klipper/klippy/mcu.py", line 73, in _do_send raise self._error(str(e)) gcode.CommandError: Unable to obtain 'sensor_bulk_status' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/mcu.py", line 71, in _do_send return xh.get_response(cmds, self._cmd_queue, minclock, reqclock) File "/home/klipper/klipper/klippy/serialhdl.py", line 327, in get_response raise error("Unable to obtain '%s' response" % (self.name,)) serialhdl.error: Unable to obtain 'spi_transfer_response' response

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/home/klipper/klipper/klippy/extras/bulk_sensor.py", line 62, in _stop self.stop_cb() File "/home/klipper/klipper/klippy/extras/adxl345.py", line 289, in _finish_measurements self.set_reg(REG_POWER_CTL, 0x00) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 232, in set_reg stored_val = self.read_reg(reg) File "/home/klipper/klipper/klippy/extras/adxl345.py", line 227, in read_reg params = self.spi.spi_transfer([reg | REG_MOD_READ, 0x00]) File "/home/klipper/klipper/klippy/extras/bus.py", line 98, in spi_transfer return self.spi_transfer_cmd.send([self.oid, data], File "/home/klipper/klipper/klippy/mcu.py", line 75, in send return self._do_send([self._cmd.encode(data)], minclock, reqclock) File "/home/klipper/klipper/klippy/mcu.py", line 73, in _do_send raise self._error(str(e)) gcode.CommandError: Unable to obtain 'spi_transfer_response' response Stats 96146.0: gcodein=0 mcu: mcu_awake=0.117 mcu_task_avg=0.000005 mcu_task_stddev=0.000003 bytes_write=155192 bytes_read=57477 bytes_retransmit=0 bytes_invalid=0 send_seq=4098 receive_seq=4098 retransmit_seq=0 srtt=0.001 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=168003292 TH0: mcu_awake=0.773 mcu_task_avg=0.000028 mcu_task_stddev=0.000030 bytes_write=10180 bytes_read=549991 bytes_retransmit=0 bytes_invalid=52 send_seq=994 receive_seq=994 retransmit_seq=0 srtt=0.001 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=12000118 adj=11999845 TH1: mcu_awake=0.002 mcu_task_avg=0.000012 mcu_task_stddev=0.000019 bytes_write=1869 bytes_read=12207 bytes_retransmit=0 bytes_invalid=0 send_seq=225 receive_seq=225 retransmit_seq=0 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=12000159 adj=12000072 TH2: mcu_awake=0.002 mcu_task_avg=0.000011 mcu_task_stddev=0.000010 bytes_write=1893 bytes_read=12191 bytes_retransmit=0 bytes_invalid=0 send_seq=226 receive_seq=226 retransmit_seq=0 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=64000440 adj=63998868 Arduino_MEGA: mcu_awake=0.068 mcu_task_avg=0.000216 mcu_task_stddev=0.000086 bytes_write=1821 bytes_read=50860 bytes_retransmit=9 bytes_invalid=6 send_seq=190 receive_seq=190 retransmit_seq=2 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=15882018 adj=15881703 TH0_temp: temp=35.9 TH1_temp: temp=37.3 TH2_temp: temp=37.7 OrangePi_temp: temp=54.5 Driver_Z0: temp=42.1 Driver_Z1: temp=57.4 Driver_Z2: temp=61.4 Driver_Z3: temp=55.9 Driver_A: temp=54.6 Driver_B: temp=53.3 Motor_Z0: temp=43.2 Motor_Z1: temp=49.7 Motor_Z2: temp=38.2 Motor_Z3: temp=46.6 chamber: temp=31.2 heater_bed: target=60 temp=60.0 pwm=0.000 sysload=0.86 cputime=249.774 memavail=375532 print_time=73.119 buffer_time=1.310 print_stall=0 extruder: target=150 temp=148.5 pwm=0.000 extruder1: target=0 temp=30.5 pwm=0.000 extruder2: target=0 temp=28.9 pwm=0.000 b'stepcompress o=13 i=0 c=6 a=0: Invalid sequence' Exception in flush_handler Traceback (most recent call last): File "/home/klipper/klipper/klippy/toolhead.py", line 435, in _flush_handler self._flush_lookahead() File "/home/klipper/klipper/klippy/toolhead.py", line 365, in _flush_lookahead self.lookahead.flush() File "/home/klipper/klipper/klippy/toolhead.py", line 176, in flush self.toolhead._process_moves(queue[:flush_count]) File "/home/klipper/klipper/klippy/toolhead.py", line 362, in _process_moves self._advance_move_time(next_move_time) File "/home/klipper/klipper/klippy/toolhead.py", line 320, in _advance_move_time self._advance_flush_time(flush_time) File "/home/klipper/klipper/klippy/toolhead.py", line 300, in _advance_flush_time sg(sg_flush_time) File "/home/klipper/klipper/klippy/stepper.py", line 228, in generate_steps raise error("Internal error in stepcompress")`

Additional information and klippy.log

No response