Octoprint Service Will Not Run After 1.5.2 Update

SiX · December 14, 2020, 4:52pm

What is the problem?

Ran Octoprint 1.5.0 and 1.5.1 continuously for months. After upgrading to 1.5.2, the Octoprint service did not appear to be running. I cannot access it via the webui (receive Octoprint service not running error).

What did you already try to solve it?

-Tried the directions on the failed webpage response (i.e. the Octoprint webservice isn't running page)
-Tried rebooting.
-Tried reformatting the card with Octoprint 1.5.2 directly from the website.
-Tried using 1.5.2 with the 0.18rc1 release candidate
-Tried flashing 1.5.1 and reinstalling 1.5.2 from the webui again. (webui worked until update to 1.5.2)

Have you tried running in safe mode?

Cannot access the webui to get it to safe mode.

Did running in safe mode solve the problem?

See above.

Complete Logs

2020-12-10 12:31:41,800 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816994365 (::ffff:192.168.24.189) 535.37ms 2020-12-12 17:49:54,294 - octoprint.server.heartbeat - INFO - Server heartbeat <3 2020-12-12 17:49:54,346 - octoprint.server - INFO - --- Log roll over detected --------------------------------------------------- 2020-12-12 17:49:54,347 - octoprint.server - INFO - OctoPrint 1.5.1 2020-12-12 17:49:54,362 - octoprint.plugin.core - INFO - 36 plugin(s) registered with the system: | Action Command Notification Support (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/action_command_notification | 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 | Autoscroll (0.0.3) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_autoscroll | Backup & Restore (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/backup | BetterHeaterTimeout (1.3.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_BetterHeaterTimeout | !Consolidate Temp Control (0.1.7) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_consolidate_temp_control | Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard | Dashboard (1.16.3) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_dashboard | Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery | DisplayLayerProgress Plugin (1.24.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_DisplayLayerProgress | Enclosure Plugin (4.13.1) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_enclosure | Error Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/errortracking | File Check (2020.8.7) (bundled) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_file_check | Firmware Check (2020.9.23) (bundled) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_firmware_check | Floating Navbar (0.3.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_floatingnavbar | Fullscreen Plugin (0.0.6) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_fullscreen | GCode Viewer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/gcodeviewer | GPX (2.6.8) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_GPX | Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging | Navbar Temperature Plugin (0.14) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_navbartemp | OctoEverywhere! (0.4.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octoeverywhere | !Octolapse (0.4.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octolapse | OctoPrint-WideScreen (0.1.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_widescreen | 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 | PSU Control (0.1.9) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_psucontrol | Resource Monitor (0.2.6) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_resource_monitor | Simple Emergency Stop (1.0.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_simpleemergencystop | Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate | Themeify (1.2.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_themeify | TouchUI (0.3.17) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_touchui | Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer | !Webcam Tab (0.1.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_webcamtab Prefix legend: ! = disabled, # = blacklisted, * = incompatible 2020-12-12 17:49:54,399 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816994811 (::ffff:192.168.24.189) 31.23ms 2020-12-12 17:49:54,403 - octoprint.environment - INFO - Detected environment is Python 2.7.16 under Linux (linux2). Details: | hardware: | cores: 4 | freq: 1500.0 | ram: 4014280704 | os: | bits: 32 | id: linux | platform: linux2 | plugins: | pi_support: | model: Raspberry Pi 4 Model B Rev 1.1 | octopi_version: 0.17.0 | throttle_state: '0x0' | python: | pip: 19.3.1 | version: 2.7.16 | virtualenv: /home/pi/oprint 2020-12-12 17:49:54,407 - octoprint.server - INFO - ------------------------------------------------------------------------------ 2020-12-12 17:49:55,236 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816995921 (::ffff:192.168.24.189) 26.56ms 2020-12-12 17:49:55,432 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816996133 (::ffff:192.168.24.189) 20.38ms 2020-12-12 17:49:56,261 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816996974 (::ffff:192.168.24.189) 12.97ms 2020-12-12 17:49:56,475 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816997169 (::ffff:192.168.24.189) 28.65ms 2020-12-12 17:49:57,797 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816997997 (::ffff:192.168.24.189) 410.99ms 2020-12-12 17:49:57,815 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816998212 (::ffff:192.168.24.189) 14.65ms 2020-12-12 17:49:58,854 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816999534 (::ffff:192.168.24.189) 37.44ms 2020-12-12 17:49:58,964 - tornado.access - WARNING - 404 GET /cached.gif?_=1607816999552 (::ffff:192.168.24.189) 86.50ms 2020-12-12 17:49:59,897 - tornado.access - WARNING - 404 GET /cached.gif?_=1607817000598 (::ffff:192.168.24.189) 20.83ms 2020-12-12 17:49:59,990 - tornado.access - WARNING - 404 GET /cached.gif?_=1607817000691 (::ffff:192.168.24.189) 24.90ms 2020-12-12 17:50:00,951 - tornado.access - WARNING - 404 GET /cached.gif?_=1607817001634 (::ffff:192.168.24.189) 27.96ms 2020-12-12 17:50:01,062 - tornado.access - WARNING - 404 GET /cached.gif?_=1607817001725 (::ffff:192.168.24.189) 43.79ms 2020-12-12 17:50:01,536 - octoprint.server.preemptive_cache - INFO - ... done in 10.90s 2020-12-12 17:50:01,537 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'hxxp: //127.0.0.1:5000/'} 2020-12-12 17:50:01,865 - octoprint.server.preemptive_cache - INFO - ... done in 0.33s 2020-12-12 17:50:01,978 - octoprint.server.views - INFO - Found path / in cache (key: ui:_default:hxxp: //192.168.24.80/:en), signaling as cached 2020-12-12 17:50:02,031 - octoprint.server.util.flask - INFO - Passively logging in user SiX from ::ffff:192.168.24.189 2020-12-12 17:50:02,032 - octoprint.access.users - INFO - Logged in user: SiX 2020-12-12 17:50:02,103 - octoprint.server.views - INFO - Found path / in cache (key: ui:_default:hxxp: //192.168.24.80/:en), signaling as cached 2020-12-12 17:50:03,113 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.24.189 2020-12-12 17:50:03,174 - octoprint.server.util.flask - INFO - Passively logging in user SiX from ::ffff:192.168.24.189 2020-12-12 17:50:03,175 - octoprint.access.users - INFO - Logged in user: SiX 2020-12-12 17:50:03,489 - octoprint.plugins.softwareupdate - INFO - Fetching check overlays from hxxp: //plugins.octoprint.org/update_check_overlay.json 2020-12-12 17:50:06,296 - octoprint.plugins.announcements - INFO - Loaded channel _important from hxxp: //octoprint.org/feeds/important.xml in 0.46s 2020-12-12 17:50:06,879 - octoprint.plugins.announcements - INFO - Loaded channel _releases from hxxp: //octoprint.org/feeds/releases.xml in 0.48s 2020-12-12 17:50:07,459 - octoprint.plugins.announcements - INFO - Loaded channel _blog from hxxp: //octoprint.org/feeds/octoblog.xml in 0.46s 2020-12-12 17:50:07,947 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from hxxp: //plugins.octoprint.org/feed.xml in 0.45s 2020-12-12 17:50:08,521 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from hxxp: //octoprint.org/feeds/octopi.xml in 0.46s 2020-12-12 17:50:09,519 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from hxxp: //plugins.octoprint.org/plugins.json 2020-12-12 17:50:10,914 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from hxxp: //plugins.octoprint.org/notices.json 2020-12-12 17:50:12,162 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip 2020-12-12 17:50:12,164 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes 2020-12-12 17:50:12,165 - octoprint.util.pip - INFO - ==> pip ok -> yes 2020-12-12 17:50:13,144 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk 2020-12-12 17:50:13,271 - octoprint.server.util.sockjs - INFO - User SiX logged in on the socket from client ::ffff:192.168.24.189 2020-12-12 17:50:52,720 - octoprint.plugins.psucontrol - INFO - Switching PSU On 2020-12-12 17:51:12,508 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial connection" 2020-12-12 17:51:12,544 - octoprint.util.comm - INFO - Serial detection: Performing autodetection with 2 port/baudrate candidates: /dev/ttyACM0@115200, /dev/ttyS0@115200 2020-12-12 17:51:12,553 - octoprint.util.comm - INFO - Serial detection: Trying port /dev/ttyACM0, baudrate 115200 2020-12-12 17:51:12,557 - octoprint.plugins.GPX - INFO - [a] 2020-12-12 17:51:12,557 - octoprint.plugins.GPX - INFO - [b] 2020-12-12 17:51:12,558 - octoprint.plugins.GPX - INFO - [left] 2020-12-12 17:51:12,559 - octoprint.plugins.GPX - INFO - [machine] 2020-12-12 17:51:12,560 - octoprint.plugins.GPX - INFO - [printer] 2020-12-12 17:51:12,561 - octoprint.plugins.GPX - INFO - build_progress=1 2020-12-12 17:51:12,561 - octoprint.plugins.GPX - INFO - ditto_printing=0 2020-12-12 17:51:12,562 - octoprint.plugins.GPX - INFO - gcode_flavor=makerbot 2020-12-12 17:51:12,563 - octoprint.plugins.GPX - INFO - machine_type=r1d 2020-12-12 17:51:12,564 - octoprint.plugins.GPX - INFO - recalculate_5d=0 2020-12-12 17:51:12,565 - octoprint.plugins.GPX - INFO - [right] 2020-12-12 17:51:12,566 - octoprint.plugins.GPX - INFO - [x] 2020-12-12 17:51:12,567 - octoprint.plugins.GPX - INFO - [y] 2020-12-12 17:51:12,568 - octoprint.plugins.GPX - INFO - [z] 2020-12-12 17:51:12,569 - octoprint.plugins.GPX - INFO - {'a': {}, 'None': {}, 'b': {}, 'machine': {}, 'printer': {'recalculate_5d': '0', 'gcode_flavor': 'makerbot', 'build_progress': '1', 'machine_type': 'r1d', 'ditto_printing': '0'}, 'right': {}, 'y': {}, 'x': {}, 'z': {}, 'left': {}} 2020-12-12 17:51:12,569 - octoprint.plugins.GPX - INFO - Connecting through x3g. 2020-12-12 17:51:12,572 - octoprint.plugins.GPX - INFO - GPXPrinter created, port: /dev/ttyACM0, baudrate: 115200 2020-12-12 17:51:16,651 - octoprint.plugins.GPX - INFO - gpx.connect succeeded 2020-12-12 17:51:16,652 - octoprint.util.comm - INFO - Serial detection: Handshake attempt #1 with timeout 2.0s 2020-12-12 17:51:16,688 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0 2020-12-12 17:51:16,722 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial connection" to "Operational" 2020-12-12 17:51:16,786 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0 2020-12-12 17:51:18,857 - octoprint.util.comm - INFO - Printer reports firmware name "Sailfish" 2020-12-12 17:51:18,890 - octoprint.util.comm - INFO - Firmware states that it supports emergency GCODEs to be sent without waiting for an acknowledgement first 2020-12-12 17:52:09,285 - octoprint.plugins.softwareupdate - INFO - Starting update of octoprint to 1.5.2... ^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@^@$

WRITE HERE

Additional information about your setup

Octorprint 1.5.2, OctoPi 0.17 & 0.18rc1, Rpi4 4gb, Flashforge Creator Pro, Sailfish 7.7, Google Chrome

Charlie_Powell · December 14, 2020, 5:03pm

This... doesn't look right.

Given that 1.5.0 was only released 14 days ago, I find this hard to believe but anyway, we will move on.

If you keep trying to update it, and it fails, I might suggest the SD card has broken. SSH to a broken instance and try running:

sudo service octoprint stop
~/oprint/bin/pip install --force-reinstall --no-cache-dir octoprint==1.5.2
sudo service octoprint start

If it still does not work after this, something is wrong with the SD card. Since that cleans out absolutely everything in the install, and reinstalls every part (even every dependency of OctoPrint with it). If something is broken with the update, this will fix it.

If you continue to have issues, try a different SD card.

SiX · December 14, 2020, 6:08pm

After attempting to re-install via Putty, it seems to hang. After submitting the command, I get no feedback at all (nor does the connection close). Going to try a new SD card, not a bad idea (even if I seem to be able to use it normally otherwise).

SiX · December 14, 2020, 6:30pm

Unfortunately using a new SD card allowed me to update to 1.5.2. It is currently running successfully after an update from 1.4.2. I did this same process with my other SD card and it failed.

One more question, if you happen to know. I didn't create a backup prior to update (I didn't even know Octoprint had such a function). After it failed (prior to formatting the first time), I collected the .octoprint folder. What is the best get everything back? Simply replace the folder with my collected one?

Is there something special should do with this .octoprint folder to make use of it?