What is the problem?
After updating from 1.5.mumble to 1.5.3, attempts to view a print in the GCode Viewer tab never show, and trying to print causes the printer to throw an error and disconnect. When I looked in octoprint.log I see lines like:
2021-01-25 21:23:58,255 - tornado.application - ERROR - Uncaught exception GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21)
Traceback (most recent call last):
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 1592, in _execute
result = yield result
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 1133, in run
value = future.result()
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/concurrent.py", line 261, in result
raise_exc_info(self._exc_info)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 326, in wrapper
yielded = next(result)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2525, in get
for chunk in content:
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2689, in get_content
chunk = file.read(chunk_size)
IOError: [Errno 5] Input/output error
2021-01-25 21:23:58,303 - tornado.access - ERROR - 500 GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21) 517.85ms
However, relative to my .octoprint directory, that file is located at uploads/test_prints/OUT_CE3_tempTower_220_250.gcode
Everything was working prior to the 1.5.3 upgrade.
What did you already try to solve it?
I tried making a symbolic link downloads -> uploads but that didn't help.
Complete Logs
2021-01-25 21:21:45,486 - octoprint.startup - INFO - ******************************************************************************
2021-01-25 21:21:45,490 - octoprint.startup - INFO - Starting OctoPrint 1.5.3
2021-01-25 21:21:45,501 - octoprint.startup - INFO - ******************************************************************************
2021-01-25 21:21:47,984 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity state is currently: online
2021-01-25 21:21:47,992 - octoprint.util.connectivity.connectivity_checker - INFO - Connecting to 8.8.8.8:53 is working
2021-01-25 21:21:47,995 - octoprint.util.connectivity.connectivity_checker - INFO - Resolving octoprint.org is working
2021-01-25 21:21:48,842 - octoprint.startup - INFO - Blacklist processing done, adding 12 blacklisted plugin versions: roomtemp (any), GcodeEditor (0.1.1), GcodeEditor (0.2.0), GcodeEditor (0.2.1), GcodeEditor (0.2.2), GcodeEditor (0.2.3), GcodeEditor (0.2.4), GcodeEditor (0.2.5), GcodeEditor (0.2.6), GcodeEditor (0.2.8), gcodeleveling (0.1.0), gcodeleveling (0.1.1)
2021-01-25 21:21:49,152 - octoprint.plugin.core - INFO - Loading plugins from /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins, /home/octoprint/.octoprint/plugins and installed plugin packages...
2021-01-25 21:21:50,629 - octoprint.plugin.core - INFO - Plugin Pi Support Plugin did not pass check, not loading.
2021-01-25 21:21:53,469 - octoprint.plugin.core - WARNING - Plugin Marlin GCode Documentation (0.9.0) is not compatible to Python 2.7.13 (compatibility string: >=3,<4).
2021-01-25 21:21:54,483 - octoprint.plugin.core - INFO - Found 21 plugin(s) providing 20 mixin implementations, 44 hook handlers
2021-01-25 21:21:54,898 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2021-01-25 21:21:54,977 - octoprint.server - INFO - Intermediary server started
2021-01-25 21:21:55,083 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/octoprint/.octoprint/uploads...
2021-01-25 21:21:57,468 - octoprint.filemanager.storage - INFO - ... file metadata for /home/octoprint/.octoprint/uploads initialized successfully.
2021-01-25 21:21:57,487 - 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-25 21:21:57,496 - 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-25 21:21:57,507 - 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-25 21:21:57,517 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: u"Need(method='role', value=u'plugin_announcements_read')")
2021-01-25 21:21:57,527 - 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-25 21:21:57,537 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: u"Need(method='role', value=u'plugin_appkeys_admin')")
2021-01-25 21:21:57,543 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: u"Need(method='role', value=u'plugin_backup_access')")
2021-01-25 21:21:57,556 - 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-25 21:21:57,566 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: u"Need(method='role', value=u'plugin_logging_manage')")
2021-01-25 21:21:57,577 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: u"Need(method='role', value=u'plugin_pluginmanager_manage')")
2021-01-25 21:21:57,586 - 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-25 21:21:57,597 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: u"Need(method='role', value=u'plugin_softwareupdate_check')")
2021-01-25 21:21:57,606 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: u"Need(method='role', value=u'plugin_softwareupdate_update')")
2021-01-25 21:21:57,614 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CONFIGURE (needs: u"Need(method='role', value=u'plugin_softwareupdate_configure')")
2021-01-25 21:21:57,687 - octoprint.printer.standard - INFO - Using print time estimator provided by PrintTimeGenius
2021-01-25 21:21:58,573 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2021-01-25 21:22:01,375 - octoprint.util.pip - INFO - Using "/home/octoprint/OctoPrint/bin/python2 -m pip" as command to invoke pip
2021-01-25 21:22:03,833 - octoprint.util.pip - INFO - Version of pip is 20.0.2
2021-01-25 21:22:03,843 - octoprint.util.pip - INFO - pip installs to /home/octoprint/OctoPrint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-01-25 21:22:03,849 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-01-25 21:22:03,917 - octoprint.plugin.core - INFO - Initialized 20 plugin implementation(s)
2021-01-25 21:22:04,000 - octoprint.plugin.core - INFO - 21 plugin(s) registered with the system:
| Action Command Notification Support (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/action_command_notification
| Action Command Prompt Support (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/action_command_prompt
| Active Filters Extended (0.1.0) = /home/octoprint/OctoPrint/local/lib/python2.7/site-packages/octoprint_active_filters_extended
| Announcement Plugin (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/announcements
| Anonymous Usage Tracking (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/tracking
| Application Keys Plugin (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/appkeys
| Backup & Restore (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/backup
| Bed Visualizer (1.0.0) = /home/octoprint/OctoPrint/local/lib/python2.7/site-packages/octoprint_bedlevelvisualizer
| Core Wizard (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/corewizard
| Discovery (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Error Tracking (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/errortracking
| File Check (2020.8.7) (bundled) = /home/octoprint/OctoPrint/local/lib/python2.7/site-packages/octoprint_file_check
| Firmware Check (2020.9.23) (bundled) = /home/octoprint/OctoPrint/local/lib/python2.7/site-packages/octoprint_firmware_check
| Firmware Updater (1.7.7) = /home/octoprint/OctoPrint/local/lib/python2.7/site-packages/octoprint_firmwareupdater
| GCode Viewer (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/gcodeviewer
| Logging (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/logging
| *Marlin GCode Documentation (0.9.0) = /home/octoprint/OctoPrint/local/lib/python2.7/site-packages/octoprint_marlingcodedocumentation
| Plugin Manager (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/pluginmanager
| PrintTimeGenius Plugin (2.2.7) = /home/octoprint/OctoPrint/local/lib/python2.7/site-packages/octoprint_PrintTimeGenius
| Software Update (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
| Virtual Printer (bundled) = /home/octoprint/OctoPrint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2021-01-25 21:22:04,084 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 1
| freq: 1008.0
| ram: 514813952
| os:
| bits: 32
| id: linux
| platform: linux2
| python:
| pip: 20.0.2
| version: 2.7.13
| virtualenv: /home/octoprint/OctoPrint
2021-01-25 21:22:04,178 - octoprint.server - INFO - Reset webasset folder /home/octoprint/.octoprint/generated/webassets...
2021-01-25 21:22:04,541 - octoprint.server - INFO - Reset webasset folder /home/octoprint/.octoprint/generated/.webassets-cache...
2021-01-25 21:22:08,057 - octoprint.server - INFO - Shutting down intermediary server...
2021-01-25 21:22:08,204 - octoprint.server - INFO - Intermediary server shut down
2021-01-25 21:22:08,217 - octoprint.events - INFO - Processing startup event, this is our first event
2021-01-25 21:22:08,227 - octoprint.events - INFO - Adding 0 events to queue that were held back before startup event
2021-01-25 21:22:08,237 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2021-01-25 21:22:08,257 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2021-01-25 21:22:08,458 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2021-01-25 21:22:08,651 - octoprint.server - INFO - Trying to connect to configured serial port None
2021-01-25 21:22:08,854 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial connection"
2021-01-25 21:22:08,861 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2021-01-25 21:22:08,872 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2021-01-25 21:22:09,048 - octoprint.util.comm - INFO - Serial detection: Performing autodetection with 5 port/baudrate candidates: /dev/ttyACM0@115200, /dev/ttyS1@115200, /dev/ttyS0@115200, /dev/ttyS3@115200, /dev/ttyS2@115200
2021-01-25 21:22:09,054 - octoprint.util.comm - INFO - Serial detection: Trying port /dev/ttyACM0, baudrate 115200
2021-01-25 21:22:09,065 - octoprint.util.comm - INFO - Connecting to port /dev/ttyACM0, baudrate 115200
2021-01-25 21:22:09,088 - octoprint.util.comm - INFO - Serial detection: Handshake attempt #1 with timeout 2.0s
2021-01-25 21:22:09,104 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-01-25 21:22:09,152 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial connection" to "Operational"
2021-01-25 21:22:09,173 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-01-25 21:22:09,200 - octoprint.plugins.firmwareupdater - INFO - Got CONNECTED event
2021-01-25 21:22:09,231 - octoprint.plugins.firmwareupdater - INFO - Run postflash flag is not set
2021-01-25 21:22:09,385 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin TH3D U2.R1.B3"
2021-01-25 21:22:09,452 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2021-01-25 21:22:09,566 - octoprint.util.comm - INFO - Firmware states that it supports sd status autoreporting
2021-01-25 21:22:09,786 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on chip._http._tcp.local.' for _http._tcp.local.
2021-01-25 21:22:10,158 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': None, u'firmware_name': u'Marlin TH3D U2.R1.B3'}
2021-01-25 21:22:10,647 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on chip._octoprint._tcp.local.' for _octoprint._tcp.local.
2021-01-25 21:22:10,685 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on chip for SSDP
2021-01-25 21:22:10,851 - octoprint.server - INFO - Listening on http://0.0.0.0:5000 and http://[::]:5000
2021-01-25 21:22:11,091 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-01-25 21:22:12,139 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.1.21
2021-01-25 21:22:13,285 - octoprint.server.util.flask - INFO - Passively logging in user octodrew from 192.168.1.21
2021-01-25 21:22:13,304 - octoprint.access.users - INFO - Logged in user: octodrew
2021-01-25 21:22:13,551 - octoprint.server.util.sockjs - INFO - User octodrew logged in on the socket from client 192.168.1.21
2021-01-25 21:22:16,412 - octoprint.plugins.softwareupdate - INFO - Fetching check overlays from https://plugins.octoprint.org/update_check_overlay.json
2021-01-25 21:22:20,027 - octoprint.util.pip - INFO - Using "/home/octoprint/OctoPrint/bin/python2 -m pip" as command to invoke pip
2021-01-25 21:22:20,030 - octoprint.util.pip - INFO - pip installs to /home/octoprint/OctoPrint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-01-25 21:22:20,044 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-01-25 21:22:20,109 - octoprint.plugins.softwareupdate - INFO - Minimum free storage across all update relevant locations is 4.9GB. That is considered sufficient for updating.
2021-01-25 21:22:20,335 - octoprint.plugins.bedlevelvisualizer - INFO - OctoPrint-BedLevelVisualizer loaded!
2021-01-25 21:22:21,245 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 28}
2021-01-25 21:22:22,257 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {u'python': '2.7.13', u'bits': 32, u'ram': 514813952, u'version': u'1.5.3', u'plugins': u'bedlevelvisualizer:1.0.0,firmwareupdater:1.7.7,active_filters_extended:0.1.0,printtimegenius:2.2.7', u'pip': '20.0.2', u'cores': 1, u'freq': 1008.0, u'os': u'linux'}
2021-01-25 21:22:22,617 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://192.168.1.90:5000/'}
2021-01-25 21:22:23,265 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {u'python': '2.7.13', u'os': u'linux', u'ram': 514813952, u'version': u'1.5.3', u'pip': '20.0.2', u'cores': 1, u'freq': 1008.0, u'bits': 32}
2021-01-25 21:22:30,079 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-01-25 21:22:46,998 - octoprint.server.util.flask - INFO - Passively logging in user octodrew from 192.168.1.21
2021-01-25 21:22:47,005 - octoprint.access.users - INFO - Logged in user: octodrew
2021-01-25 21:23:37,794 - octoprint.server.preemptive_cache - INFO - ... done in 75.18s
2021-01-25 21:23:38,240 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.1.21
2021-01-25 21:23:40,958 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.1.21
2021-01-25 21:23:41,168 - octoprint.server.util.flask - INFO - Passively logging in user octodrew from 192.168.1.21
2021-01-25 21:23:41,175 - octoprint.access.users - INFO - Logged in user: octodrew
2021-01-25 21:23:48,468 - octoprint.server.util.sockjs - INFO - User octodrew logged in on the socket from client 192.168.1.21
2021-01-25 21:23:55,716 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: test_prints/OUT_CE3_tempTower_220_250.gcode, owner: octodrew, user: octodrew
2021-01-25 21:23:56,952 - tornado.application - ERROR - Uncaught exception GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21)
Traceback (most recent call last):
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 1592, in _execute
result = yield result
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 1133, in run
value = future.result()
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/concurrent.py", line 261, in result
raise_exc_info(self._exc_info)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 326, in wrapper
yielded = next(result)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2525, in get
for chunk in content:
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2689, in get_content
chunk = file.read(chunk_size)
IOError: [Errno 5] Input/output error
2021-01-25 21:23:56,997 - tornado.access - ERROR - 500 GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21) 644.76ms
2021-01-25 21:23:57,464 - tornado.application - ERROR - Uncaught exception GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21)
Traceback (most recent call last):
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 1592, in _execute
result = yield result
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 1133, in run
value = future.result()
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/concurrent.py", line 261, in result
raise_exc_info(self._exc_info)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 326, in wrapper
yielded = next(result)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2525, in get
for chunk in content:
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2689, in get_content
chunk = file.read(chunk_size)
IOError: [Errno 5] Input/output error
2021-01-25 21:23:57,489 - tornado.access - ERROR - 500 GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21) 418.59ms
2021-01-25 21:23:58,255 - tornado.application - ERROR - Uncaught exception GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21)
Traceback (most recent call last):
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 1592, in _execute
result = yield result
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 1133, in run
value = future.result()
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/concurrent.py", line 261, in result
raise_exc_info(self._exc_info)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/gen.py", line 326, in wrapper
yielded = next(result)
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2525, in get
for chunk in content:
File "/home/octoprint/OctoPrint/local/lib/python2.7/site-packages/tornado/web.py", line 2689, in get_content
chunk = file.read(chunk_size)
IOError: [Errno 5] Input/output error
2021-01-25 21:23:58,303 - tornado.access - ERROR - 500 GET /downloads/files/local/test_prints/OUT_CE3_tempTower_220_250.gcode (192.168.1.21) 517.85ms
2021-01-25 21:36:54,916 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-01-25 21:37:21,072 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 928}
Additional information about your setup
OctoPrint version, OctoPi version, printer, firmware, browser, operating system, ... as much data as possible
OctoPrint 1.5.3 on an NTC C.H.I.P. running Debian stretch. Single core ARM v7 Allwinner cpu (R-12 I think), 512MB ram, 8GB flash filesystem.