octoprint.log

2018-08-04 15:17:16,821 - octoprint.server - INFO - --- Log roll over detected ---------------------------------------------------
2018-08-04 15:17:16,821 - octoprint.server - INFO - OctoPrint 1.3.8
2018-08-04 15:17:16,824 - octoprint.plugin.core - INFO - 15 plugin(s) registered with the system:
|  Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/announcements
|  Autoselect Plugin (0.2.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_autoselect
|  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
|  Custom Control Editor (0.2.1) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_customControl
|  Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/discovery
|  Logging (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/logging
|  MQTT (0.7.1) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_mqtt
|  OctoPi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/octopi_support
| !OctoPrint Anywhere (0.6.0) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_anywhere
|  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
|  Tasmota-MQTT (0.3.1) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_tasmota_mqtt
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/virtual_printer
2018-08-04 15:17:16,843 - octoprint.environment - INFO - Detected environment is Python 2.7.9 under Linux (linux2). Details:
|  hardware:
|    cores: 4
|    freq: 1200.0
|    ram: 904245248
|  os:
|    id: linux
|    platform: linux2
|  plugins:
|    octopi_support:
|      model: unknown
|      revision: a22082
|      version: 0.13.0
|  python:
|    pip: 8.1.1
|    version: 2.7.9
|    virtualenv: /home/pi/oprint
2018-08-04 15:17:16,844 - octoprint.server - INFO - ------------------------------------------------------------------------------
2018-08-04 15:17:16,818 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 15:32:16,845 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 15:47:16,846 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 16:02:16,847 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 16:17:16,849 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 16:32:16,850 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 16:47:16,851 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 17:02:16,853 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 17:17:16,854 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 17:32:16,855 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 17:47:16,857 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 18:02:16,858 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 18:17:16,860 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 18:32:16,861 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 18:47:16,862 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 19:02:16,864 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 19:17:16,865 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 19:32:16,866 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 19:47:16,867 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 20:02:16,869 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 20:17:16,870 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 20:32:16,872 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 20:47:16,873 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 21:02:16,874 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 21:17:16,876 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 21:32:16,877 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 21:47:16,878 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 22:02:16,880 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 22:09:11,538 - octoprint.server.util.flask.PreemptiveCache - INFO - Adding entry for / and {'query_string': 'l10n=en', 'path': u'/', '_count': 1, '_timestamp': 1533420551.538795, 'base_url': 'http://octopi.gquad.space/'}
2018-08-04 22:09:22,715 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.10.198
2018-08-04 22:09:23,711 - tornado.access - WARNING - 403 POST /api/plugin/tasmota_mqtt (127.0.0.1) 11.16ms
2018-08-04 22:09:33,982 - tornado.access - WARNING - 401 POST /api/login (127.0.0.1) 24.01ms
2018-08-04 22:09:40,587 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.53s
2018-08-04 22:09:41,342 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.53s
2018-08-04 22:09:41,927 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.52s
2018-08-04 22:09:42,499 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.51s
2018-08-04 22:09:43,099 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.51s
2018-08-04 22:09:43,811 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2018-08-04 22:09:43,831 - octoprint.plugins.tasmota_mqtt - INFO - toggling aneta8 relay 
2018-08-04 22:09:43,997 - octoprint.plugins.tasmota_mqtt - INFO - Received message for aneta8/stat/POWER: ON
2018-08-04 22:09:49,819 - octoprint.plugins.softwareupdate - INFO - Starting update of octoprint to 1.3.9...
2018-08-04 22:09:58,892 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2018-08-04 22:09:58,894 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2018-08-04 22:09:58,901 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-08-04 22:10:08,658 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-08-04 22:10:13,273 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2018-08-04 22:10:13,283 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-08-04 22:10:13,318 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin V1.0.2; Sprinter/grbl mashup for gen6"
2018-08-04 22:11:12,454 - octoprint.plugins.softwareupdate - ERROR - Update of octoprint can not be performed, please also check plugin_softwareupdate_console.log for possible causes of this
Traceback (most recent call last):
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/__init__.py", line 909, in _perform_update
    update_result = updater.perform_update(target, populated_check, target_version, log_cb=self._log, online=online)
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/updaters/pip.py", line 108, in perform_update
    raise exceptions.UpdateError("Error while executing pip install", (stdout, stderr))
UpdateError
2018-08-04 22:14:49,433 - octoprint.filemanager.analysis - INFO - Starting analysis of local:MacbookStand-15if-NORMAL.gcode
2018-08-04 22:14:49,435 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/MacbookStand-15if-NORMAL.gcode
2018-08-04 22:14:49,444 - octoprint.plugins.autoselect - INFO - Selecting MacbookStand-15if-NORMAL.gcode on local that was just uploaded
2018-08-04 22:15:35,209 - octoprint.filemanager.analysis - INFO - Analysis of entry local:MacbookStand-15if-NORMAL.gcode finished, needed 45.78s
2018-08-04 22:16:55,503 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, telling it to set the busy interval to our "communicationBusy" timeout - 1s = 2s
2018-08-04 22:17:16,881 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 22:18:23,361 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Printing"
2018-08-04 22:18:23,410 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-08-04 22:32:16,882 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 22:47:16,883 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 23:02:16,885 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 23:17:16,887 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 23:32:16,888 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-04 23:47:16,889 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 00:02:16,890 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 00:17:16,892 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 00:25:43,080 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.10.198
2018-08-05 00:32:16,893 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 00:47:16,894 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 01:02:16,896 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 01:17:16,897 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 01:32:16,898 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 01:47:16,900 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 02:02:16,900 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 02:17:16,902 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 02:32:16,903 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 02:47:16,905 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 03:02:16,913 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 03:17:16,915 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 03:32:16,916 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 03:47:16,918 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 04:02:16,919 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 04:17:16,920 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 04:32:16,922 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 04:47:16,923 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 05:02:16,924 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 05:17:16,926 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 05:32:16,927 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 05:47:16,929 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 06:02:16,930 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 06:17:16,931 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 06:32:16,933 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 06:47:16,934 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 06:59:12,755 - octoprint.util.comm - INFO - Finished in 31249.395 s.
2018-08-05 06:59:12,770 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Operational"
2018-08-05 07:02:16,936 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 07:17:16,937 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 07:32:16,938 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 07:47:16,940 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 08:02:16,941 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 08:17:16,942 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 08:32:16,944 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 08:47:16,945 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 09:02:16,946 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 09:17:16,948 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 09:32:16,949 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 09:47:16,950 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 09:57:33,765 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.10.198
2018-08-05 10:02:16,952 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 10:09:31,325 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.10.198
2018-08-05 10:09:39,641 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.10.198
2018-08-05 10:09:40,589 - octoprint.plugins.tasmota_mqtt - INFO - checking status of aneta8 relay 
2018-08-05 10:09:40,706 - octoprint.plugins.tasmota_mqtt - INFO - Received message for aneta8/stat/POWER: ON
2018-08-05 10:09:41,284 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.53s
2018-08-05 10:09:42,131 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.62s
2018-08-05 10:09:42,843 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.64s
2018-08-05 10:09:43,495 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.58s
2018-08-05 10:09:44,221 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.63s
2018-08-05 10:12:43,323 - octoprint.plugins.softwareupdate - INFO - Starting update of octoprint to 1.3.9...
2018-08-05 10:13:05,817 - octoprint.plugins.softwareupdate - ERROR - Update of octoprint can not be performed, please also check plugin_softwareupdate_console.log for possible causes of this
Traceback (most recent call last):
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/__init__.py", line 909, in _perform_update
    update_result = updater.perform_update(target, populated_check, target_version, log_cb=self._log, online=online)
  File "/home/pi/oprint/lib/python2.7/site-packages/octoprint/plugins/softwareupdate/updaters/pip.py", line 110, in perform_update
    raise exceptions.UpdateError("Error while executing pip install", (stdout, stderr))
UpdateError
2018-08-05 10:17:16,953 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2018-08-05 10:30:31,295 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.10.198
2018-08-05 10:30:31,622 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.10.198
2018-08-05 10:32:16,954 - octoprint.server.heartbeat - INFO - Server heartbeat <3