search

Klipper3d / klipper

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

printer stops due MCU shutdown #102

Closed TylonHH closed 6 years ago

TylonHH commented 6 years ago

Hi, I used klipper for few days without any big trouble. Today I had a bigger print about 6hr. Nearly exact 50% the print stops. Coincidence? It seems that the hot parts (hotend, bed) wear still aktive and shut down by the plugin HeaterTimeout Now I have to find out what I can do better to prevent this.

Here is my setup and all my logs. Raspberry Pi 3 Ramps 1.4 Atmega 2560 Octoprint 1.3.6

printer.cfg.txt (renamed for uploading) gcode.zip klippy.log config.txt make menuconfig

Read 578996.910328: 'N655899 G1 X181.374 Y165.535 E0.8396*97\n'
Read 578996.913739: 'N655900 G1 X181.155 Y165.315 E0.8489*108\n'
Read 578996.917324: 'N655901 G1 X181.045 Y165.771 E0.8629*99\n'
gcode state: absolutecoord=True absoluteextrude=True base_position=[-0.0, -0.0, -0.0, 19152.017599995204] last_position=[181.045, 165.771, 47.4, 19152.880499995204] homing_add=[0.0, 0.0, 0.0, 0.0] speed=56.0
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.

and here the graphical debug of the mcu, wich looks ok for me. loadgraph

Luckily I could finish my print, because the Z high was logged.

But this situation now its not reliable for me. How can I found the reason? I also reconized one time, a print stop for an half second while I reloaded the weppage of octoprint.

dragonnn commented 6 years ago

I also reconized one time, a print stop for an half second while I reloaded the webpage of octoprint.

This is octoprint problem - the g-code reader/sender isn't running in separate thread. Yeah this is ridiculous but they claim they can not change it because backwards compatibly. And this happened in my case on Marlin

TylonHH commented 6 years ago

the print stops at this high. As you can see there was nothing special what he not printed before failed

TylonHH commented 6 years ago

Added Inforamtion: I use 5V TMC2100 without jumper, so they are in spreadCycle with 16µ steps with interpolated 256

TylonHH commented 6 years ago

56

I changed for an workaround in the src/avr/timer.c from timer_set(timer_get() + 50); to timer_set(timer_get() + 100);

But I still have to test it

KevinOConnor commented 6 years ago

On Fri, Jan 05, 2018 at 09:28:59PM +0000, TylonHH wrote:

Hi, I used klipper for few days without any big trouble. Today I had a bigger print about 6hr. Nearly exact 50% the print stops. Coincidence? It seems that the hot parts (hotend, bed) wear still aktive and shut down by the plugin HeaterTimeout Now I have to find out what I can do better to prevent this.

Here is my setup and all my logs. Raspberry Pi 3 Ramps 1.4 Atmega 2560 Octoprint 1.3.6

Unfortunately, you've hit a bug in the Klipper pressure advance code. The bug was triggered by some odd g-code in your print:

G1 X182.900 Y157.467 E4.1681 F1050 ; outer perimeter G1 X182.900 Y157.468 E4.1861 F2100

In the second move above, Klipper was instructed to extrude 18 microns of filament while moving the Y axis 1 micron. This exceeds the max_extrude_cross_section check, but the Klipper host code allowed it because it is a small amount of filament (18 microns). Unfortunately, the pressure advance code interpretted it as a spike in extruder flow rate and subsequently amplified the spike to "account for pressure".

When the massive extruder movement arrived at the micro-controller, the mcu detected the excessive stepper movement and properly raised an error.

I'll put together a fix. In the interim, you could disable pressure advance.

I changed for an workaround in the src/avr/timer.c from timer_set(timer_get() + 50); to timer_set(timer_get() + 100);

But I still have to test it

The above change is not necessary (or desirable). The mcu code worked as expected. I would revert to the standard code for any future prints.

-Kevin

TylonHH commented 6 years ago

I'm glad to hear that you found the issue. Very impressing to see that in a gcode. I disabled pressure advance, maybe its also better with my bowden setup.

gdachs commented 6 years ago

Seeing this bug I got the idea that I might be a good idea to collect gcode sequences like this. If I would be you, I would setup a jenkins job with klipper and simulavr that could do regression tests with gcode files like this.

TylonHH commented 6 years ago

Today I tried to print a similar object. A case just with diffenrent letters as a relif. And it failed too. But this time I guess its not an klipper issue. So I review the timelaps, the gcode and the klipper log.

A picture of the mess img_0306

Here is the loadgraph loadgraph Look fine to me

the timelaps (Youtube Video, 2 minutes) klippy.log.txt (12MB) printer.cfg.txt (9MB)

What I found out so far: it seems that the Z achse fall a little bit or moved more then it should. Check out the timelaps video and look at the bottom of the object. But what is realy strange, is that what I found when I looked to Octoprint. I saw this screen screenshot-2018-1-6 octoprint You see, that it seems that Octoprint hang or kinda stopped.

Im not familiar to setup a jenkins job with klipper.

I hope it was just my Z achse. I'll remount it.

gdachs commented 6 years ago

sorry, my post was for KevinOConnor, not for you TylonHH

KevinOConnor commented 6 years ago

The shutdown due to pressure_advance bug should be fixed as of commit f6f174ab3.

EDIT: Note the fix is for pressure_advance.

C4g2 commented 6 years ago

Im getting a similar yet different error. I got everything installed and even printed up a calibration cube with Klipper! About the end of my very next print, printer just restart itself and has started throwing these errors. Now when I tell octoprint to start a file, it seems like it trys to pre-heat the bed, then just resets after a few seconds, I'm at a bit of a loss here.

Recv: // Lost communication with MCU 'mcu'
Recv: // Once the underlying issue is corrected, use the
Recv: // "FIRMWARE_RESTART" command to reset the firmware, reload the
Recv: // config, and restart the host software.
Recv: !! Printer is shutdown

I've tried attaching klippy.log but it wont take it, next post will be the file, so sorry for long post

C4g2 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-74-g83e9e92-dirty'
CPU: 4 core ARMv7 Processor rev 5 (v7l)
Python: '2.7.9 (default, Sep 17 2016, 20:26:04) \n[GCC 4.9.2]'
Start printer at Thu Jan 11 05:53:11 2018 (1515671591.7 1226.2)
===== Config file =====
[stepper_x]
step_pin = PD7
dir_pin = PC5
enable_pin = !PD6
step_distance = .01
endstop_pin = ^!PC2
position_endstop = -30
position_max = 220
position_min = -30
homing_speed = 50

[stepper_y]
step_pin = PC6
dir_pin = PC7
enable_pin = !PD6
step_distance = .01
endstop_pin = ^!PC3
position_endstop = -8
position_min = -8
position_max = 220
homing_speed = 50

[stepper_z]
step_pin = PB3
dir_pin = !PB2
enable_pin = !PA5
step_distance = 0.0025
endstop_pin = ^!PC4
position_endstop = 0.5
position_max = 240
homing_speed = 20

[extruder]
step_pin = PB1
dir_pin = PB0
enable_pin = !PD6
step_distance = .0105
nozzle_diameter = 0.400
filament_diameter = 1.750
heater_pin = PD5
sensor_type = ATC Semitec 104GT-2
sensor_pin = PA7
control = pid
pid_kp = 2.151492
pid_ki = 0.633897
pid_kd = 230.042965
min_temp = 0
max_temp = 250

[heater_bed]
heater_pin = PD4
sensor_type = ATC Semitec 104GT-2
sensor_pin = PA6
control = watermark
min_temp = 0
max_temp = 130

[fan]
pin = PB4

[mcu]
serial = /dev/ttyUSB0
baud = 57600

[printer]
kinematics = cartesian
max_velocity = 300
max_accel = 1000
max_z_velocity = 20
max_z_accel = 100

=======================
Starting serial connect
Loaded 51 commands (v0.5.0-74-g83e9e92-dirty-20180107_131101-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=57600 MCU=atmega1284p STATS_SUMSQ_BASE=256
Sending MCU 'mcu' printer configuration...
Configured MCU 'mcu' (1024 moves)
Stats 1228.6: gcodein=0 print_time=2.332 buffer_time=0.250 print_stall=0 mcu: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=786 bytes_read=2501 bytes_retransmit=27 bytes_invalid=0 send_seq=97 receive_seq=85 retransmit_seq=2 srtt=0.007 rttvar=0.002 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=15999662
Stats 1229.6: gcodein=0 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=792 bytes_read=2577 bytes_retransmit=27 bytes_invalid=0 send_seq=98 receive_seq=98 retransmit_seq=2 srtt=0.008 rttvar=0.003 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000235
Stats 1230.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.000 mcu_task_avg=0.000000 mcu_task_stddev=0.000000 bytes_write=798 bytes_read=2697 bytes_retransmit=27 bytes_invalid=0 send_seq=99 receive_seq=99 retransmit_seq=2 srtt=0.008 rttvar=0.002 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000324
Stats 1231.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.022 mcu_task_avg=0.000150 mcu_task_stddev=0.000585 bytes_write=804 bytes_read=2806 bytes_retransmit=27 bytes_invalid=0 send_seq=100 receive_seq=100 retransmit_seq=2 srtt=0.007 rttvar=0.002 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000426
Stats 1232.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.022 mcu_task_avg=0.000150 mcu_task_stddev=0.000585 bytes_write=810 bytes_read=2900 bytes_retransmit=27 bytes_invalid=0 send_seq=101 receive_seq=101 retransmit_seq=2 srtt=0.007 rttvar=0.002 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000501
Stats 1233.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.022 mcu_task_avg=0.000150 mcu_task_stddev=0.000585 bytes_write=816 bytes_read=3020 bytes_retransmit=27 bytes_invalid=0 send_seq=102 receive_seq=102 retransmit_seq=2 srtt=0.007 rttvar=0.002 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000517
Stats 1234.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.022 mcu_task_avg=0.000150 mcu_task_stddev=0.000585 bytes_write=822 bytes_read=3114 bytes_retransmit=27 bytes_invalid=0 send_seq=103 receive_seq=103 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000530
Stats 1235.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.022 mcu_task_avg=0.000150 mcu_task_stddev=0.000585 bytes_write=828 bytes_read=3208 bytes_retransmit=27 bytes_invalid=0 send_seq=104 receive_seq=104 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000544
Stats 1236.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.005 mcu_task_avg=0.000063 mcu_task_stddev=0.000053 bytes_write=834 bytes_read=3341 bytes_retransmit=27 bytes_invalid=0 send_seq=105 receive_seq=105 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000569
Stats 1237.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.005 mcu_task_avg=0.000063 mcu_task_stddev=0.000053 bytes_write=840 bytes_read=3435 bytes_retransmit=27 bytes_invalid=0 send_seq=106 receive_seq=106 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000569
Stats 1238.6: gcodein=51 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.005 mcu_task_avg=0.000063 mcu_task_stddev=0.000053 bytes_write=846 bytes_read=3529 bytes_retransmit=27 bytes_invalid=0 send_seq=107 receive_seq=107 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000583
Stats 1239.6: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.005 mcu_task_avg=0.000063 mcu_task_stddev=0.000053 bytes_write=852 bytes_read=3656 bytes_retransmit=27 bytes_invalid=0 send_seq=108 receive_seq=108 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000591
Stats 1240.6: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.005 mcu_task_avg=0.000063 mcu_task_stddev=0.000053 bytes_write=858 bytes_read=3757 bytes_retransmit=27 bytes_invalid=0 send_seq=109 receive_seq=109 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000591
Stats 1241.6: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.005 mcu_task_avg=0.000063 mcu_task_stddev=0.000053 bytes_write=864 bytes_read=3858 bytes_retransmit=27 bytes_invalid=0 send_seq=110 receive_seq=110 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000587
Stats 1242.6: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000057 bytes_write=870 bytes_read=4000 bytes_retransmit=27 bytes_invalid=0 send_seq=111 receive_seq=111 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000586
Stats 1243.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000057 bytes_write=876 bytes_read=4101 bytes_retransmit=27 bytes_invalid=0 send_seq=112 receive_seq=112 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000586
Stats 1244.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000057 bytes_write=882 bytes_read=4202 bytes_retransmit=27 bytes_invalid=0 send_seq=113 receive_seq=113 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000587
Stats 1245.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000057 bytes_write=888 bytes_read=4331 bytes_retransmit=27 bytes_invalid=0 send_seq=114 receive_seq=114 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000595
Stats 1246.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000067 mcu_task_stddev=0.000057 bytes_write=894 bytes_read=4432 bytes_retransmit=27 bytes_invalid=0 send_seq=115 receive_seq=115 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000594
Stats 1247.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=900 bytes_read=4546 bytes_retransmit=27 bytes_invalid=0 send_seq=116 receive_seq=116 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000594
Stats 1248.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=906 bytes_read=4675 bytes_retransmit=27 bytes_invalid=0 send_seq=117 receive_seq=117 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000598
Stats 1249.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=912 bytes_read=4776 bytes_retransmit=27 bytes_invalid=0 send_seq=118 receive_seq=118 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000597
Stats 1250.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=918 bytes_read=4877 bytes_retransmit=27 bytes_invalid=0 send_seq=119 receive_seq=119 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000597
Stats 1251.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=924 bytes_read=5006 bytes_retransmit=27 bytes_invalid=0 send_seq=120 receive_seq=120 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000597
Stats 1252.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=930 bytes_read=5120 bytes_retransmit=27 bytes_invalid=0 send_seq=121 receive_seq=121 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000597
Stats 1253.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=936 bytes_read=5221 bytes_retransmit=27 bytes_invalid=0 send_seq=122 receive_seq=122 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000597
Stats 1254.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=942 bytes_read=5350 bytes_retransmit=27 bytes_invalid=0 send_seq=123 receive_seq=123 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000599
Stats 1255.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=948 bytes_read=5451 bytes_retransmit=27 bytes_invalid=0 send_seq=124 receive_seq=124 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000599
Stats 1256.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=954 bytes_read=5552 bytes_retransmit=27 bytes_invalid=0 send_seq=125 receive_seq=125 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000602
Stats 1257.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=960 bytes_read=5694 bytes_retransmit=27 bytes_invalid=0 send_seq=126 receive_seq=126 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000604
Stats 1258.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=966 bytes_read=5795 bytes_retransmit=27 bytes_invalid=0 send_seq=127 receive_seq=127 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000604
Stats 1259.7: gcodein=58 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=972 bytes_read=5896 bytes_retransmit=27 bytes_invalid=0 send_seq=128 receive_seq=128 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000605
Stats 1260.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=978 bytes_read=6025 bytes_retransmit=27 bytes_invalid=0 send_seq=129 receive_seq=129 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000606
Stats 1261.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=984 bytes_read=6126 bytes_retransmit=27 bytes_invalid=0 send_seq=130 receive_seq=130 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000606
Stats 1262.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=990 bytes_read=6240 bytes_retransmit=27 bytes_invalid=0 send_seq=131 receive_seq=131 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000606
Stats 1263.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=996 bytes_read=6369 bytes_retransmit=27 bytes_invalid=0 send_seq=132 receive_seq=132 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000606
Stats 1264.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1002 bytes_read=6470 bytes_retransmit=27 bytes_invalid=0 send_seq=133 receive_seq=133 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000607
Stats 1265.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1008 bytes_read=6571 bytes_retransmit=27 bytes_invalid=0 send_seq=134 receive_seq=134 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000609
Stats 1266.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1014 bytes_read=6700 bytes_retransmit=27 bytes_invalid=0 send_seq=135 receive_seq=135 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000609
Stats 1267.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000069 mcu_task_stddev=0.000060 bytes_write=1020 bytes_read=6814 bytes_retransmit=27 bytes_invalid=0 send_seq=136 receive_seq=136 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000610
Stats 1268.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000069 mcu_task_stddev=0.000060 bytes_write=1026 bytes_read=6915 bytes_retransmit=27 bytes_invalid=0 send_seq=137 receive_seq=137 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000610
Stats 1269.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000069 mcu_task_stddev=0.000060 bytes_write=1032 bytes_read=7044 bytes_retransmit=27 bytes_invalid=0 send_seq=138 receive_seq=138 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000611
Stats 1270.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000069 mcu_task_stddev=0.000060 bytes_write=1038 bytes_read=7145 bytes_retransmit=27 bytes_invalid=0 send_seq=139 receive_seq=139 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000611
Stats 1271.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000069 mcu_task_stddev=0.000060 bytes_write=1044 bytes_read=7246 bytes_retransmit=27 bytes_invalid=0 send_seq=140 receive_seq=140 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000611
Stats 1272.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1050 bytes_read=7388 bytes_retransmit=27 bytes_invalid=0 send_seq=141 receive_seq=141 retransmit_seq=2 srtt=0.008 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000612
Stats 1273.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1056 bytes_read=7489 bytes_retransmit=27 bytes_invalid=0 send_seq=142 receive_seq=142 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000612
Stats 1274.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1062 bytes_read=7590 bytes_retransmit=27 bytes_invalid=0 send_seq=143 receive_seq=143 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000613
Stats 1275.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1068 bytes_read=7719 bytes_retransmit=27 bytes_invalid=0 send_seq=144 receive_seq=144 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000614
Stats 1276.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1074 bytes_read=7820 bytes_retransmit=27 bytes_invalid=0 send_seq=145 receive_seq=145 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000614
Stats 1277.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=1080 bytes_read=7934 bytes_retransmit=27 bytes_invalid=0 send_seq=146 receive_seq=146 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000614
Stats 1278.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=1086 bytes_read=8063 bytes_retransmit=27 bytes_invalid=0 send_seq=147 receive_seq=147 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000615
Stats 1279.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=1092 bytes_read=8164 bytes_retransmit=27 bytes_invalid=0 send_seq=148 receive_seq=148 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000615
Stats 1280.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=1098 bytes_read=8265 bytes_retransmit=27 bytes_invalid=0 send_seq=149 receive_seq=149 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000616
Stats 1281.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000060 bytes_write=1104 bytes_read=8394 bytes_retransmit=27 bytes_invalid=0 send_seq=150 receive_seq=150 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000616
Stats 1282.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1110 bytes_read=8508 bytes_retransmit=27 bytes_invalid=0 send_seq=151 receive_seq=151 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000618
Stats 1283.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1116 bytes_read=8609 bytes_retransmit=27 bytes_invalid=0 send_seq=152 receive_seq=152 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000619
Stats 1284.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1122 bytes_read=8738 bytes_retransmit=27 bytes_invalid=0 send_seq=153 receive_seq=153 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000620
Stats 1285.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1128 bytes_read=8839 bytes_retransmit=27 bytes_invalid=0 send_seq=154 receive_seq=154 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000621
Stats 1286.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1134 bytes_read=8954 bytes_retransmit=27 bytes_invalid=0 send_seq=155 receive_seq=155 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000621
Stats 1287.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1140 bytes_read=9082 bytes_retransmit=27 bytes_invalid=0 send_seq=156 receive_seq=156 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000622
Stats 1288.7: gcodein=63 print_time=2.332 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000061 bytes_write=1146 bytes_read=9183 bytes_retransmit=27 bytes_invalid=0 send_seq=157 receive_seq=157 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000622
Stats 1297.7: gcodein=106 print_time=71.314 buffer_time=0.182 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1200 bytes_read=10202 bytes_retransmit=27 bytes_invalid=0 send_seq=166 receive_seq=166 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000626
Stats 1298.7: gcodein=106 print_time=72.316 buffer_time=0.183 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1219 bytes_read=10322 bytes_retransmit=27 bytes_invalid=0 send_seq=168 receive_seq=168 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000626
Stats 1299.7: gcodein=106 print_time=73.318 buffer_time=0.184 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1225 bytes_read=10437 bytes_retransmit=27 bytes_invalid=0 send_seq=169 receive_seq=169 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000627
Stats 1300.7: gcodein=106 print_time=74.320 buffer_time=0.185 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1231 bytes_read=10538 bytes_retransmit=27 bytes_invalid=0 send_seq=170 receive_seq=170 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000627
Stats 1301.7: gcodein=106 print_time=75.322 buffer_time=0.187 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1237 bytes_read=10653 bytes_retransmit=27 bytes_invalid=0 send_seq=171 receive_seq=171 retransmit_seq=2 srtt=0.007 rttvar=0.000 rto=0.025 ready_bytes=0 stalled_bytes=0 freq=16000630
Got error 0 in read: (0)Success
Stats 1302.7: gcodein=106 print_time=76.324 buffer_time=0.188 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=1 freq=16000630
Stats 1303.7: gcodein=106 print_time=77.326 buffer_time=0.189 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=2 freq=16000630
Stats 1304.7: gcodein=106 print_time=78.328 buffer_time=0.190 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=3 freq=16000630
Timeout with MCU 'mcu' (eventtime=1305.691591)
Dumping gcode input 9 blocks
Read 1229.822342: 'N0 M110 N0*125\n'
Read 1229.833236: 'N0 M110 N0*125\n'
Read 1229.839766: 'N1 M115*39\n'
Read 1229.846549: 'N2 M21*18\n'
Read 1239.466095: 'status\n'
Read 1259.839209: 'M105\n'
Read 1289.841695: 'M105\n'
Read 1297.609311: 'N0 M110 N0*125\n'
Read 1297.616416: 'N1 M190 S50.000000*114\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
Stats 1305.7: gcodein=106 print_time=79.142 buffer_time=0.003 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=5 freq=16000630
Lost communication with MCU 'mcu'
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

Lost communication with MCU 'mcu'
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

Timeout with MCU 'mcu' (eventtime=1306.693440)
Stats 1306.7: gcodein=139 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=6 freq=16000630
Lost communication with MCU 'mcu'
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

Lost communication with MCU 'mcu'
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

Lost communication with MCU 'mcu'
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

Lost communication with MCU 'mcu'
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

Timeout with MCU 'mcu' (eventtime=1307.693770)
Stats 1307.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=7 freq=16000630
Timeout with MCU 'mcu' (eventtime=1308.694399)
Stats 1308.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=8 freq=16000630
Timeout with MCU 'mcu' (eventtime=1309.695389)
Stats 1309.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=9 freq=16000630
Timeout with MCU 'mcu' (eventtime=1310.696488)
Stats 1310.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=10 freq=16000630
Timeout with MCU 'mcu' (eventtime=1311.697175)
Stats 1311.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=11 freq=16000630
Timeout with MCU 'mcu' (eventtime=1312.697596)
Stats 1312.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=12 freq=16000630
Timeout with MCU 'mcu' (eventtime=1313.698134)
Stats 1313.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=13 freq=16000630
Timeout with MCU 'mcu' (eventtime=1314.698723)
Stats 1314.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=14 freq=16000630
Timeout with MCU 'mcu' (eventtime=1315.699442)
Stats 1315.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=15 freq=16000630
Timeout with MCU 'mcu' (eventtime=1316.700273)
Stats 1316.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=16 freq=16000630
Timeout with MCU 'mcu' (eventtime=1317.701284)
Stats 1317.7: gcodein=170 print_time=79.142 buffer_time=0.000 print_stall=0 mcu: mcu_awake=0.006 mcu_task_avg=0.000070 mcu_task_stddev=0.000059 bytes_write=1250 bytes_read=10714 bytes_retransmit=27 bytes_invalid=0 send_seq=172 receive_seq=172 retransmit_seq=2 srtt=0.007 rttvar=0.001 rto=0.025 ready_bytes=0 stalled_bytes=17 freq=16000630
KevinOConnor commented 6 years ago

On Thu, Jan 11, 2018 at 12:01:23PM +0000, C4g2 wrote:

Im getting a similar yet different error. I got everything installed and even printed up a calibration cube with Klipper! About the end of my very next print, printer just restart itself and has started throwing these errors. Now when I tell octoprint to start a file, it seems like it trys to pre-heat the bed, then just resets after a few seconds, I'm at a bit of a loss here.

Recv: // Lost communication with MCU 'mcu'
Recv: // Once the underlying issue is corrected, use the
Recv: // "FIRMWARE_RESTART" command to reset the firmware, reload the
Recv: // config, and restart the host software.
Recv: !! Printer is shutdown

The above error is unrelated to this github issue. The above is a communication outage - check your cabling and power supply to the micro-controller.

Also, not related to your error, but you do not want to use 57600 baud for the main Klipper data protocol - you should go back to the default 250000 baud rate.

-Kevin

C4g2 commented 6 years ago

Good to know its not software related and too have a place to start looking. Also thanks for the config tip!