Octopi unable to stay connected after update

David_Moore · February 5, 2019, 4:20pm

I am running octoprint 1.3.10 on pi 3b.

I was using this quite happily for many months, and then I did an apt-get-update, and now it won't stay connected.

When I press connect, it starts up the fans etc on the printer, however then I get a communications error.

Here is the Terminal code

Changing monitoring state from "Offline" to "Detecting serial port"
Serial port list: ['/dev/ttyACM0']
Connecting to: /dev/ttyACM0
Changing monitoring state from "Detecting serial port" to "Opening serial port"
Connected to: Serial<id=0x6c2857d0, open=True>(port='/dev/ttyACM0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
Starting baud rate detection...
Changing monitoring state from "Opening serial port" to "Detecting baudrate"
Trying baudrate: 115200
Recv: start
Send: N0 M110 N0*125
Changing monitoring state from "Detecting baudrate" to "Operational"
Send: N0 M110 N0*125

Communication timeout while idle, trying to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
Send: N1 M115*39

Printer is Prusa i3 Mk3 running latest firmware.
It works when printing from SD card.

The serial log seems to contain the same information, however I can paste any logs required if you ask me

Thanks!

Dave

tedder42 · February 5, 2019, 7:22pm

Post the octoprint.log, please.

David_Moore · February 6, 2019, 10:32am

2019-02-06 07:32:10,219 - octoprint.startup - INFO - ******************************************************************************
2019-02-06 07:32:10,221 - octoprint.startup - INFO - Starting OctoPrint 1.3.10
2019-02-06 07:32:10,222 - octoprint.startup - INFO - ******************************************************************************
2019-02-06 07:32:10,295 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2019-02-06 07:32:10,628 - octoprint.startup - INFO - Blacklist processing done, adding 1 blacklisted plugin versions: roomtemp (any)
2019-02-06 07:32:10,664 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2019-02-06 07:32:11,886 - octoprint.plugin.core - INFO - Plugin Discovery is disabled.
2019-02-06 07:32:12,444 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 14 mixin implementations, 17 hook handlers
2019-02-06 07:32:12,545 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-02-06 07:32:12,571 - octoprint.server - INFO - Intermediary server started
2019-02-06 07:32:12,572 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2019-02-06 07:32:13,212 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 14 mixin implementations, 17 hook handlers
2019-02-06 07:32:13,256 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-02-06 07:32:13,841 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-02-06 07:32:13,857 - octoprint.plugins.tracking - INFO - Initialized anonymous tracking
2019-02-06 07:32:13,888 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2019-02-06 07:32:15,700 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-02-06 07:32:17,502 - octoprint.util.pip - INFO - Version of pip is 9.0.3
2019-02-06 07:32:17,504 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-02-06 07:32:17,505 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-02-06 07:32:17,509 - octoprint.plugin.core - INFO - Initialized 14 plugin implementation(s)
2019-02-06 07:32:17,518 - octoprint.plugin.core - INFO - 15 plugin(s) registered with the system:
|  Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/action_command_prompt
|  Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/announcements
|  Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/tracking
|  Application Keys Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/appkeys
|  Backup & Restore (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/backup
|  Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
|  CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/cura
| !Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
|  Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
|  Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
|  Pi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pi_support
|  Plugin Manager (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pluginmanager
|  Printer Safety Check (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/printer_safety_check
|  Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
2019-02-06 07:32:17,535 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
|  hardware:
|    cores: 4
|    freq: 1400.0
|    ram: 918188032
|  os:
|    id: linux
|    platform: linux2
|  plugins:
|    pi_support:
|      model: Raspberry Pi 3 Model B Plus Rev 1.3
|      octopi_version: 0.15.1
|  python:
|    pip: 9.0.3
|    version: 2.7.13
|    virtualenv: /home/pi/oprint
2019-02-06 07:32:17,545 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-02-06 07:32:17,560 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-02-06 07:32:17,783 - octoprint.server - INFO - Shutting down intermediary server...
2019-02-06 07:32:18,006 - octoprint.server - INFO - Intermediary server shut down
2019-02-06 07:32:18,009 - octoprint.events - INFO - Processing startup event, this is our first event
2019-02-06 07:32:18,010 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2019-02-06 07:32:18,013 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-02-06 07:32:18,051 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-02-06 07:32:18,335 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-02-06 07:32:18,373 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-02-06 07:32:18,399 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-02-06 07:32:18,816 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-02-06 07:32:18,827 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.47s
2019-02-06 07:32:19,103 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.72s
2019-02-06 07:32:19,509 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.60
2019-02-06 07:32:19,567 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-02-06 07:32:19,607 - octoprint.server.util.flask - INFO - Passively logging in user dave from ::ffff:192.168.1.60
2019-02-06 07:32:19,619 - octoprint.server.util.sockjs - INFO - User dave logged in on the socket from client ::ffff:192.168.1.60
2019-02-06 07:32:19,673 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.47s
2019-02-06 07:32:19,940 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.43s
2019-02-06 07:32:20,187 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.37s
2019-02-06 07:32:20,354 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.33s
2019-02-06 07:32:20,462 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-02-06 07:32:20,464 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-02-06 07:32:20,464 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-02-06 07:32:20,550 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.1.136/'}
2019-02-06 07:32:20,572 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.3s
2019-02-06 07:32:20,744 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.3s
2019-02-06 07:32:20,977 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.13', 'ram': 918188032, 'octopi_version': '0.15.1', 'version': u'1.3.10', 'pip': '9.0.3', 'cores': 4, 'freq': 1400.0, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'os': 'linux'}
2019-02-06 07:32:21,082 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.4s
2019-02-06 07:32:21,336 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.46s
2019-02-06 07:32:30,944 - octoprint.server.preemptive_cache - INFO - ... done in 10.39s
2019-02-06 07:32:30,946 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.1.176/'}
2019-02-06 07:32:34,885 - octoprint.server.preemptive_cache - INFO - ... done in 3.94s
2019-02-06 07:33:39,259 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial port"
2019-02-06 07:33:39,298 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial port" to "Opening serial port"
2019-02-06 07:33:39,300 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Detecting baudrate"
2019-02-06 07:33:40,316 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-02-06 07:33:40,424 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting baudrate" to "Operational"
2019-02-06 07:33:40,440 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-02-06 07:34:10,450 - octoprint.util.comm - INFO - Communication timeout while idle, trying to trigger response from printer.
2019-02-06 07:34:40,463 - octoprint.util.comm - INFO - Communication timeout while idle, trying to trigger response from printer.
2019-02-06 07:35:10,473 - octoprint.util.comm - INFO - No response from printer after 3 consecutive communication timeouts, considering it dead.
2019-02-06 07:35:10,488 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Offline (Error: Too many consecutive timeouts, printer still connected and alive?)"

David_Moore · February 8, 2019, 3:12pm

I know the forum said no bumps, but having to transfer everything via sd card is getting rather frustrating.... Is there anything else I can provide to try and help with troubleshooting this?

Thanks
Dave

foosel · February 8, 2019, 3:35pm

What printer firmware version?
What does the printer do during connect? Because it certainly isn't responding via serial like it should?
What happens when you don't run baudrate detection?
Does the same happen with a fresh OctoPi install? You said it stopped working after you did an apt-get update, so it's completely impossible to say what state your install is even in. And apt-get certainly doesn't update OctoPrint.
What happens when you increase the timeouts?
Try ejecting the SD card from your printer, connect then, same issue?
Safe mode?
Any other changes?
Don't take this the wrong way, but: The printer does have power?

For the record, I can connect to my own MK3 just fine.

David_Moore · February 8, 2019, 4:34pm

What printer firmware version?

Latest firmware 3.5.1

What does the printer do during connect? Because it certainly isn't responding via serial like it should?

The fans all come on 100%, which they did before, for about 10 seconds. Then it goes quiet, and then the timeout occurs.

What happens when you don't run baudrate detection?

Will try after current print ends.

Does the same happen with a fresh OctoPi install? You said it stopped working after you did an apt-get update, so it's completely impossible to say what state your install is even in. And apt-get certainly doesn't update OctoPrint.

I didn't do a fresh install, as I didn't want to lose all my config. I will see if I have a spare SD card. apt-get update ought to refresh everything that's in the listed package repository, I assumed OP was in there.

What happens when you increase the timeouts?

Will try after current print ends.

Try ejecting the SD card from your printer, connect then, same issue?

Yes, same issue.

Safe mode?

Same issue.

Any other changes?

No other changes.

Don't take this the wrong way, but: The printer does have power?

Yes, I'm printing now. Don't worry, I've been in IT support

For the record, I can connect to my own MK3 just fine.

If you have latest FW on printer, and latest OP, then that's good news, for someone, as it's not a bug or software problem, just my problem!

Will report back on increasing timeouts and baudrate changes.
However, it was working before completely fine with the settings it did have....

Thanks!

David_Moore · February 8, 2019, 6:59pm

Setting baudrate manually, no effect.
Increasing timeouts, just meant it took longer for the same result to occur...

Next step is a brand new install then...

David_Moore · February 13, 2019, 8:29am

I did a brand new fresh install and it's the same...

I found what it was though. After I did a firmware update on the Prusa, it had set RPi port to "on".

As I was setting up the new octoprint instance, I watched this again: https://www.youtube.com/watch?time_continue=346&v=_XACqEA1hHU and went to check, and it had changed.

I believe it's working now - hope this might be useful for anyone else.

Oh, as an aside, a rather odd thing. Octoprint was only reachable by my main PC, no other device could reach it. I found that in the octopi-wpa-supplicant.txt it was using an old wifi password. Putting that right sorted it, however how on earth was my main PC able to connect... odd, but old and behind me now...

foosel · February 13, 2019, 9:41am

Ah. I keep forgetting about this serial port switch in that firmware

Could you mark your post as the solution so others with the same problem will find it easily?