Print jobs failures

What is the problem?

Prints fail randomly.

At first I thought it's the printer's firmware problem with power panic being too sensitive. Read a number of reports, especially from UPS users, that they're getting failed prints because of that.

Then I checked the logs for the TP Link plugin because I thought it may have been an actual power failure. It was clean.

And then I checked the octoprint log and found something about it shutting down, doing update (I hope I am reading it correctly) mid print???

What did you already try to solve it?

Since I thought it is caused by the MK3S power panic being too sensitive, I disabled power panic altogether but the problem persists.

Have you tried running in safe mode and if so did it solve the issue?

No.

Complete Logs

2020-10-25 07:26:39,030 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2020-10-25 07:26:39,041 - octoprint.plugins.DisplayLayerProgress - INFO - Printing started. Detailed progress started.{'origin': u'local', 'name': u'9f01dfab-39d4-48d9-a2ee-fe936d155b1f_0.2mm_PETG_MK3S_4h19m.gcode', u'user': 'davidrelich', u'owner': 'davidrelich', 'path': u'9f01dfab-39d4-48d9-a2ee-fe936d155b1f_0.2mm_PETG_MK3S_4h19m.gcode', 'size': 10095925L}
2020-10-25 07:26:39,089 - octoprint.plugins.tplinksmartplug - INFO - PrintStarted: {'origin': u'local', 'name': u'9f01dfab-39d4-48d9-a2ee-fe936d155b1f_0.2mm_PETG_MK3S_4h19m.gcode', u'user': 'davidrelich', u'owner': 'davidrelich', 'path': u'9f01dfab-39d4-48d9-a2ee-fe936d155b1f_0.2mm_PETG_MK3S_4h19m.gcode', 'size': 10095925L}
2020-10-25 07:30:32,833 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2020-10-25 07:30:45,091 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 2s
2020-10-25 07:32:14,024 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-10-25 07:47:14,026 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-10-25 07:35:39,736 - octoprint.startup - INFO - ******************************************************************************
2020-10-25 07:35:39,739 - octoprint.startup - INFO - Starting OctoPrint 1.4.2
2020-10-25 07:35:39,739 - octoprint.startup - INFO - ******************************************************************************
2020-10-25 07:35:39,894 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity state is currently: offline
2020-10-25 07:35:39,895 - octoprint.util.connectivity.connectivity_checker - INFO - Connecting to 8.8.8.8:53 is not working
2020-10-25 07:35:39,895 - octoprint.util.connectivity.connectivity_checker - INFO - Resolving octoprint.org is not working
2020-10-25 07:35:39,901 - octoprint.startup - INFO - We don't appear to be online, not fetching plugin blacklist
2020-10-25 07:35:39,963 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2020-10-25 07:35:43,139 - octoprint.startup - INFO - Added settings overlay from plugin dashboard
2020-10-25 07:35:43,141 - octoprint.plugin.core - INFO - Found 27 plugin(s) providing 27 mixin implementations, 56 hook handlers
2020-10-25 07:35:43,502 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2020-10-25 07:35:43,557 - octoprint.server - INFO - Intermediary server started
2020-10-25 07:35:43,558 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2020-10-25 07:35:44,312 - octoprint.plugin.core - INFO - Found 27 plugin(s) providing 27 mixin implementations, 56 hook handlers
2020-10-25 07:35:44,354 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2020-10-25 07:35:45,250 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2020-10-25 07:35:45,255 - 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')")
2020-10-25 07:35:45,256 - 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')")
2020-10-25 07:35:45,257 - 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')")
2020-10-25 07:35:45,259 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: u"Need(method='role', value=u'plugin_announcements_read')")
2020-10-25 07:35:45,260 - 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')")
2020-10-25 07:35:45,262 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: u"Need(method='role', value=u'plugin_appkeys_admin')")
2020-10-25 07:35:45,264 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: u"Need(method='role', value=u'plugin_backup_access')")
2020-10-25 07:35:45,265 - octoprint.server - INFO - Added new permission from plugin dashboard: PLUGIN_DASHBOARD_ADMIN (needs: u"Need(method='role', value=u'plugin_dashboard_admin')")
2020-10-25 07:35:45,266 - 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')")
2020-10-25 07:35:45,268 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: u"Need(method='role', value=u'plugin_logging_manage')")
2020-10-25 07:35:45,270 - octoprint.server - INFO - Added new permission from plugin pi_support: PLUGIN_PI_SUPPORT_STATUS (needs: u"Need(method='role', value=u'plugin_pi_support_check')")
2020-10-25 07:35:45,271 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: u"Need(method='role', value=u'plugin_pluginmanager_manage')")
2020-10-25 07:35:45,272 - 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')")
2020-10-25 07:35:45,274 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: u"Need(method='role', value=u'plugin_softwareupdate_check')")
2020-10-25 07:35:45,274 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: u"Need(method='role', value=u'plugin_softwareupdate_update')")
2020-10-25 07:35:45,303 - octoprint.printer.standard - INFO - Using print time estimator provided by PrintTimeGenius
2020-10-25 07:35:45,601 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2020-10-25 07:35:47,801 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-10-25 07:35:49,653 - octoprint.util.pip - INFO - Version of pip is 9.0.3
2020-10-25 07:35:49,654 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2020-10-25 07:35:49,655 - octoprint.util.pip - INFO - ==> pip ok -> yes
2020-10-25 07:35:49,660 - octoprint.plugin.core - INFO - Initialized 27 plugin implementation(s)
2020-10-25 07:35:49,688 - octoprint.plugin.core - INFO - 27 plugin(s) registered with the system:
| Action Command Notification Support (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/action_command_notification
| Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/action_command_prompt
| Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/announcements
| Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/tracking
| Application Keys Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/appkeys
| Backup & Restore (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/backup
| Bed Visualizer (0.1.15) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_bedlevelvisualizer
| BetterHeaterTimeout (1.2.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_BetterHeaterTimeout
| Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
| Dashboard (1.15.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_dashboard
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| DisplayLayerProgress Plugin (1.24.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_DisplayLayerProgress
| Error Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/errortracking
| File Check (2020.8.7) (bundled) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_file_check
| Firmware Check (2020.9.23) (bundled) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_firmware_check
| GCode Viewer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/gcodeviewer
| ipOnConnect (0.2.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_ipOnConnect
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| Login UI (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/loginui
| Pi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pi_support
| Plugin Manager (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/pluginmanager
| PrintTimeGenius Plugin (2.2.6) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_PrintTimeGenius
| Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
| Themeify (1.2.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_themeify
| TouchUI (0.3.16) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_touchui
| TP-Link Smartplug (0.9.24) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_tplinksmartplug
| Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2020-10-25 07:35:49,707 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918323200
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.15.0PE
| python:
| pip: 9.0.3
| version: 2.7.13
| virtualenv: /home/pi/oprint
2020-10-25 07:35:49,721 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2020-10-25 07:35:49,745 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2020-10-25 07:35:51,295 - octoprint.server - INFO - Shutting down intermediary server...
2020-10-25 07:35:51,297 - octoprint.server - INFO - Intermediary server shut down
2020-10-25 07:35:51,300 - octoprint.events - INFO - Processing startup event, this is our first event
2020-10-25 07:35:51,301 - octoprint.events - INFO - Adding 0 events to queue that were held back before startup event
2020-10-25 07:35:51,305 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2020-10-25 07:35:51,307 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2020-10-25 07:35:51,309 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2020-10-25 07:35:51,374 - octoprint.server - INFO - Trying to connect to configured serial port /dev/ttyAMA0
2020-10-25 07:35:51,438 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial connection"
2020-10-25 07:35:51,441 - octoprint.util.comm - INFO - Connecting to port /dev/ttyAMA0, baudrate 115200
2020-10-25 07:35:51,451 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2020-10-25 07:35:51,454 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial connection" to "Connecting"
2020-10-25 07:35:51,461 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2020-10-25 07:35:51,465 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-10-25 07:35:51,469 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-10-25 07:35:51,482 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2020-10-25 07:35:51,490 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance "Prusa MK3S"' for _http.tcp
2020-10-25 07:35:51,495 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-10-25 07:35:51,503 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance "Prusa MK3S"' for octoprint.tcp
2020-10-25 07:35:51,536 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance "Prusa MK3S" for SSDP
2020-10-25 07:35:51,540 - octoprint.plugins.ipOnConnect - INFO - ipOnConnectPlugin: M117 192.168.10.130
2020-10-25 07:35:51,547 - octoprint.util.comm - INFO - Printer reports firmware name "Prusa-Firmware 3.9.1 based on Marlin"
2020-10-25 07:35:51,637 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2020-10-25 07:35:51,764 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2020-10-25 07:35:52,321 - octoprint.server.util.flask - INFO - Passively logging in user davidrelich from fe80::8a4:371a:d440:5c7
2020-10-25 07:35:52,333 - octoprint.access.users - INFO - Logged in user: davidrelich
2020-10-25 07:35:52,427 - tornado.access - WARNING - 404 GET /cached.gif?
=1603609350746 (fe80::8a4:371a:d440:5c7) 149.60ms
2020-10-25 07:35:52,686 - tornado.access - WARNING - 404 GET /intermediary.gif?
=1603609351048 (fe80::8a4:371a:d440:5c7) 148.32ms
2020-10-25 07:35:53,510 - octoprint.server.util.flask - INFO - Passively logging in user davidrelich from fe80::8a4:371a:d440:5c7
2020-10-25 07:35:53,741 - octoprint.access.users - INFO - Logged in user: davidrelich
2020-10-25 07:35:53,826 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-10-25 07:35:53,865 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2020-10-25 07:35:53,901 - octoprint.util.pip - INFO - ==> pip ok -> yes
2020-10-25 07:35:54,116 - tornado.access - WARNING - 404 GET /cached.gif?
=1603609352174 (fe80::8a4:371a:d440:5c7) 642.39ms
2020-10-25 07:35:54,156 - octoprint.plugins.softwareupdate - INFO - Minimum free storage across all update relevant locations is 12.3GB. That is considered sufficient for updating.
2020-10-25 07:35:54,165 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2020-10-25 07:35:54,242 - octoprint.util.connectivity.connectivity_checker - INFO - Connectivity changed from offline to online
2020-10-25 07:35:54,354 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2020-10-25 07:35:54,358 - octoprint.plugins.ipOnConnect - INFO - ipOnConnectPlugin: M117 192.168.10.130
2020-10-25 07:35:55,101 - octoprint.plugins.bedlevelvisualizer - INFO - OctoPrint-BedLevelVisualizer loaded!
2020-10-25 07:35:55,102 - octoprint.plugins.dashboard - INFO - Dashboard started
2020-10-25 07:35:55,214 - octoprint.server.util.flask - INFO - Passively logging in user davidrelich from fe80::8a4:371a:d440:5c7
2020-10-25 07:35:55,236 - octoprint.access.users - INFO - Logged in user: davidrelich

WRITE HERE

Additional information about your setup

OctoPrint version 1.4.2, OctoPi version 0.15.0PE, running on Raspberry Pi 3 Model B Plus Rev 1.3, MK3S with FW v. 3.9.1, Safari, macOS, Plugins: Tp Link Smart Plug, Themify, Better Heater Timeout, Bed Visualiser, Dashboard, DisplayLayerProgress, PrintTimeGenious, TouchUI, ipOnConnect.

WRITE HERE

Guess there's no explanation for the above problem so I'll just close this. Cheers.