Octoprint log when error occurred

octoprint.log

2020-03-30 13:47:51,062 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 13:47:51,067 - octoprint.server - INFO - --- Log roll over detected ---------------------------------------------------
2020-03-30 13:47:51,067 - octoprint.server - INFO - OctoPrint 1.4.0
2020-03-30 13:47:51,072 - octoprint.plugin.core - INFO - 17 plugin(s) registered with the system:
|  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
|  Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
|  Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
|  Error Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/errortracking
|  Gcodebar Plugin (0.1.5) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_GCodeBar
|  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
|  OctoPod Plugin (0.2.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octopod
|  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
|  Printer Safety Check (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/printer_safety_check
|  Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2020-03-30 13:47:51,097 - octoprint.environment - INFO - Detected environment is Python 2.7.16 under Linux (linux2). Details:
|  hardware:
|      cores: 4
|      freq: 1200.0
|      ram: 917008384
|  os:
|      id: linux
|      platform: linux2
|  plugins:
|      pi_support:
|          model: Raspberry Pi 3 Model B Rev 1.2
|          octopi_version: 0.17.0
|  python:
|      pip: 19.3.1
|      version: 2.7.16
|      virtualenv: /home/pi/oprint
2020-03-30 13:47:51,097 - octoprint.server - INFO - ------------------------------------------------------------------------------
2020-03-30 13:48:00,576 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1727138}
2020-03-30 14:02:51,064 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 14:03:00,675 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1728038}
2020-03-30 14:17:51,066 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 14:18:00,616 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1728938}
2020-03-30 14:32:51,069 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 14:32:59,849 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 14:33:00,571 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1729838}
2020-03-30 14:33:11,046 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 14:40:41,267 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 14:40:41,269 - octoprint.access.users - INFO - Cleaning up user session 64DDF3F1AD1F4CB8A7A9B4D8D3F9853C for user _api
2020-03-30 14:40:41,270 - octoprint.access.users - INFO - Logged out user: _api
2020-03-30 14:40:41,274 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 14:40:42,733 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 14:40:42,875 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 14:40:42,877 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 14:40:46,213 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 14:47:51,072 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 14:48:00,614 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1730738}
2020-03-30 15:02:51,074 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 15:03:00,656 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1731638}
2020-03-30 15:17:51,075 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 15:18:00,659 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1732538}
2020-03-30 15:32:51,079 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 15:33:00,670 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1733438}
2020-03-30 15:47:51,081 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 15:48:00,706 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1734338}
2020-03-30 16:02:51,084 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 16:03:00,692 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1735238}
2020-03-30 16:08:20,879 - octoprint.server.util.sockjs - INFO - New connection from client: 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:08:21,673 - octoprint.server.util.flask - INFO - Passively logging in user pi from 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:08:21,678 - octoprint.access.users - INFO - Logged in user: pi
2020-03-30 16:08:23,694 - octoprint.server.util.sockjs - INFO - User pi logged in on the socket from client 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:17:51,087 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 16:18:00,683 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1736138}
2020-03-30 16:32:51,090 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 16:33:00,740 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1737038}
2020-03-30 16:43:06,780 - octoprint.server.api.system - INFO - Performing command for core:restart_safe: sudo service octoprint restart
2020-03-30 16:43:07,262 - octoprint.server - INFO - Shutting down...
2020-03-30 16:43:07,857 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2020-03-30 16:43:07,866 - octoprint.events - INFO - Event loop shut down
2020-03-30 16:43:07,890 - octoprint.server - INFO - Goodbye!
2020-03-30 16:43:11,126 - octoprint.startup - INFO - ******************************************************************************
2020-03-30 16:43:11,128 - octoprint.startup - INFO - Starting OctoPrint 1.4.0
2020-03-30 16:43:11,129 - octoprint.startup - INFO - Starting in SAFE MODE. Third party plugins will be disabled!
2020-03-30 16:43:11,130 - octoprint.startup - INFO - Reason for safe mode: setting in config.yaml
2020-03-30 16:43:11,131 - octoprint.startup - INFO - ******************************************************************************
2020-03-30 16:43:11,709 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2020-03-30 16:43:11,960 - octoprint.startup - INFO - Blacklist processing done, adding 1 blacklisted plugin versions: roomtemp (any)
2020-03-30 16:43:12,008 - 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-03-30 16:43:13,283 - octoprint.plugin.core - INFO - Found 17 plugin(s) providing 15 mixin implementations, 27 hook handlers
2020-03-30 16:43:13,549 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2020-03-30 16:43:13,574 - octoprint.server - INFO - Intermediary server started
2020-03-30 16:43:13,575 - 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-03-30 16:43:13,704 - octoprint.plugin.core - INFO - Found 17 plugin(s) providing 15 mixin implementations, 27 hook handlers
2020-03-30 16:43:13,741 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2020-03-30 16:43:14,344 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2020-03-30 16:43:14,350 - 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-03-30 16:43:14,351 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: u"Need(method='role', value=u'plugin_announcements_read')")
2020-03-30 16:43:14,353 - 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-03-30 16:43:14,354 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: u"Need(method='role', value=u'plugin_appkeys_admin')")
2020-03-30 16:43:14,356 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: u"Need(method='role', value=u'plugin_backup_access')")
2020-03-30 16:43:14,357 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: u"Need(method='role', value=u'plugin_logging_manage')")
2020-03-30 16:43:14,359 - 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-03-30 16:43:14,361 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: u"Need(method='role', value=u'plugin_pluginmanager_manage')")
2020-03-30 16:43:14,362 - 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-03-30 16:43:14,363 - octoprint.server - INFO - Added new permission from plugin printer_safety_check: PLUGIN_PRINTER_SAFETY_CHECK_DISPLAY (needs: u"Need(method='role', value=u'plugin_printer_safety_check_display')")
2020-03-30 16:43:14,365 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: u"Need(method='role', value=u'plugin_softwareupdate_check')")
2020-03-30 16:43:14,366 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: u"Need(method='role', value=u'plugin_softwareupdate_update')")
2020-03-30 16:43:14,508 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2020-03-30 16:43:16,618 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-03-30 16:43:18,246 - octoprint.util.pip - INFO - Version of pip is 19.3.1
2020-03-30 16:43:18,248 - 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-03-30 16:43:18,249 - octoprint.util.pip - INFO - ==> pip ok -> yes
2020-03-30 16:43:18,254 - octoprint.plugin.core - INFO - Initialized 15 plugin implementation(s)
2020-03-30 16:43:18,267 - octoprint.plugin.core - INFO - 17 plugin(s) registered with the system:
|  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
|  Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/corewizard
|  Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
|  Error Tracking (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/errortracking
| !Gcodebar Plugin (0.1.5) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_GCodeBar
|  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
| !OctoPod Plugin (0.2.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_octopod
|  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
|  Printer Safety Check (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/printer_safety_check
|  Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2020-03-30 16:43:18,284 - octoprint.environment - INFO - Detected environment is Python 2.7.16 under Linux (linux2). Details:
|  hardware:
|      cores: 4
|      freq: 1200.0
|      ram: 917008384
|  os:
|      id: linux
|      platform: linux2
|  plugins:
|      pi_support:
|          model: Raspberry Pi 3 Model B Rev 1.2
|          octopi_version: 0.17.0
|  python:
|      pip: 19.3.1
|      version: 2.7.16
|      virtualenv: /home/pi/oprint
2020-03-30 16:43:18,296 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2020-03-30 16:43:18,318 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2020-03-30 16:43:19,233 - octoprint.server - INFO - Shutting down intermediary server...
2020-03-30 16:43:19,305 - octoprint.server - INFO - Intermediary server shut down
2020-03-30 16:43:19,308 - octoprint.events - INFO - Processing startup event, this is our first event
2020-03-30 16:43:19,311 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2020-03-30 16:43:19,314 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2020-03-30 16:43:19,315 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2020-03-30 16:43:19,322 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2020-03-30 16:43:19,414 - octoprint.server - INFO - Trying to connect to configured serial port None
2020-03-30 16:43:19,507 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial port"
2020-03-30 16:43:19,531 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2020-03-30 16:43:19,538 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2020-03-30 16:43:19,587 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2020-03-30 16:43:19,612 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial port" to "Opening serial port"
2020-03-30 16:43:19,625 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2020-03-30 16:43:19,638 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:43:19,637 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2020-03-30 16:43:19,644 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:43:19,677 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2020-03-30 16:43:19,811 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.43s
2020-03-30 16:43:19,823 - 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.
2020-03-30 16:43:19,879 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2020-03-30 16:43:19,882 - octoprint.server - INFO - Server started successfully in safe mode as requested from config, removing flag
2020-03-30 16:43:19,962 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from https://plugins.octoprint.org/plugins.json
2020-03-30 16:43:20,889 - octoprint.plugins.tracking - INFO - Sent tracking event system_throttled, payload: {'throttled_mask': 327685, 'throttled_voltage_now': True, 'throttled_voltage_past': True, 'throttled_overheat_now': False, 'throttled_now': True, 'throttled_past': True, 'throttled_overheat_past': False}
2020-03-30 16:43:21,607 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:43:22,877 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2020-03-30 16:43:23,238 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 1.6s
2020-03-30 16:43:23,697 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-03-30 16:43:24,255 - octoprint.server.util.sockjs - INFO - New connection from client: 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:43:24,377 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.78s
2020-03-30 16:43:25,198 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-03-30 16:43:25,270 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2020-03-30 16:43:26,411 - octoprint.server.util.flask - INFO - Passively logging in user pi from 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:43:26,515 - octoprint.access.users - INFO - Logged in user: pi
2020-03-30 16:43:27,728 - octoprint.server.util.sockjs - INFO - User pi logged in on the socket from client 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:43:30,892 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 1.1s
2020-03-30 16:43:30,912 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 1.3s
2020-03-30 16:43:31,188 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2020-03-30 16:43:31,590 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.39s
2020-03-30 16:43:31,613 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.57s
2020-03-30 16:43:32,479 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.32s
2020-03-30 16:43:32,553 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.39s
2020-03-30 16:43:32,973 - 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-03-30 16:43:32,973 - octoprint.util.pip - INFO - ==> pip ok -> yes
2020-03-30 16:43:32,977 - 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-03-30 16:43:32,979 - octoprint.util.pip - INFO - ==> pip ok -> yes
2020-03-30 16:43:33,018 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2020-03-30 16:43:33,278 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 20}
2020-03-30 16:43:33,379 - octoprint.server.util.flask - INFO - Passively logging in user pi from 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:43:33,386 - octoprint.access.users - INFO - Logged in user: pi
2020-03-30 16:43:33,589 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'plugins': u''}
2020-03-30 16:43:33,923 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.16', 'ram': 917008384, u'octopi_version': u'0.17.0', 'version': u'1.4.0', 'pip': '19.3.1', 'cores': 4, 'freq': 1200.0, u'pi_model': u'Raspberry Pi 3 Model B Rev 1.2', 'os': 'linux'}
2020-03-30 16:43:36,818 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2020-03-30 16:43:36,824 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:43:36,929 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin 2.0.5 (GitHub)"
2020-03-30 16:43:36,968 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2020-03-30 16:43:37,294 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': None, 'firmware_name': u'Marlin 2.0.5 (GitHub)'}
2020-03-30 16:43:45,532 - octoprint.server.util.sockjs - INFO - Client connection closed: 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:43:50,166 - octoprint.plugins.tracking - INFO - Sent tracking event system_unthrottled, payload: {'throttled_mask': 327680, 'throttled_voltage_now': False, 'throttled_voltage_past': True, 'throttled_overheat_now': False, 'throttled_now': False, 'throttled_past': True, 'throttled_overheat_past': False}
2020-03-30 16:43:50,850 - octoprint.server.util.sockjs - INFO - New connection from client: 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:43:51,057 - octoprint.server.util.flask - INFO - Passively logging in user pi from 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:43:51,059 - octoprint.access.users - INFO - Logged in user: pi
2020-03-30 16:43:57,048 - octoprint.server.util.sockjs - INFO - User pi logged in on the socket from client 2a02:120b:2c3d:fa0:b50e:325e:f4bd:bac6
2020-03-30 16:45:40,972 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 16:45:43,130 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 16:45:43,133 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 16:45:43,181 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client ::ffff:192.168.1.125
2020-03-30 16:45:53,431 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 16:49:57,352 - octoprint.filemanager.analysis - INFO - Starting analysis of local:NewBambooConnector2-TPU.gcode
2020-03-30 16:49:57,355 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python2 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/NewBambooConnector2-TPU.gcode
2020-03-30 16:52:02,493 - octoprint.filemanager.analysis - INFO - Analysis of entry local:NewBambooConnector2-TPU.gcode finished, needed 125.14s
2020-03-30 16:53:07,621 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 16:53:08,338 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 16:53:08,342 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 16:53:08,379 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client ::ffff:192.168.1.125
2020-03-30 16:53:22,674 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Offline"
2020-03-30 16:53:28,292 - tornado.access - WARNING - 409 GET /api/printer (2a02:120b:2c3d:fa0:8e:de14:16e4:aa71) 16.80ms
2020-03-30 16:53:28,452 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 18.80ms
2020-03-30 16:53:31,402 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 16.44ms
2020-03-30 16:53:34,173 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 17.21ms
2020-03-30 16:53:37,167 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 19.37ms
2020-03-30 16:53:40,170 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 16.66ms
2020-03-30 16:53:43,173 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 17.51ms
2020-03-30 16:53:45,686 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 19.45ms
2020-03-30 16:53:46,149 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 9.35ms
2020-03-30 16:53:49,172 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 16.43ms
2020-03-30 16:53:52,178 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 19.10ms
2020-03-30 16:53:55,255 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 18.86ms
2020-03-30 16:54:00,382 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 16:54:11,249 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2020-03-30 16:54:11,255 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2020-03-30 16:54:11,264 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:54:13,066 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:54:22,398 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 18.06ms
2020-03-30 16:54:22,461 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 17.03ms
2020-03-30 16:54:23,060 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 8.91ms
2020-03-30 16:54:25,479 - tornado.access - WARNING - 409 GET /api/printer (::ffff:192.168.1.125) 17.45ms
2020-03-30 16:54:28,001 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:54:28,007 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2020-03-30 16:54:28,029 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:54:28,070 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin 2.0.5 (GitHub)"
2020-03-30 16:54:28,110 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2020-03-30 16:54:28,393 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': u'/dev/ttyUSB0', 'firmware_name': u'Marlin 2.0.5 (GitHub)'}
2020-03-30 16:54:54,036 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Load_Unload.gcode, owner: _api, user: _api
2020-03-30 16:54:54,064 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2020-03-30 16:54:54,076 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: Load_Unload.gcode, owner: _api, user: _api
2020-03-30 16:54:54,128 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 16:54:54,146 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2020-03-30 16:54:54,376 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': u'local', u'throttled_now': False, u'throttled_mask': 327680, 'file': '3080db804ba2e16a1f2a3cb5c0165537ea46cf61', u'throttled_past': True}
2020-03-30 16:54:56,258 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2020-03-30 16:56:32,179 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 2s
2020-03-30 16:58:13,552 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 16:58:33,238 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 920}
2020-03-30 16:58:55,469 - octoprint.util.comm - INFO - Finished in 241.406 s.
2020-03-30 16:58:55,471 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Finishing"
2020-03-30 16:58:55,487 - octoprint.printer.standard.job - INFO - Print job done - origin: local, path: Load_Unload.gcode, owner: _api
2020-03-30 16:58:55,581 - octoprint.util.comm - INFO - Changing monitoring state from "Finishing" to "Operational"
2020-03-30 16:58:55,822 - octoprint.plugins.tracking - INFO - Sent tracking event print_done, payload: {'origin': u'local', u'throttled_mask': 327680, u'elapsed': 241, 'file': '3080db804ba2e16a1f2a3cb5c0165537ea46cf61', u'throttled_now': False, u'throttled_past': True}
2020-03-30 17:01:05,868 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 17:01:06,003 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 17:01:06,005 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 17:01:06,025 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 17:01:08,505 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: NewBambooConnector2-TPU.gcode, owner: pi, user: pi
2020-03-30 17:01:08,529 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2020-03-30 17:01:08,542 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: NewBambooConnector2-TPU.gcode, owner: pi, user: pi
2020-03-30 17:01:08,576 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-03-30 17:01:08,615 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2020-03-30 17:01:08,916 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': u'local', u'throttled_now': False, u'throttled_mask': 327680, 'file': '296156258bba583016ed13b335adf8de5b49604d', u'throttled_past': True}
2020-03-30 17:12:51,881 - tornado.access - WARNING - 404 POST /cgi-bin/ViewLog.asp (::ffff:5.182.211.203) 23.95ms
2020-03-30 17:13:13,554 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 17:13:33,313 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 1820}
2020-03-30 17:25:10,468 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 17:25:10,626 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 17:25:10,628 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 17:25:10,647 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 17:28:13,557 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 17:28:33,325 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 2720}
2020-03-30 17:33:54,727 - tornado.access - WARNING - 404 POST /boaform/admin/formPing (::ffff:89.179.145.134) 19.04ms
2020-03-30 17:40:49,826 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 17:40:49,946 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 17:40:49,951 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 17:40:49,973 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 17:43:13,563 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 17:43:33,304 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 3620}
2020-03-30 17:50:05,343 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 17:50:05,493 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 17:50:05,496 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 17:50:32,989 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 17:58:13,564 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 17:58:33,334 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 4520}
2020-03-30 17:58:45,456 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 17:58:45,590 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 17:58:45,592 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 17:59:05,716 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 18:03:26,879 - tornado.access - WARNING - 404 POST /boaform/admin/formPing (::ffff:142.112.113.103) 17.25ms
2020-03-30 18:13:13,567 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 18:13:33,329 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 5420}
2020-03-30 18:28:13,568 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 18:28:33,323 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 6320}
2020-03-30 18:43:13,573 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 18:43:33,325 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 7220}
2020-03-30 18:58:13,581 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 18:58:33,367 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 8120}
2020-03-30 19:13:13,584 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 19:13:33,361 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 9020}
2020-03-30 19:28:13,586 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 19:28:33,344 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 9920}
2020-03-30 19:43:13,589 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 19:43:33,339 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 10820}
2020-03-30 19:51:42,866 - tornado.access - WARNING - 404 GET /manager/html (::ffff:162.243.130.135) 16.94ms
2020-03-30 19:58:13,592 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 19:58:33,357 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 11720}
2020-03-30 20:13:13,594 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 20:13:29,508 - octoprint.plugins.errortracking - INFO - Initialized error tracking
2020-03-30 20:13:33,325 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 12620}
2020-03-30 20:21:51,525 - tornado.access - WARNING - 404 POST /cgi-bin/ViewLog.asp (::ffff:64.20.33.141) 23.19ms
2020-03-30 20:22:05,833 - tornado.access - WARNING - 404 GET /muieblackcat (::ffff:167.172.158.192) 16.85ms
2020-03-30 20:22:06,027 - tornado.access - WARNING - 404 GET //phpMyAdmin/scripts/setup.php (::ffff:167.172.158.192) 13.13ms
2020-03-30 20:22:06,231 - tornado.access - WARNING - 404 GET //phpmyadmin/scripts/setup.php (::ffff:167.172.158.192) 14.03ms
2020-03-30 20:22:06,431 - tornado.access - WARNING - 404 GET //pma/scripts/setup.php (::ffff:167.172.158.192) 13.22ms
2020-03-30 20:22:06,635 - tornado.access - WARNING - 404 GET //myadmin/scripts/setup.php (::ffff:167.172.158.192) 14.40ms
2020-03-30 20:22:06,845 - tornado.access - WARNING - 404 GET //MyAdmin/scripts/setup.php (::ffff:167.172.158.192) 21.18ms
2020-03-30 20:22:07,047 - tornado.access - WARNING - 404 GET //Admin/scripts/setup.php (::ffff:167.172.158.192) 12.76ms
2020-03-30 20:28:13,597 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 20:28:33,347 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 13520}
2020-03-30 20:39:46,613 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 20:39:46,736 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 20:39:46,739 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 20:39:46,758 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 20:43:13,604 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 20:43:33,388 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 14420}
2020-03-30 20:44:46,143 - tornado.access - WARNING - 404 POST /cgi-bin/ViewLog.asp (::ffff:64.20.54.74) 21.12ms
2020-03-30 20:49:40,285 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 20:49:40,405 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 20:49:40,408 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 20:50:00,348 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 20:57:53,329 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 20:57:53,452 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 20:57:53,459 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 20:57:53,485 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125
2020-03-30 20:58:13,608 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-03-30 20:58:33,398 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 15320}
2020-03-30 21:05:31,681 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.125
2020-03-30 21:05:31,848 - octoprint.server.util.flask - INFO - Passively logging in user _api from ::ffff:192.168.1.125
2020-03-30 21:05:31,851 - octoprint.access.users - INFO - Logged in user: _api
2020-03-30 21:05:31,870 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.125