Skip to content

Instantly share code, notes, and snippets.

@kazibole
Created April 9, 2019 03:28
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save kazibole/c40d53531689e863a5ee1e68c1ca6545 to your computer and use it in GitHub Desktop.
Save kazibole/c40d53531689e863a5ee1e68c1ca6545 to your computer and use it in GitHub Desktop.
octoprint_1.3.10_safe.log
2019-04-08 22:38:29,687 - 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...
2019-04-08 22:38:31,951 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 15 mixin implementations, 17 hook handlers
2019-04-08 22:38:32,097 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-04-08 22:38:32,170 - octoprint.server - INFO - Intermediary server started
2019-04-08 22:38:32,171 - 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...
2019-04-08 22:38:32,696 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 15 mixin implementations, 17 hook handlers
2019-04-08 22:38:32,698 - octoprint.printer.profile - ERROR - Profile _default does not exist, creating _default again and setting it as default
2019-04-08 22:38:32,827 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-04-08 22:38:32,829 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-04-08 22:38:34,568 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 22:38:35,956 - octoprint.util.pip - INFO - Version of pip is 19.0.1
2019-04-08 22:38:35,958 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 22:38:35,958 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 22:38:35,963 - octoprint.plugin.core - INFO - Initialized 15 plugin implementation(s)
2019-04-08 22:38:36,065 - octoprint.plugin.core - INFO - 15 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
| CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/cura
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| 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
2019-04-08 22:38:36,083 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-04-08 22:38:36,091 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-04-08 22:38:36,092 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-04-08 22:38:36,436 - octoprint.server - INFO - Shutting down intermediary server...
2019-04-08 22:38:36,696 - octoprint.server - INFO - Intermediary server shut down
2019-04-08 22:38:36,699 - octoprint.events - INFO - Processing startup event, this is our first event
2019-04-08 22:38:36,700 - octoprint.events - INFO - Adding 0 events to queue that were held back before startup event
2019-04-08 22:38:36,702 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-04-08 22:38:36,707 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-04-08 22:38:36,722 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2019-04-08 22:38:36,733 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2019-04-08 22:38:36,743 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2019-04-08 22:38:36,784 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-04-08 22:38:37,543 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.69s
2019-04-08 22:38:37,833 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from https://plugins.octoprint.org/plugins.json
2019-04-08 22:38:38,323 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 22:38:38,324 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 22:38:38,325 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 22:38:38,549 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.73s
2019-04-08 22:38:38,923 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-04-08 22:38:39,204 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.58s
2019-04-08 22:38:39,860 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.58s
2019-04-08 22:38:40,416 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 22:38:40,560 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.57s
2019-04-08 22:38:46,825 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages/ (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 22:38:46,825 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 22:38:46,842 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2019-04-08 16:47:47,977 - octoprint.server - INFO - Shutting down...
2019-04-08 16:47:48,907 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2019-04-08 16:47:48,907 - octoprint.events - INFO - Event loop shut down
2019-04-08 16:47:48,917 - octoprint.server - INFO - Goodbye!
2019-04-08 16:47:53,794 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 16:47:53,796 - octoprint.startup - INFO - Starting OctoPrint 1.3.10
2019-04-08 16:47:53,798 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 16:47:54,950 - 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...
2019-04-08 16:47:57,297 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 15 mixin implementations, 17 hook handlers
2019-04-08 16:47:57,415 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-04-08 16:47:57,461 - octoprint.server - INFO - Intermediary server started
2019-04-08 16:47:57,462 - 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...
2019-04-08 16:47:58,033 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 15 mixin implementations, 17 hook handlers
2019-04-08 16:47:58,071 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-04-08 16:47:58,072 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-04-08 16:47:58,116 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2019-04-08 16:48:00,024 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 16:48:01,399 - octoprint.util.pip - INFO - Version of pip is 19.0.1
2019-04-08 16:48:01,400 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 16:48:01,401 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 16:48:01,405 - octoprint.plugin.core - INFO - Initialized 15 plugin implementation(s)
2019-04-08 16:48:01,414 - octoprint.plugin.core - INFO - 15 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
| CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/cura
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| 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
2019-04-08 16:48:01,432 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-04-08 16:48:01,441 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-04-08 16:48:01,443 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-04-08 16:48:01,804 - octoprint.server - INFO - Shutting down intermediary server...
2019-04-08 16:48:01,976 - octoprint.server - INFO - Intermediary server shut down
2019-04-08 16:48:01,978 - octoprint.events - INFO - Processing startup event, this is our first event
2019-04-08 16:48:01,979 - octoprint.events - INFO - Adding 0 events to queue that were held back before startup event
2019-04-08 16:48:01,980 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-04-08 16:48:01,987 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-04-08 16:48:02,001 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2019-04-08 16:48:02,011 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2019-04-08 16:48:02,022 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2019-04-08 16:48:02,037 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-04-08 16:48:02,100 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-04-08 16:48:02,439 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-04-08 16:48:03,666 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 16:48:03,668 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 16:48:03,669 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 16:48:12,223 - octoprint.server.util.flask.PreemptiveCache - INFO - Adding entry for / and {'query_string': 'l10n=en', 'path': u'/', '_count': 1, '_timestamp': 1554760092.223473, 'base_url': 'https://172.16.12.81/'}
2019-04-08 16:48:31,088 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:172.16.12.20
2019-04-08 16:48:40,067 - octoprint.server.api - INFO - Actively logging in user admin from ::ffff:172.16.12.20
2019-04-08 16:48:41,672 - octoprint.server.util.sockjs - INFO - User admin logged in on the socket from client ::ffff:172.16.12.20
2019-04-08 16:48:43,831 - octoprint.plugins.tracking - INFO - Initialized anonymous tracking
2019-04-08 16:48:45,093 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.13', 'ram': 918188032, 'octopi_version': '0.16.0', 'version': u'1.3.10', 'pip': '19.0.1', 'cores': 4, 'freq': 1400.0, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'os': 'linux'}
2019-04-08 16:49:57,649 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-04-08 16:49:57,653 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-04-08 16:49:57,657 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 16:49:58,772 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 16:50:00,384 - octoprint.util.comm - INFO - Got a resend request for line 1 which is also our current line. It looks like we just handled a reset and this is a left over of this
2019-04-08 16:50:00,393 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2019-04-08 16:50:00,404 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 16:50:00,444 - octoprint.util.comm - INFO - Printer reports firmware name "Prusa-Firmware 3.7.0 based on Marlin"
2019-04-08 16:50:01,060 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': u'/dev/ttyACM0', 'firmware_name': 'Prusa-Firmware 3.7.0 based on Marlin'}
2019-04-08 16:52:26,638 - octoprint.plugins.tracking - INFO - Sent tracking event disable_plugin, payload: {'plugin_version': None, 'plugin': 'cura'}
2019-04-08 16:52:35,501 - octoprint.plugins.tracking - INFO - Sent tracking event disable_plugin, payload: {'plugin_version': None, 'plugin': 'virtual_printer'}
2019-04-08 16:52:41,302 - octoprint.server.api.system - INFO - Performing command for core:restart: sudo service octoprint restart
2019-04-08 16:52:41,492 - octoprint.server - INFO - Shutting down...
2019-04-08 16:52:42,217 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2019-04-08 16:52:42,224 - octoprint.events - INFO - Event loop shut down
2019-04-08 16:52:42,244 - octoprint.server - INFO - Goodbye!
2019-04-08 16:52:44,409 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 16:52:44,411 - octoprint.startup - INFO - Starting OctoPrint 1.3.10
2019-04-08 16:52:44,412 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 16:52:45,381 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2019-04-08 16:52:46,003 - octoprint.startup - INFO - Blacklist processing done, adding 1 blacklisted plugin versions: roomtemp (any)
2019-04-08 16:52:46,039 - 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...
2019-04-08 16:52:46,784 - octoprint.plugin.core - INFO - Plugin Virtual Printer is disabled.
2019-04-08 16:52:47,039 - octoprint.plugin.core - INFO - Plugin CuraEngine (<= 15.04) is disabled.
2019-04-08 16:52:47,808 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 13 mixin implementations, 16 hook handlers
2019-04-08 16:52:47,904 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-04-08 16:52:47,929 - octoprint.server - INFO - Intermediary server started
2019-04-08 16:52:47,929 - 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...
2019-04-08 16:52:48,445 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 13 mixin implementations, 16 hook handlers
2019-04-08 16:52:48,486 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-04-08 16:52:48,488 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-04-08 16:52:48,504 - octoprint.plugins.tracking - INFO - Initialized anonymous tracking
2019-04-08 16:52:48,533 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2019-04-08 16:52:49,908 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 16:52:51,279 - octoprint.util.pip - INFO - Version of pip is 19.0.1
2019-04-08 16:52:51,281 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 16:52:51,281 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 16:52:51,286 - octoprint.plugin.core - INFO - Initialized 13 plugin implementation(s)
2019-04-08 16:52:51,294 - octoprint.plugin.core - INFO - 15 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
| !CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/cura
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| 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
2019-04-08 16:52:51,312 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-04-08 16:52:51,321 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-04-08 16:52:51,337 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-04-08 16:52:51,646 - octoprint.server - INFO - Shutting down intermediary server...
2019-04-08 16:52:52,122 - octoprint.server - INFO - Intermediary server shut down
2019-04-08 16:52:52,127 - octoprint.events - INFO - Processing startup event, this is our first event
2019-04-08 16:52:52,143 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2019-04-08 16:52:52,152 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-04-08 16:52:52,204 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-04-08 16:52:52,257 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-04-08 16:52:52,298 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2019-04-08 16:52:52,385 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2019-04-08 16:52:52,563 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2019-04-08 16:52:52,686 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-04-08 16:52:52,686 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-04-08 16:52:52,817 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-04-08 16:52:53,458 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-04-08 16:52:54,103 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:172.16.12.20
2019-04-08 16:52:54,173 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 16:52:54,185 - octoprint.server.util.sockjs - INFO - User admin logged in on the socket from client ::ffff:172.16.12.20
2019-04-08 16:52:54,302 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 16:52:54,303 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 16:52:54,304 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 16:52:54,416 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'https://172.16.12.81/'}
2019-04-08 16:52:55,047 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.13', 'ram': 918188032, 'octopi_version': '0.16.0', 'version': u'1.3.10', 'pip': '19.0.1', 'cores': 4, 'freq': 1400.0, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'os': 'linux'}
2019-04-08 16:53:04,072 - octoprint.server.preemptive_cache - INFO - ... done in 9.66s
2019-04-08 16:55:17,176 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 16:55:17,286 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:172.16.12.20
2019-04-08 16:55:18,129 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:172.16.12.20
2019-04-08 16:55:18,197 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 16:55:19,892 - octoprint.server.util.sockjs - INFO - User admin logged in on the socket from client ::ffff:172.16.12.20
2019-04-08 16:55:59,715 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-04-08 16:55:59,719 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-04-08 16:55:59,736 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 16:57:00,909 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2019-04-08 16:58:07,633 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-04-08 16:58:07,645 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-04-08 16:58:07,655 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 16:58:08,762 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 16:58:10,409 - octoprint.util.comm - INFO - Got a resend request for line 1 which is also our current line. It looks like we just handled a reset and this is a left over of this
2019-04-08 16:58:10,423 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2019-04-08 16:58:10,433 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 16:58:10,480 - octoprint.util.comm - INFO - Printer reports firmware name "Prusa-Firmware 3.7.0 based on Marlin"
2019-04-08 16:58:11,431 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': u'/dev/ttyACM0', 'firmware_name': 'Prusa-Firmware 3.7.0 based on Marlin'}
2019-04-08 16:59:22,123 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Printing from SD"
2019-04-08 16:59:22,779 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': 'sdcard', 'file': '61b53313e9fb83b1d2bd8320047c14d3068cfd17'}
2019-04-08 16:59:24,505 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2019-04-08 16:59:39,275 - octoprint.util.comm - INFO - Externally triggered heatup detected
2019-04-08 17:06:14,593 - octoprint.util.comm - INFO - Externally triggered heatup detected
2019-04-08 17:06:14,600 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 2s
2019-04-08 17:06:15,056 - octoprint.printer.standard - ERROR - Looks like something crashed inside the state update worker. Please report this on the OctoPrint issue tracker (make sure to include logs!)
Traceback (most recent call last):
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1346, in _work
data = self.get_current_data()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1356, in get_current_data
self._progress = self._get_current_progress()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1282, in _get_current_progress
return self._on_get_progress()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 793, in _updateProgressDataCallback
statisticalTotalPrintTimeType)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 106, in estimate
estimatedTotalPrintTime = self.estimate_total(progress, cleanedPrintTime)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 166, in estimate_total
return self._data.update(printTime / progress)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 207, in update
if -1.0 * self._threshold < self.average_distance < self._threshold:
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 239, in average_distance
return sum(self._distances) / len(self._distances)
ZeroDivisionError: division by zero
2019-04-08 17:07:47,905 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-04-08 17:07:48,954 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 17:07:49,084 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:172.16.12.20
2019-04-08 17:07:49,924 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:172.16.12.20
2019-04-08 17:07:50,011 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 17:07:51,836 - octoprint.server.util.sockjs - INFO - User admin logged in on the socket from client ::ffff:172.16.12.20
2019-04-08 17:07:51,838 - octoprint.printer.standard - ERROR - Error while trying to send initial state update
Traceback (most recent call last):
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 919, in _sendInitialStateUpdate
data = self._stateMonitor.get_current_data()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1356, in get_current_data
self._progress = self._get_current_progress()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1282, in _get_current_progress
return self._on_get_progress()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 793, in _updateProgressDataCallback
statisticalTotalPrintTimeType)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 106, in estimate
estimatedTotalPrintTime = self.estimate_total(progress, cleanedPrintTime)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 166, in estimate_total
return self._data.update(printTime / progress)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 207, in update
if -1.0 * self._threshold < self.average_distance < self._threshold:
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 239, in average_distance
return sum(self._distances) / len(self._distances)
ZeroDivisionError: division by zero
2019-04-08 17:07:54,970 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {}
2019-04-08 17:12:40,089 - octoprint.util.comm - INFO - Changing monitoring state from "Printing from SD" to "Finishing"
2019-04-08 17:12:40,745 - octoprint.plugins.tracking - INFO - Sent tracking event print_done, payload: {'origin': 'sdcard', 'file': '61b53313e9fb83b1d2bd8320047c14d3068cfd17', 'elapsed': 797}
2019-04-08 17:12:40,801 - octoprint.util.comm - INFO - Changing monitoring state from "Finishing" to "Operational"
2019-04-08 17:15:09,280 - octoprint.server.api.system - INFO - Performing command for core:reboot: sudo shutdown -r now
2019-04-08 17:15:09,456 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:172.16.12.20
2019-04-08 17:15:09,702 - octoprint.server - INFO - Shutting down...
2019-04-08 17:15:09,773 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2019-04-08 17:15:09,774 - octoprint.events - INFO - Event loop shut down
2019-04-08 17:15:09,801 - octoprint.server - INFO - Goodbye!
2019-04-08 17:15:15,137 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 17:15:15,139 - octoprint.startup - INFO - Starting OctoPrint 1.3.10
2019-04-08 17:15:15,141 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 17:15:16,265 - octoprint.startup - INFO - We don't appear to be online, not fetching plugin blacklist
2019-04-08 17:15:16,337 - 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...
2019-04-08 17:15:17,571 - octoprint.plugin.core - INFO - Plugin Virtual Printer is disabled.
2019-04-08 17:15:17,932 - octoprint.plugin.core - INFO - Plugin CuraEngine (<= 15.04) is disabled.
2019-04-08 17:15:18,770 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 13 mixin implementations, 16 hook handlers
2019-04-08 17:15:18,892 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-04-08 17:15:18,940 - octoprint.server - INFO - Intermediary server started
2019-04-08 17:15:18,941 - 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...
2019-04-08 17:15:19,511 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 13 mixin implementations, 16 hook handlers
2019-04-08 17:15:19,557 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-04-08 17:15:19,559 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-04-08 17:15:19,576 - octoprint.plugins.tracking - INFO - Initialized anonymous tracking
2019-04-08 17:15:19,608 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2019-04-08 17:15:21,964 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 17:15:23,428 - octoprint.util.pip - INFO - Version of pip is 19.0.1
2019-04-08 17:15:23,430 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 17:15:23,430 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 17:15:23,435 - octoprint.plugin.core - INFO - Initialized 13 plugin implementation(s)
2019-04-08 17:15:23,443 - octoprint.plugin.core - INFO - 15 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
| !CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/cura
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| 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
2019-04-08 17:15:23,462 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-04-08 17:15:23,471 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-04-08 17:15:23,487 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-04-08 17:15:23,912 - octoprint.server - INFO - Shutting down intermediary server...
2019-04-08 17:15:23,981 - octoprint.server - INFO - Intermediary server shut down
2019-04-08 17:15:23,984 - octoprint.events - INFO - Processing startup event, this is our first event
2019-04-08 17:15:23,985 - octoprint.events - INFO - Adding 0 events to queue that were held back before startup event
2019-04-08 17:15:23,986 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-04-08 17:15:23,990 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-04-08 17:15:24,003 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2019-04-08 17:15:24,014 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2019-04-08 17:15:24,025 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2019-04-08 17:15:24,040 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-04-08 17:15:24,148 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-04-08 17:15:24,578 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-04-08 17:15:25,534 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:172.16.12.20
2019-04-08 17:15:25,588 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 17:15:25,598 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 17:15:25,598 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 17:15:25,624 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 17:15:25,635 - octoprint.server.util.sockjs - INFO - User admin logged in on the socket from client ::ffff:172.16.12.20
2019-04-08 17:15:25,639 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2019-04-08 17:15:25,745 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-04-08 17:15:26,417 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'https://172.16.12.81/'}
2019-04-08 17:15:26,497 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-04-08 17:15:26,607 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.13', 'ram': 918188032, 'octopi_version': '0.16.0', 'version': u'1.3.10', 'pip': '19.0.1', 'cores': 4, 'freq': 1400.0, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'os': 'linux'}
2019-04-08 17:15:36,756 - octoprint.server.preemptive_cache - INFO - ... done in 10.34s
2019-04-08 17:15:53,089 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 17:15:53,194 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:172.16.12.20
2019-04-08 17:15:54,051 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:172.16.12.20
2019-04-08 17:15:54,175 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 17:15:55,912 - octoprint.server.util.sockjs - INFO - User admin logged in on the socket from client ::ffff:172.16.12.20
2019-04-08 17:15:59,593 - octoprint.server.api.system - INFO - Performing command for core:restart_safe: sudo service octoprint restart
2019-04-08 17:15:59,934 - octoprint.server - INFO - Shutting down...
2019-04-08 17:15:59,972 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2019-04-08 17:15:59,975 - octoprint.events - INFO - Event loop shut down
2019-04-08 17:15:59,990 - octoprint.server - INFO - Goodbye!
2019-04-08 17:16:02,106 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 17:16:02,108 - octoprint.startup - INFO - Starting OctoPrint 1.3.10
2019-04-08 17:16:02,109 - octoprint.startup - INFO - Starting in SAFE MODE. Third party plugins will be disabled!
2019-04-08 17:16:02,110 - octoprint.startup - INFO - ******************************************************************************
2019-04-08 17:16:03,086 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2019-04-08 17:16:03,710 - octoprint.startup - INFO - Blacklist processing done, adding 1 blacklisted plugin versions: roomtemp (any)
2019-04-08 17:16:03,773 - 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...
2019-04-08 17:16:04,471 - octoprint.plugin.core - INFO - Plugin Virtual Printer is disabled.
2019-04-08 17:16:04,726 - octoprint.plugin.core - INFO - Plugin CuraEngine (<= 15.04) is disabled.
2019-04-08 17:16:05,495 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 13 mixin implementations, 16 hook handlers
2019-04-08 17:16:05,591 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-04-08 17:16:05,616 - octoprint.server - INFO - Intermediary server started
2019-04-08 17:16:05,616 - 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...
2019-04-08 17:16:06,135 - octoprint.plugin.core - INFO - Found 15 plugin(s) providing 13 mixin implementations, 16 hook handlers
2019-04-08 17:16:06,176 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2019-04-08 17:16:06,178 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2019-04-08 17:16:06,194 - octoprint.plugins.tracking - INFO - Initialized anonymous tracking
2019-04-08 17:16:06,223 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2019-04-08 17:16:07,606 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 17:16:08,979 - octoprint.util.pip - INFO - Version of pip is 19.0.1
2019-04-08 17:16:08,981 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 17:16:08,981 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 17:16:08,990 - octoprint.plugin.core - INFO - Initialized 13 plugin implementation(s)
2019-04-08 17:16:08,999 - octoprint.plugin.core - INFO - 15 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
| !CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/cura
| Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
| Force Login (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/forcelogin
| Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
| 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
2019-04-08 17:16:09,016 - octoprint.environment - INFO - Detected environment is Python 2.7.13 under Linux (linux2). Details:
| hardware:
| cores: 4
| freq: 1400.0
| ram: 918188032
| os:
| id: linux
| platform: linux2
| plugins:
| pi_support:
| model: Raspberry Pi 3 Model B Plus Rev 1.3
| octopi_version: 0.16.0
| python:
| pip: 19.0.1
| version: 2.7.13
| virtualenv: /home/pi/oprint
2019-04-08 17:16:09,026 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2019-04-08 17:16:09,039 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2019-04-08 17:16:09,344 - octoprint.server - INFO - Shutting down intermediary server...
2019-04-08 17:16:09,371 - octoprint.server - INFO - Intermediary server shut down
2019-04-08 17:16:09,373 - octoprint.events - INFO - Processing startup event, this is our first event
2019-04-08 17:16:09,373 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2019-04-08 17:16:09,376 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-04-08 17:16:09,403 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-04-08 17:16:09,704 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-04-08 17:16:09,708 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-04-08 17:16:09,719 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2019-04-08 17:16:09,837 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2019-04-08 17:16:09,852 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2019-04-08 17:16:09,923 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2019-04-08 17:16:09,934 - octoprint.server - INFO - Server started successfully in safe mode as requested from config, removing flag
2019-04-08 17:16:10,306 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-04-08 17:16:10,703 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-04-08 17:16:11,744 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2019-04-08 17:16:11,746 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2019-04-08 17:16:11,747 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-04-08 17:16:11,817 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'https://172.16.12.81/'}
2019-04-08 17:16:11,998 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:172.16.12.20
2019-04-08 17:16:12,080 - octoprint.server.util.flask - INFO - Passively logging in user admin from ::ffff:172.16.12.20
2019-04-08 17:16:12,092 - octoprint.server.util.sockjs - INFO - User admin logged in on the socket from client ::ffff:172.16.12.20
2019-04-08 17:16:12,468 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'python': '2.7.13', 'ram': 918188032, 'octopi_version': '0.16.0', 'version': u'1.3.10', 'pip': '19.0.1', 'cores': 4, 'freq': 1400.0, 'pi_model': 'Raspberry Pi 3 Model B Plus Rev 1.3', 'os': 'linux'}
2019-04-08 17:16:21,770 - octoprint.server.preemptive_cache - INFO - ... done in 9.95s
2019-04-08 17:16:48,496 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2019-04-08 17:16:48,502 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-04-08 17:16:48,515 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 17:16:49,629 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 17:16:51,134 - octoprint.util.comm - INFO - Got a resend request for line 1 which is also our current line. It looks like we just handled a reset and this is a left over of this
2019-04-08 17:16:51,153 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2019-04-08 17:16:51,164 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-04-08 17:16:51,213 - octoprint.util.comm - INFO - Printer reports firmware name "Prusa-Firmware 3.7.0 based on Marlin"
2019-04-08 17:16:51,836 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {u'printer_baudrate': 115200, u'printer_port': u'/dev/ttyACM0', 'firmware_name': 'Prusa-Firmware 3.7.0 based on Marlin'}
2019-04-08 17:17:04,248 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2019-04-08 17:17:04,255 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 9s
2019-04-08 17:17:39,078 - octoprint.util.comm - INFO - Externally triggered heatup detected
2019-04-08 17:21:41,068 - octoprint.util.comm - INFO - Externally triggered heatup detected
2019-04-08 17:22:21,258 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Printing from SD"
2019-04-08 17:22:21,933 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': 'sdcard', 'file': '61b53313e9fb83b1d2bd8320047c14d3068cfd17'}
2019-04-08 17:22:31,313 - octoprint.util.comm - INFO - Communication timeout while printing, trying to trigger response from printer.
2019-04-08 17:22:42,676 - octoprint.util.comm - INFO - Communication timeout while printing, trying to trigger response from printer.
2019-04-08 17:22:53,104 - octoprint.printer.standard - ERROR - Looks like something crashed inside the state update worker. Please report this on the OctoPrint issue tracker (make sure to include logs!)
Traceback (most recent call last):
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1346, in _work
data = self.get_current_data()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1356, in get_current_data
self._progress = self._get_current_progress()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 1282, in _get_current_progress
return self._on_get_progress()
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/standard.py", line 793, in _updateProgressDataCallback
statisticalTotalPrintTimeType)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 106, in estimate
estimatedTotalPrintTime = self.estimate_total(progress, cleanedPrintTime)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 166, in estimate_total
return self._data.update(printTime / progress)
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 207, in update
if -1.0 * self._threshold < self.average_distance < self._threshold:
File "/home/pi/oprint/local/lib/python2.7/site-packages/octoprint/printer/estimation.py", line 239, in average_distance
return sum(self._distances) / len(self._distances)
ZeroDivisionError: division by zero
2019-04-08 17:23:21,881 - octoprint.util.comm - INFO - Communication timeout while printing, trying to trigger response from printer.
2019-04-08 17:23:41,883 - octoprint.util.comm - INFO - Communication timeout while printing, trying to trigger response from printer.
2019-04-08 17:27:31,459 - octoprint.util.comm - INFO - Communication timeout while printing, trying to trigger response from printer.
2019-04-08 17:28:07,088 - octoprint.util.comm - INFO - Changing monitoring state from "Printing from SD" to "Finishing"
2019-04-08 17:28:07,751 - octoprint.plugins.tracking - INFO - Sent tracking event print_done, payload: {'origin': 'sdcard', 'file': '61b53313e9fb83b1d2bd8320047c14d3068cfd17', 'elapsed': 345}
2019-04-08 17:28:20,605 - octoprint.util.comm - INFO - Changing monitoring state from "Finishing" to "Operational"
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment