What is the problem? So today I went to my usual browser on the computer I use all the time to print (last used Octoprint last night from this mac). It told me to login. It would not let me login (using the browser saved password. Following the guide I figured somehow the password had gotten munged, so went in via SSH and did the instructions, but that failed [note on the weirder side, it worked fine via my iPad with the same password]. And on the mac that is failing, my PrusaSlicer can upload no-problem to octoprint.
pi@octopi:~ source ~/oprint/bin/activate
(oprint) pi@octopi:~ user password henryhbk
-bash: user: command not found
That seemed somewhat ominous. So luckily some time back I had created a second user (admin) from a chrome box I had nearby so I could separate log entries if I ever needed to debug something. That login still worked, so I went in via the GUI and reset the password on the user above (henryhbk), I still cannot login via the browser.
WRITE HERE
What did you already try to solve it?
see above
Have you tried running in safe mode?
no
Did running in safe mode solve the problem?
WRITE HERE
Complete Logs
[NOTE I tructated the log as it is 48MB in size [and even zipped it is still too big]. So this is the log since last shutdown:
2021-01-17 13:48:27,677 - octoprint.server - INFO - Shutting down...
2021-01-17 13:48:28,669 - octoprint.server - INFO - Calling on_shutdown on plugins
2021-01-17 13:48:28,670 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2021-01-17 13:48:28,679 - octoprint.events - INFO - Event loop shut down
2021-01-17 13:48:29,662 - octoprint.plugins.tracking - INFO - Sent tracking event shutdown, payload: {}
2021-01-17 13:48:29,706 - octoprint.plugins.thespaghettidetective - ERROR - Server websocket is closing
2021-01-17 13:48:29,745 - octoprint.plugins.thespaghettidetective - WARNING - WS ERROR: Connection is already closed.
2021-01-17 13:48:29,747 - octoprint.plugins.thespaghettidetective - ERROR - Backing off 0.131884 seconds: Janus WS connection closed!
2021-01-17 13:48:30,893 - root - ERROR - {"remote_status": {"should_watch": false}, "type": "printer.message"}
2021-01-17 13:48:30,896 - root - ERROR - {"remote_status": {"viewing": false}, "type": "printer.message"}
2021-01-17 13:48:31,457 - octoprint.server - INFO - Goodbye!
2021-01-17 13:48:40,335 - octoprint.startup - INFO - ******************************************************************************
2021-01-17 13:48:40,336 - octoprint.startup - INFO - Starting OctoPrint 1.5.2
2021-01-17 13:48:40,337 - octoprint.startup - INFO - ******************************************************************************
2021-01-17 13:48:46,885 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity state is currently: offline
2021-01-17 13:48:46,886 - octoprint.util.connectivity.connectivity_checker - INFO - Connecting to 8.8.8.8:53 is not working
2021-01-17 13:48:46,888 - octoprint.util.connectivity.connectivity_checker - INFO - Resolving octoprint.org is not working
2021-01-17 13:48:46,898 - octoprint.startup - INFO - We don't appear to be online, not fetching plugin blacklist
2021-01-17 13:48:46,977 - 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...
2021-01-17 13:48:48,308 - octoprint.plugin.core - ERROR - Invalid syntax in /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_mmu2filamentselect/init.py for plugin mmu2filamentselect
Traceback (most recent call last):
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/plugin/core.py", line 762, 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)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint_mmu2filamentselect/init.py", line 125
b"octoprint.plugin.softwareupdate.check_config": plugin_implementation.get_update_information
^
SyntaxError: invalid syntax
2021-01-17 13:48:50,262 - octoprint.startup - INFO - Added settings overlay from plugin dashboard
2021-01-17 13:48:50,272 - octoprint.plugin.core - INFO - Found 27 plugin(s) providing 26 mixin implementations, 60 hook handlers
2021-01-17 13:48:50,397 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2021-01-17 13:48:50,441 - octoprint.server - INFO - Intermediary server started
2021-01-17 13:48:50,459 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2021-01-17 13:48:50,708 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2021-01-17 13:48:50,712 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_SHOW (needs: u"Need(method='role', value=u'plugin_action_command_notification_show')")
2021-01-17 13:48:50,712 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_CLEAR (needs: u"Need(method='role', value=u'plugin_action_command_notification_clear')")
2021-01-17 13:48:50,713 - octoprint.server - INFO - Added new permission from plugin action_command_prompt: PLUGIN_ACTION_COMMAND_PROMPT_INTERACT (needs: u"Need(method='role', value=u'plugin_action_command_prompt_interact')")
2021-01-17 13:48:50,714 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: u"Need(method='role', value=u'plugin_announcements_read')")
2021-01-17 13:48:50,716 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_MANAGE (needs: u"Need(method='role', value=u'plugin_announcements_manage'), Need(method='role', value=u'plugin_announcements_read')")
2021-01-17 13:48:50,717 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: u"Need(method='role', value=u'plugin_appkeys_admin')")
2021-01-17 13:48:50,720 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: u"Need(method='role', value=u'plugin_backup_access')")
2021-01-17 13:48:50,721 - octoprint.server - INFO - Added new permission from plugin dashboard: PLUGIN_DASHBOARD_ADMIN (needs: u"Need(method='role', value=u'plugin_dashboard_admin')")
2021-01-17 13:48:50,723 - octoprint.server - INFO - Added new permission from plugin firmware_check: PLUGIN_FIRMWARE_CHECK_DISPLAY (needs: u"Need(method='role', value=u'plugin_firmware_check_display')")
2021-01-17 13:48:50,724 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: u"Need(method='role', value=u'plugin_logging_manage')")
2021-01-17 13:48:50,725 - octoprint.server - INFO - Added new permission from plugin pi_support: PLUGIN_PI_SUPPORT_STATUS (needs: u"Need(method='role', value=u'plugin_pi_support_check')")
2021-01-17 13:48:50,726 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: u"Need(method='role', value=u'plugin_pluginmanager_manage')")
2021-01-17 13:48:50,727 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_INSTALL (needs: u"Need(method='role', value=u'plugin_pluginmanager_install'), Need(method='role', value=u'plugin_pluginmanager_manage')")
2021-01-17 13:48:50,729 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: u"Need(method='role', value=u'plugin_softwareupdate_check')")
2021-01-17 13:48:50,730 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: u"Need(method='role', value=u'plugin_softwareupdate_update')")
2021-01-17 13:48:50,731 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CONFIGURE (needs: u"Need(method='role', value=u'plugin_softwareupdate_configure')")
2021-01-17 13:48:50,973 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2021-01-17 13:48:52,171 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2021-01-17 13:48:52,985 - octoprint.util.pip - INFO - Version of pip is 19.1.1
2021-01-17 13:48:52,987 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-01-17 13:48:52,987 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-01-17 13:48:52,996 - octoprint.plugin.core - INFO - Initialized 26 plugin implementation(s)
2021-01-17 13:48:53,021 - octoprint.plugin.core - INFO - 27 plugin(s) registered with the system:
| Access Anywhere - The Spaghetti Detective (1.4.7) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_thespaghettidetective
| 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
| Backup & Restore (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/backup
| Bed Visualizer (1.0.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_bedlevelvisualizer
| Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
| Dashboard (1.17.0) = /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
| 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
| GCode Viewer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/gcodeviewer
| 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
| Octolapse (0.4.1) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octolapse
| OctoPrint-Mmu2filamentselect (1.0.1) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_mmu2filamentselect
| OctoVox Plugin (0.1.6) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octovox
| 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
| Resource Monitor (0.2.8) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_resource_monitor
| Simple Filament Change Buttons (1.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_simple_filament_change_buttons
| 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
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2021-01-17 13:48:53,055 - octoprint.environment - INFO - Detected environment is Python 2.7.16 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1500.0
| ram: 1903988736
| 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.1.1
| version: 2.7.16
| virtualenv: /home/pi/oprint
2021-01-17 13:48:53,072 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2021-01-17 13:48:53,100 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2021-01-17 13:48:54,051 - octoprint.server - INFO - Shutting down intermediary server...
2021-01-17 13:48:54,315 - octoprint.server - INFO - Intermediary server shut down
2021-01-17 13:48:54,318 - octoprint.events - INFO - Processing startup event, this is our first event
2021-01-17 13:48:54,319 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2021-01-17 13:48:54,322 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2021-01-17 13:48:54,323 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2021-01-17 13:48:54,339 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2021-01-17 13:48:54,361 - octoprint.server - INFO - Trying to connect to configured serial port None
2021-01-17 13:48:54,399 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial connection"
2021-01-17 13:48:54,401 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2021-01-17 13:48:54,404 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2021-01-17 13:48:54,408 - octoprint.plugins.octovox - INFO - Starting Timer...
2021-01-17 13:48:54,425 - octoprint.util.comm - INFO - Serial detection: Performing autodetection with 1 port/baudrate candidates: /dev/ttyACM0@115200
2021-01-17 13:48:54,426 - octoprint.util.comm - INFO - Serial detection: Trying port /dev/ttyACM0, baudrate 115200
2021-01-17 13:48:54,428 - octoprint.util.comm - INFO - Connecting to port /dev/ttyACM0, baudrate 115200
2021-01-17 13:48:54,464 - octoprint.util.comm - INFO - Serial detection: Handshake attempt #1 with timeout 2.0s
2021-01-17 13:48:54,498 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-01-17 13:48:55,490 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial connection" to "Operational"
2021-01-17 13:48:55,497 - octoprint.plugins.octovox - INFO - Stopping Timer...
2021-01-17 13:48:55,500 - octoprint.plugins.octovox - INFO - Starting Timer...
2021-01-17 13:48:55,568 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-01-17 13:48:55,588 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance "Prusa i3mk3"._http._tcp.local.' for _http._tcp.local.
2021-01-17 13:48:56,403 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance "Prusa i3mk3"._octoprint._tcp.local.' for _octoprint._tcp.local.
2021-01-17 13:48:56,414 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance "Prusa i3mk3" for SSDP
2021-01-17 13:48:56,481 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2021-01-17 13:48:56,502 - octoprint.plugins.announcements - INFO - Looks like we are offline, can't fetch announcements for channel _important from network
2021-01-17 13:48:56,505 - octoprint.plugins.announcements - INFO - Looks like we are offline, can't fetch announcements for channel _releases from network
2021-01-17 13:48:56,564 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-01-17 13:48:56,615 - octoprint.plugins.announcements - INFO - Looks like we are offline, can't fetch announcements for channel _blog from network
2021-01-17 13:48:56,667 - octoprint.plugins.announcements - INFO - Looks like we are offline, can't fetch announcements for channel _plugins from network
2021-01-17 13:48:56,817 - octoprint.plugins.announcements - INFO - Looks like we are offline, can't fetch announcements for channel _octopi from network
2021-01-17 13:48:57,525 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2021-01-17 13:48:57,526 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-01-17 13:48:57,527 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-01-17 13:48:57,532 - octoprint.plugins.softwareupdate - INFO - Minimum free storage across all update relevant locations is 87.3GB. That is considered sufficient for updating.
2021-01-17 13:48:57,533 - octoprint.plugins.softwareupdate - INFO - Not fetching check overlays, we are offline
2021-01-17 13:48:57,747 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity changed from offline to online
2021-01-17 13:48:57,795 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-01-17 13:48:58,524 - octoprint.plugins.announcements - INFO - Loaded channel _important from Important OctoPrint Announcements in 0.76s
2021-01-17 13:48:58,536 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2021-01-17 13:48:58,567 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::10ec:a230:d8ab:e1ca
2021-01-17 13:48:58,748 - octoprint.util.comm - INFO - Printer reports firmware name "Prusa-Firmware 3.9.3 based on Marlin"
2021-01-17 13:48:58,984 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-01-17 13:48:59,323 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': None, u'firmware_name': u'Prusa-Firmware 3.9.3 based on Marlin'}
2021-01-17 13:48:59,613 - octoprint.plugins.announcements - INFO - Loaded channel _releases from OctoPrint Release Announcements in 0.49s
2021-01-17 13:48:59,659 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2021-01-17 13:48:59,664 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-01-17 13:48:59,669 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-01-17 13:48:59,728 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2021-01-17 13:48:59,739 - octoprint.plugins.bedlevelvisualizer - INFO - OctoPrint-BedLevelVisualizer loaded!
2021-01-17 13:48:59,740 - octoprint.plugins.dashboard - INFO - Dashboard started
2021-01-17 13:48:59,746 - octoprint.plugins.octovox - INFO - OctoVox Is Alive!
2021-01-17 13:48:59,747 - octoprint.plugins.octovox - INFO - Stopping Timer...
2021-01-17 13:48:59,748 - octoprint.plugins.octovox - INFO - Starting Timer...
2021-01-17 13:49:00,141 - octoprint.plugins.announcements - INFO - Loaded channel _blog from OctoBlog in 0.47s
2021-01-17 13:49:00,159 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11}
2021-01-17 13:49:00,229 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://octopi.local/'}
2021-01-17 13:49:00,285 - octoprint.plugins.thespaghettidetective - INFO - User account: {u'is_pro': True}
2021-01-17 13:49:00,299 - octoprint.plugins.thespaghettidetective - INFO - Starting webcam streamer
2021-01-17 13:49:00,763 - root - ERROR - {"remote_status": {"should_watch": false}, "type": "printer.message"}
2021-01-17 13:49:00,766 - root - ERROR - {"remote_status": {"viewing": false}, "type": "printer.message"}
2021-01-17 13:49:00,923 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {u'python': '2.7.16', u'os': u'linux', u'ram': 1903988736, u'octopi_version': u'0.17.0', u'version': u'1.5.2', u'pip': '19.1.1', u'cores': 4, u'freq': 1500.0, u'pi_model': u'Raspberry Pi 4 Model B Rev 1.1', u'bits': 32}
2021-01-17 13:49:01,052 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from OctoPrint Plugin Repository in 0.85s
2021-01-17 13:49:01,377 - backoff - INFO - Backing off wait_for_janus(...) for 0.7s (error: [Errno 111] Connection refused)
2021-01-17 13:49:01,398 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {u'python': '2.7.16', u'os': u'linux', u'ram': 1903988736, u'octopi_version': u'0.17.0', u'version': u'1.5.2', u'plugins': u'displaylayerprogress:1.24.0,simple_filament_change_buttons:1.0,octovox:0.1.6,resource_monitor:0.2.8,bedlevelvisualizer:1.0.0,thespaghettidetective:1.4.7,navbartemp:0.14,dashboard:1.17.0,octolapse:0.4.1,mmu2filamentselect:1.0.1', u'pip': '19.1.1', u'cores': 4, u'freq': 1500.0, u'pi_model': u'Raspberry Pi 4 Model B Rev 1.1', u'bits': 32}
2021-01-17 13:49:01,709 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from OctoPi Announcements in 0.59s
2021-01-17 13:49:03,167 - backoff - INFO - Backing off wait_for_janus(...) for 1.2s (error: [Errno 111] Connection refused)
2021-01-17 13:49:27,115 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.20
2021-01-17 13:49:27,308 - octoprint.server.util.flask - INFO - Passively logging in user Chrome from ::ffff:192.168.1.20
2021-01-17 13:49:27,321 - octoprint.access.users - INFO - Logged in user: Chrome
2021-01-17 13:49:27,410 - octoprint.server.util.sockjs - INFO - User Chrome logged in on the socket from client ::ffff:192.168.1.20
2021-01-17 13:49:29,130 - octoprint.server.preemptive_cache - INFO - ... done in 12.65s
2021-01-17 13:49:29,131 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.1.55/'}
2021-01-17 13:49:29,676 - octoprint.server.preemptive_cache - INFO - ... done in 0.55s
2021-01-17 13:51:15,112 - octoprint.server.util.flask - INFO - Passively logging in user Chrome from ::ffff:192.168.1.20
2021-01-17 13:51:15,113 - octoprint.access.users - INFO - Logged in user: Chrome
2021-01-17 13:51:15,218 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.20
2021-01-17 13:51:16,833 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.20
2021-01-17 13:51:16,863 - octoprint.server.util.flask - INFO - Passively logging in user Chrome from ::ffff:192.168.1.20
2021-01-17 13:51:16,865 - octoprint.access.users - INFO - Logged in user: Chrome
2021-01-17 13:51:19,079 - octoprint.server.util.sockjs - INFO - User Chrome logged in on the socket from client ::ffff:192.168.1.20
2021-01-17 14:03:50,399 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-01-17 14:04:00,166 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 895}
2021-01-17 14:05:17,518 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::10ec:a230:d8ab:e1ca
2021-01-17 14:05:17,789 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::10ec:a230:d8ab:e1ca
2021-01-17 14:05:26,641 - tornado.access - WARNING - 403 POST /api/login (fe80::10ec:a230:d8ab:e1ca) 36.88ms
2021-01-17 14:05:37,802 - tornado.access - WARNING - 403 POST /api/login (fe80::10ec:a230:d8ab:e1ca) 14.70ms
2021-01-17 14:05:43,202 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::10ec:a230:d8ab:e1ca
2021-01-17 14:05:44,466 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::10ec:a230:d8ab:e1ca
2021-01-17 14:05:56,849 - tornado.access - WARNING - 403 POST /api/login (fe80::10ec:a230:d8ab:e1ca) 16.20ms
2021-01-17 14:18:50,401 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-01-17 14:19:00,171 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1795}
2021-01-17 14:21:26,078 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2021-01-17 14:33:50,402 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-01-17 14:34:00,170 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 2695}
2021-01-17 14:48:50,404 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-01-17 14:49:00,182 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 3595}
2021-01-17 15:02:07,337 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 2s
2021-01-17 15:03:50,405 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-01-17 15:04:00,182 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 4495}
2021-01-17 15:07:39,406 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: battery_holder_0.15mm_PC_MK3S_5h7m.gcode, owner: Chrome, user: Chrome
2021-01-17 15:07:39,428 - octoprint.plugins.DisplayLayerProgress - INFO - File '/home/pi/.octoprint/uploads/battery_holder_0.15mm_PC_MK3S_5h7m.gcode' selected. Determining number of layers.
2021-01-17 15:07:39,446 - octoprint.plugins.DisplayLayerProgress - INFO - Read total height from MetaFile
2021-01-17 15:07:40,664 - octoprint.plugins.DisplayLayerProgress - INFO - Store layer count in MetaFile
2021-01-17 15:07:40,693 - octoprint.plugins.DisplayLayerProgress - INFO - File select-event processing done!'
2021-01-17 15:07:49,153 - octoprint.util.comm - INFO - Starting job on behalf of user Chrome
2021-01-17 15:07:49,155 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2021-01-17 15:07:49,163 - octoprint.plugins.octovox - INFO - Stopping Timer...
2021-01-17 15:07:49,165 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: battery_holder_0.15mm_PC_MK3S_5h7m.gcode, owner: Chrome, user: Chrome
2021-01-17 15:07:49,166 - octoprint.plugins.octovox - INFO - Starting Timer...
2021-01-17 15:07:49,279 - octoprint.plugins.DisplayLayerProgress - INFO - Printing started. Detailed progress started.{u'origin': u'local', u'name': u'battery_holder_0.15mm_PC_MK3S_5h7m.gcode', u'user': 'Chrome', u'owner': 'Chrome', u'path': u'battery_holder_0.15mm_PC_MK3S_5h7m.gcode', u'size': 6108155L}
2021-01-17 15:07:49,286 - octoprint.plugins.octovox - INFO - Stopping Timer...
2021-01-17 15:07:49,295 - octoprint.plugins.octovox - INFO - Starting Timer...
2021-01-17 15:07:49,457 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-01-17 15:07:49,470 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2021-01-17 15:07:49,478 - root - ERROR - {"remote_status": {"should_watch": true}, "type": "printer.message"}
2021-01-17 15:07:49,478 - octoprint.plugins.octovox - INFO - Stopping Timer...
2021-01-17 15:07:49,487 - octoprint.plugins.octovox - INFO - Starting Timer...
2021-01-17 15:07:49,728 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {u'origin': u'local', u'file': '8fe5a8a6dbc48814425899148728a63054c55b82'}
2021-01-17 15:11:42,723 - tornado.access - WARNING - 403 POST /api/login (fe80::10ec:a230:d8ab:e1ca) 21.50ms
2021-01-17 15:11:50,549 - tornado.access - WARNING - 403 POST /api/login (fe80::10ec:a230:d8ab:e1ca) 17.75ms
2021-01-17 15:11:53,411 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::10ec:a230:d8ab:e1ca
2021-01-17 15:11:53,645 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::10ec:a230:d8ab:e1ca
2021-01-17 15:12:06,773 - tornado.access - WARNING - 403 POST /api/login (fe80::10ec:a230:d8ab:e1ca) 35.97ms
2021-01-17 15:12:11,514 - tornado.access - WARNING - 403 POST /api/login (fe80::10ec:a230:d8ab:e1ca) 15.47ms
2021-01-17 15:12:21,675 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::10ec:a230:d8ab:e1ca
2021-01-17 15:12:23,308 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::10ec:a230:d8ab:e1ca
2021-01-17 15:18:44,268 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::10ec:a230:d8ab:e1ca
2021-01-17 15:18:44,781 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::10ec:a230:d8ab:e1ca
2021-01-17 15:18:50,406 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-01-17 15:19:00,581 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 5395}
Additional information about your setup
Latest octoprint version, Pi 4 with 2gb of RAM, on ethernet.