octoprint-plugin-log.txt

2016-02-15 15:16:13,509 - octoprint.server - INFO - Starting OctoPrint 1.2.9 (master branch)
2016-02-15 15:16:13,564 - octoprint.server - DEBUG - Starting intermediary server on 0.0.0.0:5000
2016-02-15 15:16:13,577 - octoprint.server - DEBUG - Intermediary server started
2016-02-15 15:16:13,583 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/.octoprint/plugins, /home/pi/OctoPrint/venv/lib/python2.7/site-packages/OctoPrint-1.2.9-py2.7.egg/octoprint/plugins and installed plugin packages...
2016-02-15 15:16:14,243 - octoprint.plugin.core - DEBUG - Loaded plugin virtual_printer: Virtual Printer
2016-02-15 15:16:14,246 - octoprint.plugin.core - DEBUG - Loaded plugin customControl: Custom Control Editor (0.2.1)
2016-02-15 15:16:14,248 - octoprint.plugin.core - DEBUG - Loaded plugin navbartemp: Navbar Temperature Plugin (0.7)
2016-02-15 15:16:14,250 - octoprint.plugin.core - DEBUG - Loaded plugin softwareupdate: Software Update
2016-02-15 15:16:14,252 - octoprint.plugin.core - DEBUG - Enabled plugin softwareupdate: Software Update
2016-02-15 15:16:14,254 - octoprint.plugin.core - DEBUG - Loaded plugin automaticshutdown: Automatic Shutdown (0.1.0)
2016-02-15 15:16:14,256 - octoprint.plugin.core - DEBUG - Loaded plugin cura: CuraEngine (<= 15.04)
2016-02-15 15:16:14,258 - octoprint.plugin.core - DEBUG - Enabled plugin cura: CuraEngine (<= 15.04)
2016-02-15 15:16:14,261 - octoprint.plugin.core - DEBUG - Loaded plugin pluginmanager: Plugin Manager
2016-02-15 15:16:14,263 - octoprint.plugin.core - DEBUG - Enabled plugin pluginmanager: Plugin Manager
2016-02-15 15:16:14,265 - octoprint.plugin.core - DEBUG - Loaded plugin displayz: DisplayZ (0.1.0)
2016-02-15 15:16:14,267 - octoprint.plugin.core - DEBUG - Loaded plugin octopibox: OctoPiBox Plugin (0.1.0)
2016-02-15 15:16:14,269 - octoprint.plugin.core - DEBUG - Enabled plugin octopibox: OctoPiBox Plugin (0.1.0)
2016-02-15 15:16:14,271 - octoprint.plugin.core - DEBUG - Loaded plugin discovery: Discovery
2016-02-15 15:16:14,273 - octoprint.plugin.core - INFO - Found 10 plugin(s) providing 4 mixin implementations, 2 hook handlers
2016-02-15 15:16:14,492 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2016-02-15 15:16:15,995 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2016-02-15 15:16:16,001 - octoprint.events - DEBUG - Subscribed listener <bound method Printer._on_event_MetadataAnalysisFinished of <octoprint.printer.standard.Printer object at 0x163bdd0>> for event MetadataAnalysisFinished
2016-02-15 15:16:16,002 - octoprint.events - DEBUG - Subscribed listener <bound method Printer._on_event_MetadataStatisticsUpdated of <octoprint.printer.standard.Printer object at 0x163bdd0>> for event MetadataStatisticsUpdated
2016-02-15 15:16:16,126 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2016-02-15 15:16:16,128 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin softwareupdate
2016-02-15 15:16:16,136 - octoprint.util.pip - DEBUG - Found pip at /home/pi/OctoPrint/venv/bin/pip, going to figure out its version
2016-02-15 15:16:19,158 - octoprint.util.pip - INFO - Found pip at /home/pi/OctoPrint/venv/bin/pip, version is 1.1
2016-02-15 15:16:19,161 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin pluginmanager
2016-02-15 15:16:19,166 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin octopibox
2016-02-15 15:16:19,171 - octoprint.plugin.core - DEBUG - Initialized plugin mixin implementation for plugin cura
2016-02-15 15:16:19,173 - octoprint.plugin.core - INFO - Initialized 4 plugin implementation(s)
2016-02-15 15:16:19,183 - octoprint.plugin.core - INFO - 10 plugin(s) registered with the system:
| !Automatic Shutdown (0.1.0) = /home/pi/OctoPrint/venv/local/lib/python2.7/site-packages/octoprint_automaticshutdown
|  CuraEngine (<= 15.04) (bundled) = /home/pi/OctoPrint/venv/lib/python2.7/site-packages/OctoPrint-1.2.9-py2.7.egg/octoprint/plugins/cura
| !Custom Control Editor (0.2.1) = /home/pi/OctoPrint/venv/local/lib/python2.7/site-packages/octoprint_customControl
| !Discovery (bundled) = /home/pi/OctoPrint/venv/lib/python2.7/site-packages/OctoPrint-1.2.9-py2.7.egg/octoprint/plugins/discovery
| !DisplayZ (0.1.0) = /home/pi/OctoPrint/venv/local/lib/python2.7/site-packages/octoprint_displayz
| !Navbar Temperature Plugin (0.7) = /home/pi/OctoPrint/venv/local/lib/python2.7/site-packages/octoprint_navbartemp
|  OctoPiBox Plugin (0.1.0) = /home/pi/OctoPrint/venv/local/lib/python2.7/site-packages/octoprint_octopibox
|  Plugin Manager (bundled) = /home/pi/OctoPrint/venv/lib/python2.7/site-packages/OctoPrint-1.2.9-py2.7.egg/octoprint/plugins/pluginmanager
|  Software Update (bundled) = /home/pi/OctoPrint/venv/lib/python2.7/site-packages/OctoPrint-1.2.9-py2.7.egg/octoprint/plugins/softwareupdate
| !Virtual Printer (bundled) = /home/pi/OctoPrint/venv/lib/python2.7/site-packages/OctoPrint-1.2.9-py2.7.egg/octoprint/plugins/virtual_printer
2016-02-15 15:16:19,187 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2016-02-15 15:16:19,191 - octoprint.server - DEBUG - Deleting /home/pi/.octoprint/generated/webassets...
2016-02-15 15:16:19,198 - octoprint.filemanager - INFO - Added 0 items from storage type "local" to analysis queue
2016-02-15 15:16:19,203 - octoprint.server - DEBUG - Creating /home/pi/.octoprint/generated/webassets...
2016-02-15 15:16:19,205 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2016-02-15 15:16:19,207 - octoprint.server - DEBUG - Deleting /home/pi/.octoprint/generated/.webassets-cache...
2016-02-15 15:16:19,243 - octoprint.server - DEBUG - Creating /home/pi/.octoprint/generated/.webassets-cache...
2016-02-15 15:16:19,247 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2016-02-15 15:16:19,269 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event ALERT
2016-02-15 15:16:19,272 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event CAPTURE_DONE
2016-02-15 15:16:19,273 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event CAPTURE_FAILED
2016-02-15 15:16:19,275 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event CAPTURE_START
2016-02-15 15:16:19,277 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event CLIENT_CLOSED
2016-02-15 15:16:19,278 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event CLIENT_OPENED
2016-02-15 15:16:19,280 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event CONNECTED
2016-02-15 15:16:19,282 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event CONVEYOR
2016-02-15 15:16:19,283 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event COOLING
2016-02-15 15:16:19,285 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event DISCONNECTED
2016-02-15 15:16:19,287 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event DWELL
2016-02-15 15:16:19,289 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event EJECT
2016-02-15 15:16:19,292 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event ERROR
2016-02-15 15:16:19,294 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event E_STOP
2016-02-15 15:16:19,296 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event FILE_DESELECTED
2016-02-15 15:16:19,298 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event FILE_SELECTED
2016-02-15 15:16:19,300 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event HOME
2016-02-15 15:16:19,302 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event METADATA_ANALYSIS_FINISHED
2016-02-15 15:16:19,304 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event METADATA_ANALYSIS_STARTED
2016-02-15 15:16:19,307 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event METADATA_STATISTICS_UPDATED
2016-02-15 15:16:19,310 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event MOVIE_DONE
2016-02-15 15:16:19,313 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event MOVIE_FAILED
2016-02-15 15:16:19,315 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event MOVIE_RENDERING
2016-02-15 15:16:19,317 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event POSTROLL_END
2016-02-15 15:16:19,319 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event POSTROLL_START
2016-02-15 15:16:19,321 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event POWER_OFF
2016-02-15 15:16:19,322 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event POWER_ON
2016-02-15 15:16:19,324 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event PRINT_CANCELLED
2016-02-15 15:16:19,326 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event PRINT_DONE
2016-02-15 15:16:19,328 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event PRINT_FAILED
2016-02-15 15:16:19,329 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event PRINT_PAUSED
2016-02-15 15:16:19,331 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event PRINT_RESUMED
2016-02-15 15:16:19,333 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event PRINT_STARTED
2016-02-15 15:16:19,335 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event REGISTERED_MESSAGE_RECEIVED
2016-02-15 15:16:19,337 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event SETTINGS_UPDATED
2016-02-15 15:16:19,338 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event SLICING_CANCELLED
2016-02-15 15:16:19,340 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event SLICING_DONE
2016-02-15 15:16:19,342 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event SLICING_FAILED
2016-02-15 15:16:19,344 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event SLICING_STARTED
2016-02-15 15:16:19,346 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event STARTUP
2016-02-15 15:16:19,347 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event TRANSFER_DONE
2016-02-15 15:16:19,349 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event TRANSFER_STARTED
2016-02-15 15:16:19,351 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event UPDATED_FILES
2016-02-15 15:16:19,353 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event UPLOAD
2016-02-15 15:16:19,354 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event WAITING
2016-02-15 15:16:19,356 - octoprint.events - DEBUG - Subscribed listener <bound method DebugEventListener.eventCallback of <octoprint.events.DebugEventListener object at 0x176a6b0>> for event Z_CHANGE
2016-02-15 15:16:20,006 - octoprint.server - DEBUG - Registered API of plugin softwareupdate under URL prefix /plugin/softwareupdate
2016-02-15 15:16:20,017 - octoprint.server - DEBUG - Registered API of plugin cura under URL prefix /plugin/cura
2016-02-15 15:16:20,032 - octoprint.server - DEBUG - Registered API of plugin pluginmanager under URL prefix /plugin/pluginmanager
2016-02-15 15:16:20,040 - octoprint.server - DEBUG - Registered assets of plugin octopibox under URL prefix /plugin/octopibox
2016-02-15 15:16:20,099 - octoprint.server - DEBUG - Adding maximum body size of 52428800B for POST requests to /plugin/pluginmanager/upload_archive)
2016-02-15 15:16:20,101 - octoprint.server - DEBUG - Shutting down intermediary server...
2016-02-15 15:16:20,209 - octoprint.server - DEBUG - Intermediary server shut down
2016-02-15 15:16:20,245 - octoprint.events - DEBUG - Firing event: Startup (Payload: None)
2016-02-15 15:16:20,271 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2016-02-15 15:16:20,343 - octoprint.server - INFO - Listening on http://0.0.0.0:5000
2016-02-15 15:16:20,424 - octoprint.server - INFO - Preemptively caching / for {'query_string': 'l10n=en', 'path': '/', 'base_url': 'http://cnc-pi.localdomain.org:5000/'}
2016-02-15 15:16:31,147 - octoprint.plugins.cura - INFO - Path to CuraEngine has not been configured yet or does not exist (currently set to None), Cura will not be selectable for slicing
2016-02-15 15:16:31,525 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.1.144
2016-02-15 15:16:31,530 - octoprint.events - DEBUG - Firing event: ClientOpened (Payload: {'remoteAddress': '192.168.1.144'})
2016-02-15 15:16:31,534 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PowerOn
2016-02-15 15:16:31,539 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event TransferStarted
2016-02-15 15:16:31,541 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event FileDeselected
2016-02-15 15:16:31,543 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PostRollEnd
2016-02-15 15:16:31,544 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PowerOff
2016-02-15 15:16:31,545 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Upload
2016-02-15 15:16:31,546 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PrintDone
2016-02-15 15:16:31,547 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event FileSelected
2016-02-15 15:16:31,548 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PrintCancelled
2016-02-15 15:16:31,550 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event MetadataAnalysisStarted
2016-02-15 15:16:31,552 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event UpdatedFiles
2016-02-15 15:16:31,553 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event MetadataStatisticsUpdated
2016-02-15 15:16:31,554 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event SlicingCancelled
2016-02-15 15:16:31,555 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Disconnected
2016-02-15 15:16:31,556 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Conveyor
2016-02-15 15:16:31,557 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event SettingsUpdated
2016-02-15 15:16:31,559 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event MovieRendering
2016-02-15 15:16:31,560 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event MetadataAnalysisFinished
2016-02-15 15:16:31,561 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event SlicingFailed
2016-02-15 15:16:31,562 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Home
2016-02-15 15:16:31,563 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event ClientClosed
2016-02-15 15:16:31,564 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event EStop
2016-02-15 15:16:31,566 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event RegisteredMessageReceived
2016-02-15 15:16:31,567 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PrintResumed
2016-02-15 15:16:31,569 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PostRollStart
2016-02-15 15:16:31,571 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Eject
2016-02-15 15:16:31,573 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event MovieFailed
2016-02-15 15:16:31,575 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Waiting
2016-02-15 15:16:31,577 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Cooling
2016-02-15 15:16:31,578 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Error
2016-02-15 15:16:31,579 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event CaptureFailed
2016-02-15 15:16:31,581 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Dwelling
2016-02-15 15:16:31,582 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event SlicingDone
2016-02-15 15:16:31,583 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event ZChange
2016-02-15 15:16:31,585 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PrintStarted
2016-02-15 15:16:31,586 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Startup
2016-02-15 15:16:31,588 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PrintPaused
2016-02-15 15:16:31,589 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event ClientOpened
2016-02-15 15:16:31,593 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Alert
2016-02-15 15:16:31,594 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event TransferDone
2016-02-15 15:16:31,596 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event CaptureStart
2016-02-15 15:16:31,597 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event PrintFailed
2016-02-15 15:16:31,598 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event Connected
2016-02-15 15:16:31,600 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event MovieDone
2016-02-15 15:16:31,601 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event SlicingStarted
2016-02-15 15:16:31,602 - octoprint.events - DEBUG - Subscribed listener <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>> for event CaptureDone
2016-02-15 15:16:31,814 - octoprint.users - DEBUG - Logged in user: SessionUser(id=export,name=export,active=True,user=True,admin=True,session=OAVu09nERn,created=1455567391.81)
2016-02-15 15:16:31,853 - octoprint.server.util.sockjs - DEBUG - Set throttle factor for client 192.168.1.144 to 1
2016-02-15 15:16:42,464 - octoprint.server.api - INFO - Performing command: pigs w 17 1
2016-02-15 15:16:42,628 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:16:42,630 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:42,654 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: -1
2016-02-15 15:16:42,656 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:43,154 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:16:43,156 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:16:43,161 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:16:43,163 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:52,168 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:16:52,174 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:16:52,177 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:16:52,179 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:53,928 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:16:53,930 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:16:53,937 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:16:53,939 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:53,997 - octoprint.events - DEBUG - Firing event: Connected (Payload: {'baudrate': 250000, 'port': '/dev/ttyACM0'})
2016-02-15 15:16:54,001 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>>
2016-02-15 15:16:54,007 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:16:54,010 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:16:54,015 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:16:54,017 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:54,026 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:16:54,028 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:16:54,033 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:16:54,035 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:54,060 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:16:54,062 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:16:54,166 - octoprint.events - DEBUG - Firing event: UpdatedFiles (Payload: {'type': 'gcode'})
2016-02-15 15:16:54,167 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>>
2016-02-15 15:16:58,941 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:16:58,944 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:16:58,946 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:16:58,948 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:03,940 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:03,970 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:03,972 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:03,975 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:07,131 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:07,133 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:07,136 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:07,137 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:07,454 - octoprint.events - DEBUG - Firing event: FileSelected (Payload: {'origin': 'sdcard', 'file': u'/fakepr.gcode'})
2016-02-15 15:17:07,456 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>>
2016-02-15 15:17:08,942 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:08,955 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:08,958 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:08,960 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:13,364 - octoprint.filemanager.analysis - DEBUG - Pausing analysis
2016-02-15 15:17:13,461 - octoprint.events - DEBUG - Firing event: PrintStarted (Payload: {'origin': 'sdcard', 'file': u'/fakepr.gcode', 'filename': u'fakepr.gcode'})
2016-02-15 15:17:13,463 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>>
2016-02-15 15:17:13,467 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:13,469 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:13,477 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:13,480 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:13,493 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:13,505 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:13,522 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:13,524 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:13,528 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:13,531 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:13,541 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:13,545 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:13,548 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:13,550 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:13,582 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:13,584 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:13,944 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:13,954 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:14,410 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:14,411 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:18,495 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:18,498 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:25,928 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:25,929 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:25,938 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:25,945 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:25,962 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:25,965 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:25,970 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:25,973 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:26,440 - octoprint.filemanager.analysis - DEBUG - Resuming analyzer
2016-02-15 15:17:26,443 - octoprint.events - DEBUG - Firing event: PrintDone (Payload: {'origin': 'sdcard', 'time': 13.078630924224854, 'file': u'/fakepr.gcode', 'filename': u'fakepr.gcode'})
2016-02-15 15:17:26,445 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>>
2016-02-15 15:17:26,449 - octoprint.plugins.octopibox - INFO - Print complete. Automatic Printer Power-off is ENABLED. Starting timer.
2016-02-15 15:17:26,462 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:26,465 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:28,948 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:28,953 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:28,957 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:28,960 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:33,951 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:33,953 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:33,959 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:33,961 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:38,954 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:38,958 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:38,983 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:38,985 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:43,959 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:43,988 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:43,990 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:43,992 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:48,959 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:48,965 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:49,094 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:49,096 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:53,967 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 0
2016-02-15 15:17:53,968 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Cleared event
2016-02-15 15:17:53,978 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - New counter value: 1
2016-02-15 15:17:53,980 - octoprint.util.CountedEvent.comm.clear_to_send - DEBUG - Set event
2016-02-15 15:17:56,500 - octoprint.plugins.octopibox - INFO - Printer disconnect before power-off.
2016-02-15 15:17:56,503 - octoprint.events - DEBUG - Firing event: UpdatedFiles (Payload: {'type': 'gcode'})
2016-02-15 15:17:56,505 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>>
2016-02-15 15:18:30,149 - octoprint.events - DEBUG - Firing event: Disconnected (Payload: None)
2016-02-15 15:18:30,157 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x1a0d810>>
2016-02-15 15:18:58,547 - octoprint.server - INFO - Shutting down...
2016-02-15 15:18:59,351 - octoprint.server - INFO - Goodbye!