log 1

2019-12-08 17:18:23,201 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 17:18:23,205 - octoprint.server - INFO - --- Log roll over detected ---------------------------------------------------
2019-12-08 17:18:23,206 - octoprint.server - INFO - OctoPrint 1.4.0rc3.dev9+g124f01ca
2019-12-08 17:18:23,211 - octoprint.plugin.core - INFO - 23 plugin(s) registered with the system:
|  Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/action_command_prompt
|  Announcement Plugin (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/announcements
|  Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/tracking
|  Application Keys Plugin (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/appkeys
|  Backup & Restore (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/backup
|  Bed Visualizer (0.1.11) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_bedlevelvisualizer
|  CANVAS (2.1.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_canvas
|  Core Wizard (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/corewizard
| *Custom Control Editor (0.2.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_customControl
|  Discovery (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/discovery
|  Error Tracking (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/errortracking
| *Firmware Updater (1.6.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_firmwareupdater
|  Logging (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/logging
|  M73 ETA Override (1.0.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_m73etaoverride
|  MultiCam (0.2.6) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_multicam
|  OctoPod Plugin (0.2.4) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_octopod
| *OctoPrint-Cancelobject (0.3.3) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_cancelobject
|  Palette 2 (2.3.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_palette2
|  Pi Support Plugin (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/pi_support
|  Plugin Manager (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/pluginmanager
|  Printer Safety Check (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/printer_safety_check
|  Software Update (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/softwareupdate
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2019-12-08 17:18:23,229 - octoprint.environment - INFO - Detected environment is Python 3.8.0 under Linux (linux). Details:
|  hardware:
|      cores: 4
|      freq: 1200.0
|      ram: 917016576
|  os:
|      id: linux
|      platform: linux
|  plugins:
|      pi_support:
|          model: Raspberry Pi 3 Model B Rev 1.2
|          octopi_version: 0.15.0
|  python:
|      pip: 19.3.1
|      version: 3.8.0
|      virtualenv: /home/pi/oprint
2019-12-08 17:18:23,230 - octoprint.server - INFO - ------------------------------------------------------------------------------
2019-12-08 17:18:37,567 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'plugins': 'palette2:2.3.1,octopod:0.2.4,multicam:0.2.6,firmwareupdater:1.6.1,customcontrol:0.2.1,cancelobject:0.3.3,m73etaoverride:1.0.1,canvas:2.1.1,bedlevelvisualizer:0.1.11'}
2019-12-08 17:18:38,439 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 86415}
2019-12-08 17:22:58,540 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:8c8c:5979:e6a9:ffd1
2019-12-08 17:23:09,653 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:8c8c:5979:e6a9:ffd1
2019-12-08 17:23:09,789 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-08 17:23:09,808 - octoprint.server.util.flask - INFO - Passively logging in user qweqwe from 2601:1c0:4600:4c9:8c8c:5979:e6a9:ffd1
2019-12-08 17:23:10,256 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-08 17:23:10,261 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-08 17:23:10,263 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-08 17:23:10,270 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-08 17:23:10,271 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-08 17:23:10,304 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-08 17:23:10,304 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-08 17:23:10,310 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-08 17:23:10,311 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-08 17:23:10,404 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-08 17:23:10,405 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575825790,"clientToken":"dc2e8fa0-959e-431d-9b4b-5bf11302ac31"}
2019-12-08 17:23:10,405 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-08 17:23:10,405 - octoprint.plugins.canvas - INFO - Token: dc2e8fa0-959e-431d-9b4b-5bf11302ac31
2019-12-08 17:23:10,406 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-08 17:23:14,479 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 3.7s
2019-12-08 17:23:15,770 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 1.0s
2019-12-08 17:23:17,539 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 1.0s
2019-12-08 17:23:18,578 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.96s
2019-12-08 17:23:19,680 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 1.0s
2019-12-08 17:23:20,859 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-12-08 17:23:21,230 - octoprint.plugins.canvas - INFO - current version: 2.1.1
2019-12-08 17:23:21,230 - octoprint.plugins.canvas - INFO - remote version: 2.1.1
2019-12-08 17:23:21,231 - octoprint.plugins.canvas - INFO - needs update: False
2019-12-08 17:23:21,576 - octoprint.plugins.palette2 - INFO - current version: 2.3.1
2019-12-08 17:23:21,577 - octoprint.plugins.palette2 - INFO - remote version: 2.3.1
2019-12-08 17:23:21,577 - octoprint.plugins.palette2 - INFO - needs update: False
2019-12-08 17:23:21,680 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2019-12-08 17:23:21,821 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-08 17:23:21,821 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-08 17:23:21,828 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-08 17:23:21,828 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-08 17:23:21,850 - octoprint.server.util.sockjs - INFO - User qweqwe logged in on the socket from client 2601:1c0:4600:4c9:8c8c:5979:e6a9:ffd1
2019-12-08 17:24:45,383 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:8c8c:5979:e6a9:ffd1
2019-12-08 17:33:23,202 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 17:33:37,790 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 87315}
2019-12-08 17:48:23,204 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 17:48:37,781 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 88215}
2019-12-08 18:03:23,205 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 18:03:37,787 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 89115}
2019-12-08 18:18:23,207 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 18:18:37,796 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 90015}
2019-12-08 18:33:23,208 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 18:33:37,803 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 90915}
2019-12-08 18:48:23,210 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 18:48:37,799 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 91815}
2019-12-08 19:03:23,211 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 19:03:37,790 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 92715}
2019-12-08 19:18:23,212 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 19:18:37,798 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 93615}
2019-12-08 19:33:23,214 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 19:33:37,787 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 94515}
2019-12-08 19:48:23,215 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 19:48:37,812 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 95415}
2019-12-08 20:03:23,217 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 20:03:37,795 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 96315}
2019-12-08 20:18:23,218 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 20:18:37,807 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 97215}
2019-12-08 20:33:23,220 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 20:33:37,796 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 98115}
2019-12-08 20:48:23,221 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 20:48:37,833 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 99015}
2019-12-08 21:03:23,223 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 21:03:37,824 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 99915}
2019-12-08 21:18:23,224 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 21:18:37,834 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 100815}
2019-12-08 21:33:23,226 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 21:33:37,810 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 101715}
2019-12-08 21:48:23,227 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 21:48:37,861 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 102615}
2019-12-08 22:03:23,228 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 22:03:37,834 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 103515}
2019-12-08 22:18:23,230 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 22:18:37,816 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 104415}
2019-12-08 22:33:23,231 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 22:33:37,906 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 105315}
2019-12-08 22:48:23,233 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 22:48:37,849 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 106215}
2019-12-08 23:03:23,234 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 23:03:37,853 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 107115}
2019-12-08 23:18:23,236 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 23:18:37,841 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 108015}
2019-12-08 23:33:23,237 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 23:33:37,829 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 108915}
2019-12-08 23:48:23,239 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-08 23:48:37,836 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 109815}
2019-12-09 00:03:23,240 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 00:03:37,841 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 110715}
2019-12-09 00:18:23,242 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 00:18:37,841 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 111615}
2019-12-09 00:33:23,243 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 00:33:37,856 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 112515}
2019-12-09 00:48:23,244 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 00:48:37,826 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 113415}
2019-12-09 01:03:23,246 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 01:03:37,819 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 114315}
2019-12-09 01:18:23,247 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 01:18:38,140 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 115215}
2019-12-09 01:33:23,249 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 01:33:37,862 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 116115}
2019-12-09 01:48:23,250 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 01:48:37,843 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 117015}
2019-12-09 02:03:23,252 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 02:03:37,881 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 117915}
2019-12-09 02:18:23,253 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 02:18:37,845 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 118815}
2019-12-09 02:33:23,255 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 02:33:37,866 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 119715}
2019-12-09 02:43:04,539 - octoprint.filemanager.analysis - INFO - Starting analysis of local:E_PETG_Top_Cover_LCR_T4.gcode
2019-12-09 02:43:04,541 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python3.8 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/E_PETG_Top_Cover_LCR_T4.gcode
2019-12-09 02:43:25,805 - octoprint.filemanager.analysis - INFO - Analysis of entry local:E_PETG_Top_Cover_LCR_T4.gcode finished, needed 21.27s
2019-12-09 02:43:46,863 - octoprint.filemanager.analysis - INFO - Starting analysis of local:E_PETG_Top_Cover_LCR_T4.gcode
2019-12-09 02:43:46,864 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python3.8 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/E_PETG_Top_Cover_LCR_T4.gcode
2019-12-09 02:43:59,603 - octoprint.filemanager.analysis - INFO - Analysis of entry local:E_PETG_Top_Cover_LCR_T4.gcode finished, needed 12.74s
2019-12-09 02:44:02,462 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 02:44:02,597 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 02:44:02,619 - octoprint.server.util.flask - INFO - Passively logging in user qweqwe from ::ffff:192.168.252.17
2019-12-09 02:44:03,022 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-09 02:44:03,022 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 02:44:03,029 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 02:44:03,029 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 02:44:03,169 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 02:44:03,175 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 02:44:03,178 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 02:44:03,202 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 02:44:03,203 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 02:44:03,319 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 02:44:03,319 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575859443,"clientToken":"f6fe516f-fbb5-4fa4-822f-879f7df1067c"}
2019-12-09 02:44:03,320 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 02:44:03,320 - octoprint.plugins.canvas - INFO - Token: f6fe516f-fbb5-4fa4-822f-879f7df1067c
2019-12-09 02:44:03,321 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 02:44:04,711 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from https://plugins.octoprint.org/plugins.json
2019-12-09 02:44:06,609 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-12-09 02:44:06,849 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-09 02:44:06,850 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 02:44:06,857 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 02:44:06,857 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 02:44:06,882 - octoprint.server.util.sockjs - INFO - User qweqwe logged in on the socket from client 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 02:44:39,947 - octoprint.filemanager.analysis - INFO - Starting analysis of local:E_PETG_Top_Cover_LCR_T4.gcode
2019-12-09 02:44:39,949 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python3.8 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/E_PETG_Top_Cover_LCR_T4.gcode
2019-12-09 02:45:01,477 - octoprint.filemanager.analysis - INFO - Analysis of entry local:E_PETG_Top_Cover_LCR_T4.gcode finished, needed 21.53s
2019-12-09 02:45:19,908 - octoprint.filemanager.analysis - INFO - Starting analysis of local:E_PETG_Back_Cover_LCR_T4.gcode
2019-12-09 02:45:19,911 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python3.8 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/E_PETG_Back_Cover_LCR_T4.gcode
2019-12-09 02:45:33,343 - octoprint.filemanager.analysis - INFO - Analysis of entry local:E_PETG_Back_Cover_LCR_T4.gcode finished, needed 13.43s
2019-12-09 02:47:54,149 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial port"
2019-12-09 02:47:54,199 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial port" to "Opening serial port"
2019-12-09 02:47:54,201 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-12-09 02:47:54,205 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-09 02:47:54,217 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-09 02:47:54,242 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2019-12-09 02:47:54,268 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-09 02:47:54,308 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin 2.0.x (GitHub)"
2019-12-09 02:47:54,315 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2019-12-09 02:47:54,732 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Operational
2019-12-09 02:47:54,819 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Operational
2019-12-09 02:47:55,645 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {'firmware_name': 'Marlin 2.0.x (GitHub)', 'printer_port': 'AUTO', 'printer_baudrate': 250000}
2019-12-09 02:48:23,256 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 02:48:37,845 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 120615}
2019-12-09 03:00:48,581 - octoprint.plugins.octopod - DEBUG - Bed reached target temp for 8.00626433690389 minutes
2019-12-09 03:00:48,585 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'bed-warmed' (Ender 3)
2019-12-09 03:00:48,828 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-09 03:00:48,829 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'bed-warmed' (Ender 3)
2019-12-09 03:00:48,924 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-09 03:01:06,805 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 03:01:07,179 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 03:01:07,283 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 03:01:07,307 - octoprint.server.util.flask - INFO - Passively logging in user qweqwe from 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 03:01:07,775 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 03:01:07,796 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 03:01:07,810 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 03:01:07,869 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 03:01:07,869 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 03:01:07,964 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 03:01:07,965 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575860467,"clientToken":"e227388c-5b5d-4e55-af5f-c800a4130d20"}
2019-12-09 03:01:07,965 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 03:01:07,965 - octoprint.plugins.canvas - INFO - Token: e227388c-5b5d-4e55-af5f-c800a4130d20
2019-12-09 03:01:07,966 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 03:01:08,414 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-09 03:01:08,414 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 03:01:08,421 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 03:01:08,422 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 03:01:08,440 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-09 03:01:08,440 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 03:01:08,447 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 03:01:08,447 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 03:01:08,469 - octoprint.server.util.sockjs - INFO - User qweqwe logged in on the socket from client 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 03:01:15,750 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: E_PETG_Top_Cover_LCR_T4.gcode, owner: qweqwe, user: qweqwe
2019-12-09 03:01:15,774 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2019-12-09 03:01:15,790 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: E_PETG_Top_Cover_LCR_T4.gcode, owner: qweqwe, user: qweqwe
2019-12-09 03:01:15,810 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-09 03:01:15,856 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2019-12-09 03:01:16,003 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Printing
2019-12-09 03:01:16,094 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Printing
2019-12-09 03:01:16,787 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': 'local', 'file': 'e43de395bd918976c14bac5cebfc75edb0fe6320'}
2019-12-09 03:01:18,135 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2019-12-09 03:02:40,312 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 4s
2019-12-09 03:03:23,257 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 03:03:37,860 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 121515}
2019-12-09 03:18:23,258 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 03:18:37,872 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 122415}
2019-12-09 03:32:27,605 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 03:32:27,743 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 03:32:28,225 - octoprint.server.util.flask - INFO - Passively logging in user _api from blablabla
2019-12-09 03:32:28,227 - octoprint.server.util.sockjs - INFO - User qweqwe logged out, logging out on socket
2019-12-09 03:32:28,286 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client blablabla
2019-12-09 03:32:28,336 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 03:32:28,369 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 03:32:28,435 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 03:32:28,466 - tornado.application - ERROR - Exception in callback functools.partial(<function wrap.<locals>.null_wrapper at 0x6ffb8610>)
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/ioloop.py", line 605, in _run_callback
    ret = callback()
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/stack_context.py", line 277, in null_wrapper
    return fn(*args, **kwargs)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 90, in <lambda>
    self.server.io_loop.add_callback(lambda: self.send_pack(message, binary))
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 85, in send_pack
    self.write_message(message, binary)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/websocket.py", line 251, in write_message
    message = tornado.escape.json_encode(message)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/server/util/tornado.py", line 42, in fixed_json_encode
    return json.dumps(value, default=JsonEncoding.encode, allow_nan=False).replace("</", "<\\/")
  File "/usr/local/lib/python3.8/json/__init__.py", line 234, in dumps
    return cls(
  File "/usr/local/lib/python3.8/json/encoder.py", line 199, in encode
    chunks = self.iterencode(o, _one_shot=True)
  File "/usr/local/lib/python3.8/json/encoder.py", line 257, in iterencode
    return _iterencode(o, 0)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/json/__init__.py", line 33, in encode
    raise TypeError
TypeError
2019-12-09 03:32:28,482 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 03:32:28,485 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 03:32:28,546 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 03:32:28,547 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575862348,"clientToken":"c3d8ee7d-1b2d-454f-9587-742169e38ce9"}
2019-12-09 03:32:28,547 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 03:32:28,547 - octoprint.plugins.canvas - INFO - Token: c3d8ee7d-1b2d-454f-9587-742169e38ce9
2019-12-09 03:32:28,548 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 03:32:39,909 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 03:33:23,259 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 03:33:37,897 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 123315}
2019-12-09 03:45:00,685 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 03:45:03,382 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 03:45:03,557 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 03:45:04,067 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 03:45:04,072 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 03:45:04,075 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 03:45:04,081 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 03:45:04,082 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 03:45:04,180 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 03:45:04,181 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575863104,"clientToken":"c1567c1d-a6b5-4640-9dc8-2f50b15314ae"}
2019-12-09 03:45:04,181 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 03:45:04,181 - octoprint.plugins.canvas - INFO - Token: c1567c1d-a6b5-4640-9dc8-2f50b15314ae
2019-12-09 03:45:04,181 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 03:48:23,261 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 03:48:37,861 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 124215}
2019-12-09 04:02:44,424 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:891e:8f74:6eac:ade0
2019-12-09 04:03:23,262 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 04:03:37,876 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 125115}
2019-12-09 04:08:47,242 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 04:08:47,375 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 04:08:48,437 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 04:08:48,446 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 04:08:48,451 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 04:08:48,463 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 04:08:48,465 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 04:08:48,578 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 04:08:48,579 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575864528,"clientToken":"ae54027b-f79a-4d49-becd-dbeebc9f25ea"}
2019-12-09 04:08:48,580 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 04:08:48,580 - octoprint.plugins.canvas - INFO - Token: ae54027b-f79a-4d49-becd-dbeebc9f25ea
2019-12-09 04:08:48,581 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 04:08:49,442 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 04:18:23,263 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 04:18:37,875 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 126015}
2019-12-09 04:21:58,464 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 04:21:58,592 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 04:21:59,016 - octoprint.server.util.flask - INFO - Passively logging in user _api from blablabla
2019-12-09 04:21:59,071 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client blablabla
2019-12-09 04:21:59,317 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 04:21:59,322 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 04:21:59,327 - tornado.application - ERROR - Exception in callback functools.partial(<function wrap.<locals>.null_wrapper at 0x655d78e0>)
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/ioloop.py", line 605, in _run_callback
    ret = callback()
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/stack_context.py", line 277, in null_wrapper
    return fn(*args, **kwargs)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 90, in <lambda>
    self.server.io_loop.add_callback(lambda: self.send_pack(message, binary))
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 85, in send_pack
    self.write_message(message, binary)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/websocket.py", line 251, in write_message
    message = tornado.escape.json_encode(message)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/server/util/tornado.py", line 42, in fixed_json_encode
    return json.dumps(value, default=JsonEncoding.encode, allow_nan=False).replace("</", "<\\/")
  File "/usr/local/lib/python3.8/json/__init__.py", line 234, in dumps
    return cls(
  File "/usr/local/lib/python3.8/json/encoder.py", line 199, in encode
    chunks = self.iterencode(o, _one_shot=True)
  File "/usr/local/lib/python3.8/json/encoder.py", line 257, in iterencode
    return _iterencode(o, 0)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/json/__init__.py", line 33, in encode
    raise TypeError
TypeError
2019-12-09 04:21:59,329 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 04:21:59,346 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 04:21:59,348 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 04:21:59,508 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 04:21:59,508 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575865319,"clientToken":"37c23928-8144-4fe3-8a2c-65b2ea697ac2"}
2019-12-09 04:21:59,508 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 04:21:59,508 - octoprint.plugins.canvas - INFO - Token: 37c23928-8144-4fe3-8a2c-65b2ea697ac2
2019-12-09 04:21:59,509 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 04:22:44,809 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 04:33:23,265 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 04:33:37,907 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 126915}
2019-12-09 04:36:10,931 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 04:36:11,076 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 04:36:11,477 - octoprint.server.util.flask - INFO - Passively logging in user _api from blablabla
2019-12-09 04:36:11,514 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client blablabla
2019-12-09 04:36:12,141 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 04:36:12,146 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 04:36:12,150 - tornado.application - ERROR - Exception in callback functools.partial(<function wrap.<locals>.null_wrapper at 0x68251d60>)
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/ioloop.py", line 605, in _run_callback
    ret = callback()
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/stack_context.py", line 277, in null_wrapper
    return fn(*args, **kwargs)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 90, in <lambda>
    self.server.io_loop.add_callback(lambda: self.send_pack(message, binary))
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 85, in send_pack
    self.write_message(message, binary)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/websocket.py", line 251, in write_message
    message = tornado.escape.json_encode(message)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/server/util/tornado.py", line 42, in fixed_json_encode
    return json.dumps(value, default=JsonEncoding.encode, allow_nan=False).replace("</", "<\\/")
  File "/usr/local/lib/python3.8/json/__init__.py", line 234, in dumps
    return cls(
  File "/usr/local/lib/python3.8/json/encoder.py", line 199, in encode
    chunks = self.iterencode(o, _one_shot=True)
  File "/usr/local/lib/python3.8/json/encoder.py", line 257, in iterencode
    return _iterencode(o, 0)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/json/__init__.py", line 33, in encode
    raise TypeError
TypeError
2019-12-09 04:36:12,154 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 04:36:12,186 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 04:36:12,189 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 04:36:12,249 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 04:36:12,250 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575866172,"clientToken":"3625105f-6cb9-4e8a-aff7-f2d816da6e4c"}
2019-12-09 04:36:12,250 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 04:36:12,250 - octoprint.plugins.canvas - INFO - Token: 3625105f-6cb9-4e8a-aff7-f2d816da6e4c
2019-12-09 04:36:12,251 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 04:36:32,571 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 04:48:23,267 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 04:48:37,871 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 127815}
2019-12-09 05:03:23,268 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 05:03:37,867 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 128715}
2019-12-09 05:05:50,940 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 05:05:51,122 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:05:51,366 - octoprint.server.util.flask - INFO - Passively logging in user _api from blablabla
2019-12-09 05:05:51,401 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client blablabla
2019-12-09 05:05:51,613 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 05:05:51,622 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:05:51,626 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 05:05:51,638 - tornado.application - ERROR - Exception in callback functools.partial(<function wrap.<locals>.null_wrapper at 0x6ffa2c88>)
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/ioloop.py", line 605, in _run_callback
    ret = callback()
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/stack_context.py", line 277, in null_wrapper
    return fn(*args, **kwargs)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 90, in <lambda>
    self.server.io_loop.add_callback(lambda: self.send_pack(message, binary))
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 85, in send_pack
    self.write_message(message, binary)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/websocket.py", line 251, in write_message
    message = tornado.escape.json_encode(message)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/server/util/tornado.py", line 42, in fixed_json_encode
    return json.dumps(value, default=JsonEncoding.encode, allow_nan=False).replace("</", "<\\/")
  File "/usr/local/lib/python3.8/json/__init__.py", line 234, in dumps
    return cls(
  File "/usr/local/lib/python3.8/json/encoder.py", line 199, in encode
    chunks = self.iterencode(o, _one_shot=True)
  File "/usr/local/lib/python3.8/json/encoder.py", line 257, in iterencode
    return _iterencode(o, 0)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/json/__init__.py", line 33, in encode
    raise TypeError
TypeError
2019-12-09 05:05:51,649 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 05:05:51,651 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 05:05:51,740 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 05:05:51,740 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575867951,"clientToken":"eadd7204-6934-4740-adc4-ecf6925a9b63"}
2019-12-09 05:05:51,741 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 05:05:51,741 - octoprint.plugins.canvas - INFO - Token: eadd7204-6934-4740-adc4-ecf6925a9b63
2019-12-09 05:05:51,741 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 05:06:02,272 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 05:18:23,270 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 05:18:37,953 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 129615}
2019-12-09 05:19:40,673 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:5563:824b:d003:382b
2019-12-09 05:19:40,830 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:19:42,048 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 05:19:42,063 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:19:42,069 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 05:19:42,082 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 05:19:42,083 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 05:19:42,196 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 05:19:42,197 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575868782,"clientToken":"9f7e2be5-ffe5-4faf-a8e8-b11c4430a61a"}
2019-12-09 05:19:42,197 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 05:19:42,198 - octoprint.plugins.canvas - INFO - Token: 9f7e2be5-ffe5-4faf-a8e8-b11c4430a61a
2019-12-09 05:19:42,198 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 05:20:00,633 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 05:20:00,799 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:20:01,234 - octoprint.server.util.flask - INFO - Passively logging in user _api from blablabla
2019-12-09 05:20:01,280 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client blablabla
2019-12-09 05:20:01,901 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 05:20:01,905 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:20:01,908 - tornado.application - ERROR - Exception in callback functools.partial(<function wrap.<locals>.null_wrapper at 0x655e2028>)
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/ioloop.py", line 605, in _run_callback
    ret = callback()
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/stack_context.py", line 277, in null_wrapper
    return fn(*args, **kwargs)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 90, in <lambda>
    self.server.io_loop.add_callback(lambda: self.send_pack(message, binary))
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 85, in send_pack
    self.write_message(message, binary)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/websocket.py", line 251, in write_message
    message = tornado.escape.json_encode(message)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/server/util/tornado.py", line 42, in fixed_json_encode
    return json.dumps(value, default=JsonEncoding.encode, allow_nan=False).replace("</", "<\\/")
  File "/usr/local/lib/python3.8/json/__init__.py", line 234, in dumps
    return cls(
  File "/usr/local/lib/python3.8/json/encoder.py", line 199, in encode
    chunks = self.iterencode(o, _one_shot=True)
  File "/usr/local/lib/python3.8/json/encoder.py", line 257, in iterencode
    return _iterencode(o, 0)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/json/__init__.py", line 33, in encode
    raise TypeError
TypeError
2019-12-09 05:20:01,913 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 05:20:01,938 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 05:20:01,941 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 05:20:02,352 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 05:20:02,353 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575868802,"clientToken":"90e1609a-2ba0-4986-abee-a42827ac26f8"}
2019-12-09 05:20:02,353 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 05:20:02,354 - octoprint.plugins.canvas - INFO - Token: 90e1609a-2ba0-4986-abee-a42827ac26f8
2019-12-09 05:20:02,355 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 05:22:51,940 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 05:33:23,271 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 05:33:37,919 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 130515}
2019-12-09 05:36:31,305 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:5563:824b:d003:382b
2019-12-09 05:46:16,225 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 05:46:16,379 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:46:17,006 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 05:46:17,011 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 05:46:17,014 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 05:46:17,022 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 05:46:17,023 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 05:46:17,145 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 05:46:17,145 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575870377,"clientToken":"3ef02c3e-7762-4887-bc83-d3513c6cc0ff"}
2019-12-09 05:46:17,146 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 05:46:17,146 - octoprint.plugins.canvas - INFO - Token: 3ef02c3e-7762-4887-bc83-d3513c6cc0ff
2019-12-09 05:46:17,147 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 05:46:18,396 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 05:48:23,272 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 05:48:37,917 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 131415}
2019-12-09 06:03:23,273 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 06:03:37,905 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 132315}
2019-12-09 06:18:23,275 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 06:18:37,895 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 133215}
2019-12-09 06:24:56,783 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 06:24:56,919 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 06:24:57,419 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 06:24:57,425 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 06:24:57,428 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 06:24:57,435 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 06:24:57,435 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 06:24:57,535 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 06:24:57,535 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575872697,"clientToken":"1c51916a-69f2-4b66-835e-212f0d1237ee"}
2019-12-09 06:24:57,536 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 06:24:57,536 - octoprint.plugins.canvas - INFO - Token: 1c51916a-69f2-4b66-835e-212f0d1237ee
2019-12-09 06:24:57,537 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 06:24:58,936 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 06:33:23,276 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 06:33:37,903 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 134115}
2019-12-09 06:46:08,153 - octoprint.util.comm - INFO - Finished in 13492.380 s.
2019-12-09 06:46:08,154 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Finishing"
2019-12-09 06:46:08,172 - octoprint.plugins.octopod - DEBUG - Snapshot URL: http://localhost:8080/?action=snapshot
2019-12-09 06:46:08,179 - octoprint.printer.standard.job - INFO - Print job done - origin: local, path: E_PETG_Top_Cover_LCR_T4.gcode, owner: qweqwe
2019-12-09 06:46:08,209 - octoprint.util.comm - INFO - Changing monitoring state from "Finishing" to "Operational"
2019-12-09 06:46:08,265 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'Print complete' (Ender 3)
2019-12-09 06:46:08,810 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-09 06:46:08,811 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'Print complete' (Ender 3)
2019-12-09 06:46:08,930 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-09 06:46:09,082 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Operational
2019-12-09 06:46:09,203 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Operational
2019-12-09 06:46:09,852 - octoprint.plugins.tracking - INFO - Sent tracking event print_done, payload: {'origin': 'local', 'file': 'e43de395bd918976c14bac5cebfc75edb0fe6320', 'elapsed': 13492}
2019-12-09 06:46:10,428 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 06:46:10,598 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 06:46:11,069 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 06:46:11,074 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 06:46:11,077 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 06:46:11,085 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 06:46:11,086 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 06:46:11,180 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 06:46:11,181 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575873971,"clientToken":"2a098ee5-ed52-4ce9-886b-36d39a7d08f6"}
2019-12-09 06:46:11,181 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 06:46:11,181 - octoprint.plugins.canvas - INFO - Token: 2a098ee5-ed52-4ce9-886b-36d39a7d08f6
2019-12-09 06:46:11,182 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 06:46:11,489 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 06:48:23,277 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 06:48:37,911 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 135015}
2019-12-09 07:03:23,278 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 07:03:37,913 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 135915}
2019-12-09 07:04:49,124 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 07:04:49,293 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 07:04:49,763 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 07:04:49,768 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 07:04:49,772 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 07:04:49,779 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 07:04:49,780 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 07:04:49,879 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 07:04:49,879 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575875089,"clientToken":"d58a4f5c-3fe7-4232-9870-f8b9b31ca16d"}
2019-12-09 07:04:49,879 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 07:04:49,880 - octoprint.plugins.canvas - INFO - Token: d58a4f5c-3fe7-4232-9870-f8b9b31ca16d
2019-12-09 07:04:49,880 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 07:04:51,372 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 07:16:24,341 - octoprint.plugins.octopod - DEBUG - Print done and bed temp is now below threshold 30. Actual 29.89.
2019-12-09 07:16:24,344 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'bed-cooled' (Ender 3)
2019-12-09 07:16:24,847 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-09 07:16:24,847 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'bed-cooled' (Ender 3)
2019-12-09 07:16:25,257 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-09 07:18:23,280 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 07:18:37,920 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 136815}
2019-12-09 07:33:23,281 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 07:33:37,897 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 137715}
2019-12-09 07:39:40,074 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:5563:824b:d003:382b
2019-12-09 07:39:40,242 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 07:39:40,761 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 07:39:40,767 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 07:39:40,770 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 07:39:40,777 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 07:39:40,778 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 07:39:40,903 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 07:39:40,904 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575877180,"clientToken":"48d61d86-df0c-486f-94b4-67982fe67b94"}
2019-12-09 07:39:40,904 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 07:39:40,905 - octoprint.plugins.canvas - INFO - Token: 48d61d86-df0c-486f-94b4-67982fe67b94
2019-12-09 07:39:40,905 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 07:47:34,992 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 07:47:35,165 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 07:47:35,697 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 07:47:35,702 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 07:47:35,705 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 07:47:35,713 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 07:47:35,714 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 07:47:35,821 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 07:47:35,821 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575877655,"clientToken":"e998765f-0e15-45ce-afea-d2c2b6b08c79"}
2019-12-09 07:47:35,821 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 07:47:35,821 - octoprint.plugins.canvas - INFO - Token: e998765f-0e15-45ce-afea-d2c2b6b08c79
2019-12-09 07:47:35,822 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 07:47:37,245 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 07:48:23,282 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 07:48:37,873 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 138615}
2019-12-09 07:58:36,104 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:5563:824b:d003:382b
2019-12-09 08:03:23,284 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 08:03:37,904 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 139515}
2019-12-09 08:15:01,999 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:5563:824b:d003:382b
2019-12-09 08:15:02,153 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 08:15:02,678 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 08:15:02,683 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 08:15:02,686 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 08:15:02,693 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 08:15:02,693 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 08:15:02,823 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 08:15:02,824 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575879302,"clientToken":"8258903c-8346-4d2e-ba3f-decd41c2a7d7"}
2019-12-09 08:15:02,824 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 08:15:02,825 - octoprint.plugins.canvas - INFO - Token: 8258903c-8346-4d2e-ba3f-decd41c2a7d7
2019-12-09 08:15:02,826 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 08:18:23,285 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 08:18:37,903 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 140415}
2019-12-09 08:33:23,286 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 08:33:37,897 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 141315}
2019-12-09 08:36:03,785 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:5563:824b:d003:382b
2019-12-09 08:48:23,287 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 08:48:37,903 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 142215}
2019-12-09 08:49:03,601 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 08:49:03,744 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 08:49:04,299 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 08:49:04,304 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 08:49:04,307 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 08:49:04,314 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 08:49:04,314 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 08:49:04,436 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 08:49:04,437 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575881344,"clientToken":"2563cc55-aa0b-48d1-8f4d-5daaa8d4d7c9"}
2019-12-09 08:49:04,437 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 08:49:04,438 - octoprint.plugins.canvas - INFO - Token: 2563cc55-aa0b-48d1-8f4d-5daaa8d4d7c9
2019-12-09 08:49:04,439 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 08:49:05,804 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 09:03:23,289 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 09:03:37,927 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 143115}
2019-12-09 09:06:49,405 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 09:06:49,571 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 09:06:49,582 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 09:06:50,070 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 09:06:50,075 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 09:06:50,079 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 09:06:50,084 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 09:06:50,084 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 09:06:50,320 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 09:06:50,321 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575882410,"clientToken":"3947a23d-0a12-4887-afc6-4ad3c6d521f3"}
2019-12-09 09:06:50,322 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 09:06:50,322 - octoprint.plugins.canvas - INFO - Token: 3947a23d-0a12-4887-afc6-4ad3c6d521f3
2019-12-09 09:06:50,323 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 09:18:23,290 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 09:18:37,900 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 144015}
2019-12-09 09:33:23,292 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 09:33:37,909 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 144915}
2019-12-09 09:44:00,028 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 09:44:00,181 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 09:44:01,156 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 09:44:01,167 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 09:44:01,173 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 09:44:01,187 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 09:44:01,188 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 09:44:01,280 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 09:44:01,280 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575884641,"clientToken":"87e2824f-27c8-4ad1-94c4-91424b082d9d"}
2019-12-09 09:44:01,281 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 09:44:01,281 - octoprint.plugins.canvas - INFO - Token: 87e2824f-27c8-4ad1-94c4-91424b082d9d
2019-12-09 09:44:01,282 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 09:44:02,195 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 09:48:23,293 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 09:48:37,902 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 145815}
2019-12-09 10:03:23,294 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 10:03:37,926 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 146715}
2019-12-09 10:09:41,904 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 10:09:42,032 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:09:42,456 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 10:09:42,461 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:09:42,464 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 10:09:42,471 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 10:09:42,471 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 10:09:42,592 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 10:09:42,593 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575886182,"clientToken":"52041bc7-6b74-4a88-aee1-9104a593fb3c"}
2019-12-09 10:09:42,593 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 10:09:42,593 - octoprint.plugins.canvas - INFO - Token: 52041bc7-6b74-4a88-aee1-9104a593fb3c
2019-12-09 10:09:42,593 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 10:09:44,065 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 10:18:23,296 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 10:18:37,940 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 147615}
2019-12-09 10:33:23,297 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 10:33:37,926 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 148515}
2019-12-09 10:34:12,440 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:a45d:7eb2:cbb3:7532
2019-12-09 10:34:12,623 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:34:13,501 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 10:34:13,511 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:34:13,517 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 10:34:13,529 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 10:34:13,530 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 10:34:13,663 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 10:34:13,664 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575887653,"clientToken":"f470b0fd-14ef-46b0-bfe8-0f1330d1d73a"}
2019-12-09 10:34:13,664 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 10:34:13,665 - octoprint.plugins.canvas - INFO - Token: f470b0fd-14ef-46b0-bfe8-0f1330d1d73a
2019-12-09 10:34:13,665 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 10:37:17,231 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 10:37:17,386 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:37:18,709 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 10:37:18,713 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:37:18,716 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 10:37:18,724 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 10:37:18,725 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 10:37:18,842 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 10:37:18,843 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575887838,"clientToken":"abbb1cf7-0bcc-4d4d-8b29-d453fd353a95"}
2019-12-09 10:37:18,843 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 10:37:18,844 - octoprint.plugins.canvas - INFO - Token: abbb1cf7-0bcc-4d4d-8b29-d453fd353a95
2019-12-09 10:37:18,844 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 10:37:19,173 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 10:48:23,298 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 10:48:38,019 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 149415}
2019-12-09 10:51:18,985 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:a45d:7eb2:cbb3:7532
2019-12-09 10:58:49,308 - octoprint.util.comm - ERROR - Unexpected error while reading from serial port
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/serial/serialposix.py", line 500, in read
    raise SerialException(
serial.serialutil.SerialException: device reports readiness to read but returned no data (device disconnected or multiple access on port?)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/comm.py", line 2824, in _readline
    ret = self._serial.readline()
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/comm.py", line 4969, in readline
    c = self.read(1)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/serial/serialposix.py", line 509, in read
    raise SerialException('read failed: {}'.format(e))
serial.serialutil.SerialException: read failed: device reports readiness to read but returned no data (device disconnected or multiple access on port?)
2019-12-09 10:58:49,311 - octoprint.util.comm - ERROR - Please see https://faq.octoprint.org/serialerror for possible reasons of this.
2019-12-09 10:58:49,327 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Offline (Error: SerialException: 'read failed: device reports readiness to read but returned no data (device disconnected or multiple access on port?)' @ comm.py:_readline:2824)"
2019-12-09 10:58:49,615 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Offline
2019-12-09 10:58:49,703 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Offline
2019-12-09 10:58:53,241 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:8d89:8805:bca4:e30b
2019-12-09 10:58:53,370 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:58:53,945 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 10:58:53,950 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 10:58:53,953 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 10:58:53,960 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 10:58:53,960 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 10:58:54,081 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 10:58:54,082 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575889134,"clientToken":"31ec9ad5-e271-4fc1-8bb1-03d648cad3a6"}
2019-12-09 10:58:54,083 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 10:58:54,083 - octoprint.plugins.canvas - INFO - Token: 31ec9ad5-e271-4fc1-8bb1-03d648cad3a6
2019-12-09 10:58:54,084 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 11:03:23,300 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 11:03:37,935 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 150315}
2019-12-09 11:15:43,304 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:8d89:8805:bca4:e30b
2019-12-09 11:18:23,301 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 11:18:37,916 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 151215}
2019-12-09 11:33:23,302 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 11:33:37,917 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 152115}
2019-12-09 11:39:51,778 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 11:39:51,927 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 11:39:52,373 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 11:39:52,378 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 11:39:52,381 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 11:39:52,388 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 11:39:52,389 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 11:39:52,483 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 11:39:52,483 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575891592,"clientToken":"76f8cd52-f681-4ace-8667-6c4e5f00ed88"}
2019-12-09 11:39:52,484 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 11:39:52,484 - octoprint.plugins.canvas - INFO - Token: 76f8cd52-f681-4ace-8667-6c4e5f00ed88
2019-12-09 11:39:52,484 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 11:39:53,760 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 11:48:20,203 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:4061:8516:eec6:717f
2019-12-09 11:48:20,375 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 11:48:20,827 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 11:48:20,833 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 11:48:20,837 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 11:48:20,843 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 11:48:20,844 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 11:48:20,949 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 11:48:20,949 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575892100,"clientToken":"01af1394-64c1-4d13-9590-778f3e22bd26"}
2019-12-09 11:48:20,950 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 11:48:20,950 - octoprint.plugins.canvas - INFO - Token: 01af1394-64c1-4d13-9590-778f3e22bd26
2019-12-09 11:48:20,950 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 11:48:23,303 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 11:48:37,942 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 153015}
2019-12-09 12:03:23,304 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 12:03:38,052 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 153915}
2019-12-09 12:06:29,704 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:4061:8516:eec6:717f
2019-12-09 12:15:47,142 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:bd26:17ac:9f2b:6402
2019-12-09 12:15:47,283 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 12:15:47,861 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 12:15:47,866 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 12:15:47,869 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 12:15:47,875 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 12:15:47,876 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 12:15:48,147 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 12:15:48,148 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575893748,"clientToken":"e311edcb-534f-4b76-9bb1-9bd711ae0f4d"}
2019-12-09 12:15:48,148 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 12:15:48,149 - octoprint.plugins.canvas - INFO - Token: e311edcb-534f-4b76-9bb1-9bd711ae0f4d
2019-12-09 12:15:48,149 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 12:18:23,306 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 12:18:38,039 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 154815}
2019-12-09 12:23:25,914 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 12:23:26,044 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 12:23:26,583 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 12:23:26,588 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 12:23:26,592 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 12:23:26,600 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 12:23:26,600 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 12:23:26,706 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 12:23:26,707 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575894206,"clientToken":"be463523-dca9-49c1-a4e0-4053bf5274cb"}
2019-12-09 12:23:26,707 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 12:23:26,708 - octoprint.plugins.canvas - INFO - Token: be463523-dca9-49c1-a4e0-4053bf5274cb
2019-12-09 12:23:26,708 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 12:23:27,779 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 12:33:02,024 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:bd26:17ac:9f2b:6402
2019-12-09 12:33:23,307 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 12:33:38,023 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 155715}
2019-12-09 12:48:23,308 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 12:48:37,940 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 156615}
2019-12-09 12:52:28,462 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:f934:7f99:5b1b:9890
2019-12-09 12:52:28,612 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 12:52:29,058 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 12:52:29,063 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 12:52:29,067 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 12:52:29,074 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 12:52:29,075 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 12:52:29,174 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 12:52:29,174 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575895949,"clientToken":"1df0d8f9-fd21-450a-874c-15723451c6bd"}
2019-12-09 12:52:29,174 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 12:52:29,175 - octoprint.plugins.canvas - INFO - Token: 1df0d8f9-fd21-450a-874c-15723451c6bd
2019-12-09 12:52:29,175 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 13:03:23,309 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 13:03:37,950 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 157515}
2019-12-09 13:08:57,545 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:f934:7f99:5b1b:9890
2019-12-09 13:18:00,515 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 13:18:00,686 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 13:18:01,166 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 13:18:01,172 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 13:18:01,176 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 13:18:01,182 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 13:18:01,183 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 13:18:01,608 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 13:18:01,609 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575897481,"clientToken":"a9fe6361-35f7-4db2-93e9-6cf0ef4bce4d"}
2019-12-09 13:18:01,609 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 13:18:01,610 - octoprint.plugins.canvas - INFO - Token: a9fe6361-35f7-4db2-93e9-6cf0ef4bce4d
2019-12-09 13:18:01,611 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 13:18:02,627 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 13:18:23,311 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 13:18:37,948 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 158415}
2019-12-09 13:28:17,369 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:20ae:6a51:ccbb:c0fd
2019-12-09 13:28:17,529 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 13:28:17,988 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 13:28:17,993 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 13:28:17,996 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 13:28:18,003 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 13:28:18,004 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 13:28:18,104 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 13:28:18,105 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575898098,"clientToken":"1cc302b0-36d5-40c3-8ff9-db9cd3e055b6"}
2019-12-09 13:28:18,105 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 13:28:18,105 - octoprint.plugins.canvas - INFO - Token: 1cc302b0-36d5-40c3-8ff9-db9cd3e055b6
2019-12-09 13:28:18,106 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 13:33:23,312 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 13:33:37,945 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 159315}
2019-12-09 13:44:42,824 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:20ae:6a51:ccbb:c0fd
2019-12-09 13:48:23,313 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 13:48:37,964 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 160215}
2019-12-09 14:03:23,314 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 14:03:37,945 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 161115}
2019-12-09 14:05:45,781 - octoprint.server.util.sockjs - INFO - New connection from client: 2601:1c0:4600:4c9:d903:f915:2eeb:b295
2019-12-09 14:05:45,922 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 14:05:46,386 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 14:05:46,391 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 14:05:46,395 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 14:05:46,402 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 14:05:46,402 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 14:05:46,527 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 14:05:46,527 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575900346,"clientToken":"39ea9c2c-cc1b-4b74-baea-b610993c30a3"}
2019-12-09 14:05:46,527 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 14:05:46,527 - octoprint.plugins.canvas - INFO - Token: 39ea9c2c-cc1b-4b74-baea-b610993c30a3
2019-12-09 14:05:46,528 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 14:18:23,316 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 14:18:37,947 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 162015}
2019-12-09 14:23:27,206 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 14:23:27,361 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 14:23:27,373 - octoprint.server.util.sockjs - INFO - Client connection closed: 2601:1c0:4600:4c9:d903:f915:2eeb:b295
2019-12-09 14:23:27,376 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 14:23:27,836 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 14:23:27,841 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 14:23:27,844 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 14:23:27,849 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 14:23:27,850 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 14:23:27,953 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 14:23:27,953 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575901407,"clientToken":"51913a52-c787-4c20-ae0c-626f695fe2e3"}
2019-12-09 14:23:27,953 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 14:23:27,954 - octoprint.plugins.canvas - INFO - Token: 51913a52-c787-4c20-ae0c-626f695fe2e3
2019-12-09 14:23:27,954 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 14:33:23,317 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 14:33:42,966 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 162915}
2019-12-09 14:40:33,320 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 14:40:33,477 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 14:40:33,970 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 14:40:33,974 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 14:40:33,978 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 14:40:33,984 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 14:40:33,985 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 14:40:34,124 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 14:40:34,125 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575902434,"clientToken":"b9b03965-206f-4493-b0b0-e4cdd4715a96"}
2019-12-09 14:40:34,125 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 14:40:34,125 - octoprint.plugins.canvas - INFO - Token: b9b03965-206f-4493-b0b0-e4cdd4715a96
2019-12-09 14:40:34,125 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 14:40:35,490 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 14:48:23,319 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 14:48:37,935 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 163815}
2019-12-09 15:03:23,320 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 15:03:37,963 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 164715}
2019-12-09 15:18:23,322 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 15:18:37,936 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 165615}
2019-12-09 15:22:41,756 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 15:22:41,899 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 15:22:42,365 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 15:22:42,369 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 15:22:42,373 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 15:22:42,379 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 15:22:42,380 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 15:22:42,531 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 15:22:42,532 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575904962,"clientToken":"a29ba064-9a01-478f-ad4d-e4a53f522920"}
2019-12-09 15:22:42,532 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 15:22:42,532 - octoprint.plugins.canvas - INFO - Token: a29ba064-9a01-478f-ad4d-e4a53f522920
2019-12-09 15:22:42,533 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 15:22:44,076 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 15:33:23,323 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 15:33:37,953 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 166515}
2019-12-09 15:48:23,324 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 15:48:37,969 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 167415}
2019-12-09 15:49:24,177 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 15:49:24,343 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 15:49:24,780 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 15:49:24,785 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 15:49:24,788 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 15:49:24,795 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 15:49:24,795 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 15:49:24,918 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 15:49:24,918 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575906564,"clientToken":"f6cbef23-b52f-432e-a733-c6ba9539f79d"}
2019-12-09 15:49:24,919 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 15:49:24,919 - octoprint.plugins.canvas - INFO - Token: f6cbef23-b52f-432e-a733-c6ba9539f79d
2019-12-09 15:49:24,919 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 15:49:49,219 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 15:57:41,805 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 15:57:41,967 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 15:57:41,976 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 15:57:42,449 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 15:57:42,453 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 15:57:42,456 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 15:57:42,461 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 15:57:42,461 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 15:57:42,557 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 15:57:42,557 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575907062,"clientToken":"4ddcb5cd-3e04-4910-8272-7d51e45de137"}
2019-12-09 15:57:42,558 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 15:57:42,558 - octoprint.plugins.canvas - INFO - Token: 4ddcb5cd-3e04-4910-8272-7d51e45de137
2019-12-09 15:57:42,558 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 16:03:23,326 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 16:03:37,956 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 168315}
2019-12-09 16:18:23,327 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 16:18:37,967 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 169215}
2019-12-09 16:21:04,562 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 16:21:04,734 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 16:21:05,293 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 16:21:05,298 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 16:21:05,302 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 16:21:05,308 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 16:21:05,308 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 16:21:05,412 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 16:21:05,413 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575908465,"clientToken":"c25f7d0c-b1be-4806-b282-e4bb0ac587ec"}
2019-12-09 16:21:05,413 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 16:21:05,414 - octoprint.plugins.canvas - INFO - Token: c25f7d0c-b1be-4806-b282-e4bb0ac587ec
2019-12-09 16:21:05,415 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 16:21:05,434 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 16:33:23,329 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 16:33:37,955 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 170115}
2019-12-09 16:48:23,330 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 16:48:37,991 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 171015}
2019-12-09 16:50:39,575 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 16:50:39,743 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 16:50:40,237 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 16:50:40,243 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 16:50:40,246 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 16:50:40,253 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 16:50:40,254 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 16:50:40,349 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 16:50:40,350 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575910240,"clientToken":"b1dda6f5-4e55-4ad3-9af3-db3920bb0054"}
2019-12-09 16:50:40,350 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 16:50:40,350 - octoprint.plugins.canvas - INFO - Token: b1dda6f5-4e55-4ad3-9af3-db3920bb0054
2019-12-09 16:50:40,351 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 16:50:41,474 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 17:03:23,331 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 17:03:37,956 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 171915}

log 2

2019-12-09 17:18:23,333 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 17:18:23,337 - octoprint.server - INFO - --- Log roll over detected ---------------------------------------------------
2019-12-09 17:18:23,338 - octoprint.server - INFO - OctoPrint 1.4.0rc3.dev9+g124f01ca
2019-12-09 17:18:23,343 - octoprint.plugin.core - INFO - 23 plugin(s) registered with the system:
|  Action Command Prompt Support (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/action_command_prompt
|  Announcement Plugin (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/announcements
|  Anonymous Usage Tracking (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/tracking
|  Application Keys Plugin (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/appkeys
|  Backup & Restore (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/backup
|  Bed Visualizer (0.1.11) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_bedlevelvisualizer
|  CANVAS (2.1.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_canvas
|  Core Wizard (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/corewizard
| *Custom Control Editor (0.2.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_customControl
|  Discovery (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/discovery
|  Error Tracking (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/errortracking
| *Firmware Updater (1.6.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_firmwareupdater
|  Logging (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/logging
|  M73 ETA Override (1.0.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_m73etaoverride
|  MultiCam (0.2.6) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_multicam
|  OctoPod Plugin (0.2.4) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_octopod
| *OctoPrint-Cancelobject (0.3.3) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_cancelobject
|  Palette 2 (2.3.1) = /home/pi/oprint/local/lib/python3.8/site-packages/octoprint_palette2
|  Pi Support Plugin (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/pi_support
|  Plugin Manager (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/pluginmanager
|  Printer Safety Check (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/printer_safety_check
|  Software Update (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/softwareupdate
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python3.8/site-packages/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2019-12-09 17:18:23,361 - octoprint.environment - INFO - Detected environment is Python 3.8.0 under Linux (linux). Details:
|  hardware:
|      cores: 4
|      freq: 1200.0
|      ram: 917016576
|  os:
|      id: linux
|      platform: linux
|  plugins:
|      pi_support:
|          model: Raspberry Pi 3 Model B Rev 1.2
|          octopi_version: 0.15.0
|  python:
|      pip: 19.3.1
|      version: 3.8.0
|      virtualenv: /home/pi/oprint
2019-12-09 17:18:23,362 - octoprint.server - INFO - ------------------------------------------------------------------------------
2019-12-09 17:18:37,599 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'plugins': 'palette2:2.3.1,octopod:0.2.4,multicam:0.2.6,firmwareupdater:1.6.1,customcontrol:0.2.1,cancelobject:0.3.3,m73etaoverride:1.0.1,canvas:2.1.1,bedlevelvisualizer:0.1.11'}
2019-12-09 17:18:38,493 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 172815}
2019-12-09 17:33:23,334 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 17:33:37,988 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 173715}
2019-12-09 17:34:17,027 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 17:34:17,197 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 17:34:17,674 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 17:34:17,679 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 17:34:17,683 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 17:34:17,690 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 17:34:17,691 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 17:34:17,822 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 17:34:17,822 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575912857,"clientToken":"bb8476ab-4cd8-40a2-9948-a4a6510ea246"}
2019-12-09 17:34:17,823 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 17:34:17,823 - octoprint.plugins.canvas - INFO - Token: bb8476ab-4cd8-40a2-9948-a4a6510ea246
2019-12-09 17:34:17,823 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 17:34:18,508 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 17:48:23,336 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 17:48:37,991 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 174615}
2019-12-09 18:00:03,452 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 18:00:03,624 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 18:00:04,154 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 18:00:04,159 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 18:00:04,163 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 18:00:04,171 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 18:00:04,171 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 18:00:04,297 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 18:00:04,298 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575914404,"clientToken":"36e539be-c419-442e-b993-68f57700ad0f"}
2019-12-09 18:00:04,298 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 18:00:04,299 - octoprint.plugins.canvas - INFO - Token: 36e539be-c419-442e-b993-68f57700ad0f
2019-12-09 18:00:04,299 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 18:00:05,493 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 18:03:23,337 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 18:03:37,986 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 175515}
2019-12-09 18:18:23,338 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 18:18:37,957 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 176415}
2019-12-09 18:27:30,849 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 18:27:31,008 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 18:27:31,543 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 18:27:31,548 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 18:27:31,552 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 18:27:31,559 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 18:27:31,559 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 18:27:31,659 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 18:27:31,659 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575916051,"clientToken":"27966e9e-47d2-4926-a36f-fc8e29004478"}
2019-12-09 18:27:31,660 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 18:27:31,660 - octoprint.plugins.canvas - INFO - Token: 27966e9e-47d2-4926-a36f-fc8e29004478
2019-12-09 18:27:31,661 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 18:27:32,802 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 18:33:23,340 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 18:33:37,963 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 177315}
2019-12-09 18:48:23,341 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 18:48:38,007 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 178215}
2019-12-09 19:02:31,676 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 19:02:31,845 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 19:02:32,473 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 19:02:32,478 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 19:02:32,481 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 19:02:32,489 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 19:02:32,490 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 19:02:32,614 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 19:02:32,614 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575918152,"clientToken":"49415f12-f07a-4c0f-840f-aed58b6b8655"}
2019-12-09 19:02:32,615 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 19:02:32,615 - octoprint.plugins.canvas - INFO - Token: 49415f12-f07a-4c0f-840f-aed58b6b8655
2019-12-09 19:02:32,616 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 19:02:33,491 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 19:03:23,342 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 19:03:37,987 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 179115}
2019-12-09 19:14:58,373 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 19:14:58,530 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 19:14:59,263 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 19:14:59,272 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 19:14:59,278 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 19:14:59,291 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 19:14:59,292 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 19:14:59,384 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 19:14:59,385 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575918899,"clientToken":"71312416-4855-41ae-aed2-38204f3d5566"}
2019-12-09 19:14:59,386 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 19:14:59,386 - octoprint.plugins.canvas - INFO - Token: 71312416-4855-41ae-aed2-38204f3d5566
2019-12-09 19:14:59,387 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 19:15:00,268 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 19:18:23,343 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 19:18:37,970 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 180015}
2019-12-09 19:33:23,345 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 19:33:38,013 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 180915}
2019-12-09 19:48:23,346 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 19:48:38,002 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 181815}
2019-12-09 20:03:23,347 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 20:03:38,013 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 182715}
2019-12-09 20:18:23,349 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 20:18:38,002 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 183615}
2019-12-09 20:27:09,010 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 20:27:09,147 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 20:27:09,602 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 20:27:09,607 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 20:27:09,610 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 20:27:09,616 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 20:27:09,617 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 20:27:09,736 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 20:27:09,737 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575923229,"clientToken":"ba0a911f-306b-4384-a5d1-352fa5fd8e19"}
2019-12-09 20:27:09,737 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 20:27:09,737 - octoprint.plugins.canvas - INFO - Token: ba0a911f-306b-4384-a5d1-352fa5fd8e19
2019-12-09 20:27:09,738 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 20:27:11,071 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 20:33:23,350 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 20:33:38,048 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 184515}
2019-12-09 20:48:23,352 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 20:48:38,016 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 185415}
2019-12-09 21:03:23,353 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 21:03:38,061 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 186315}
2019-12-09 21:18:23,355 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 21:18:38,102 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 187215}
2019-12-09 21:33:23,356 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 21:33:38,130 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 188115}
2019-12-09 21:48:23,358 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 21:48:38,155 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 189015}
2019-12-09 22:03:23,359 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 22:03:38,140 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 189915}
2019-12-09 22:18:23,361 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 22:18:38,060 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 190815}
2019-12-09 22:33:23,362 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 22:33:38,058 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 191715}
2019-12-09 22:48:23,364 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 22:48:38,064 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 192615}
2019-12-09 23:03:23,365 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 23:03:38,182 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 193515}
2019-12-09 23:10:10,981 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-09 23:10:11,128 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 23:10:11,567 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-09 23:10:11,573 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-09 23:10:11,578 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-09 23:10:11,584 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-09 23:10:11,585 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-09 23:10:11,681 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-09 23:10:11,682 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575933011,"clientToken":"19a16b44-d1a5-4266-9ae1-e82168a38916"}
2019-12-09 23:10:11,682 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-09 23:10:11,682 - octoprint.plugins.canvas - INFO - Token: 19a16b44-d1a5-4266-9ae1-e82168a38916
2019-12-09 23:10:11,682 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-09 23:10:53,355 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-09 23:18:23,367 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 23:18:38,014 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 194415}
2019-12-09 23:33:23,368 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 23:33:38,070 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 195315}
2019-12-09 23:48:23,369 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-09 23:48:38,067 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 196215}
2019-12-10 00:03:23,371 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 00:03:38,096 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 197115}
2019-12-10 00:18:23,372 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 00:18:38,084 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 198015}
2019-12-10 00:33:23,374 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 00:33:38,090 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 198915}
2019-12-10 00:48:23,375 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 00:48:38,111 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 199815}
2019-12-10 01:03:23,377 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 01:03:38,084 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 200715}
2019-12-10 01:18:23,378 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 01:18:38,109 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 201615}
2019-12-10 01:29:48,283 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-10 01:29:48,456 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-10 01:29:48,679 - tornado.access - WARNING - 409 GET /api/printer (blablabla) 8.25ms
2019-12-10 01:29:48,836 - octoprint.server.util.flask - INFO - Passively logging in user _api from blablabla
2019-12-10 01:29:48,906 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client blablabla
2019-12-10 01:29:49,061 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-10 01:29:49,066 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-10 01:29:49,070 - tornado.application - ERROR - Exception in callback functools.partial(<function wrap.<locals>.null_wrapper at 0x655e32b0>)
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/ioloop.py", line 605, in _run_callback
    ret = callback()
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/stack_context.py", line 277, in null_wrapper
    return fn(*args, **kwargs)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 90, in <lambda>
    self.server.io_loop.add_callback(lambda: self.send_pack(message, binary))
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/vendor/sockjs/tornado/transports/rawwebsocket.py", line 85, in send_pack
    self.write_message(message, binary)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/tornado/websocket.py", line 251, in write_message
    message = tornado.escape.json_encode(message)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/server/util/tornado.py", line 42, in fixed_json_encode
    return json.dumps(value, default=JsonEncoding.encode, allow_nan=False).replace("</", "<\\/")
  File "/usr/local/lib/python3.8/json/__init__.py", line 234, in dumps
    return cls(
  File "/usr/local/lib/python3.8/json/encoder.py", line 199, in encode
    chunks = self.iterencode(o, _one_shot=True)
  File "/usr/local/lib/python3.8/json/encoder.py", line 257, in iterencode
    return _iterencode(o, 0)
  File "/home/pi/oprint/local/lib/python3.8/site-packages/octoprint/util/json/__init__.py", line 33, in encode
    raise TypeError
TypeError
2019-12-10 01:29:49,072 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-10 01:29:49,090 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-10 01:29:49,092 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-10 01:29:49,192 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-10 01:29:49,192 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575941389,"clientToken":"85055802-2526-47ac-8116-c14d917145af"}
2019-12-10 01:29:49,192 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-10 01:29:49,193 - octoprint.plugins.canvas - INFO - Token: 85055802-2526-47ac-8116-c14d917145af
2019-12-10 01:29:49,193 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-10 01:29:50,259 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial port"
2019-12-10 01:29:50,292 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial port" to "Opening serial port"
2019-12-10 01:29:50,294 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2019-12-10 01:29:50,297 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-10 01:29:50,306 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-10 01:29:50,314 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Operational"
2019-12-10 01:29:50,331 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-10 01:29:50,337 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin 2.0.x (GitHub)"
2019-12-10 01:29:50,344 - octoprint.util.comm - INFO - Firmware states that it supports temperature autoreporting
2019-12-10 01:29:50,770 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Operational
2019-12-10 01:29:50,869 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Operational
2019-12-10 01:29:51,756 - octoprint.plugins.tracking - INFO - Sent tracking event printer_connected, payload: {'firmware_name': 'Marlin 2.0.x (GitHub)', 'printer_port': None, 'printer_baudrate': 250000}
2019-12-10 01:30:25,046 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-10 01:33:23,380 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 01:33:38,041 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 202515}
2019-12-10 01:42:36,633 - octoprint.plugins.octopod - DEBUG - Bed reached target temp for 8.005217711130777 minutes
2019-12-10 01:42:36,636 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'bed-warmed' (Ender 3)
2019-12-10 01:42:36,731 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-10 01:42:36,732 - octoprint.plugins.octopod - DEBUG - Sending notification for event 'bed-warmed' (Ender 3)
2019-12-10 01:42:36,817 - octoprint.plugins.octopod - DEBUG - Notification Response code: 204
2019-12-10 01:47:52,734 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-10 01:47:52,902 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-10 01:47:53,468 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-10 01:47:53,481 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-10 01:47:53,500 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-10 01:47:53,544 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-10 01:47:53,544 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-10 01:47:53,593 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-10 01:47:53,593 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575942473,"clientToken":"50ef5c28-123a-438e-b329-18306d8eb837"}
2019-12-10 01:47:53,594 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-10 01:47:53,594 - octoprint.plugins.canvas - INFO - Token: 50ef5c28-123a-438e-b329-18306d8eb837
2019-12-10 01:47:53,594 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-10 01:47:53,769 - octoprint.server.util.flask - INFO - Passively logging in user _api from blablabla
2019-12-10 01:47:54,162 - octoprint.server.util.sockjs - INFO - User _api logged in on the socket from client blablabla
2019-12-10 01:47:58,878 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: E_PETG_Back_Cover_LCR_T4.gcode, owner: _api, user: _api
2019-12-10 01:47:58,902 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2019-12-10 01:47:58,910 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: E_PETG_Back_Cover_LCR_T4.gcode, owner: _api, user: _api
2019-12-10 01:47:58,935 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2019-12-10 01:47:58,944 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2019-12-10 01:47:59,039 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Printing
2019-12-10 01:47:59,122 - octoprint.plugins.octopod - DEBUG - Silent Print Job Notification Response code: 204. State: Printing
2019-12-10 01:47:59,803 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': 'local', 'file': '10f3c5033ad2f7307af78fa213b39a80743b059e'}
2019-12-10 01:48:01,199 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2019-12-10 01:48:07,234 - octoprint.server.util.sockjs - INFO - Client connection closed: blablabla
2019-12-10 01:48:23,381 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2019-12-10 01:48:38,119 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 203415}
2019-12-10 01:49:24,542 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 4s
2019-12-10 01:52:32,738 - octoprint.server.api - INFO - Actively logging in user qweqwe from blablabla
2019-12-10 01:52:34,436 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2019-12-10 01:52:34,792 - octoprint.plugins.canvas - INFO - current version: 2.1.1
2019-12-10 01:52:34,792 - octoprint.plugins.canvas - INFO - remote version: 2.1.1
2019-12-10 01:52:34,793 - octoprint.plugins.canvas - INFO - needs update: False
2019-12-10 01:52:35,156 - octoprint.plugins.palette2 - INFO - current version: 2.3.1
2019-12-10 01:52:35,157 - octoprint.plugins.palette2 - INFO - remote version: 2.3.1
2019-12-10 01:52:35,157 - octoprint.plugins.palette2 - INFO - needs update: False
2019-12-10 01:52:35,455 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2019-12-10 01:53:27,972 - octoprint.server.util.sockjs - INFO - New connection from client: blablabla
2019-12-10 01:53:28,158 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-10 01:53:28,179 - octoprint.server.util.flask - INFO - Passively logging in user qweqwe from blablabla
2019-12-10 01:53:28,215 - octoprint.server.util.sockjs - INFO - User qweqwe logged in on the socket from client blablabla
2019-12-10 01:53:28,657 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-10 01:53:28,662 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-10 01:53:28,663 - octoprint.server.util.sockjs - WARNING - Could not send message to client blablabla:
2019-12-10 01:53:28,668 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-10 01:53:28,685 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-10 01:53:28,687 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-10 01:53:28,740 - octoprint.plugins.palette2 - INFO - Got a command changeAutoVariationCancelPing
2019-12-10 01:53:28,809 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-10 01:53:28,810 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["blablabla"]},"reported":{"userIds":["blablabla"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575239559}]},"reported":{"userIds":[{"timestamp":1564782116}]}},"version":15,"timestamp":1575942808,"clientToken":"8fc7264e-f7c2-4495-8a73-6546bfa340a5"}
2019-12-10 01:53:28,810 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-10 01:53:28,810 - octoprint.plugins.canvas - INFO - Token: 8fc7264e-f7c2-4495-8a73-6546bfa340a5
2019-12-10 01:53:28,811 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-10 01:53:29,091 - octoprint.plugins.palette2 - INFO - ADVANCED: autoVariationCancelPing -> 'False' 'False'
2019-12-10 01:53:29,110 - octoprint.plugins.palette2 - INFO - Got a command changeShowPingOnPrinter
2019-12-10 01:53:29,399 - octoprint.plugins.palette2 - INFO - ADVANCED: showPingOnPrinter -> 'False' 'False'
2019-12-10 01:53:29,415 - octoprint.plugins.palette2 - INFO - Got a command changeFeedRateControl
2019-12-10 01:53:29,715 - octoprint.plugins.palette2 - INFO - ADVANCED: feedRateControl -> 'False' 'False'
2019-12-10 01:53:29,731 - octoprint.plugins.palette2 - INFO - Got a command changeFeedRateSlowPct
2019-12-10 01:53:29,732 - octoprint.plugins.palette2 - INFO - Splice Feed Rate Speed did not change. Do nothing