Resonance test hangs Klipper

[pechex]

Hi all,

I recently installed Debian in my Sonic Pad. It went mostly OK but now I´m trying to do the resonance test following #4
I all worked fine, except when trying to do the actual test. I use the sensor provided by Creality, which worked fine on the stock firmware.

After running SHAPER_CALIBRATE it goes trough all the frecuencies and at the end it returns a message saying Wait for calculations.. At that point, Klipper is no longer responsive. No command works, restarting it makes ir responsive again, but I get no input shaping.

I also tried TEST_RESONANCES AXIS=X or TEST_RESONANCES AXIS=Y but I get exactly the same results

printer.cfg

[mcu rpi]
serial: /tmp/klipper_host_mcu

[adxl345]
cs_pin: rpi:None
spi_speed: 2000000
spi_bus: spidev2.0

[resonance_tester]
accel_chip: adxl345
accel_per_hz: 70
probe_points:
      117.5,117.5,10

Example commands

ACCELEROMETER_QUERY 
accelerometer values (x, y, z): -518.144159, 0.000000, 8197.221829

Wait for calculations..
Testing frequency 133 Hz
Testing frequency 132 Hz
Testing frequency 131 Hz
Testing frequency 130 Hz
Testing frequency 129 Hz
Testing frequency 128 Hz
Testing frequency 127 Hz
<<<< REMOVED>>>>
Testing frequency 17 Hz
Testing frequency 16 Hz
Testing frequency 15 Hz
Testing frequency 14 Hz
Testing frequency 13 Hz
Testing frequency 12 Hz
Testing frequency 11 Hz
Testing frequency 10 Hz
Testing frequency 9 Hz
Testing frequency 8 Hz
Testing frequency 7 Hz
Testing frequency 6 Hz
Testing frequency 5 Hz
Testing axis x
SHAPER_CALIBRATE

klippy.log

Testing axis x
ADXL345 starting 'adxl345' measurements
toolhead: max_velocity: 300.000000
max_accel: 9333.333000
minimum_cruise_ratio: 0.000000
square_corner_velocity: 5.000000
Testing frequency 5 Hz
Testing frequency 6 Hz
Testing frequency 7 Hz
Testing frequency 8 Hz
Testing frequency 9 Hz
mcu 'mcu': got {'waketime': 3643379947, '#name': 'wxlstepinsert', '#sent_time': 5270.865039512, '#receive_time': 5270.872558387}
Stats 5271.4: gcodein=0  mcu: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000009 bytes_write=15305 bytes_read=41369 bytes_retransmit=0 bytes_invalid=0 send_seq=881 receive_seq=881 retransmit_seq=0 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=33 upcoming_bytes=0 freq=84000684 rpi: mcu_awake=0.001 mcu_task_avg=0.000009 mcu_task_stddev=0.000009 bytes_write=2821 bytes_read=46461 bytes_retransmit=0 bytes_invalid=0 send_seq=408 receive_seq=408 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000619 adj=50000209  heater_bed: target=0 temp=19.9 pwm=0.000 sysload=0.26 cputime=10.326 memavail=1807464 print_time=2194.861 buffer_time=4.167 print_stall=0 extruder: target=0 temp=20.0 pwm=0.000
Stats 5272.4: gcodein=0  mcu: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000009 bytes_write=15344 bytes_read=41505 bytes_retransmit=0 bytes_invalid=0 send_seq=882 receive_seq=882 retransmit_seq=0 srtt=0.007 rttvar=0.002 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000685 rpi: mcu_awake=0.710 mcu_task_avg=0.000150 mcu_task_stddev=0.000175 bytes_write=2897 bytes_read=65145 bytes_retransmit=0 bytes_invalid=0 send_seq=419 receive_seq=419 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000619 adj=50000210  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=0.26 cputime=10.488 memavail=1807400 print_time=2194.861 buffer_time=3.167 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Stats 5273.4: gcodein=0  mcu: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000009 bytes_write=15350 bytes_read=41611 bytes_retransmit=0 bytes_invalid=0 send_seq=883 receive_seq=883 retransmit_seq=0 srtt=0.006 rttvar=0.002 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000683 rpi: mcu_awake=0.710 mcu_task_avg=0.000150 mcu_task_stddev=0.000175 bytes_write=2973 bytes_read=83873 bytes_retransmit=0 bytes_invalid=0 send_seq=430 receive_seq=430 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000617 adj=50000206  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=0.26 cputime=10.664 memavail=1807096 print_time=2194.861 buffer_time=2.166 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Testing frequency 10 Hz
Stats 5274.4: gcodein=0  mcu: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000009 bytes_write=17256 bytes_read=41872 bytes_retransmit=0 bytes_invalid=0 send_seq=915 receive_seq=915 retransmit_seq=0 srtt=0.007 rttvar=0.002 rto=0.025 ready_bytes=41 upcoming_bytes=0 freq=84000681 rpi: mcu_awake=0.710 mcu_task_avg=0.000150 mcu_task_stddev=0.000175 bytes_write=3049 bytes_read=102539 bytes_retransmit=0 bytes_invalid=0 send_seq=441 receive_seq=441 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000617 adj=50000205  heater_bed: target=0 temp=19.4 pwm=0.000 sysload=0.48 cputime=10.874 memavail=1806904 print_time=2195.864 buffer_time=2.169 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Testing frequency 11 Hz
Stats 5275.4: gcodein=0  mcu: mcu_awake=0.008 mcu_task_avg=0.000024 mcu_task_stddev=0.000020 bytes_write=19414 bytes_read=42193 bytes_retransmit=0 bytes_invalid=0 send_seq=950 receive_seq=950 retransmit_seq=0 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=41 upcoming_bytes=0 freq=84000682 rpi: mcu_awake=0.710 mcu_task_avg=0.000150 mcu_task_stddev=0.000175 bytes_write=3125 bytes_read=121267 bytes_retransmit=0 bytes_invalid=0 send_seq=452 receive_seq=452 retransmit_seq=0 srtt=0.001 rttvar=0.002 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000619 adj=50000213  heater_bed: target=0 temp=20.0 pwm=0.000 sysload=0.48 cputime=11.054 memavail=1806556 print_time=2196.913 buffer_time=2.217 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Testing frequency 12 Hz
Stats 5276.4: gcodein=0  mcu: mcu_awake=0.008 mcu_task_avg=0.000024 mcu_task_stddev=0.000020 bytes_write=21583 bytes_read=42469 bytes_retransmit=0 bytes_invalid=0 send_seq=985 receive_seq=985 retransmit_seq=0 srtt=0.008 rttvar=0.001 rto=0.025 ready_bytes=40 upcoming_bytes=0 freq=84000683 rpi: mcu_awake=0.710 mcu_task_avg=0.000150 mcu_task_stddev=0.000175 bytes_write=3201 bytes_read=139874 bytes_retransmit=0 bytes_invalid=0 send_seq=463 receive_seq=463 retransmit_seq=0 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000647 adj=50000223  heater_bed: target=0 temp=20.0 pwm=0.000 sysload=0.48 cputime=11.246 memavail=1806048 print_time=2197.932 buffer_time=2.235 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Testing frequency 13 Hz
Stats 5277.4: gcodein=0  mcu: mcu_awake=0.008 mcu_task_avg=0.000024 mcu_task_stddev=0.000020 bytes_write=23876 bytes_read=42755 bytes_retransmit=0 bytes_invalid=0 send_seq=1022 receive_seq=1022 retransmit_seq=0 srtt=0.008 rttvar=0.002 rto=0.025 ready_bytes=24 upcoming_bytes=0 freq=84000703 rpi: mcu_awake=2.245 mcu_task_avg=0.000145 mcu_task_stddev=0.000086 bytes_write=3277 bytes_read=158615 bytes_retransmit=0 bytes_invalid=0 send_seq=474 receive_seq=474 retransmit_seq=0 srtt=0.001 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000639 adj=50000477  heater_bed: target=0 temp=19.9 pwm=0.000 sysload=0.48 cputime=11.436 memavail=1805800 print_time=2198.967 buffer_time=2.270 print_stall=0 extruder: target=0 temp=19.9 pwm=0.000
Testing frequency 14 Hz
<<< REMOVED LINES >>>>
Testing frequency 127 Hz
Stats 5391.5: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000034 mcu_task_stddev=0.000022 bytes_write=539536 bytes_read=99519 bytes_retransmit=0 bytes_invalid=0 send_seq=9777 receive_seq=9777 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=51 upcoming_bytes=782 freq=84000709 rpi: mcu_awake=2.264 mcu_task_avg=0.000147 mcu_task_stddev=0.000084 bytes_write=11884 bytes_read=2316211 bytes_retransmit=8 bytes_invalid=0 send_seq=1720 receive_seq=1720 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000842 adj=50001175  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.32 cputime=37.445 memavail=1764040 print_time=2313.110 buffer_time=2.270 print_stall=0 extruder: target=0 temp=20.3 pwm=0.000
Testing frequency 128 Hz
Stats 5392.5: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000034 mcu_task_stddev=0.000022 bytes_write=544225 bytes_read=99995 bytes_retransmit=0 bytes_invalid=0 send_seq=9853 receive_seq=9852 retransmit_seq=0 srtt=0.003 rttvar=0.001 rto=0.025 ready_bytes=10 upcoming_bytes=843 freq=84000709 rpi: mcu_awake=2.317 mcu_task_avg=0.000150 mcu_task_stddev=0.000077 bytes_write=11960 bytes_read=2335209 bytes_retransmit=8 bytes_invalid=0 send_seq=1731 receive_seq=1731 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000828 adj=50001431  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.32 cputime=37.694 memavail=1763120 print_time=2314.109 buffer_time=2.267 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Testing frequency 129 Hz
Stats 5393.5: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000034 mcu_task_stddev=0.000022 bytes_write=548910 bytes_read=100491 bytes_retransmit=0 bytes_invalid=0 send_seq=9929 receive_seq=9928 retransmit_seq=0 srtt=0.003 rttvar=0.001 rto=0.025 ready_bytes=14 upcoming_bytes=921 freq=84000710 rpi: mcu_awake=2.317 mcu_task_avg=0.000150 mcu_task_stddev=0.000077 bytes_write=12036 bytes_read=2354192 bytes_retransmit=8 bytes_invalid=0 send_seq=1742 receive_seq=1742 retransmit_seq=918 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000797 adj=50001082  heater_bed: target=0 temp=20.0 pwm=0.000 sysload=1.32 cputime=37.930 memavail=1762888 print_time=2315.111 buffer_time=2.269 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Testing frequency 130 Hz
Stats 5394.6: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000034 mcu_task_stddev=0.000022 bytes_write=553599 bytes_read=100992 bytes_retransmit=0 bytes_invalid=0 send_seq=10005 receive_seq=10005 retransmit_seq=0 srtt=0.003 rttvar=0.001 rto=0.025 ready_bytes=34 upcoming_bytes=986 freq=84000710 rpi: mcu_awake=2.317 mcu_task_avg=0.000150 mcu_task_stddev=0.000077 bytes_write=12112 bytes_read=2373237 bytes_retransmit=8 bytes_invalid=0 send_seq=1753 receive_seq=1753 retransmit_seq=918 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000766 adj=50000575  heater_bed: target=0 temp=20.0 pwm=0.000 sysload=1.38 cputime=38.185 memavail=1762464 print_time=2316.107 buffer_time=2.264 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Testing frequency 131 Hz
Stats 5395.6: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=558347 bytes_read=101494 bytes_retransmit=0 bytes_invalid=0 send_seq=10082 receive_seq=10082 retransmit_seq=0 srtt=0.003 rttvar=0.000 rto=0.025 ready_bytes=41 upcoming_bytes=1071 freq=84000709 rpi: mcu_awake=2.317 mcu_task_avg=0.000150 mcu_task_stddev=0.000077 bytes_write=12188 bytes_read=2392222 bytes_retransmit=8 bytes_invalid=0 send_seq=1764 receive_seq=1764 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000787 adj=50000179  heater_bed: target=0 temp=20.0 pwm=0.000 sysload=1.38 cputime=38.451 memavail=1762284 print_time=2317.111 buffer_time=2.267 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Testing frequency 132 Hz
Stats 5396.6: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=563158 bytes_read=102000 bytes_retransmit=0 bytes_invalid=0 send_seq=10160 receive_seq=10160 retransmit_seq=0 srtt=0.003 rttvar=0.000 rto=0.025 ready_bytes=24 upcoming_bytes=1149 freq=84000708 rpi: mcu_awake=2.317 mcu_task_avg=0.000150 mcu_task_stddev=0.000077 bytes_write=12257 bytes_read=2411243 bytes_retransmit=8 bytes_invalid=0 send_seq=1774 receive_seq=1774 retransmit_seq=918 srtt=0.001 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000826 adj=50000468  heater_bed: target=0 temp=20.2 pwm=0.000 sysload=1.38 cputime=38.694 memavail=1761600 print_time=2318.111 buffer_time=2.267 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Testing frequency 133 Hz
Stats 5397.6: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=567959 bytes_read=102506 bytes_retransmit=0 bytes_invalid=0 send_seq=10238 receive_seq=10238 retransmit_seq=0 srtt=0.003 rttvar=0.000 rto=0.025 ready_bytes=58 upcoming_bytes=1200 freq=84000707 rpi: mcu_awake=2.294 mcu_task_avg=0.000149 mcu_task_stddev=0.000080 bytes_write=12333 bytes_read=2430302 bytes_retransmit=8 bytes_invalid=0 send_seq=1785 receive_seq=1785 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000793 adj=50000915  heater_bed: target=0 temp=20.0 pwm=0.000 sysload=1.38 cputime=38.955 memavail=1761428 print_time=2319.109 buffer_time=2.264 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
toolhead: max_velocity: 300.000000
max_accel: 4000.000000
minimum_cruise_ratio: 0.500000
square_corner_velocity: 5.000000
Stats 5398.6: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=569832 bytes_read=102762 bytes_retransmit=0 bytes_invalid=0 send_seq=10269 receive_seq=10269 retransmit_seq=0 srtt=0.003 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000705 rpi: mcu_awake=2.294 mcu_task_avg=0.000149 mcu_task_stddev=0.000080 bytes_write=12409 bytes_read=2449285 bytes_retransmit=8 bytes_invalid=0 send_seq=1796 receive_seq=1796 retransmit_seq=918 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000761 adj=50000443  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.38 cputime=39.168 memavail=1761268 print_time=2319.209 buffer_time=1.363 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Stats 5399.6: gcodein=0  mcu: mcu_awake=0.020 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=569838 bytes_read=102883 bytes_retransmit=0 bytes_invalid=0 send_seq=10270 receive_seq=10270 retransmit_seq=0 srtt=0.003 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000701 rpi: mcu_awake=2.294 mcu_task_avg=0.000149 mcu_task_stddev=0.000080 bytes_write=12485 bytes_read=2468812 bytes_retransmit=8 bytes_invalid=0 send_seq=1807 receive_seq=1807 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000776 adj=50000070  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.43 cputime=39.450 memavail=1760768 print_time=2319.209 buffer_time=0.335 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
ADXL345 finished 'adxl345' measurements
Stats 5400.6: gcodein=0  mcu: mcu_awake=0.013 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=570180 bytes_read=103049 bytes_retransmit=0 bytes_invalid=0 send_seq=10277 receive_seq=10277 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000712 rpi: mcu_awake=2.294 mcu_task_avg=0.000149 mcu_task_stddev=0.000080 bytes_write=12554 bytes_read=2477387 bytes_retransmit=8 bytes_invalid=0 send_seq=1816 receive_seq=1816 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000744 adj=50000271  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.43 cputime=39.593 memavail=1739188 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Stats 5401.6: gcodein=0  mcu: mcu_awake=0.013 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=570186 bytes_read=103155 bytes_retransmit=0 bytes_invalid=0 send_seq=10278 receive_seq=10278 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000709 rpi: mcu_awake=2.294 mcu_task_avg=0.000149 mcu_task_stddev=0.000080 bytes_write=12560 bytes_read=2477402 bytes_retransmit=8 bytes_invalid=0 send_seq=1817 receive_seq=1817 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000718 adj=49999771  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.43 cputime=39.613 memavail=1716432 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Stats 5402.6: gcodein=0  mcu: mcu_awake=0.013 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=570192 bytes_read=103276 bytes_retransmit=0 bytes_invalid=0 send_seq=10279 receive_seq=10279 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000706 rpi: mcu_awake=1.190 mcu_task_avg=0.000143 mcu_task_stddev=0.000091 bytes_write=12566 bytes_read=2477434 bytes_retransmit=8 bytes_invalid=0 send_seq=1818 receive_seq=1818 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000697 adj=49999395  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.43 cputime=39.634 memavail=1696500 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Stats 5403.6: gcodein=0  mcu: mcu_awake=0.013 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=570198 bytes_read=103397 bytes_retransmit=0 bytes_invalid=0 send_seq=10280 receive_seq=10280 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000702 rpi: mcu_awake=1.190 mcu_task_avg=0.000143 mcu_task_stddev=0.000091 bytes_write=12572 bytes_read=2477449 bytes_retransmit=8 bytes_invalid=0 send_seq=1819 receive_seq=1819 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000674 adj=49999317  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.43 cputime=39.654 memavail=1687808 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Stats 5404.6: gcodein=0  mcu: mcu_awake=0.013 mcu_task_avg=0.000037 mcu_task_stddev=0.000020 bytes_write=570204 bytes_read=103503 bytes_retransmit=0 bytes_invalid=0 send_seq=10281 receive_seq=10281 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000700 rpi: mcu_awake=1.190 mcu_task_avg=0.000143 mcu_task_stddev=0.000091 bytes_write=12578 bytes_read=2477464 bytes_retransmit=8 bytes_invalid=0 send_seq=1820 receive_seq=1820 retransmit_seq=918 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000656 adj=49999217  heater_bed: target=0 temp=20.0 pwm=0.000 sysload=1.39 cputime=39.674 memavail=1679764 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Stats 5405.6: gcodein=0  mcu: mcu_awake=0.001 mcu_task_avg=0.000009 mcu_task_stddev=0.000006 bytes_write=570210 bytes_read=103637 bytes_retransmit=0 bytes_invalid=0 send_seq=10282 receive_seq=10282 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000698 rpi: mcu_awake=1.190 mcu_task_avg=0.000143 mcu_task_stddev=0.000091 bytes_write=12584 bytes_read=2477480 bytes_retransmit=8 bytes_invalid=0 send_seq=1821 receive_seq=1821 retransmit_seq=918 srtt=0.001 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000638 adj=49999203  heater_bed: target=0 temp=20.2 pwm=0.000 sysload=1.39 cputime=39.694 memavail=1671564 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.1 pwm=0.000
Stats 5406.6: gcodein=0  mcu: mcu_awake=0.001 mcu_task_avg=0.000009 mcu_task_stddev=0.000006 bytes_write=570216 bytes_read=103758 bytes_retransmit=0 bytes_invalid=0 send_seq=10283 receive_seq=10283 retransmit_seq=0 srtt=0.004 rttvar=0.001 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000698 rpi: mcu_awake=1.190 mcu_task_avg=0.000143 mcu_task_stddev=0.000091 bytes_write=12590 bytes_read=2477496 bytes_retransmit=8 bytes_invalid=0 send_seq=1822 receive_seq=1822 retransmit_seq=918 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000622 adj=49999203  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.39 cputime=39.714 memavail=1663688 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000
Stats 5407.6: gcodein=0  mcu: mcu_awake=0.001 mcu_task_avg=0.000009 mcu_task_stddev=0.000006 bytes_write=570222 bytes_read=103864 bytes_retransmit=0 bytes_invalid=0 send_seq=10284 receive_seq=10284 retransmit_seq=0 srtt=0.004 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=84000696 rpi: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000011 bytes_write=12596 bytes_read=2477525 bytes_retransmit=8 bytes_invalid=0 send_seq=1823 receive_seq=1823 retransmit_seq=918 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=50000604 adj=49999200  heater_bed: target=0 temp=20.1 pwm=0.000 sysload=1.39 cputime=39.735 memavail=1678468 print_time=2319.209 buffer_time=0.000 print_stall=0 extruder: target=0 temp=20.2 pwm=0.000

In moonraker.log I get lots of these

2024-07-23 21:38:18,076 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 60.00 seconds
2024-07-23 21:39:18,078 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 120.00 seconds
2024-07-23 21:40:18,080 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 180.00 seconds
2024-07-23 21:41:18,082 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 240.01 seconds
2024-07-23 21:42:18,085 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 300.01 seconds
2024-07-23 21:43:18,088 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 360.01 seconds
2024-07-23 21:44:18,090 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 420.02 seconds
2024-07-23 21:45:18,092 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 480.02 seconds
2024-07-23 21:46:18,094 [klippy_connection.py:wait()] - Request 'gcode/script' pending: 540.02 seconds