Internal error in stepcompress after upgrading to v0.11.0-303 on CR-10S with Raspberry Pi 4 and Ubuntu ARM

General Discussion
1

Basic Information:

Printer Model: CR-10S
MCU / Printerboard: Raspberry Pi 4 running Ubuntu ARM
Stats 672.zip (13.2 KB)

Received 654.001974: b'{"id": 281473084731120, "method": "gcode/script", "params": {"script": "SET_GCODE_VARIABLE MACRO=TIMELAPSE_TAKE_FRAME VARIABLE=takingframe VALUE=False"}}'
Virtual sdcard (56074): ' X262.695 Y48.163 E19.41328\nG1 X263.045 Y48.309 E19.44776\nG1 X263.201 Y48.408 E19.46456\nG1 X263.875 Y49.073 E19.55066\nG1 X264.01 Y49.276 E19.57283\nG1 X264.152 Y49.619 E19.60659\nG1 X264.2 Y49.86 E19.62893\nG1 X264.2 Y255.866 E38.36097\nG1 X264.152 Y256.107 E38.38331\nG1 X264.01 Y256.45 E38.41707\nG1 X263.874 Y256.654 E38.43936\nG1 X263.242 Y257.285 E38.52057\nG1 X263.09 Y257.392 E38.53747\nG1 X262.696 Y257.561 E38.57645\nG1 X262.458 Y257.609 E38.59853\nG1 X56.451 Y257.609 E57.33066\nG1 X56.211 Y257.561 E57.35292\nG1 X55.867 Y257.419 E57.38676\nG1 X55.664 Y257.282 E57.40903\nG1 X55.032 Y256.651 E57.49024\nG1 X54.925 Y256.499 E57.50714\nG1 X54.756 Y256.105 E57.54612\nG1 X54.709 Y255.866 E57.56827\nG1 X54.709 Y49.86 E76.30031\nG1 X54.756 Y49.619 E76.32264\nG1 X54.899 Y49.276 E76.35643\nG1 X55.002 Y49.122 E76.37328\nG1 X55.498 Y49.492 F13800\nG1 F1800\nG1 X56.084 Y48.907 E76.44857\nG1 X56.482 Y48.742 E76.48775\nG1 X262.427 Y48.742 E95.21424\nM73 P1 R50\nG1 X262.825 Y48.907 E95.25342\nG1 X263.41 Y49.492 E95.32865\nG1 X263.575 Y49.89 E95.36783\n'
Upcoming (57098): 'G1 X263.575 Y255.835 E114.09432\nM73 P1 R49\nG1 X263.41 Y256.233 E114.1335\nG1 X262.825 Y256.819 E114.20879\nG1 X262.427 Y256.984 E1'
gcode state: absolute_coord=True absolute_extrude=True base_position=[0.0, 0.0, 0.0, 39.5] last_position=[263.575, 255.835, 0.35, 153.59431999999998] homing_position=[0.0, 0.0, 0.0, 0.0] speed_factor=0.016666666666666666 extrude_factor=1.0 speed=30.0
Reactor garbage collection: (497.918436332, 0.0, 0.0)
Internal error on command:"G1"
virtual_sdcard dispatch
Traceback (most recent call last):
  File "/home/noorbutt/klipper/klippy/extras/virtual_sdcard.py", line 264, in work_handler
    self.gcode.run_script(line)
  File "/home/noorbutt/klipper/klippy/gcode.py", line 216, in run_script
    self._process_commands(script.split('\n'), need_ack=False)
  File "/home/noorbutt/klipper/klippy/gcode.py", line 198, in _process_commands
    handler(gcmd)
  File "/home/noorbutt/klipper/klippy/extras/gcode_move.py", line 143, in cmd_G1
    self.move_with_transform(self.last_position, self.speed)
  File "/home/noorbutt/klipper/klippy/extras/bed_mesh.py", line 209, in move
    self.toolhead.move([x, y, z + self.fade_target, e], speed)
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 427, in move
    self.move_queue.add_move(move)
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 187, in add_move
    self.flush(lazy=True)
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 176, in flush
    self.toolhead._process_moves(queue[:flush_count])
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 335, in _process_moves
    self._update_move_time(next_move_time)
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 288, in _update_move_time
    sg(sg_flush_time)
  File "/home/noorbutt/klipper/klippy/stepper.py", line 228, in generate_steps
    raise error("Internal error in stepcompress")
stepper.error: Internal error in stepcompress
MCU 'rpi' shutdown: Command request
clocksync state: mcu_freq=50000000 last_clock=27159963673 clock_est=(651.651 25732108859 49999097.458) min_half_rtt=0.000086 min_rtt_time=416.317 time_avg=651.651(843.637) clock_avg=25732108859.964(42181087806.938) pred_variance=3747607.113 clock_adj=(-7.290 49999586.045)
Dumping serial stats: bytes_write=4079 bytes_read=14465 bytes_retransmit=0 bytes_invalid=0 send_seq=654 receive_seq=654 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0
Dumping send queue 100 messages
Sent 0 583.703440 583.703440 6: seq: 1a, get_clock
Sent 1 584.687940 584.687940 6: seq: 1b, get_clock


Receive: 99 680.781657 680.770314 11: seq: 16, shutdown clock=4288641124 static_string_id=Command request
Exiting SD card print (position 57098)
Dumping stepper 'stepper_y' (mcu) 2 queue_step:
queue_step 0: t=8574982878 p=-13822 i=6667 c=-1202 a=0
queue_step 1: t=8582996611 p=-15024 i=6666 c=-601 a=0
Dumping stepper 'extruder' (mcu) 3 queue_step:
queue_step 0: t=8575321787 p=43710 i=14664 c=469 a=0
queue_step 1: t=8582199202 p=44179 i=14663 c=76 a=0
queue_step 2: t=8583313590 p=44255 i=14663 c=546 a=0
Dumping trapq 'toolhead' 1 moves:
move 0: pt=531.482380 mt=6.866867 sv=30.000000 a=0.000000 sp=(54.709000,255.866000,0.350000) ar=(0.000000,-1.000000,0.000000)
Dumping trapq 'extruder' 1 moves:
move 0: pt=531.482380 mt=6.866867 sv=2.727888 a=0.000000 sp=(97.068270,0.000000,0.000000) ar=(1.000000,1.000000,0.000000)
Requested toolhead position at shutdown time 536.475526: (54.709, 106.07160888530444, 0.35)
Stats 681.0: gcodein=0  mcu: mcu_awake=0.022 mcu_task_avg=0.000151 mcu_task_stddev=0.000144 bytes_write=50162 bytes_read=78070 bytes_retransmit=9 bytes_invalid=8 send_seq=3064 receive_seq=3064 retransmit_seq=2 srtt=0.009 rttvar=0.003 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=15999816 rpi: mcu_awake=0.000 mcu_task_avg=0.000008 mcu_task_stddev=0.000013 bytes_write=4079 bytes_read=14465 bytes_retransmit=0 bytes_invalid=0 send_seq=654 receive_seq=654 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=49999097 adj=49999586  heater_bed: target=55 temp=55.6 pwm=0.176 sysload=0.39 cputime=16.670 memavail=977616 print_time=538.952 buffer_time=2.259 print_stall=0 extruder: target=235 temp=235.6 pwm=0.411
Stats 682.0: gcodein=0  mcu: mcu_awake=0.022 mcu_task_avg=0.000151 mcu_task_stddev=0.000144 bytes_write=50242 bytes_read=78230 bytes_retransmit=9 bytes_invalid=8 send_seq=3070 receive_seq=3070 retransmit_seq=2 srtt=0.008 rttvar=0.003 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=15999816 rpi: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000020 bytes_write=4085 bytes_read=14494 bytes_retransmit=0 bytes_invalid=0 send_seq=655 receive_seq=655 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=49999101 adj=49999584  heater_bed: target=55 temp=55.6 pwm=0.232 sysload=0.39 cputime=16.696 memavail=977088 print_time=538.952 buffer_time=1.258 print_stall=0 extruder: target=235 temp=235.6 pwm=0.359
b'stepcompress o=3 i=0 c=2 a=0: Invalid sequence'
Exception in flush_handler
Traceback (most recent call last):
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 400, in _flush_handler
    self.flush_step_generation()
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 354, in flush_step_generation
    self._update_move_time(max(self.print_time, self.force_flush_time))
  File "/home/noorbutt/klipper/klippy/toolhead.py", line 294, in _update_move_time
    m.flush_moves(mcu_flush_time)
  File "/home/noorbutt/klipper/klippy/mcu.py", line 969, in flush_moves
    raise error("Internal error in MCU '%s' stepcompress"
mcu.error: Internal error in MCU 'mcu' stepcompress
Stats 683.0: gcodein=0  mcu: mcu_awake=0.013 mcu_task_avg=0.000108 mcu_task_stddev=0.000079 bytes_write=50312 bytes_read=78411 bytes_retransmit=9 bytes_invalid=8 send_seq=3076 receive_seq=3076 retransmit_seq=2 srtt=0.008 rttvar=0.004 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=15999815 rpi: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000020 bytes_write=4091 bytes_read=14510 bytes_retransmit=0 bytes_invalid=0 send_seq=656 receive_seq=656 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=49999101 adj=49999607  heater_bed: target=55 temp=55.6 pwm=0.361 sysload=0.39 cputime=16.726 memavail=981180 print_time=538.952 buffer_time=0.258 print_stall=0 extruder: target=235 temp=235.6 pwm=0.359
Stats 684.0: gcodein=0  mcu: mcu_awake=0.013 mcu_task_avg=0.000108 mcu_task_stddev=0.000079 bytes_write=50347 bytes_read=78548 bytes_retransmit=9 bytes_invalid=8 send_seq=3079 receive_seq=3079 retransmit_seq=2 srtt=0.008 rttvar=0.005 rto=0.027 ready_bytes=0 upcoming_bytes=0 freq=15999814 rpi: mcu_awake=0.001 mcu_task_avg=0.000010 mcu_task_stddev=0.000020 bytes_write=4097 bytes_read=14526 bytes_retransmit=0 bytes_invalid=0 send_seq=657 receive_seq=657 retransmit_seq=0 srtt=0.000 rttvar=0.000 rto=0.025 ready_bytes=0 upcoming_bytes=0 freq=49999102 adj=49999616  heater_bed: target=55 temp=55.6 pwm=0.281 sysload=0.39 cputime=16.750 memavail=980160 print_time=538.952 buffer_time=0.000 print_stall=0 extruder: target=235 temp=235.6 pwm=0.359
Stats 685.0: gcodein=0  mcu: mcu_awake=0.013 mcu

[Stats 672.zip|attachment](upload://irRPbmrk5EyscZIftckQd29OzAg.zip) (13.2 KB)

Describe your issue:

After upgrading Klipper to version v0.11.0-303 , my CR-10S 3D printer started experiencing an internal error related to stepcompress . The specific error message encountered is:

This issue renders my printer unusable when printing during first layer. I have tried the following troubleshooting steps, but none resolved the issue:

  • Updating the MCU firmware
  • Micro-controller Architecture (Atmega AVR) Processor model (atmega2560)
  • Running sudo apt update and sudo apt upgrade
  • Swapping out the USB connection between the Raspberry Pi and the printer
  • Reinstalling Klipper

Workaround

Was able to print successfully after downgrading Klipper to version v0.11.0-300.

  • Sharing this to help our community who are running a similar setup and so the main contributors are aware of future releases.
2

This is hardly a solution.
It would be much better if you had followed the instructions given when opening this thread and post proper information so that the issue can be diagnosed.

1 Like
3

Cloud be caused by Toolhead: Use dict for step generation flush times. by viesturz · Pull Request #6303 · Klipper3d/klipper · GitHub

Just updated and trying to repro. In the meantime, can you try patching,

and see if that fixes it for you?

4

I think if found the issue, if you can verify if toolhead: Fix input shaper crash by viesturz · Pull Request #6372 · Klipper3d/klipper · GitHub fixes it for you?

1 Like
5

This topic was automatically closed 60 days after the last reply. New replies are no longer allowed.