G-code loads, but is grayed out, wont print

Get Help
1

What is the problem?

A certain uploaded Creality Slicer G-code job will not run. It is kind of greyed out and won't load or load/run. The log shows an error, I have no idea what it means:

TypeError: argument of type 'NoneType' is not iterable
2021-03-30 06:00:16,110 - tornado.access - ERROR - 500 POST /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode (fe80::7405:bab4:3240:fead) 64.01ms

I have been trying to run a specific part for several days off and on, created in TinkerCad and Sliced by Creality Slicer 1.2.3 similar to hundreds of other jobs I have run over the past 2 months.

Now it is getting worse. I ran a job today successfully and the same g-code, never moved from OctoPrint, would not run 4 hours later. I had to reload it 3 times and then it ran.

What did you already try to solve it?

I tried many changes to the slicer parameters, no help. I loaded it many time and it always remained grayed out, would never run. I finally loaded it sneaker net to the SD card and it ran fine

I can load the questionable g-code to the SD card manually or through the Octo feature and the Ender Pro 3 will run it without an issue.

Have you tried running in safe mode?

Yes.

Did running in safe mode solve the problem?

No.

Complete Logs

octoprint.log, serial.log or output on terminal tab at a minimum, browser error console if UI issue ... no logs, no support! Not log excerpts, complete logs.)

This is from a reboot through the error:

2021-03-30 05:57:22,236 - octoprint.startup - INFO - ******************************************************************************
2021-03-30 05:57:22,238 - octoprint.startup - INFO - Starting OctoPrint 1.5.3
2021-03-30 05:57:22,241 - octoprint.startup - INFO - ******************************************************************************
2021-03-30 05:57:28,865 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity state is currently: offline
2021-03-30 05:57:28,865 - octoprint.util.connectivity.connectivity_checker - INFO - Connecting to 8.8.8.8:53 is not working
2021-03-30 05:57:28,866 - octoprint.util.connectivity.connectivity_checker - INFO - Resolving octoprint.org is not working
2021-03-30 05:57:28,874 - octoprint.startup - INFO - We don't appear to be online, not fetching plugin blacklist
2021-03-30 05:57:28,979 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2021-03-30 05:57:30,575 - octoprint.plugin.core - INFO - Plugin TpLinkHandler (1.0.4) is disabled.
2021-03-30 05:57:30,651 - octoprint.plugin.core - INFO - Plugin Tasmota (1.0.1) is disabled.
2021-03-30 05:57:30,833 - octoprint.startup - INFO - Added settings overlay from plugin firmware_check
2021-03-30 05:57:30,838 - octoprint.plugin.core - INFO - Found 21 plugin(s) providing 19 mixin implementations, 40 hook handlers
2021-03-30 05:57:31,052 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2021-03-30 05:57:31,081 - octoprint.server - INFO - Intermediary server started
2021-03-30 05:57:31,131 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2021-03-30 05:57:31,641 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2021-03-30 05:57:31,646 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_SHOW (needs: "Need(method='role', value='plugin_action_command_notification_show')")
2021-03-30 05:57:31,646 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_CLEAR (needs: "Need(method='role', value='plugin_action_command_notification_clear')")
2021-03-30 05:57:31,647 - octoprint.server - INFO - Added new permission from plugin action_command_prompt: PLUGIN_ACTION_COMMAND_PROMPT_INTERACT (needs: "Need(method='role', value='plugin_action_command_prompt_interact')")
2021-03-30 05:57:31,648 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: "Need(method='role', value='plugin_announcements_read')")
2021-03-30 05:57:31,648 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_MANAGE (needs: "Need(method='role', value='plugin_announcements_manage'), Need(method='role', value='plugin_announcements_read')")
2021-03-30 05:57:31,649 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: "Need(method='role', value='plugin_appkeys_admin')")
2021-03-30 05:57:31,650 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: "Need(method='role', value='plugin_backup_access')")
2021-03-30 05:57:31,650 - octoprint.server - INFO - Added new permission from plugin firmware_check: PLUGIN_FIRMWARE_CHECK_DISPLAY (needs: "Need(method='role', value='plugin_firmware_check_display')")
2021-03-30 05:57:31,651 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: "Need(method='role', value='plugin_logging_manage')")
2021-03-30 05:57:31,651 - octoprint.server - INFO - Added new permission from plugin pi_support: PLUGIN_PI_SUPPORT_STATUS (needs: "Need(method='role', value='plugin_pi_support_check')")
2021-03-30 05:57:31,655 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: "Need(method='role', value='plugin_pluginmanager_manage')")
2021-03-30 05:57:31,658 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_INSTALL (needs: "Need(method='role', value='plugin_pluginmanager_manage'), Need(method='role', value='plugin_pluginmanager_install')")
2021-03-30 05:57:31,660 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: "Need(method='role', value='plugin_softwareupdate_check')")
2021-03-30 05:57:31,662 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: "Need(method='role', value='plugin_softwareupdate_update')")
2021-03-30 05:57:31,665 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CONFIGURE (needs: "Need(method='role', value='plugin_softwareupdate_configure')")
2021-03-30 05:57:31,697 - octoprint.printer.standard - INFO - Using print time estimator provided by SlicerEstimator
2021-03-30 05:57:32,860 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python3 -m pip" as command to invoke pip
2021-03-30 05:57:33,750 - octoprint.util.pip - INFO - Version of pip is 20.3.3
2021-03-30 05:57:33,752 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-03-30 05:57:33,752 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-03-30 05:57:33,874 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2021-03-30 05:57:33,911 - octoprint.plugin.core - INFO - Initialized 19 plugin implementation(s)
2021-03-30 05:57:33,926 - octoprint.plugin.core - INFO - 21 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
| Core Wizard (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/corewizard
| Creality-2x-temperature-reporting-fix (0.0.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_ender3v2tempfix
| 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
| File Check (2021.2.23) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_file_check
| Firmware Check (2021.2.4) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_firmware_check
| 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
| Pi Support Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pi_support
| Plugin Manager (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pluginmanager
| Slicer Print Time Estimator (1.0.2) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_SlicerEstimator
| Software Update (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/softwareupdate
| !Tasmota (1.0.1) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_tasmota
| !TpLinkHandler (1.0.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_TpLinkAutoShutdown
| Virtual Printer (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2021-03-30 05:57:33,959 - octoprint.environment - INFO - Detected environment is Python 3.7.3 under Linux (linux). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 915718144
| os:
| bits: 32
| id: linux
| platform: linux
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.18.0
| throttle_state: '0x0'
| python:
| pip: 20.3.3
| version: 3.7.3
| virtualenv: /home/pi/oprint
2021-03-30 05:57:33,974 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2021-03-30 05:57:33,994 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2021-03-30 05:57:34,772 - octoprint.server - INFO - Shutting down intermediary server...
2021-03-30 05:57:34,991 - octoprint.server - INFO - Intermediary server shut down
2021-03-30 05:57:34,994 - octoprint.events - INFO - Processing startup event, this is our first event
2021-03-30 05:57:34,994 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2021-03-30 05:57:34,995 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2021-03-30 05:57:35,000 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2021-03-30 05:57:35,012 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2021-03-30 05:57:35,059 - octoprint.server - INFO - Trying to connect to configured serial port /dev/ttyUSB0
2021-03-30 05:57:35,104 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial connection"
2021-03-30 05:57:35,106 - octoprint.util.comm - INFO - Connecting to port /dev/ttyUSB0, baudrate 115200
2021-03-30 05:57:35,111 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2021-03-30 05:57:35,115 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2021-03-30 05:57:35,128 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial connection" to "Connecting"
2021-03-30 05:57:35,227 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 05:57:35,239 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 05:57:35,241 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2021-03-30 05:57:35,267 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 05:57:35,297 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin Creality 3D"
2021-03-30 05:57:35,307 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2021-03-30 05:57:35,929 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi._http._tcp.local.' for _http._tcp.local.
2021-03-30 05:57:36,746 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi._octoprint._tcp.local.' for _octoprint._tcp.local.
2021-03-30 05:57:36,768 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2021-03-30 05:57:36,789 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2021-03-30 05:57:36,892 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-03-30 05:57:37,910 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python3 -m pip" as command to invoke pip
2021-03-30 05:57:37,917 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.227
2021-03-30 05:57:37,923 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-03-30 05:57:37,955 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-03-30 05:57:38,034 - octoprint.plugins.softwareupdate - INFO - Minimum free storage across all update relevant locations is 52.8GB. That is considered sufficient for updating.
2021-03-30 05:57:38,034 - octoprint.plugins.softwareupdate - INFO - Not fetching check overlays, we are offline
2021-03-30 05:57:38,176 - octoprint.server.util.flask - INFO - Passively logging in user ghb from ::ffff:192.168.1.227
2021-03-30 05:57:38,183 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 05:57:38,600 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity changed from offline to online
2021-03-30 05:57:38,729 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-03-30 05:57:39,330 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-03-30 05:57:40,593 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python3 -m pip" as command to invoke pip
2021-03-30 05:57:40,594 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-03-30 05:57:40,594 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-03-30 05:57:40,809 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2021-03-30 05:57:40,856 - octoprint.plugins.SlicerEstimator - INFO - Started up SlicerEstimator
2021-03-30 05:57:41,396 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-03-30 05:57:41,624 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'base_url': 'http://octopi.local/', 'path': '/', 'query_string': 'l10n=en'}
2021-03-30 05:57:41,766 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 10}
2021-03-30 05:57:41,952 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.227
2021-03-30 05:57:42,360 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'version': '1.5.3', 'os': 'linux', 'bits': 32, 'python': '3.7.3', 'pip': '20.3.3', 'cores': 4, 'freq': 1400.0, 'ram': 915718144, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'octopi_version': '0.18.0', 'plugins': 'slicerestimator:1.0.2,ender3v2tempfix:0.0.4'}
2021-03-30 05:57:42,718 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 05:57:42,719 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 05:57:42,933 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'version': '1.5.3', 'os': 'linux', 'bits': 32, 'python': '3.7.3', 'pip': '20.3.3', 'cores': 4, 'freq': 1400.0, 'ram': 915718144, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'octopi_version': '0.18.0'}
2021-03-30 05:58:13,219 - octoprint.server.preemptive_cache - INFO - ... done in 20.16s
2021-03-30 05:58:13,278 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.227
2021-03-30 05:58:14,208 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 05:58:14,209 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 05:58:14,807 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 05:58:14,843 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 05:58:14,843 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 05:58:17,015 - octoprint.server.util.sockjs - INFO - User ghb logged in on the socket from client fe80::7405:bab4:3240:fead
2021-03-30 05:58:41,248 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead
2021-03-30 05:58:41,504 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 05:58:41,601 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 05:58:41,662 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead
2021-03-30 05:58:41,683 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 05:58:41,684 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 05:58:41,868 - octoprint.server.util.sockjs - INFO - User ghb logged in on the socket from client fe80::7405:bab4:3240:fead
2021-03-30 06:00:09,307 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Bing_Gluing_Clamp_Long_Convx_Pair.gcode, owner: ghb, user: ghb
2021-03-30 06:00:09,323 - octoprint - ERROR - Exception on /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode [POST]
Traceback (most recent call last):
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 2447, in wsgi_app
response = self.full_dispatch_request()
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1952, in full_dispatch_request
rv = self.handle_user_exception(e)
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1821, in handle_user_exception
reraise(exc_type, exc_value, tb)
File "/home/pi/oprint/lib/python3.7/site-packages/flask/_compat.py", line 39, in reraise
raise value
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1950, in full_dispatch_request
rv = self.dispatch_request()
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1936, in dispatch_request
return self.view_functionsrule.endpoint
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/util/flask.py", line 1539, in decorated_view
return func(*args, **kwargs)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/api/files.py", line 802, in gcodeFileCommand
printer.select_file(filenameToSelect, sd, printAfterLoading, user)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 680, in select_file
tags=kwargs.get("tags", set()) | {"trigger:printer.select_file"},
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/util/comm.py", line 1599, in selectFile
filename, self._currentFile.getFilesize(), False, user=user
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1545, in on_comm_file_selected
self._setJobData(full_path, size, sd, user=user)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1282, in _setJobData
and "estimatedPrintTime" in fileData["analysis"]
TypeError: argument of type 'NoneType' is not iterable
2021-03-30 06:00:09,344 - tornado.access - ERROR - 500 POST /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode (fe80::7405:bab4:3240:fead) 75.50ms
2021-03-30 06:00:16,085 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Bing_Gluing_Clamp_Long_Convx_Pair.gcode, owner: ghb, user: ghb
2021-03-30 06:00:16,099 - octoprint - ERROR - Exception on /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode [POST]
Traceback (most recent call last):
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 2447, in wsgi_app
response = self.full_dispatch_request()
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1952, in full_dispatch_request
rv = self.handle_user_exception(e)
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1821, in handle_user_exception
reraise(exc_type, exc_value, tb)
File "/home/pi/oprint/lib/python3.7/site-packages/flask/_compat.py", line 39, in reraise
raise value
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1950, in full_dispatch_request
rv = self.dispatch_request()
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1936, in dispatch_request
return self.view_functionsrule.endpoint
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/util/flask.py", line 1539, in decorated_view
return func(*args, **kwargs)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/api/files.py", line 802, in gcodeFileCommand
printer.select_file(filenameToSelect, sd, printAfterLoading, user)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 680, in select_file
tags=kwargs.get("tags", set()) | {"trigger:printer.select_file"},
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/util/comm.py", line 1599, in selectFile
filename, self._currentFile.getFilesize(), False, user=user
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1545, in on_comm_file_selected
self._setJobData(full_path, size, sd, user=user)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1282, in _setJobData
and "estimatedPrintTime" in fileData["analysis"]
TypeError: argument of type 'NoneType' is not iterable
2021-03-30 06:00:16,110 - tornado.access - ERROR - 500 POST /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode (fe80::7405:bab4:3240:fead) 64.01ms

Note:
I loaded the g-code in question for the third time and it was loading very slowly so I started the printer which connected while g-code was loading. It was not grayed out so I started the print job which is running fine. This log is from the period I just described:

2021-03-30 06:00:53,756 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead
2021-03-30 06:12:31,055 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-03-30 06:12:41,908 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 898}
2021-03-30 06:12:50,160 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.227
2021-03-30 06:23:05,452 - octoprint.util.comm - ERROR - Unexpected error while reading from serial port
Traceback (most recent call last):
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/util/comm.py", line 3831, in _readline
ret = self._serial.readline()
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/util/comm.py", line 6455, in readline
c = self.read(1)
File "/home/pi/oprint/lib/python3.7/site-packages/serial/serialposix.py", line 596, in read
'device reports readiness to read but returned no data '
serial.serialutil.SerialException: device reports readiness to read but returned no data (device disconnected or multiple access on port?)
2021-03-30 06:23:05,471 - octoprint.util.comm - ERROR - Please see https://faq.octoprint.org/serialerror for possible reasons of this.
2021-03-30 06:23:05,493 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Offline (Error: SerialException: 'device reports readiness to read but returned no data (device disconnected or multiple access on port?)' @ comm.py:_readline:3831)"
2021-03-30 06:23:05,520 - octoprint.plugins.action_command_notification - INFO - Notifications cleared
2021-03-30 06:27:31,057 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-03-30 06:27:41,583 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1798}
2021-03-30 06:37:37,047 - octoprint.plugins.pi_support - WARNING - This Raspberry Pi is reporting problems that might lead to bad performance or errors caused by overheating or insufficient power.
!!! UNDERVOLTAGE REPORTED !!! Make sure that the power supply and power cable are capable of supplying enough voltage and current to your Pi.
2021-03-30 06:37:37,607 - octoprint.plugins.tracking - INFO - Sent tracking event system_unthrottled, payload: {'throttled_now': False, 'throttled_past': True, 'throttled_mask': 327680, 'throttled_voltage_now': False, 'throttled_voltage_past': True, 'throttled_overheat_now': False, 'throttled_overheat_past': False}
2021-03-30 06:40:18,584 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial connection"
2021-03-30 06:40:18,612 - octoprint.util.comm - INFO - Serial detection: Performing autodetection with 1 port/baudrate candidates: /dev/ttyUSB0@115200
2021-03-30 06:40:18,613 - octoprint.util.comm - INFO - Serial detection: Trying port /dev/ttyUSB0, baudrate 115200
2021-03-30 06:40:18,613 - octoprint.util.comm - INFO - Connecting to port /dev/ttyUSB0, baudrate 115200
2021-03-30 06:40:18,622 - octoprint.util.comm - INFO - Serial detection: Handshake attempt #1 with timeout 2.0s
2021-03-30 06:40:18,626 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 06:40:18,633 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial connection" to "Operational"
2021-03-30 06:40:18,638 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 06:40:18,691 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin Creality 3D"
2021-03-30 06:40:18,711 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2021-03-30 06:40:19,358 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {'firmware_name': 'Marlin Creality 3D', 'printer_port': 'AUTO', 'printer_baudrate': 115200}
2021-03-30 06:41:25,689 - octoprint.filemanager.analysis - INFO - Starting analysis of local:Bing_Gluing_Clamp_Long_Convx_Pair.gcode
2021-03-30 06:41:25,690 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python3 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/Bing_Gluing_Clamp_Long_Convx_Pair.gcode
2021-03-30 06:41:39,126 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Bing_Gluing_Clamp_Long_Convx_Pair.gcode, owner: ghb, user: ghb
2021-03-30 06:41:43,618 - octoprint.util.comm - INFO - Starting job on behalf of user ghb
2021-03-30 06:41:43,624 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2021-03-30 06:41:43,631 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: Bing_Gluing_Clamp_Long_Convx_Pair.gcode, owner: ghb, user: ghb
2021-03-30 06:41:43,696 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 06:41:43,707 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2021-03-30 06:41:44,698 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': 'local', 'file': '1a71ffb425297e03d330e24958d591715f547061', 'throttled_now': False, 'throttled_past': True, 'throttled_mask': 327680}
2021-03-30 06:41:44,999 - octoprint.plugins.SlicerEstimator - WARNING - Autoselection of slicer not successful!
2021-03-30 06:41:45,502 - octoprint.plugins.SlicerEstimator - WARNING - Slicer-Comment not found. Please check if you selected the correct slicer.
2021-03-30 06:41:45,720 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2021-03-30 06:42:31,059 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2021-03-30 06:42:41,388 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 2698}
2021-03-30 06:45:03,741 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead
2021-03-30 06:45:04,318 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 06:45:04,419 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 06:45:04,420 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 06:45:04,663 - octoprint.server.util.sockjs - INFO - User ghb logged in on the socket from client fe80::7405:bab4:3240:fead
2021-03-30 06:45:06,304 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.227
2021-03-30 06:45:06,329 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.227
2021-03-30 06:45:30,112 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 2s
2021-03-30 06:48:23,030 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead
2021-03-30 06:48:23,311 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.227
2021-03-30 06:48:23,383 - octoprint.server.util.flask - INFO - Passively logging in user ghb from ::ffff:192.168.1.227
2021-03-30 06:48:23,383 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 06:48:23,435 - octoprint.server.util.sockjs - INFO - User ghb logged in on the socket from client ::ffff:192.168.1.227
2021-03-30 06:50:12,298 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.227

Note: I saw the low voltage warning, this is with a new and approved power supply. Cable? Who knows. But that is a different issue.

Additional information about your setup

OctoPrint version, OctoPi version, printer, firmware, browser, operating system, ... as much data as possible

pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.18.0
| throttle_state: '0x0'
| python:
| pip: 20.3.3
| version: 3.7.3
| virtualenv: /home/pi/oprint

Windows 10, 32 bit.
Firefox Current Browser.
Creality Slicer 1.2.3
Marlin Version 0.0.6 May 30, 2020

Thanks.

2

You've got a SerialException error.

(BTW: Please format the log with </> in the forums editor toolbar: grafik )

3

There's a serial error, but earlier the relevant ones from the files API. Could you upload the gcode file? There was a bug patched since 1.5.3 was out for invalid analysis causing issues, you may be running into the same thing.

4

First, thanks for responding.

There are more than one issue here:

  1. G-code file errors related to a certain file.
  2. G-code file coruption as files that once ran are now marked as unuseable. Newly reloaded files that once ran will not run now.
  3. Communications issues. - I think I may have caused these by connecting to the printer while running a file upload.
  4. Low voltage. I upgraded Pi and bought the approved power supply as was recommended. Chord are short, I dont know about quality. I think in all the logging since there were no low voltage errors.
  5. I am learning as we go.

I am bringing the conclusion of my trouble shooting here, to the top, I will leave the investigation sequence and log files below to support this conclusion and action:

I deleted the SlicerEstimator Plug-in because it was reported in the log as not found and I requested data cleanup. Restarted OctoPrint.
The past run files are still grayed out, marked as trash.
I deleted and re-loaded a grayed out file that I ran last night but would not run today. It loaded, it is not grayed, and when selected to print it was accepted. So the SlicerEstimator must have been at least part the problem.


This is the warning sequence log for SlicerEstimator:

TypeError: argument of type 'NoneType' is not iterable
2021-03-30 17:38:23,624 - tornado.access - ERROR - 500 POST /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode (fe80::7405:bab4:3240:fead) 266.29ms
2021-03-30 17:38:24,100 - octoprint.plugins.SlicerEstimator - WARNING - Autoselection of slicer not successful!
2021-03-30 17:38:24,594 - octoprint.plugins.SlicerEstimator - WARNING - Slicer-Estimation not found. Please check if you selected the correct slicer.
2021-03-30 17:38:24,761 - octoprint.filemanager.analysis - INFO - Analysis of entry local:Bing_Gluing_Clamp_Long_Convx_Pair.gcode finished, needed 34.08s
2021-03-30 17:38:24,787 - octoprint.events - ERROR - Got an exception while sending event MetadataAnalysisFinished (Payload: {'name': 'Bing_Gluing_Clamp_Long_Convx_Pair.gcode', 'path': 'Bing_Gluing_Clamp_Long_Convx_Pair.gcode', 'origin': 'local', 'result': None}) to <bound method Printer._on_event_MetadataAnalysisFinished of <octoprint.printer.standard.Printer object at 0x72846850>>
Traceback (most recent call last):
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/events.py", line 202, in _work
listener(event, payload)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 296, in _on_event_MetadataAnalysisFinished
self._selectedFile["user"],
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1282, in _setJobData
and "estimatedPrintTime" in fileData["analysis"]
TypeError: argument of type 'NoneType' is not iterable


I still don't know what this error means:
```TypeError: argument of type 'NoneType' is not iterable


This is the sequence I went through to back up my assumptions:

To get a clean view of the file error I will Reboot, load the errant file that I have tried to run several times unsuccessfully , and attempt to run it and then get the log.

NOW! upon Reboot I find 27 of 30 uploaded g-code files I HAVE RUN BEFORE are now grayed out and will not run again.  They were not that way yesterday.

So I tried Safe Mode.  Got the normal FIRMWARE BROKEN warning sign. G-code wont run.

So I restarted OctoPrint and tried to run a grayed out job.  Here is the tale of the tail:

2021-03-30 17:18:03,574 - octoprint.server - INFO - Goodbye!
2021-03-30 17:18:05,756 - octoprint.startup - INFO - ******************************************************************************
2021-03-30 17:18:05,758 - octoprint.startup - INFO - Starting OctoPrint 1.5.3
2021-03-30 17:18:05,758 - octoprint.startup - INFO - ******************************************************************************
2021-03-30 17:18:06,509 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity changed from offline to online
2021-03-30 17:18:06,878 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity state is currently: online
2021-03-30 17:18:06,879 - octoprint.util.connectivity.connectivity_checker - INFO - Connecting to 8.8.8.8:53 is working
2021-03-30 17:18:06,879 - octoprint.util.connectivity.connectivity_checker - INFO - Resolving octoprint.org is working
2021-03-30 17:18:06,914 - 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-03-30 17:18:06,982 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2021-03-30 17:18:08,079 - octoprint.plugin.core - INFO - Plugin TpLinkHandler (1.0.4) is disabled.
2021-03-30 17:18:08,134 - octoprint.plugin.core - INFO - Plugin Tasmota (1.0.1) is disabled.
2021-03-30 17:18:08,302 - octoprint.startup - INFO - Added settings overlay from plugin firmware_check
2021-03-30 17:18:08,304 - octoprint.plugin.core - INFO - Found 21 plugin(s) providing 19 mixin implementations, 40 hook handlers
2021-03-30 17:18:08,465 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2021-03-30 17:18:08,473 - octoprint.server - INFO - Intermediary server started
2021-03-30 17:18:08,501 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2021-03-30 17:18:08,941 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2021-03-30 17:18:08,944 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_SHOW (needs: "Need(method='role', value='plugin_action_command_notification_show')")
2021-03-30 17:18:08,945 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_CLEAR (needs: "Need(method='role', value='plugin_action_command_notification_clear')")
2021-03-30 17:18:08,945 - octoprint.server - INFO - Added new permission from plugin action_command_prompt: PLUGIN_ACTION_COMMAND_PROMPT_INTERACT (needs: "Need(method='role', value='plugin_action_command_prompt_interact')")
2021-03-30 17:18:08,946 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: "Need(method='role', value='plugin_announcements_read')")
2021-03-30 17:18:08,946 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_MANAGE (needs: "Need(method='role', value='plugin_announcements_read'), Need(method='role', value='plugin_announcements_manage')")
2021-03-30 17:18:08,947 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: "Need(method='role', value='plugin_appkeys_admin')")
2021-03-30 17:18:08,947 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: "Need(method='role', value='plugin_backup_access')")
2021-03-30 17:18:08,948 - octoprint.server - INFO - Added new permission from plugin firmware_check: PLUGIN_FIRMWARE_CHECK_DISPLAY (needs: "Need(method='role', value='plugin_firmware_check_display')")
2021-03-30 17:18:08,948 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: "Need(method='role', value='plugin_logging_manage')")
2021-03-30 17:18:08,949 - octoprint.server - INFO - Added new permission from plugin pi_support: PLUGIN_PI_SUPPORT_STATUS (needs: "Need(method='role', value='plugin_pi_support_check')")
2021-03-30 17:18:08,949 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: "Need(method='role', value='plugin_pluginmanager_manage')")
2021-03-30 17:18:08,949 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_INSTALL (needs: "Need(method='role', value='plugin_pluginmanager_install'), Need(method='role', value='plugin_pluginmanager_manage')")
2021-03-30 17:18:08,951 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: "Need(method='role', value='plugin_softwareupdate_check')")
2021-03-30 17:18:08,952 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: "Need(method='role', value='plugin_softwareupdate_update')")
2021-03-30 17:18:08,953 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CONFIGURE (needs: "Need(method='role', value='plugin_softwareupdate_configure')")
2021-03-30 17:18:08,972 - octoprint.printer.standard - INFO - Using print time estimator provided by SlicerEstimator
2021-03-30 17:18:09,943 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python3 -m pip" as command to invoke pip
2021-03-30 17:18:10,830 - octoprint.util.pip - INFO - Version of pip is 20.3.3
2021-03-30 17:18:10,831 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-03-30 17:18:10,832 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-03-30 17:18:10,951 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2021-03-30 17:18:10,987 - octoprint.plugin.core - INFO - Initialized 19 plugin implementation(s)
2021-03-30 17:18:11,003 - octoprint.plugin.core - INFO - 21 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
| Core Wizard (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/corewizard
| Creality-2x-temperature-reporting-fix (0.0.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_ender3v2tempfix
| 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
| File Check (2021.2.23) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_file_check
| Firmware Check (2021.2.4) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_firmware_check
| 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
| Pi Support Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pi_support
| Plugin Manager (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pluginmanager
| Slicer Print Time Estimator (1.0.2) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_SlicerEstimator
| Software Update (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/softwareupdate
| !Tasmota (1.0.1) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_tasmota
| !TpLinkHandler (1.0.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_TpLinkAutoShutdown
| Virtual Printer (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2021-03-30 17:18:11,033 - octoprint.environment - INFO - Detected environment is Python 3.7.3 under Linux (linux). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 915718144
| os:
| bits: 32
| id: linux
| platform: linux
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.18.0
| throttle_state: '0x0'
| python:
| pip: 20.3.3
| version: 3.7.3
| virtualenv: /home/pi/oprint
2021-03-30 17:18:11,047 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2021-03-30 17:18:11,068 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2021-03-30 17:18:11,808 - octoprint.server - INFO - Shutting down intermediary server...
2021-03-30 17:18:11,809 - octoprint.server - INFO - Intermediary server shut down
2021-03-30 17:18:11,811 - octoprint.events - INFO - Processing startup event, this is our first event
2021-03-30 17:18:11,812 - octoprint.events - INFO - Adding 2 events to queue that were held back before startup event
2021-03-30 17:18:11,819 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2021-03-30 17:18:11,821 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2021-03-30 17:18:11,832 - octoprint.plugins.softwareupdate - INFO - Fetching check overlays from https://plugins.octoprint.org/update_check_overlay.json
2021-03-30 17:18:11,848 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2021-03-30 17:18:11,987 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-03-30 17:18:12,697 - octoprint.server - INFO - Trying to connect to configured serial port /dev/ttyUSB0
2021-03-30 17:18:12,884 - octoprint.plugins.tracking - INFO - Sent tracking event system_unthrottled, payload: {'throttled_now': False, 'throttled_past': False, 'throttled_mask': 0, 'throttled_voltage_now': False, 'throttled_voltage_past': False, 'throttled_overheat_now': False, 'throttled_overheat_past': False}
2021-03-30 17:18:12,895 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial connection"
2021-03-30 17:18:12,897 - octoprint.util.comm - INFO - Connecting to port /dev/ttyUSB0, baudrate 115200
2021-03-30 17:18:12,986 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2021-03-30 17:18:13,013 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2021-03-30 17:18:13,063 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial connection" to "Connecting"
2021-03-30 17:18:13,068 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 17:18:13,125 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 17:18:13,162 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2021-03-30 17:18:13,234 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 17:18:13,322 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin Creality 3D"
2021-03-30 17:18:13,356 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2021-03-30 17:18:13,918 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {'firmware_name': 'Marlin Creality 3D', 'printer_port': '/dev/ttyUSB0', 'printer_baudrate': 115200}
2021-03-30 17:18:13,934 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-03-30 17:18:13,935 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi._http._tcp.local.' for _http._tcp.local.
2021-03-30 17:18:14,749 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi._octoprint._tcp.local.' for _octoprint._tcp.local.
2021-03-30 17:18:14,762 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2021-03-30 17:18:14,777 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2021-03-30 17:18:14,869 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-03-30 17:18:15,647 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.227
2021-03-30 17:18:15,854 - octoprint.server.util.flask - INFO - Passively logging in user ghb from ::ffff:192.168.1.227
2021-03-30 17:18:15,855 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:18:15,983 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python3 -m pip" as command to invoke pip
2021-03-30 17:18:15,994 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-03-30 17:18:15,994 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-03-30 17:18:16,085 - octoprint.plugins.softwareupdate - INFO - Minimum free storage across all update relevant locations is 52.7GB. That is considered sufficient for updating.
2021-03-30 17:18:16,481 - octoprint.plugins.SlicerEstimator - INFO - Started up SlicerEstimator
2021-03-30 17:18:17,026 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 8}
2021-03-30 17:18:17,123 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-03-30 17:18:17,246 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'base_url': 'http://octopi.local/', 'path': '/', 'query_string': 'l10n=en'}
2021-03-30 17:18:17,578 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'version': '1.5.3', 'os': 'linux', 'bits': 32, 'python': '3.7.3', 'pip': '20.3.3', 'cores': 4, 'freq': 1400.0, 'ram': 915718144, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'octopi_version': '0.18.0', 'plugins': 'slicerestimator:1.0.2,ender3v2tempfix:0.0.4'}
2021-03-30 17:18:18,115 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'version': '1.5.3', 'os': 'linux', 'bits': 32, 'python': '3.7.3', 'pip': '20.3.3', 'cores': 4, 'freq': 1400.0, 'ram': 915718144, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'octopi_version': '0.18.0'}
2021-03-30 17:18:19,788 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 17:18:19,789 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:18:35,284 - octoprint.server.util.sockjs - INFO - User ghb logged in on the socket from client ::ffff:192.168.1.227
2021-03-30 17:18:35,309 - octoprint.server.preemptive_cache - INFO - ... done in 18.06s
2021-03-30 17:18:35,314 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.227
2021-03-30 17:18:36,255 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 17:18:36,256 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:18:37,064 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 17:18:37,199 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 17:18:37,200 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:18:39,602 - octoprint.server.util.sockjs - INFO - User ghb logged in on the socket from client fe80::7405:bab4:3240:fead
2021-03-30 17:20:14,305 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Low_Rider_Body_Rounded_B_Jaw.gcode, owner: ghb, user: ghb
2021-03-30 17:20:14,314 - octoprint - ERROR - Exception on /api/files/local/Low_Rider_Body_Rounded_B_Jaw.gcode [POST]
Traceback (most recent call last):
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 2447, in wsgi_app
response = self.full_dispatch_request()
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1952, in full_dispatch_request
rv = self.handle_user_exception(e)
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1821, in handle_user_exception
reraise(exc_type, exc_value, tb)
File "/home/pi/oprint/lib/python3.7/site-packages/flask/_compat.py", line 39, in reraise
raise value
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1950, in full_dispatch_request
rv = self.dispatch_request()
File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1936, in dispatch_request
return self.view_functionsrule.endpoint
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/util/flask.py", line 1539, in decorated_view
return func(*args, **kwargs)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/api/files.py", line 802, in gcodeFileCommand
printer.select_file(filenameToSelect, sd, printAfterLoading, user)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 680, in select_file
tags=kwargs.get("tags", set()) | {"trigger:printer.select_file"},
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/util/comm.py", line 1599, in selectFile
filename, self._currentFile.getFilesize(), False, user=user
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1545, in on_comm_file_selected
self._setJobData(full_path, size, sd, user=user)
File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1282, in _setJobData
and "estimatedPrintTime" in fileData["analysis"]
TypeError: argument of type 'NoneType' is not iterable
2021-03-30 17:20:14,328 - tornado.access - ERROR - 500 POST /api/files/local/Low_Rider_Body_Rounded_B_Jaw.gcode (fe80::7405:bab4:3240:fead) 46.69ms
2021-03-30 17:20:35,071 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead



Well . . . I exceed a charector limit.  I will break it into two parts.
5

So back to to get a clean view of the file error I will Reboot, load the errant file that I have tried to run several times unsuccessfully , and attempt to run it and then tail the log.

Continued next post - exceed charector limit.

2021-03-30 17:31:48,237 - octoprint.server - INFO - Goodbye!
2021-03-30 17:31:55,941 - octoprint.startup - INFO - ******************************************************************************
2021-03-30 17:31:55,944 - octoprint.startup - INFO - Starting OctoPrint 1.5.3
2021-03-30 17:31:55,946 - octoprint.startup - INFO - ******************************************************************************
2021-03-30 17:32:02,539 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity state is currently: offline
2021-03-30 17:32:02,539 - octoprint.util.connectivity.connectivity_checker - INFO - Connecting to 8.8.8.8:53 is not working
2021-03-30 17:32:02,539 - octoprint.util.connectivity.connectivity_checker - INFO - Resolving octoprint.org is not working
2021-03-30 17:32:02,551 - octoprint.startup - INFO - We don't appear to be online, not fetching plugin blacklist
2021-03-30 17:32:02,664 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2021-03-30 17:32:04,356 - octoprint.plugin.core - INFO - Plugin TpLinkHandler (1.0.4) is disabled.
2021-03-30 17:32:04,419 - octoprint.plugin.core - INFO - Plugin Tasmota (1.0.1) is disabled.
2021-03-30 17:32:04,601 - octoprint.startup - INFO - Added settings overlay from plugin firmware_check
2021-03-30 17:32:04,604 - octoprint.plugin.core - INFO - Found 21 plugin(s) providing 19 mixin implementations, 40 hook handlers
2021-03-30 17:32:04,790 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2021-03-30 17:32:04,816 - octoprint.server - INFO - Intermediary server started
2021-03-30 17:32:04,846 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2021-03-30 17:32:05,319 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2021-03-30 17:32:05,324 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_SHOW (needs: "Need(method='role', value='plugin_action_command_notification_show')")
2021-03-30 17:32:05,324 - octoprint.server - INFO - Added new permission from plugin action_command_notification: PLUGIN_ACTION_COMMAND_NOTIFICATION_CLEAR (needs: "Need(method='role', value='plugin_action_command_notification_clear')")
2021-03-30 17:32:05,325 - octoprint.server - INFO - Added new permission from plugin action_command_prompt: PLUGIN_ACTION_COMMAND_PROMPT_INTERACT (needs: "Need(method='role', value='plugin_action_command_prompt_interact')")
2021-03-30 17:32:05,325 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: "Need(method='role', value='plugin_announcements_read')")
2021-03-30 17:32:05,326 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_MANAGE (needs: "Need(method='role', value='plugin_announcements_read'), Need(method='role', value='plugin_announcements_manage')")
2021-03-30 17:32:05,326 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: "Need(method='role', value='plugin_appkeys_admin')")
2021-03-30 17:32:05,327 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: "Need(method='role', value='plugin_backup_access')")
2021-03-30 17:32:05,327 - octoprint.server - INFO - Added new permission from plugin firmware_check: PLUGIN_FIRMWARE_CHECK_DISPLAY (needs: "Need(method='role', value='plugin_firmware_check_display')")
2021-03-30 17:32:05,328 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: "Need(method='role', value='plugin_logging_manage')")
2021-03-30 17:32:05,328 - octoprint.server - INFO - Added new permission from plugin pi_support: PLUGIN_PI_SUPPORT_STATUS (needs: "Need(method='role', value='plugin_pi_support_check')")
2021-03-30 17:32:05,330 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: "Need(method='role', value='plugin_pluginmanager_manage')")
2021-03-30 17:32:05,333 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_INSTALL (needs: "Need(method='role', value='plugin_pluginmanager_install'), Need(method='role', value='plugin_pluginmanager_manage')")
2021-03-30 17:32:05,335 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: "Need(method='role', value='plugin_softwareupdate_check')")
2021-03-30 17:32:05,337 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: "Need(method='role', value='plugin_softwareupdate_update')")
2021-03-30 17:32:05,339 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CONFIGURE (needs: "Need(method='role', value='plugin_softwareupdate_configure')")
2021-03-30 17:32:05,362 - octoprint.printer.standard - INFO - Using print time estimator provided by SlicerEstimator
2021-03-30 17:32:06,635 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python3 -m pip" as command to invoke pip
2021-03-30 17:32:07,551 - octoprint.util.pip - INFO - Version of pip is 20.3.3
2021-03-30 17:32:07,553 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-03-30 17:32:07,553 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-03-30 17:32:07,674 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2021-03-30 17:32:07,711 - octoprint.plugin.core - INFO - Initialized 19 plugin implementation(s)
2021-03-30 17:32:07,727 - octoprint.plugin.core - INFO - 21 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
|  Core Wizard (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/corewizard
|  Creality-2x-temperature-reporting-fix (0.0.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_ender3v2tempfix
|  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
|  File Check (2021.2.23) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_file_check
|  Firmware Check (2021.2.4) (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_firmware_check
|  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
|  Pi Support Plugin (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pi_support
|  Plugin Manager (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/pluginmanager
|  Slicer Print Time Estimator (1.0.2) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_SlicerEstimator
|  Software Update (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/softwareupdate
| !Tasmota (1.0.1) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_tasmota
| !TpLinkHandler (1.0.4) = /home/pi/oprint/lib/python3.7/site-packages/octoprint_TpLinkAutoShutdown
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python3.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2021-03-30 17:32:07,759 - octoprint.environment - INFO - Detected environment is Python 3.7.3 under Linux (linux). Details:
|  hardware:
|      cores: 4
|      freq: 1400.0
|      ram: 915718144
|  os:
|      bits: 32
|      id: linux
|      platform: linux
|  plugins:
|      pi_support:
|          model: Raspberry Pi 3 Model B Plus Rev 1.3
|          octopi_version: 0.18.0
|          throttle_state: '0x0'
|  python:
|      pip: 20.3.3
|      version: 3.7.3
|      virtualenv: /home/pi/oprint
2021-03-30 17:32:07,772 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2021-03-30 17:32:07,792 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2021-03-30 17:32:08,611 - octoprint.server - INFO - Shutting down intermediary server...
2021-03-30 17:32:08,807 - octoprint.server - INFO - Intermediary server shut down
2021-03-30 17:32:08,810 - octoprint.events - INFO - Processing startup event, this is our first event
2021-03-30 17:32:08,810 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2021-03-30 17:32:08,811 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2021-03-30 17:32:08,816 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2021-03-30 17:32:08,827 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2021-03-30 17:32:08,874 - octoprint.server - INFO - Trying to connect to configured serial port /dev/ttyUSB0
2021-03-30 17:32:08,919 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial connection"
2021-03-30 17:32:08,920 - octoprint.util.comm - INFO - Connecting to port /dev/ttyUSB0, baudrate 115200
2021-03-30 17:32:08,927 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2021-03-30 17:32:08,930 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2021-03-30 17:32:08,941 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial connection" to "Connecting"
2021-03-30 17:32:09,041 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 17:32:09,052 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 17:32:09,054 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2021-03-30 17:32:09,081 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2021-03-30 17:32:09,110 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin Creality 3D"
2021-03-30 17:32:09,120 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2021-03-30 17:32:09,744 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi._http._tcp.local.' for _http._tcp.local.
2021-03-30 17:32:10,551 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi._octoprint._tcp.local.' for _octoprint._tcp.local.
2021-03-30 17:32:10,557 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2021-03-30 17:32:10,604 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2021-03-30 17:32:10,669 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-03-30 17:32:11,641 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python3 -m pip" as command to invoke pip
2021-03-30 17:32:11,647 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2021-03-30 17:32:11,648 - octoprint.util.pip - INFO - ==> pip ok -> yes
2021-03-30 17:32:11,665 - octoprint.plugins.softwareupdate - INFO - Minimum free storage across all update relevant locations is 52.7GB. That is considered sufficient for updating.
2021-03-30 17:32:11,666 - octoprint.plugins.softwareupdate - INFO - Not fetching check overlays, we are offline
2021-03-30 17:32:11,926 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 17:32:12,208 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 17:32:12,209 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:32:12,288 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity changed from offline to online
2021-03-30 17:32:12,402 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2021-03-30 17:32:12,566 - octoprint.plugins.SlicerEstimator - INFO - Started up SlicerEstimator
2021-03-30 17:32:13,325 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-03-30 17:32:13,607 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 8}
2021-03-30 17:32:13,776 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'base_url': 'http://octopi.local/', 'path': '/', 'query_string': 'l10n=en'}
2021-03-30 17:32:14,219 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'version': '1.5.3', 'os': 'linux', 'bits': 32, 'python': '3.7.3', 'pip': '20.3.3', 'cores': 4, 'freq': 1400.0, 'ram': 915718144, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'octopi_version': '0.18.0', 'plugins': 'slicerestimator:1.0.2,ender3v2tempfix:0.0.4'}
2021-03-30 17:32:14,840 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'version': '1.5.3', 'os': 'linux', 'bits': 32, 'python': '3.7.3', 'pip': '20.3.3', 'cores': 4, 'freq': 1400.0, 'ram': 915718144, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'octopi_version': '0.18.0'}
2021-03-30 17:32:14,993 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2021-03-30 17:32:15,630 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 17:32:16,722 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 17:32:16,723 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:32:44,942 - octoprint.server.preemptive_cache - INFO - ... done in 19.57s
2021-03-30 17:32:45,012 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead
2021-03-30 17:32:45,761 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 17:32:45,762 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:32:46,723 - octoprint.server.util.sockjs - INFO - New connection from client: fe80::7405:bab4:3240:fead
2021-03-30 17:32:46,793 - octoprint.server.util.flask - INFO - Passively logging in user ghb from fe80::7405:bab4:3240:fead
2021-03-30 17:32:46,794 - octoprint.access.users - INFO - Logged in user: ghb
2021-03-30 17:32:48,860 - octoprint.server.util.sockjs - INFO - User ghb logged in on the socket from client fe80::7405:bab4:3240:fead
2021-03-30 17:37:50,677 - octoprint.filemanager.analysis - INFO - Starting analysis of local:Bing_Gluing_Clamp_Long_Convx_Pair.gcode
2021-03-30 17:37:50,678 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python3 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/Bing_Gluing_Clamp_Long_Convx_Pair.gcode
2021-03-30 17:38:23,573 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Bing_Gluing_Clamp_Long_Convx_Pair.gcode, owner: ghb, user: ghb
2021-03-30 17:38:23,603 - octoprint - ERROR - Exception on /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode [POST]
Traceback (most recent call last):
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 2447, in wsgi_app
    response = self.full_dispatch_request()
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1952, in full_dispatch_request
    rv = self.handle_user_exception(e)
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1821, in handle_user_exception
    reraise(exc_type, exc_value, tb)
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/_compat.py", line 39, in reraise
    raise value
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1950, in full_dispatch_request
    rv = self.dispatch_request()
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1936, in dispatch_request
    return self.view_functions[rule.endpoint](**req.view_args)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/util/flask.py", line 1539, in decorated_view
    return func(*args, **kwargs)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/api/files.py", line 802, in gcodeFileCommand
    printer.select_file(filenameToSelect, sd, printAfterLoading, user)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 680, in select_file
    tags=kwargs.get("tags", set()) | {"trigger:printer.select_file"},
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/util/comm.py", line 1599, in selectFile
    filename, self._currentFile.getFilesize(), False, user=user
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1545, in on_comm_file_selected
    self._setJobData(full_path, size, sd, user=user)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1282, in _setJobData
    and "estimatedPrintTime" in fileData["analysis"]
TypeError: argument of type 'NoneType' is not iterable
2021-03-30 17:38:23,624 - tornado.access - ERROR - 500 POST /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode (fe80::7405:bab4:3240:fead) 266.29ms
2021-03-30 17:38:24,100 - octoprint.plugins.SlicerEstimator - WARNING - Autoselection of slicer not successful!
2021-03-30 17:38:24,594 - octoprint.plugins.SlicerEstimator - WARNING - Slicer-Estimation not found. Please check if you selected the correct slicer.
2021-03-30 17:38:24,761 - octoprint.filemanager.analysis - INFO - Analysis of entry local:Bing_Gluing_Clamp_Long_Convx_Pair.gcode finished, needed 34.08s
2021-03-30 17:38:24,787 - octoprint.events - ERROR - Got an exception while sending event MetadataAnalysisFinished (Payload: {'name': 'Bing_Gluing_Clamp_Long_Convx_Pair.gcode', 'path': 'Bing_Gluing_Clamp_Long_Convx_Pair.gcode', 'origin': 'local', 'result': None}) to <bound method Printer._on_event_MetadataAnalysisFinished of <octoprint.printer.standard.Printer object at 0x72846850>>
Traceback (most recent call last):
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/events.py", line 202, in _work
    listener(event, payload)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 296, in _on_event_MetadataAnalysisFinished
    self._selectedFile["user"],
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1282, in _setJobData
    and "estimatedPrintTime" in fileData["analysis"]
TypeError: argument of type 'NoneType' is not iterable
2021-03-30 17:38:28,640 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Bing_Gluing_Clamp_Long_Convx_Pair.gcode, owner: ghb, user: ghb
2021-03-30 17:38:28,655 - octoprint - ERROR - Exception on /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode [POST]
Traceback (most recent call last):
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 2447, in wsgi_app
    response = self.full_dispatch_request()
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1952, in full_dispatch_request
    rv = self.handle_user_exception(e)
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1821, in handle_user_exception
    reraise(exc_type, exc_value, tb)
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/_compat.py", line 39, in reraise
    raise value
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1950, in full_dispatch_request
    rv = self.dispatch_request()
  File "/home/pi/oprint/lib/python3.7/site-packages/flask/app.py", line 1936, in dispatch_request
    return self.view_functions[rule.endpoint](**req.view_args)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/util/flask.py", line 1539, in decorated_view
    return func(*args, **kwargs)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/server/api/files.py", line 802, in gcodeFileCommand
    printer.select_file(filenameToSelect, sd, printAfterLoading, user)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 680, in select_file
    tags=kwargs.get("tags", set()) | {"trigger:printer.select_file"},
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/util/comm.py", line 1599, in selectFile
    filename, self._currentFile.getFilesize(), False, user=user
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1545, in on_comm_file_selected
    self._setJobData(full_path, size, sd, user=user)
  File "/home/pi/oprint/lib/python3.7/site-packages/octoprint/printer/standard.py", line 1282, in _setJobData
    and "estimatedPrintTime" in fileData["analysis"]
TypeError: argument of type 'NoneType' is not iterable
2021-03-30 17:38:28,673 - tornado.access - ERROR - 500 POST /api/files/local/Bing_Gluing_Clamp_Long_Convx_Pair.gcode (fe80::7405:bab4:3240:fead) 76.56ms
2021-03-30 17:39:02,670 - octoprint.server.util.sockjs - INFO - Client connection closed: fe80::7405:bab4:3240:fead

I deleted the Slicer Time Estimator because it was reported in the log as not found and requested data cleanup. Restarted OctoPrint.
Past run files are still grayed out.
I deleated and re-loaded a grayed out file that I ran last night. It loaded, it is not grayed, and when selected to print it was accepted.

6

Back to this: please upload the gcode still and perform all of your tests in safe mode.

Edit I read closer, looks like you did try safe mode already.

7

Yes sir, I did try save mode several times over the past few days. What is baffling is that it read through my 30+ old, previously used, uploaded g-code files and marked them for posterity as unusable. When they had already printed just fine.

8

You could also try the 1.6.0 release candidate, since there have been some fixes for invalid analysis.

The file at ~/.octoprint/uploads/metadata.json seems to be corrupted. You can also delete that and then restart the server.

9

Where is this file located and how do I get to it to delete it? Thanks.

10

That's the path it's located at, if you have SSH access just

rm ~/.octoprint/uploads/metadata.json
11

if you said previously you were getting low voltage errors, your file system on the pi's SD card could have corruption causing issues. I would replace the SD card with a fresh flash of Octopi. I wouldn't even do a backup, because any files already corrupted will be copied into the new instance and you will have the same issues.

1 Like
12

I don't do much of this stuff, Charlie. RE:

I presume I log-on to Pi as "pi" user. I tried this with Putty:

pi@octopi:~ $ rm ~/.octoprint/uploads/metadata.json
rm: cannot remove '/home/pi/.octoprint/uploads/metadata.json': No such file or directory

??

13

5ft24,
I may try the reload. Right about no back-up.
I am finding things are improving regarding test running my grayed-out g-code using a virtual printer. I am running a big real job now from the SD card so I can't try Octopi live. I haven't seen any low volt warnings for a couple days. I will evaluate when I can reconnect to the real printer. I will post results. BTW I think it was a plug-in, now deleted.
Thanks

14

Apologies, I missed out a dot on the front of the filename. You did the right thing with Putty though, good assumption :wink:

Try rm ~/.octoprint/uploads/.metadata.json, notice the dot before metadata. That should actually find the file now.

15

I ran it. No complaints from system so I guess it rm it.
head produced file not found.
Ha. I had to find my "Unix Hip Pocket Guide" from 20 years ago to remember how to display a file.
Thanks.

16

New issue today - won't connect to printer.
I restarted Octoprint fresh today. It connected to virtual printer. I tried to connect to my real printer. No go.I turned off virtual printer in maintenance and tried again.Nope. Rebooted. No go.

Background - when I was using Putty logged in as pi yesterday I was warned by sys that the pi PW need to be changed. I did so. Does Octoprint use the pi login? Do I need to change it somewhere in Octoprint?

Thanks.

17

Do you have the new octoprint.log?

Is the old issue fixed (with the metadata?)?

18

When a job I am running (from sneaker net SD) finishes I will restart Octoprint and get a fresh tail of the log.

I don't know what I have until I can connect to my printer. I have deleted all my uploaded g-code files. I reloaded one but can't try it.

Could the PW change for user pi affect Octoprint's ability to connect to the printer?

19

I know of a reason why it should, if the user itself changed I can see the permissions on the ports going wrong but not if the password is changed.

Run lsusb, see what is there (post it) and then unplug the printer and plug it back in and run dmesg | tail -n 20. There could be some clues in the dmesg as to why the printer is not connected.

20 
pi@octopi:~ $ dmesg | tail -n 20
[    7.569083] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
[    9.448431] uart-pl011 3f201000.serial: no DMA platform data
[    9.686309] 8021q: 802.1Q VLAN Support v1.8
[    9.797874] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
[   10.026112] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   10.284119] 8021q: adding VLAN 0 to HW filter on device eth0
[   15.981040] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   16.539158] Bluetooth: Core ver 2.22
[   16.539276] NET: Registered protocol family 31
[   16.539287] Bluetooth: HCI device and connection manager initialized
[   16.539319] Bluetooth: HCI socket layer initialized
[   16.539337] Bluetooth: L2CAP socket layer initialized
[   16.539368] Bluetooth: SCO socket layer initialized
[   16.560178] Bluetooth: HCI UART driver ver 2.3
[   16.560198] Bluetooth: HCI UART protocol H4 registered
[   16.560286] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   16.560563] Bluetooth: HCI UART protocol Broadcom registered
[   16.803277] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   16.803287] Bluetooth: BNEP filters: protocol multicast
[   16.803304] Bluetooth: BNEP socket layer initialized
pi@octopi:~ $


pi@octopi:~ $ lsusb
Bus 001 Device 004: ID 0424:7800 Standard Microsystems Corp.
Bus 001 Device 003: ID 0424:2514 Standard Microsystems Corp. USB 2.0 Hub
Bus 001 Device 002: ID 0424:2514 Standard Microsystems Corp. USB 2.0 Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub