πŸ‡ΊπŸ‡¦ We stand with Ukraine! πŸ‡ΊπŸ‡¦

Can't connect to printer anymore via Octoprint (Timeout while try)

What is the problem?

Setup: Ender3 + SKR 1.3 + Pi 4b

Before i had a Pi3b+ working fine with octoprint, then i upgraded to a pi4b, reinstalled octopi, restore the backup and configured the env. I had an ideia that the connection worked and i was able to move the printer axis when i first reinstall everything, but not sure anymore...
Btw: i did the exact same thing (re-setup pi4b) with Prusa MK3 and got no problems, but i have diferent plugins and configurations

What did you already try to solve it?

  1. Safe mode
  2. Other USB cable
  3. Reflash firmware and try diferent bauds
  4. Other USB ports
  5. Bump marlin firmware to latest master

Have you tried running in safe mode?

Yes

Did running in safe mode solve the problem?

No

Complete Logs

With safe mode:
Octoprint

2021-03-02 19:02:41,299 - octoprint.server.util.sockjs - INFO - User sn4k3 logged in on the socket from client ::ffff:192.168.1.1
2021-03-02 19:04:33,526 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial connection"
2021-03-02 19:04:33,531 - octoprint.util.comm - INFO - Connecting to port /dev/ttyACM0, baudrate 115200
2021-03-02 19:04:33,550 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial connection" to "Connecting"
2021-03-02 19:04:33,552 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-02 19:05:03,588 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-02 19:05:13,611 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2021-03-02 19:05:13,621 - octoprint.plugins.action_command_notification - INFO - Notifications cleared

Serial:

2021-03-02 19:04:33,521 - Changing monitoring state from "Offline" to "Opening serial connection"
2021-03-02 19:04:33,534 - Connecting to port /dev/ttyACM0, baudrate 115200
2021-03-02 19:04:33,550 - Changing monitoring state from "Opening serial connection" to "Connecting"
2021-03-02 19:04:33,551 - Connected to: Serial<id=0xa906a490, open=True>(port='/dev/ttyACM0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2021-03-02 19:04:33,553 - Send: N0 M110 N0*125
2021-03-02 19:05:03,586 - No answer from the printer within the connection timeout, trying another hello
2021-03-02 19:05:03,589 - Send: N0 M110 N0*125
2021-03-02 19:05:13,599 - There was a timeout while trying to connect to the printer
2021-03-02 19:05:13,611 - Changing monitoring state from "Connecting" to "Offline"
2021-03-02 19:05:13,613 - Connection closed, closing down monitor

With normal mode (no safe mode)

2021-03-02 19:17:39,235 - octoprint.access.users - INFO - Logged in user: sn4k3
2021-03-02 19:17:40,932 - octoprint.server.util.sockjs - INFO - User sn4k3 logged in on the socket from client ::ffff:192.168.1.1
2021-03-02 19:17:46,258 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial connection"
2021-03-02 19:17:46,261 - octoprint.plugins.meatpack - INFO - [Serial]: Cannot query packing state -- port not open.
2021-03-02 19:17:46,263 - octoprint.plugins.meatpack - INFO - [Serial]: Cannot query packing state -- port not open.
2021-03-02 19:17:46,302 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial connection" to "Connecting"
2021-03-02 19:17:46,312 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-02 19:17:46,315 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-02 19:17:46,338 - octoprint.plugins.meatpack - INFO - [Serial]: Detected MeatPack protocol version V1
2021-03-02 19:17:46,338 - octoprint.plugins.meatpack - INFO - [Serial]: Config var [Enabled] synchronized (=enabled).
2021-03-02 19:17:46,339 - octoprint.plugins.meatpack - INFO - [Serial]: Config var [NoSpaces] synchronized (=enabled).
2021-03-02 19:17:46,339 - octoprint.plugins.meatpack - INFO - [Serial]: MeatPack configuration successfully synchronized and confirmed between host/device.
2021-03-02 19:17:46,374 - octoprint.util.comm - INFO - Got a resend request for line 1 which is also our current line. It looks like we just handled a reset and this is a left over of this
2021-03-02 19:17:46,379 - 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...
2021-03-02 19:17:46,382 - octoprint.util.comm - INFO - Got a resend request for line 1 which is also our current line. It looks like we just handled a reset and this is a left over of this
2021-03-02 19:17:46,882 - 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...
2021-03-02 19:18:16,428 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-02 19:18:16,446 - octoprint.util.comm - INFO - Got a resend request for line 1 which is also our current line. It looks like we just handled a reset and this is a left over of this
2021-03-02 19:18:16,947 - 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...
2021-03-02 19:18:46,494 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2021-03-02 19:18:46,503 - octoprint.plugins.action_command_notification - INFO - Notifications cleared
2021-03-02 19:17:46,254 - Changing monitoring state from "Offline" to "Opening serial connection"
2021-03-02 19:17:46,302 - Changing monitoring state from "Opening serial connection" to "Connecting"
2021-03-02 19:17:46,304 - Connected to: PackingSerial<id=0xab21d350, open=True>(port='/dev/ttyACM0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2021-03-02 19:17:46,313 - Send: N0 M110 N0*125
2021-03-02 19:17:46,315 - Send: N0 M110 N0*125
2021-03-02 19:17:46,372 - Recv: Error:Line Number is not Last Line Number+1, Last Line: 0
2021-03-02 19:17:46,374 - Recv: Resend: 1
2021-03-02 19:17:46,378 - Recv: Error:Line Number is not Last Line Number+1, Last Line: 0
2021-03-02 19:17:46,381 - Recv: Resend: 1
2021-03-02 19:18:16,419 - No answer from the printer within the connection timeout, trying another hello
2021-03-02 19:18:16,429 - Send: N0 M110 N0*125
2021-03-02 19:18:16,438 - Recv: Error:Line Number is not Last Line Number+1, Last Line: 0
2021-03-02 19:18:16,445 - Recv: Resend: 1
2021-03-02 19:18:46,481 - There was a timeout while trying to connect to the printer
2021-03-02 19:18:46,494 - Changing monitoring state from "Connecting" to "Offline"
2021-03-02 19:18:46,496 - Connection closed, closing down monitor

Additional information about your setup

------------------------------------------------------------------------------
OctoPrint version : 1.5.3
OctoPi version    : 0.18.0
------------------------------------------------------------------------------
pi@octopiender3:~ $ vcgencmd get_throttled
throttled=0x0

Access and operations are fast, never experienced throttles, my cables are all short (50cm)

1 Like

Could you also run sudo rpi-eeprom-update and post the output?
There is an older version of the VL805 firmware that got some issues.

You can also try to update to the latest version of the VL805 firmware.
If you want to do that you should boot the latest Raspberry Pi OS, update it with sudo apt update && sudo apt -y upgrade && sudo reboot and then update the firmware with sudo rpi-eeprom-update -a

1 Like

run all that but without solving the problem, in fact i already had everything up-to-date

BCM2711 detected
Dedicated VL805 EEPROM detected
Checking for updates in /lib/firmware/raspberrypi/bootloader/default
Use raspi-config to select either the default-production release or latest update.
BOOTLOADER: up-to-date
CURRENT: Thu  3 Sep 12:11:43 UTC 2020 (1599135103)
 LATEST: Thu  3 Sep 12:11:43 UTC 2020 (1599135103)
RELEASE: default
VL805: up-to-date
CURRENT: 000138a1
 LATEST: 000138a1

I just tried to swap sdcard's, from MK3 into Ender3 Pi, and it works, connected without problems.
So i guess i'm with a damaged instalation? Anything i can try before reformat the sdcard?

Edit: I have the time so i formated the scard, it works, but as soon i restore the backup it stop connecting. However this time i got the temperature messages but it keep Connecting forever without timeout: https://i.imgur.com/LsYAS8G.png

My backup: REDACTED

@sn4k3 Do NOT share your backup publicly, it includes credentials that should stay secret! I've redacted it.

Looking at it however I don't see anything jumping out at me that would explain that. You have a bunch of plugins that could influence printer communication, but you said it also happens in safe mode.

The only thing I see in your connection logs is that there is nothing ever coming back from the printer. I'm currently unclear on how that could be caused by settings or plugins at all.

Thanks for looking, i will reinstall again and this time i will manually install plugins one by one and test out. I will report back.

Ok i tried what i said, and found the problem. Is with MeatPack, as soon it got installed printer no longer connects even on safe mode :frowning: I tried before to disable that plugin but it also prevent printer from connecting.
Now i tried to uninstall MeatPack but problem not solved by that, it wont connect anymore... It should be breaking something on setup process.
This also explains why Prusa MK3 instalation works because i dont have MeatPack there!

This is what i got after install MeatPack on normal mode:

ize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
Send: N0 M110 N0*125
Send: N0 M110 N0*125
Recv: Error:Line Number is not Last Line Number+1, Last Line: 1
Recv: Resend: 2
Printer requested line 2 but no sufficient history is available, can't resend
Recv: Error:Line Number is not Last Line Number+1, Last Line: 1
Recv: Resend: 2
Printer requested line 2 but no sufficient history is available, can't resend
Recv:  T:3.50 /0.00 B:15.31 /0.00 @:0 B@:0

After uninstall MeatPack i got this forever:

Changing monitoring state from "Offline" to "Opening serial connection"
Connecting to port /dev/ttyACM0, baudrate 115200
Changing monitoring state from "Opening serial connection" to "Connecting"
Connected to: Serial<id=0xb11cb430, open=True>(port='/dev/ttyACM0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
Send: N0 M110 N0*125
Recv:  T:3.50 /0.00 B:15.47 /0.00 @:0 B@:0
Send: N0 M110 N0*125
Recv:  T:3.50 /0.00 B:15.47 /0.00 @:0 B@:0

Also discussed here: OctoPrint unable to connect to printer when plug-in is enabled Β· Issue #12 Β· scottmudge/OctoPrint-MeatPack Β· GitHub
But that guy have better luck than me, since it broke the connection no matter if is disabled or in safe mode

I'm assuming if you are using MeatPack, you have the development branch of Marlin installed? You may need to disable meatpack in the firmware to properly test.

Yes i have.
Somehow i tried to update for today dev and it worked! only 4 commits behind compared to my config, last time i updated marlin (2 days ago) it didn't solve but now is working...

That's the nature of running the bugfix branch, sometimes things can break and be fixed quite quickly. Keep note of a known good build/commit for you, when you next update and have an issue roll back to the known good point.

1 Like