1.8.6 > 1.9.0rc2 went very bad - unable to start octoprint. no new log update on start attempt

What is the problem?

octoopi wont start after apparently successful upgrade

What did you already try to solve it?

rebooted, looked for process, checked log for cles

Have you tried running in safe mode?

it does not get that far as trying to start

Did running in safe mode solve the problem?

no

Systeminfo Bundle

$ ps -ef | grep -i octoprint | grep -i python
pi@octopi:~ $ cat ~/.octoprint/logs/octoprint.log
2023-03-08 11:24:29,501 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 11:24:29,507 - octoprint.server - INFO - --- Log roll over detected ---------------------------------------------------
2023-03-08 11:24:29,507 - octoprint.server - INFO - OctoPrint 1.8.6
2023-03-08 11:24:29,511 - octoprint.plugin.core - INFO - 23 plugin(s) registered with the system:
|  Action Command Notification Support (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/action_command_notification
|  Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/action_command_prompt
|  Announcement Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/announcements
|  Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/tracking
|  Application Keys Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/appkeys
|  Backup & Restore (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/backup
| !Cancel Objects (0.4.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_cancelobject
|  Core Wizard (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/corewizard
|  Discovery (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/discovery
|  Error Tracking (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/errortracking
|  Event Manager (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/eventmanager
|  File Check (2021.2.23) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_file_check
|  Firmware Check (2021.10.11) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_firmware_check
|  Fullscreen Plugin (0.0.6) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_fullscreen
|  GCode Viewer (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/gcodeviewer
|  Logging (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/logging
|  Marlin GCode Documentation (0.18.0) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_marlingcodedocumentation
|  Pi Support Plugin (2022.6.13) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_pi_support
|  Plugin Manager (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pluginmanager
|  PrintJobHistory (1.17.0) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_PrintJobHistory
|  Software Update (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/softwareupdate
|  Themeify (1.2.2) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_themeify
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2023-03-08 11:24:29,534 - octoprint.environment - INFO - Detected environment is Python 3.7.3 under Linux (linux). Details:
|  hardware:
|    cores: 4
|    freq: 1400.0
|    ram: 914006016
|  os:
|    bits: 32
|    id: linux
|    platform: linux
|  plugins:
|    pi_support:
|      model: Raspberry Pi 3 Model B Plus Rev 1.3
|      octopi_version: 0.17.0
|      throttle_check_enabled: true
|      throttle_check_functional: true
|      throttle_state: '0x0'
|  python:
|    pip: 20.2.3
|    version: 3.7.3
|    virtualenv: /home/pi/oprint
2023-03-08 11:24:29,535 - octoprint.server - INFO - ------------------------------------------------------------------------------
2023-03-08 11:25:41,859 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11750503, 'printer_state': 'OFFLINE'}
2023-03-08 11:39:29,503 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 11:40:41,869 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11751403, 'printer_state': 'OFFLINE'}
2023-03-08 11:54:29,505 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 11:55:42,046 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11752303, 'printer_state': 'OFFLINE'}
2023-03-08 12:09:29,508 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 12:10:41,868 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11753203, 'printer_state': 'OFFLINE'}
2023-03-08 12:24:29,510 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 12:25:41,881 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11754103, 'printer_state': 'OFFLINE'}
2023-03-08 12:39:29,512 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 12:40:42,127 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11755003, 'printer_state': 'OFFLINE'}
2023-03-08 12:54:29,515 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 12:55:41,891 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11755903, 'printer_state': 'OFFLINE'}
2023-03-08 13:09:29,517 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 13:10:41,887 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11756803, 'printer_state': 'OFFLINE'}
2023-03-08 13:24:29,519 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 13:25:41,973 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11757703, 'printer_state': 'OFFLINE'}
2023-03-08 13:39:29,522 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 13:40:41,907 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11758603, 'printer_state': 'OFFLINE'}
2023-03-08 13:54:29,524 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 13:55:41,916 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11759503, 'printer_state': 'OFFLINE'}
2023-03-08 14:09:29,526 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 14:10:42,175 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11760403, 'printer_state': 'OFFLINE'}
2023-03-08 14:24:29,529 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 14:25:41,931 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11761303, 'printer_state': 'OFFLINE'}
2023-03-08 14:39:29,531 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 14:40:41,937 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11762203, 'printer_state': 'OFFLINE'}
2023-03-08 14:54:29,533 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 14:55:42,089 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11763103, 'printer_state': 'OFFLINE'}
2023-03-08 15:09:29,536 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 15:10:41,950 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11764003, 'printer_state': 'OFFLINE'}
2023-03-08 15:24:29,538 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 15:25:41,959 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11764903, 'printer_state': 'OFFLINE'}
2023-03-08 15:39:29,540 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 15:40:47,039 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11765803, 'printer_state': 'OFFLINE'}
2023-03-08 15:54:29,543 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 15:55:42,105 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11766703, 'printer_state': 'OFFLINE'}
2023-03-08 16:09:29,545 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 16:10:41,973 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11767603, 'printer_state': 'OFFLINE'}
2023-03-08 16:24:29,547 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 16:25:42,127 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11768503, 'printer_state': 'OFFLINE'}
2023-03-08 16:39:29,549 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 16:40:43,571 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11769403, 'printer_state': 'OFFLINE'}
2023-03-08 16:54:29,552 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 16:55:41,999 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11770303, 'printer_state': 'OFFLINE'}
2023-03-08 17:09:29,554 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 17:10:42,003 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11771203, 'printer_state': 'OFFLINE'}
2023-03-08 17:24:29,556 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 17:25:47,030 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11772103, 'printer_state': 'OFFLINE'}
2023-03-08 17:39:29,558 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2023-03-08 17:39:56,569 - octoprint.access.users - INFO - Cleaning up user session D9C45478F1434CD3B5CB10E2B732BEA3 for user andre
2023-03-08 17:39:56,570 - octoprint.access.users - INFO - Logged out user: andre
2023-03-08 17:39:56,606 - octoprint.server.util.flask - INFO - Passively logging in user andre from ::ffff:192.168.1.80
2023-03-08 17:39:56,609 - octoprint.access.users - INFO - Logged in user: andre
2023-03-08 17:39:56,673 - octoprint.server.util.flask - INFO - Passively logging in user andre from ::ffff:192.168.1.80
2023-03-08 17:39:56,675 - octoprint.access.users - INFO - Logged in user: andre
2023-03-08 17:39:56,726 - octoprint.server.util.flask - INFO - Passively logging in user andre from ::ffff:192.168.1.80
2023-03-08 17:39:56,727 - octoprint.access.users - INFO - Logged in user: andre
2023-03-08 17:39:57,034 - octoprint.server.util.flask - INFO - Passively logging in user andre from ::ffff:192.168.1.80
2023-03-08 17:39:57,035 - octoprint.access.users - INFO - Logged in user: andre
2023-03-08 17:39:57,646 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.80
2023-03-08 17:39:57,656 - octoprint.plugins.PrintJobHistory - INFO - Plugin-State:
| PreHeat=missing (None)
| filamentmanager=missing (None)
| DisplayLayerProgress=missing (None)
| SpoolManager=missing (None)
| UltimakerFormat=missing (None)
| PrusaSlicerThumbnail=missing (None)
| costestimation=missing (None)

2023-03-08 17:39:57,696 - octoprint.server.util.flask - INFO - Passively logging in user andre from ::ffff:192.168.1.80
2023-03-08 17:39:57,697 - octoprint.access.users - INFO - Logged in user: andre
2023-03-08 17:39:58,126 - octoprint.plugins.softwareupdate - INFO - Fetching check overlays from https://plugins.octoprint.org/update_check_overlay.json
2023-03-08 17:40:00,243 - octoprint.server.util.sockjs - INFO - User andre logged in on the socket from client ::ffff:192.168.1.80
2023-03-08 17:40:01,104 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.24s
2023-03-08 17:40:01,529 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.25s
2023-03-08 17:40:01,832 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.24s
2023-03-08 17:40:02,140 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.24s
2023-03-08 17:40:02,464 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.24s
2023-03-08 17:40:03,170 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2023-03-08 17:40:08,677 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2023-03-08 17:40:09,625 - octoprint.plugins.tracking - INFO - Sent tracking event webui_load, payload: {'browser_name': 'Chrome', 'browser_version': '110.0.0.0', 'os_name': 'Linux', 'os_version': 'x86_64'}
2023-03-08 17:40:38,305 - octoprint.server.api.system - INFO - Performing command for custom:A10On
2023-03-08 17:40:42,000 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11773003, 'printer_state': 'OFFLINE'}
2023-03-08 17:41:27,902 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial connection"
2023-03-08 17:41:27,930 - octoprint.util.comm - INFO - Serial detection: Performing autodetection with 1 port/baudrate candidates: /dev/ttyACM0@115200
2023-03-08 17:41:27,931 - octoprint.util.comm - INFO - Serial detection: Trying port /dev/ttyACM0, baudrate 115200
2023-03-08 17:41:27,934 - octoprint.util.comm - INFO - Connecting to port /dev/ttyACM0, baudrate 115200
2023-03-08 17:41:27,946 - octoprint.util.comm - INFO - Serial detection: Handshake attempt #1 with timeout 2.0s
2023-03-08 17:41:27,955 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2023-03-08 17:41:27,969 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial connection" to "Operational"
2023-03-08 17:41:27,987 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2023-03-08 17:41:28,078 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin 2.0.9.3 (Jun  2 2022 19:36:35)"
2023-03-08 17:41:28,124 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2023-03-08 17:41:28,592 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {'firmware_name': 'Marlin 2.0.9.3 (Jun  2 2022 19:36:35)', 'printer_port': 'AUTO', 'printer_baudrate': 115200}
2023-03-08 17:41:30,210 - octoprint.plugins.action_command_notification - INFO - Got a notification: Media Init Fail
2023-03-08 17:41:48,611 - octoprint.plugins.action_command_notification - INFO - Got a notification: Bed Heating...
2023-03-08 17:41:56,239 - octoprint.plugins.softwareupdate - INFO - Starting update of octoprint to 1.9.0rc2...
2023-03-08 17:43:36,511 - octoprint.plugins.softwareupdate - INFO - Update of octoprint to 1.9.0rc2 successful!
2023-03-08 17:43:36,659 - octoprint.plugins.softwareupdate - INFO - Saved update log to disk
2023-03-08 17:43:36,723 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2023-03-08 17:43:36,728 - octoprint.plugins.softwareupdate - INFO - Restarting...
2023-03-08 17:43:36,860 - octoprint.plugins.tracking - INFO - Sent tracking event update_successful, payload: {'target': 'octoprint', 'from_version': '1.8.6', 'to_version': '1.9.0rc2'}
2023-03-08 17:43:37,280 - octoprint.server - INFO - Shutting down...
2023-03-08 17:43:37,781 - octoprint.server - INFO - Calling on_shutdown on plugins
2023-03-08 17:43:37,783 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2023-03-08 17:43:37,787 - octoprint.events - INFO - Event loop shut down
2023-03-08 17:43:38,128 - octoprint.plugins.tracking - INFO - Sent tracking event shutdown, payload: {}
2023-03-08 17:43:38,131 - octoprint.server - INFO - Goodbye!
pi@octopi:~ $ 

Additional information about your setup

Please the systeminfo bundle!

It includes more information.

unable - this is what I see:

that's basically the problem.

also:
image

You may try this:

2 Likes

Thank you. I reinstalled 1.8.7 (last stable) - and it works perfectly fine now :slight_smile:

1 Like

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.