Klipper3d / klipper

Klipper is a 3d-printer firmware
GNU General Public License v3.0
9.3k stars 5.28k forks source link

LV8729 doesn't work #259

Closed luxflow closed 6 years ago

luxflow commented 6 years ago

===== Config file ===== [stepper_a] step_pin = ar54 dir_pin = !ar55 enable_pin = !ar38 step_distance = .0015625 endstop_pin = ^ar2 homing_speed = 30 position_endstop = 291.775742 angle = 209.984729 arm_length = 269.009

[stepper_b] step_pin = ar60 dir_pin = !ar61 enable_pin = !ar56 step_distance = .0015625 endstop_pin = ^ar15 position_endstop = 292.597408 angle = 330.205626

[stepper_c] step_pin = ar46 dir_pin = !ar48 enable_pin = !ar62 step_distance = .0015625 endstop_pin = ^ar19 position_endstop = 290.222678 angle = 90.00000

Read 115004.781415: 'G28\n' gcode state: absolutecoord=True absoluteextrude=True base_position=[0.0, 0.0, 0.0, 0.0] last_position=[0.0, 0.0, 0.0, 0.0] homing_add=[0.0, 0.0, 0.0, 0.0] speed_factor=0.0166666666667 extrude_factor=1.0 speed=25.0 Internal error on command:"G28" Traceback (most recent call last): File "/home/pi/klipper/klippy/gcode.py", line 153, in process_commands handler(params) File "/home/pi/klipper/klippy/gcode.py", line 66, in func = lambda params: origfunc(self.get_extended_params(params)) File "/home/pi/klipper/klippy/gcode.py", line 427, in cmd_G28 homing_state.home_axes(axes) File "/home/pi/klipper/klippy/homing.py", line 109, in home_axes self.toolhead.get_kinematics().home(self) File "/home/pi/klipper/klippy/delta.py", line 103, in home homing_state.home(coord, homepos, endstops, homing_speed) File "/home/pi/klipper/klippy/homing.py", line 99, in home self.homing_move(movepos, endstops, speed) File "/home/pi/klipper/klippy/homing.py", line 66, in homing_move mcu_endstop.home_wait(move_end_print_time) File "/home/pi/klipper/klippy/mcu.py", line 187, in home_wait while self._check_busy(eventtime, home_end_time): File "/home/pi/klipper/klippy/mcu.py", line 214, in _check_busy raise error("MCU is shutdown") error: MCU is shutdown Internal error on command:"G28" Stats 115006.4: gcodein=87 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000054 bytes_write=1442 bytes_read=3934 bytes_retransmit=9 bytes_invalid=6 send_seq=127 receive_seq=127 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15993676 print_time=12.431 buffer_time=0.000 print_stall=0 extruder: target=0 temp=26.4 pwm=0.000 heater_bed: target=0 temp=27.3 pwm=0.000 Stats 115007.4: gcodein=87 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000054 bytes_write=1448 bytes_read=4035 bytes_retransmit=9 bytes_invalid=6 send_seq=128 receive_seq=128 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15993673 print_time=12.431 buffer_time=0.000 print_stall=0 extruder: target=0 temp=26.4 pwm=0.000 heater_bed: target=0 temp=27.1 pwm=0.000 Stats 115008.4: gcodein=87 mcu: mcu_awake=0.010 mcu_task_avg=0.000100 mcu_task_stddev=0.000136 bytes_write=1454 bytes_read=4165 bytes_retransmit=9 bytes_invalid=6 send_seq=129 receive_seq=129 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15993669 print_time=12.431 buffer_time=0.000 print_stall=0 extruder: target=0 temp=26.4 pwm=0.000 heater_bed: target=0 temp=27.2 pwm=0.000 Stats 115009.4: gcodein=87 mcu: mcu_awake=0.010 mcu_task_avg=0.000100 mcu_task_stddev=0.000136 bytes_write=1460 bytes_read=4280 bytes_retransmit=9 bytes_invalid=6 send_seq=130 receive_seq=130 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15993656 print_time=12.431 buffer_time=0.000 print_stall=0 extruder: target=0 temp=26.4 pwm=0.000 heater_bed: target=0 temp=26.9 pwm=0.000 Stats 115010.4: gcodein=92 mcu: mcu_awake=0.010 mcu_task_avg=0.000100 mcu_task_stddev=0.000136 bytes_write=1466 bytes_read=4381 bytes_retransmit=9 bytes_invalid=6 send_seq=131 receive_seq=131 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15993659 print_time=12.431 buffer_time=0.000 print_stall=0 extruder: target=0 temp=26.4 pwm=0.000 heater_bed: target=0 temp=26.9 pwm=0.000

when I use tmc2100, it works great I used 80 step per mm when since 1/16 microstep and 1/128 interpolation

when I try to use LV8729, it doesn't work I used 640 step per mm since 1/128 microstep so step_distance is 0.0015625

CFG1, CFG2, CFG3 are all high meaning lv8729 1/128 operating mode

tried baud rate 256000 doesn't work

megablue commented 6 years ago

1/128 microstep is too fine for Atmega 2560 to handle, even for Klipper, more so on a delta.

KevinOConnor commented 6 years ago

@luxflow - you need to attach the full log (not cut-and-paste portions of it) as described at: https://github.com/KevinOConnor/klipper/blob/master/docs/Contact.md

@megablue - acceptable microstep size is too dependent on other factors to determine if that is the issue - only way to know is with the full log.

3DSTB commented 6 years ago

I'm not sure if this is the problem: for LV8729 enable is high (not low) so enable_pin = ar38 should be correct

luxflow commented 6 years ago
Starting Klippy...
Args: ['/home/pi/klipper/klippy/klippy.py', '/home/pi/printer.cfg', '-l', '/tmp/klippy.log']
Git version: 'v0.5.0-225-g99f3c99-dirty'
CPU: 4 core ?
Python: '2.7.9 (default, Aug 13 2016, 17:56:53) \n[GCC 4.9.2]'
Start printer at Tue Mar 27 12:16:49 2018 (1522153009.3 264.4)
===== Config file =====
[stepper_a]
step_pin = ar54
dir_pin = ar55
enable_pin = !ar38
step_distance = .0015625
endstop_pin = ^ar2
homing_speed = 30
position_endstop = 291.675742
angle = 209.984729
arm_length = 269.009

[stepper_b]
step_pin = ar60
dir_pin = ar61
enable_pin = !ar56
step_distance = .0015625
endstop_pin = ^ar15
position_endstop = 292.497408
angle = 330.205626

[stepper_c]
step_pin = ar46
dir_pin = ar48
enable_pin = !ar62
step_distance = .0015625
endstop_pin = ^ar19
position_endstop = 290.122678
angle = 90.00000

[extruder]
step_pin = ar26
dir_pin = !ar28
enable_pin = !ar24
step_distance = .01031
nozzle_diameter = 0.400
filament_diameter = 1.750
heater_pin = ar10
sensor_type = ATC Semitec 104GT-2
sensor_pin = analog13
control = pid
pid_kp = 20.952
pid_ki = 0.884
pid_kd = 124.140
min_temp = 0
max_temp = 250
pressure_advance = 0.09
pressure_advance_lookahead_time = 0

[heater_bed]
heater_pin = ar8
sensor_type = ATC Semitec 104GT-2
sensor_pin = analog14
control = pid
pid_kp = 66.886
pid_ki = 1.225
pid_kd = 912.998
min_temp = 0
max_temp = 130

[fan]
pin = ar45

[heater_fan board_fan]
pin = ar44
heater = heater_bed
heater_temp = 30.0
fan_speed = 1.0

[mcu]
serial = /dev/ttyUSB0
pin_map = arduino

[printer]
kinematics = delta
max_velocity = 500
max_accel = 3000
max_z_velocity = 150
delta_radius = 132.961770
minimum_z_position = -10

[probe]
pin = ^ar18
z_offset = 16.37

[delta_calibrate]
radius = 80
speed = 10
horizontal_move_z = 30

=======================
Delta max build height 290.12mm (radius tapered above 254.97mm)
Delta max build radius 132.96mm (moves slowed past 122.24mm and 132.39mm)
Starting serial connect
Loaded 57 commands (v0.5.0-225-g99f3c99-dirty-20180326_163620-octopi / gcc: (GCC) 4.8.1 binutils: (GNU Binutils) 2.24)
MCU config: ADC_MAX=1023 PWM_MAX=255 CLOCK_FREQ=16000000 SOFT_PWM_MAX=256 SERIAL_BAUD=250000 MCU=atmega2560 STATS_SUMSQ_BASE=256
Sending MCU 'mcu' printer configuration...
Configured MCU 'mcu' (628 moves)
Starting heater checks for extruder
Starting heater checks for heater_bed
Stats 266.0: gcodein=0 mcu: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=877 bytes_read=2694 bytes_retransmit=9 bytes_invalid=6 send_seq=107 receive_seq=107 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15993922 print_time=1.605 buffer_time=0.249 print_stall=0 extruder: target=0 temp=0.0 pwm=0.000 heater_bed: target=0 temp=0.0 pwm=0.000
Stats 267.0: gcodein=0 mcu: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=883 bytes_read=2736 bytes_retransmit=9 bytes_invalid=6 send_seq=108 receive_seq=108 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994091 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.4 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 268.0: gcodein=0 mcu: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=889 bytes_read=2843 bytes_retransmit=9 bytes_invalid=6 send_seq=109 receive_seq=109 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994074 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 269.0: gcodein=0 mcu: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=895 bytes_read=2950 bytes_retransmit=9 bytes_invalid=6 send_seq=110 receive_seq=110 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994026 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 270.0: gcodein=0 mcu: mcu_awake=0.024 mcu_task_avg=0.000127 mcu_task_stddev=0.000323 bytes_write=901 bytes_read=3059 bytes_retransmit=9 bytes_invalid=6 send_seq=111 receive_seq=111 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994056 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 271.0: gcodein=0 mcu: mcu_awake=0.024 mcu_task_avg=0.000127 mcu_task_stddev=0.000323 bytes_write=907 bytes_read=3166 bytes_retransmit=9 bytes_invalid=6 send_seq=112 receive_seq=112 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994084 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 272.0: gcodein=0 mcu: mcu_awake=0.024 mcu_task_avg=0.000127 mcu_task_stddev=0.000323 bytes_write=913 bytes_read=3273 bytes_retransmit=9 bytes_invalid=6 send_seq=113 receive_seq=113 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994104 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 273.0: gcodein=0 mcu: mcu_awake=0.024 mcu_task_avg=0.000127 mcu_task_stddev=0.000323 bytes_write=919 bytes_read=3367 bytes_retransmit=9 bytes_invalid=6 send_seq=114 receive_seq=114 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994102 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 274.0: gcodein=0 mcu: mcu_awake=0.024 mcu_task_avg=0.000127 mcu_task_stddev=0.000323 bytes_write=925 bytes_read=3474 bytes_retransmit=9 bytes_invalid=6 send_seq=115 receive_seq=115 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994107 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 275.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000054 bytes_write=931 bytes_read=3594 bytes_retransmit=9 bytes_invalid=6 send_seq=116 receive_seq=116 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994107 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 276.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000054 bytes_write=937 bytes_read=3688 bytes_retransmit=9 bytes_invalid=6 send_seq=117 receive_seq=117 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994105 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 277.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000054 bytes_write=943 bytes_read=3796 bytes_retransmit=9 bytes_invalid=6 send_seq=118 receive_seq=118 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994109 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 278.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000054 bytes_write=949 bytes_read=3911 bytes_retransmit=9 bytes_invalid=6 send_seq=119 receive_seq=119 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994129 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 279.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000054 bytes_write=955 bytes_read=4012 bytes_retransmit=9 bytes_invalid=6 send_seq=120 receive_seq=120 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994123 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 280.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000068 mcu_task_stddev=0.000057 bytes_write=961 bytes_read=4140 bytes_retransmit=9 bytes_invalid=6 send_seq=121 receive_seq=121 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994113 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 281.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000068 mcu_task_stddev=0.000057 bytes_write=967 bytes_read=4255 bytes_retransmit=9 bytes_invalid=6 send_seq=122 receive_seq=122 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994115 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 282.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000068 mcu_task_stddev=0.000057 bytes_write=973 bytes_read=4356 bytes_retransmit=9 bytes_invalid=6 send_seq=123 receive_seq=123 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994113 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 283.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000068 mcu_task_stddev=0.000057 bytes_write=979 bytes_read=4471 bytes_retransmit=9 bytes_invalid=6 send_seq=124 receive_seq=124 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994112 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 284.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000068 mcu_task_stddev=0.000057 bytes_write=985 bytes_read=4586 bytes_retransmit=9 bytes_invalid=6 send_seq=125 receive_seq=125 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994113 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 285.0: gcodein=0 mcu: mcu_awake=0.007 mcu_task_avg=0.000072 mcu_task_stddev=0.000063 bytes_write=991 bytes_read=4700 bytes_retransmit=9 bytes_invalid=6 send_seq=126 receive_seq=126 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994111 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 286.0: gcodein=0 mcu: mcu_awake=0.007 mcu_task_avg=0.000072 mcu_task_stddev=0.000063 bytes_write=997 bytes_read=4815 bytes_retransmit=9 bytes_invalid=6 send_seq=127 receive_seq=127 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994111 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 287.0: gcodein=0 mcu: mcu_awake=0.007 mcu_task_avg=0.000072 mcu_task_stddev=0.000063 bytes_write=1003 bytes_read=4930 bytes_retransmit=9 bytes_invalid=6 send_seq=128 receive_seq=128 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994110 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 288.0: gcodein=0 mcu: mcu_awake=0.007 mcu_task_avg=0.000072 mcu_task_stddev=0.000063 bytes_write=1009 bytes_read=5031 bytes_retransmit=9 bytes_invalid=6 send_seq=129 receive_seq=129 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994109 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 289.0: gcodein=0 mcu: mcu_awake=0.007 mcu_task_avg=0.000072 mcu_task_stddev=0.000063 bytes_write=1015 bytes_read=5146 bytes_retransmit=9 bytes_invalid=6 send_seq=130 receive_seq=130 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994107 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 290.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1021 bytes_read=5274 bytes_retransmit=9 bytes_invalid=6 send_seq=131 receive_seq=131 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994103 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 291.0: gcodein=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1027 bytes_read=5375 bytes_retransmit=9 bytes_invalid=6 send_seq=132 receive_seq=132 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994102 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 292.0: gcodein=61 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1033 bytes_read=5490 bytes_retransmit=9 bytes_invalid=6 send_seq=133 receive_seq=133 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994103 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 293.0: gcodein=61 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1039 bytes_read=5605 bytes_retransmit=9 bytes_invalid=6 send_seq=134 receive_seq=134 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994102 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 294.0: gcodein=61 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1045 bytes_read=5706 bytes_retransmit=9 bytes_invalid=6 send_seq=135 receive_seq=135 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994100 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 295.0: gcodein=61 mcu: mcu_awake=0.007 mcu_task_avg=0.000074 mcu_task_stddev=0.000063 bytes_write=1051 bytes_read=5834 bytes_retransmit=9 bytes_invalid=6 send_seq=136 receive_seq=136 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994097 print_time=1.605 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 296.0: gcodein=78 mcu: mcu_awake=0.007 mcu_task_avg=0.000074 mcu_task_stddev=0.000063 bytes_write=1559 bytes_read=5989 bytes_retransmit=9 bytes_invalid=6 send_seq=145 receive_seq=145 retransmit_seq=2 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994096 print_time=31.549 buffer_time=0.171 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
MCU 'mcu' shutdown: Rescheduled timer in the past
clocksync state: mcu_freq=16000000 last_clock=500137347 clock_est=(277.185 201237746 15994096.520) min_half_rtt=0.000354 min_rtt_time=289.864 time_avg=277.185(124.043) clock_avg=201237746.757(1983948351.724) pred_variance=68317084.147
Dumping serial stats: bytes_write=1559 bytes_read=6015 bytes_retransmit=9 bytes_invalid=6 send_seq=145 receive_seq=145 retransmit_seq=2 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0
Dumping send queue 100 messages
Sent 0 265.230219 265.230219 9: seq: 1d, identify offset=1160 count=40
Sent 1 265.234262 265.234262 9: seq: 1e, identify offset=1200 count=40
Sent 2 265.238125 265.238125 9: seq: 1f, identify offset=1240 count=40
Sent 3 265.242127 265.242127 9: seq: 10, identify offset=1280 count=40
Sent 4 265.246127 265.246127 9: seq: 11, identify offset=1320 count=40
Sent 5 265.250188 265.250188 9: seq: 12, identify offset=1360 count=40
Sent 6 265.254104 265.254104 9: seq: 13, identify offset=1400 count=40
Sent 7 265.258091 265.258091 9: seq: 14, identify offset=1440 count=40
Sent 8 265.262282 265.262282 9: seq: 15, identify offset=1480 count=40
Sent 9 265.266139 265.266139 9: seq: 16, identify offset=1520 count=40
Sent 10 265.270248 265.270248 9: seq: 17, identify offset=1560 count=40
Sent 11 265.274117 265.274117 9: seq: 18, identify offset=1600 count=40
Sent 12 265.278099 265.278099 9: seq: 19, identify offset=1640 count=40
Sent 13 265.282196 265.282196 9: seq: 1a, identify offset=1680 count=40
Sent 14 265.285115 265.285115 9: seq: 1b, identify offset=1694 count=40
Sent 15 265.347132 265.346892 6: seq: 1c, get_uptime
Sent 16 265.394599 265.394359 6: seq: 1d, get_status
Sent 17 265.448064 265.447824 6: seq: 1e, get_status
Sent 18 265.499685 265.499445 6: seq: 1f, get_status
Sent 19 265.551376 265.551136 6: seq: 10, get_status
Sent 20 265.603069 265.602829 6: seq: 11, get_status
Sent 21 265.654646 265.654406 6: seq: 12, get_status
Sent 22 265.706304 265.706064 6: seq: 13, get_status
Sent 23 265.757863 265.757623 6: seq: 14, get_status
Sent 24 265.825101 265.824861 6: seq: 15, get_status
Sent 25 265.825528 265.825288 6: seq: 16, get_config
Sent 26 265.877981 265.877701 7: seq: 17, allocate_oids count=18
Sent 27 265.878985 265.878425 14: seq: 18, config_soft_pwm_out oid=4 pin=92 cycle_ticks=160000 value=0 default_value=0 max_duration=0
Sent 28 265.880054 265.879494 14: seq: 19, config_soft_pwm_out oid=5 pin=93 cycle_ticks=160000 value=0 default_value=1 max_duration=0
Sent 29 265.880736 265.880336 10: seq: 1a, config_end_stop oid=6 pin=27 pull_up=1 stepper_count=3
Sent 30 265.881444 265.880964 12: seq: 1b, config_stepper oid=0 step_pin=40 dir_pin=41 min_stop_interval=724 invert_step=0
Sent 31 265.881902 265.881462 11: seq: 1c, config_digital_out oid=7 pin=31 value=1 default_value=1 max_duration=0
Sent 32 265.882376 265.881976 10: seq: 1d, config_end_stop oid=8 pin=36 pull_up=1 stepper_count=1
Sent 33 265.882869 265.882389 12: seq: 1e, config_stepper oid=1 step_pin=46 dir_pin=47 min_stop_interval=724 invert_step=0
Sent 34 265.883429 265.882989 11: seq: 1f, config_digital_out oid=9 pin=42 value=1 default_value=1 max_duration=0
Sent 35 265.883829 265.883339 10: seq: 10, config_end_stop oid=10 pin=72 pull_up=1 stepper_count=1
Sent 36 265.884411 265.883931 12: seq: 11, config_stepper oid=2 step_pin=91 dir_pin=89 min_stop_interval=724 invert_step=0
Sent 37 265.884851 265.884328 11: seq: 12, config_digital_out oid=11 pin=80 value=1 default_value=1 max_duration=0
Sent 38 265.885345 265.884945 10: seq: 13, config_end_stop oid=12 pin=26 pull_up=1 stepper_count=1
Sent 39 265.886183 265.885463 18: seq: 14, config_soft_pwm_out oid=13 pin=12 cycle_ticks=1600000 value=0 default_value=0 max_duration=80000000
Sent 40 265.886503 265.885912 8: seq: 15, config_analog_in oid=14 pin=85
Sent 41 265.886943 265.886314 11: seq: 16, config_stepper oid=3 step_pin=4 dir_pin=6 min_stop_interval=0 invert_step=0
Sent 42 265.887383 265.886916 11: seq: 17, config_digital_out oid=15 pin=2 value=1 default_value=1 max_duration=0
Sent 43 265.888117 265.887397 18: seq: 18, config_soft_pwm_out oid=16 pin=61 cycle_ticks=1600000 value=0 default_value=0 max_duration=80000000
Sent 44 265.888437 265.887874 8: seq: 19, config_analog_in oid=17 pin=86
Sent 45 265.888877 265.888177 11: seq: 1a, finalize_config crc=2623829350
Sent 46 265.889188 265.888948 6: seq: 1b, get_config
Sent 47 265.947323 265.946843 12: seq: 1c, end_stop_home oid=6 clock=0 sample_ticks=0 sample_count=0 rest_ticks=0 pin_value=0
Sent 48 265.947683 265.947162 9: seq: 1d, end_stop_set_stepper oid=6 pos=0 stepper_oid=0
Sent 49 265.948043 265.947476 9: seq: 1e, end_stop_set_stepper oid=6 pos=1 stepper_oid=1
Sent 50 265.948403 265.947931 9: seq: 1f, end_stop_set_stepper oid=6 pos=2 stepper_oid=2
Sent 51 265.948723 265.948196 8: seq: 10, reset_step_clock oid=0 clock=0
Sent 52 265.949348 265.948868 12: seq: 11, end_stop_home oid=8 clock=0 sample_ticks=0 sample_count=0 rest_ticks=0 pin_value=0
Sent 53 265.949937 265.949457 12: seq: 12, end_stop_set_stepper oid=8 pos=0 stepper_oid=0, reset_step_clock oid=1 clock=0
Sent 54 265.950686 265.950206 12: seq: 13, end_stop_home oid=10 clock=0 sample_ticks=0 sample_count=0 rest_ticks=0 pin_value=0
Sent 55 265.951046 265.950669 9: seq: 14, end_stop_set_stepper oid=10 pos=0 stepper_oid=1
Sent 56 265.951366 265.950976 8: seq: 15, reset_step_clock oid=2 clock=0
Sent 57 265.951897 265.951417 12: seq: 16, end_stop_home oid=12 clock=0 sample_ticks=0 sample_count=0 rest_ticks=0 pin_value=0
Sent 58 265.952286 265.951926 9: seq: 17, end_stop_set_stepper oid=12 pos=0 stepper_oid=2
Sent 59 265.953404 265.952484 23: seq: 18, query_analog_in oid=14 clock=34240000 sample_ticks=16000 sample_count=8 rest_ticks=4800000 min_value=292 max_value=8077
Sent 60 265.953724 265.952945 8: seq: 19, reset_step_clock oid=3 clock=0
Sent 61 265.954644 265.953487 23: seq: 1a, query_analog_in oid=17 clock=34720000 sample_ticks=16000 sample_count=8 rest_ticks=4800000 min_value=2687 max_value=8077
Sent 62 266.827105 266.826865 6: seq: 1b, get_status
Sent 63 267.828380 267.828140 6: seq: 1c, get_status
Sent 64 268.830386 268.830146 6: seq: 1d, get_status
Sent 65 269.832392 269.832152 6: seq: 1e, get_status
Sent 66 270.834616 270.834376 6: seq: 1f, get_status
Sent 67 271.835712 271.835472 6: seq: 10, get_status
Sent 68 272.836974 272.836734 6: seq: 11, get_status
Sent 69 273.838402 273.838162 6: seq: 12, get_status
Sent 70 274.840490 274.840250 6: seq: 13, get_status
Sent 71 275.841769 275.841529 6: seq: 14, get_status
Sent 72 276.842954 276.842714 6: seq: 15, get_status
Sent 73 277.844091 277.843851 6: seq: 16, get_status
Sent 74 278.845185 278.844945 6: seq: 17, get_status
Sent 75 279.846103 279.845863 6: seq: 18, get_status
Sent 76 280.847864 280.847624 6: seq: 19, get_status
Sent 77 281.850000 281.849760 6: seq: 1a, get_status
Sent 78 282.852205 282.851965 6: seq: 1b, get_status
Sent 79 283.854423 283.854183 6: seq: 1c, get_status
Sent 80 284.855713 284.855473 6: seq: 1d, get_status
Sent 81 285.857494 285.857254 6: seq: 1e, get_status
Sent 82 286.859533 286.859293 6: seq: 1f, get_status
Sent 83 287.860828 287.860588 6: seq: 10, get_status
Sent 84 288.862104 288.861864 6: seq: 11, get_status
Sent 85 289.864448 289.864208 6: seq: 12, get_status
Sent 86 290.866516 290.866276 6: seq: 13, get_status
Sent 87 291.867602 291.867362 6: seq: 14, get_status
Sent 88 292.869792 292.869552 6: seq: 15, get_status
Sent 89 293.871043 293.870803 6: seq: 16, get_status
Sent 90 294.872042 294.871802 6: seq: 17, get_status
Sent 91 295.609981 295.607581 60: seq: 18, end_stop_home oid=8 clock=504776216 sample_ticks=240 sample_count=4 rest_ticks=834 pin_value=1, end_stop_home oid=10 clock=504776216 sample_ticks=240 sample_count=4 rest_ticks=834 pin_value=1, end_stop_home oid=12 clock=504776216 sample_ticks=240 sample_count=4 rest_ticks=834 pin_value=1, schedule_digital_out oid=7 clock=504776216 value=0, schedule_digital_out oid=9 clock=504776216 value=0
Sent 92 295.718126 295.715806 58: seq: 19, schedule_digital_out oid=11 clock=504776216 value=0, set_next_step_dir oid=0 dir=1, queue_step oid=0 interval=504787763 count=1 add=0, queue_step oid=0 interval=8057 count=3 add=-1842, queue_step oid=0 interval=4055 count=7 add=-286, queue_step oid=0 interval=2414 count=16 add=-62, queue_step oid=0 interval=1540 count=33 add=-17, queue_step oid=0 interval=1049 count=47 add=-6
Sent 93 295.720686 295.715806 64: seq: 1a, queue_step oid=0 interval=834 count=65535 add=0, queue_step oid=0 interval=834 count=65535 add=0, queue_step oid=0 interval=834 count=65535 add=0, queue_step oid=0 interval=834 count=65535 add=0, queue_step oid=0 interval=834 count=65535 add=0, queue_step oid=0 interval=834 count=65535 add=0, queue_step oid=0 interval=834 count=16769 add=0, set_next_step_dir oid=1 dir=1
Sent 94 295.723206 295.715806 63: seq: 1b, queue_step oid=1 interval=504787763 count=1 add=0, queue_step oid=1 interval=8057 count=3 add=-1842, queue_step oid=1 interval=4055 count=7 add=-286, queue_step oid=1 interval=2414 count=16 add=-62, queue_step oid=1 interval=1540 count=33 add=-17, queue_step oid=1 interval=1049 count=47 add=-6, queue_step oid=1 interval=834 count=65535 add=0, queue_step oid=1 interval=834 count=65535 add=0
Sent 95 295.725766 295.719405 64: seq: 1c, queue_step oid=1 interval=834 count=65535 add=0, queue_step oid=1 interval=834 count=65535 add=0, queue_step oid=1 interval=834 count=65535 add=0, queue_step oid=1 interval=834 count=65535 add=0, queue_step oid=1 interval=834 count=16769 add=0, set_next_step_dir oid=2 dir=1, queue_step oid=2 interval=504787763 count=1 add=0, queue_step oid=2 interval=8057 count=3 add=-1842
Sent 96 295.728286 295.722392 63: seq: 1d, queue_step oid=2 interval=4055 count=7 add=-286, queue_step oid=2 interval=2414 count=16 add=-62, queue_step oid=2 interval=1540 count=33 add=-17, queue_step oid=2 interval=1049 count=47 add=-6, queue_step oid=2 interval=834 count=65535 add=0, queue_step oid=2 interval=834 count=65535 add=0, queue_step oid=2 interval=834 count=65535 add=0, queue_step oid=2 interval=834 count=65535 add=0
Sent 97 295.730726 295.724391 61: seq: 1e, queue_step oid=2 interval=834 count=65535 add=0, queue_step oid=2 interval=834 count=65535 add=0, queue_step oid=2 interval=834 count=16769 add=0, queue_step oid=0 interval=864 count=44 add=6, queue_step oid=0 interval=1174 count=27 add=20, queue_step oid=0 interval=1760 count=13 add=83, queue_step oid=0 interval=2917 count=5 add=463, queue_step oid=0 interval=5648 count=2 add=3307
Sent 98 295.733206 295.727389 62: seq: 1f, queue_step oid=1 interval=864 count=44 add=6, queue_step oid=1 interval=1174 count=27 add=20, queue_step oid=1 interval=1760 count=13 add=83, queue_step oid=1 interval=2917 count=5 add=463, queue_step oid=1 interval=5648 count=2 add=3307, queue_step oid=2 interval=864 count=44 add=6, queue_step oid=2 interval=1174 count=27 add=20, queue_step oid=2 interval=1760 count=13 add=83, queue_step oid=2 interval=2917 count=5 add=463
Sent 99 295.872817 295.872297 13: seq: 10, get_status, queue_step oid=2 interval=5648 count=2 add=3307
Dumping receive queue 20 messages
Receive: 0 293.872124 293.871043 12: seq: 17, status clock=468120769 status=0
Receive: 1 294.063123 293.871043 14: seq: 17, analog_in_state oid=14 next_clock=475840000 value=7824
Receive: 2 294.093097 293.871043 14: seq: 17, analog_in_state oid=17 next_clock=476320000 value=7816
Receive: 3 294.363087 293.871043 14: seq: 17, analog_in_state oid=14 next_clock=480640000 value=7824
Receive: 4 294.393037 293.871043 14: seq: 17, analog_in_state oid=17 next_clock=481120000 value=7816
Receive: 5 294.662965 293.871043 14: seq: 17, analog_in_state oid=14 next_clock=485440000 value=7824
Receive: 6 294.692933 293.871043 14: seq: 17, analog_in_state oid=17 next_clock=485920000 value=7816
Receive: 7 294.873980 294.872042 12: seq: 18, status clock=484130513 status=0
Receive: 8 294.873967 294.872042 13: seq: 18, stats count=90 sum=105887 sumsq=842004
Receive: 9 294.962949 294.872042 14: seq: 18, analog_in_state oid=14 next_clock=490240000 value=7824
Receive: 10 294.992920 294.872042 14: seq: 18, analog_in_state oid=17 next_clock=490720000 value=7816
Receive: 11 295.263086 294.872042 14: seq: 18, analog_in_state oid=14 next_clock=495040000 value=7824
Receive: 12 295.293015 294.872042 14: seq: 18, analog_in_state oid=17 next_clock=495520000 value=7816
Receive: 13 295.563105 294.872042 14: seq: 18, analog_in_state oid=14 next_clock=499840000 value=7824
Receive: 14 295.593630 294.872042 14: seq: 18, analog_in_state oid=17 next_clock=500320000 value=7816
Receive: 15 295.862884 295.733206 14: seq: 10, analog_in_state oid=14 next_clock=504640000 value=7824
Receive: 16 295.875752 295.872817 12: seq: 11, status clock=500137347 status=0
Receive: 17 295.892943 295.872817 14: seq: 11, analog_in_state oid=17 next_clock=505120000 value=7816
Receive: 18 296.164073 295.872817 14: seq: 11, analog_in_state oid=14 next_clock=509440000 value=7824
Receive: 19 296.174091 295.872817 12: seq: 11, shutdown clock=504915278 static_string_id=29
Dumping gcode input 8 blocks
Read 291.550680: 'N0 M110 N0*125\n'
Read 291.561298: 'N0 M110 N0*125\n'
Read 291.568651: 'N1 M115*39\n'
Read 291.598371: 'N2 M105*37\n'
Read 291.603118: 'M21\n'
Read 291.604556: 'M115\n'
Read 295.599959: 'G91\n'
Read 295.604478: 'G28 X0 Y0 Z0\n'
gcode state: absolutecoord=False absoluteextrude=True base_position=[0.0, 0.0, 0.0, 0.0] last_position=[0.0, 0.0, 0.0, 0.0] homing_add=[0.0, 0.0, 0.0, 0.0] speed_factor=0.0166666666667 extrude_factor=1.0 speed=25.0
Internal error on command:"G28"
Traceback (most recent call last):
  File "/home/pi/klipper/klippy/gcode.py", line 153, in process_commands
    handler(params)
  File "/home/pi/klipper/klippy/gcode.py", line 66, in <lambda>
    func = lambda params: origfunc(self.get_extended_params(params))
  File "/home/pi/klipper/klippy/gcode.py", line 427, in cmd_G28
    homing_state.home_axes(axes)
  File "/home/pi/klipper/klippy/homing.py", line 109, in home_axes
    self.toolhead.get_kinematics().home(self)
  File "/home/pi/klipper/klippy/delta.py", line 103, in home
    homing_state.home(coord, homepos, endstops, homing_speed)
  File "/home/pi/klipper/klippy/homing.py", line 99, in home
    self.homing_move(movepos, endstops, speed)
  File "/home/pi/klipper/klippy/homing.py", line 66, in homing_move
    mcu_endstop.home_wait(move_end_print_time)
  File "/home/pi/klipper/klippy/mcu.py", line 187, in home_wait
    while self._check_busy(eventtime, home_end_time):
  File "/home/pi/klipper/klippy/mcu.py", line 214, in _check_busy
    raise error("MCU is shutdown")
error: MCU is shutdown
Internal error on command:"G28"
MCU 'mcu' shutdown: Rescheduled timer in the past
This generally occurs when the micro-controller has been
requested to step at a rate higher than it is capable of
obtaining.
Once the underlying issue is corrected, use the
"FIRMWARE_RESTART" command to reset the firmware, reload the
config, and restart the host software.
Printer is shutdown

Stats 297.0: gcodein=87 mcu: mcu_awake=0.007 mcu_task_avg=0.000074 mcu_task_stddev=0.000063 bytes_write=1565 bytes_read=6074 bytes_retransmit=9 bytes_invalid=6 send_seq=146 receive_seq=146 retransmit_seq=2 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994096 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
MCU 'mcu' shutdown: Rescheduled timer in the past
This generally occurs when the micro-controller has been
requested to step at a rate higher than it is capable of
obtaining.
Once the underlying issue is corrected, use the
"FIRMWARE_RESTART" command to reset the firmware, reload the
config, and restart the host software.
Printer is shutdown

Stats 298.0: gcodein=92 mcu: mcu_awake=0.007 mcu_task_avg=0.000074 mcu_task_stddev=0.000063 bytes_write=1571 bytes_read=6189 bytes_retransmit=9 bytes_invalid=6 send_seq=147 receive_seq=147 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994093 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 299.0: gcodein=92 mcu: mcu_awake=0.007 mcu_task_avg=0.000074 mcu_task_stddev=0.000063 bytes_write=1577 bytes_read=6304 bytes_retransmit=9 bytes_invalid=6 send_seq=148 receive_seq=148 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994091 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 300.0: gcodein=92 mcu: mcu_awake=0.010 mcu_task_avg=0.000103 mcu_task_stddev=0.000134 bytes_write=1583 bytes_read=6420 bytes_retransmit=9 bytes_invalid=6 send_seq=149 receive_seq=149 retransmit_seq=2 srtt=0.002 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994089 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 301.0: gcodein=92 mcu: mcu_awake=0.010 mcu_task_avg=0.000103 mcu_task_stddev=0.000134 bytes_write=1589 bytes_read=6535 bytes_retransmit=9 bytes_invalid=6 send_seq=150 receive_seq=150 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994087 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 302.0: gcodein=97 mcu: mcu_awake=0.010 mcu_task_avg=0.000103 mcu_task_stddev=0.000134 bytes_write=1595 bytes_read=6650 bytes_retransmit=9 bytes_invalid=6 send_seq=151 receive_seq=151 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994085 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 303.0: gcodein=97 mcu: mcu_awake=0.010 mcu_task_avg=0.000103 mcu_task_stddev=0.000134 bytes_write=1601 bytes_read=6751 bytes_retransmit=9 bytes_invalid=6 send_seq=152 receive_seq=152 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994080 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 304.0: gcodein=97 mcu: mcu_awake=0.010 mcu_task_avg=0.000103 mcu_task_stddev=0.000134 bytes_write=1607 bytes_read=6880 bytes_retransmit=9 bytes_invalid=6 send_seq=153 receive_seq=153 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994074 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 305.0: gcodein=97 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000061 bytes_write=1613 bytes_read=6994 bytes_retransmit=9 bytes_invalid=6 send_seq=154 receive_seq=154 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994078 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 306.0: gcodein=97 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000061 bytes_write=1619 bytes_read=7095 bytes_retransmit=9 bytes_invalid=6 send_seq=155 receive_seq=155 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994070 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 307.0: gcodein=102 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000061 bytes_write=1625 bytes_read=7224 bytes_retransmit=9 bytes_invalid=6 send_seq=156 receive_seq=156 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994063 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 308.0: gcodein=102 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000061 bytes_write=1631 bytes_read=7325 bytes_retransmit=9 bytes_invalid=6 send_seq=157 receive_seq=157 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994056 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 309.0: gcodein=102 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000061 bytes_write=1637 bytes_read=7426 bytes_retransmit=9 bytes_invalid=6 send_seq=158 receive_seq=158 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994048 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 310.0: gcodein=102 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1643 bytes_read=7568 bytes_retransmit=9 bytes_invalid=6 send_seq=159 receive_seq=159 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994041 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 311.0: gcodein=102 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1649 bytes_read=7669 bytes_retransmit=9 bytes_invalid=6 send_seq=160 receive_seq=160 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994041 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 312.0: gcodein=107 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1655 bytes_read=7770 bytes_retransmit=9 bytes_invalid=6 send_seq=161 receive_seq=161 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994032 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 313.0: gcodein=107 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1661 bytes_read=7899 bytes_retransmit=9 bytes_invalid=6 send_seq=162 receive_seq=162 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994024 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 314.0: gcodein=107 mcu: mcu_awake=0.006 mcu_task_avg=0.000072 mcu_task_stddev=0.000062 bytes_write=1667 bytes_read=8000 bytes_retransmit=9 bytes_invalid=6 send_seq=163 receive_seq=163 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994015 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 315.0: gcodein=107 mcu: mcu_awake=0.006 mcu_task_avg=0.000073 mcu_task_stddev=0.000061 bytes_write=1673 bytes_read=8114 bytes_retransmit=9 bytes_invalid=6 send_seq=164 receive_seq=164 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994008 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000
Stats 316.0: gcodein=107 mcu: mcu_awake=0.006 mcu_task_avg=0.000073 mcu_task_stddev=0.000061 bytes_write=1679 bytes_read=8243 bytes_retransmit=9 bytes_invalid=6 send_seq=165 receive_seq=165 retransmit_seq=2 srtt=0.002 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15994000 print_time=31.620 buffer_time=0.000 print_stall=0 extruder: target=0 temp=24.5 pwm=0.000 heater_bed: target=0 temp=25.0 pwm=0.000

weird thing founded by mistake is if I disconnect stepper c endstop, both stepper a,b works well with same setting but when I connect, all stepper doesn't work thanks

@3DSTB I tried inverting enable_pin, but no luck and even cannot move two stepper using above method (maybe driver carrier inverting? i don't know)

KevinOConnor commented 6 years ago

You are indeed hitting the limits of the atmega2560. If you want to continue anyway, you can reduce your maximum velocities - try: homing_speed=15, max_z_velocity=30, max_velocity=45

-Kevin

hg42 commented 6 years ago

the power of klipper is this:

you can easily add more MCUs (e.g. an extra RAMPS or one of these pure stepper boards for laser cutters) and give them different tasks. I once used LV8729 with klipper (either with 128usteps or 64usteps), but I don't remember in which exact configuration. Either one RAMPS only ran X and Y or even X and Y on different MCUs.

But it wasn't a delta but a corexy. While the MCU doesn't calculate delta kinematics it still has to continuously process all those little segments. May be this makes a difference here.

Oh, and I forgot the problem with the end stops. I think this prevents distributing the three steppers on separate MCUs. This is because each stepper must react on all three endstops. And there is no communication between MCUS about end stops.

For corexy I solved this by coupling both end stops electrically (and adding a feature to klipper).

For deltas this probably would be more complicated. My idea for a solution was to connect each end stop via a diode to it's own input and via another diode to a common input which indicates that any of the end stops is triggered. So homing would step all steppers until the common input is triggered and then each axis until it's own input is triggered. Because my corexy replaced the delta I couldn't try the idea.

I am not sure if this could be accomplished by pure software. The MCU would need a fast channel either to other MCUs or to the host. Such a common end stop input could also be implemented as an open collector line and pulled down by software, which would make it more flexible, because non moving steppers could be ignored. This would simplify the electrical circuit (no diodes necessary) and the software (the stepper always looks at the same [common] end stop line).

hg42 commented 6 years ago

btw. you could also try 64usteps which should be enough for precision and silence.

KevinOConnor commented 6 years ago

On Tue, Mar 27, 2018 at 01:51:46PM -0700, Harald wrote:

Oh, and I forgot the problem with the end stops. I think this prevents distributing the three steppers on separate MCUs.

A delta shouldn't have this problem, as each endstop only stops one stepper. Just make sure both the stepper and endstop for a tower is wired to the same MCU.

It's more complicated for the z-probe though. In that case, the probe needs to stop all three towers.

My idea for a solution was to connect each end stop via a diode to it's own input and via another diode to a common input which indicates that any of the end stops is triggered. So homing would step all steppers until the common input is triggered and then each axis until it's own input is triggered. Because my corexy replaced the delta I couldn't try the idea.

I think a simpler solution would be to run the endstop wire in parallel to multiple MCUs. Specifically, connect one side of the endstop to a pin on each MCU and connect the other side of the endstop to ground - all MCUs will see the line pulled up when not triggered and all will see the pin pulled down when it is triggered. This has the advantage of not requiring any special hardware and it doesn't requiring any special code in the micro-controller. It would require a small amount of code in the host software, but I can code that up if anyone wants to test it.

The above should work for both the probe and for endstops on corexy.

-Kevin

hg42 commented 6 years ago

A delta shouldn't have this problem, as each endstop only stops one stepper.

oh, nice, I thought it must be more complicated with a delta :-)

Also, nice idea to wire each end stop to each MCU (if it is involved).

I would be happy to test this. However I am bound to cruwaller's fork (and my own changes), so I would merge this change to my local code. The merge may also conflict with other changes. So, this might not be sufficient as a test from your point of view.

And I don't use a probe...

luxflow commented 6 years ago

I'm leaving notes for anyone who interesting 3 lv8729 1/64 works well with delta, cannot handle 90mm/s but it can handle 80mm/s don't know exact limit speed. In my opinion, lv8729 1/64 less noisy than tmc2100 1/256

I think it would be nice to collect statics about maximum speed for other printer, and leave documentation.

hg42 commented 6 years ago

Really tmc2100 in StealthChop mode? because the other mode is quite noisy (hmm, I have tmc2130 but should be similar for non-SPI modes). My TMC2130 is nearly inaudible (even with 16usteps interpolated to 256). Though, other sounds from the printer construction are still audible, but not the steps. But I agree, LV8729 with 64usteps is very silent. And 128 usteps is not worth a limit in speed.

According to speeds of printers, I think this is very much dependent on the parts you print, the materials, the extruder models etc.

I can confirm Kevin's announced speed limit for a single RAMPS board (or similar) with cartesian kinematics. It can run at nearly 500 mm/s with the usual 80 steps/mm setup. But no real printer will run that fast, because of other factors.

FYI: my LPC1768 smoothieboard can even run steppers with XY cartesian kinematics smoothly at 2000 mm/s and accelerations up to 20000 mm/s^2 (also with 80 steps/mm). Note that this was tested with a simple square of 100mm x 100mm and also with a real part (a bar with a lot of holes in all directions) and pure motors (with a small arm to show the movement and position). There were no lost steps, the arm positions were fully reproducible even when running for hours. But these insane speeds don't matter...no extruder or material or mechnical construction will ever catch up with this.

More important is the exact calculation of acceleration ramps and step times. You hear and see the results.

If you want speed, you should also keep an eye on polygon counts. Once I had pauses of about a second each because the polygon rate was too high (a lot of circles). I assume the serial interface could not catch up with this rate, because reducing serial speed made it worse. The model was sliced with way too high precision. So, always use a realistic precision. I recommend to compare real print results. You can easily overestimate what can be achieved with usual filaments at higher speeds.

KevinOConnor commented 6 years ago

On Wed, Mar 28, 2018 at 07:37:45AM -0700, Harald wrote:

If you want speed, you should also keep an eye on polygon counts. Once I had pauses of about a second each because the polygon rate was too high (a lot of circles). I assume the serial interface could not catch up with this rate, because reducing serial speed made it worse. The model was sliced with way too high precision.

That's almost certainly a limit in OctoPrint (or whatever is sending the g-code to Klipper). Use a [virtual_sdcard] definition and print directly from Klipper if that is a concern. If the serial line between host and mcu can not keep up then Klipper will raise an error to inform you of that. Also, note that the scripts/graphstats.py tool (as described in docs/Debugging.md) can be used to graph out the bandwidth and mcu load during a print.

-Kevin

hg42 commented 6 years ago

Thanks for pointing to graphstats, I never tried it and will keep it in mind.

I know, virtual_sdcard can help (but this didn't exist at that time), but I like to adjust the printer while printing. I think this is not possible with virtual_sdcard?

I don't remember 100%, but I think I started with the usual octopi configuration and then switched to the PC to exclude the bottleneck. Which means I then tested with Printrun on the PC connected via /tmp/printer to klippy which was also running on the PC and contorlling the MCUs via some usb hub.

The problematic layers of the test object were a 80mm x 10mm with 8 x 4mm holes equally distributed. The pauses always started at the third hole. Then I reduced the slicer precision (the same Cura like in octoprint 15.04?) to 0.05 and the problem was gone. I think the precision had been at least ten times higher (= lower values).

But any recorded data was deleted in the mean time. So let's push this to the world of tales.

luxflow commented 6 years ago

@hg42 I didn't use spread cycle, and as I heard I should have to use spread cycle since stealth chop cannot hold high torque, and high speed isn't it?

hg42 commented 6 years ago

@luxflow stealth chop is silent and the other makes noises that sound cruel to my ears (high pitched). So I will probably never use this mode. So you found that LV8729 64usteps is more silent than TMC21000 StealthChop interpolated, which doesn't match my experiences.

For me LV8729 with 64 or 128 usteps is nearly silent but my TMC2130 in interpolated StealthChop mode are still more silent.

May be you have another problem with your TMC2100s, or eventually TMC2130 is better than TMC2100? Are you sure they are interpolating? I think there are several conditions that can prevent this mode from working in an optimal way. I usually use slow acceleration (1000) but fairly high speeds (e.g. 100-120 for printing and 200 for traveling). I also use low current settings which matches well with the slow acceleration. May be they switch modes when becoming hot. I heard, in some situations they may even skip steps and take a large step instead, which results in random noise. I think, TMC drivers have very complex behavior. You need to keep them happy = inside certain limits.

But I am still not experienced with these drivers. My time goes elsewhere nowadays.

luxflow commented 6 years ago

oh sorry I have typo in my previous comment, I used spreadcycle not stealchop, 64 lv8729 is more silent than 128 spreadcycle and I used stealchop long long time ago, and as I remember it was extremely silent, so 128 stealchop is more silient than 64 lv8729 (IMHO)

hg42 commented 6 years ago

I agree :-) They should have used more different names.

If something would force me to use SpreadCycle, I would certainly choose LV8729 instead. They also have better current ratings (if I remember correctly).

gdachs commented 6 years ago

So a STM32F103 just for X and Y would be great on a CoreXY.