Communication stuck

What is the problem?
On Octoprint 1.3.12, my printer fails every ~second print because of communication issues.

OctoPrint introduced detection of endless resend requests in 1.3.12, but I'm not sure if it takes the firmware setting "ignore consecutive resend requests of the same line" into account. It looks like it doesn't and thus disconnects my printer, failing the print. Also, OctoPrint reacted to every resend request by sending the line again, seemingly ignoring the firmware setting again.

From the logs of a correctly resolved communication issue it looks like it takes a little above a half second before the printer acknowledges that the line was sent successfully. During this time, the resend request flood already triggered the new feature in 13.12, disconnecting the printer.

Note: Most resend requests get resolved in a few milliseconds, I'm not sure why occasionally it takes so long. Is it normal to have ~1000 resend requests over ~ 1.6 million lines or is that too high?

What did you already try to solve it?

Changing the power source of the raspberry, changing the data cable between raspberry and arduino.
Switching firmware detection between automatic and selecting the features manually.
Next i'm trying to downgrade to 1.3.11 and look if it prints normally (it did in the past).

Logs (octoprint.log, serial.log or output on terminal tab at a minimum, browser error console if UI issue ... no logs, no support!)

Here is an example communication issue which lead to a failed print:
serial.log:

2019-12-07 10:24:36,035 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:36,080 - Recv: T:206.10 /206 B:50.00 /50 B@:32 @:170 T0:206.10 /206 @0:170 T1:-20.00 /0 @1:0
2019-12-07 10:24:36,313 - Recv: ok 11414
2019-12-07 10:24:36,319 - Send: N11418 G1 X222.797 Y144.639*34
2019-12-07 10:24:36,326 - Recv: ok 11415
2019-12-07 10:24:39,619 - Recv: ok 11416
2019-12-07 10:24:39,634 - Recv: Error:Wrong checksum
2019-12-07 10:24:39,634 - Send: N11419 G1 X221.579 Y143.615*43
2019-12-07 10:24:39,638 - Recv: 
2019-12-07 10:24:39,645 - Send: N11420 G1 X223.135 Y141.723*41
2019-12-07 10:24:39,655 - Recv: Resend:11417
2019-12-07 10:24:39,673 - Recv: ok
2019-12-07 10:24:39,677 - Recv: 
2019-12-07 10:24:39,681 - Recv: Resend:11417
2019-12-07 10:24:39,682 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,730 - Recv: ok
2019-12-07 10:24:39,734 - Recv: 
2019-12-07 10:24:39,738 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,751 - Recv: Resend:11417
2019-12-07 10:24:39,768 - Recv: ok
2019-12-07 10:24:39,773 - Recv: 
2019-12-07 10:24:39,773 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,775 - Recv: Resend:11417
2019-12-07 10:24:39,792 - Recv: ok
2019-12-07 10:24:39,794 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,795 - Recv: T:205.76 /206 B:50.00 /50 B@:27 @:170 T0:205.76 /206 @0:170 T1:-20.00 /0 @1:0
2019-12-07 10:24:39,803 - Recv: 
2019-12-07 10:24:39,806 - Recv: Resend:11417
2019-12-07 10:24:39,808 - Recv: ok
2019-12-07 10:24:39,810 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,810 - Recv: 
2019-12-07 10:24:39,813 - Recv: Resend:11417
2019-12-07 10:24:39,819 - Recv: ok
2019-12-07 10:24:39,821 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,823 - Recv: 
2019-12-07 10:24:39,825 - Recv: Resend:11417
2019-12-07 10:24:39,831 - Recv: ok
2019-12-07 10:24:39,834 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,836 - Recv: 
2019-12-07 10:24:39,838 - Recv: Resend:11417
2019-12-07 10:24:39,840 - Recv: ok
2019-12-07 10:24:39,842 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,843 - Recv: 
2019-12-07 10:24:39,846 - Recv: Resend:11417
2019-12-07 10:24:39,849 - Recv: ok
2019-12-07 10:24:39,851 - Recv: 
2019-12-07 10:24:39,851 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,853 - Recv: Resend:11417
2019-12-07 10:24:39,857 - Recv: ok
2019-12-07 10:24:39,859 - Recv: T:205.76 /206 B:50.00 /50 B@:30 @:170 T0:205.76 /206 @0:170 T1:-20.00 /0 @1:0
2019-12-07 10:24:39,866 - Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,868 - Recv: 
2019-12-07 10:24:39,871 - Recv: Resend:11417
2019-12-07 10:24:39,872 - Printer keeps requesting line 11417 again and again, communication stuck
2019-12-07 10:24:39,875 - Changing monitoring state from "Printing" to "Error: Printer keeps requesting line 11417 again and again, communication stuck"
2019-12-07 10:24:39,884 - Send: M112
2019-12-07 10:24:39,887 - Send: N11421 M112*38
2019-12-07 10:24:39,889 - Send: N11422 M104 T0 S0*37
2019-12-07 10:24:39,908 - Send: N11423 M104 T1 S0*37
2019-12-07 10:24:39,911 - Send: N11424 M140 S0*103
2019-12-07 10:24:39,920 - Changing monitoring state from "Error: Printer keeps requesting line 11417 again and again, communication stuck" to "Offline (Error: Printer keeps requesting line 11417 again and again, communication stuck)"
2019-12-07 10:24:39,940 - Connection closed, closing down monitor

Here is the corresponding octoprint.log:

2019-12-07 10:24:39,664 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 11417, current line = 11421
| Last lines in terminal:
| Send: :G90
| Send: N11413 G1 Z2.985 F360*47
| Recv: ok 11411
| Send: N11414 G1 X202.832 Y158.045 F3600*79
| Recv: ok 11412
| Send: N11415 G1 X202.283 Y156.053*36
| Recv: ok 11412
| Send: N11416 G1 X204.637 Y155.409*34
| Recv: ok 11413
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: T:206.10 /206 B:50.00 /50 B@:32 @:170 T0:206.10 /206 @0:170 T1:-20.00 /0 @1:0
| Recv: ok 11414
| Send: N11418 G1 X222.797 Y144.639*34
| Recv: ok 11415
| Recv: ok 11416
| Send: N11419 G1 X221.579 Y143.615*43
| Recv: Error:Wrong checksum
| Recv: 
| Send: N11420 G1 X223.135 Y141.723*41
| Recv: Resend:11417
2019-12-07 10:24:39,685 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 11417, current line = 11421
| Last lines in terminal:
| Recv: ok 11412
| Send: N11415 G1 X202.283 Y156.053*36
| Recv: ok 11412
| Send: N11416 G1 X204.637 Y155.409*34
| Recv: ok 11413
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: T:206.10 /206 B:50.00 /50 B@:32 @:170 T0:206.10 /206 @0:170 T1:-20.00 /0 @1:0
| Recv: ok 11414
| Send: N11418 G1 X222.797 Y144.639*34
| Recv: ok 11415
| Recv: ok 11416
| Send: N11419 G1 X221.579 Y143.615*43
| Recv: Error:Wrong checksum
| Recv: 
| Send: N11420 G1 X223.135 Y141.723*41
| Recv: Resend:11417
| Recv: ok
| Recv: 
| Recv: Resend:11417
| Send: N11417 G1 X204.969 Y156.610*46
2019-12-07 10:24:39,753 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 11417, current line = 11421
| Last lines in terminal:
| Recv: ok 11413
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: T:206.10 /206 B:50.00 /50 B@:32 @:170 T0:206.10 /206 @0:170 T1:-20.00 /0 @1:0
| Recv: ok 11414
| Send: N11418 G1 X222.797 Y144.639*34
| Recv: ok 11415
| Recv: ok 11416
| Send: N11419 G1 X221.579 Y143.615*43
| Recv: Error:Wrong checksum
| Recv: 
| Send: N11420 G1 X223.135 Y141.723*41
| Recv: Resend:11417
| Recv: ok
| Recv: 
| Recv: Resend:11417
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: ok
| Recv: 
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: Resend:11417
2019-12-07 10:24:39,788 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 11417, current line = 11421
| Last lines in terminal:
| Send: N11418 G1 X222.797 Y144.639*34
| Recv: ok 11415
| Recv: ok 11416
| Send: N11419 G1 X221.579 Y143.615*43
| Recv: Error:Wrong checksum
| Recv: 
| Send: N11420 G1 X223.135 Y141.723*41
| Recv: Resend:11417
| Recv: ok
| Recv: 
| Recv: Resend:11417
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: ok
| Recv: 
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: Resend:11417
| Recv: ok
| Recv: 
| Send: N11417 G1 X204.969 Y156.610*46
| Recv: Resend:11417
2019-12-07 10:24:39,873 - octoprint.util.comm - WARNING - Printer keeps requesting line 11417 again and again, communication stuck
2019-12-07 10:24:39,875 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Error: Printer keeps requesting line 11417 again and again, communication stuck"
2019-12-07 10:24:39,882 - octoprint.util.comm - INFO - Force-sending M112 to the printer
2019-12-07 10:24:39,921 - octoprint.util.comm - INFO - Changing monitoring state from "Error: Printer keeps requesting line 11417 again and again, communication stuck" to "Offline (Error: Printer keeps requesting line 11417 again and again, communication stuck)"
2019-12-07 10:24:40,150 - tornado.access - WARNING - 409 GET /api/printer (127.0.0.1) 24.24ms
2019-12-07 10:24:40,445 - octoprint.util.comm - INFO - Firmware didn't send an 'ok' with their resend request. That's a known bug with some firmware variants out there. Simulating an ok to continue...

The following shows a communication issue which gets resolved correctly:

2019-12-06 13:11:58,502 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:11:59,056 - Recv: ok 33415
2019-12-06 13:11:59,061 - Send: N33419 G1 X193.609 Y35.551 E0.1272*107
2019-12-06 13:11:59,064 - Recv: X_OFFSET:0.000 Y_OFFSET:0.000 Z_OFFSET:0.000
2019-12-06 13:11:59,066 - Recv: ok 33416
2019-12-06 13:11:59,070 - Send: N33420 G1 X194.487 Y36.503 E0.1589*101
2019-12-06 13:11:59,143 - Recv: T:204.58 /206 B:50.00 /50 B@:23 @:160 T0:204.58 /206 @0:160 T1:-20.00 /0 @1:0
2019-12-06 13:11:59,252 - Recv: ok 33417
2019-12-06 13:11:59,258 - Send: N33421 G1 X196.414 Y38.661 E0.2296*111
2019-12-06 13:11:59,355 - Recv: Error:Wrong checksum
2019-12-06 13:11:59,361 - Recv: 
2019-12-06 13:12:00,791 - Recv: Resend:33418
2019-12-06 13:12:00,811 - Recv: ok
2019-12-06 13:12:00,842 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,849 - Recv: Error:Wrong checksum
2019-12-06 13:12:00,858 - Recv: 
2019-12-06 13:12:00,865 - Recv: Resend:33418
2019-12-06 13:12:00,872 - Recv: ok
2019-12-06 13:12:00,877 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,881 - Recv: 
2019-12-06 13:12:00,887 - Recv: Resend:33418
2019-12-06 13:12:00,892 - Recv: ok
2019-12-06 13:12:00,896 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,898 - Recv: 
2019-12-06 13:12:00,900 - Recv: Resend:33418
2019-12-06 13:12:00,902 - Recv: ok
2019-12-06 13:12:00,904 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,906 - Recv: 
2019-12-06 13:12:00,907 - Recv: Resend:33418
2019-12-06 13:12:00,909 - Recv: ok
2019-12-06 13:12:00,911 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,912 - Recv: 
2019-12-06 13:12:00,914 - Recv: Resend:33418
2019-12-06 13:12:00,915 - Recv: ok
2019-12-06 13:12:00,918 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,919 - Recv: T:204.75 /206 B:50.00 /50 B@:23 @:160 T0:204.75 /206 @0:160 T1:-20.00 /0 @1:0
2019-12-06 13:12:00,927 - Recv: 
2019-12-06 13:12:00,928 - Recv: Resend:33418
2019-12-06 13:12:00,930 - Recv: ok
2019-12-06 13:12:00,932 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,933 - Recv: 
2019-12-06 13:12:00,934 - Recv: Resend:33418
2019-12-06 13:12:00,937 - Recv: ok
2019-12-06 13:12:00,938 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,940 - Recv: 
2019-12-06 13:12:00,941 - Recv: Resend:33418
2019-12-06 13:12:00,943 - Recv: ok
2019-12-06 13:12:00,945 - Send: N33418 G1 X192.959 Y34.880 E0.1044*102
2019-12-06 13:12:00,947 - Recv: ok 33418
2019-12-06 13:12:00,949 - Send: N33419 G1 X193.609 Y35.551 E0.1272*107
2019-12-06 13:12:00,950 - Recv: skip 33418
2019-12-06 13:12:00,951 - Recv: ok
2019-12-06 13:12:00,953 - Send: N33420 G1 X194.487 Y36.503 E0.1589*101
2019-12-06 13:12:00,955 - Recv: skip 33418
2019-12-06 13:12:00,956 - Recv: ok
2019-12-06 13:12:00,958 - Send: N33421 G1 X196.414 Y38.661 E0.2296*111
2019-12-06 13:12:00,959 - Recv: skip 33418
2019-12-06 13:12:00,961 - Recv: ok
2019-12-06 13:12:00,964 - Send: N33422 G1 X198.385 Y40.970 E0.3038*106
2019-12-06 13:12:00,966 - Recv: skip 33418
2019-12-06 13:12:00,967 - Recv: ok
2019-12-06 13:12:00,970 - Send: N33423 G1 X199.480 Y42.302 E0.3460*108
2019-12-06 13:12:00,972 - Recv: skip 33418
2019-12-06 13:12:00,974 - Recv: ok
2019-12-06 13:12:00,977 - Send: N33424 G1 X200.510 Y43.600 E0.3865*111
2019-12-06 13:12:00,979 - Recv: skip 33418
2019-12-06 13:12:00,981 - Recv: ok
2019-12-06 13:12:00,983 - Send: N33425 G1 X201.442 Y44.831 E0.4242*106
2019-12-06 13:12:00,985 - Recv: skip 33418
2019-12-06 13:12:00,986 - Recv: ok
2019-12-06 13:12:00,989 - Send: N33426 G1 X202.056 Y45.692 E0.4501*109
2019-12-06 13:12:00,991 - Recv: skp 33418
2019-12-06 13:12:00,992 - Recv: ok
2019-12-06 13:12:00,995 - Send: N33427 G1 X202.647 Y46.570 E0.4759*105
2019-12-06 13:12:00,996 - Recv: ok 33419
2019-12-06 13:12:00,999 - Send: N33428 G1 X203.125 Y47.325 E0.4978*110
2019-12-06 13:12:01,000 - Recv: ok 33420

Additional information about your setup (OctoPrint version, OctoPi version, printer, firmware, browser, operating system, ... as much data as possible)
OctoPrint 1.3.12
OctoPi 0.14.0
Custom Printer: Arduino Due with RADDS Shield
Firmware: Repetier-Firmware 1.0.3

Hi!

I've checked the complained checksums, but they are ok.
It seems that the printer firmware calculates the checksum wrong under certain circumstances.
You may recompile the firmware and bring it to the board again.

1 Like

Could it also be an error while exchanging the data? So that the printer correctly identifies it as a broken line. It then sends multiple resend requests (because repetier-firmware does that). And somehow, every few hours, it takes up to a second before the printer receives and interprets the first resent line from octoprint - during which the resend requests triggered the new feature.
This is my interpretation of what's going on here, I'm not sure if it's correct. I'll try to reupload the firmware after printing for a few hours on 1.3.11.

By the way, how do you calculate the checksums? Is it using Fletcher's algorithm?

Here's the reprap section for checksums.

The checksum "cs" for a G-code string "cmd" (including its line number) is computed by exor-ing the bytes in the string up to and not including the * character

1 Like

Do we have a fix for this? My OctoP just started doing this today, and I don't understand what the fix is. I can't even get one print. I have restarted both the printer and the Pi.
EDIT: Tried a different gcode file, and it worked. Guess something was wrong with that gcode's creation? I'll post again if the problem re-occurs on any other files.

Same issue for me today. State: Offline (Error: Printer keeps requesting line 14879 again and again, communication stuck)

Hi there,

Is there a solution here?

I upgraded my prusa mk3s and octoprint to the latest software yesterday (firmware 3.9.1 and Octopi 1.4.2)

Ever since, I've had 1 successful print (9 hour print actually) but ~6-7 failed prints. The failed prints always fail within the first ~15 minutes or so.. (at various layer heights)

Failure is always the same way -

full rx buffer, followed by error: line number isnot last line number+1

Console log attached.

Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: ok
Send: N42456 G1 X106.411 Y123.823 E0.01421*111
Recv: ok
Send: N42457 G1 X106.361 Y123.952 E0.00362*104
Recv: Full RX Buffer
Recv: ok
Send: N42458 G1 X107.002 Y124.935 E0.03056*97
Recv: ok
Send: N42459 G1 X107.631 Y125.797 E0.02782*110
Recv: ok
Send: N42460 G1 X108.302 Y126.618 E0.02762*101
Recv: Error:Line Number is not Last Line Number+1, Last Line: 42453
Recv: Resend: 42454
Recv: ok
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: ok
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Error:Line Number is not Last Line Number+1, Last Line: 42453
Recv: Resend: 42454
Recv: ok
Send: N42456 G1 X106.411 Y123.823 E0.01421*111
Recv: ok
Send: N42457 G1 X106.361 Y123.952 E0.00362*104
Recv: Error:Line Number is not Last Line Number+1, Last Line: 42453
Recv: Resend: 42454
Recv: ok
Send: N42458 G1 X107.002 Y124.935 E0.03056*97
Recv: Error:No Line Number with checksum, Last Line: 42453
Recv: Resend: 42454
Send: N42459 G1 X107.631 Y125.797 E0.02782*110
Recv: ok
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Error:Line Number is not Last Line Number+1, Last Line: 42453
Recv: Resend: 42454
Recv: ok
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Error:No Line Number with checksum, Last Line: 42453
Recv: Resend: 42454
Send: N42456 G1 X106.411 Y123.823 E0.01421*111
Recv: ok
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Error:Line Number is not Last Line Number+1, Last Line: 42453
Recv: Resend: 42454
Recv: ok
Recv: Error:No Line Number with checksum, Last Line: 42453
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Send: N42456 G1 X106.411 Y123.823 E0.01421*111
Recv: Resend: 42454
Recv: ok
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Error:No Line Number with checksum, Last Line: 42453
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Resend: 42454
Recv: ok
Recv: Error:Line Number is not Last Line Number+1, Last Line: 42453
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Resend: 42454
Recv: ok
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Error:No Line Number with checksum, Last Line: 42453
Recv: Resend: 42454
Send: N42456 G1 X106.411 Y123.823 E0.01421*111
Recv: ok
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Error:No Line Number with checksum, Last Line: 42453
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Resend: 42454
Recv: ok
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Error:No Line Number with checksum, Last Line: 42453
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Resend: 42454
Recv: ok
Recv: echo:Unknown command: "97"(2)
Recv: ok
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Error:Line Number is not Last Line Number+1, Last Line: 42453
Recv: Resend: 42454
Recv: ok
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Error:No Line Number with checksum, Last Line: 42453
Recv: Resend: 42454
Send: N42456 G1 X106.411 Y123.823 E0.01421*111
Recv: ok
Send: N42457 G1 X106.361 Y123.952 E0.00362*104
Recv: Error:No Line Number with checksum, Last Line: 42453
Recv: Resend: 42454
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: ok
Send: N42455 G1 X106.722 Y123.375 E0.03195*111
Recv: Error:No Line Number with checksum, Last Line: 42453
Send: N42454 G1 X107.559 Y122.478 E0.01352*97
Recv: Resend: 42454
Printer keeps requesting line 42454 again and again, communication stuck
Changing monitoring state from "Printing" to "Error: Printer keeps requesting line 42454 again and again, communication stuck"
Send: M112
Send: N42461 M112*36
Send: N42462 M104 T0 S0*39
Send: N42463 M140 S0*98
Changing monitoring state from "Error: Printer keeps requesting line 42454 again and again, communication stuck" to "Offline (Error: Printer keeps requesting line 42454 again and again, communication stuck)"
Connection closed, closing down monitor
Closing down send loop

Full log attached. (my serial log was empty as it wasn't enabled:( octoprint.log attached)

octoprint.log (145.2 KB)

Roll back your firmware update, I've heard from several users now that they ran into problems with Prusa Firmware 3.9.1.

I have a different printer having the same issue. It fails on every print, nearly always around the 10,200th line, regardless of the item being printed. Any advice on settings or troubleshooting steps to try would be greatly appreciated. Something related to buffering, perhaps?

Gcode file executes fully off of SD card. I know the transfer speeds are much different but wanted to rule out the file itself. Just to check, I've also used different USB cables.

Octoprint version 0.18.0; Monoprice Ultimate 2 running firmware 1.6. Windows 10 x64, Chrome.

Thanks in advance for any help or insight.

Serial.log attached. serial.log (1.0 MB)

Anybody found a solution to this?
Having the same problem, it use to be occasional now is constant.
I think it started after I upgrade.

I went away after a few days and today after nozzle change.

serial.log (251.2 KB)
octoprint.log (1.8 MB)
plugin_pluginmanager_console.log (11.1 KB)
plugin_softwareupdate_console.log (71.5 KB)
octoprint-systeminfo-20210521214957.zip (252.8 KB)

2021-05-15 07:38:59,234 - octoprint.plugins.pi_support - WARNING - This Raspberry Pi is reporting problems that might lead to bad performance or errors caused by overheating or insufficient power.
!!! UNDERVOLTAGE REPORTED !!! Make sure that the power supply and power cable are capable of supplying enough voltage and current to your Pi.

You need to fix this.

2021-05-21 21:47:03,469 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 2022, current line = 2023
| Last lines in terminal:
| Recv: ok
| Send: N2022 M117 Printing PI3_3DBenchy_-_Multi-part_-_Single_-_Hull_-_3DBenchy.com - Layer 1 of 220*47
| Recv: Error:checksum mismatch, Last Line: 2021
| Recv: Resend: 2022
| Recv: ok
| Send: N2022 M117 Printing PI3_3DBenchy_-_Multi-part_-_Single_-_Hull_-_3DBenchy.com - Layer 1 of 220*47
| Recv: Error:checksum mismatch, Last Line: 2021
| Recv: Resend: 2022
| Recv: ok
| Send: N2022 M117 Printing PI3_3DBenchy_-_Multi-part_-_Single_-_Hull_-_3DBenchy.com - Layer 1 of 220*47
| Recv: Error:checksum mismatch, Last Line: 2021
| Recv: Resend: 2022
| Recv: ok
| Send: N2022 M117 Printing PI3_3DBenchy_-_Multi-part_-_Single_-_Hull_-_3DBenchy.com - Layer 1 of 220*47
| Recv: Error:checksum mismatch, Last Line: 2021
| Recv: Resend: 2022
| Recv: ok
| Send: N2022 M117 Printing PI3_3DBenchy_-_Multi-part_-_Single_-_Hull_-_3DBenchy.com - Layer 1 of 220*47
| Recv: Error:checksum mismatch, Last Line: 2021
| Recv: Resend: 2022

And this looks like an M117 being sent from a third party plugin. Test if the issues go away in safe mode. You might want to disable or reconfigure "Detailed Progress" (if that's what's sending those messages that seem to be too long or misformated for the firmware)

I had the same issue, changing the name to a shorter name and removing the - resolved it for me.

2 Likes

What is the solution?
I have an Ender 3 Pro starting doing this yesterday. short prints looks fine (for now). Long prints fail the same way. The terminal looks like this:

Recv: ok
Send: N96771 G1 X79.352 Y110.024 E2280.44998*96
Recv: ok
Send: N96772 G1 X77.377 Y110.024 E2280.52682*105
Recv: ok
Send: N96773 G1 X75.402 Y110.024 E2280.60365*98
Recv: echo:Unknown command: ".024 E2280.37314"
Recv: ok
Send: N96774 G1 X73.427 Y110.024 E2280.68049*97
Recv: ok
Send: N96775 G1 X71.452 Y110.024 E2280.75733*102
Recv: ok
Send: N96776 G1 X69.477 Y110.024 E2280.83417*103
Recv: ok
Send: N96777 G0 F9000 X68.874 Y110.674*127
Recv: ok
Send: N96778 G1 F1094.9 X68.874 Y126.674 E2281.51234*21
Recv: ok
Send: N96779 G1 F1085 X68.879 Y126.791 E2281.51735*0
Recv: ok
Send: N96780 G0 F9000 X68.78 Y127.394*76
Recv: ok
Send: N96781 G0 X68.401 Y127.773*21
Recv: ok
Send: N96782 G1 F1500 X151.6 Y127.774 E2284.09141*48
Recv:   TT::200.00200.00  //200.00200.00  BB::55.0055.00  //55.0055.00  @@::6767  BB@@::1616
Recv: 
Recv: ok
Send: N96783 G1 X151.6 Y92.226 E2285.19121*104
Recv: ok
Send: N96784 G1 X68.401 Y92.227 E2287.76527*95
Recv: ok
Send: N96785 G1 X68.4 Y109.491 E2288.2994*95
Recv: ok
Send: N96786 G1 X68.401 Y127.773 E2288.86502*97
Recv:   TT::200.00200.00  //200.00200.00  BB::55.0055.00  //55.0055.00  @@::6767  BB@@::1616
Recv: 
Recv: echo:busy: processing
Recv: ok
Send: N96787 G0 F9000 X68.78 Y127.394*75
Recv:   TT::200.00200.00  //200.00200.00  BB::55.0055.00  //55.0055.00  @@::6767  BB@@::1616
Recv: 
Recv: ok
Send: N96788 G0 X69.548 Y127.324*23
Recv:   TT::200.00200.00  //200.00200.00  BB::55.0055.00  //55.0055.00  @@::6767  BB@@::1616
Recv: 
Recv: echo:busy: processing
Recv: ok
Send: N96789 G1 F1282.1 X69.331 Y127.198 E2288.8741*43
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96786
Recv: Resend: 96787
Recv: ok
Send: N96787 G0 F9000 X68.78 Y127.394*75
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96786
Recv: Resend: 96787
Recv: ok
Send: N96788 G0 X69.548 Y127.324*23
Recv:   TT::200.00200.00  //200.00200.00  BB::55.0055.00  //55.0055.00  @@::6767  BB@@::1616
Recv: 
Recv: ok
Send: N96789 G1 F1282.1 X69.331 Y127.198 E2288.8741*43
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: echo:Unknown command: "6787 G0 F9000 X68.78 Y127.394"
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: echo:Unknown command: "\x04"
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: echo:Unknown command: "26.791 E2288.89517"
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: echo:Unknown command: "6794 G1 X68.976 Y127.386 E2288.92111"
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Recv: echo:Unknown command: "288.88199"
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "X69.114 Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: ".386 E2288.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "4 Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: "386 E2288.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: "27.386 E2288.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "7.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: "E2288.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "4 Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: "8.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "X69.114 Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: "8.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "8 X69.114 Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: "288.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "X69.114 Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96795 G1 F1477.8 X69.262 Y127.355 E2288.93014*18
Recv: echo:Unknown command: "2288.91565"
Recv: ok
Send: N96790 G1 F1477.8 X69.114 Y127.071 E2288.88199*30
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96791 G1 F1287.6 X68.879 Y126.791 E2288.89517*16
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96792 G1 X68.788 Y127.198 E2288.9102*86
Recv: echo:Unknown command: "69.114 Y127.071 E2288.88199"
Recv: ok
Send: N96793 G1 F1600 X68.788 Y127.386 E2288.91565*15
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Recv: ok
Send: N96794 G1 X68.976 Y127.386 E2288.92111*98
Recv: Error:Line Number is not Last Line Number+1, Last Line: 96789
Recv: Resend: 96790
Printer keeps requesting line 96790 again and again, communication stuck
Changing monitoring state from "Printing" to "Error"
Send: M112
Send: N96796 M112*38
Send: N96797 M104 T0 S0*39
Send: N96798 M140 S0*108
Changing monitoring state from "Error" to "Offline after error"
Connection closed, closing down monitor
Closing down send loop

octoprint-systeminfo-20220714120511.zip (1.7 MB)

You may have a look on this thread:

Already did.

These errors:

| Recv: echo:Unknown command: "¾ÜÇü‡xÔðÎßvÿÿÿûÿÌô"
| Recv: ok
| Send: N10528 G1 X33.09 Y66.963 E3853.44928*103
| Recv: echo:Unknown command: "›mÁ12ÕÅA±"
| Recv: ok
| Recv: echo:Unknown command: "êՃ"
| Send: N10529 G1 X32 Y66.963 E3853.48301*77

are definitely caused by a corrupted communication on the USB line.

Also caused by EMI from high power devices near by.

I'm having the same issue with a Prusa Mini+ on firmware v4.3.4 and Octoprint v1.8.1

I made some CLEAN logs in hopes they will help diagnose.

For my reproduction, I did the following:

  • Deleted octoprint logs
  • Rebooted octopi
  • Disconnected/Reconnected to Prusa Mini+
  • Sent a new print from slicer to octoprint and printed through octoprint
  • After crash, saved logs
  • Attempted to print exact same gcode without using octoprint (i.e. directly from printer), which was successful.

Let me know if any other information would be helpful.

octoprint-logs-crash-issue.zip (35.1 KB)

Hello @WillDeed !

Your issue is quite different.

You try to send unparsed code to the printer:

| Send: N13787 {if layer_z < max_print_height}G1 Z{z_offset+min(layer_z+140, max_print_height)}{endif} F720*4
| Recv: Error:No Checksum with line number, Last Line: 13786

In the send line there is a condition that is not processed by the slicer or you put it in the OctoPrint end gcode section, but that can not handle it because of the wrong syntax

BTW: Please always share the complete systeminfo bundle.

I can solve my problem by renaming the the gcode file to a shorter name (the former name was very long). I'm not sure if it is only about the file name, because I also replaced all M117 lines that output the filename to my printer's display.