Serial connection commands bufferend until 'fake ack'

Get Help
1

What is the problem?
Ihave a skr v1.3 board now for 2 weeks and it has been working well with OctoPrint. However yesterday I did reset the EEPROM with M502 and performed a PID of the bed...after that I'm not able to send commands to the board anymore.
When I connect M501 is sent en M155 S2...and I see the response of the board but I cannot send anything..all comands I send seem to be buffered until I send a 'fake acknowledgement'..then they are send.
The temperature is updated on the first page when I start to heat the nozzle through the LCD
Sending comands from pronterface works fine
I wonder if there is som conflict with the access to the sd storage card(on the skrv 1.3 over USB...but what do I know

What did you already try to solve it?
restarted octprint in save mode...didn't help
reinstalled octopi on sd card..I just tried some moves ..that worked but now I'm having the problem again
Tried new MArlin 2.0
Logs (octoprint.log, serial.log or output on terminal tab, browser error console ...)
Serial log:
2019-09-05 15:14:15,566 - Changing monitoring state from "Offline" to "Opening serial port"
2019-09-05 15:14:15,570 - Connected to: Serial<id=0x69c8e670, open=True>(port='/dev/ttyACM0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2019-09-05 15:14:15,571 - Changing monitoring state from "Opening serial port" to "Connecting"
2019-09-05 15:14:15,594 - Send: N0 M110 N0125
2019-09-05 15:14:15,596 - Recv: ok
2019-09-05 15:14:15,600 - Changing monitoring state from "Connecting" to "Operational"
2019-09-05 15:14:15,613 - Send: N0 M110 N0125
2019-09-05 15:14:15,617 - Recv: ok
2019-09-05 15:14:15,620 - Send: N1 M115*39
2019-09-05 15:14:15,622 - Recv: FIRMWARE_NAME:Marlin AM8_01_09_19 (Github) /MarlinFirmware/Marlin PROTOCOL_VERSION:1.0 MACHINE_TYPE:3D Printer EXTRUDER_COUNT:1 UUID:cede2a2f-41a2-4748-9b12-c55c62f367ff
2019-09-05 15:14:15,629 - Recv: Cap:SERIAL_XON_XOFF:0
2019-09-05 15:14:15,637 - Recv: Cap:BINARY_FILE_TRANSFER:0
2019-09-05 15:14:15,640 - Recv: Cap:EEPROM:1
2019-09-05 15:14:15,642 - Recv: Cap:VOLUMETRIC:1
2019-09-05 15:14:15,644 - Recv: Cap:AUTOREPORT_TEMP:1
2019-09-05 15:14:15,648 - Recv: Cap:PROGRESS:0
2019-09-05 15:14:15,650 - Recv: Cap:PRINT_JOB:1
2019-09-05 15:14:15,722 - Recv: Cap:AUTOLEVEL:1
2019-09-05 15:14:15,724 - Recv: Cap:Z_PROBE:1
2019-09-05 15:14:15,726 - Recv: Cap:LEVELING_DATA:1
2019-09-05 15:14:15,728 - Recv: Cap:BUILD_PERCENT:0
2019-09-05 15:14:15,730 - Recv: Cap:SOFTWARE_POWER:0
2019-09-05 15:14:15,732 - Recv: Cap:TOGGLE_LIGHTS:0
2019-09-05 15:14:15,734 - Recv: Cap:CASE_LIGHT_BRIGHTNESS:0
2019-09-05 15:14:15,737 - Recv: Cap:EMERGENCY_PARSER:0
2019-09-05 15:14:15,739 - Recv: Cap:PROMPT_SUPPORT:0
2019-09-05 15:14:15,742 - Recv: Cap:AUTOREPORT_SD_STATUS:0
2019-09-05 15:14:15,744 - Recv: Cap:THERMAL_PROTECTION:1
2019-09-05 15:14:15,745 - Recv: Cap:MOTION_MODES:0
2019-09-05 15:14:15,748 - Recv: Cap:CHAMBER_TEMPERATURE:0
2019-09-05 15:14:15,750 - Recv: ok
2019-09-05 15:14:15,752 - Send: M21
2019-09-05 15:14:15,845 - Recv: T:18.88 /0.00 B:18.97 /0.00 @:0 B@:0
2019-09-05 15:14:17,841 - Recv: echo:SD init fail
2019-09-05 15:14:17,850 - Recv: ok
2019-09-05 15:14:17,855 - Send: M155 S2
2019-09-05 15:14:17,934 - Recv: T:18.57 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:14:17,938 - Recv: ok
2019-09-05 15:14:19,940 - Recv: T:18.57 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:14:21,940 - Recv: T:18.62 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:14:23,940 - Recv: T:18.57 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:14:25,940 - Recv: T:18.57 /0.00 B:18.75 /0.00 @:0 B@:0
2019-09-05 15:14:27,940 - Recv: T:18.57 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:14:29,940 - Recv: T:18.57 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:14:31,940 - Recv: T:19.06 /0.00 B:19.24 /0.00 @:0 B@:0
2019-09-05 15:14:33,940 - Recv: T:18.57 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:14:35,940 - Recv: T:18.57 /0.00 B:19.06 /0.00 @:0 B@:0
2019-09-05 15:14:36,970 - Send: M501
2019-09-05 15:14:38,475 - Recv: echo:V69 stored settings retrieved (604 bytes; crc 40499)
2019-09-05 15:14:38,484 - Recv: echo: G21 ; Units in mm (mm)
2019-09-05 15:14:38,488 - Recv: echo: M149 C ; Units in Celsius
2019-09-05 15:14:38,490 - Recv:
2019-09-05 15:14:38,493 - Recv: echo:Filament settings: Disabled
2019-09-05 15:14:38,495 - Recv: echo: M200 D1.75
2019-09-05 15:14:38,497 - Recv: echo: M200 D0
2019-09-05 15:14:38,500 - Recv: echo:Steps per unit:
2019-09-05 15:14:38,503 - Recv: echo: M92 X200.00 Y200.00 Z800.00 E837.00
2019-09-05 15:14:38,505 - Recv: echo:Maximum feedrates (units/s):
2019-09-05 15:14:38,507 - Recv: echo: M203 X400.00 Y400.00 Z8.00 E50.00
2019-09-05 15:14:38,510 - Recv: echo:Maximum Acceleration (units/s2):
2019-09-05 15:14:38,512 - Recv: echo: M201 X600.00 Y600.00 Z100.00 E5000.00
2019-09-05 15:14:38,515 - Recv: echo:Acceleration (units/s2): P<print_accel> R<retract_accel> T<travel_accel>
2019-09-05 15:14:38,517 - Recv: echo: M204 P400.00 R1000.00 T400.00
2019-09-05 15:14:38,520 - Recv: echo:Advanced: B<min_segment_time_us> S<min_feedrate> T<min_travel_feedrate> X<max_x_jerk> Y<max_y_jerk> Z<max_z_jerk> E<max_e_jerk>
2019-09-05 15:14:38,523 - Recv: echo: M205 B20000.00 S0.00 T0.00 X8.00 Y8.00 Z0.30 E5.00
2019-09-05 15:14:38,526 - Recv: echo:Home offset:
2019-09-05 15:14:38,529 - Recv: echo: M206 X0.00 Y0.00 Z0.00
2019-09-05 15:14:38,532 - Recv: echo:Auto Bed Leveling:
2019-09-05 15:14:38,534 - Recv: echo: M420 S0 Z0.00
2019-09-05 15:14:38,536 - Recv: echo:Material heatup parameters:
2019-09-05 15:14:38,537 - Recv: echo: M145 S0 H180 B70 F0
2019-09-05 15:14:38,539 - Recv: echo: M145 S1 H240 B110 F0
2019-09-05 15:14:38,542 - Recv: echo:PID settings:
2019-09-05 15:14:38,544 - Recv: echo: M301 P14.03 I0.65 D75.57
2019-09-05 15:14:38,546 - Recv: echo: M304 P104.09 I12.06 D598.74
2019-09-05 15:14:38,549 - Recv: echo:Retract: S F<units/m> Z
2019-09-05 15:14:38,551 - Recv: echo: M207 S1.50 W13.00 F1800.00 Z0.00
2019-09-05 15:14:38,554 - Recv: echo:Recover: S F<units/m>
2019-09-05 15:14:38,556 - Recv: echo: M208 S0.00 W0.00 F480.00
2019-09-05 15:14:38,557 - Recv: echo:Z-Probe Offset (mm):
2019-09-05 15:14:38,559 - Recv: echo: M851 Z-1.10
2019-09-05 15:14:38,561 - Recv: echo:Stepper driver current:
2019-09-05 15:14:38,563 - Recv: echo: M906 X800 Y800 Z800
2019-09-05 15:14:38,565 - Recv: echo: M906 I1 Z800
2019-09-05 15:14:38,568 - Recv: echo: M906 T0 E1200
2019-09-05 15:14:38,570 - Recv:
2019-09-05 15:14:38,575 - Recv: echo:Driver stepping mode:
2019-09-05 15:14:38,578 - Recv: echo:ok
2019-09-05 15:14:38,580 - Recv: T:18.57 /0.00 B:18.75 /0.00 @:0 B@:0
2019-09-05 15:14:40,601 - Recv: T:19.15 /0.00 B:18.79 /0.00 @:0 B@:0
2019-09-05 15:14:42,601 - Recv: T:18.57 /0.00 B:19.11 /0.00 @:0 B@:0
2019-09-05 15:14:44,634 - Recv: T:18.84 /0.00 B:19.06 /0.00 @:0 B@:0
2019-09-05 15:14:46,663 - Recv: T:18.57 /0.00 B:18.66 /0.00 @:0 B@:0
2019-09-05 15:14:48,693 - Recv: T:19.15 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:14:49,438 - Send: M501
2019-09-05 15:14:50,978 - Recv: echo:V69 stored settings retrieved (604 bytes; crc 40499)
2019-09-05 15:14:50,981 - Recv: echo: G21 ; Units in mm (mm)
2019-09-05 15:14:50,984 - Recv: echo: M149 C ; Units in Celsius
2019-09-05 15:14:50,987 - Recv:
2019-09-05 15:14:50,989 - Recv: echo:Filament settings: Disabled
2019-09-05 15:14:50,994 - Recv: echo: M200 D1.75
2019-09-05 15:14:50,997 - Recv: echo: M200 D0
2019-09-05 15:14:50,999 - Recv: echo:Steps per unit:
2019-09-05 15:14:51,000 - Recv: echo: M92 X200.00 Y200.00 Z800.00 E837.00
2019-09-05 15:14:51,002 - Recv: echo:Maximum feedrates (units/s):
2019-09-05 15:14:51,003 - Recv: echo: M203 X400.00 Y400.00 Z8.00 E50.00
2019-09-05 15:14:51,008 - Recv: echo:Maximum Acceleration (units/s2):
2019-09-05 15:14:51,012 - Recv: echo: M201 X600.00 Y600.00 Z100.00 E5000.00
2019-09-05 15:14:51,014 - Recv: echo:Acceleration (units/s2): P<print_accel> R<retract_accel> T<travel_accel>
2019-09-05 15:14:51,017 - Recv: echo: M204 P400.00 R1000.00 T400.00
2019-09-05 15:14:51,019 - Recv: echo:Advanced: B<min_segment_time_us> S<min_feedrate> T<min_travel_feedrate> X<max_x_jerk> Y<max_y_jerk> Z<max_z_jerk> E<max_e_jerk>
2019-09-05 15:14:51,022 - Recv: echo: M205 B20000.00 S0.00 T0.00 X8.00 Y8.00 Z0.30 E5.00
2019-09-05 15:14:51,024 - Recv: echo:Home offset:
2019-09-05 15:14:51,026 - Recv: echo: M206 X0.00 Y0.00 Z0.00
2019-09-05 15:14:51,029 - Recv: echo:Auto Bed Leveling:
2019-09-05 15:14:51,031 - Recv: echo: M420 S0 Z0.00
2019-09-05 15:14:51,034 - Recv: echo:Material heatup parameters:
2019-09-05 15:14:51,036 - Recv: echo: M145 S0 H180 B70 F0
2019-09-05 15:14:51,039 - Recv: echo: M145 S1 H240 B110 F0
2019-09-05 15:14:51,041 - Recv: echo:PID settings:
2019-09-05 15:14:51,044 - Recv: echo: M301 P14.03 I0.65 D75.57
2019-09-05 15:14:51,046 - Recv: echo: M304 P104.09 I12.06 D598.74
2019-09-05 15:14:51,047 - Recv: echo:Retract: S F<units/m> Z
2019-09-05 15:14:51,049 - Recv: echo: M207 S1.50 W13.00 F1800.00 Z0.00
2019-09-05 15:14:51,050 - Recv: echo:Recover: S F<units/m>
2019-09-05 15:14:51,052 - Recv: echo: M208 S0.00 W0.00 F480.00
2019-09-05 15:14:51,053 - Recv: echo:Z-Probe Offset (mm):
2019-09-05 15:14:51,055 - Recv: echo: M851 Z-1.10
2019-09-05 15:14:51,057 - Recv: echo:Stepper driver current:
2019-09-05 15:14:51,060 - Recv: echo: M906 X800 Y800 Z800
2019-09-05 15:14:51,063 - Recv: echo: M906 I1 Z800
2019-09-05 15:14:51,065 - Recv: echo: M906 T0 E1200
2019-09-05 15:14:51,067 - Recv:
2019-09-05 15:14:51,069 - Recv: echo:Driver stepping mode:
2019-09-05 15:14:51,073 - Recv: echo:ok
2019-09-05 15:14:51,079 - Recv: T:18.93 /0.00 B:18.79 /0.00 @:0 B@:0
2019-09-05 15:14:53,078 - Recv: T:18.57 /0.00 B:18.97 /0.00 @:0 B@:0
2019-09-05 15:14:55,078 - Recv: T:18.57 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:14:57,077 - Recv: T:18.57 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:14:59,077 - Recv: T:18.57 /0.00 B:18.71 /0.00 @:0 B@:0
2019-09-05 15:15:01,077 - Recv: T:18.71 /0.00 B:19.24 /0.00 @:0 B@:0
2019-09-05 15:15:03,078 - Recv: T:19.02 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:15:05,077 - Recv: T:18.57 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:15:07,077 - Recv: T:18.57 /0.00 B:19.20 /0.00 @:0 B@:0
2019-09-05 15:15:09,077 - Recv: T:18.57 /0.00 B:19.24 /0.00 @:0 B@:0
2019-09-05 15:15:11,077 - Recv: T:18.57 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:15:13,077 - Recv: T:19.29 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:15:15,077 - Recv: T:18.88 /0.00 B:18.93 /0.00 @:0 B@:0
2019-09-05 15:15:17,077 - Recv: T:18.66 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:15:19,077 - Recv: T:19.20 /0.00 B:19.24 /0.00 @:0 B@:0
2019-09-05 15:15:21,077 - Recv: T:18.57 /0.00 B:18.57 /0.00 @:0 B@:0
2019-09-05 15:15:23,077 - Recv: T:18.57 /0.00 B:19.11 /0.00 @:0 B@:0
2019-09-05 15:15:25,077 - Recv: T:18.57 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:15:27,077 - Recv: T:18.57 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:15:29,077 - Recv: T:18.57 /0.00 B:19.29 /0.00 @:0 B@:0
2019-09-05 15:15:31,077 - Recv: T:19.02 /0.00 B:18.62 /0.00 @:0 B@:0
2019-09-05 15:15:33,077 - Recv: T:18.88 /0.00 B:19.06 /0.00 @:0 B@:0

posting the octoprint log caused an error about max 2 links for new users

Additional information about your setup (OctoPrint version, OctoPi version, printer, firmware, browser, operating system, ...)
OctoPrint 1.3.11
OctoPi 0.16.0
MArlin 2.0 bugfix from sept 4th 2019
board: BigTreeTech SKR V1.3
marlin serial config
#define SERIAL_PORT -1

#define SERIAL_PORT_2 0

#define BAUDRATE 115200

2

Your firmware has a bug.

The final line in the responses to your M501 commands is echo:ok. That's not a proper acknowledgment, it should be just ok. Due to this OctoPrint doesn't know it's allowed to send more commands and you need to fake acknowledge.

3

Hmm interesting...I did a clean in stall of octopi again but without any plug-ins and it works fine now..I''l have a look in the serial log after the print job is ready
Thanks for the quick answer

4

Hmm it seems the first few 'ok' are correct but after the first M501 it starts to send echo:ok....could be that the contect of the EEPROM causes that....but agreed FW bug

5

Sounds a bit like this issue only for M111.

It was the SERIAL_ECHO_START at the beginning. If the M111 command didn't set either DEBUG_ECHO or DEBUG_DRYRUN then it would only print echo: with no newline, swallowing the next ok .

6

So this would be a Marlin bug (I already reported it)..if not I can close it.What is work around? It was the EEPROM plugin that did the harm since it always does a M501 after a connect

7

It doesn't feel like it's the plugin's fault since it's behaving. It's Marlin 2.0 that's sending back the wrong response to M501. You could either follow the issue you added or you could attempt to go to school on what they're presumably going to do for the M111-related code and then consider fixing it yourself. It sounds like the Marlin code itself is being sloppy about not setting DEBUG_ECHO for the M501 command, I'd guess.

8

Correct when not setting the stepper driver mode with a g-code it is not saved in the EEPROM but M501 rshows the driver setting header but no value and then you get the echo (from m501) and then ok..setting the driver mode and then saving it to eeprom fixed it. It is a marlin bug