Octoprint will not start

Get Help
1

What is the problem?
After not using my printer for several weeks, then adding plugin StickyPad and removing it, Octoprint webserver does not respond.
What did you already try to solve it?
I tried a ps -ef | grep -i octoprint | grep -i python via Putty and nothing returned. I looked at the log and did see an error but not sure where that takes me. Tried a sudo service octoprint restart with no joy. Tried a

Logs (octoprint.log, serial.log or output on terminal tab at a minimum, browser error console if UI issue ... no logs, no support!) This tool will not allow me to upload a text file of the log so I will paste it here:

2020-06-04 09:37:53,666 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 20.63ms
{lots of the same message}
2020-06-04 14:51:03,845 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.16ms
2020-06-04 14:51:08,938 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 10.29ms
2020-06-04 14:51:09,150 - octoprint.server.util.flask - INFO - Passively logging in user towatson from ::ffff:192.168.1.147
2020-06-04 14:51:09,151 - octoprint.access.users - INFO - Cleaning up user session 1E9FB75780494D75A75C05FFCC014DC2 for user towatson
2020-06-04 14:51:09,151 - octoprint.access.users - INFO - Logged out user: towatson
2020-06-04 14:51:09,153 - octoprint.access.users - INFO - Logged in user: towatson
2020-06-04 14:51:13,845 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.20ms
2020-06-04 14:51:21,549 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.36ms
2020-06-04 14:51:23,844 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.94ms
2020-06-04 14:51:28,973 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.70ms
2020-06-04 14:51:33,846 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.15ms
2020-06-04 14:51:38,973 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 12.61ms
2020-06-04 14:51:42,075 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.147
2020-06-04 14:51:43,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 13.37ms
2020-06-04 14:51:48,976 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.13ms
2020-06-04 14:51:53,846 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 13.22ms
2020-06-04 14:51:58,975 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 14.36ms
2020-06-04 14:52:03,850 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.13ms
2020-06-04 14:52:08,978 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 10.57ms
2020-06-04 14:52:13,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 11.89ms
2020-06-04 14:52:18,980 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.18ms
2020-06-04 14:52:23,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 13.29ms
2020-06-04 14:52:28,982 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 18.47ms
2020-06-04 14:52:33,845 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.21ms
2020-06-04 14:52:38,984 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.59ms
2020-06-04 14:52:43,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.54ms
2020-06-04 14:52:48,971 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 11.18ms
2020-06-04 14:52:53,856 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 20.75ms
2020-06-04 14:52:58,995 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 11.01ms
2020-06-04 14:53:03,848 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 11.89ms
2020-06-04 14:53:09,001 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.70ms
2020-06-04 14:53:13,846 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 13.30ms
2020-06-04 14:53:18,980 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 12.25ms
2020-06-04 14:53:23,844 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.05ms
2020-06-04 14:53:28,979 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 11.10ms
2020-06-04 14:53:33,850 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.94ms
2020-06-04 14:53:38,981 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 12.44ms
2020-06-04 14:53:43,841 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 9.91ms
2020-06-04 14:53:48,982 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.75ms
2020-06-04 14:53:53,849 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 14.87ms
2020-06-04 14:53:58,980 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 14.17ms
2020-06-04 14:54:03,845 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.99ms
2020-06-04 14:54:08,983 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.35ms
2020-06-04 14:54:13,848 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 13.47ms
2020-06-04 14:54:18,979 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 11.84ms
2020-06-04 14:54:23,846 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 11.92ms
2020-06-04 14:54:28,979 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 12.93ms
2020-06-04 14:54:33,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.97ms
2020-06-04 14:54:38,977 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 10.62ms
2020-06-04 14:54:43,848 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.96ms
2020-06-04 14:54:48,978 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.07ms
2020-06-04 14:54:53,844 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 11.56ms
2020-06-04 14:54:58,973 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 9.74ms
2020-06-04 14:55:02,170 - octoprint.access.users - INFO - Logged in user: towatson
2020-06-04 14:55:02,174 - octoprint.server.api - INFO - Actively logging in user towatson from ::ffff:192.168.1.147
2020-06-04 14:55:02,220 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.147
2020-06-04 14:55:02,248 - octoprint.server.util.flask - INFO - Passively logging in user towatson from ::ffff:192.168.1.147
2020-06-04 14:55:02,249 - octoprint.access.users - INFO - Logged in user: towatson
2020-06-04 14:55:03,850 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 14.46ms
2020-06-04 14:55:04,871 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.147
2020-06-04 14:55:05,061 - octoprint.server.util.flask - INFO - Passively logging in user towatson from ::ffff:192.168.1.147
2020-06-04 14:55:05,061 - octoprint.access.users - INFO - Logged in user: towatson
2020-06-04 14:55:08,764 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.55s
2020-06-04 14:55:09,592 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.68s
2020-06-04 14:55:10,772 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.74s
2020-06-04 14:55:11,372 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.57s
2020-06-04 14:55:12,093 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.65s
2020-06-04 14:55:12,977 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from https://plugins.octoprint.org/plugins.json
2020-06-04 14:55:14,455 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2020-06-04 14:55:14,597 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 12.26ms
2020-06-04 14:55:15,787 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-06-04 14:55:15,889 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-06-04 14:55:15,958 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-06-04 14:55:16,061 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-06-04 14:55:16,062 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-06-04 14:55:19,237 - 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-06-04 14:55:19,237 - octoprint.util.pip - INFO - ==> pip ok -> yes
2020-06-04 14:55:20,386 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2020-06-04 14:55:20,928 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 6.46ms
2020-06-04 14:55:20,951 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 5.99ms
2020-06-04 14:55:21,072 - octoprint.server.util.sockjs - INFO - User towatson logged in on the socket from client ::ffff:192.168.1.147
2020-06-04 14:55:23,846 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.08ms
2020-06-04 14:55:28,974 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 11.95ms
2020-06-04 14:55:33,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 11.71ms
2020-06-04 14:55:38,974 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 14.13ms
2020-06-04 14:55:43,871 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 15.02ms
2020-06-04 14:55:48,976 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 13.25ms
2020-06-04 14:55:50,274 - octoprint.plugins.softwareupdate - INFO - Starting update of PrintTimeGenius to 2.2.4...
2020-06-04 14:55:53,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 11.34ms
2020-06-04 14:55:55,729 - octoprint.plugins.softwareupdate - ERROR - Update of PrintTimeGenius can not be performed, please also check plugin_softwareupdat                                                                     e_console.log for possible causes of this
Traceback (most recent call last):
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/__init__.py", line 1060, in _perform_update
    update_result = updater.perform_update(target, populated_check, target_version, log_cb=self._log, online=online)
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/updaters/pip.py", line 116, in perform_update
    raise exceptions.UpdateError("Error while executing pip install", (stdout, stderr))
UpdateError
2020-06-04 14:55:55,739 - octoprint.plugins.softwareupdate - INFO - Starting update of dashboard to 1.13.0...
2020-06-04 14:55:58,974 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 9.91ms
2020-06-04 14:56:00,641 - octoprint.plugins.softwareupdate - ERROR - Update of dashboard can not be performed, please also check plugin_softwareupdate_cons                                                                     ole.log for possible causes of this
Traceback (most recent call last):
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/__init__.py", line 1060, in _perform_update
    update_result = updater.perform_update(target, populated_check, target_version, log_cb=self._log, online=online)
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/updaters/pip.py", line 116, in perform_update
    raise exceptions.UpdateError("Error while executing pip install", (stdout, stderr))
UpdateError
2020-06-04 14:56:03,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 12.48ms
2020-06-04 14:56:08,971 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 10.88ms
2020-06-04 14:56:13,847 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 11.53ms
2020-06-04 14:56:18,984 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 10.14ms
2020-06-04 14:56:23,845 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 10.85ms
2020-06-04 14:56:28,977 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 11.25ms
2020-06-04 14:56:33,849 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 9.93ms
2020-06-04 14:56:38,972 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 10.17ms
2020-06-04 14:56:43,844 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 10.76ms
2020-06-04 14:56:48,972 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 10.44ms
2020-06-04 14:56:53,844 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.247) 9.82ms
2020-06-04 14:56:54,822 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-06-04 14:56:58,348 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial port"
2020-06-04 14:56:58,386 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial port" to "Opening serial port"
2020-06-04 14:56:58,389 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2020-06-04 14:56:58,401 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-06-04 14:56:58,406 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-06-04 14:56:58,965 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.147) 5.73ms
2020-06-04 14:56:59,348 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-06-04 14:57:01,370 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2020-06-04 14:57:01,388 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-06-04 14:57:01,424 - octoprint.plugins.firmwareupdater - INFO - Got CONNECTED event
2020-06-04 14:57:01,428 - octoprint.plugins.firmwareupdater - INFO - Run postflash flag is not set
2020-06-04 14:57:01,494 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin Ver 1.70.1 BL (Creality3D)"
2020-06-04 14:57:01,500 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2020-06-04 14:58:09,589 - octoprint.plugins.pluginmanager - INFO - Installing plugin from https://github.com/jneilliii/OctoPrint-StickyPad/archive/master.z                                                                     ip
2020-06-04 14:58:10,247 - octoprint.plugin.core - ERROR - Error while parsing AST from /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octolaps                                                                     e/__init__.py for plugin octolapse
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/plugin/core.py", line 599, in _parse_metadata
    root = ast.parse(f.read(), filename=path)
  File "/usr/lib/python2.7/ast.py", line 37, in parse
    return compile(source, filename, mode, PyCF_ONLY_AST)
TypeError: compile() expected string without null bytes
2020-06-04 14:58:13,838 - octoprint.plugins.pluginmanager - ERROR - Installing the plugin from https://github.com/jneilliii/OctoPrint-StickyPad/archive/mas                                                                     ter.zip failed, could not parse output from pip. See plugin_pluginmanager_console.log for generated output
2020-06-04 14:59:09,904 - octoprint.server.api.system - INFO - Performing command for core:reboot: sudo shutdown -r now
2020-06-04 14:59:10,130 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.147
2020-06-04 14:59:10,280 - octoprint.server - INFO - Shutting down...
2020-06-04 14:59:11,220 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2020-06-04 14:59:11,225 - octoprint.events - INFO - Event loop shut down
2020-06-04 14:59:11,746 - octoprint.server - INFO - Goodbye!
2020-06-04 15:15:18,914 - octoprint.startup - INFO - ******************************************************************************
2020-06-04 15:15:18,915 - octoprint.startup - INFO - Starting OctoPrint 1.4.0
2020-06-04 15:15:18,916 - octoprint.startup - INFO - ******************************************************************************
2020-06-04 15:15:19,389 - octoprint.startup - CRITICAL - Could not initialize event manager: [Errno 5] Input/output error: '/home/pi/oprint/lib/python2.7/s                                                                     ite-packages/monotonic-1.5.dist-info'
2020-06-04 15:15:19,390 - octoprint.startup - CRITICAL - There was a fatal error starting up OctoPrint.

Additional information about your setup (OctoPrint version, OctoPi version, printer, firmware, browser, operating system, ... as much data as possible) Octoprint Ver 1.4.0, Octopi 0.17.0, Firefox browser in a Windows 10 environment.
This setup has been stable for six months and driving an Ender 5 Plus.
Octoprint will not start in safe mode. I would have added plugin_pluginmanager_console.log but I do not know where it lives.

2

I was about to respond with a "me too" post (because mine would not run either), but as I was gathering log information I noticed some odd behavior. The Octoprint service was reported as running but there was no associated process. I got a few "out of space" errors following unrelated commands.

To make a long story (I've been at it for over an hour) very short, I solved my problem by erasing all the time lapse videos and all the larger stored .gcode files. I restarted by power-cycling the Pi and voila - I was back!

Now I'm not sure what caused this, but after restarting I was unable to log in with my normal username/password. I tried changing the password from the command line, but despite an apparent successful command it still didn't work. I ended up having to remove the users.yaml file and reconfigure OctoPi from scratch.

Moral of the story: remove all those extra files!

3

Does this mean you were able to log into Octoprint to remove those files? Or did you locate them through ssh? I cannot get Octoprint to start.

4

If OctoPrint won't start because your SD card is full, then SSH (or WinSCP) is your only option.

Start with a "df -kh" and see if a full filesystem is your problem.

5

This is the output of "df -kh"
Filesystem Size Used Avail Use% Mounted on
/dev/root 29G 5.8G 22G 22% /
devtmpfs 1.8G 0 1.8G 0% /dev
tmpfs 1.9G 0 1.9G 0% /dev/shm
tmpfs 1.9G 17M 1.9G 1% /run
tmpfs 5.0M 4.0K 5.0M 1% /run/lock
tmpfs 1.9G 0 1.9G 0% /sys/fs/cgroup
/dev/mmcblk0p1 253M 53M 200M 21% /boot
tmpfs 386M 0 386M 0% /run/user/1000

It is appears the issue is not a full filesystem

6

Corrupt file?

7

Maybe a corrupt file but not sure what may have corrupted it.
I hate to do things like this but instead of spending a lot hours trying to filx the problem and losing my printer, I flashed a new sd card for the Rpi and started over.

8

Sometimes that is the easiest with the least headaches

9

A weakness in the RPi design is that when power is unexpectedly removed, the SD card can be corrupted. The OS needs to be properly shutdown before removing power.

Early desktop PCs had similar problems and now almost all desktop power buttons shutdown the OS instead of removing the power. The power gets turned off by the software. Most RPi configurations don't have a software controlled power shutdown.

10

This is good to know. Thanks!