MCU 'HermitCrab' shutdown: Timer too close

[lesaux]

I started seeing this on a large print. I'm using canbus. Not sure what it is about. I had 500000 bitrate and tried reducing to 250000 to no effect.

Stats 4939.2: gcodein=0  mcu: mcu_awake=0.160 mcu_task_avg=0.000020 mcu_task_stddev=0.000020 bytes_write=6994245 bytes_read=2035380 bytes_retransmit=0 bytes_invalid=0 send_seq=157570 receive_seq=157570 retransmit_seq=0 srtt=0.003 rttvar=0.003 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=64001023 mmu: mcu_awake=0.006 mcu_task_avg=0.000019 mcu_task_stddev=0.000014 bytes_write=306532 bytes_read=1369847 bytes_retransmit=9 bytes_invalid=0 send_seq=23300 receive_seq=23300 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=48014005 adj=48012969 HermitCrab: mcu_awake=0.024 mcu_task_avg=0.000022 mcu_task_stddev=0.000031 bytes_write=2250684 bytes_read=847309 bytes_retransmit=0 bytes_invalid=0 send_seq=57696 receive_seq=57696 retransmit_seq=0 srtt=0.003 rttvar=0.002 rto=0.025 ready_bytes=8 upcoming_bytes=0 freq=47999655 adj=47998706 sd_pos=1546525 heater_bed: target=80 temp=80.1 pwm=0.183 sysload=1.42 cputime=490.377 memavail=753956 print_time=4632.293 buffer_time=2.090 print_stall=0 extruder: target=215 temp=215.2 pwm=0.331
Stats 4940.2: gcodein=0  mcu: mcu_awake=0.169 mcu_task_avg=0.000020 mcu_task_stddev=0.000020 bytes_write=6998396 bytes_read=2036017 bytes_retransmit=0 bytes_invalid=0 send_seq=157645 receive_seq=157645 retransmit_seq=0 srtt=0.003 rttvar=0.003 rto=0.025 ready_bytes=44 upcoming_bytes=0 freq=64001019 mmu: mcu_awake=0.006 mcu_task_avg=0.000019 mcu_task_stddev=0.000014 bytes_write=306594 bytes_read=1370160 bytes_retransmit=9 bytes_invalid=0 send_seq=23305 receive_seq=23305 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=48014004 adj=48013016 HermitCrab: mcu_awake=0.024 mcu_task_avg=0.000022 mcu_task_stddev=0.000031 bytes_write=2252251 bytes_read=847538 bytes_retransmit=0 bytes_invalid=0 send_seq=57724 receive_seq=57724 retransmit_seq=0 srtt=0.004 rttvar=0.002 rto=0.025 ready_bytes=36 upcoming_bytes=0 freq=47999648 adj=47998791 sd_pos=1547618 heater_bed: target=80 temp=80.1 pwm=0.183 sysload=1.42 cputime=490.485 memavail=753960 print_time=4633.482 buffer_time=2.279 print_stall=0 extruder: target=215 temp=215.0 pwm=0.403
Stats 4944.7: gcodein=0  mcu: mcu_awake=0.169 mcu_task_avg=0.000020 mcu_task_stddev=0.000020 bytes_write=7000256 bytes_read=2036605 bytes_retransmit=0 bytes_invalid=0 send_seq=157689 receive_seq=157686 retransmit_seq=0 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=842 upcoming_bytes=151 freq=64001012 mmu: mcu_awake=0.005 mcu_task_avg=0.000018 mcu_task_stddev=0.000014 bytes_write=306662 bytes_read=1371153 bytes_retransmit=9 bytes_invalid=0 send_seq=23311 receive_seq=23311 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=48014003 adj=48013109 HermitCrab: mcu_awake=0.022 mcu_task_avg=0.000022 mcu_task_stddev=0.000031 bytes_write=2253165 bytes_read=847931 bytes_retransmit=0 bytes_invalid=0 send_seq=57749 receive_seq=57746 retransmit_seq=0 srtt=0.001 rttvar=0.001 rto=0.025 ready_bytes=203 upcoming_bytes=47 freq=47999636 adj=47998765 sd_pos=1548267 heater_bed: target=80 temp=80.2 pwm=0.120 sysload=1.71 cputime=490.598 memavail=754092 print_time=4634.179 buffer_time=0.000 print_stall=0 extruder: target=215 temp=214.9 pwm=0.433
- - TRACE: Processing idle_timeout 'ready' event
MCU 'HermitCrab' shutdown: Timer too close
clocksync state: mcu_freq=48000000 last_clock=222508611506 clock_est=(4912.129 220947035299 47999636.459) min_half_rtt=0.000225 min_rtt_time=3212.466 time_avg=4912.129(941.802) clock_avg=220947035299.966(45206152803.844) pred_variance=45354636.411 clock_adj=(-0.036 47998670.500)
Dumping serial stats: bytes_write=2253221 bytes_read=847955 bytes_retransmit=0 bytes_invalid=0 send_seq=57750 receive_seq=57748 retransmit_seq=0 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=199 upcoming_bytes=0
Dumping send queue 100 messages
Sent 0 4937.339547 4937.338739 14: seq: 10, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 1 4937.353960 4937.350616 58: seq: 11, queue_step oid=1 interval=153642 count=1 add=0, queue_step oid=1 interval=294463 count=1 add=0, queue_step oid=1 interval=211099 count=1 add=0, queue_step oid=1 interval=134761 count=2 add=-25446, queue_step oid=1 interval=102483 count=8 add=0, queue_step oid=1 interval=106181 count=2 add=26065, queue_step oid=1 interval=195664 count=1 add=0
Sent 2 4937.357164 4937.350616 59: seq: 12, queue_step oid=1 interval=305771 count=1 add=0, queue_step oid=1 interval=159059 count=2 add=-28186, queue_step oid=1 interval=129115 count=3 add=1235, queue_step oid=1 interval=171320 count=1 add=0, queue_step oid=1 interval=312880 count=1 add=0, queue_step oid=1 interval=178508 count=1 add=0, queue_step oid=1 interval=125417 count=2 add=-19673
Sent 3 4937.360588 4937.350616 60: seq: 13, queue_step oid=1 interval=102423 count=8 add=0, queue_step oid=1 interval=110836 count=2 add=31931, queue_step oid=1 interval=244009 count=2 add=20398, queue_step oid=1 interval=144713 count=2 add=-13938, queue_step oid=1 interval=130026 count=2 add=0, queue_step oid=1 interval=135672 count=1 add=0, queue_step oid=1 interval=199032 count=1 add=0
Sent 4 4937.364092 4937.356841 63: seq: 14, queue_step oid=1 interval=303738 count=1 add=0, queue_step oid=1 interval=158236 count=1 add=0, queue_step oid=1 interval=117870 count=2 add=-14035, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=118178 count=1 add=0, queue_step oid=1 interval=156078 count=1 add=0, queue_step oid=1 interval=300437 count=1 add=0, queue_step oid=1 interval=203897 count=1 add=0
Sent 5 4937.367436 4937.359960 58: seq: 15, queue_step oid=1 interval=135712 count=2 add=-4999, queue_step oid=1 interval=129962 count=2 add=0, queue_step oid=1 interval=143916 count=1 add=0, queue_step oid=1 interval=251897 count=2 add=4816, queue_step oid=1 interval=152623 count=3 add=-1634, queue_step oid=1 interval=185397 count=1 add=0, queue_step oid=1 interval=409774 count=1 add=0
Sent 6 4937.370812 4937.363107 59: seq: 16, queue_step oid=1 interval=185200 count=1 add=0, queue_step oid=1 interval=127609 count=2 add=-21107, queue_step oid=1 interval=102452 count=10 add=0, queue_step oid=1 interval=114537 count=1 add=0, queue_step oid=1 interval=148563 count=1 add=0, queue_step oid=1 interval=307273 count=2 add=11500, queue_step oid=1 interval=155417 count=2 add=-4297
Sent 7 4937.374252 4937.367594 61: seq: 17, queue_step oid=1 interval=149619 count=2 add=26494, queue_step oid=1 interval=313269 count=1 add=0, queue_step oid=1 interval=175193 count=1 add=0, queue_step oid=1 interval=124299 count=2 add=-18874, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=112009 count=2 add=32164, queue_step oid=1 interval=255662 count=2 add=-3033
Sent 8 4937.377596 4937.371223 58: seq: 18, queue_step oid=1 interval=143042 count=2 add=-12376, queue_step oid=1 interval=129916 count=2 add=0, queue_step oid=1 interval=136452 count=1 add=0, queue_step oid=1 interval=204010 count=1 add=0, queue_step oid=1 interval=299831 count=1 add=0, queue_step oid=1 interval=155888 count=1 add=0, queue_step oid=1 interval=116881 count=2 add=-13260
Sent 9 4937.553379 4937.552127 22: seq: 19, queue_digital_out oid=12 clock=3135895318 on_ticks=1532332, queue_step oid=1 interval=102423 count=8 add=0
Sent 10 4937.683490 4937.683126 6: seq: 1a, get_clock
Sent 11 4937.728010 4937.724918 56: seq: 1b, queue_step oid=1 interval=119258 count=1 add=0, queue_step oid=1 interval=158305 count=1 add=0, queue_step oid=1 interval=303897 count=1 add=0, queue_step oid=1 interval=198823 count=1 add=0, queue_step oid=1 interval=134887 count=2 add=-4112, queue_step oid=1 interval=130026 count=2 add=0, queue_step oid=1 interval=145530 count=1 add=0
Sent 12 4937.731434 4937.724918 60: seq: 1c, queue_step oid=1 interval=263319 count=2 add=-18436, queue_step oid=1 interval=141234 count=2 add=-29076, queue_step oid=1 interval=102365 count=8 add=0, queue_step oid=1 interval=104221 count=2 add=22713, queue_step oid=1 interval=178615 count=1 add=0, queue_step oid=1 interval=313002 count=1 add=0, queue_step oid=1 interval=171333 count=1 add=0
Sent 13 4937.734606 4937.724918 58: seq: 1d, queue_step oid=1 interval=130400 count=4 add=0, queue_step oid=1 interval=160065 count=1 add=0, queue_step oid=1 interval=306099 count=1 add=0, queue_step oid=1 interval=195387 count=1 add=0, queue_step oid=1 interval=130644 count=2 add=-23027, queue_step oid=1 interval=102423 count=8 add=0, queue_step oid=1 interval=107809 count=2 add=28471
Sent 14 4937.737918 4937.729727 57: seq: 1e, queue_step oid=1 interval=211282 count=1 add=0, queue_step oid=1 interval=294114 count=1 add=0, queue_step oid=1 interval=152366 count=2 add=-21700, queue_step oid=1 interval=129069 count=3 add=1694, queue_step oid=1 interval=181226 count=1 add=0, queue_step oid=1 interval=312492 count=1 add=0, queue_step oid=1 interval=169240 count=1 add=0
Sent 15 4937.741326 4937.734745 60: seq: 1f, queue_step oid=1 interval=122161 count=2 add=-17353, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=113984 count=2 add=32629, queue_step oid=1 interval=276366 count=1 add=0, queue_step oid=1 interval=232225 count=1 add=0, queue_step oid=1 interval=140330 count=2 add=-9661, queue_step oid=1 interval=129918 count=2 add=0
Sent 16 4937.744830 4937.737115 63: seq: 10, queue_step oid=1 interval=138433 count=1 add=0, queue_step oid=1 interval=215608 count=1 add=0, queue_step oid=1 interval=290663 count=1 add=0, queue_step oid=1 interval=151760 count=1 add=0, queue_step oid=1 interval=115129 count=2 add=-11695, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=121602 count=1 add=0, queue_step oid=1 interval=163159 count=1 add=0
Sent 17 4937.748206 4937.742341 59: seq: 11, queue_step oid=1 interval=309109 count=1 add=0, queue_step oid=1 interval=189755 count=1 add=0, queue_step oid=1 interval=132889 count=3 add=-1857, queue_step oid=1 interval=129912 count=2 add=19592, queue_step oid=1 interval=280757 count=1 add=0, queue_step oid=1 interval=226705 count=1 add=0, queue_step oid=1 interval=138171 count=2 add=-27374
Sent 18 4938.092479 4938.089135 58: seq: 12, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=105107 count=2 add=24246, queue_step oid=1 interval=185945 count=1 add=0, queue_step oid=1 interval=310864 count=1 add=0, queue_step oid=1 interval=164433 count=2 add=-32668, queue_step oid=1 interval=129916 count=3 add=0, queue_step oid=1 interval=165475 count=1 add=0
Sent 19 4938.095651 4938.089135 58: seq: 13, queue_step oid=1 interval=310269 count=1 add=0, queue_step oid=1 interval=186940 count=1 add=0, queue_step oid=1 interval=128142 count=2 add=-21464, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=109083 count=2 add=30145, queue_step oid=1 interval=224640 count=1 add=0, queue_step oid=1 interval=283184 count=1 add=0
Sent 20 4938.098855 4938.089135 59: seq: 14, queue_step oid=1 interval=148687 count=2 add=-17865, queue_step oid=1 interval=129021 count=3 add=2101, queue_step oid=1 interval=189649 count=1 add=0, queue_step oid=1 interval=310029 count=1 add=0, queue_step oid=1 interval=163843 count=1 add=0, queue_step oid=1 interval=120097 count=2 add=-15846, queue_step oid=1 interval=102424 count=8 add=0
Sent 21 4938.102359 4938.094486 63: seq: 15, queue_step oid=1 interval=115775 count=1 add=0, queue_step oid=1 interval=151145 count=1 add=0, queue_step oid=1 interval=288974 count=1 add=0, queue_step oid=1 interval=217557 count=1 add=0, queue_step oid=1 interval=138087 count=2 add=-7314, queue_step oid=1 interval=130023 count=2 add=0, queue_step oid=1 interval=140854 count=1 add=0, queue_step oid=1 interval=230180 count=1 add=0
Sent 22 4938.105671 4938.098972 57: seq: 16, queue_step oid=1 interval=277712 count=1 add=0, queue_step oid=1 interval=146842 count=2 add=-31687, queue_step oid=1 interval=102028 count=9 add=115, queue_step oid=1 interval=123571 count=1 add=0, queue_step oid=1 interval=168395 count=1 add=0, queue_step oid=1 interval=312062 count=1 add=0, queue_step oid=1 interval=182056 count=1 add=0
Sent 23 4938.109047 4938.102111 59: seq: 17, queue_step oid=1 interval=131599 count=4 add=-740, queue_step oid=1 interval=153098 count=1 add=0, queue_step oid=1 interval=292844 count=1 add=0, queue_step oid=1 interval=213048 count=1 add=0, queue_step oid=1 interval=135221 count=2 add=-25712, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=106024 count=2 add=25805
Sent 24 4938.112455 4938.106604 60: seq: 18, queue_digital_out oid=12 clock=3164695318 on_ticks=2079989, queue_step oid=1 interval=194204 count=1 add=0, queue_step oid=1 interval=306662 count=1 add=0, queue_step oid=1 interval=159830 count=2 add=-28909, queue_step oid=1 interval=129916 count=3 add=0, queue_step oid=1 interval=171559 count=1 add=0, queue_step oid=1 interval=312676 count=1 add=0
Sent 25 4938.115927 4938.110125 62: seq: 19, queue_step oid=1 interval=179610 count=1 add=0, queue_step oid=1 interval=125787 count=2 add=-19922, queue_step oid=1 interval=102423 count=8 add=0, queue_step oid=1 interval=110496 count=2 add=31840, queue_step oid=1 interval=240752 count=2 add=26845, queue_step oid=1 interval=145224 count=2 add=-14452, queue_step oid=1 interval=130023 count=2 add=0
Sent 26 4938.119431 4938.115216 63: seq: 1a, queue_step oid=1 interval=135401 count=1 add=0, queue_step oid=1 interval=197468 count=1 add=0, queue_step oid=1 interval=304600 count=1 add=0, queue_step oid=1 interval=158923 count=1 add=0, queue_step oid=1 interval=118136 count=2 add=-14298, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=117804 count=1 add=0, queue_step oid=1 interval=155326 count=1 add=0
Sent 27 4938.334891 4938.332371 44: seq: 1b, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10, queue_step oid=1 interval=299180 count=1 add=0, queue_step oid=1 interval=205820 count=1 add=0, queue_step oid=1 interval=136126 count=2 add=-5307, queue_step oid=1 interval=130069 count=2 add=0
Sent 28 4938.342682 4938.341874 14: seq: 1c, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 29 4938.452938 4938.452098 15: seq: 1d, queue_digital_out oid=12 clock=3179095319 on_ticks=1252805
Sent 30 4938.485033 4938.481657 59: seq: 1e, queue_step oid=1 interval=143565 count=1 add=0, queue_step oid=1 interval=248420 count=2 add=11983, queue_step oid=1 interval=143575 count=2 add=-30254, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=103843 count=2 add=21673, queue_step oid=1 interval=174385 count=1 add=0, queue_step oid=1 interval=313740 count=1 add=0
Sent 31 4938.488173 4938.481657 57: seq: 1f, queue_step oid=1 interval=175545 count=1 add=0, queue_step oid=1 interval=131101 count=4 add=-421, queue_step oid=1 interval=157318 count=1 add=0, queue_step oid=1 interval=302045 count=1 add=0, queue_step oid=1 interval=201926 count=1 add=0, queue_step oid=1 interval=132433 count=2 add=-24106, queue_step oid=1 interval=102423 count=8 add=0
Sent 32 4938.491377 4938.481657 59: seq: 10, queue_step oid=1 interval=107027 count=2 add=27379, queue_step oid=1 interval=203735 count=1 add=0, queue_step oid=1 interval=300194 count=1 add=0, queue_step oid=1 interval=155366 count=2 add=-24554, queue_step oid=1 interval=129222 count=3 add=1468, queue_step oid=1 interval=176677 count=1 add=0, queue_step oid=1 interval=313410 count=1 add=0
Sent 33 4938.494849 4938.487347 62: seq: 11, queue_step oid=1 interval=173159 count=1 add=0, queue_step oid=1 interval=123564 count=2 add=-18395, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=112710 count=2 add=32308, queue_step oid=1 interval=262536 count=2 add=-17052, queue_step oid=1 interval=142115 count=2 add=-11449, queue_step oid=1 interval=129916 count=2 add=0
Sent 34 4938.498353 4938.490468 63: seq: 12, queue_step oid=1 interval=137062 count=1 add=0, queue_step oid=1 interval=207548 count=1 add=0, queue_step oid=1 interval=297303 count=1 add=0, queue_step oid=1 interval=154549 count=1 add=0, queue_step oid=1 interval=116330 count=2 add=-12744, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=120013 count=1 add=0, queue_step oid=1 interval=159862 count=1 add=0
Sent 35 4938.501889 4938.494964 64: seq: 13, queue_step oid=1 interval=305797 count=1 add=0, queue_step oid=1 interval=195604 count=1 add=0, queue_step oid=1 interval=134249 count=2 add=-3581, queue_step oid=1 interval=129918 count=2 add=0, queue_step oid=1 interval=146449 count=1 add=0, queue_step oid=1 interval=269199 count=2 add=-30402, queue_step oid=1 interval=164470 count=2 add=0, queue_step oid=1 interval=191670 count=1 add=0
Sent 36 4938.505233 4938.498112 58: seq: 14, queue_step oid=1 interval=375816 count=1 add=0, queue_step oid=1 interval=184382 count=2 add=-10337, queue_step oid=1 interval=182591 count=1 add=0, queue_step oid=1 interval=373871 count=1 add=0, queue_step oid=1 interval=195757 count=2 add=-30479, queue_step oid=1 interval=164460 count=1 add=0, queue_step oid=1 interval=227035 count=1 add=0
Sent 37 4938.508609 4938.501875 59: seq: 15, queue_step oid=1 interval=280405 count=1 add=0, queue_step oid=1 interval=147919 count=2 add=-17251, queue_step oid=1 interval=128832 count=3 add=2173, queue_step oid=1 interval=190809 count=1 add=0, queue_step oid=1 interval=309009 count=1 add=0, queue_step oid=1 interval=163040 count=1 add=0, queue_step oid=1 interval=119798 count=2 add=-15573
Sent 38 4938.670970 4938.667658 57: seq: 16, get_clock, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=116130 count=1 add=0, queue_step oid=1 interval=151856 count=1 add=0, queue_step oid=1 interval=290923 count=1 add=0, queue_step oid=1 interval=215305 count=1 add=0, queue_step oid=1 interval=137630 count=2 add=-6964, queue_step oid=1 interval=129916 count=2 add=0
Sent 39 4938.752945 4938.752073 16: seq: 17, queue_digital_out oid=12 clock=3193495319 on_ticks=1588108
Sent 40 4938.889500 4938.885964 64: seq: 18, queue_step oid=1 interval=141134 count=1 add=0, queue_step oid=1 interval=232589 count=1 add=0, queue_step oid=1 interval=275040 count=1 add=0, queue_step oid=1 interval=146276 count=2 add=-31437, queue_step oid=1 interval=102019 count=9 add=117, queue_step oid=1 interval=124001 count=1 add=0, queue_step oid=1 interval=169324 count=1 add=0, queue_step oid=1 interval=312651 count=1 add=0
Sent 41 4938.892828 4938.885964 57: seq: 19, queue_step oid=1 interval=181085 count=1 add=0, queue_step oid=1 interval=131565 count=4 add=-652, queue_step oid=1 interval=153779 count=1 add=0, queue_step oid=1 interval=294683 count=1 add=0, queue_step oid=1 interval=211033 count=1 add=0, queue_step oid=1 interval=134720 count=2 add=-25443, queue_step oid=1 interval=102423 count=8 add=0
Sent 42 4938.896032 4938.885964 59: seq: 1a, queue_step oid=1 interval=106145 count=2 add=26054, queue_step oid=1 interval=195577 count=1 add=0, queue_step oid=1 interval=305640 count=1 add=0, queue_step oid=1 interval=159048 count=2 add=-28176, queue_step oid=1 interval=129115 count=3 add=1235, queue_step oid=1 interval=171335 count=1 add=0, queue_step oid=1 interval=313030 count=1 add=0
Sent 43 4938.899504 4938.891218 62: seq: 1b, queue_step oid=1 interval=178580 count=1 add=0, queue_step oid=1 interval=125461 count=2 add=-19671, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=110870 count=2 add=31934, queue_step oid=1 interval=244089 count=2 add=20048, queue_step oid=1 interval=144587 count=2 add=-13921, queue_step oid=1 interval=129916 count=2 add=0
Sent 44 4938.903008 4938.895838 63: seq: 1c, queue_step oid=1 interval=135557 count=1 add=0, queue_step oid=1 interval=198844 count=1 add=0, queue_step oid=1 interval=303513 count=1 add=0, queue_step oid=1 interval=158183 count=1 add=0, queue_step oid=1 interval=117833 count=2 add=-14050, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=118134 count=1 add=0, queue_step oid=1 interval=156004 count=1 add=0
Sent 45 4938.906384 4938.898603 59: seq: 1d, queue_step oid=1 interval=300233 count=1 add=0, queue_step oid=1 interval=203978 count=1 add=0, queue_step oid=1 interval=135780 count=2 add=-5002, queue_step oid=1 interval=130028 count=2 add=0, queue_step oid=1 interval=143987 count=1 add=0, queue_step oid=1 interval=251962 count=2 add=4566, queue_step oid=1 interval=142994 count=2 add=-30003
Sent 46 4938.909920 4938.903109 64: seq: 1e, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=103897 count=2 add=21924, queue_step oid=1 interval=175307 count=1 add=0, queue_step oid=1 interval=313187 count=1 add=0, queue_step oid=1 interval=174288 count=1 add=0, queue_step oid=1 interval=130758 count=4 add=-279, queue_step oid=1 interval=157634 count=1 add=0, queue_step oid=1 interval=302702 count=1 add=0
Sent 47 4938.913328 4938.906880 60: seq: 1f, queue_step oid=1 interval=200305 count=1 add=0, queue_step oid=1 interval=132023 count=2 add=-23837, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=107248 count=2 add=27651, queue_step oid=1 interval=205568 count=1 add=0, queue_step oid=1 interval=298849 count=1 add=0, queue_step oid=1 interval=154543 count=2 add=-23854
Sent 48 4939.278210 4939.274834 59: seq: 10, queue_step oid=1 interval=129116 count=3 add=1517, queue_step oid=1 interval=177564 count=1 add=0, queue_step oid=1 interval=313015 count=1 add=0, queue_step oid=1 interval=172162 count=1 add=0, queue_step oid=1 interval=123208 count=2 add=-18143, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=113071 count=2 add=32385
Sent 49 4939.281382 4939.274834 58: seq: 11, queue_step oid=1 interval=265887 count=2 add=-23360, queue_step oid=1 interval=141798 count=2 add=-11019, queue_step oid=1 interval=130028 count=2 add=0, queue_step oid=1 interval=137497 count=1 add=0, queue_step oid=1 interval=209588 count=1 add=0, queue_step oid=1 interval=295877 count=1 add=0, queue_step oid=1 interval=153820 count=1 add=0
Sent 50 4939.284934 4939.274834 64: seq: 12, queue_step oid=1 interval=116004 count=2 add=-12512, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=120351 count=1 add=0, queue_step oid=1 interval=160573 count=1 add=0, queue_step oid=1 interval=306522 count=1 add=0, queue_step oid=1 interval=194126 count=1 add=0, queue_step oid=1 interval=133994 count=2 add=-3328, queue_step oid=1 interval=129916 count=2 add=0
Sent 51 4939.288278 4939.280495 58: seq: 13, queue_step oid=1 interval=146983 count=1 add=0, queue_step oid=1 interval=272825 count=1 add=0, queue_step oid=1 interval=235126 count=1 add=0, queue_step oid=1 interval=139765 count=2 add=-28250, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=104685 count=2 add=23451, queue_step oid=1 interval=182129 count=1 add=0
Sent 52 4939.291782 4939.283628 63: seq: 14, queue_step oid=1 interval=312211 count=1 add=0, queue_step oid=1 interval=168407 count=1 add=0, queue_step oid=1 interval=130235 count=4 add=0, queue_step oid=1 interval=162325 count=1 add=0, queue_step oid=1 interval=308235 count=1 add=0, queue_step oid=1 interval=191109 count=1 add=0, queue_step oid=1 interval=129406 count=2 add=-22263, queue_step oid=1 interval=102424 count=8 add=0
Sent 53 4939.295158 4939.287346 59: seq: 15, queue_step oid=1 interval=108407 count=2 add=29279, queue_step oid=1 interval=217394 count=1 add=0, queue_step oid=1 interval=289305 count=1 add=0, queue_step oid=1 interval=150560 count=2 add=-19782, queue_step oid=1 interval=129088 count=3 add=1881, queue_step oid=1 interval=185220 count=1 add=0, queue_step oid=1 interval=311475 count=1 add=0
Sent 54 4939.298250 4939.292600 56: seq: 16, queue_step oid=1 interval=166504 count=1 add=0, queue_step oid=1 interval=121120 count=2 add=-16621, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=114816 count=1 add=0, queue_step oid=1 interval=149158 count=1 add=0, queue_step oid=1 interval=282556 count=1 add=0, queue_step oid=1 interval=224529 count=1 add=0
Sent 55 4939.301626 4939.296759 59: seq: 17, queue_step oid=1 interval=139138 count=2 add=-8472, queue_step oid=1 interval=129916 count=2 add=0, queue_step oid=1 interval=139509 count=1 add=0, queue_step oid=1 interval=222162 count=1 add=0, queue_step oid=1 interval=284897 count=1 add=0, queue_step oid=1 interval=148691 count=2 add=-32499, queue_step oid=1 interval=102109 count=9 add=111
Sent 56 4939.335863 4939.332963 50: seq: 18, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10, queue_step oid=1 interval=122286 count=1 add=0, queue_step oid=1 interval=165672 count=1 add=0, queue_step oid=1 interval=310880 count=1 add=0, queue_step oid=1 interval=185887 count=1 add=0, queue_step oid=1 interval=132202 count=4 add=-1138
Sent 57 4939.343447 4939.342639 14: seq: 19, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 58 4939.652899 4939.652535 6: seq: 1a, get_clock
Sent 59 4939.667876 4939.664532 58: seq: 1b, queue_step oid=1 interval=151664 count=1 add=0, queue_step oid=1 interval=287021 count=1 add=0, queue_step oid=1 interval=219570 count=1 add=0, queue_step oid=1 interval=136663 count=2 add=-26549, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=105493 count=2 add=25009, queue_step oid=1 interval=189756 count=1 add=0
Sent 60 4939.671048 4939.664532 58: seq: 1c, queue_step oid=1 interval=309269 count=1 add=0, queue_step oid=1 interval=162348 count=2 add=-30979, queue_step oid=1 interval=130026 count=3 add=0, queue_step oid=1 interval=168487 count=1 add=0, queue_step oid=1 interval=311853 count=1 add=0, queue_step oid=1 interval=183199 count=1 add=0, queue_step oid=1 interval=126942 count=2 add=-20717
Sent 61 4939.674252 4939.664532 59: seq: 1d, queue_step oid=1 interval=102365 count=8 add=0, queue_step oid=1 interval=109711 count=2 add=30943, queue_step oid=1 interval=232091 count=1 add=0, queue_step oid=1 interval=275699 count=1 add=0, queue_step oid=1 interval=146910 count=2 add=-16137, queue_step oid=1 interval=128858 count=3 add=2329, queue_step oid=1 interval=193949 count=1 add=0
Sent 62 4939.677756 4939.670917 63: seq: 1e, queue_step oid=1 interval=307604 count=1 add=0, queue_step oid=1 interval=161426 count=1 add=0, queue_step oid=1 interval=119161 count=2 add=-15074, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=116784 count=1 add=0, queue_step oid=1 interval=153203 count=1 add=0, queue_step oid=1 interval=294511 count=1 add=0, queue_step oid=1 interval=211445 count=1 add=0
Sent 63 4939.681164 4939.673974 60: seq: 1f, queue_step oid=1 interval=137008 count=2 add=-6296, queue_step oid=1 interval=129963 count=2 add=0, queue_step oid=1 interval=142034 count=1 add=0, queue_step oid=1 interval=238168 count=2 add=32116, queue_step oid=1 interval=146608 count=2 add=-11392, queue_step oid=1 interval=134465 count=2 add=0, queue_step oid=1 interval=148964 count=1 add=0
Sent 64 4939.684540 4939.676864 59: seq: 10, queue_step oid=1 interval=298947 count=1 add=0, queue_step oid=1 interval=348827 count=1 add=0, queue_step oid=1 interval=152806 count=2 add=-9215, queue_step oid=1 interval=142091 count=2 add=6127, queue_step oid=1 interval=235224 count=2 add=-13367, queue_step oid=1 interval=192507 count=1 add=0, queue_step oid=1 interval=303849 count=1 add=0
Sent 65 4939.687916 4939.681247 59: seq: 11, queue_step oid=1 interval=198377 count=1 add=0, queue_step oid=1 interval=134728 count=2 add=-4059, queue_step oid=1 interval=129919 count=2 add=0, queue_step oid=1 interval=145497 count=1 add=0, queue_step oid=1 interval=263660 count=2 add=-19112, queue_step oid=1 interval=141239 count=2 add=-29050, queue_step oid=1 interval=102482 count=8 add=0
Sent 66 4939.691420 4939.685005 63: seq: 12, queue_step oid=1 interval=104333 count=2 add=22744, queue_step oid=1 interval=178924 count=1 add=0, queue_step oid=1 interval=312971 count=1 add=0, queue_step oid=1 interval=171035 count=1 add=0, queue_step oid=1 interval=130290 count=4 add=0, queue_step oid=1 interval=160053 count=1 add=0, queue_step oid=1 interval=306057 count=1 add=0, queue_step oid=1 interval=195202 count=1 add=0
Sent 67 4939.904580 4939.901044 64: seq: 13, queue_digital_out oid=12 clock=3251095345 on_ticks=1934519, queue_step oid=1 interval=130614 count=2 add=-22984, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=107881 count=2 add=28523, queue_step oid=1 interval=211703 count=1 add=0, queue_step oid=1 interval=293964 count=1 add=0, queue_step oid=1 interval=152264 count=2 add=-21598
Sent 68 4940.078267 4940.074891 59: seq: 14, queue_step oid=1 interval=129064 count=3 add=1703, queue_step oid=1 interval=181418 count=1 add=0, queue_step oid=1 interval=312284 count=1 add=0, queue_step oid=1 interval=169020 count=1 add=0, queue_step oid=1 interval=122063 count=2 add=-17318, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=113998 count=2 add=32639
Sent 69 4940.081787 4940.074891 63: seq: 15, queue_step oid=1 interval=276801 count=1 add=0, queue_step oid=1 interval=232011 count=1 add=0, queue_step oid=1 interval=140383 count=2 add=-9607, queue_step oid=1 interval=130025 count=2 add=0, queue_step oid=1 interval=138604 count=1 add=0, queue_step oid=1 interval=216138 count=1 add=0, queue_step oid=1 interval=290218 count=1 add=0, queue_step oid=1 interval=151551 count=1 add=0
Sent 70 4940.084927 4940.074891 57: seq: 16, queue_step oid=1 interval=115009 count=2 add=-11689, queue_step oid=1 interval=102365 count=8 add=0, queue_step oid=1 interval=121610 count=1 add=0, queue_step oid=1 interval=163184 count=1 add=0, queue_step oid=1 interval=309148 count=1 add=0, queue_step oid=1 interval=189691 count=1 add=0, queue_step oid=1 interval=132962 count=3 add=-1824
Sent 71 4940.088335 4940.080599 60: seq: 17, queue_step oid=1 interval=129989 count=2 add=19740, queue_step oid=1 interval=281385 count=1 add=0, queue_step oid=1 interval=226222 count=1 add=0, queue_step oid=1 interval=138047 count=2 add=-27321, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=105083 count=2 add=24291, queue_step oid=1 interval=186074 count=1 add=0
Sent 72 4940.091679 4940.085126 58: seq: 18, queue_step oid=1 interval=310638 count=1 add=0, queue_step oid=1 interval=164296 count=2 add=-32562, queue_step oid=1 interval=129916 count=3 add=0, queue_step oid=1 interval=165642 count=1 add=0, queue_step oid=1 interval=310503 count=1 add=0, queue_step oid=1 interval=186807 count=1 add=0, queue_step oid=1 interval=128121 count=2 add=-21421
Sent 73 4940.095055 4940.087357 59: seq: 19, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=109152 count=2 add=30181, queue_step oid=1 interval=225024 count=1 add=0, queue_step oid=1 interval=282305 count=1 add=0, queue_step oid=1 interval=148404 count=2 add=-17736, queue_step oid=1 interval=128863 count=3 add=2111, queue_step oid=1 interval=189613 count=1 add=0
Sent 74 4940.098559 4940.092096 63: seq: 1a, queue_step oid=1 interval=309559 count=1 add=0, queue_step oid=1 interval=163723 count=1 add=0, queue_step oid=1 interval=120067 count=2 add=-15782, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=115871 count=1 add=0, queue_step oid=1 interval=151315 count=1 add=0, queue_step oid=1 interval=289424 count=1 add=0, queue_step oid=1 interval=217046 count=1 add=0
Sent 75 4940.101935 4940.095871 59: seq: 1b, queue_step oid=1 interval=137922 count=2 add=-7253, queue_step oid=1 interval=129918 count=2 add=0, queue_step oid=1 interval=140803 count=1 add=0, queue_step oid=1 interval=230385 count=1 add=0, queue_step oid=1 interval=277320 count=1 add=0, queue_step oid=1 interval=146791 count=2 add=-31654, queue_step oid=1 interval=102086 count=9 add=115
Sent 76 4940.255114 4940.252150 52: seq: 1c, queue_digital_out oid=12 clock=3265495337 on_ticks=1676975, queue_step oid=1 interval=123676 count=1 add=0, queue_step oid=1 interval=168614 count=1 add=0, queue_step oid=1 interval=312277 count=1 add=0, queue_step oid=1 interval=181878 count=1 add=0, queue_step oid=1 interval=131576 count=4 add=-726
Sent 77 4940.334424 4940.333616 14: seq: 1d, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 78 4940.341978 4940.341170 14: seq: 1e, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 79 4940.466416 4940.463072 58: seq: 1f, queue_step oid=1 interval=153180 count=1 add=0, queue_step oid=1 interval=293007 count=1 add=0, queue_step oid=1 interval=212619 count=1 add=0, queue_step oid=1 interval=135096 count=2 add=-25659, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=106007 count=2 add=25853, queue_step oid=1 interval=194385 count=1 add=0
Sent 80 4940.469620 4940.463072 59: seq: 10, queue_step oid=1 interval=306708 count=1 add=0, queue_step oid=1 interval=159842 count=2 add=-28819, queue_step oid=1 interval=129226 count=3 add=1202, queue_step oid=1 interval=170683 count=1 add=0, queue_step oid=1 interval=312865 count=1 add=0, queue_step oid=1 interval=179371 count=1 add=0, queue_step oid=1 interval=125688 count=2 add=-19887
Sent 81 4940.473044 4940.463072 60: seq: 11, queue_step oid=1 interval=102365 count=8 add=0, queue_step oid=1 interval=110499 count=2 add=31870, queue_step oid=1 interval=241150 count=2 add=25856, queue_step oid=1 interval=145131 count=2 add=-14355, queue_step oid=1 interval=130025 count=2 add=0, queue_step oid=1 interval=135455 count=1 add=0, queue_step oid=1 interval=197764 count=1 add=0
Sent 82 4940.476548 4940.468342 63: seq: 12, queue_step oid=1 interval=304602 count=1 add=0, queue_step oid=1 interval=158818 count=1 add=0, queue_step oid=1 interval=118094 count=2 add=-14263, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=117842 count=1 add=0, queue_step oid=1 interval=155405 count=1 add=0, queue_step oid=1 interval=298940 count=1 add=0, queue_step oid=1 interval=205193 count=1 add=0
Sent 83 4940.479988 4940.472970 61: seq: 13, queue_step oid=1 interval=135907 count=2 add=-5241, queue_step oid=1 interval=129916 count=2 add=0, queue_step oid=1 interval=143472 count=1 add=0, queue_step oid=1 interval=248597 count=2 add=11082, queue_step oid=1 interval=143474 count=2 add=-30211, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=103859 count=2 add=21707
Sent 84 4940.483524 4940.476756 64: seq: 14, queue_step oid=1 interval=174527 count=1 add=0, queue_step oid=1 interval=313344 count=1 add=0, queue_step oid=1 interval=175150 count=1 add=0, queue_step oid=1 interval=130926 count=4 add=-405, queue_step oid=1 interval=157218 count=1 add=0, queue_step oid=1 interval=301749 count=1 add=0, queue_step oid=1 interval=201653 count=1 add=0, queue_step oid=1 interval=132384 count=2 add=-24054
Sent 85 4940.486900 4940.480338 59: seq: 15, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=107099 count=2 add=27431, queue_step oid=1 interval=204128 count=1 add=0, queue_step oid=1 interval=299939 count=1 add=0, queue_step oid=1 interval=155130 count=2 add=-24425, queue_step oid=1 interval=129118 count=3 add=1475, queue_step oid=1 interval=176693 count=1 add=0
Sent 86 4940.490372 4940.484839 62: seq: 16, queue_step oid=1 interval=313264 count=1 add=0, queue_step oid=1 interval=173047 count=1 add=0, queue_step oid=1 interval=123543 count=2 add=-18346, queue_step oid=1 interval=102482 count=8 add=0, queue_step oid=1 interval=112819 count=2 add=32324, queue_step oid=1 interval=263239 count=2 add=-18265, queue_step oid=1 interval=142046 count=2 add=-11380
Sent 87 4940.553445 4940.552161 23: seq: 17, queue_digital_out oid=12 clock=3279895337 on_ticks=2053639, queue_step oid=1 interval=129916 count=2 add=0
Sent 88 4940.637532 4940.637168 6: seq: 18, get_clock
Sent 89 4940.853027 4940.852155 16: seq: 19, queue_digital_out oid=12 clock=3294295337 on_ticks=1777559
Sent 90 4941.452963 4941.452091 16: seq: 1a, queue_digital_out oid=12 clock=3323095337 on_ticks=2231745
Sent 91 4941.753036 4941.752164 16: seq: 1b, queue_digital_out oid=12 clock=3337495337 on_ticks=1827119
Sent 92 4942.353048 4942.352176 16: seq: 1c, queue_digital_out oid=12 clock=3366295337 on_ticks=2297474
Sent 93 4942.953015 4942.952143 16: seq: 1d, queue_digital_out oid=12 clock=3395095337 on_ticks=1583385
Sent 94 4943.252712 4943.251840 16: seq: 1e, queue_digital_out oid=12 clock=3409495337 on_ticks=1916639
Sent 95 4943.852941 4943.852069 16: seq: 1f, queue_digital_out oid=12 clock=3438295337 on_ticks=1639584
Sent 96 4944.452944 4944.452072 16: seq: 10, queue_digital_out oid=12 clock=3467095337 on_ticks=2079326
Sent 97 4944.662222 4944.661858 6: seq: 11, get_clock
Sent 98 4944.668960 4944.665456 63: seq: 12, queue_step oid=1 interval=137111 count=1 add=0, queue_step oid=1 interval=207825 count=1 add=0, queue_step oid=1 interval=296899 count=1 add=0, queue_step oid=1 interval=154378 count=1 add=0, queue_step oid=1 interval=116242 count=2 add=-12717, queue_step oid=1 interval=102424 count=8 add=0, queue_step oid=1 interval=120045 count=1 add=0, queue_step oid=1 interval=159942 count=1 add=0
Sent 99 4944.672352 4944.665456 59: seq: 13, queue_step oid=1 interval=306023 count=1 add=0, queue_step oid=1 interval=195542 count=1 add=0, queue_step oid=1 interval=134320 count=2 add=-3544, queue_step oid=1 interval=130025 count=2 add=0, queue_step oid=1 interval=146657 count=1 add=0, queue_step oid=1 interval=269705 count=2 add=-31388, queue_step oid=1 interval=140101 count=2 add=-28463
Dumping receive queue 100 messages
Receive: 0 4927.899421 4927.871149 14: seq: 10, analog_in_state oid=11 next_clock=2674711718 value=2165
Receive: 1 4928.201211 4927.954519 14: seq: 11, analog_in_state oid=11 next_clock=2689111718 value=2163
Receive: 2 4928.332098 4928.327742 18: seq: 1a, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 3 4928.339473 4928.335250 18: seq: 1b, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 4 4928.499429 4928.335250 14: seq: 1b, analog_in_state oid=11 next_clock=2703511718 value=2160
Receive: 5 4928.799423 4928.613513 14: seq: 13, analog_in_state oid=11 next_clock=2717911718 value=2173
Receive: 6 4928.824396 4928.823707 11: seq: 14, clock clock=2705042270
Receive: 7 4929.099425 4929.020636 14: seq: 1c, analog_in_state oid=11 next_clock=2732311718 value=2155
Receive: 8 4929.330634 4929.326301 18: seq: 1e, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 9 4929.338344 4929.334078 18: seq: 1f, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 10 4929.400912 4929.393999 14: seq: 11, analog_in_state oid=11 next_clock=2746711718 value=2161
Receive: 11 4929.699417 4929.424995 14: seq: 1a, analog_in_state oid=11 next_clock=2761111718 value=2158
Receive: 12 4929.807218 4929.806451 11: seq: 1b, clock clock=2752211858
Receive: 13 4929.999424 4929.838496 14: seq: 12, analog_in_state oid=11 next_clock=2775511718 value=2154
Receive: 14 4930.299419 4930.004408 14: seq: 13, analog_in_state oid=11 next_clock=2789911718 value=2147
Receive: 15 4930.331971 4930.327630 18: seq: 14, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 16 4930.339347 4930.335127 18: seq: 15, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 17 4930.599421 4930.432468 14: seq: 1d, analog_in_state oid=11 next_clock=2804311718 value=2152
Receive: 18 4930.809891 4930.804719 11: seq: 12, clock clock=2800256926
Receive: 19 4930.899422 4930.818511 14: seq: 16, analog_in_state oid=11 next_clock=2818711718 value=2149
Receive: 20 4931.200459 4931.193836 14: seq: 1a, analog_in_state oid=11 next_clock=2833111718 value=2146
Receive: 21 4931.328262 4931.254973 15: seq: 1f, stats count=1076 sum=1138822 sumsq=14398410
Receive: 22 4931.332226 4931.327927 18: seq: 10, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 23 4931.339697 4931.335363 18: seq: 11, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 24 4931.499419 4931.335363 14: seq: 11, analog_in_state oid=11 next_clock=2847511718 value=2141
Receive: 25 4931.778394 4931.777864 11: seq: 1a, clock clock=2846835714
Receive: 26 4931.799417 4931.777864 14: seq: 1a, analog_in_state oid=11 next_clock=2861911718 value=2151
Receive: 27 4932.099421 4932.003770 14: seq: 13, analog_in_state oid=11 next_clock=2876311718 value=2156
Receive: 28 4932.334343 4932.329876 18: seq: 14, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 29 4932.341974 4932.337696 18: seq: 15, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 30 4932.399411 4932.388247 14: seq: 1c, analog_in_state oid=11 next_clock=2890711718 value=2160
Receive: 31 4932.699420 4932.455138 14: seq: 1d, analog_in_state oid=11 next_clock=2905111718 value=2168
Receive: 32 4932.765014 4932.764071 11: seq: 15, clock clock=2894190862
Receive: 33 4932.999478 4932.764071 14: seq: 15, analog_in_state oid=11 next_clock=2919511718 value=2147
Receive: 34 4933.299422 4933.118114 14: seq: 1e, analog_in_state oid=11 next_clock=2933911718 value=2169
Receive: 35 4933.334846 4933.330489 18: seq: 1f, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 36 4933.342846 4933.338538 18: seq: 10, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 37 4933.599417 4933.508211 14: seq: 18, analog_in_state oid=11 next_clock=2948311718 value=2163
Receive: 38 4933.746896 4933.746361 11: seq: 1a, clock clock=2941321022
Receive: 39 4933.901846 4933.896804 14: seq: 1b, analog_in_state oid=11 next_clock=2962711718 value=2148
Receive: 40 4934.199454 4933.920376 14: seq: 12, analog_in_state oid=11 next_clock=2977111718 value=2146
Receive: 41 4934.337495 4934.333017 18: seq: 1b, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 42 4934.346246 4934.341890 18: seq: 1c, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 43 4934.499454 4934.341890 14: seq: 1c, analog_in_state oid=11 next_clock=2991511718 value=2173
Receive: 44 4934.734302 4934.733143 11: seq: 15, clock clock=2988711578
Receive: 45 4934.799456 4934.733143 14: seq: 15, analog_in_state oid=11 next_clock=3005911718 value=2170
Receive: 46 4935.099438 4935.040019 14: seq: 1c, analog_in_state oid=11 next_clock=3020311718 value=2168
Receive: 47 4935.336872 4935.332478 18: seq: 1d, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 48 4935.345126 4935.340792 18: seq: 1e, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 49 4935.401752 4935.340792 14: seq: 1e, analog_in_state oid=11 next_clock=3034711718 value=2166
Receive: 50 4935.699586 4935.423878 14: seq: 16, analog_in_state oid=11 next_clock=3049111718 value=2177
Receive: 51 4935.717802 4935.716890 11: seq: 17, clock clock=3035915530
Receive: 52 4935.999593 4935.811575 14: seq: 1f, analog_in_state oid=11 next_clock=3063511718 value=2156
Receive: 53 4936.299579 4936.200645 14: seq: 18, analog_in_state oid=11 next_clock=3077911718 value=2172
Receive: 54 4936.332797 4936.304741 15: seq: 19, stats count=1104 sum=1165312 sumsq=14658029
Receive: 55 4936.336621 4936.332344 18: seq: 1a, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 56 4936.344872 4936.340553 18: seq: 1b, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 57 4936.599737 4936.592956 14: seq: 1f, analog_in_state oid=11 next_clock=3092311718 value=2161
Receive: 58 4936.699811 4936.699097 11: seq: 15, clock clock=3083056178
Receive: 59 4936.899634 4936.699097 14: seq: 15, analog_in_state oid=11 next_clock=3106711718 value=2195
Receive: 60 4937.199544 4936.998796 14: seq: 1e, analog_in_state oid=11 next_clock=3121111718 value=2159
Receive: 61 4937.336346 4937.332065 18: seq: 10, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 62 4937.343872 4937.339547 18: seq: 11, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 63 4937.499546 4937.377596 14: seq: 19, analog_in_state oid=11 next_clock=3135511718 value=2161
Receive: 64 4937.684083 4937.683490 11: seq: 1b, clock clock=3130301390
Receive: 65 4937.799546 4937.748206 14: seq: 12, analog_in_state oid=11 next_clock=3149911718 value=2158
Receive: 66 4938.101069 4938.095651 14: seq: 14, analog_in_state oid=11 next_clock=3164311718 value=2168
Receive: 67 4938.339347 4938.334891 18: seq: 1c, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 68 4938.346974 4938.342682 18: seq: 1d, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 69 4938.399547 4938.342682 14: seq: 1d, analog_in_state oid=11 next_clock=3178711718 value=2153
Receive: 70 4938.671768 4938.670970 11: seq: 17, clock clock=3177708682
Receive: 71 4938.699553 4938.670970 14: seq: 17, analog_in_state oid=11 next_clock=3193111718 value=2157
Receive: 72 4938.999565 4938.913328 14: seq: 10, analog_in_state oid=11 next_clock=3207511718 value=2153
Receive: 73 4939.304018 4939.295158 14: seq: 16, analog_in_state oid=11 next_clock=3221911718 value=2154
Receive: 74 4939.340345 4939.335863 18: seq: 19, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 75 4939.347782 4939.343447 18: seq: 1a, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 76 4939.599550 4939.343447 14: seq: 1a, analog_in_state oid=11 next_clock=3236311718 value=2153
Receive: 77 4939.653395 4939.652899 11: seq: 1b, clock clock=3224831762
Receive: 78 4939.899544 4939.691420 14: seq: 13, analog_in_state oid=11 next_clock=3250711718 value=2160
Receive: 79 4940.199543 4940.101935 14: seq: 1c, analog_in_state oid=11 next_clock=3265111718 value=2156
Receive: 80 4940.338719 4940.334424 18: seq: 1e, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80"\x02\x08 \xce'
Receive: 81 4940.346343 4940.341978 18: seq: 1f, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 82 4940.499540 4940.490372 14: seq: 17, analog_in_state oid=11 next_clock=3279511718 value=2163
Receive: 83 4940.638017 4940.637532 11: seq: 19, clock clock=3272093846
Receive: 84 4940.799546 4940.637532 14: seq: 19, analog_in_state oid=11 next_clock=3293911718 value=2159
Receive: 85 4941.099545 4940.853027 14: seq: 1a, analog_in_state oid=11 next_clock=3308311718 value=2160
Receive: 86 4941.366510 4940.853027 15: seq: 1a, stats count=983 sum=1034772 sumsq=12773530
Receive: 87 4941.399540 4940.853027 14: seq: 1a, analog_in_state oid=11 next_clock=3322711718 value=2168
Receive: 88 4941.699544 4941.452963 14: seq: 1b, analog_in_state oid=11 next_clock=3337111718 value=2162
Receive: 89 4941.999573 4941.753036 14: seq: 1c, analog_in_state oid=11 next_clock=3351511718 value=2162
Receive: 90 4942.299549 4941.753036 14: seq: 1c, analog_in_state oid=11 next_clock=3365911718 value=2171
Receive: 91 4942.599543 4942.353048 14: seq: 1d, analog_in_state oid=11 next_clock=3380311718 value=2175
Receive: 92 4942.899545 4942.353048 14: seq: 1d, analog_in_state oid=11 next_clock=3394711718 value=2161
Receive: 93 4943.199703 4942.953015 14: seq: 1e, analog_in_state oid=11 next_clock=3409111718 value=2166
Receive: 94 4943.499545 4943.252712 14: seq: 1f, analog_in_state oid=11 next_clock=3423511718 value=2162
Receive: 95 4943.799548 4943.252712 14: seq: 1f, analog_in_state oid=11 next_clock=3437911718 value=2160
Receive: 96 4944.099549 4943.852941 14: seq: 10, analog_in_state oid=11 next_clock=3452311718 value=2161
Receive: 97 4944.399548 4943.852941 14: seq: 10, analog_in_state oid=11 next_clock=3466711718 value=2167
Receive: 98 4944.662891 4944.662222 11: seq: 12, clock clock=3465279410
Receive: 99 4944.672079 4944.668960 12: seq: 13, shutdown clock=3465616641 static_string_id=Timer too close
MCU 'mcu' shutdown: Timer too close
clocksync state: mcu_freq=64000000 last_clock=296684040923 clock_est=(4911.778 294579466452 64001012.292) min_half_rtt=0.000133 min_rtt_time=363.023 time_avg=4911.778(993.325) clock_avg=294579466452.079(63573777000.006) pred_variance=71436961.842
Dumping serial stats: bytes_write=7000314 bytes_read=2036657 bytes_retransmit=0 bytes_invalid=0 send_seq=157690 receive_seq=157688 retransmit_seq=0 srtt=0.002 rttvar=0.004 rto=0.025 ready_bytes=949 upcoming_bytes=0
Dumping send queue 100 messages
Sent 0 4939.306979 4939.306979 64: seq: 15, queue_step oid=8 interval=5665 count=191 add=0, queue_step oid=8 interval=5905 count=35 add=68, queue_step oid=8 interval=8544 count=18 add=213, queue_step oid=8 interval=12833 count=9 add=790, queue_step oid=8 interval=23357 count=2 add=0, queue_step oid=8 interval=44409 count=5 add=-3916, queue_step oid=5 interval=25889 count=7 add=-1637, queue_step oid=5 interval=15690 count=14 add=-416
Sent 1 4939.307478 4939.307478 64: seq: 16, queue_step oid=8 interval=25625 count=10 add=-1001, queue_step oid=5 interval=10410 count=21 add=-129, queue_step oid=8 interval=16808 count=11 add=-317, queue_step oid=5 interval=8301 count=76 add=0, queue_step oid=8 interval=14384 count=43 add=0, queue_step oid=5 interval=8746 count=20 add=198, queue_step oid=8 interval=15606 count=10 add=556, queue_step oid=8 interval=21434 count=6 add=1985
Sent 2 4939.307979 4939.307979 63: seq: 17, queue_step oid=5 interval=13618 count=9 add=642, queue_step oid=8 interval=36104 count=3 add=4380, queue_step oid=5 interval=21708 count=3 add=2332, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2573271 count=1 add=0, queue_step oid=8 interval=23320 count=8 add=-1362, queue_step oid=8 interval=14104 count=13 add=-378, queue_step oid=8 interval=9573 count=29 add=-112
Sent 3 4939.308480 4939.308480 63: seq: 18, queue_step oid=8 interval=6592 count=34 add=-38, queue_step oid=8 interval=5664 count=202 add=0, queue_step oid=8 interval=5989 count=33 add=65, queue_step oid=8 interval=8325 count=19 add=204, queue_step oid=8 interval=12690 count=9 add=747, queue_step oid=8 interval=22296 count=2 add=0, queue_step oid=8 interval=34551 count=2 add=11766, queue_step oid=5 interval=24583 count=8 add=-1403
Sent 4 4939.554365 4939.554365 59: seq: 19, tmcuart_send oid=3 write=b'\xea\x03\xe8\xad\xe1' read=10, queue_step oid=8 interval=36317 count=5 add=-2761, queue_step oid=5 interval=15053 count=13 add=-403, queue_step oid=8 interval=23092 count=12 add=-740, queue_step oid=5 interval=10312 count=21 add=-124, queue_step oid=8 interval=15385 count=11 add=-168, queue_step oid=5 interval=8301 count=76 add=0
Sent 5 4939.560138 4939.560138 21: seq: 1a, tmcuart_send oid=3 write=b'\xea\x03( \xc3' read=10, queue_step oid=8 interval=14398 count=39 add=0
Sent 6 4939.652430 4939.652430 6: seq: 1b, get_clock
Sent 7 4939.664366 4939.664366 61: seq: 1c, set_next_step_dir oid=11 dir=0, queue_step oid=11 interval=47317057 count=1 add=0, set_next_step_dir oid=13 dir=0, queue_step oid=13 interval=47317057 count=1 add=0, set_next_step_dir oid=14 dir=0, queue_step oid=14 interval=47317057 count=1 add=0, queue_step oid=8 interval=15144 count=11 add=607, queue_step oid=5 interval=8915 count=18 add=186, queue_step oid=5 interval=12493 count=10 add=662
Sent 8 4939.664366 4939.664366 63: seq: 1d, queue_step oid=8 interval=22454 count=6 add=2018, queue_step oid=5 interval=19881 count=4 add=2531, queue_step oid=8 interval=39634 count=2 add=8749, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2555055 count=1 add=0, queue_step oid=8 interval=27187 count=4 add=-2965, queue_step oid=8 interval=17302 count=11 add=-617, queue_step oid=8 interval=11095 count=23 add=-172
Sent 9 4939.664366 4939.664366 57: seq: 1e, queue_step oid=8 interval=7535 count=38 add=-60, queue_step oid=8 interval=5664 count=210 add=0, queue_step oid=8 interval=5949 count=34 add=67, queue_step oid=8 interval=8426 count=19 add=212, queue_step oid=8 interval=13213 count=8 add=779, queue_step oid=8 interval=20407 count=3 add=3650, queue_step oid=8 interval=46907 count=3 add=-6382
Sent 10 4939.665151 4939.665151 57: seq: 1f, queue_step oid=5 interval=25200 count=7 add=-1501, queue_step oid=8 interval=29264 count=8 add=-1474, queue_step oid=5 interval=15347 count=16 add=-372, queue_step oid=8 interval=18888 count=13 add=-459, queue_step oid=5 interval=10090 count=20 add=-124, queue_step oid=8 interval=14372 count=44 add=0, queue_step oid=5 interval=8304 count=75 add=0
Sent 11 4939.665770 4939.665770 63: seq: 10, queue_step oid=8 interval=14894 count=12 add=605, queue_step oid=5 interval=8736 count=20 add=199, queue_step oid=8 interval=23561 count=5 add=2107, queue_step oid=5 interval=13604 count=9 add=646, queue_step oid=8 interval=36315 count=2 add=8133, queue_step oid=5 interval=21676 count=3 add=2352, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2570873 count=1 add=0
Sent 12 4939.666131 4939.666131 63: seq: 11, queue_step oid=8 interval=38047 count=2 add=-12910, queue_step oid=8 interval=20398 count=9 add=-922, queue_step oid=8 interval=12567 count=20 add=-238, queue_step oid=8 interval=8326 count=32 add=-81, queue_step oid=8 interval=5930 count=39 add=-12, queue_step oid=8 interval=5665 count=184 add=0, queue_step oid=8 interval=5919 count=35 add=68, queue_step oid=8 interval=8610 count=18 add=210
Sent 13 4939.675750 4939.675750 61: seq: 12, queue_step oid=8 interval=13032 count=9 add=784, queue_step oid=8 interval=24135 count=2 add=0, queue_step oid=8 interval=46919 count=4 add=-5779, queue_step oid=5 interval=24583 count=8 add=-1403, queue_step oid=8 interval=28424 count=6 add=-1603, queue_step oid=5 interval=15050 count=13 add=-402, queue_step oid=8 interval=19433 count=14 add=-459
Sent 14 4939.676230 4939.676230 64: seq: 13, queue_step oid=5 interval=10310 count=21 add=-124, queue_step oid=8 interval=14366 count=44 add=0, queue_step oid=5 interval=8297 count=76 add=0, queue_step oid=8 interval=14775 count=12 add=605, queue_step oid=5 interval=8834 count=19 add=192, queue_step oid=5 interval=12994 count=10 add=669, queue_step oid=8 interval=23002 count=5 add=2186, queue_step oid=8 interval=35791 count=3 add=4524
Sent 15 4939.676786 4939.676786 61: seq: 14, queue_step oid=5 interval=22080 count=3 add=2459, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2562215 count=1 add=0, queue_step oid=8 interval=23310 count=8 add=-1360, queue_step oid=8 interval=14097 count=14 add=-377, queue_step oid=8 interval=9644 count=23 add=-132, queue_step oid=8 interval=6941 count=36 add=-47, queue_step oid=8 interval=5664 count=205 add=0
Sent 16 4939.684503 4939.684503 57: seq: 15, queue_step oid=8 interval=6027 count=32 add=62, queue_step oid=8 interval=8222 count=19 add=193, queue_step oid=8 interval=12202 count=10 add=688, queue_step oid=8 interval=22837 count=2 add=0, queue_step oid=8 interval=34132 count=2 add=12340, queue_step oid=5 interval=25203 count=7 add=-1503, queue_step oid=8 interval=36420 count=5 add=-2777
Sent 17 4939.685005 4939.685005 64: seq: 16, queue_step oid=5 interval=15342 count=16 add=-372, queue_step oid=8 interval=23147 count=11 add=-745, queue_step oid=5 interval=10085 count=20 add=-124, queue_step oid=8 interval=15275 count=14 add=-102, queue_step oid=5 interval=8300 count=75 add=0, queue_step oid=8 interval=14428 count=37 add=0, queue_step oid=5 interval=8740 count=20 add=198, queue_step oid=8 interval=15313 count=11 add=605
Sent 18 4939.685487 4939.685487 61: seq: 17, queue_step oid=8 interval=22669 count=6 add=2163, queue_step oid=5 interval=13630 count=9 add=637, queue_step oid=5 interval=21781 count=3 add=2289, queue_step oid=8 interval=41047 count=2 add=7063, queue_step oid=5 interval=1735627 count=1 add=0, queue_step oid=8 interval=23958 count=7 add=-1466, queue_step oid=8 interval=14378 count=16 add=-349
Sent 19 4939.692995 4939.692995 60: seq: 18, queue_step oid=8 interval=9343 count=23 add=-109, queue_step oid=8 interval=7437 count=84 add=0, queue_step oid=8 interval=7773 count=22 add=168, queue_step oid=8 interval=12163 count=10 add=583, queue_step oid=8 interval=19844 count=4 add=2658, queue_step oid=8 interval=33292 count=2 add=15821, set_next_step_dir oid=8 dir=0, queue_step oid=8 interval=50943 count=2 add=10059
Sent 20 4939.693590 4939.693590 63: seq: 19, queue_step oid=5 interval=67467 count=2 add=-22663, queue_step oid=8 interval=39919 count=4 add=-5092, queue_step oid=5 interval=33448 count=5 add=-2988, queue_step oid=8 interval=23810 count=7 add=-1307, queue_step oid=5 interval=20331 count=11 add=-732, queue_step oid=8 interval=15909 count=15 add=-373, queue_step oid=5 interval=13445 count=13 add=-247
Sent 21 4939.695870 4939.695870 62: seq: 1a, queue_step oid=8 interval=11177 count=54 add=0, queue_step oid=5 interval=11178 count=50 add=0, queue_step oid=5 interval=11733 count=14 add=415, queue_step oid=8 interval=11851 count=13 add=409, queue_step oid=8 interval=17776 count=7 add=1372, queue_step oid=5 interval=19182 count=5 add=1257, queue_step oid=5 interval=879567 count=1 add=0, queue_step oid=8 interval=17884 count=12 add=-631
Sent 22 4939.696227 4939.696227 58: seq: 1b, queue_step oid=8 interval=11112 count=18 add=-47, queue_step oid=8 interval=10702 count=22 add=0, queue_step oid=8 interval=11494 count=11 add=544, queue_step oid=8 interval=19554 count=3 add=1959, queue_step oid=8 interval=27781 count=2 add=20623, queue_step oid=5 interval=27404 count=6 add=-1923, queue_step oid=8 interval=37621 count=5 add=-3130
Sent 23 4939.696859 4939.696859 64: seq: 1c, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=23675 count=10 add=-835, queue_step oid=5 interval=10776 count=22 add=-147, queue_step oid=8 interval=15827 count=12 add=-182, queue_step oid=5 interval=8308 count=76 add=0, queue_step oid=8 interval=14383 count=40 add=0, queue_step oid=8 interval=14986 count=12 add=615, queue_step oid=5 interval=8620 count=20 add=203
Sent 24 4939.697232 4939.697232 57: seq: 1d, queue_step oid=5 interval=13042 count=10 add=725, queue_step oid=8 interval=23946 count=5 add=2138, queue_step oid=8 interval=37677 count=2 add=8640, queue_step oid=5 interval=22909 count=3 add=2747, queue_step oid=8 interval=33266 count=2 add=-8686, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2538532 count=1 add=0
Sent 25 4939.697744 4939.697744 62: seq: 1e, queue_step oid=8 interval=20074 count=10 add=-891, queue_step oid=8 interval=12503 count=16 add=-274, queue_step oid=8 interval=8693 count=30 add=-92, queue_step oid=8 interval=6194 count=32 add=-26, queue_step oid=8 interval=5665 count=197 add=0, queue_step oid=8 interval=6015 count=33 add=65, queue_step oid=8 interval=8424 count=19 add=202, queue_step oid=8 interval=13103 count=8 add=719
Sent 26 4939.698227 4939.698227 60: seq: 1f, queue_step oid=8 interval=19601 count=3 add=2905, queue_step oid=8 interval=43290 count=3 add=-2971, queue_step oid=5 interval=26635 count=7 add=-1780, queue_step oid=8 interval=30412 count=7 add=-1624, queue_step oid=5 interval=16178 count=12 add=-500, queue_step oid=8 interval=19583 count=14 add=-467, queue_step oid=5 interval=11051 count=21 add=-165
Sent 27 4939.698743 4939.698743 57: seq: 10, queue_step oid=5 interval=8306 count=78 add=0, queue_step oid=8 interval=14387 count=45 add=0, queue_step oid=5 interval=8684 count=20 add=200, queue_step oid=8 interval=15562 count=10 add=567, queue_step oid=8 interval=21509 count=6 add=1937, queue_step oid=5 interval=13307 count=9 add=693, queue_step oid=8 interval=36264 count=3 add=4276
Sent 28 4939.699118 4939.699118 61: seq: 11, queue_step oid=5 interval=20728 count=4 add=2634, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2526570 count=1 add=0, queue_step oid=8 interval=23320 count=8 add=-1362, queue_step oid=8 interval=14104 count=13 add=-378, queue_step oid=8 interval=9573 count=29 add=-112, queue_step oid=8 interval=6592 count=34 add=-38, queue_step oid=8 interval=5664 count=202 add=0
Sent 29 4939.699603 4939.699603 57: seq: 12, queue_step oid=8 interval=5989 count=33 add=65, queue_step oid=8 interval=8325 count=19 add=204, queue_step oid=8 interval=12690 count=9 add=747, queue_step oid=8 interval=22297 count=2 add=0, queue_step oid=8 interval=34549 count=2 add=11768, queue_step oid=5 interval=27404 count=6 add=-1923, queue_step oid=8 interval=36317 count=5 add=-2761
Sent 30 4939.811415 4939.811415 58: seq: 13, tmcuart_send oid=4 write=b'\xea\x03\xe8\xad\xe1' read=10, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=23092 count=12 add=-740, queue_step oid=5 interval=10776 count=22 add=-147, queue_step oid=8 interval=15385 count=11 add=-168, queue_step oid=5 interval=8308 count=77 add=0, queue_step oid=8 interval=14398 count=39 add=0
Sent 31 4939.817406 4939.817406 14: seq: 14, tmcuart_send oid=4 write=b'\xea\x03( \xc3' read=10
Sent 32 4939.975379 4939.975379 14: seq: 15, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 33 4939.981138 4939.981138 14: seq: 16, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 34 4940.074740 4940.074740 61: seq: 17, set_next_step_dir oid=11 dir=1, queue_step oid=11 interval=35340907 count=1 add=0, set_next_step_dir oid=13 dir=1, queue_step oid=13 interval=35340907 count=1 add=0, set_next_step_dir oid=14 dir=1, queue_step oid=14 interval=35340907 count=1 add=0, queue_step oid=8 interval=15144 count=11 add=607, queue_step oid=5 interval=8991 count=18 add=180, queue_step oid=5 interval=12783 count=10 add=626
Sent 35 4940.074740 4940.074740 63: seq: 18, queue_step oid=8 interval=22454 count=6 add=2018, queue_step oid=5 interval=20712 count=4 add=2309, queue_step oid=8 interval=39634 count=2 add=8749, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2543812 count=1 add=0, queue_step oid=8 interval=27187 count=4 add=-2965, queue_step oid=8 interval=17302 count=11 add=-617, queue_step oid=8 interval=11095 count=23 add=-172
Sent 36 4940.074740 4940.074740 57: seq: 19, queue_step oid=8 interval=7535 count=38 add=-60, queue_step oid=8 interval=5664 count=210 add=0, queue_step oid=8 interval=5949 count=34 add=67, queue_step oid=8 interval=8426 count=19 add=212, queue_step oid=8 interval=13213 count=8 add=779, queue_step oid=8 interval=20408 count=3 add=3648, queue_step oid=8 interval=46910 count=3 add=-6384
Sent 37 4940.075506 4940.075506 57: seq: 1a, queue_step oid=5 interval=25910 count=7 add=-1641, queue_step oid=8 interval=29264 count=8 add=-1474, queue_step oid=5 interval=15709 count=14 add=-419, queue_step oid=8 interval=18888 count=13 add=-459, queue_step oid=5 interval=10441 count=21 add=-132, queue_step oid=8 interval=14372 count=44 add=0, queue_step oid=5 interval=8298 count=76 add=0
Sent 38 4940.086750 4940.086750 64: seq: 1b, queue_step oid=8 interval=14894 count=12 add=605, queue_step oid=5 interval=8711 count=20 add=197, queue_step oid=5 interval=13403 count=9 add=667, queue_step oid=8 interval=23561 count=5 add=2107, queue_step oid=8 interval=36315 count=2 add=8133, queue_step oid=5 interval=20922 count=4 add=2503, queue_step oid=8 interval=38047 count=2 add=-12910, set_next_step_dir oid=5 dir=0
Sent 39 4940.087122 4940.087122 62: seq: 1c, queue_step oid=5 interval=2524376 count=1 add=0, queue_step oid=8 interval=20398 count=9 add=-922, queue_step oid=8 interval=12567 count=20 add=-238, queue_step oid=8 interval=8326 count=32 add=-81, queue_step oid=8 interval=5930 count=39 add=-12, queue_step oid=8 interval=5665 count=184 add=0, queue_step oid=8 interval=5919 count=35 add=68, queue_step oid=8 interval=8610 count=18 add=210
Sent 40 4940.087728 4940.087728 61: seq: 1d, queue_step oid=8 interval=13032 count=9 add=784, queue_step oid=8 interval=24135 count=2 add=0, queue_step oid=8 interval=46919 count=4 add=-5779, queue_step oid=5 interval=27401 count=6 add=-1922, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=28424 count=6 add=-1603, queue_step oid=8 interval=19433 count=14 add=-459
Sent 41 4940.095232 4940.095232 64: seq: 1e, queue_step oid=5 interval=10777 count=22 add=-147, queue_step oid=8 interval=14367 count=45 add=0, queue_step oid=5 interval=8304 count=77 add=0, queue_step oid=5 interval=8880 count=19 add=191, queue_step oid=8 interval=15608 count=10 add=554, queue_step oid=8 interval=21433 count=6 add=1981, queue_step oid=5 interval=13176 count=9 add=658, queue_step oid=8 interval=36096 count=3 add=4374
Sent 42 4940.095725 4940.095725 61: seq: 1f, queue_step oid=5 interval=19929 count=4 add=2514, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2543305 count=1 add=0, queue_step oid=8 interval=23322 count=8 add=-1362, queue_step oid=8 interval=14103 count=13 add=-378, queue_step oid=8 interval=9574 count=29 add=-112, queue_step oid=8 interval=6591 count=34 add=-38, queue_step oid=8 interval=5664 count=201 add=0
Sent 43 4940.098986 4940.098986 58: seq: 10, queue_step oid=8 interval=5892 count=35 add=68, queue_step oid=8 interval=8493 count=18 add=214, queue_step oid=8 interval=12609 count=9 add=810, queue_step oid=8 interval=21472 count=2 add=3662, queue_step oid=8 interval=38059 count=2 add=6410, queue_step oid=5 interval=26621 count=7 add=-1777, queue_step oid=8 interval=35303 count=6 add=-2555
Sent 44 4940.102498 4940.102498 64: seq: 11, queue_step oid=5 interval=16167 count=12 add=-498, queue_step oid=8 interval=22236 count=10 add=-751, queue_step oid=5 interval=11049 count=21 add=-165, queue_step oid=8 interval=15320 count=13 add=-123, queue_step oid=5 interval=8306 count=78 add=0, queue_step oid=8 interval=14392 count=38 add=0, queue_step oid=5 interval=8662 count=20 add=203, queue_step oid=8 interval=15317 count=11 add=598
Sent 45 4940.102993 4940.102993 62: seq: 12, queue_step oid=8 interval=22643 count=6 add=2153, queue_step oid=5 interval=13173 count=9 add=731, queue_step oid=5 interval=20425 count=4 add=2836, set_next_step_dir oid=11 dir=0, queue_step oid=11 interval=3058030 count=1 add=0, set_next_step_dir oid=13 dir=0, queue_step oid=13 interval=3058030 count=1 add=0, set_next_step_dir oid=14 dir=0, queue_step oid=14 interval=3058030 count=1 add=0
Sent 46 4940.106621 4940.106621 61: seq: 13, queue_step oid=8 interval=40912 count=2 add=7026, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2526267 count=1 add=0, queue_step oid=8 interval=23924 count=7 add=-1460, queue_step oid=8 interval=14367 count=16 add=-348, queue_step oid=8 interval=9329 count=29 add=-108, queue_step oid=8 interval=6524 count=32 add=-38, queue_step oid=8 interval=5664 count=202 add=0
Sent 47 4940.107108 4940.107108 59: seq: 14, queue_step oid=8 interval=5947 count=34 add=67, queue_step oid=8 interval=8441 count=19 add=210, queue_step oid=8 interval=13335 count=8 add=736, queue_step oid=8 interval=20451 count=3 add=3867, queue_step oid=5 interval=27404 count=6 add=-1923, queue_step oid=8 interval=46473 count=3 add=-6088, queue_step oid=8 interval=29277 count=8 add=-1475
Sent 48 4940.107618 4940.107618 61: seq: 15, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=18895 count=12 add=-459, queue_step oid=5 interval=10776 count=22 add=-147, set_next_step_dir oid=11 dir=1, queue_step oid=11 interval=4689272 count=1 add=0, set_next_step_dir oid=13 dir=1, queue_step oid=13 interval=4689272 count=1 add=0, set_next_step_dir oid=14 dir=1, queue_step oid=14 interval=4689272 count=1 add=0
Sent 49 4940.108107 4940.108107 57: seq: 16, queue_step oid=8 interval=14370 count=45 add=0, queue_step oid=5 interval=8308 count=76 add=0, queue_step oid=8 interval=15007 count=12 add=611, queue_step oid=5 interval=8620 count=20 add=203, queue_step oid=5 interval=13038 count=10 add=726, queue_step oid=8 interval=23994 count=5 add=2115, queue_step oid=8 interval=37723 count=2 add=8594
Sent 50 4940.108491 4940.108491 62: seq: 17, queue_step oid=5 interval=22903 count=3 add=2751, queue_step oid=8 interval=33266 count=2 add=-8685, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2538533 count=1 add=0, queue_step oid=8 interval=20071 count=10 add=-890, queue_step oid=8 interval=12482 count=16 add=-270, queue_step oid=8 interval=8645 count=32 add=-88, queue_step oid=8 interval=6132 count=30 add=-25
Sent 51 4940.108966 4940.108966 57: seq: 18, queue_step oid=8 interval=5664 count=197 add=0, queue_step oid=8 interval=6039 count=32 add=63, queue_step oid=8 interval=8280 count=19 add=195, queue_step oid=8 interval=12336 count=9 add=730, queue_step oid=8 interval=19547 count=3 add=2941, queue_step oid=8 interval=43290 count=3 add=-2972, queue_step oid=5 interval=26635 count=7 add=-1780
Sent 52 4940.109491 4940.109491 63: seq: 19, queue_step oid=8 interval=30415 count=7 add=-1625, queue_step oid=5 interval=16178 count=12 add=-500, queue_step oid=8 interval=19588 count=14 add=-468, queue_step oid=5 interval=11051 count=21 add=-165, queue_step oid=5 interval=8306 count=78 add=0, queue_step oid=8 interval=14387 count=45 add=0, set_next_step_dir oid=11 dir=0, queue_step oid=11 interval=3854326 count=1 add=0, set_next_step_dir oid=13 dir=0
Sent 53 4940.109992 4940.109992 63: seq: 1a, queue_step oid=13 interval=3854326 count=1 add=0, set_next_step_dir oid=14 dir=0, queue_step oid=14 interval=3854326 count=1 add=0, queue_step oid=5 interval=8684 count=20 add=200, queue_step oid=8 interval=15586 count=10 add=561, queue_step oid=8 interval=21592 count=6 add=1901, queue_step oid=5 interval=13307 count=9 add=693, queue_step oid=8 interval=36355 count=3 add=4217
Sent 54 4940.110477 4940.110477 61: seq: 1b, queue_step oid=5 interval=20727 count=4 add=2635, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2526567 count=1 add=0, queue_step oid=8 interval=23320 count=8 add=-1362, queue_step oid=8 interval=14108 count=13 add=-379, queue_step oid=8 interval=9584 count=29 add=-113, queue_step oid=8 interval=6620 count=33 add=-40, queue_step oid=8 interval=5664 count=203 add=0
Sent 55 4940.110865 4940.110865 59: seq: 1c, queue_step oid=8 interval=5962 count=34 add=67, queue_step oid=8 interval=8484 count=18 add=210, queue_step oid=8 interval=12520 count=9 add=794, queue_step oid=8 interval=20933 count=2 add=3647, queue_step oid=8 interval=33266 count=2 add=13051, queue_step oid=5 interval=27404 count=6 add=-1923, queue_step oid=8 interval=36317 count=5 add=-2761
Sent 56 4940.179414 4940.179414 58: seq: 1d, tmcuart_send oid=2 write=b'\xea\x03\xe8\xad\xe1' read=10, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=23092 count=12 add=-740, queue_step oid=5 interval=10776 count=22 add=-147, queue_step oid=8 interval=15385 count=11 add=-168, queue_step oid=5 interval=8308 count=77 add=0, queue_step oid=8 interval=14398 count=39 add=0
Sent 57 4940.185333 4940.185333 14: seq: 1e, tmcuart_send oid=2 write=b'\xea\x03( \xc3' read=10
Sent 58 4940.205140 4940.205140 14: seq: 1f, tmcuart_send oid=1 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 59 4940.210647 4940.210647 14: seq: 10, tmcuart_send oid=1 write=b'\xea\x03( \xc3' read=10
Sent 60 4940.462934 4940.462934 61: seq: 11, set_next_step_dir oid=11 dir=1, queue_step oid=11 interval=4399590 count=1 add=0, set_next_step_dir oid=13 dir=1, queue_step oid=13 interval=4399590 count=1 add=0, set_next_step_dir oid=14 dir=1, queue_step oid=14 interval=4399590 count=1 add=0, queue_step oid=8 interval=15144 count=11 add=607, queue_step oid=5 interval=8991 count=18 add=180, queue_step oid=5 interval=12783 count=10 add=626
Sent 61 4940.462934 4940.462934 63: seq: 12, queue_step oid=8 interval=22454 count=6 add=2018, queue_step oid=5 interval=20712 count=4 add=2309, queue_step oid=8 interval=39634 count=2 add=8749, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2543812 count=1 add=0, queue_step oid=8 interval=27187 count=4 add=-2965, queue_step oid=8 interval=17302 count=11 add=-617, queue_step oid=8 interval=11095 count=23 add=-172
Sent 62 4940.462934 4940.462934 57: seq: 13, queue_step oid=8 interval=7535 count=38 add=-60, queue_step oid=8 interval=5664 count=210 add=0, queue_step oid=8 interval=5949 count=34 add=67, queue_step oid=8 interval=8426 count=19 add=212, queue_step oid=8 interval=13213 count=8 add=779, queue_step oid=8 interval=20408 count=3 add=3648, queue_step oid=8 interval=46910 count=3 add=-6383
Sent 63 4940.463756 4940.463756 60: seq: 14, queue_step oid=5 interval=25910 count=7 add=-1641, queue_step oid=8 interval=29264 count=8 add=-1474, queue_step oid=5 interval=15709 count=14 add=-419, queue_step oid=8 interval=18888 count=13 add=-459, queue_step oid=5 interval=10441 count=21 add=-132, queue_step oid=8 interval=14372 count=44 add=0, queue_step oid=5 interval=8298 count=76 add=0, set_next_step_dir oid=11 dir=0
Sent 64 4940.472627 4940.472627 58: seq: 15, queue_step oid=11 interval=3727806 count=1 add=0, set_next_step_dir oid=13 dir=0, queue_step oid=13 interval=3727806 count=1 add=0, set_next_step_dir oid=14 dir=0, queue_step oid=14 interval=3727806 count=1 add=0, queue_step oid=8 interval=14890 count=12 add=606, queue_step oid=5 interval=8711 count=20 add=197, queue_step oid=5 interval=13403 count=9 add=667
Sent 65 4940.474493 4940.474493 57: seq: 16, queue_step oid=8 interval=23541 count=5 add=2117, queue_step oid=8 interval=36294 count=2 add=8154, queue_step oid=5 interval=20922 count=4 add=2503, queue_step oid=8 interval=38047 count=2 add=-12910, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2524376 count=1 add=0, queue_step oid=8 interval=20398 count=9 add=-922
Sent 66 4940.474978 4940.474978 61: seq: 17, queue_step oid=8 interval=12567 count=20 add=-238, queue_step oid=8 interval=8326 count=32 add=-81, queue_step oid=8 interval=5930 count=39 add=-12, queue_step oid=8 interval=5665 count=184 add=0, queue_step oid=8 interval=5919 count=35 add=68, queue_step oid=8 interval=8610 count=18 add=210, queue_step oid=8 interval=13032 count=9 add=784, queue_step oid=8 interval=24135 count=2 add=0
Sent 67 4940.478626 4940.478626 60: seq: 18, queue_step oid=8 interval=46918 count=4 add=-5778, queue_step oid=5 interval=27401 count=6 add=-1922, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=28420 count=6 add=-1601, queue_step oid=8 interval=19432 count=14 add=-459, queue_step oid=5 interval=10777 count=22 add=-147, queue_step oid=8 interval=14367 count=45 add=0
Sent 68 4940.482233 4940.482233 59: seq: 19, queue_step oid=5 interval=8304 count=77 add=0, set_next_step_dir oid=11 dir=1, queue_step oid=11 interval=5006911 count=1 add=0, set_next_step_dir oid=13 dir=1, queue_step oid=13 interval=5006911 count=1 add=0, set_next_step_dir oid=14 dir=1, queue_step oid=14 interval=5006911 count=1 add=0, queue_step oid=5 interval=8880 count=19 add=191, queue_step oid=8 interval=15612 count=10 add=553
Sent 69 4940.482730 4940.482730 64: seq: 1a, queue_step oid=8 interval=21441 count=6 add=1978, queue_step oid=5 interval=13176 count=9 add=658, queue_step oid=8 interval=36104 count=3 add=4368, queue_step oid=5 interval=19929 count=4 add=2514, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2543305 count=1 add=0, queue_step oid=8 interval=23322 count=8 add=-1362, queue_step oid=8 interval=14104 count=13 add=-378
Sent 70 4940.486620 4940.486620 61: seq: 1b, queue_step oid=8 interval=9573 count=29 add=-112, queue_step oid=8 interval=6592 count=34 add=-38, queue_step oid=8 interval=5664 count=202 add=0, queue_step oid=8 interval=6039 count=32 add=62, queue_step oid=8 interval=8266 count=19 add=192, queue_step oid=8 interval=12258 count=10 add=712, queue_step oid=8 interval=23223 count=2 add=0, queue_step oid=8 interval=38970 count=2 add=5499
Sent 71 4940.490252 4940.490252 58: seq: 1c, queue_step oid=5 interval=26621 count=7 add=-1777, queue_step oid=8 interval=35305 count=6 add=-2556, queue_step oid=5 interval=16167 count=12 add=-498, queue_step oid=8 interval=22239 count=10 add=-752, queue_step oid=5 interval=11049 count=21 add=-165, queue_step oid=8 interval=15329 count=13 add=-125, queue_step oid=5 interval=8306 count=78 add=0
Sent 72 4940.490733 4940.490733 63: seq: 1d, queue_step oid=8 interval=14394 count=38 add=0, queue_step oid=5 interval=8662 count=20 add=203, queue_step oid=8 interval=15307 count=11 add=600, queue_step oid=8 interval=22623 count=6 add=2161, queue_step oid=5 interval=13173 count=9 add=731, set_next_step_dir oid=11 dir=0, queue_step oid=11 interval=1124832 count=1 add=0, set_next_step_dir oid=13 dir=0, queue_step oid=13 interval=1124832 count=1 add=0
Sent 73 4940.494604 4940.494604 58: seq: 1e, set_next_step_dir oid=14 dir=0, queue_step oid=14 interval=1124832 count=1 add=0, queue_step oid=5 interval=20425 count=4 add=2836, queue_step oid=8 interval=40893 count=2 add=7045, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2526267 count=1 add=0, queue_step oid=8 interval=23924 count=7 add=-1460, queue_step oid=8 interval=14367 count=16 add=-348
Sent 74 4940.495107 4940.495107 63: seq: 1f, queue_step oid=8 interval=9329 count=29 add=-108, queue_step oid=8 interval=6524 count=32 add=-38, queue_step oid=8 interval=5664 count=202 add=0, queue_step oid=8 interval=5947 count=34 add=67, queue_step oid=8 interval=8441 count=19 add=210, queue_step oid=8 interval=13335 count=8 add=736, queue_step oid=8 interval=20451 count=3 add=3867, queue_step oid=5 interval=27404 count=6 add=-1923
Sent 75 4940.495600 4940.495600 58: seq: 10, queue_step oid=8 interval=46473 count=3 add=-6088, queue_step oid=8 interval=29277 count=8 add=-1475, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=18895 count=12 add=-459, queue_step oid=5 interval=10776 count=22 add=-147, queue_step oid=8 interval=14370 count=45 add=0, queue_step oid=5 interval=8308 count=76 add=0
Sent 76 4940.496108 4940.496108 64: seq: 11, queue_step oid=8 interval=15007 count=12 add=611, queue_step oid=5 interval=8620 count=20 add=203, queue_step oid=5 interval=13042 count=10 add=725, queue_step oid=8 interval=23994 count=5 add=2115, queue_step oid=8 interval=37723 count=2 add=8594, queue_step oid=5 interval=22908 count=3 add=2748, queue_step oid=8 interval=33266 count=2 add=-8685, set_next_step_dir oid=5 dir=1
Sent 77 4940.496614 4940.496614 62: seq: 12, queue_step oid=5 interval=2538532 count=1 add=0, queue_step oid=8 interval=20071 count=10 add=-890, queue_step oid=8 interval=12482 count=16 add=-270, queue_step oid=8 interval=8645 count=32 add=-88, queue_step oid=8 interval=6132 count=30 add=-25, queue_step oid=8 interval=5664 count=197 add=0, queue_step oid=8 interval=6039 count=32 add=63, queue_step oid=8 interval=8280 count=19 add=195
Sent 78 4940.496993 4940.496993 61: seq: 13, queue_step oid=8 interval=12336 count=9 add=730, queue_step oid=8 interval=19547 count=3 add=2941, queue_step oid=8 interval=43290 count=3 add=-2972, queue_step oid=5 interval=26635 count=7 add=-1780, queue_step oid=8 interval=30415 count=7 add=-1625, queue_step oid=5 interval=16178 count=12 add=-500, queue_step oid=8 interval=19588 count=14 add=-468
Sent 79 4940.497492 4940.497492 64: seq: 14, queue_step oid=5 interval=11051 count=21 add=-165, queue_step oid=5 interval=8306 count=78 add=0, queue_step oid=8 interval=14387 count=45 add=0, queue_step oid=5 interval=8684 count=20 add=200, queue_step oid=8 interval=15586 count=10 add=561, queue_step oid=8 interval=21592 count=6 add=1901, queue_step oid=5 interval=13307 count=9 add=693, queue_step oid=8 interval=36356 count=3 add=4216
Sent 80 4940.497991 4940.497991 61: seq: 15, queue_step oid=5 interval=20727 count=4 add=2635, set_next_step_dir oid=5 dir=0, queue_step oid=5 interval=2526567 count=1 add=0, queue_step oid=8 interval=23320 count=8 add=-1362, queue_step oid=8 interval=14108 count=13 add=-379, queue_step oid=8 interval=9584 count=29 add=-113, queue_step oid=8 interval=6620 count=33 add=-40, queue_step oid=8 interval=5664 count=203 add=0
Sent 81 4940.498479 4940.498479 59: seq: 16, queue_step oid=8 interval=5962 count=34 add=67, queue_step oid=8 interval=8484 count=18 add=210, queue_step oid=8 interval=12520 count=9 add=794, queue_step oid=8 interval=20933 count=2 add=3647, queue_step oid=8 interval=33266 count=2 add=13051, queue_step oid=5 interval=27404 count=6 add=-1923, queue_step oid=8 interval=36317 count=5 add=-2761
Sent 82 4940.555475 4940.555475 58: seq: 17, tmcuart_send oid=3 write=b'\xea\x03\xe8\xad\xe1' read=10, queue_step oid=5 interval=16579 count=14 add=-464, queue_step oid=8 interval=23092 count=12 add=-740, queue_step oid=5 interval=10776 count=22 add=-147, queue_step oid=8 interval=15385 count=11 add=-168, queue_step oid=5 interval=8308 count=77 add=0, queue_step oid=8 interval=14398 count=39 add=0
Sent 83 4940.561338 4940.561338 14: seq: 18, tmcuart_send oid=3 write=b'\xea\x03( \xc3' read=10
Sent 84 4940.637070 4940.637070 6: seq: 19, get_clock
Sent 85 4940.811705 4940.811705 14: seq: 1a, tmcuart_send oid=4 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 86 4940.817239 4940.817239 14: seq: 1b, tmcuart_send oid=4 write=b'\xea\x03( \xc3' read=10
Sent 87 4941.083897 4941.083897 16: seq: 1c, queue_digital_out oid=16 clock=118168576 on_ticks=1826875
Sent 88 4941.383890 4941.383890 15: seq: 1d, queue_digital_out oid=16 clock=137368576 on_ticks=1075203
Sent 89 4941.683944 4941.683944 15: seq: 1e, queue_digital_out oid=16 clock=156568576 on_ticks=1468387
Sent 90 4941.983901 4941.983901 15: seq: 1f, queue_digital_out oid=16 clock=175768576 on_ticks=389166
Sent 91 4942.583859 4942.583859 15: seq: 10, queue_digital_out oid=16 clock=214168576 on_ticks=1244503
Sent 92 4943.183929 4943.183929 15: seq: 11, queue_digital_out oid=16 clock=252568576 on_ticks=390914
Sent 93 4943.483951 4943.483951 15: seq: 12, queue_digital_out oid=16 clock=271768576 on_ticks=848441
Sent 94 4943.783925 4943.783925 15: seq: 13, queue_digital_out oid=16 clock=290968576 on_ticks=424788
Sent 95 4944.083962 4944.083962 15: seq: 14, queue_digital_out oid=16 clock=310168576 on_ticks=766783
Sent 96 4944.661783 4944.661783 6: seq: 15, get_clock
Sent 97 4944.665388 4944.665388 60: seq: 16, queue_step oid=11 interval=24593671 count=1 add=0, queue_step oid=13 interval=24593671 count=1 add=0, queue_step oid=14 interval=24593671 count=1 add=0, queue_step oid=8 interval=15145 count=11 add=607, queue_step oid=5 interval=8991 count=18 add=180, queue_step oid=5 interval=12783 count=10 add=626, queue_step oid=8 interval=22439 count=6 add=2026
Sent 98 4944.665388 4944.665388 62: seq: 17, queue_step oid=5 interval=20713 count=4 add=2308, queue_step oid=8 interval=39594 count=2 add=8788, set_next_step_dir oid=5 dir=1, queue_step oid=5 interval=2543815 count=1 add=0, queue_step oid=8 interval=27187 count=4 add=-2965, queue_step oid=8 interval=17302 count=11 add=-617, queue_step oid=8 interval=11095 count=23 add=-172, queue_step oid=8 interval=7535 count=38 add=-60
Sent 99 4944.665388 4944.665388 58: seq: 18, queue_step oid=8 interval=5664 count=210 add=0, queue_step oid=8 interval=5949 count=34 add=67, queue_step oid=8 interval=8426 count=19 add=212, queue_step oid=8 interval=13213 count=8 add=779, queue_step oid=8 interval=20408 count=3 add=3648, queue_step oid=8 interval=46910 count=3 add=-6383, queue_step oid=5 interval=25910 count=7 add=-1641
Dumping receive queue 100 messages
Receive: 0 4935.031385 4935.020387 15: seq: 17, analog_in_state oid=15 next_clock=4028623872 value=23520
Receive: 1 4935.166856 4935.162342 18: seq: 18, tmcuart_response oid=2 read=b'\n\xfa\xef-\xa0&\x02\x08 \x9b'
Receive: 2 4935.172976 4935.168548 18: seq: 19, tmcuart_response oid=2 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 3 4935.207755 4935.203431 18: seq: 1a, tmcuart_response oid=1 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 4 4935.214245 4935.209873 18: seq: 1b, tmcuart_response oid=1 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 5 4935.331352 4935.209873 15: seq: 1b, analog_in_state oid=15 next_clock=4047823872 value=23528
Receive: 6 4935.556014 4935.551456 18: seq: 11, tmcuart_response oid=3 read=b'\n\xfa\xef-\xa0&\x02\x08 \x9b'
Receive: 7 4935.562357 4935.558101 18: seq: 12, tmcuart_response oid=3 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 8 4935.631377 4935.558101 15: seq: 12, analog_in_state oid=15 next_clock=4067023872 value=23514
Receive: 9 4935.714680 4935.714131 11: seq: 14, clock clock=4053606144
Receive: 10 4935.822415 4935.819544 18: seq: 18, tmcuart_response oid=4 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 11 4935.829129 4935.824412 18: seq: 19, tmcuart_response oid=4 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 12 4935.931383 4935.824412 15: seq: 19, analog_in_state oid=15 next_clock=4086223872 value=23511
Receive: 13 4935.961607 4935.957298 18: seq: 1a, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 14 4935.967863 4935.963580 18: seq: 1b, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 15 4936.197048 4936.185768 18: seq: 13, tmcuart_response oid=2 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 16 4936.210512 4936.208543 18: seq: 1f, tmcuart_response oid=2 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 17 4936.217870 4936.213105 18: seq: 12, tmcuart_response oid=1 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 18 4936.224274 4936.219876 18: seq: 13, tmcuart_response oid=1 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 19 4936.231364 4936.219876 15: seq: 13, analog_in_state oid=15 next_clock=4105423872 value=23512
Receive: 20 4936.531390 4936.219876 15: seq: 13, analog_in_state oid=15 next_clock=4124623872 value=23511
Receive: 21 4936.556531 4936.552123 18: seq: 14, tmcuart_response oid=3 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 22 4936.563128 4936.558755 18: seq: 15, tmcuart_response oid=3 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 23 4936.699600 4936.698570 11: seq: 1b, clock clock=4116639528
Receive: 24 4936.814372 4936.810031 18: seq: 1c, tmcuart_response oid=4 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 25 4936.820737 4936.816359 18: seq: 1d, tmcuart_response oid=4 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 26 4936.831408 4936.816359 15: seq: 1d, analog_in_state oid=15 next_clock=4143823872 value=23509
Receive: 27 4936.987855 4936.981642 18: seq: 13, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 28 4937.002854 4936.991543 18: seq: 18, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 29 4937.131323 4937.007144 15: seq: 14, analog_in_state oid=15 next_clock=4163023872 value=23512
Receive: 30 4937.183133 4937.178618 18: seq: 15, tmcuart_response oid=2 read=b'\n\xfa\xef-\xa0&\x02\x08 \x9b'
Receive: 31 4937.188915 4937.184496 18: seq: 16, tmcuart_response oid=2 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 32 4937.208926 4937.204504 18: seq: 17, tmcuart_response oid=1 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 33 4937.214483 4937.210134 18: seq: 18, tmcuart_response oid=1 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 34 4937.431335 4937.386726 15: seq: 1e, analog_in_state oid=15 next_clock=4182223872 value=23497
Receive: 35 4937.556944 4937.552697 18: seq: 10, tmcuart_response oid=3 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 36 4937.562481 4937.558140 18: seq: 11, tmcuart_response oid=3 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 37 4937.683409 4937.683015 11: seq: 12, clock clock=4179615784
Receive: 38 4937.731257 4937.724756 15: seq: 14, analog_in_state oid=15 next_clock=4201423872 value=23497
Receive: 39 4937.814562 4937.809979 18: seq: 15, tmcuart_response oid=4 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 40 4937.819944 4937.815742 18: seq: 16, tmcuart_response oid=4 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 41 4937.978684 4937.974403 18: seq: 17, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 42 4937.984098 4937.979870 18: seq: 18, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 43 4938.031333 4937.979870 15: seq: 18, analog_in_state oid=15 next_clock=4220623872 value=23506
Receive: 44 4938.184376 4938.179779 18: seq: 1d, tmcuart_response oid=2 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 45 4938.190189 4938.185830 18: seq: 1e, tmcuart_response oid=2 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 46 4938.209219 4938.204963 18: seq: 1f, tmcuart_response oid=1 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 47 4938.214687 4938.210421 18: seq: 10, tmcuart_response oid=1 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 48 4938.331321 4938.210421 14: seq: 10, analog_in_state oid=15 next_clock=4239823872 value=23512
Receive: 49 4938.558312 4938.553739 18: seq: 17, tmcuart_response oid=3 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 50 4938.563856 4938.559628 18: seq: 18, tmcuart_response oid=3 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 51 4938.631313 4938.559628 14: seq: 18, analog_in_state oid=15 next_clock=4259023872 value=23509
Receive: 52 4938.668037 4938.667556 10: seq: 19, clock clock=4242627505
Receive: 53 4938.814818 4938.810522 18: seq: 1a, tmcuart_response oid=4 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 54 4938.820335 4938.816024 18: seq: 1b, tmcuart_response oid=4 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 55 4938.931213 4938.921002 14: seq: 10, analog_in_state oid=15 next_clock=4278223872 value=23510
Receive: 56 4938.979485 4938.974817 18: seq: 11, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 57 4938.985058 4938.980733 18: seq: 12, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 58 4939.183479 4939.179128 18: seq: 13, tmcuart_response oid=2 read=b'\n\xfa\xef-\xa0&\x02\x08 \x9b'
Receive: 59 4939.189186 4939.184900 18: seq: 14, tmcuart_response oid=2 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 60 4939.209236 4939.205002 18: seq: 15, tmcuart_response oid=1 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 61 4939.214724 4939.210447 18: seq: 16, tmcuart_response oid=1 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 62 4939.231194 4939.210447 14: seq: 16, analog_in_state oid=15 next_clock=2456576 value=23513
Receive: 63 4939.531221 4939.308480 14: seq: 19, analog_in_state oid=15 next_clock=21656576 value=23507
Receive: 64 4939.558960 4939.554365 18: seq: 1a, tmcuart_response oid=3 read=b'\n\xfa\xef-\xa0&\x02\x08 \x9b'
Receive: 65 4939.564647 4939.560138 18: seq: 1b, tmcuart_response oid=3 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 66 4939.652982 4939.652430 10: seq: 1c, clock clock=10693100
Receive: 67 4939.816072 4939.811415 18: seq: 14, tmcuart_response oid=4 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 68 4939.821696 4939.817406 18: seq: 15, tmcuart_response oid=4 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 69 4939.831211 4939.817406 14: seq: 15, analog_in_state oid=15 next_clock=40856576 value=23514
Receive: 70 4939.974206 4939.817406 16: seq: 15, stats count=8490 sum=10834045 sumsq=109184645
Receive: 71 4939.979603 4939.975379 18: seq: 16, tmcuart_response oid=0 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 72 4939.985489 4939.981138 18: seq: 17, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 73 4940.131218 4940.110865 14: seq: 1d, analog_in_state oid=15 next_clock=60056576 value=23506
Receive: 74 4940.184069 4940.179414 18: seq: 1e, tmcuart_response oid=2 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 75 4940.189570 4940.185333 18: seq: 1f, tmcuart_response oid=2 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 76 4940.209472 4940.205140 18: seq: 10, tmcuart_response oid=1 read=b'\n\xfa\xef-\x80&\x02\x08 \x8d'
Receive: 77 4940.214953 4940.210647 18: seq: 11, tmcuart_response oid=1 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 78 4940.431241 4940.210647 14: seq: 11, analog_in_state oid=15 next_clock=79256576 value=23510
Receive: 79 4940.560080 4940.555475 18: seq: 18, tmcuart_response oid=3 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 80 4940.565569 4940.561338 18: seq: 19, tmcuart_response oid=3 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 81 4940.637542 4940.637070 10: seq: 1a, clock clock=73711021
Receive: 82 4940.731206 4940.637070 14: seq: 1a, analog_in_state oid=15 next_clock=98456576 value=23504
Receive: 83 4940.815968 4940.811705 18: seq: 1b, tmcuart_response oid=4 read=b'\n\xfa\xef-\xe0&\x02\x08 \x90'
Receive: 84 4940.821579 4940.817239 18: seq: 1c, tmcuart_response oid=4 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 85 4941.031228 4940.817239 14: seq: 1c, analog_in_state oid=15 next_clock=117656576 value=23523
Receive: 86 4941.331198 4941.083897 14: seq: 1d, analog_in_state oid=15 next_clock=136856576 value=23511
Receive: 87 4941.631215 4941.383890 14: seq: 1e, analog_in_state oid=15 next_clock=156056576 value=23519
Receive: 88 4941.931225 4941.683944 14: seq: 1f, analog_in_state oid=15 next_clock=175256576 value=23498
Receive: 89 4942.231214 4941.983901 14: seq: 10, analog_in_state oid=15 next_clock=194456576 value=23497
Receive: 90 4942.531201 4941.983901 15: seq: 10, analog_in_state oid=15 next_clock=213656576 value=23509
Receive: 91 4942.831207 4942.583859 15: seq: 11, analog_in_state oid=15 next_clock=232856576 value=23507
Receive: 92 4943.131217 4942.583859 15: seq: 11, analog_in_state oid=15 next_clock=252056576 value=23492
Receive: 93 4943.431207 4943.183929 15: seq: 12, analog_in_state oid=15 next_clock=271256576 value=23498
Receive: 94 4943.731205 4943.483951 15: seq: 13, analog_in_state oid=15 next_clock=290456576 value=23488
Receive: 95 4944.031256 4943.783925 15: seq: 14, analog_in_state oid=15 next_clock=309656576 value=23492
Receive: 96 4944.331187 4944.083962 15: seq: 15, analog_in_state oid=15 next_clock=328856576 value=23490
Receive: 97 4944.631187 4944.083962 15: seq: 15, analog_in_state oid=15 next_clock=348056576 value=23490
Receive: 98 4944.662234 4944.661783 11: seq: 16, clock clock=331297499
Receive: 99 4944.678448 4944.665388 12: seq: 17, shutdown clock=332307712 static_string_id=Timer too close
- - TRACE: Processing idle_timeout 'printing' event
Transition to shutdown state: MCU 'HermitCrab' shutdown: Timer too close
This often indicates the host computer is overloaded. Check
for other processes consuming excessive CPU time, high swap
usage, disk errors, overheating, unstable voltage, or
similar system problems on the host computer.
Dumping gcode input 0 blocks
Dumping 20 requests for client 281472807143456
Received 548.254713: b'{"id": 281473168673760, "method": "gcode/script", "params": {"script": "SDCARD_PRINT_FILE FILENAME=\\"buffer-bottom_xN.gcode\\""}}'
Received 1164.463028: b'{"id": 281473168729232, "method": "info", "params": {}}'
Received 1164.464191: b'{"id": 281473168799296, "method": "objects/list", "params": {}}'
Received 1164.464191: b'{"id": 281473168799680, "method": "gcode/help", "params": {}}'
Received 1164.521051: b'{"id": 281473168715632, "method": "objects/subscribe", "params": {"objects": {"webhooks": null, "configfile": null, "mcu": null, "mcu mmu": null, "mcu HermitCrab": null, "stepper_enable": null, "tmc2209 manual_extruder_stepper extruder": null, "motion_report": null, "query_endstops": null, "manual_extruder_stepper extruder": null, "tmc2209 manual_extruder_stepper gear_stepper": null, "manual_extruder_stepper gear_stepper": null, "tmc2209 manual_mh_stepper selector_stepper": null, "mmu_servo mmu_servo": null, "mmu_encoder mmu_encoder": null, "gcode_move": null, "mmu": null, "save_variables": null, "gcode_macro T0": null, "gcode_macro T1": null, "gcode_macro T2": null, "gcode_macro T3": null, "gcode_macro T4": null, "gcode_macro T5": null, "gcode_macro T6": null, "gcode_macro T7": null, "gcode_macro T8": null, "gcode_macro MMU__EJECT": null, "gcode_macro MMU__HOME": null, "gcode_macro MMU__STATUS": null, "gcode_macro MMU__MOTORS_OFF": null, "gcode_macro MMU__SERVO": null, "gcode_macro MMU__SELECT_TOOL": null, "gcode_macro MMU__SELECT_BYPASS": null, "gcode_macro MMU__LOAD_BYPASS": null, "gcode_macro MMU__RECOVER": null, "gcode_macro MMU__PRELOAD": null, "gcode_macro MMU__CHECK_GATES": null, "gcode_macro MMU_CHANGE_TOOL_STANDALONE": null, "gcode_macro _MMU_FORM_TIP_STANDALONE": null, "gcode_macro _MMU_CUT_TIP": null, "gcode_macro _MMU_PRE_UNLOAD": null, "gcode_macro _MMU_POST_LOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_PRE_UNLOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_POST_LOAD": null, "gcode_macro _MMU_ACTION_CHANGED": null, "gcode_macro _MMU_LOAD_SEQUENCE": null, "gcode_macro _MMU_UNLOAD_SEQUENCE": null, "gcode_macro _PARK": null, "gcode_macro CANCEL_PRINT": null, "gcode_macro PAUSE": null, "gcode_macro RESUME": null, "print_stats": null, "virtual_sdcard": null, "pause_resume": null, "display_status": 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 DISABLE_MOTORS_EXCEPT_Z": null, "gcode_macro TIP_FORMING": null, "gcode_macro TIP_FORMING_AND_EJECT": null, "gcode_macro UNSAFE_LOWER_BED": null, "gcode_macro BLTOUCH_RESET": null, "gcode_macro BLTOUCH_SELF_TEST": null, "gcode_macro BLTOUCH_PIN_DOWN": null, "gcode_macro calibrate_probe": null, "gcode_macro PRIME_LINE": null, "gcode_macro START_PRINT": null, "gcode_macro END_PRINT": null, "gcode_macro TEST_SPEED": null, "z_tilt": null, "bed_mesh": null, "gcode_macro _KAMP_Settings": null, "gcode_macro BED_MESH_CALIBRATE": null, "gcode_macro VORON_PURGE": null, "gcode_macro SMART_PARK": null, "exclude_object": null, "tmc2209 stepper_x": null, "tmc2209 stepper_y": null, "tmc2209 stepper_z": null, "tmc2209 stepper_z1": null, "tmc2209 stepper_z2": null, "heaters": null, "heater_bed": null, "led front_led_bar": null, "probe": null, "fan": null, "heater_fan heatbreak_cooling_fan": null, "neopixel hotend_rgb": null, "idle_timeout": null, "system_stats": null, "manual_probe": null, "toolhead": null, "extruder": null}, "response_template": {"method": "process_status_update"}}}'
Received 1164.848220: b'{"id": 281473168800304, "method": "objects/query", "params": {"objects": {"extruder": ["can_extrude"]}}}'
Received 1434.450288: b'{"id": 281473168097680, "method": "info", "params": {}}'
Received 1434.451416: b'{"id": 281473168097872, "method": "objects/list", "params": {}}'
Received 1434.451416: b'{"id": 281473168099072, "method": "gcode/help", "params": {}}'
Received 1434.489844: b'{"id": 281473171037728, "method": "objects/subscribe", "params": {"objects": {"webhooks": null, "configfile": null, "mcu": null, "mcu mmu": null, "mcu HermitCrab": null, "stepper_enable": null, "tmc2209 manual_extruder_stepper extruder": null, "motion_report": null, "query_endstops": null, "manual_extruder_stepper extruder": null, "tmc2209 manual_extruder_stepper gear_stepper": null, "manual_extruder_stepper gear_stepper": null, "tmc2209 manual_mh_stepper selector_stepper": null, "mmu_servo mmu_servo": null, "mmu_encoder mmu_encoder": null, "gcode_move": null, "mmu": null, "save_variables": null, "gcode_macro T0": null, "gcode_macro T1": null, "gcode_macro T2": null, "gcode_macro T3": null, "gcode_macro T4": null, "gcode_macro T5": null, "gcode_macro T6": null, "gcode_macro T7": null, "gcode_macro T8": null, "gcode_macro MMU__EJECT": null, "gcode_macro MMU__HOME": null, "gcode_macro MMU__STATUS": null, "gcode_macro MMU__MOTORS_OFF": null, "gcode_macro MMU__SERVO": null, "gcode_macro MMU__SELECT_TOOL": null, "gcode_macro MMU__SELECT_BYPASS": null, "gcode_macro MMU__LOAD_BYPASS": null, "gcode_macro MMU__RECOVER": null, "gcode_macro MMU__PRELOAD": null, "gcode_macro MMU__CHECK_GATES": null, "gcode_macro MMU_CHANGE_TOOL_STANDALONE": null, "gcode_macro _MMU_FORM_TIP_STANDALONE": null, "gcode_macro _MMU_CUT_TIP": null, "gcode_macro _MMU_PRE_UNLOAD": null, "gcode_macro _MMU_POST_LOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_PRE_UNLOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_POST_LOAD": null, "gcode_macro _MMU_ACTION_CHANGED": null, "gcode_macro _MMU_LOAD_SEQUENCE": null, "gcode_macro _MMU_UNLOAD_SEQUENCE": null, "gcode_macro _PARK": null, "gcode_macro CANCEL_PRINT": null, "gcode_macro PAUSE": null, "gcode_macro RESUME": null, "print_stats": null, "virtual_sdcard": null, "pause_resume": null, "display_status": 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 DISABLE_MOTORS_EXCEPT_Z": null, "gcode_macro TIP_FORMING": null, "gcode_macro TIP_FORMING_AND_EJECT": null, "gcode_macro UNSAFE_LOWER_BED": null, "gcode_macro BLTOUCH_RESET": null, "gcode_macro BLTOUCH_SELF_TEST": null, "gcode_macro BLTOUCH_PIN_DOWN": null, "gcode_macro calibrate_probe": null, "gcode_macro PRIME_LINE": null, "gcode_macro START_PRINT": null, "gcode_macro END_PRINT": null, "gcode_macro TEST_SPEED": null, "z_tilt": null, "bed_mesh": null, "gcode_macro _KAMP_Settings": null, "gcode_macro BED_MESH_CALIBRATE": null, "gcode_macro VORON_PURGE": null, "gcode_macro SMART_PARK": null, "exclude_object": null, "tmc2209 stepper_x": null, "tmc2209 stepper_y": null, "tmc2209 stepper_z": null, "tmc2209 stepper_z1": null, "tmc2209 stepper_z2": null, "heaters": null, "heater_bed": null, "led front_led_bar": null, "probe": null, "fan": null, "heater_fan heatbreak_cooling_fan": null, "neopixel hotend_rgb": null, "idle_timeout": null, "system_stats": null, "manual_probe": null, "toolhead": null, "extruder": null}, "response_template": {"method": "process_status_update"}}}'
Received 1934.525080: b'{"id": 281473168715584, "method": "info", "params": {}}'
Received 1934.527106: b'{"id": 281473168098160, "method": "objects/list", "params": {}}'
Received 1934.527106: b'{"id": 281473168099360, "method": "gcode/help", "params": {}}'
Received 1934.559472: b'{"id": 281473168801072, "method": "objects/subscribe", "params": {"objects": {"webhooks": null, "configfile": null, "mcu": null, "mcu mmu": null, "mcu HermitCrab": null, "stepper_enable": null, "tmc2209 manual_extruder_stepper extruder": null, "motion_report": null, "query_endstops": null, "manual_extruder_stepper extruder": null, "tmc2209 manual_extruder_stepper gear_stepper": null, "manual_extruder_stepper gear_stepper": null, "tmc2209 manual_mh_stepper selector_stepper": null, "mmu_servo mmu_servo": null, "mmu_encoder mmu_encoder": null, "gcode_move": null, "mmu": null, "save_variables": null, "gcode_macro T0": null, "gcode_macro T1": null, "gcode_macro T2": null, "gcode_macro T3": null, "gcode_macro T4": null, "gcode_macro T5": null, "gcode_macro T6": null, "gcode_macro T7": null, "gcode_macro T8": null, "gcode_macro MMU__EJECT": null, "gcode_macro MMU__HOME": null, "gcode_macro MMU__STATUS": null, "gcode_macro MMU__MOTORS_OFF": null, "gcode_macro MMU__SERVO": null, "gcode_macro MMU__SELECT_TOOL": null, "gcode_macro MMU__SELECT_BYPASS": null, "gcode_macro MMU__LOAD_BYPASS": null, "gcode_macro MMU__RECOVER": null, "gcode_macro MMU__PRELOAD": null, "gcode_macro MMU__CHECK_GATES": null, "gcode_macro MMU_CHANGE_TOOL_STANDALONE": null, "gcode_macro _MMU_FORM_TIP_STANDALONE": null, "gcode_macro _MMU_CUT_TIP": null, "gcode_macro _MMU_PRE_UNLOAD": null, "gcode_macro _MMU_POST_LOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_PRE_UNLOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_POST_LOAD": null, "gcode_macro _MMU_ACTION_CHANGED": null, "gcode_macro _MMU_LOAD_SEQUENCE": null, "gcode_macro _MMU_UNLOAD_SEQUENCE": null, "gcode_macro _PARK": null, "gcode_macro CANCEL_PRINT": null, "gcode_macro PAUSE": null, "gcode_macro RESUME": null, "print_stats": null, "virtual_sdcard": null, "pause_resume": null, "display_status": 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 DISABLE_MOTORS_EXCEPT_Z": null, "gcode_macro TIP_FORMING": null, "gcode_macro TIP_FORMING_AND_EJECT": null, "gcode_macro UNSAFE_LOWER_BED": null, "gcode_macro BLTOUCH_RESET": null, "gcode_macro BLTOUCH_SELF_TEST": null, "gcode_macro BLTOUCH_PIN_DOWN": null, "gcode_macro calibrate_probe": null, "gcode_macro PRIME_LINE": null, "gcode_macro START_PRINT": null, "gcode_macro END_PRINT": null, "gcode_macro TEST_SPEED": null, "z_tilt": null, "bed_mesh": null, "gcode_macro _KAMP_Settings": null, "gcode_macro BED_MESH_CALIBRATE": null, "gcode_macro VORON_PURGE": null, "gcode_macro SMART_PARK": null, "exclude_object": null, "tmc2209 stepper_x": null, "tmc2209 stepper_y": null, "tmc2209 stepper_z": null, "tmc2209 stepper_z1": null, "tmc2209 stepper_z2": null, "heaters": null, "heater_bed": null, "led front_led_bar": null, "probe": null, "fan": null, "heater_fan heatbreak_cooling_fan": null, "neopixel hotend_rgb": null, "idle_timeout": null, "system_stats": null, "manual_probe": null, "toolhead": null, "extruder": null}, "response_template": {"method": "process_status_update"}}}'
Received 1934.948063: b'{"id": 281473168729136, "method": "objects/query", "params": {"objects": {"extruder": ["can_extrude"]}}}'
Received 3996.886781: b'{"id": 281473168728464, "method": "info", "params": {}}'
Received 3996.887969: b'{"id": 281473168799872, "method": "objects/list", "params": {}}'
Received 3996.887969: b'{"id": 281473168798864, "method": "gcode/help", "params": {}}'
Received 3996.919318: b'{"id": 281473168731104, "method": "objects/subscribe", "params": {"objects": {"webhooks": null, "configfile": null, "mcu": null, "mcu mmu": null, "mcu HermitCrab": null, "stepper_enable": null, "tmc2209 manual_extruder_stepper extruder": null, "motion_report": null, "query_endstops": null, "manual_extruder_stepper extruder": null, "tmc2209 manual_extruder_stepper gear_stepper": null, "manual_extruder_stepper gear_stepper": null, "tmc2209 manual_mh_stepper selector_stepper": null, "mmu_servo mmu_servo": null, "mmu_encoder mmu_encoder": null, "gcode_move": null, "mmu": null, "save_variables": null, "gcode_macro T0": null, "gcode_macro T1": null, "gcode_macro T2": null, "gcode_macro T3": null, "gcode_macro T4": null, "gcode_macro T5": null, "gcode_macro T6": null, "gcode_macro T7": null, "gcode_macro T8": null, "gcode_macro MMU__EJECT": null, "gcode_macro MMU__HOME": null, "gcode_macro MMU__STATUS": null, "gcode_macro MMU__MOTORS_OFF": null, "gcode_macro MMU__SERVO": null, "gcode_macro MMU__SELECT_TOOL": null, "gcode_macro MMU__SELECT_BYPASS": null, "gcode_macro MMU__LOAD_BYPASS": null, "gcode_macro MMU__RECOVER": null, "gcode_macro MMU__PRELOAD": null, "gcode_macro MMU__CHECK_GATES": null, "gcode_macro MMU_CHANGE_TOOL_STANDALONE": null, "gcode_macro _MMU_FORM_TIP_STANDALONE": null, "gcode_macro _MMU_CUT_TIP": null, "gcode_macro _MMU_PRE_UNLOAD": null, "gcode_macro _MMU_POST_LOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_PRE_UNLOAD": null, "gcode_macro _MMU_ENDLESS_SPOOL_POST_LOAD": null, "gcode_macro _MMU_ACTION_CHANGED": null, "gcode_macro _MMU_LOAD_SEQUENCE": null, "gcode_macro _MMU_UNLOAD_SEQUENCE": null, "gcode_macro _PARK": null, "gcode_macro CANCEL_PRINT": null, "gcode_macro PAUSE": null, "gcode_macro RESUME": null, "print_stats": null, "virtual_sdcard": null, "pause_resume": null, "display_status": 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 DISABLE_MOTORS_EXCEPT_Z": null, "gcode_macro TIP_FORMING": null, "gcode_macro TIP_FORMING_AND_EJECT": null, "gcode_macro UNSAFE_LOWER_BED": null, "gcode_macro BLTOUCH_RESET": null, "gcode_macro BLTOUCH_SELF_TEST": null, "gcode_macro BLTOUCH_PIN_DOWN": null, "gcode_macro calibrate_probe": null, "gcode_macro PRIME_LINE": null, "gcode_macro START_PRINT": null, "gcode_macro END_PRINT": null, "gcode_macro TEST_SPEED": null, "z_tilt": null, "bed_mesh": null, "gcode_macro _KAMP_Settings": null, "gcode_macro BED_MESH_CALIBRATE": null, "gcode_macro VORON_PURGE": null, "gcode_macro SMART_PARK": null, "exclude_object": null, "tmc2209 stepper_x": null, "tmc2209 stepper_y": null, "tmc2209 stepper_z": null, "tmc2209 stepper_z1": null, "tmc2209 stepper_z2": null, "heaters": null, "heater_bed": null, "led front_led_bar": null, "probe": null, "fan": null, "heater_fan heatbreak_cooling_fan": null, "neopixel hotend_rgb": null, "idle_timeout": null, "system_stats": null, "manual_probe": null, "toolhead": null, "extruder": null}, "response_template": {"method": "process_status_update"}}}'
Received 3997.315222: b'{"id": 281473168728992, "method": "objects/query", "params": {"objects": {"extruder": ["can_extrude"]}}}'
gcode state: absolute_coord=True absolute_extrude=False base_position=[0.0, 0.0, 0.0, 4281.844476084364] last_position=[221.684, 87.562, 2.0, 4379.690996084342] homing_position=[0.0, 0.0, 0.0, 0.0] speed_factor=0.016666666666666666 extrude_factor=1.0 speed=40.0
Virtual sdcard (1549818): '73\nG1 X208.817 Y100.934 E0.01836\nG1 X207.942 Y101.809 E0.03673\nG1 X208.102 Y102.406 E0.01836\nG1 X207.227 Y103.281 E0.03673\nG1 X206.63 Y103.121 E0.01836\nG1 X206.36 Y103.391 E0.01134\nG1 X206.107 Y103.138 E0.0106\nG1 X206.377 Y102.868 E0.01134\nG1 X206.217 Y102.271 E0.01836\nG1 X207.092 Y101.396 E0.03673\nG1 X207.689 Y101.556 E0.01836\nG1 X208.564 Y100.682 E0.03673\nG1 X208.404 Y100.084 E0.01836\nG1 X209.279 Y99.21 E0.03673\nG1 X209.876 Y99.37 E0.01836\nG1 X210.751 Y98.495 E0.03673\nG1 X210.591 Y97.898 E0.01836\nG1 X211.465 Y97.023 E0.03673\nG1 X212.063 Y97.183 E0.01836\nG1 X212.937 Y96.308 E0.03673\nG1 X212.777 Y95.711 E0.01836\nG1 X213.652 Y94.836 E0.03673\nG1 X214.249 Y94.996 E0.01836\nG1 X215.124 Y94.122 E0.03673\nG1 X214.964 Y93.524 E0.01836\nG1 X215.839 Y92.65 E0.03673\nG1 X216.436 Y92.81 E0.01836\nG1 X217.311 Y91.935 E0.03673\nG1 X217.151 Y91.338 E0.01836\nG1 X218.025 Y90.463 E0.03673\nG1 X218.623 Y90.623 E0.01836\nG1 X219.497 Y89.748 E0.03673\nG1 X219.337 Y89.151 E0.01836\nG1 X220.212 Y88.276 E0.03673\nG1 X220.809 Y88.436 E0.01836\n'
Upcoming (1550842): 'G1 X221.684 Y87.562 E0.03673\nG1 X221.524 Y86.964 E0.01836\nG1 X221.606 Y86.882 E0.00346\nG1 X220.344 Y86.882 E0.03748\nG1 X219.799 '
Reactor garbage collection: (1935.330415297, 527.309117167, 0.0)
MCU 'mmu' shutdown: Command request
clocksync state: mcu_freq=48000000 last_clock=222532297710 clock_est=(4913.166 221020063391 48014003.338) min_half_rtt=0.000103 min_rtt_time=4706.285 time_avg=4913.166(849.721) clock_avg=221020063391.280(40798527526.294) pred_variance=1353022.845 clock_adj=(0.873 48013197.500)
Dumping serial stats: bytes_write=306682 bytes_read=1371207 bytes_retransmit=9 bytes_invalid=0 send_seq=23313 receive_seq=23313 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 4921.818461 4921.818461 14: seq: 1d, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 1 4921.926776 4921.926776 6: seq: 1e, get_clock
Sent 2 4922.567301 4922.567301 14: seq: 1f, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 3 4922.572587 4922.572587 14: seq: 10, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 4 4922.813656 4922.813656 14: seq: 11, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 5 4922.818927 4922.818927 14: seq: 12, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 6 4922.910877 4922.910877 6: seq: 13, get_clock
Sent 7 4923.568096 4923.568096 14: seq: 14, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 8 4923.573575 4923.573575 14: seq: 15, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 9 4923.814190 4923.814190 14: seq: 16, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 10 4923.819583 4923.819583 14: seq: 17, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 11 4923.895194 4923.895194 6: seq: 18, get_clock
Sent 12 4924.569303 4924.569303 14: seq: 19, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 13 4924.575594 4924.575594 14: seq: 1a, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 14 4924.815280 4924.815280 14: seq: 1b, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 15 4924.821850 4924.821850 14: seq: 1c, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 16 4924.879432 4924.879432 6: seq: 1d, get_clock
Sent 17 4925.569760 4925.569760 14: seq: 1e, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 18 4925.575938 4925.575938 14: seq: 1f, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 19 4925.815925 4925.815925 14: seq: 10, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 20 4925.822172 4925.822172 14: seq: 11, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 21 4925.866973 4925.866973 6: seq: 12, get_clock
Sent 22 4926.570131 4926.570131 14: seq: 13, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 23 4926.575499 4926.575499 14: seq: 14, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 24 4926.815827 4926.815827 14: seq: 15, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 25 4926.821144 4926.821144 14: seq: 16, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 26 4926.851462 4926.851462 6: seq: 17, get_clock
Sent 27 4927.571124 4927.571124 14: seq: 18, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 28 4927.576435 4927.576435 14: seq: 19, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 29 4927.816569 4927.816569 14: seq: 1a, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 30 4927.821968 4927.821968 14: seq: 1b, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 31 4927.836323 4927.836323 6: seq: 1c, get_clock
Sent 32 4928.572021 4928.572021 14: seq: 1d, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 33 4928.577390 4928.577390 14: seq: 1e, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 34 4928.817458 4928.817458 14: seq: 1f, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 35 4928.820654 4928.820654 6: seq: 10, get_clock
Sent 36 4928.822745 4928.822745 14: seq: 11, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 37 4929.572833 4929.572833 14: seq: 12, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 38 4929.578173 4929.578173 14: seq: 13, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 39 4929.805170 4929.805170 6: seq: 14, get_clock
Sent 40 4929.818148 4929.818148 14: seq: 15, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 41 4929.823542 4929.823542 14: seq: 16, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 42 4930.573522 4930.573522 14: seq: 17, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 43 4930.578887 4930.578887 14: seq: 18, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 44 4930.792016 4930.792016 6: seq: 19, get_clock
Sent 45 4930.818649 4930.818649 14: seq: 1a, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 46 4930.823927 4930.823927 14: seq: 1b, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 47 4931.574103 4931.574103 14: seq: 1c, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 48 4931.579429 4931.579429 14: seq: 1d, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 49 4931.776583 4931.776583 6: seq: 1e, get_clock
Sent 50 4931.818724 4931.818724 14: seq: 1f, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 51 4931.824068 4931.824068 14: seq: 10, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 52 4932.574798 4932.574798 14: seq: 11, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 53 4932.580085 4932.580085 14: seq: 12, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 54 4932.761182 4932.761182 6: seq: 13, get_clock
Sent 55 4932.819429 4932.819429 14: seq: 14, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 56 4932.824914 4932.824914 14: seq: 15, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 57 4933.575173 4933.575173 14: seq: 16, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 58 4933.580499 4933.580499 14: seq: 17, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 59 4933.745953 4933.745953 6: seq: 18, get_clock
Sent 60 4933.820092 4933.820092 14: seq: 19, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 61 4933.825446 4933.825446 14: seq: 1a, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 62 4934.576007 4934.576007 14: seq: 1b, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 63 4934.582792 4934.582792 14: seq: 1c, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 64 4934.730157 4934.730157 6: seq: 1d, get_clock
Sent 65 4934.820788 4934.820788 14: seq: 1e, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 66 4934.827031 4934.827031 14: seq: 1f, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 67 4935.577049 4935.577049 14: seq: 10, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 68 4935.583183 4935.583183 14: seq: 11, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 69 4935.714231 4935.714231 6: seq: 12, get_clock
Sent 70 4935.820559 4935.820559 14: seq: 13, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 71 4935.827597 4935.827597 14: seq: 14, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 72 4936.581270 4936.581270 14: seq: 15, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 73 4936.587837 4936.587837 14: seq: 16, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 74 4936.698663 4936.698663 6: seq: 17, get_clock
Sent 75 4936.820951 4936.820951 14: seq: 18, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 76 4936.827128 4936.827128 14: seq: 19, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 77 4937.581120 4937.581120 14: seq: 1a, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 78 4937.586458 4937.586458 14: seq: 1b, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 79 4937.683090 4937.683090 6: seq: 1c, get_clock
Sent 80 4937.821029 4937.821029 14: seq: 1d, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 81 4937.826301 4937.826301 14: seq: 1e, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 82 4938.581570 4938.581570 14: seq: 1f, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 83 4938.586857 4938.586857 14: seq: 10, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 84 4938.667632 4938.667632 6: seq: 11, get_clock
Sent 85 4938.821362 4938.821362 14: seq: 12, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 86 4938.826718 4938.826718 14: seq: 13, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 87 4939.582484 4939.582484 14: seq: 14, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 88 4939.587921 4939.587921 14: seq: 15, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 89 4939.652505 4939.652505 6: seq: 16, get_clock
Sent 90 4939.821955 4939.821955 14: seq: 17, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 91 4939.827259 4939.827259 14: seq: 18, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 92 4940.583221 4940.583221 14: seq: 19, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 93 4940.588573 4940.588573 14: seq: 1a, tmcuart_send oid=0 write=b'\xea\x03( \xc3' read=10
Sent 94 4940.637136 4940.637136 6: seq: 1b, get_clock
Sent 95 4940.822088 4940.822088 14: seq: 1c, tmcuart_send oid=0 write=b'\xea\x0b\xe8\xad\xba' read=10
Sent 96 4940.827537 4940.827537 14: seq: 1d, tmcuart_send oid=0 write=b'\xea\x0b( \x98' read=10
Sent 97 4944.661806 4944.661806 6: seq: 1e, get_clock
Sent 98 4944.741549 4944.741549 14: seq: 1f, tmcuart_send oid=0 write=b'\xea\x03\xe8\xad\xe1' read=10
Sent 99 4944.826470 4944.826470 6: seq: 10, emergency_stop
Dumping receive queue 100 messages
Receive: 0 4937.283932 4936.827128 19: seq: 1a, counter_state oid=5 next_clock=3134756252 count=8217 count_clock=3121508252
Receive: 1 4937.384934 4936.827128 19: seq: 1a, counter_state oid=5 next_clock=3139604252 count=8217 count_clock=3121508252
Receive: 2 4937.485922 4936.827128 19: seq: 1a, counter_state oid=5 next_clock=3144452252 count=8217 count_clock=3121508252
Receive: 3 4937.585272 4937.581120 18: seq: 1b, tmcuart_response oid=0 read=b'\n\xfa\xef-\xc0\x04\x02\x08 \xab'
Receive: 4 4937.586771 4937.586458 19: seq: 1c, counter_state oid=5 next_clock=3149300252 count=8217 count_clock=3121508252
Receive: 5 4937.590525 4937.586458 18: seq: 1c, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 6 4937.683398 4937.683090 11: seq: 1d, clock clock=3153888974
Receive: 7 4937.687794 4937.683090 19: seq: 1d, counter_state oid=5 next_clock=3154148252 count=8217 count_clock=3121508252
Receive: 8 4937.788828 4937.683090 19: seq: 1d, counter_state oid=5 next_clock=3158996252 count=8217 count_clock=3121508252
Receive: 9 4937.825129 4937.821029 18: seq: 1e, tmcuart_response oid=0 read=b'\n\xfa\xef-\xe0\x0c\x02\x08\xa0\x9c'
Receive: 10 4937.830382 4937.826301 18: seq: 1f, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 11 4937.889779 4937.826301 19: seq: 1f, counter_state oid=5 next_clock=3163844252 count=8217 count_clock=3121508252
Receive: 12 4937.990872 4937.826301 19: seq: 1f, counter_state oid=5 next_clock=3168692252 count=8217 count_clock=3121508252
Receive: 13 4938.091643 4937.826301 19: seq: 1f, counter_state oid=5 next_clock=3173540252 count=8217 count_clock=3121508252
Receive: 14 4938.192719 4937.826301 19: seq: 1f, counter_state oid=5 next_clock=3178388252 count=8218 count_clock=3176516252
Receive: 15 4938.293656 4937.826301 19: seq: 1f, counter_state oid=5 next_clock=3183236252 count=8218 count_clock=3176516252
Receive: 16 4938.394666 4937.826301 19: seq: 1f, counter_state oid=5 next_clock=3188084252 count=8218 count_clock=3176516252
Receive: 17 4938.495515 4937.826301 19: seq: 1f, counter_state oid=5 next_clock=3192932252 count=8218 count_clock=3176516252
Receive: 18 4938.585647 4938.581570 18: seq: 10, tmcuart_response oid=0 read=b'\n\xfa\xef-\xc0\x04\x02\x08 \xab'
Receive: 19 4938.591015 4938.586857 18: seq: 11, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 20 4938.596525 4938.586857 19: seq: 11, counter_state oid=5 next_clock=3197780252 count=8218 count_clock=3176516252
Receive: 21 4938.667959 4938.667632 11: seq: 12, clock clock=3201161157
Receive: 22 4938.697513 4938.667632 19: seq: 12, counter_state oid=5 next_clock=3202628252 count=8218 count_clock=3176516252
Receive: 23 4938.798532 4938.667632 19: seq: 12, counter_state oid=5 next_clock=3207476252 count=8218 count_clock=3176516252
Receive: 24 4938.825509 4938.821362 18: seq: 13, tmcuart_response oid=0 read=b'\n\xfa\xef-\xe0\x0c\x02\x08\xa0\x9c'
Receive: 25 4938.830757 4938.826718 18: seq: 14, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 26 4938.899521 4938.826718 19: seq: 14, counter_state oid=5 next_clock=3212324252 count=8219 count_clock=3208100252
Receive: 27 4939.000408 4938.826718 19: seq: 14, counter_state oid=5 next_clock=3217172252 count=8219 count_clock=3208100252
Receive: 28 4939.101419 4938.826718 19: seq: 14, counter_state oid=5 next_clock=3222020252 count=8219 count_clock=3208100252
Receive: 29 4939.202416 4938.826718 19: seq: 14, counter_state oid=5 next_clock=3226868252 count=8219 count_clock=3208100252
Receive: 30 4939.303269 4938.826718 19: seq: 14, counter_state oid=5 next_clock=3231716252 count=8219 count_clock=3208100252
Receive: 31 4939.404386 4938.826718 19: seq: 14, counter_state oid=5 next_clock=3236564252 count=8219 count_clock=3208100252
Receive: 32 4939.505293 4938.826718 19: seq: 14, counter_state oid=5 next_clock=3241412252 count=8219 count_clock=3208100252
Receive: 33 4939.582929 4939.582484 15: seq: 15, stats count=330 sum=299735 sumsq=1651782
Receive: 34 4939.586663 4939.582484 18: seq: 15, tmcuart_response oid=0 read=b'\n\xfa\xef-\xc0\x04\x02\x08 \xab'
Receive: 35 4939.592042 4939.587921 18: seq: 16, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 36 4939.606288 4939.587921 19: seq: 16, counter_state oid=5 next_clock=3246260252 count=8219 count_clock=3208100252
Receive: 37 4939.652870 4939.652505 11: seq: 17, clock clock=3248448903
Receive: 38 4939.707286 4939.652505 19: seq: 17, counter_state oid=5 next_clock=3251108252 count=8220 count_clock=3247892252
Receive: 39 4939.808174 4939.652505 19: seq: 17, counter_state oid=5 next_clock=3255956252 count=8220 count_clock=3247892252
Receive: 40 4939.826054 4939.821955 18: seq: 18, tmcuart_response oid=0 read=b'\n\xfa\xef-\xe0\x0c\x02\x08\xa0\x9c'
Receive: 41 4939.831404 4939.827259 18: seq: 19, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 42 4939.909150 4939.827259 19: seq: 19, counter_state oid=5 next_clock=3260804252 count=8220 count_clock=3247892252
Receive: 43 4940.010186 4939.827259 19: seq: 19, counter_state oid=5 next_clock=3265652252 count=8220 count_clock=3247892252
Receive: 44 4940.111179 4939.827259 19: seq: 19, counter_state oid=5 next_clock=3270500252 count=8220 count_clock=3247892252
Receive: 45 4940.212080 4939.827259 19: seq: 19, counter_state oid=5 next_clock=3275348252 count=8220 count_clock=3247892252
Receive: 46 4940.313046 4939.827259 19: seq: 19, counter_state oid=5 next_clock=3280196252 count=8220 count_clock=3247892252
Receive: 47 4940.414060 4939.827259 19: seq: 19, counter_state oid=5 next_clock=3285044252 count=8220 count_clock=3247892252
Receive: 48 4940.515054 4939.827259 19: seq: 19, counter_state oid=5 next_clock=3289892252 count=8220 count_clock=3247892252
Receive: 49 4940.587395 4940.583221 18: seq: 1a, tmcuart_response oid=0 read=b'\n\xfa\xef-\xc0\x04\x02\x08 \xab'
Receive: 50 4940.592856 4940.588573 18: seq: 1b, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 51 4940.615913 4940.588573 19: seq: 1b, counter_state oid=5 next_clock=3294740252 count=8221 count_clock=3290708252
Receive: 52 4940.637519 4940.637136 11: seq: 1c, clock clock=3295725203
Receive: 53 4940.716959 4940.637136 19: seq: 1c, counter_state oid=5 next_clock=3299588252 count=8221 count_clock=3290708252
Receive: 54 4940.817912 4940.637136 19: seq: 1c, counter_state oid=5 next_clock=3304436252 count=8221 count_clock=3290708252
Receive: 55 4940.826284 4940.822088 18: seq: 1d, tmcuart_response oid=0 read=b'\n\xfa\xef-\xe0\x0c\x02\x08\xa0\x9c'
Receive: 56 4940.831640 4940.827537 18: seq: 1e, tmcuart_response oid=0 read=b'\n\xfa/ \x80\x00\x02\x08\xa0\x89'
Receive: 57 4940.918908 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3309284252 count=8221 count_clock=3290708252
Receive: 58 4941.019800 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3314132252 count=8221 count_clock=3290708252
Receive: 59 4941.120844 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3318980252 count=8221 count_clock=3290708252
Receive: 60 4941.221776 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3323828252 count=8221 count_clock=3290708252
Receive: 61 4941.322787 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3328676252 count=8222 count_clock=3328052252
Receive: 62 4941.423664 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3333524252 count=8222 count_clock=3328052252
Receive: 63 4941.524676 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3338372252 count=8222 count_clock=3328052252
Receive: 64 4941.625647 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3343220252 count=8222 count_clock=3328052252
Receive: 65 4941.726679 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3348068252 count=8222 count_clock=3328052252
Receive: 66 4941.827681 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3352916252 count=8222 count_clock=3328052252
Receive: 67 4941.928554 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3357764252 count=8222 count_clock=3328052252
Receive: 68 4942.029562 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3362612252 count=8222 count_clock=3328052252
Receive: 69 4942.130532 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3367460252 count=8223 count_clock=3365588252
Receive: 70 4942.231557 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3372308252 count=8223 count_clock=3365588252
Receive: 71 4942.332550 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3377156252 count=8223 count_clock=3365588252
Receive: 72 4942.433425 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3382004252 count=8223 count_clock=3365588252
Receive: 73 4942.534423 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3386852252 count=8223 count_clock=3365588252
Receive: 74 4942.635426 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3391700252 count=8223 count_clock=3365588252
Receive: 75 4942.736305 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3396548252 count=8223 count_clock=3365588252
Receive: 76 4942.837299 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3401396252 count=8223 count_clock=3365588252
Receive: 77 4942.938292 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3406244252 count=8223 count_clock=3365588252
Receive: 78 4943.039299 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3411092252 count=8223 count_clock=3365588252
Receive: 79 4943.140300 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3415940252 count=8223 count_clock=3365588252
Receive: 80 4943.241191 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3420788252 count=8223 count_clock=3365588252
Receive: 81 4943.342161 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3425636252 count=8223 count_clock=3365588252
Receive: 82 4943.443170 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3430484252 count=8223 count_clock=3365588252
Receive: 83 4943.544175 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3435332252 count=8223 count_clock=3365588252
Receive: 84 4943.645060 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3440180252 count=8223 count_clock=3365588252
Receive: 85 4943.746023 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3445028252 count=8223 count_clock=3365588252
Receive: 86 4943.847038 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3449876252 count=8223 count_clock=3365588252
Receive: 87 4943.948058 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3454724252 count=8223 count_clock=3365588252
Receive: 88 4944.048998 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3459572252 count=8223 count_clock=3365588252
Receive: 89 4944.149921 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3464420252 count=8223 count_clock=3365588252
Receive: 90 4944.250927 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3469268252 count=8223 count_clock=3365588252
Receive: 91 4944.351925 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3474116252 count=8223 count_clock=3365588252
Receive: 92 4944.452845 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3478964252 count=8223 count_clock=3365588252
Receive: 93 4944.553788 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3483812252 count=8223 count_clock=3365588252
Receive: 94 4944.654796 4940.827537 19: seq: 1e, counter_state oid=5 next_clock=3488660252 count=8223 count_clock=3365588252
Receive: 95 4944.654806 4940.827537 14: seq: 1e, stats count=254 sum=224041 sumsq=1206566
Receive: 96 4944.662239 4944.661806 11: seq: 1f, clock clock=3488965614
Receive: 97 4944.745725 4944.741549 18: seq: 10, tmcuart_response oid=0 read=b'\n\xfa\xef-\xc0\x04\x02\x08 \xab'
Receive: 98 4944.755832 4944.741549 19: seq: 10, counter_state oid=5 next_clock=3493508252 count=8223 count_clock=3365588252
Receive: 99 4944.827027 4944.826470 12: seq: 11, shutdown clock=3496872198 static_string_id=Command request
Requested toolhead position at shutdown time 4635.695637: (209.666, 101.347, 2.026689919988599)
Stats 4945.7: gcodein=0  mcu: mcu_awake=0.131 mcu_task_avg=0.000021 mcu_task_stddev=0.000022 bytes_write=7010531 bytes_read=2046667 bytes_retransmit=0 bytes_invalid=0 send_seq=157859 receive_seq=157859 retransmit_seq=0 srtt=0.002 rttvar=0.002 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=64001008 mmu: mcu_awake=0.005 mcu_task_avg=0.000018 mcu_task_stddev=0.000014 bytes_write=306716 bytes_read=1371247 bytes_retransmit=9 bytes_invalid=0 send_seq=23316 receive_seq=23316 retransmit_seq=2 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=48014003 adj=48013197 HermitCrab: mcu_awake=0.022 mcu_task_avg=0.000022 mcu_task_stddev=0.000031 bytes_write=2257062 bytes_read=851557 bytes_retransmit=0 bytes_invalid=0 send_seq=57817 receive_seq=57817 retransmit_seq=0 srtt=0.007 rttvar=0.004 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=47999631 adj=47998670 sd_pos=1550842 heater_bed: target=80 temp=80.0 pwm=0.319 sysload=1.71 cputime=490.993 memavail=754048 print_time=4638.885 buffer_time=2.196 print_stall=0 extruder: target=215 temp=214.8 pwm=0.417
Exiting SD card print (position 1550871)```

[lesaux]

It seems this was caused by the rpi being overloaded. The error log was a bit confusing.