Skip to content

Instantly share code, notes, and snippets.

@gdombiak
Last active December 14, 2019 04:01
Show Gist options
  • Save gdombiak/476306a0108b2e402ef97744c9d23d19 to your computer and use it in GitHub Desktop.
Save gdombiak/476306a0108b2e402ef97744c9d23d19 to your computer and use it in GitHub Desktop.
/Users/gaston/repo/OctoPrintPython3/venv/bin/python "/Applications/PyCharm CE.app/Contents/plugins/python-ce/helpers/pydev/pydevd.py" --multiproc --qt-support=auto --client 127.0.0.1 --port 54173 --file /Users/gaston/repo/OctoPrintPython3/run serve --debug
pydev debugger: process 13095 is connecting
Connected to pydev debugger (build 193.5233.109)
fatal: Invalid revision range 059819b8bb60d6261156fb71c046ea98c3095bef..HEAD
2019-12-13 20:00:46,364 - octoprint.startup - INFO - ******************************************************************************
2019-12-13 20:00:46,365 - octoprint.startup - INFO - Starting OctoPrint 1.4.0rc3
2019-12-13 20:00:46,366 - octoprint.startup - INFO - ******************************************************************************
2019-12-13 20:00:46,412 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2019-12-13 20:00:47,114 - octoprint.startup - DEBUG - Blacklisted plugin: roomtemp
2019-12-13 20:00:47,115 - octoprint.startup - INFO - Blacklist processing done, adding 1 blacklisted plugin versions: roomtemp (any)
2019-12-13 20:00:47,137 - octoprint.plugin.core - INFO - Loading plugins from /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins, /Users/gaston/Library/Application Support/OctoPrint/plugins and installed plugin packages...
2019-12-13 20:00:47,137 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for action_command_prompt from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/action_command_prompt/__init__.py
2019-12-13 20:00:47,214 - py.warnings - WARNING - /Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/watchdog/utils/bricks.py:175: DeprecationWarning: Using or importing the ABCs from 'collections' instead of from 'collections.abc' is deprecated, and in 3.8 it will stop working
class OrderedSet(collections.MutableSet):
2019-12-13 20:00:47,380 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for discovery from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/discovery/__init__.py
2019-12-13 20:00:47,387 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for announcements from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/announcements/__init__.py
2019-12-13 20:00:47,435 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for corewizard from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/corewizard/__init__.py
2019-12-13 20:00:47,437 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for softwareupdate from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/softwareupdate/__init__.py
2019-12-13 20:00:47,467 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for pluginmanager from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/pluginmanager/__init__.py
2019-12-13 20:00:47,481 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for printer_safety_check from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/printer_safety_check/__init__.py
2019-12-13 20:00:47,484 - octoprint.plugin.core - ERROR - Error loading plugin forcelogin
Traceback (most recent call last):
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/plugin/core.py", line 937, in _import_plugin
module = _load_module(module_name, spec)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/plugin/core.py", line 71, in _load_module
return imp.load_module(name, f, filename, details)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/vendor/imp.py", line 241, in load_module
return load_package(name, filename)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/vendor/imp.py", line 215, in load_package
return _load(spec)
File "<frozen importlib._bootstrap>", line 696, in _load
File "<frozen importlib._bootstrap>", line 677, in _load_unlocked
File "<frozen importlib._bootstrap_external>", line 724, in exec_module
File "<frozen importlib._bootstrap_external>", line 1005, in get_code
File "<frozen importlib._bootstrap_external>", line 458, in _classify_pyc
ImportError: bad magic number in 'octoprint.plugins.forcelogin': b'\x03\xf3\r\n'
2019-12-13 20:00:47,485 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for virtual_printer from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/virtual_printer/__init__.py
2019-12-13 20:00:47,487 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for appkeys from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/appkeys/__init__.py
2019-12-13 20:00:47,506 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for tracking from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/tracking/__init__.py
2019-12-13 20:00:47,509 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for pi_support from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/pi_support/__init__.py
2019-12-13 20:00:47,512 - octoprint.plugin.core - INFO - Plugin Pi Support Plugin did not pass check, not loading.
2019-12-13 20:00:47,513 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for backup from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/backup/__init__.py
2019-12-13 20:00:47,519 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for errortracking from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/errortracking/__init__.py
2019-12-13 20:00:47,521 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for logging from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/logging/__init__.py
2019-12-13 20:00:47,548 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for octopod from AST of /Users/gaston/repo/OctoPrint-OctoPod/octoprint_octopod/__init__.py
2019-12-13 20:00:47,569 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for palette2 from AST of /Users/gaston/repo/palette-2-plugin/octoprint_palette2/__init__.py
2019-12-13 20:00:47,685 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for canvas from AST of /Users/gaston/repo/canvas-plugin/octoprint_canvas/__init__.py
2019-12-13 20:00:47,761 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for multicam from AST of /Users/gaston/repo/OctoPrint_MultiCam/octoprint_multicam/__init__.py
2019-12-13 20:00:47,767 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for m73etaoverride from AST of /Users/gaston/repo/OctoPrint-M73ETAOverride/octoprint_m73etaoverride/__init__.py
2019-12-13 20:00:47,767 - py.warnings - WARNING - /Users/gaston/repo/OctoPrint-M73ETAOverride/octoprint_m73etaoverride/__init__.py:12: DeprecationWarning: invalid escape sequence \w
m = re.search('(?<=R)\w+', cmd)
2019-12-13 20:00:47,769 - py.warnings - WARNING - /Users/gaston/repo/OctoPrint-M73ETAOverride/octoprint_m73etaoverride/__init__.py:12: DeprecationWarning: invalid escape sequence \w
m = re.search('(?<=R)\w+', cmd)
2019-12-13 20:00:47,773 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for cancelobject from AST of /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py
2019-12-13 20:00:47,774 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:59: DeprecationWarning: invalid escape sequence \s
MOVE_RE = re.compile("^G0\s+|^G1\s+")
2019-12-13 20:00:47,774 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:60: DeprecationWarning: invalid escape sequence \s
X_COORD_RE = re.compile(".*\s+X([-]*\d+\.*\d*)")
2019-12-13 20:00:47,774 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:61: DeprecationWarning: invalid escape sequence \s
Y_COORD_RE = re.compile(".*\s+Y([-]*\d+\.*\d*)")
2019-12-13 20:00:47,774 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:62: DeprecationWarning: invalid escape sequence \s
E_COORD_RE = re.compile(".*\s+E([-]*\d+\.*\d*)")
2019-12-13 20:00:47,774 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:63: DeprecationWarning: invalid escape sequence \s
Z_COORD_RE = re.compile(".*\s+Z([-]*\d+\.*\d*)")
2019-12-13 20:00:47,775 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:64: DeprecationWarning: invalid escape sequence \s
SPEED_VAL_RE = re.compile(".*\s+F(\d+\.*\d*)")
2019-12-13 20:00:47,775 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:148: DeprecationWarning: invalid escape sequence \d
self.trackregex = [re.compile("G1 .* E(\d*\.\d+)")]
2019-12-13 20:00:47,780 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:59: DeprecationWarning: invalid escape sequence \s
MOVE_RE = re.compile("^G0\s+|^G1\s+")
2019-12-13 20:00:47,780 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:60: DeprecationWarning: invalid escape sequence \s
X_COORD_RE = re.compile(".*\s+X([-]*\d+\.*\d*)")
2019-12-13 20:00:47,780 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:61: DeprecationWarning: invalid escape sequence \s
Y_COORD_RE = re.compile(".*\s+Y([-]*\d+\.*\d*)")
2019-12-13 20:00:47,780 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:62: DeprecationWarning: invalid escape sequence \s
E_COORD_RE = re.compile(".*\s+E([-]*\d+\.*\d*)")
2019-12-13 20:00:47,780 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:63: DeprecationWarning: invalid escape sequence \s
Z_COORD_RE = re.compile(".*\s+Z([-]*\d+\.*\d*)")
2019-12-13 20:00:47,780 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:64: DeprecationWarning: invalid escape sequence \s
SPEED_VAL_RE = re.compile(".*\s+F(\d+\.*\d*)")
2019-12-13 20:00:47,780 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:148: DeprecationWarning: invalid escape sequence \d
self.trackregex = [re.compile("G1 .* E(\d*\.\d+)")]
2019-12-13 20:00:47,783 - py.warnings - WARNING - /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/__init__.py:13: ExtDeprecationWarning: Importing flask.ext.login is deprecated, use flask_login instead.
from flask.ext.login import current_user
2019-12-13 20:00:47,787 - octoprint.plugin.core - DEBUG - Loaded plugin action_command_prompt: Action Command Prompt Support
2019-12-13 20:00:47,787 - octoprint.plugins.octoprint.plugins.discovery - INFO - pybonjour is not installed, Zeroconf Discovery won't be available
2019-12-13 20:00:47,787 - octoprint.plugin.core - DEBUG - Loaded plugin discovery: Discovery
2019-12-13 20:00:47,787 - octoprint.plugin.core - DEBUG - Loaded plugin announcements: Announcement Plugin
2019-12-13 20:00:47,787 - octoprint.plugin.core - DEBUG - Loaded plugin corewizard: Core Wizard
2019-12-13 20:00:47,787 - octoprint.plugin.core - DEBUG - Loaded plugin softwareupdate: Software Update
2019-12-13 20:00:47,787 - octoprint.plugin.core - DEBUG - Loaded plugin pluginmanager: Plugin Manager
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin printer_safety_check: Printer Safety Check
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin virtual_printer: Virtual Printer
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin appkeys: Application Keys Plugin
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin tracking: Anonymous Usage Tracking
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin backup: Backup & Restore
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin errortracking: Error Tracking
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin logging: Logging
2019-12-13 20:00:47,788 - octoprint.plugin.core - DEBUG - Loaded plugin octopod: OctoPod Plugin (0.2.3)
2019-12-13 20:00:47,789 - octoprint.plugin.core - DEBUG - Loaded plugin palette2: Palette 2 (2.3.1)
2019-12-13 20:00:47,789 - octoprint.plugin.core - DEBUG - Loaded plugin canvas: CANVAS (2.1.1)
2019-12-13 20:00:47,789 - octoprint.plugin.core - DEBUG - Loaded plugin multicam: MultiCam (0.2.6)
2019-12-13 20:00:47,789 - octoprint.plugin.core - DEBUG - Loaded plugin m73etaoverride: M73 ETA Override (1.0.1)
2019-12-13 20:00:47,789 - octoprint.plugin.core - DEBUG - Loaded plugin cancelobject: Cancel Objects (0.3.3)
2019-12-13 20:00:47,790 - octoprint.plugin.core - DEBUG - Enabled plugin action_command_prompt: Action Command Prompt Support
2019-12-13 20:00:47,790 - octoprint.plugin.core - DEBUG - Enabled plugin discovery: Discovery
2019-12-13 20:00:47,790 - octoprint.plugin.core - DEBUG - Enabled plugin announcements: Announcement Plugin
2019-12-13 20:00:47,790 - octoprint.plugin.core - DEBUG - Enabled plugin corewizard: Core Wizard
2019-12-13 20:00:47,791 - octoprint.plugin.core - DEBUG - Enabled plugin softwareupdate: Software Update
2019-12-13 20:00:47,791 - octoprint.plugin.core - DEBUG - Enabled plugin pluginmanager: Plugin Manager
2019-12-13 20:00:47,791 - octoprint.plugin.core - DEBUG - Enabled plugin printer_safety_check: Printer Safety Check
2019-12-13 20:00:47,794 - octoprint.plugin.core - DEBUG - Enabled plugin virtual_printer: Virtual Printer
2019-12-13 20:00:47,794 - octoprint.plugin.core - DEBUG - Enabled plugin appkeys: Application Keys Plugin
2019-12-13 20:00:47,795 - octoprint.plugin.core - DEBUG - Enabled plugin tracking: Anonymous Usage Tracking
2019-12-13 20:00:47,796 - octoprint.plugin.core - DEBUG - Enabled plugin backup: Backup & Restore
2019-12-13 20:00:47,796 - octoprint.plugin.core - DEBUG - Enabled plugin errortracking: Error Tracking
2019-12-13 20:00:47,796 - octoprint.plugin.core - DEBUG - Enabled plugin logging: Logging
2019-12-13 20:00:47,797 - octoprint.plugin.core - DEBUG - Enabled plugin octopod: OctoPod Plugin (0.2.3)
2019-12-13 20:00:47,797 - octoprint.plugin.core - DEBUG - Enabled plugin palette2: Palette 2 (2.3.1)
2019-12-13 20:00:47,797 - octoprint.plugin.core - DEBUG - Enabled plugin canvas: CANVAS (2.1.1)
2019-12-13 20:00:47,798 - octoprint.plugin.core - DEBUG - Enabled plugin multicam: MultiCam (0.2.6)
2019-12-13 20:00:47,798 - octoprint.plugin.core - DEBUG - Enabled plugin m73etaoverride: M73 ETA Override (1.0.1)
2019-12-13 20:00:47,798 - octoprint.plugin.core - DEBUG - Enabled plugin cancelobject: Cancel Objects (0.3.3)
2019-12-13 20:00:47,799 - octoprint.plugin.core - INFO - Found 19 plugin(s) providing 19 mixin implementations, 39 hook handlers
2019-12-13 20:00:47,871 - octoprint.server.heartbeat - INFO - Starting server heartbeat, 900.0s interval
2019-12-13 20:00:47,874 - octoprint.server - DEBUG - Starting intermediary server on http://0.0.0.0:5000 and http://[::]:5000
2019-12-13 20:00:47,876 - octoprint.server - INFO - Intermediary server started
2019-12-13 20:00:47,876 - octoprint.plugin.core - INFO - Loading plugins from /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins, /Users/gaston/Library/Application Support/OctoPrint/plugins and installed plugin packages...
2019-12-13 20:00:47,877 - octoprint.plugin.core - ERROR - Error loading plugin forcelogin
Traceback (most recent call last):
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/plugin/core.py", line 937, in _import_plugin
module = _load_module(module_name, spec)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/plugin/core.py", line 71, in _load_module
return imp.load_module(name, f, filename, details)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/vendor/imp.py", line 241, in load_module
return load_package(name, filename)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/vendor/imp.py", line 215, in load_package
return _load(spec)
File "<frozen importlib._bootstrap>", line 696, in _load
File "<frozen importlib._bootstrap>", line 677, in _load_unlocked
File "<frozen importlib._bootstrap_external>", line 724, in exec_module
File "<frozen importlib._bootstrap_external>", line 1005, in get_code
File "<frozen importlib._bootstrap_external>", line 458, in _classify_pyc
ImportError: bad magic number in 'octoprint.plugins.forcelogin': b'\x03\xf3\r\n'
2019-12-13 20:00:47,878 - octoprint.plugin.core - DEBUG - Parsing plugin metadata for pi_support from AST of /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/pi_support/__init__.py
2019-12-13 20:00:47,881 - octoprint.plugin.core - INFO - Plugin Pi Support Plugin did not pass check, not loading.
2019-12-13 20:00:47,907 - octoprint.plugin.core - INFO - Found 19 plugin(s) providing 19 mixin implementations, 39 hook handlers
2019-12-13 20:00:47,928 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /Users/gaston/Library/Application Support/OctoPrint/uploads...
2019-12-13 20:00:48,066 - octoprint.filemanager.storage - INFO - ... file metadata for /Users/gaston/Library/Application Support/OctoPrint/uploads initialized successfully.
2019-12-13 20:00:48,067 - octoprint.events - DEBUG - Subscribed listener <function Server.run.<locals>.on_settings_update at 0x10b7ea950> for event SettingsUpdated
2019-12-13 20:00:48,067 - octoprint.server - INFO - Added new permission from plugin action_command_prompt: PLUGIN_ACTION_COMMAND_PROMPT_INTERACT (needs: "Need(method='role', value='plugin_action_command_prompt_interact')")
2019-12-13 20:00:48,067 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_READ (needs: "Need(method='role', value='plugin_announcements_read')")
2019-12-13 20:00:48,068 - octoprint.server - INFO - Added new permission from plugin announcements: PLUGIN_ANNOUNCEMENTS_MANAGE (needs: "Need(method='role', value='plugin_announcements_manage'), Need(method='role', value='plugin_announcements_read')")
2019-12-13 20:00:48,068 - octoprint.server - INFO - Added new permission from plugin appkeys: PLUGIN_APPKEYS_ADMIN (needs: "Need(method='role', value='plugin_appkeys_admin')")
2019-12-13 20:00:48,068 - octoprint.server - INFO - Added new permission from plugin backup: PLUGIN_BACKUP_ACCESS (needs: "Need(method='role', value='plugin_backup_access')")
2019-12-13 20:00:48,068 - octoprint.server - INFO - Added new permission from plugin logging: PLUGIN_LOGGING_MANAGE (needs: "Need(method='role', value='plugin_logging_manage')")
2019-12-13 20:00:48,068 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_MANAGE (needs: "Need(method='role', value='plugin_pluginmanager_manage')")
2019-12-13 20:00:48,068 - octoprint.server - INFO - Added new permission from plugin pluginmanager: PLUGIN_PLUGINMANAGER_INSTALL (needs: "Need(method='role', value='plugin_pluginmanager_install'), Need(method='role', value='plugin_pluginmanager_manage')")
2019-12-13 20:00:48,068 - octoprint.server - INFO - Added new permission from plugin printer_safety_check: PLUGIN_PRINTER_SAFETY_CHECK_DISPLAY (needs: "Need(method='role', value='plugin_printer_safety_check_display')")
2019-12-13 20:00:48,069 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_CHECK (needs: "Need(method='role', value='plugin_softwareupdate_check')")
2019-12-13 20:00:48,069 - octoprint.server - INFO - Added new permission from plugin softwareupdate: PLUGIN_SOFTWAREUPDATE_UPDATE (needs: "Need(method='role', value='plugin_softwareupdate_update')")
2019-12-13 20:00:48,076 - octoprint.printer.standard - INFO - Using print time estimator provided by m73etaoverride
2019-12-13 20:00:48,077 - octoprint.events - DEBUG - Subscribed listener <bound method Printer._on_event_MetadataAnalysisFinished of <octoprint.printer.standard.Printer object at 0x10b21e050>> for event MetadataAnalysisFinished
2019-12-13 20:00:48,077 - octoprint.events - DEBUG - Subscribed listener <bound method Printer._on_event_MetadataStatisticsUpdated of <octoprint.printer.standard.Printer object at 0x10b21e050>> for event MetadataStatisticsUpdated
2019-12-13 20:00:48,077 - octoprint.server - DEBUG - Registered event install_plugin of plugin pluginmanager as Events.PLUGIN_PLUGINMANAGER_INSTALL_PLUGIN = "plugin_pluginmanager_install_plugin"
2019-12-13 20:00:48,077 - octoprint.server - DEBUG - Registered event uninstall_plugin of plugin pluginmanager as Events.PLUGIN_PLUGINMANAGER_UNINSTALL_PLUGIN = "plugin_pluginmanager_uninstall_plugin"
2019-12-13 20:00:48,077 - octoprint.server - DEBUG - Registered event enable_plugin of plugin pluginmanager as Events.PLUGIN_PLUGINMANAGER_ENABLE_PLUGIN = "plugin_pluginmanager_enable_plugin"
2019-12-13 20:00:48,077 - octoprint.server - DEBUG - Registered event disable_plugin of plugin pluginmanager as Events.PLUGIN_PLUGINMANAGER_DISABLE_PLUGIN = "plugin_pluginmanager_disable_plugin"
2019-12-13 20:00:48,077 - octoprint.server - DEBUG - Registered event warning of plugin printer_safety_check as Events.PLUGIN_PRINTER_SAFETY_CHECK_WARNING = "plugin_printer_safety_check_warning"
2019-12-13 20:00:48,077 - octoprint.server - DEBUG - Registered event update_succeeded of plugin softwareupdate as Events.PLUGIN_SOFTWAREUPDATE_UPDATE_SUCCEEDED = "plugin_softwareupdate_update_succeeded"
2019-12-13 20:00:48,077 - octoprint.server - DEBUG - Registered event update_failed of plugin softwareupdate as Events.PLUGIN_SOFTWAREUPDATE_UPDATE_FAILED = "plugin_softwareupdate_update_failed"
2019-12-13 20:00:48,079 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin action_command_prompt
2019-12-13 20:00:48,080 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin discovery
2019-12-13 20:00:48,080 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin announcements
2019-12-13 20:00:48,081 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin corewizard
fatal: Invalid revision range 059819b8bb60d6261156fb71c046ea98c3095bef..HEAD
2019-12-13 20:00:48,176 - octoprint.plugins.softwareupdate - INFO - Version cache was created for another version of OctoPrint, not using it
2019-12-13 20:00:48,177 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin softwareupdate
2019-12-13 20:00:48,746 - octoprint.util.pip - INFO - Using "/Users/gaston/repo/OctoPrintPython3/venv/bin/python -m pip" as command to invoke pip
2019-12-13 20:00:49,306 - octoprint.util.pip - INFO - Version of pip is 19.3.1
2019-12-13 20:00:49,307 - octoprint.util.pip - INFO - pip installs to /Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> no
2019-12-13 20:00:49,307 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-12-13 20:00:49,307 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin pluginmanager
2019-12-13 20:00:49,308 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin printer_safety_check
2019-12-13 20:00:49,309 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin virtual_printer
2019-12-13 20:00:49,309 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin appkeys
2019-12-13 20:00:49,310 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin tracking
2019-12-13 20:00:49,311 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin backup
2019-12-13 20:00:49,311 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin errortracking
2019-12-13 20:00:49,312 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin logging
2019-12-13 20:00:49,312 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin octopod
2019-12-13 20:00:49,313 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin palette2
2019-12-13 20:00:49,314 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin canvas
2019-12-13 20:00:49,314 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin multicam
2019-12-13 20:00:49,315 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin m73etaoverride
/Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject/static
2019-12-13 20:00:49,316 - octoprint.plugins.cancelobject - INFO - No beforegcode defined
2019-12-13 20:00:49,316 - octoprint.plugins.cancelobject - INFO - No aftergcode defined
2019-12-13 20:00:49,317 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin cancelobject
2019-12-13 20:00:49,317 - octoprint.plugin.core - INFO - Initialized 19 plugin implementation(s)
2019-12-13 20:00:49,321 - octoprint.plugin.core - INFO - 19 plugin(s) registered with the system:
| Action Command Prompt Support (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/action_command_prompt
| Announcement Plugin (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/announcements
| Anonymous Usage Tracking (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/tracking
| Application Keys Plugin (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/appkeys
| Backup & Restore (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/backup
| Cancel Objects (0.3.3) = /Users/gaston/repo/Octoprint-Cancelobject/octoprint_cancelobject
| CANVAS (2.1.1) = /Users/gaston/repo/canvas-plugin/octoprint_canvas
| Core Wizard (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/corewizard
| Discovery (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/discovery
| Error Tracking (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/errortracking
| Logging (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/logging
| M73 ETA Override (1.0.1) = /Users/gaston/repo/OctoPrint-M73ETAOverride/octoprint_m73etaoverride
| MultiCam (0.2.6) = /Users/gaston/repo/OctoPrint_MultiCam/octoprint_multicam
| OctoPod Plugin (0.2.3) = /Users/gaston/repo/OctoPrint-OctoPod/octoprint_octopod
| Palette 2 (2.3.1) = /Users/gaston/repo/palette-2-plugin/octoprint_palette2
| Plugin Manager (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/pluginmanager
| Printer Safety Check (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/printer_safety_check
| Software Update (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/softwareupdate
| Virtual Printer (bundled) = /Users/gaston/repo/OctoPrintPython3/src/octoprint/plugins/virtual_printer
Prefix legend: ! = disabled, # = blacklisted, * = incompatible
2019-12-13 20:00:49,325 - octoprint.environment - INFO - Detected environment is Python 3.7.4 under Macos (darwin). Details:
| hardware:
| cores: 12
| freq: 3200
| ram: 17179869184
| os:
| id: macos
| platform: darwin
| python:
| pip: 19.3.1
| version: 3.7.4
| virtualenv: /Users/gaston/repo/OctoPrintPython3/venv
2019-12-13 20:00:49,351 - octoprint.server - DEBUG - Deleting /Users/gaston/Library/Application Support/OctoPrint/generated/webassets...
2019-12-13 20:00:49,352 - octoprint.server - DEBUG - Creating /Users/gaston/Library/Application Support/OctoPrint/generated/webassets...
2019-12-13 20:00:49,352 - octoprint.server - INFO - Reset webasset folder /Users/gaston/Library/Application Support/OctoPrint/generated/webassets...
2019-12-13 20:00:49,353 - octoprint.server - DEBUG - Deleting /Users/gaston/Library/Application Support/OctoPrint/generated/.webassets-cache...
2019-12-13 20:00:49,363 - octoprint.server - DEBUG - Creating /Users/gaston/Library/Application Support/OctoPrint/generated/.webassets-cache...
2019-12-13 20:00:49,363 - octoprint.server - INFO - Reset webasset folder /Users/gaston/Library/Application Support/OctoPrint/generated/.webassets-cache...
2019-12-13 20:00:49,369 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event ALERT
2019-12-13 20:00:49,369 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CAPTURE_DONE
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CAPTURE_FAILED
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CAPTURE_START
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CLIENT_CLOSED
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CLIENT_OPENED
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CONNECTED
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CONNECTING
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CONNECTIVITY_CHANGED
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event CONVEYOR
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event COOLING
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event DISCONNECTED
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event DISCONNECTING
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event DWELL
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event EJECT
2019-12-13 20:00:49,370 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event ERROR
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event E_STOP
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event FILE_ADDED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event FILE_DESELECTED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event FILE_REMOVED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event FILE_SELECTED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event FIRMWARE_DATA
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event FOLDER_ADDED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event FOLDER_REMOVED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event HOME
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event METADATA_ANALYSIS_FINISHED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event METADATA_ANALYSIS_STARTED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event METADATA_STATISTICS_UPDATED
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event MOVIE_DONE
2019-12-13 20:00:49,371 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event MOVIE_FAILED
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event MOVIE_RENDERING
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PLUGIN_PLUGINMANAGER_DISABLE_PLUGIN
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PLUGIN_PLUGINMANAGER_ENABLE_PLUGIN
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PLUGIN_PLUGINMANAGER_INSTALL_PLUGIN
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PLUGIN_PLUGINMANAGER_UNINSTALL_PLUGIN
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PLUGIN_PRINTER_SAFETY_CHECK_WARNING
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PLUGIN_SOFTWAREUPDATE_UPDATE_FAILED
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PLUGIN_SOFTWAREUPDATE_UPDATE_SUCCEEDED
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event POSITION_UPDATE
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event POSTROLL_END
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event POSTROLL_START
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event POWER_OFF
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event POWER_ON
2019-12-13 20:00:49,372 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINTER_PROFILE_ADDED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINTER_PROFILE_DELETED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINTER_PROFILE_MODIFIED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINTER_RESET
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINTER_STATE_CHANGED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINT_CANCELLED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINT_CANCELLING
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINT_DONE
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINT_FAILED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINT_PAUSED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINT_RESUMED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event PRINT_STARTED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event REGISTERED_MESSAGE_RECEIVED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SETTINGS_UPDATED
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SHUTDOWN
2019-12-13 20:00:49,373 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SLICING_CANCELLED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SLICING_DONE
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SLICING_FAILED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SLICING_PROFILE_ADDED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SLICING_PROFILE_DELETED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SLICING_PROFILE_MODIFIED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event SLICING_STARTED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event STARTUP
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event TOOL_CHANGE
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event TRANSFER_DONE
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event TRANSFER_FAILED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event TRANSFER_STARTED
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event UPDATED_FILES
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event UPLOAD
2019-12-13 20:00:49,374 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event USER_LOGGED_IN
2019-12-13 20:00:49,375 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event USER_LOGGED_OUT
2019-12-13 20:00:49,377 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event WAITING
2019-12-13 20:00:49,377 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event Z_CHANGE
2019-12-13 20:00:49,379 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event _all_cap_re
2019-12-13 20:00:49,379 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event _first_cap_re
2019-12-13 20:00:49,379 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event _to_identifier
2019-12-13 20:00:49,380 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x10b999150>> for event register_event
2019-12-13 20:00:49,424 - octoprint.server - DEBUG - Registered API of plugin announcements under URL prefix /plugin/announcements
2019-12-13 20:00:49,431 - octoprint.server - DEBUG - Registered API of plugin appkeys under URL prefix /plugin/appkeys
2019-12-13 20:00:49,439 - octoprint.server - DEBUG - Registered API of plugin backup under URL prefix /plugin/backup
2019-12-13 20:00:49,443 - octoprint.server - DEBUG - Registered API of plugin corewizard under URL prefix /plugin/corewizard
2019-12-13 20:00:49,446 - octoprint.server - DEBUG - Registered API of plugin discovery under URL prefix /plugin/discovery
2019-12-13 20:00:49,454 - octoprint.server - DEBUG - Registered API of plugin logging under URL prefix /plugin/logging
2019-12-13 20:00:49,458 - octoprint.server - DEBUG - Registered API of plugin pluginmanager under URL prefix /plugin/pluginmanager
2019-12-13 20:00:49,462 - octoprint.server - DEBUG - Registered API of plugin softwareupdate under URL prefix /plugin/softwareupdate
2019-12-13 20:00:49,465 - octoprint.server - DEBUG - Registered assets of plugin action_command_prompt under URL prefix /plugin/action_command_prompt
2019-12-13 20:00:49,467 - octoprint.server - DEBUG - Registered assets of plugin errortracking under URL prefix /plugin/errortracking
2019-12-13 20:00:49,470 - octoprint.server - DEBUG - Registered assets of plugin printer_safety_check under URL prefix /plugin/printer_safety_check
2019-12-13 20:00:49,472 - octoprint.server - DEBUG - Registered assets of plugin tracking under URL prefix /plugin/tracking
2019-12-13 20:00:49,474 - octoprint.server - DEBUG - Registered assets of plugin cancelobject under URL prefix /plugin/cancelobject
2019-12-13 20:00:49,477 - octoprint.server - DEBUG - Registered assets of plugin canvas under URL prefix /plugin/canvas
2019-12-13 20:00:49,479 - octoprint.server - DEBUG - Registered assets of plugin multicam under URL prefix /plugin/multicam
2019-12-13 20:00:49,481 - octoprint.server - DEBUG - Registered assets of plugin octopod under URL prefix /plugin/octopod
2019-12-13 20:00:49,483 - octoprint.server - DEBUG - Registered assets of plugin palette2 under URL prefix /plugin/palette2
2019-12-13 20:00:49,611 - octoprint.server - DEBUG - Adding additional route /plugin/backup/download/(.*) handled by handler <class 'octoprint.server.util.tornado.LargeResponseHandler'> and with additional arguments {'path': '/Users/gaston/Library/Application Support/OctoPrint/data/backup', 'as_attachment': True, 'path_validation': <function path_validation_factory.<locals>.f at 0x10b8a7a70>, 'access_validation': <function access_validation_factory.<locals>.f at 0x10b8a7b00>}
2019-12-13 20:00:49,619 - octoprint.server - DEBUG - Adding maximum body size of 1073741824B for POST requests to /plugin/backup/restore)
2019-12-13 20:00:49,620 - octoprint.server - DEBUG - Adding maximum body size of 52428800B for POST requests to /plugin/pluginmanager/upload_archive)
2019-12-13 20:00:49,620 - octoprint.server - INFO - Shutting down intermediary server...
2019-12-13 20:00:49,889 - octoprint.server - INFO - Intermediary server shut down
2019-12-13 20:00:49,890 - octoprint.events - INFO - Processing startup event, this is our first event
2019-12-13 20:00:49,890 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2019-12-13 20:00:49,891 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2019-12-13 20:00:49,893 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2019-12-13 20:00:49,893 - octoprint.events.fire - DEBUG - Firing event: Startup (Payload: None)
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on action_command_prompt
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on announcements
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on pluginmanager
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on printer_safety_check
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on softwareupdate
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on tracking
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on cancelobject
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on canvas
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on octopod
2019-12-13 20:00:49,894 - octoprint.plugin - DEBUG - Calling on_event on palette2
2019-12-13 20:00:49,895 - octoprint.events.fire - DEBUG - Firing event: ConnectivityChanged (Payload: {'old': False, 'new': True})
2019-12-13 20:00:49,895 - octoprint.plugin - DEBUG - Calling on_event on action_command_prompt
2019-12-13 20:00:49,895 - octoprint.plugin - DEBUG - Calling on_event on announcements
2019-12-13 20:00:49,897 - octoprint.plugin - DEBUG - Calling on_event on pluginmanager
2019-12-13 20:00:49,897 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2019-12-13 20:00:49,898 - octoprint.plugin - DEBUG - Calling on_event on printer_safety_check
2019-12-13 20:00:49,898 - octoprint.plugin - DEBUG - Calling on_event on softwareupdate
2019-12-13 20:00:49,898 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2019-12-13 20:00:49,901 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-12-13 20:00:49,902 - octoprint.plugin - DEBUG - Calling on_event on tracking
2019-12-13 20:00:49,903 - octoprint.plugin - DEBUG - Calling on_startup on announcements
2019-12-13 20:00:49,909 - octoprint.plugin - DEBUG - Calling on_event on cancelobject
2019-12-13 20:00:49,909 - octoprint.plugin - DEBUG - Calling on_startup on backup
2019-12-13 20:00:49,938 - octoprint.plugin - DEBUG - Calling on_event on canvas
2019-12-13 20:00:49,943 - octoprint.plugin - DEBUG - Calling on_startup on discovery
2019-12-13 20:00:49,976 - octoprint.plugin - DEBUG - Calling on_event on octopod
2019-12-13 20:00:50,011 - octoprint.plugin - DEBUG - Calling on_event on palette2
2019-12-13 20:00:50,018 - octoprint.plugin - DEBUG - Calling on_startup on pluginmanager
2019-12-13 20:00:50,026 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on Gastons-Mac-mini-129.local for SSDP
2019-12-13 20:00:50,077 - octoprint.plugin - DEBUG - Calling on_startup on softwareupdate
2019-12-13 20:00:50,128 - octoprint.plugin - DEBUG - Calling on_startup on tracking
2019-12-13 20:00:50,129 - octoprint.plugin - DEBUG - Calling on_startup on cancelobject
2019-12-13 20:00:50,172 - octoprint.plugin - DEBUG - Calling on_startup on canvas
2019-12-13 20:00:50,179 - octoprint.plugin - DEBUG - Calling on_startup on multicam
2019-12-13 20:00:50,199 - octoprint.plugin - DEBUG - Calling on_startup on octopod
2019-12-13 20:00:50,221 - octoprint.plugin - DEBUG - Calling on_startup on palette2
2019-12-13 20:00:50,263 - octoprint.server - INFO - Listening on http://0.0.0.0:5000 and http://[::]:5000
2019-12-13 20:00:50,263 - octoprint.plugin - DEBUG - Calling on_after_startup on palette2
2019-12-13 20:00:50,263 - octoprint.plugins.palette2 - INFO - Palette 2 (2.3.1) Plugin STARTED
2019-12-13 20:00:50,268 - tornado.access - INFO - 200 GET /sockjs/info?t=1576296050167 (::1) 1.00ms
2019-12-13 20:00:50,268 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-12-13 20:00:50,281 - octoprint.plugins.canvas - INFO - current version: 2.1.1
2019-12-13 20:00:50,281 - octoprint.plugins.canvas - INFO - remote version: 2.1.1
2019-12-13 20:00:50,281 - octoprint.plugins.canvas - INFO - needs update: False
2019-12-13 20:00:50,286 - octoprint.plugins.palette2 - INFO - Omega: Resetting all values - STARTED
2019-12-13 20:00:50,287 - octoprint.plugins.palette2 - INFO - Omega: Resetting all values - FINISHED
2019-12-13 20:00:50,293 - tornado.access - INFO - 101 GET /sockjs/555/yvsbbg13/websocket (::1) 4.67ms
2019-12-13 20:00:50,294 - octoprint.plugins.palette2 - INFO - Resetting read and write threads
2019-12-13 20:00:50,298 - octoprint.server.util.sockjs - INFO - New connection from client: ::1
2019-12-13 20:00:50,354 - octoprint.events.fire - DEBUG - Firing event: ClientOpened (Payload: {'remoteAddress': '::1'})
2019-12-13 20:00:50,354 - octoprint.plugin - DEBUG - Calling on_event on action_command_prompt
2019-12-13 20:00:50,354 - octoprint.plugin - DEBUG - Calling on_event on announcements
2019-12-13 20:00:50,354 - octoprint.plugin - DEBUG - Calling on_event on pluginmanager
2019-12-13 20:00:50,354 - octoprint.plugin - DEBUG - Calling on_event on printer_safety_check
2019-12-13 20:00:50,354 - octoprint.plugin - DEBUG - Calling on_event on softwareupdate
2019-12-13 20:00:50,354 - octoprint.plugin - DEBUG - Calling on_event on tracking
2019-12-13 20:00:50,355 - octoprint.plugin - DEBUG - Calling on_event on cancelobject
2019-12-13 20:00:50,355 - octoprint.plugin - DEBUG - Calling on_event on canvas
2019-12-13 20:00:50,355 - octoprint.plugins.canvas - INFO - 'CanvasPlugin' object has no attribute 'canvas'
2019-12-13 20:00:50,355 - octoprint.plugin - DEBUG - Calling on_event on octopod
2019-12-13 20:00:50,355 - octoprint.plugin - DEBUG - Calling on_event on palette2
2019-12-13 20:00:50,356 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-13 20:00:50,425 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-13 20:00:50,426 - octoprint.plugin - DEBUG - Calling on_after_startup on announcements
2019-12-13 20:00:50,428 - octoprint.server.util.flask - INFO - Passively logging in user gato from ::1
2019-12-13 20:00:50,428 - octoprint.access.users - DEBUG - Logged in user: SessionUser(User(id=gato,name=gato,active=True,user=True,admin=True,permissions=[],groups=[Group("users", "Users", description="All logged in users", permissions=[OctoPrintPermission('Status', 'Allows to gather status information, e.g. job progress, printer state, temperatures, ...', Need(method='role', value='status')), OctoPrintPermission('Connection', 'Allows to connect to and disconnect from a printer', Need(method='role', value='connection')), OctoPrintPermission('Webcam', 'Allows to watch the webcam stream', Need(method='role', value='webcam')), OctoPrintPermission('File List', 'Allows to retrieve a list of all uploaded files, includingtheir metadata (e.g. date, file size, analysis results, ...)', Need(method='role', value='files_list')), OctoPrintPermission('File Upload', 'Allows users to upload new files', Need(method='role', value='files_upload')), OctoPrintPermission('File Download', 'Allows users to download files. The GCODE viewer is affected by this as well.', Need(method='role', value='files_list'), Need(method='role', value='files_download')), OctoPrintPermission('File Delete', 'Allows users to delete files', Need(method='role', value='files_list'), Need(method='role', value='files_delete')), OctoPrintPermission('File Select', 'Allows to select a file for printing', Need(method='role', value='files_list'), Need(method='role', value='files_select')), OctoPrintPermission('Print', 'Allows to start, pause and cancel a print job. Includes the "Select" permission', Need(method='role', value='files_list'), Need(method='role', value='print'), Need(method='role', value='files_select')), OctoPrintPermission('GCODE viewer', 'Allows access to the GCODE viewer. Includes the "File Download"permission.', Need(method='role', value='files_list'), Need(method='role', value='gcodeviewer'), Need(method='role', value='files_download')), OctoPrintPermission('Terminal', 'Allows to watch the terminal tab but not to send commands to the printer from it', Need(method='role', value='monitor_terminal')), OctoPrintPermission('Control', 'Allows to control of the printer by using the temperature controls,the control tab or sending commands through the terminal.', Need(method='role', value='control')), OctoPrintPermission('Slice', 'Allows to slice files', Need(method='role', value='slice')), OctoPrintPermission('Timelapse List', 'Allows to list timelapse videos', Need(method='role', value='timelapse_list')), OctoPrintPermission('Timelapse Download', 'Allows to download timelapse videos', Need(method='role', value='timelapse_download')), OctoPrintPermission('Timelapse Delete', 'Allows to delete timelapse videos and unrendered timelapses', Need(method='role', value='timelapse_delete')), OctoPrintPermission('Timelapse Admin', 'Allows to change the timelapse settings and delete or render unrendered timelapses. Includes the "Timelapse List","Timelapse Delete" and "Timelapse Download" permissions', Need(method='role', value='timelapse_list'), Need(method='role', value='timelapse_download'), Need(method='role', value='timelapse_admin')), PluginOctoPrintPermission('Action Command Prompt Support: Interact with printer prompts', 'Allows to see and interact with printer prompts', Need(method='role', value='plugin_action_command_prompt_interact')), PluginOctoPrintPermission('Printer Safety Check: Display printer safety warnings', 'Allows to see printer safety warnings', Need(method='role', value='plugin_printer_safety_check_display')), PluginOctoPrintPermission('Software Update: Check', 'Allows to check for software updates', Need(method='role', value='plugin_softwareupdate_check'))], default=True, removable=False, changeable=True), Group("admins", "Admins", description="Administrators", permissions=[OctoPrintPermission('Admin', 'Admin is allowed to do everything', Need(method='role', value='admin')), PluginOctoPrintPermission('Announcement Plugin: Read announcements', 'Allows to read announcements', Need(method='role', value='plugin_announcements_read')), PluginOctoPrintPermission('Announcement Plugin: Manage announcement subscriptions', 'Allows to manage announcement subscriptions. Includes "Read announcements" permission', Need(method='role', value='plugin_announcements_manage'), Need(method='role', value='plugin_announcements_read')), PluginOctoPrintPermission('Application Keys Plugin: Admin access', 'Allows administrating all application keys', Need(method='role', value='plugin_appkeys_admin')), PluginOctoPrintPermission('Backup & Restore: Backup access', 'Allows access to backups and restores', Need(method='role', value='plugin_backup_access')), PluginOctoPrintPermission('Logging: Logging management', 'Allows to download and delete log files and list and set log levels.', Need(method='role', value='plugin_logging_manage')), PluginOctoPrintPermission('Plugin Manager: Manage plugins', 'Allows to enable, disable and uninstall installed plugins.', Need(method='role', value='plugin_pluginmanager_manage')), PluginOctoPrintPermission('Plugin Manager: Install new plugins', 'Allows to install new plugins. Includes the "Manage plugins" permission.', Need(method='role', value='plugin_pluginmanager_install'), Need(method='role', value='plugin_pluginmanager_manage')), PluginOctoPrintPermission('Software Update: Update', 'Allows to perform software updates', Need(method='role', value='plugin_softwareupdate_update'))], default=False, removable=False, changeable=False)]),session=E38FC1309F654CF881DF2C921956E5BB,created=5.068926528)
2019-12-13 20:00:50,436 - tornado.access - INFO - 200 POST /api/login (::1) 77.34ms
2019-12-13 20:00:50,436 - tornado.access - INFO - 200 POST /api/login (::1) 77.61ms
2019-12-13 20:00:50,437 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-13 20:00:50,437 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-13 20:00:50,438 - octoprint.events.fire - DEBUG - Firing event: UserLoggedIn (Payload: {'username': 'gato'})
2019-12-13 20:00:50,438 - octoprint.plugin - DEBUG - Calling on_event on action_command_prompt
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on announcements
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on pluginmanager
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on printer_safety_check
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on softwareupdate
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on tracking
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on cancelobject
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on canvas
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on octopod
2019-12-13 20:00:50,439 - octoprint.plugin - DEBUG - Calling on_event on palette2
2019-12-13 20:00:50,440 - octoprint.plugin - DEBUG - Calling on_after_startup on backup
2019-12-13 20:00:50,440 - octoprint.plugin - DEBUG - Calling on_after_startup on discovery
2019-12-13 20:00:50,442 - octoprint.plugin - DEBUG - Calling on_after_startup on pluginmanager
2019-12-13 20:00:50,447 - octoprint.plugin - DEBUG - Calling on_after_startup on softwareupdate
2019-12-13 20:00:50,479 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2019-12-13 20:00:50,484 - tornado.access - INFO - 200 GET /api/access/groups (::1) 37.12ms
2019-12-13 20:00:50,578 - tornado.access - INFO - 200 GET /api/system/commands (::1) 50.50ms
2019-12-13 20:00:50,957 - octoprint.util.pip - INFO - Using "/Users/gaston/repo/OctoPrintPython3/venv/bin/python -m pip" as command to invoke pip
2019-12-13 20:00:50,977 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2019-12-13 20:00:51,029 - tornado.access - INFO - 200 GET /api/printerprofiles (::1) 431.65ms
2019-12-13 20:00:51,070 - octoprint.util.pip - INFO - Using "/Users/gaston/repo/OctoPrintPython3/venv/bin/python -m pip" as command to invoke pip
2019-12-13 20:00:51,119 - tornado.access - INFO - 200 GET /api/settings (::1) 87.87ms
2019-12-13 20:00:51,119 - octoprint.util.pip - INFO - Using "/Users/gaston/repo/OctoPrintPython3/venv/bin/python -m pip" as command to invoke pip
2019-12-13 20:00:51,125 - octoprint.util.pip - INFO - Using "/Users/gaston/repo/OctoPrintPython3/venv/bin/python -m pip" as command to invoke pip
2019-12-13 20:00:51,166 - octoprint.util.pip - INFO - Using "/Users/gaston/repo/OctoPrintPython3/venv/bin/python -m pip" as command to invoke pip
2019-12-13 20:00:51,174 - tornado.access - INFO - 200 GET /api/connection (::1) 53.75ms
2019-12-13 20:00:51,178 - tornado.access - INFO - 200 GET /api/printer/command/custom (::1) 3.11ms
2019-12-13 20:00:51,370 - tornado.access - INFO - 200 GET /plugin/announcements/channels (::1) 190.32ms
2019-12-13 20:00:51,375 - tornado.access - INFO - 200 GET /api/slicing (::1) 3.86ms
2019-12-13 20:00:51,396 - tornado.access - INFO - 200 GET /api/files?recursive=true (::1) 19.68ms
2019-12-13 20:00:51,407 - tornado.access - INFO - 304 GET /api/plugin/pluginmanager (::1) 9.82ms
2019-12-13 20:00:51,432 - octoprint.util.pip - INFO - Using "/Users/gaston/repo/OctoPrintPython3/venv/bin/python -m pip" as command to invoke pip
2019-12-13 20:00:53,121 - octoprint.util.pip - ERROR - Could not detect desired output from testballoon install, got this instead: {}
2019-12-13 20:00:53,121 - octoprint.util.pip - ERROR - Cannot use this pip install, something's wrong with the python environment. Check the lines before.
2019-12-13 20:00:53,123 - octoprint.util.pip - INFO - pip installs to /Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> no
2019-12-13 20:00:53,124 - octoprint.util.pip - INFO - ==> pip ok -> yes
2019-12-13 20:00:53,580 - octoprint.plugins.palette2 - INFO - current version: 2.3.1
2019-12-13 20:00:53,580 - octoprint.plugins.palette2 - INFO - remote version: 2.3.1
2019-12-13 20:00:53,580 - octoprint.plugins.palette2 - INFO - needs update: False
2019-12-13 20:00:55,214 - octoprint.util.pip - ERROR - Could not detect desired output from testballoon install, got this instead: {}
2019-12-13 20:00:55,214 - octoprint.util.pip - ERROR - Cannot use this pip install, something's wrong with the python environment. Check the lines before.
2019-12-13 20:00:57,292 - octoprint.util.pip - ERROR - Could not detect desired output from testballoon install, got this instead: {}
2019-12-13 20:00:57,292 - octoprint.util.pip - ERROR - Cannot use this pip install, something's wrong with the python environment. Check the lines before.
2019-12-13 20:00:59,390 - octoprint.util.pip - ERROR - Could not detect desired output from testballoon install, got this instead: {}
2019-12-13 20:00:59,390 - octoprint.util.pip - ERROR - Cannot use this pip install, something's wrong with the python environment. Check the lines before.
2019-12-13 20:01:01,165 - octoprint.util.pip - ERROR - Could not detect desired output from testballoon install, got this instead: {}
2019-12-13 20:01:01,165 - octoprint.util.pip - ERROR - Cannot use this pip install, something's wrong with the python environment. Check the lines before.
fatal: Invalid revision range 059819b8bb60d6261156fb71c046ea98c3095bef..HEAD
2019-12-13 20:01:01,236 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2019-12-13 20:01:01,237 - octoprint.plugin - DEBUG - Calling on_after_startup on tracking
2019-12-13 20:01:01,247 - tornado.access - INFO - 200 GET /plugin/softwareupdate/check (::1) 9839.18ms
2019-12-13 20:01:01,249 - octoprint.plugin - DEBUG - Calling on_after_startup on cancelobject
2019-12-13 20:01:01,251 - octoprint.plugin - DEBUG - Calling on_after_startup on canvas
2019-12-13 20:01:01,251 - octoprint.plugins.canvas - INFO - CANVAS (2.1.1) Plugin STARTED
2019-12-13 20:01:01,260 - tornado.access - INFO - 200 GET /api/timelapse?unrendered=true (::1) 8.26ms
2019-12-13 20:01:01,266 - tornado.access - INFO - 200 GET /api/access/users (::1) 4.30ms
2019-12-13 20:01:01,270 - tornado.access - INFO - 200 GET /api/plugin/printer_safety_check (::1) 3.39ms
2019-12-13 20:01:01,275 - tornado.access - INFO - 200 GET /api/timelapse?unrendered=true (::1) 3.82ms
2019-12-13 20:01:01,279 - octoprint.server.util.sockjs - INFO - User gato logged in on the socket from client ::1
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Startup
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Shutdown
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event ConnectivityChanged
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Connecting
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Connected
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Disconnecting
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Disconnected
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrinterStateChanged
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrinterReset
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event ClientOpened
2019-12-13 20:01:01,280 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event ClientClosed
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event UserLoggedIn
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event UserLoggedOut
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Upload
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event FileSelected
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event FileDeselected
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event UpdatedFiles
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event MetadataAnalysisStarted
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event MetadataAnalysisFinished
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event MetadataStatisticsUpdated
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event FileAdded
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event FileRemoved
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event FolderAdded
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event FolderRemoved
2019-12-13 20:01:01,281 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event TransferStarted
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event TransferDone
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event TransferFailed
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrintStarted
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrintDone
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrintFailed
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrintCancelling
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrintCancelled
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrintPaused
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrintResumed
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Error
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PowerOn
2019-12-13 20:01:01,282 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PowerOff
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Home
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event ZChange
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Waiting
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Dwelling
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Cooling
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Alert
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Conveyor
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event Eject
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event EStop
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PositionUpdate
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event FirmwareData
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event ToolChange
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event RegisteredMessageReceived
2019-12-13 20:01:01,283 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event CaptureStart
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event CaptureDone
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event CaptureFailed
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PostRollStart
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PostRollEnd
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event MovieRendering
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event MovieDone
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event MovieFailed
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SlicingStarted
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SlicingDone
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SlicingFailed
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SlicingCancelled
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SlicingProfileAdded
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SlicingProfileModified
2019-12-13 20:01:01,284 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SlicingProfileDeleted
2019-12-13 20:01:01,285 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrinterProfileAdded
2019-12-13 20:01:01,285 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrinterProfileModified
2019-12-13 20:01:01,285 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event PrinterProfileDeleted
2019-12-13 20:01:01,285 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event SettingsUpdated
2019-12-13 20:01:01,285 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event plugin_pluginmanager_install_plugin
2019-12-13 20:01:01,289 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event plugin_pluginmanager_uninstall_plugin
2019-12-13 20:01:01,289 - octoprint.plugins.canvas - INFO - Loading HUB data
2019-12-13 20:01:01,289 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event plugin_pluginmanager_enable_plugin
2019-12-13 20:01:01,290 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event plugin_pluginmanager_disable_plugin
2019-12-13 20:01:01,290 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event plugin_printer_safety_check_warning
2019-12-13 20:01:01,292 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event plugin_softwareupdate_update_succeeded
2019-12-13 20:01:01,292 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10b8c4c10>> for event plugin_softwareupdate_update_failed
2019-12-13 20:01:01,301 - octoprint.plugins.canvas - INFO - ROOT-CA ALREADY THERE
2019-12-13 20:01:01,301 - octoprint.plugins.canvas - INFO - HUB already registered
2019-12-13 20:01:01,301 - octoprint.plugins.canvas - INFO - HUB version is 2 --- NO UPGRADE NEEDED
2019-12-13 20:01:01,707 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-13 20:01:01,708 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-13 20:01:01,708 - octoprint.server.util.sockjs - ERROR - Could not send message to client ::1
Traceback (most recent call last):
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/server/util/sockjs.py", line 420, in _do_emit
self.send({type: payload})
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/vendor/sockjs/tornado/conn.py", line 51, in send
self.session.send_message(message, binary=binary)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/server/util/sockjs.py", line 67, in send_message
default=JsonEncoding.encode),
File "/usr/local/Cellar/python/3.7.4/Frameworks/Python.framework/Versions/3.7/lib/python3.7/json/__init__.py", line 238, in dumps
**kw).encode(obj)
File "/usr/local/Cellar/python/3.7.4/Frameworks/Python.framework/Versions/3.7/lib/python3.7/json/encoder.py", line 199, in encode
chunks = self.iterencode(o, _one_shot=True)
File "/usr/local/Cellar/python/3.7.4/Frameworks/Python.framework/Versions/3.7/lib/python3.7/json/encoder.py", line 257, in iterencode
return _iterencode(o, 0)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/util/json/__init__.py", line 33, in encode
raise TypeError
TypeError
2019-12-13 20:01:01,709 - octoprint.plugins.canvas - INFO - Making Shadow Client & Device
2019-12-13 20:01:01,710 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - MqttCore initialized
2019-12-13 20:01:01,710 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Client id: 72722b8ff11409b340a0fdb1fe0a3ee6
2019-12-13 20:01:01,711 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Protocol version: MQTTv3.1.1
2019-12-13 20:01:01,711 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Authentication type: TLSv1.2 certificate based Mutual Auth.
2019-12-13 20:01:01,711 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Configuring offline requests queueing: max queue size: 0
2019-12-13 20:01:01,711 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Configuring offline requests queue draining interval: 0.100000 sec
2019-12-13 20:01:01,711 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Configuring endpoint...
2019-12-13 20:01:01,711 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Configuring certificates...
2019-12-13 20:01:01,711 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Configuring reconnect back off timing...
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Base quiet time: 1.000000 sec
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Max quiet time: 32.000000 sec
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Stable connection time: 20.000000 sec
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Configuring connect/disconnect time out: 15.000000 sec
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Configuring MQTT operation time out: 5.000000 sec
2019-12-13 20:01:01,712 - octoprint.plugins.canvas - INFO - Shadow Client initialized
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Performing sync connect...
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Performing async connect...
2019-12-13 20:01:01,712 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Keep-alive: 30.000000 sec
2019-12-13 20:01:01,981 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 13}
2019-12-13 20:01:02,160 - octoprint.plugins.canvas - INFO - Shadow Client is online
2019-12-13 20:01:02,160 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-13 20:01:02,162 - octoprint.plugins.canvas - INFO - Device Shadow created
2019-12-13 20:01:02,162 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Performing sync subscribe...
2019-12-13 20:01:02,701 - octoprint.plugins.tracking - INFO - Sent tracking event pong, payload: {'plugins': 'octopod:0.2.3,palette2:2.3.1,canvas:2.1.1,multicam:0.2.6,m73etaoverride:1.0.1,cancelobject:0.3.3'}
2019-12-13 20:01:03,409 - octoprint.plugins.tracking - INFO - Sent tracking event startup, payload: {'version': '1.4.0rc3', 'os': 'macos', 'python': '3.7.4', 'pip': '19.3.1', 'cores': 12, 'freq': 3200, 'ram': 17179869184}
2019-12-13 20:01:04,270 - AWSIoTPythonSDK.core.shadow.deviceShadow - INFO - Subscribed to delta topic for deviceShadow: canvas-hub-72722b8ff11409b340a0fdb1fe0a3ee6
2019-12-13 20:01:04,270 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Performing sync subscribe...
2019-12-13 20:01:04,378 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Performing sync subscribe...
2019-12-13 20:01:04,672 - tornado.access - INFO - 200 GET /plugin/discovery/discovery.xml (192.168.252.9) 16.44ms
2019-12-13 20:01:04,675 - tornado.access - INFO - 200 GET /plugin/discovery/discovery.xml (192.168.252.9) 2.96ms
2019-12-13 20:01:06,489 - AWSIoTPythonSDK.core.shadow.deviceShadow - INFO - Subscribed to get accepted/rejected topics for deviceShadow: canvas-hub-72722b8ff11409b340a0fdb1fe0a3ee6
2019-12-13 20:01:06,489 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Performing sync publish...
2019-12-13 20:01:06,491 - octoprint.plugin - DEBUG - Calling on_after_startup on multicam
2019-12-13 20:01:06,492 - octoprint.plugins.multicam - INFO - MultiCam Loaded! (more: [{'URL': 'http://192.168.252.16:8081', 'isButtonEnabled': False, 'name': 'Default'}, {'URL': 'http://192.168.252.6:811/', 'isButtonEnabled': True, 'name': 'Webcam 1'}])
2019-12-13 20:01:06,492 - octoprint.plugin - DEBUG - Calling on_after_startup on octopod
2019-12-13 20:01:06,492 - octoprint.plugins.octopod - INFO - OctoPod loaded!
2019-12-13 20:01:06,492 - octoprint.plugins.octopod - DEBUG - Starting Timer...
2019-12-13 20:01:06,557 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'base_url': 'http://localhost:5000/', 'path': '/', 'query_string': 'l10n=en'}
2019-12-13 20:01:06,592 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-13 20:01:06,592 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["b1bc32bfd6cf85b4dbf9394b3d824d08"]},"reported":{"userIds":["b1bc32bfd6cf85b4dbf9394b3d824d08"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575237825}]},"reported":{"userIds":[{"timestamp":1564722857}]}},"version":15,"timestamp":1576296066,"clientToken":"d5ed0a58-8391-411c-a98e-2a8c172989b0"}
2019-12-13 20:01:06,592 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-13 20:01:06,592 - octoprint.plugins.canvas - INFO - Token: d5ed0a58-8391-411c-a98e-2a8c172989b0
2019-12-13 20:01:06,592 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-13 20:01:06,659 - octoprint.server.views - DEBUG - Forcing rendering of view _default
2019-12-13 20:01:06,709 - octoprint.server.util.flask - DEBUG - No cache entry or refreshing cache for / (key: ui:_default:http://localhost:5000/:en), calling wrapped function
2019-12-13 20:01:09,080 - octoprint.server.util.flask - DEBUG - Needed 2.37s to render / (key: ui:_default:http://localhost:5000/:en)
2019-12-13 20:01:09,080 - octoprint.server.preemptive_cache - INFO - ... done in 2.52s
2019-12-13 20:01:13,600 - octoprint.server.util.flask.PreemptiveCache - DEBUG - Updating timestamp and counter for / and {'path': '/', 'base_url': 'http://localhost:5000/', 'query_string': 'l10n=en'}
2019-12-13 20:01:13,618 - octoprint.server.util.flask - DEBUG - No cache entry or refreshing cache for / (key: ui:_default:http://localhost:5000/:en), calling wrapped function
2019-12-13 20:01:14,543 - octoprint.server.util.flask - DEBUG - Needed 0.92s to render / (key: ui:_default:http://localhost:5000/:en)
2019-12-13 20:01:14,545 - tornado.access - INFO - 200 GET / (::1) 1032.42ms
2019-12-13 20:01:14,554 - octoprint.server.util.sockjs - INFO - Client connection closed: ::1
2019-12-13 20:01:14,555 - octoprint.events.fire - DEBUG - Firing event: ClientClosed (Payload: {'remoteAddress': '::1'})
2019-12-13 20:01:14,555 - octoprint.plugin - DEBUG - Calling on_event on action_command_prompt
2019-12-13 20:01:14,555 - octoprint.plugin - DEBUG - Calling on_event on announcements
2019-12-13 20:01:14,555 - octoprint.plugin - DEBUG - Calling on_event on pluginmanager
2019-12-13 20:01:14,555 - octoprint.plugin - DEBUG - Calling on_event on printer_safety_check
2019-12-13 20:01:14,555 - octoprint.plugin - DEBUG - Calling on_event on softwareupdate
2019-12-13 20:01:14,555 - octoprint.plugin - DEBUG - Calling on_event on tracking
2019-12-13 20:01:14,555 - octoprint.plugin - DEBUG - Calling on_event on cancelobject
2019-12-13 20:01:14,556 - octoprint.plugin - DEBUG - Calling on_event on canvas
2019-12-13 20:01:14,556 - octoprint.plugin - DEBUG - Calling on_event on octopod
2019-12-13 20:01:14,556 - octoprint.plugin - DEBUG - Calling on_event on palette2
2019-12-13 20:01:14,578 - tornado.access - INFO - 200 GET /static/webassets/packed_libs.css?ec88168c (::1) 3.06ms
2019-12-13 20:01:14,580 - tornado.access - INFO - 200 GET /static/webassets/packed_plugins.css?e319a9ea (::1) 1.03ms
2019-12-13 20:01:14,585 - tornado.access - INFO - 200 GET /static/js/lib/less.min.js (::1) 3.71ms
2019-12-13 20:01:14,587 - tornado.access - INFO - 200 GET /static/webassets/packed_core.css?bb25a3af (::1) 0.96ms
2019-12-13 20:01:14,613 - tornado.access - INFO - 200 GET /static/webassets/packed_core.js?bfdd4fa0 (::1) 1.89ms
2019-12-13 20:01:14,622 - tornado.access - INFO - 200 GET /i18n/en/messages.js (::1) 7.53ms
2019-12-13 20:01:14,627 - tornado.access - INFO - 200 GET /static/webassets/packed_plugins.js?3f85b4f7 (::1) 1.22ms
2019-12-13 20:01:14,630 - tornado.access - INFO - 200 GET /static/webassets/packed_client.js?92e73cbf (::1) 1.98ms
2019-12-13 20:01:14,631 - tornado.access - INFO - 200 GET /static/webassets/packed_libs.js?3339d949 (::1) 22.13ms
2019-12-13 20:01:14,646 - tornado.access - INFO - 200 GET /static/img/tentacle-20x20.png (::1) 3.71ms
2019-12-13 20:01:14,651 - tornado.access - INFO - 200 GET /static/vendor/font-awesome-4.7.0/fonts/fontawesome-webfont.woff2?v=4.7.0 (::1) 3.63ms
2019-12-13 20:01:14,813 - tornado.access - INFO - 200 GET /static/img/mask-theme.svg (::1) 4.95ms
2019-12-13 20:01:14,816 - tornado.access - INFO - 200 GET /sockjs/info?t=1576296074812 (::1) 0.59ms
2019-12-13 20:01:14,833 - tornado.access - INFO - 101 GET /sockjs/776/y5qdbwzh/websocket (::1) 0.74ms
2019-12-13 20:01:14,834 - octoprint.server.util.sockjs - INFO - New connection from client: ::1
2019-12-13 20:01:14,857 - octoprint.events.fire - DEBUG - Firing event: ClientOpened (Payload: {'remoteAddress': '::1'})
2019-12-13 20:01:14,858 - octoprint.plugin - DEBUG - Calling on_event on action_command_prompt
2019-12-13 20:01:14,858 - octoprint.plugin - DEBUG - Calling on_event on announcements
2019-12-13 20:01:14,858 - octoprint.plugin - DEBUG - Calling on_event on pluginmanager
2019-12-13 20:01:14,858 - octoprint.plugin - DEBUG - Calling on_event on printer_safety_check
2019-12-13 20:01:14,858 - octoprint.plugin - DEBUG - Calling on_event on softwareupdate
2019-12-13 20:01:14,859 - octoprint.plugin - DEBUG - Calling on_event on tracking
2019-12-13 20:01:14,859 - octoprint.plugin - DEBUG - Calling on_event on cancelobject
2019-12-13 20:01:14,860 - octoprint.plugin - DEBUG - Calling on_event on canvas
2019-12-13 20:01:14,860 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-13 20:01:14,868 - octoprint.server.util.flask - INFO - Passively logging in user gato from ::1
2019-12-13 20:01:14,869 - octoprint.access.users - DEBUG - Logged in user: SessionUser(User(id=gato,name=gato,active=True,user=True,admin=True,permissions=[],groups=[Group("users", "Users", description="All logged in users", permissions=[OctoPrintPermission('Status', 'Allows to gather status information, e.g. job progress, printer state, temperatures, ...', Need(method='role', value='status')), OctoPrintPermission('Connection', 'Allows to connect to and disconnect from a printer', Need(method='role', value='connection')), OctoPrintPermission('Webcam', 'Allows to watch the webcam stream', Need(method='role', value='webcam')), OctoPrintPermission('File List', 'Allows to retrieve a list of all uploaded files, includingtheir metadata (e.g. date, file size, analysis results, ...)', Need(method='role', value='files_list')), OctoPrintPermission('File Upload', 'Allows users to upload new files', Need(method='role', value='files_upload')), OctoPrintPermission('File Download', 'Allows users to download files. The GCODE viewer is affected by this as well.', Need(method='role', value='files_list'), Need(method='role', value='files_download')), OctoPrintPermission('File Delete', 'Allows users to delete files', Need(method='role', value='files_list'), Need(method='role', value='files_delete')), OctoPrintPermission('File Select', 'Allows to select a file for printing', Need(method='role', value='files_list'), Need(method='role', value='files_select')), OctoPrintPermission('Print', 'Allows to start, pause and cancel a print job. Includes the "Select" permission', Need(method='role', value='files_list'), Need(method='role', value='print'), Need(method='role', value='files_select')), OctoPrintPermission('GCODE viewer', 'Allows access to the GCODE viewer. Includes the "File Download"permission.', Need(method='role', value='files_list'), Need(method='role', value='gcodeviewer'), Need(method='role', value='files_download')), OctoPrintPermission('Terminal', 'Allows to watch the terminal tab but not to send commands to the printer from it', Need(method='role', value='monitor_terminal')), OctoPrintPermission('Control', 'Allows to control of the printer by using the temperature controls,the control tab or sending commands through the terminal.', Need(method='role', value='control')), OctoPrintPermission('Slice', 'Allows to slice files', Need(method='role', value='slice')), OctoPrintPermission('Timelapse List', 'Allows to list timelapse videos', Need(method='role', value='timelapse_list')), OctoPrintPermission('Timelapse Download', 'Allows to download timelapse videos', Need(method='role', value='timelapse_download')), OctoPrintPermission('Timelapse Delete', 'Allows to delete timelapse videos and unrendered timelapses', Need(method='role', value='timelapse_delete')), OctoPrintPermission('Timelapse Admin', 'Allows to change the timelapse settings and delete or render unrendered timelapses. Includes the "Timelapse List","Timelapse Delete" and "Timelapse Download" permissions', Need(method='role', value='timelapse_list'), Need(method='role', value='timelapse_download'), Need(method='role', value='timelapse_admin')), PluginOctoPrintPermission('Action Command Prompt Support: Interact with printer prompts', 'Allows to see and interact with printer prompts', Need(method='role', value='plugin_action_command_prompt_interact')), PluginOctoPrintPermission('Printer Safety Check: Display printer safety warnings', 'Allows to see printer safety warnings', Need(method='role', value='plugin_printer_safety_check_display')), PluginOctoPrintPermission('Software Update: Check', 'Allows to check for software updates', Need(method='role', value='plugin_softwareupdate_check'))], default=True, removable=False, changeable=True), Group("admins", "Admins", description="Administrators", permissions=[OctoPrintPermission('Admin', 'Admin is allowed to do everything', Need(method='role', value='admin')), PluginOctoPrintPermission('Announcement Plugin: Read announcements', 'Allows to read announcements', Need(method='role', value='plugin_announcements_read')), PluginOctoPrintPermission('Announcement Plugin: Manage announcement subscriptions', 'Allows to manage announcement subscriptions. Includes "Read announcements" permission', Need(method='role', value='plugin_announcements_manage'), Need(method='role', value='plugin_announcements_read')), PluginOctoPrintPermission('Application Keys Plugin: Admin access', 'Allows administrating all application keys', Need(method='role', value='plugin_appkeys_admin')), PluginOctoPrintPermission('Backup & Restore: Backup access', 'Allows access to backups and restores', Need(method='role', value='plugin_backup_access')), PluginOctoPrintPermission('Logging: Logging management', 'Allows to download and delete log files and list and set log levels.', Need(method='role', value='plugin_logging_manage')), PluginOctoPrintPermission('Plugin Manager: Manage plugins', 'Allows to enable, disable and uninstall installed plugins.', Need(method='role', value='plugin_pluginmanager_manage')), PluginOctoPrintPermission('Plugin Manager: Install new plugins', 'Allows to install new plugins. Includes the "Manage plugins" permission.', Need(method='role', value='plugin_pluginmanager_install'), Need(method='role', value='plugin_pluginmanager_manage')), PluginOctoPrintPermission('Software Update: Update', 'Allows to perform software updates', Need(method='role', value='plugin_softwareupdate_update'))], default=False, removable=False, changeable=False)]),session=E38FC1309F654CF881DF2C921956E5BB,created=5.068926528)
2019-12-13 20:01:14,872 - tornado.access - INFO - 200 POST /api/login (::1) 6.20ms
2019-12-13 20:01:14,872 - tornado.access - INFO - 200 POST /api/login (::1) 6.38ms
2019-12-13 20:01:14,946 - tornado.access - INFO - 200 GET /api/settings (::1) 64.48ms
2019-12-13 20:01:15,144 - tornado.access - INFO - 204 GET /api/plugin/cancelobject (::1) 3.33ms
2019-12-13 20:01:15,179 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-13 20:01:15,179 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-13 20:01:15,182 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-13 20:01:15,182 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-13 20:01:15,184 - tornado.access - INFO - 200 POST /api/plugin/palette2 (::1) 7.72ms
2019-12-13 20:01:15,184 - tornado.access - INFO - 200 POST /api/plugin/palette2 (::1) 7.91ms
2019-12-13 20:01:15,188 - octoprint.plugins.palette2 - INFO - Got a command uiUpdate
2019-12-13 20:01:15,188 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-13 20:01:15,191 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-13 20:01:15,191 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-13 20:01:15,193 - tornado.access - INFO - 200 POST /api/plugin/palette2 (::1) 7.83ms
2019-12-13 20:01:15,194 - tornado.access - INFO - 200 POST /api/plugin/palette2 (::1) 7.99ms
2019-12-13 20:01:15,207 - tornado.access - INFO - 200 GET /api/plugin/appkeys (::1) 4.35ms
2019-12-13 20:01:15,214 - tornado.access - INFO - 200 GET /api/access/groups (::1) 5.76ms
2019-12-13 20:01:15,220 - tornado.access - INFO - 200 GET /api/system/commands (::1) 4.33ms
2019-12-13 20:01:15,277 - octoprint.plugins.canvas - INFO - Got list of registered users.
2019-12-13 20:01:15,279 - octoprint.plugins.canvas - INFO - Sending UIUpdate from Canvas
2019-12-13 20:01:15,284 - AWSIoTPythonSDK.core.protocol.mqtt_core - INFO - Performing sync publish...
2019-12-13 20:01:15,285 - octoprint.plugin - DEBUG - Calling on_event on octopod
2019-12-13 20:01:15,285 - octoprint.plugin - DEBUG - Calling on_event on palette2
2019-12-13 20:01:15,286 - octoprint.plugins.palette2 - INFO - Updating all UI variables
2019-12-13 20:01:15,288 - octoprint.plugins.palette2 - INFO - Paths: []
2019-12-13 20:01:15,288 - octoprint.plugins.palette2 - INFO - ADVANCED UPDATE UI
2019-12-13 20:01:15,289 - octoprint.events.fire - DEBUG - Firing event: UserLoggedIn (Payload: {'username': 'gato'})
2019-12-13 20:01:15,289 - octoprint.plugin - DEBUG - Calling on_event on action_command_prompt
2019-12-13 20:01:15,289 - octoprint.plugin - DEBUG - Calling on_event on announcements
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on pluginmanager
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on printer_safety_check
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on softwareupdate
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on tracking
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on cancelobject
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on canvas
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on octopod
2019-12-13 20:01:15,290 - octoprint.plugin - DEBUG - Calling on_event on palette2
2019-12-13 20:01:15,313 - tornado.access - INFO - 200 GET /api/printerprofiles (::1) 92.78ms
2019-12-13 20:01:15,319 - tornado.access - INFO - 200 GET /api/timelapse?unrendered=true (::1) 4.13ms
2019-12-13 20:01:15,396 - octoprint.plugins.canvas - INFO - GOT SHADOW OBJECT
2019-12-13 20:01:15,396 - octoprint.plugins.canvas - INFO - Payload: {"state":{"desired":{"userIds":["b1bc32bfd6cf85b4dbf9394b3d824d08"]},"reported":{"userIds":["b1bc32bfd6cf85b4dbf9394b3d824d08"]}},"metadata":{"desired":{"userIds":[{"timestamp":1575237825}]},"reported":{"userIds":[{"timestamp":1564722857}]}},"version":15,"timestamp":1576296075,"clientToken":"ab93f681-c33a-4568-b725-a112d97005ae"}
2019-12-13 20:01:15,396 - octoprint.plugins.canvas - INFO - Status: accepted
2019-12-13 20:01:15,396 - octoprint.plugins.canvas - INFO - Token: ab93f681-c33a-4568-b725-a112d97005ae
2019-12-13 20:01:15,396 - octoprint.plugins.canvas - INFO - No delta found in object. No action needed.
2019-12-13 20:01:15,397 - tornado.access - INFO - 304 GET /plugin/announcements/channels (::1) 76.82ms
2019-12-13 20:01:15,449 - tornado.access - INFO - 200 GET /api/connection (::1) 48.72ms
2019-12-13 20:01:15,453 - tornado.access - INFO - 200 GET /api/printer/command/custom (::1) 2.93ms
2019-12-13 20:01:15,464 - tornado.access - INFO - 200 GET /static/vendor/font-awesome-3.2.1/fonts/fontawesome-webfont.woff?v=3.2.1 (::1) 8.82ms
2019-12-13 20:01:15,470 - tornado.access - INFO - 200 GET /api/plugin/printer_safety_check (::1) 4.04ms
2019-12-13 20:01:15,486 - tornado.access - INFO - 200 GET /api/files?recursive=true (::1) 14.29ms
2019-12-13 20:01:15,498 - tornado.access - INFO - 304 GET /api/plugin/pluginmanager (::1) 10.84ms
2019-12-13 20:01:15,540 - tornado.access - INFO - 200 GET /plugin/softwareupdate/check (::1) 40.94ms
2019-12-13 20:01:15,544 - tornado.access - INFO - 200 GET /api/plugin/action_command_prompt (::1) 3.19ms
2019-12-13 20:01:15,549 - tornado.access - INFO - 200 GET /api/access/users (::1) 4.09ms
2019-12-13 20:01:15,553 - tornado.access - INFO - 200 GET /api/slicing (::1) 3.23ms
2019-12-13 20:01:15,562 - tornado.access - INFO - 200 GET /api/plugin/appkeys?all=true (::1) 5.16ms
2019-12-13 20:01:15,564 - octoprint.server.util.sockjs - INFO - User gato logged in on the socket from client ::1
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Startup
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Shutdown
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event ConnectivityChanged
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Connecting
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Connected
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Disconnecting
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Disconnected
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrinterStateChanged
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrinterReset
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event ClientOpened
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event ClientClosed
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event UserLoggedIn
2019-12-13 20:01:15,565 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event UserLoggedOut
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Upload
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event FileSelected
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event FileDeselected
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event UpdatedFiles
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event MetadataAnalysisStarted
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event MetadataAnalysisFinished
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event MetadataStatisticsUpdated
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event FileAdded
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event FileRemoved
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event FolderAdded
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event FolderRemoved
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event TransferStarted
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event TransferDone
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event TransferFailed
2019-12-13 20:01:15,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrintStarted
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrintDone
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrintFailed
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrintCancelling
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrintCancelled
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrintPaused
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrintResumed
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Error
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PowerOn
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PowerOff
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Home
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event ZChange
2019-12-13 20:01:15,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Waiting
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Dwelling
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Cooling
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Alert
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Conveyor
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event Eject
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event EStop
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PositionUpdate
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event FirmwareData
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event ToolChange
2019-12-13 20:01:15,568 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event RegisteredMessageReceived
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event CaptureStart
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event CaptureDone
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event CaptureFailed
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PostRollStart
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PostRollEnd
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event MovieRendering
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event MovieDone
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event MovieFailed
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SlicingStarted
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SlicingDone
2019-12-13 20:01:15,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SlicingFailed
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SlicingCancelled
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SlicingProfileAdded
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SlicingProfileModified
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SlicingProfileDeleted
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrinterProfileAdded
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrinterProfileModified
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event PrinterProfileDeleted
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event SettingsUpdated
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event plugin_pluginmanager_install_plugin
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event plugin_pluginmanager_uninstall_plugin
2019-12-13 20:01:15,570 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event plugin_pluginmanager_enable_plugin
2019-12-13 20:01:15,573 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event plugin_pluginmanager_disable_plugin
2019-12-13 20:01:15,573 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event plugin_printer_safety_check_warning
2019-12-13 20:01:15,574 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event plugin_softwareupdate_update_succeeded
2019-12-13 20:01:15,574 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x10c2d84d0>> for event plugin_softwareupdate_update_failed
2019-12-13 20:01:16,160 - tornado.access - INFO - 200 GET /plugin/discovery/discovery.xml (192.168.252.120) 3.98ms
2019-12-13 20:01:16,164 - tornado.access - INFO - 200 GET /plugin/discovery/discovery.xml (192.168.252.108) 2.92ms
2019-12-13 20:01:16,167 - tornado.access - INFO - 200 GET /plugin/discovery/discovery.xml (192.168.252.106) 2.80ms
2019-12-13 20:01:21,754 - tornado.application - ERROR - Uncaught exception POST /api/files/local (::1)
HTTPServerRequest(protocol='http', host='localhost:5000', method='POST', uri='/api/files/local', version='HTTP/1.1', remote_ip='::1', headers={'Host': 'localhost:5000', 'Connection': 'keep-alive', 'Accept': 'application/json, text/javascript, */*; q=0.01', 'Cache-Control': 'no-cache', 'Origin': 'http://localhost:5000', 'X-Requested-With': 'XMLHttpRequest', 'User-Agent': 'Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_2) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.79 Safari/537.36', 'Sec-Fetch-Site': 'same-origin', 'Sec-Fetch-Mode': 'cors', 'Accept-Encoding': 'gzip, deflate, br', 'Accept-Language': 'en-US,en;q=0.9,es;q=0.8', 'Cookie': '_ga=GA1.1.1429836465.1558858415; cookieconsent_status=dismiss; session_P5000=.eJxdzrtuwzAMheF30RwUoi4U6TFu_BoGJVONgcAJLHkIir57g3Ypup7_G86nmeuu7WqGvh96MvO6mMEsmFRtUfUFOC8-QlHnOHjkDJG8lCzVc6iIxYtFqJq1gLgklpakVJFKJLAZfYixuJcWyZRDrEmlFiEGLokpRU41BeeQSSsJWc2czMmsi2597c83Ofp17s-HmmE7brc_5efqh_T7ix9N9_n_0LS19b79wounaQRvecIYxokI3ic3sgOOeInns_n6BkFQUA0.XfReiw.hWw--NOKL9uTh--LHhqCAVYT4lI'})
Traceback (most recent call last):
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/tornado/web.py", line 1510, in _execute
result = method(*self.path_args, **self.path_kwargs)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/server/util/tornado.py", line 506, in _handle_method
self._fallback(self.request, body)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/server/util/tornado.py", line 619, in __call__
WsgiInputContainer.environ(request, body), start_response)
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/app.py", line 1985, in wsgi_app
response = self.handle_exception(e)
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/app.py", line 1540, in handle_exception
reraise(exc_type, exc_value, tb)
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/_compat.py", line 33, in reraise
raise value
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/app.py", line 1982, in wsgi_app
response = self.full_dispatch_request()
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/app.py", line 1614, in full_dispatch_request
rv = self.handle_user_exception(e)
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/app.py", line 1517, in handle_user_exception
reraise(exc_type, exc_value, tb)
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/_compat.py", line 33, in reraise
raise value
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/app.py", line 1612, in full_dispatch_request
rv = self.dispatch_request()
File "/Users/gaston/repo/OctoPrintPython3/venv/lib/python3.7/site-packages/flask/app.py", line 1598, in dispatch_request
return self.view_functions[rule.endpoint](**req.view_args)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/server/util/flask.py", line 1297, in decorated_view
return func(*args, **kwargs)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/vendor/flask_principal.py", line 199, in _decorated
rv = f(*args, **kw)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/server/api/files.py", line 371, in uploadGcodeFile
display=canonFilename)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/filemanager/__init__.py", line 501, in add_file
path_in_storage = self._storage(destination).add_file(path, file_object, links=links, printer_profile=printer_profile, allow_overwrite=allow_overwrite, display=display)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/filemanager/storage.py", line 765, in add_file
file_object.save(file_path)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/filemanager/util.py", line 92, in save
shutil.copyfileobj(source, dest)
File "/usr/local/Cellar/python/3.7.4/Frameworks/Python.framework/Versions/3.7/lib/python3.7/shutil.py", line 79, in copyfileobj
buf = fsrc.read(length)
File "/Users/gaston/repo/OctoPrintPython3/src/octoprint/filemanager/util.py", line 205, in read
result += processed_line
TypeError: can't concat str to bytes
2019-12-13 20:01:21,754 - tornado.access - ERROR - 500 POST /api/files/local (::1) 40.81ms
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment