brandstaetter/octoprint.log.2018-01-05

## octoprint.log.2018-01-05
2018-01-05 07:18:11,330 - octoprint.server - INFO - --- Log roll over detected ---------------------------------------------------
2018-01-05 07:18:11,335 - octoprint.server - INFO - OctoPrint 1.3.6
2018-01-05 07:18:11,347 - octoprint.plugin.core - INFO - 13 plugin(s) registered with the system:
|  Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/announcements
|  Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/corewizard
|  CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/cura
|  Detailed Progress Plugin (0.1.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_detailedprogress
|  Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/discovery
|  Display ETA (1.0.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_eta
|  FileManager (0.1.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_filemanager
|  Fullscreen Plugin (0.0.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_fullscreen
|  OctoPi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/octopi_support
|  Plugin Manager (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/pluginmanager
|  Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/softwareupdate
|  Telegram Notifications (1.4.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_telegram
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/virtual_printer
2018-01-05 07:18:11,434 - octoprint.environment - INFO - Detected environment is Python 2.7.9 under Linux (linux2). Details:
|  hardware:
|    cores: 1
|    freq: 1000.0
|    ram: 388685824
|  os:
|    id: linux
|    platform: linux2
|  plugins:
|    octopi_support:
|      model: Zero W
|      revision: 9000c1
|      version: 0.14.0
|  python:
|    pip: 9.0.1
|    version: 2.7.9
|    virtualenv: /home/pi/oprint
2018-01-05 07:18:11,435 - octoprint.server - INFO - ------------------------------------------------------------------------------
2018-01-05 07:18:11,313 - octoprint.filemanager.analysis - INFO - Starting analysis of local:catan.gcode
2018-01-05 07:18:11,446 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/catan.gcode
2018-01-05 07:20:44,375 - octoprint.filemanager.analysis - INFO - Analysis of entry local:catan.gcode finished, needed 153.06s
2018-01-05 07:21:30,666 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.0.6
2018-01-05 07:21:32,863 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.0.6
2018-01-05 07:21:35,813 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.57s
2018-01-05 07:21:36,968 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.42s
2018-01-05 07:21:37,791 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.46s
2018-01-05 07:21:38,564 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.41s
2018-01-05 07:21:39,491 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.42s
2018-01-05 07:21:43,414 - octoprint.plugins.softwareupdate - INFO - Saved version cache to disk
2018-01-05 08:18:30,175 - octoprint.plugins.detailedprogress - INFO - Printing started. Detailed progress started.
2018-01-05 08:18:30,184 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-01-05 08:29:00,435 - octoprint.printer.standard - ERROR - Exception while pushing current data
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/printer/standard.py", line 157, in _sendCurrentDataCallbacks
    try: callback.on_printer_send_current_data(copy.deepcopy(data))
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 257, in _deepcopy_dict
    y[deepcopy(key, memo)] = deepcopy(value, memo)
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 256, in _deepcopy_dict
    for key, value in x.iteritems():
RuntimeError: dictionary changed size during iteration
2018-01-05 09:08:53,404 - octoprint.plugins.detailedprogress - INFO - Printing stopped. Detailed progress stopped.
2018-01-05 09:08:55,403 - octoprint.plugins.detailedprogress - INFO - Printing stopped. Detailed progress stopped.
2018-01-05 09:13:52,014 - octoprint.filemanager.analysis - INFO - Starting analysis of local:catan.gcode
2018-01-05 09:13:52,045 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/catan.gcode
2018-01-05 09:14:43,638 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-01-05 09:14:43,642 - octoprint.plugins.detailedprogress - INFO - Printing started. Detailed progress started.
2018-01-05 09:27:24,454 - octoprint.printer.standard - ERROR - Exception while pushing current data
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/printer/standard.py", line 157, in _sendCurrentDataCallbacks
    try: callback.on_printer_send_current_data(copy.deepcopy(data))
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 257, in _deepcopy_dict
    y[deepcopy(key, memo)] = deepcopy(value, memo)
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 256, in _deepcopy_dict
    for key, value in x.iteritems():
RuntimeError: dictionary changed size during iteration
2018-01-05 09:28:44,473 - octoprint.printer.standard - ERROR - Exception while pushing current data
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/printer/standard.py", line 157, in _sendCurrentDataCallbacks
    try: callback.on_printer_send_current_data(copy.deepcopy(data))
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 257, in _deepcopy_dict
    y[deepcopy(key, memo)] = deepcopy(value, memo)
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 256, in _deepcopy_dict
    for key, value in x.iteritems():
RuntimeError: dictionary changed size during iteration
2018-01-05 10:56:08,833 - octoprint.util.comm - INFO - Finished in 6085.302 s.
2018-01-05 10:56:08,862 - octoprint.plugins.detailedprogress - INFO - Printing stopped. Detailed progress stopped.
2018-01-05 10:56:08,887 - octoprint.filemanager.analysis - INFO - Starting analysis of local:catan.gcode
2018-01-05 10:56:08,958 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/catan.gcode
2018-01-05 11:00:19,451 - octoprint.filemanager.analysis - INFO - Analysis of entry local:catan.gcode finished, needed 250.56s
2018-01-05 11:24:37,285 - octoprint.filemanager.analysis - INFO - Starting analysis of local:RussianDollMazeOuter1.gcode
2018-01-05 11:24:37,320 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/RussianDollMazeOuter1.gcode
2018-01-05 11:26:08,638 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2018-01-05 11:26:13,910 - octoprint.plugin.core - INFO - Found 13 plugin(s) providing 13 mixin implementations, 10 hook handlers
2018-01-05 11:27:54,844 - octoprint.server.api.system - INFO - Performing command for core:restart: sudo service octoprint restart
2018-01-05 11:27:55,882 - octoprint.server - INFO - Shutting down...
2018-01-05 11:27:56,770 - octoprint.events - INFO - Processing shutdown event, this will be our last event
2018-01-05 11:27:56,813 - octoprint.events - INFO - Event loop shut down
2018-01-05 11:27:58,030 - octoprint.server - INFO - Goodbye!
2018-01-05 11:28:23,913 - octoprint.startup - INFO - ******************************************************************************
2018-01-05 11:28:23,919 - octoprint.startup - INFO - Starting OctoPrint 1.3.6
2018-01-05 11:28:23,924 - octoprint.startup - INFO - ******************************************************************************
2018-01-05 11:28:24,238 - octoprint.util.connectivity_checker - INFO - Connectivity changed from offline to online
2018-01-05 11:28:25,060 - octoprint.startup - INFO - Blacklist processing done
2018-01-05 11:28:25,455 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2018-01-05 11:28:40,157 - octoprint.plugin.core - INFO - Found 12 plugin(s) providing 12 mixin implementations, 9 hook handlers
2018-01-05 11:28:41,280 - octoprint.server - INFO - Intermediary server started
2018-01-05 11:28:41,285 - octoprint.plugin.core - INFO - Loading plugins from /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins, /home/pi/.octoprint/plugins and installed plugin packages...
2018-01-05 11:28:44,468 - octoprint.plugin.core - INFO - Found 12 plugin(s) providing 12 mixin implementations, 9 hook handlers
2018-01-05 11:28:44,835 - octoprint.filemanager.storage - INFO - Initializing the file metadata for /home/pi/.octoprint/uploads...
2018-01-05 11:28:56,523 - octoprint.filemanager.storage - INFO - ... file metadata for /home/pi/.octoprint/uploads initialized successfully.
2018-01-05 11:28:57,979 - octoprint.plugins.softwareupdate - INFO - Loaded version cache from disk
2018-01-05 11:29:20,814 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python -m pip" as command to invoke pip
2018-01-05 11:29:43,030 - octoprint.util.pip - INFO - Version of pip is 9.0.1
2018-01-05 11:29:43,055 - octoprint.plugin.core - INFO - Initialized 12 plugin implementation(s)
2018-01-05 11:29:43,172 - octoprint.plugin.core - INFO - 12 plugin(s) registered with the system:
|  Announcement Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/announcements
|  Core Wizard (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/corewizard
|  CuraEngine (<= 15.04) (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/cura
|  Detailed Progress Plugin (0.1.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_detailedprogress
|  Discovery (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/discovery
|  Display ETA (1.0.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_eta
|  Fullscreen Plugin (0.0.4) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_fullscreen
|  OctoPi Support Plugin (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/octopi_support
|  Plugin Manager (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/pluginmanager
|  Software Update (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/softwareupdate
|  Telegram Notifications (1.4.2) = /home/pi/oprint/local/lib/python2.7/site-packages/octoprint_telegram
|  Virtual Printer (bundled) = /home/pi/oprint/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/plugins/virtual_printer
2018-01-05 11:30:00,425 - octoprint.environment - INFO - Detected environment is Python 2.7.9 under Linux (linux2). Details:
|  hardware:
|    cores: 1
|    freq: 1000.0
|    ram: 388685824
|  os:
|    id: linux
|    platform: linux2
|  plugins:
|    octopi_support:
|      model: Zero W
|      revision: 9000c1
|      version: 0.14.0
|  python:
|    pip: 9.0.1
|    version: 2.7.9
|    virtualenv: /home/pi/oprint
2018-01-05 11:30:00,499 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2018-01-05 11:30:00,753 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2018-01-05 11:30:02,831 - octoprint.server - INFO - Shutting down intermediary server...
2018-01-05 11:30:03,244 - octoprint.server - INFO - Intermediary server shut down
2018-01-05 11:30:03,249 - octoprint.events - INFO - Processing startup event, this is our first event
2018-01-05 11:30:03,263 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2018-01-05 11:30:03,293 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2018-01-05 11:30:06,048 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from https://plugins.octoprint.org/plugins.json
2018-01-05 11:30:06,061 - octoprint.filemanager.analysis - INFO - Starting analysis of local:RussianDollMazeOuter1.gcode
2018-01-05 11:30:06,240 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.01 --throttle-lines=100 /home/pi/.octoprint/uploads/RussianDollMazeOuter1.gcode
2018-01-05 11:30:06,304 - octoprint.filemanager - INFO - Added 1 items from storage type "local" to analysis queue
2018-01-05 11:30:08,630 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-01-05 11:30:08,919 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance "Original PRUSA i3 MK2" for _http._tcp
2018-01-05 11:30:09,145 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-01-05 11:30:09,368 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance "Original PRUSA i3 MK2" for _octoprint._tcp
2018-01-05 11:30:09,485 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance "Original PRUSA i3 MK2" for SSDP
2018-01-05 11:30:09,536 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2018-01-05 11:30:09,844 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin V1.0.2; Sprinter/grbl mashup for gen6"
2018-01-05 11:30:10,533 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2018-01-05 11:30:18,322 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2018-01-05 11:30:21,035 - octoprint.plugins.pluginmanager - INFO - Loaded plugin notices data from https://plugins.octoprint.org/notices.json
2018-01-05 11:30:32,120 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=de', 'path': '/', 'base_url': 'http://prusa.local/'}
2018-01-05 11:31:05,954 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.0.6
2018-01-05 11:31:36,805 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.0.6
2018-01-05 11:31:41,657 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.0.6
2018-01-05 11:38:01,294 - octoprint.server.preemptive_cache - INFO - ... done in 449.16s
2018-01-05 11:38:03,042 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.0.6
2018-01-05 11:38:05,987 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.0.6
2018-01-05 11:38:07,689 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.0.6
2018-01-05 11:49:15,060 - octoprint.filemanager.analysis - INFO - Analysis of entry local:RussianDollMazeOuter1.gcode finished, needed 1149.00s
2018-01-05 13:19:37,973 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.0.6
2018-01-05 13:19:40,837 - octoprint.server.util.sockjs - INFO - Client connection closed: 192.168.0.6
2018-01-05 13:19:40,842 - octoprint.server.util.sockjs - WARNING - Could not send message to client 192.168.0.6: object of type 'NoneType' has no len()
2018-01-05 16:26:04,144 - octoprint.server.util.sockjs - INFO - New connection from client: 192.168.0.6
2018-01-05 16:26:07,190 - octoprint.plugins.announcements - INFO - Loaded channel _important from https://octoprint.org/feeds/important.xml in 0.46s
2018-01-05 16:26:08,324 - octoprint.plugins.announcements - INFO - Loaded channel _releases from https://octoprint.org/feeds/releases.xml in 0.41s
2018-01-05 16:26:09,125 - octoprint.plugins.announcements - INFO - Loaded channel _blog from https://octoprint.org/feeds/octoblog.xml in 0.43s
2018-01-05 16:26:09,880 - octoprint.plugins.announcements - INFO - Loaded channel _plugins from https://plugins.octoprint.org/feed.xml in 0.4s
2018-01-05 16:26:10,810 - octoprint.plugins.announcements - INFO - Loaded channel _octopi from https://octoprint.org/feeds/octopi.xml in 0.42s
2018-01-05 17:44:44,500 - octoprint.filemanager.analysis - INFO - Starting analysis of local:RussianDollMazeOuter1-20180105-184431.gcode
2018-01-05 17:44:44,531 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/RussianDollMazeOuter1-20180105-184431.gcode
2018-01-05 17:59:14,878 - octoprint.filemanager.analysis - INFO - Analysis of entry local:RussianDollMazeOuter1-20180105-184431.gcode finished, needed 870.38s
2018-01-06 10:56:35,071 - octoprint.filemanager.analysis - INFO - Starting analysis of local:RussianDollMazeOuter1_20180106-115623.gcode
2018-01-06 10:56:35,107 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/RussianDollMazeOuter1_20180106-115623.gcode
2018-01-06 10:57:16,171 - octoprint.plugins.detailedprogress - INFO - Printing started. Detailed progress started.
2018-01-06 10:57:16,217 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-01-06 11:08:56,627 - octoprint.printer.standard - ERROR - Exception while pushing current data
Traceback (most recent call last):
  File "/home/pi/oprint/local/lib/python2.7/site-packages/OctoPrint-1.3.6-py2.7.egg/octoprint/printer/standard.py", line 157, in _sendCurrentDataCallbacks
    try: callback.on_printer_send_current_data(copy.deepcopy(data))
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 257, in _deepcopy_dict
    y[deepcopy(key, memo)] = deepcopy(value, memo)
  File "/usr/lib/python2.7/copy.py", line 163, in deepcopy
    y = copier(x, memo)
  File "/usr/lib/python2.7/copy.py", line 256, in _deepcopy_dict
    for key, value in x.iteritems():
RuntimeError: dictionary changed size during iteration
2018-01-06 11:11:57,073 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 8699, current line = 8701
| Last lines in terminal:
| Recv: ok
| Send: N8692 G1 X118.164 Y83.237 E0.00773*98
| Recv: ok
| Send: N8693 G1 X118.330 Y83.237 E0.00569*105
| Recv: ok
| Send: N8694 G1 X104.788 Y96.779 E0.65337*97
| Recv: ok
| Send: N8695 G1 X104.509 Y97.639 E0.03083*99
| Recv: ok
| Send: N8696 M117 2.92p  complete*91
| Recv: ok
| Send: N8697 G1 X118.911 Y83.238 E0.69483*97
| Recv: ok
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: ok
| Send: N8699 G1 X103.945 Y98.784 E0.75006*104
| Recv: ok
| Send: N8700 M204 S1000*104
| Recv: Error:No Checksum with line number, Last Line: 8698
| Recv: Resend: 8699
2018-01-06 11:11:57,098 - octoprint.util.comm - INFO - Ignoring resend request for line 8699, that still originates from lines we sent before we got the first resend request
2018-01-06 11:12:24,253 - octoprint.filemanager.analysis - INFO - Starting analysis of local:RussianDollMazeOuter1_20180106-115623.gcode
2018-01-06 11:12:24,301 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/RussianDollMazeOuter1_20180106-115623.gcode
2018-01-06 11:12:57,157 - octoprint.util.comm - INFO - Communication timeout during an active resend, resending same line again to trigger response from printer.
2018-01-06 11:12:57,199 - octoprint.util.comm - INFO - Ignoring resend request for line 8699, that still originates from lines we sent before we got the first resend request
2018-01-06 11:13:29,814 - octoprint.filemanager.analysis - INFO - Starting analysis of local:RussianDollMazeOuter1_20180106-115623.gcode
2018-01-06 11:13:29,857 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/RussianDollMazeOuter1_20180106-115623.gcode
2018-01-06 11:13:57,265 - octoprint.util.comm - INFO - Communication timeout during an active resend, resending same line again to trigger response from printer.
2018-01-06 11:13:57,344 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 8699, current line = 8701
| Last lines in terminal:
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: ok
| Send: N8699 G1 X103.945 Y98.784 E0.75006*104
| Recv: ok
| Send: N8700 M204 S1000*104
| Recv: Error:No Checksum with line number, Last Line: 8698
| Recv: Resend: 8699
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
| Changing monitoring state from 'Printing' to 'Paused'
| Changing monitoring state from 'Paused' to 'Printing'
| Communication timeout during an active resend, resending same line again to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
| Changing monitoring state from 'Printing' to 'Operational'
| Communication timeout during an active resend, resending same line again to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
2018-01-06 11:14:57,423 - octoprint.util.comm - INFO - Communication timeout during an active resend, resending same line again to trigger response from printer.
2018-01-06 11:14:57,496 - octoprint.util.comm - INFO - Ignoring resend request for line 8699, that still originates from lines we sent before we got the first resend request
2018-01-06 11:15:57,573 - octoprint.util.comm - INFO - Communication timeout during an active resend, resending same line again to trigger response from printer.
2018-01-06 11:15:57,661 - octoprint.util.comm - INFO - Ignoring resend request for line 8699, that still originates from lines we sent before we got the first resend request
2018-01-06 11:16:57,722 - octoprint.util.comm - INFO - Communication timeout during an active resend, resending same line again to trigger response from printer.
2018-01-06 11:16:57,817 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 8699, current line = 8701
| Last lines in terminal:
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
| Changing monitoring state from 'Printing' to 'Operational'
| Communication timeout during an active resend, resending same line again to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
| Communication timeout during an active resend, resending same line again to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
| Communication timeout during an active resend, resending same line again to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
| Communication timeout during an active resend, resending same line again to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
| Send: N8698 G1 X119.491 Y83.238 E0.01979*108
| Recv: Error:Line Number is not Last Line Number+1, Last Line: 8698
| Recv: Resend: 8699
2018-01-06 11:17:57,893 - octoprint.util.comm - INFO - Communication timeout during an active resend, resending same line again to trigger response from printer.
2018-01-06 11:17:57,978 - octoprint.util.comm - INFO - Ignoring resend request for line 8699, that still originates from lines we sent before we got the first resend request
2018-01-06 11:18:42,426 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-01-06 11:18:42,513 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2018-01-06 11:18:42,776 - octoprint.util.comm - INFO - Printer reports firmware name "Marlin V1.0.2; Sprinter/grbl mashup for gen6"
2018-01-06 11:18:50,196 - octoprint.filemanager.analysis - INFO - Starting analysis of local:RussianDollMazeOuter1_20180106-120804.gcode
2018-01-06 11:18:50,222 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/RussianDollMazeOuter1_20180106-120804.gcode