Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

0.2.4 can't process simplify3d gcode file when printing from SD Card #55

Open
devslashlol opened this issue Sep 25, 2020 · 66 comments
Open
Labels
Finder v2 Updated version of the original Finder

Comments

@devslashlol
Copy link

Hi,
I used Upload to SD card, then printed from that - I get these kinds of messages in the log
2020-09-25 14:43:37,492 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): dropping command
2020-09-25 14:43:37,493 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:G1, cmd:G1 X1.482 Y-9.400 E1.5032

The extruder sounds like it's grinding or skipping steps or something. I also see this:
2020-09-25 16:23:46,385 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:23:46,387 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:46,389 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:46,391 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:23:46,392 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:23:46,394 - octoprint.plugins.flashforge - DEBUG - buffering: Not SD printing
2020-09-25 16:23:46,395 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:23:46,398 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:46,400 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:46,402 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:46,403 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:47,364 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:23:47,364 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:23:47,366 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:23:47,367 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:23:47,376 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:23:47,377 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:47,380 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:47,382 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:23:47,383 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:23:47,384 - octoprint.plugins.flashforge - DEBUG - buffering: Not SD printing
2020-09-25 16:23:47,384 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:23:47,386 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:47,387 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:47,388 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:47,388 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:48,359 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-09-25 16:23:48,360 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:23:48,363 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:23:48,364 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-09-25 16:23:48,367 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:23:48,369 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:23:48,374 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:28 /0 B:0/0 | ok |
2020-09-25 16:23:48,374 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-09-25 16:23:48,376 - octoprint.plugins.flashforge - DEBUG - buffering: T0:28 /0 B:0/0
2020-09-25 16:23:48,377 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:23:48,380 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:48,383 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-09-25 16:23:48,395 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:48,397 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:48,399 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:23:48,400 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:48,401 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:23:48,411 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:23:48,412 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:48,414 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:48,417 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:23:48,418 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:23:48,420 - octoprint.plugins.flashforge - DEBUG - buffering: Not SD printing
2020-09-25 16:23:48,421 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:23:48,423 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:48,426 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:48,429 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:48,430 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:49,371 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:23:49,373 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:23:49,376 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:23:49,378 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:23:49,386 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:23:49,388 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:49,389 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:49,392 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:23:49,393 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:23:49,394 - octoprint.plugins.flashforge - DEBUG - buffering: Not SD printing
2020-09-25 16:23:49,396 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:23:49,402 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:49,408 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:49,411 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:49,413 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:50,375 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:23:50,376 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:23:50,378 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:23:50,380 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:23:50,389 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:23:50,390 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:50,392 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:50,395 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:23:50,396 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:23:50,398 - octoprint.plugins.flashforge - DEBUG - buffering: Not SD printing
2020-09-25 16:23:50,399 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:23:50,402 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:50,404 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:50,407 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:23:50,407 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:23:51,378 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:23:51,379 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:23:51,382 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:23:51,387 - octoprint.plugins.flashforge - DEBUG - write() M119

I use Simplify 3D to slice, and the gcode files directly out of that always work if I load them via a usb flash drive at the unit directly.

@Mrnt
Copy link
Owner

Mrnt commented Sep 25, 2020

Please upload the file so I can test with it, thanks!

@devslashlol
Copy link
Author

@devslashlol
Copy link
Author

it wouldn't let me upload a plain gcode file.

@Mrnt
Copy link
Owner

Mrnt commented Sep 26, 2020

If you upload to the address card using “Upload to SD” the file should go straight to the SD card and you should just see printing progress type messages in the log which looks like what you have. The plugin does not parse the uploaded file and alter it. It is a bit of a red flag that it says the total bytes it is/was printing is 1000. If you look further back in the log you should see the actual upload happening with progress in %. Make sure that got to 100%. There will be an M29 command at the end of the upload which should get a non error response.

@Mrnt
Copy link
Owner

Mrnt commented Sep 26, 2020

If you can upload a fresh OctoPrint.log captured during an SD upload that doesn’t print it would be helpful.

@devslashlol
Copy link
Author

I see the files completing and M29 looks ok
image

@devslashlol
Copy link
Author

every time I see this in the log, it's completes

@devslashlol
Copy link
Author

I should note, the printer never actually does anything - the hot end doesn't heat up, nothing. Just sits there.
The display shows what appears to be a print job, but ... just sits.

@devslashlol
Copy link
Author

mo' log
2020-09-25 16:21:33,996 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-09-25 16:21:33,997 - octoprint.plugins.flashforge - DEBUG - Sent: 99.00% 248832/250896
2020-09-25 16:21:33,998 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-09-25 16:21:33,999 - octoprint.plugins.flashforge - DEBUG - Sent: 99.00% 249856/250896
2020-09-25 16:21:34,001 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-09-25 16:21:34,002 - octoprint.plugins.flashforge - DEBUG - Sent: 99.00% 250880/250896
2020-09-25 16:21:34,003 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-09-25 16:21:34,004 - octoprint.plugins.flashforge - DEBUG - Sent: 100.00% 250896/250896
2020-09-25 16:21:34,005 - octoprint.plugins.flashforge - DEBUG - sendcommand() M29
2020-09-25 16:21:34,006 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-09-25 16:21:34,007 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 10000
2020-09-25 16:21:34,052 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M29 Received. | Done saving file. | ok |
2020-09-25 16:21:34,052 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-09-25 16:21:34,054 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,062 - octoprint.plugins.flashforge - DEBUG - enable_keep_alive(True)
2020-09-25 16:21:34,063 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M23, cmd:M23 0:/user/finder_gcode/xyzCalibration_cube.gcode
2020-09-25 16:21:34,063 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:34,064 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-09-25 16:21:34,068 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,069 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,070 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:34,071 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:34,072 - octoprint.plugins.flashforge - DEBUG - write() M23 0:/user/finder_gcode/xyzCalibration_cube.gcode
M119
2020-09-25 16:21:34,092 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M23 Received. | File opened: /data/xyzCalibration_cube.gcode Size: 0 | File selected | ok |
2020-09-25 16:21:34,092 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M23 Received.
2020-09-25 16:21:34,093 - octoprint.plugins.flashforge - DEBUG - buffering: File opened: /data/xyzCalibration_cube.gcode Size: 0
2020-09-25 16:21:34,094 - octoprint.plugins.flashforge - DEBUG - buffering: File selected
2020-09-25 16:21:34,095 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:34,096 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,101 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,103 - octoprint.printer.standard.job - INFO - Print job selected - origin: sdcard, path: 0:/user/finder_gcode/xyzCalibration_cube.gcode
, owner: None, user: None
2020-09-25 16:21:34,109 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,110 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,111 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:34,179 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,180 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,182 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,183 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,185 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,187 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,189 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,190 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,192 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,193 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,196 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,197 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,271 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,272 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,274 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,276 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,278 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,279 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,281 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,283 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,285 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,286 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,288 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,290 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:34,851 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:34,852 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:34,854 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:34,854 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:34,863 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:34,863 - octoprint.plugins.flashforge - DEBUG - state changed from 1 to 3
2020-09-25 16:21:34,864 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,865 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:34,873 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:34,874 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:34,874 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:34,875 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:34,877 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,878 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,880 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:34,880 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:35,034 - octoprint.filemanager.analysis - INFO - Analysis of entry local:finder_gcode/xyzCalibration_cube.gcode finished, needed 1.90s
2020-09-25 16:21:35,096 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,098 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,100 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,102 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,104 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,110 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,112 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,113 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,115 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,116 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,118 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,119 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,122 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,123 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,125 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,126 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,128 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,129 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-09-25 16:21:35,853 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:35,854 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:35,855 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:35,858 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:35,867 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:35,868 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:35,868 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:35,878 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:35,878 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:35,879 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:35,879 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:35,880 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:35,882 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:35,883 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:35,883 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:36,856 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:36,857 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:36,860 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:36,862 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:36,877 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:36,879 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:36,881 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:36,882 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:36,884 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:36,886 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:36,887 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:36,890 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:36,892 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:36,895 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:36,896 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:37,860 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:37,861 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:37,864 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:37,865 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:37,877 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:37,879 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:37,887 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:37,891 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:37,891 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:37,894 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:37,895 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:37,898 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:37,900 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:37,903 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:37,903 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:38,251 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-09-25 16:21:38,252 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:38,256 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:38,257 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-09-25 16:21:38,263 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:28 /0 B:0/0 | ok |
2020-09-25 16:21:38,264 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-09-25 16:21:38,266 - octoprint.plugins.flashforge - DEBUG - buffering: T0:28 /0 B:0/0
2020-09-25 16:21:38,267 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:38,270 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:38,272 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-09-25 16:21:38,276 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:38,279 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:38,280 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:38,864 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:38,865 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:38,868 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:38,869 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:38,881 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:38,883 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:38,884 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:38,891 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:38,892 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:38,894 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:38,903 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:38,906 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:38,908 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:38,911 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:38,912 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:39,868 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:39,868 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:39,872 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:39,873 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:39,886 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:39,888 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:39,889 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:39,892 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:39,893 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:39,894 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:39,896 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:39,899 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:39,901 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:39,903 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:39,905 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:40,872 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:40,872 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:40,876 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:40,877 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:40,888 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:40,890 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:40,891 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:40,893 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:40,894 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:40,896 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:40,897 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:40,899 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:40,902 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:40,905 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:40,906 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:41,308 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting print from SD"
2020-09-25 16:21:41,315 - octoprint.printer.standard.job - INFO - Print job started - origin: sdcard, path: 0:/user/finder_gcode/xyzCalibration_cube.gcode
, owner: mw, user: mw
2020-09-25 16:21:41,332 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M23, cmd:M23 0:/user/finder_gcode/xyzCalibration_cube.gcode
2020-09-25 16:21:41,333 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:41,334 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): dropping command
2020-09-25 16:21:41,875 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:41,875 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:41,877 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:41,878 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:41,887 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:41,887 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:41,888 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:41,892 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:41,893 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:41,894 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:41,895 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:41,896 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:41,897 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:41,899 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M24, cmd:M24
2020-09-25 16:21:41,900 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:41,900 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:41,901 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:41,902 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:41,903 - octoprint.plugins.flashforge - DEBUG - write() M24
2020-09-25 16:21:41,917 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M24 Received. | ok |
2020-09-25 16:21:41,918 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M24 Received.
2020-09-25 16:21:41,918 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:41,919 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:41,921 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:41,921 - octoprint.util.comm - INFO - Changing monitoring state from "Starting print from SD" to "Printing from SD"
2020-09-25 16:21:41,922 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:42,877 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:42,878 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:42,882 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:42,883 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:42,894 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:42,896 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:42,897 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:42,904 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:42,908 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:42,909 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:42,911 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:42,914 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:42,917 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:42,919 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:42,920 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:43,255 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-09-25 16:21:43,256 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:43,260 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:43,260 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-09-25 16:21:43,269 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:28 /0 B:0/0 | ok |
2020-09-25 16:21:43,270 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-09-25 16:21:43,271 - octoprint.plugins.flashforge - DEBUG - buffering: T0:28 /0 B:0/0
2020-09-25 16:21:43,272 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:43,276 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:43,277 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-09-25 16:21:43,282 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:43,284 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:43,286 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:43,883 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:43,883 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:43,887 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:43,888 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:43,898 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:43,899 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:43,900 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:43,903 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:43,904 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:43,906 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:43,907 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:43,910 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:43,912 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:43,922 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:43,923 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:44,887 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:44,888 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:44,891 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:44,892 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:44,905 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:44,907 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:44,908 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:44,911 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:44,912 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:44,914 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:44,915 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:44,917 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:44,920 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:44,923 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:44,924 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:45,890 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:45,891 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:45,894 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:45,896 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:45,910 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:45,912 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:45,913 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:45,915 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:45,917 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:45,918 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:45,919 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:45,922 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:45,924 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:45,927 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:45,928 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:46,894 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:46,895 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:46,898 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:46,899 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:46,909 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:46,911 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:46,920 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:46,922 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:46,924 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:46,925 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:46,927 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:46,930 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:46,932 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:46,935 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:46,936 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:47,897 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:47,898 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:47,902 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:47,903 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:47,912 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:47,914 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:47,915 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:47,918 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:47,919 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:47,921 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:47,922 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:47,924 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:47,935 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:47,937 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:47,938 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:48,259 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-09-25 16:21:48,260 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:48,263 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:48,264 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-09-25 16:21:48,271 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:28 /0 B:0/0 | ok |
2020-09-25 16:21:48,272 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-09-25 16:21:48,274 - octoprint.plugins.flashforge - DEBUG - buffering: T0:28 /0 B:0/0
2020-09-25 16:21:48,275 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:48,278 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:48,280 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-09-25 16:21:48,284 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:48,287 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:48,288 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:48,901 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:48,902 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:48,905 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:48,906 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:48,917 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:48,918 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:48,919 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:48,925 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:48,926 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:48,928 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:48,929 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:48,932 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:48,934 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:48,936 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:48,938 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:49,904 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:49,905 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:49,909 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:49,910 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:49,927 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:0 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:49,929 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:49,930 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:49,932 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:49,933 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:49,935 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:49,936 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:49,939 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:49,941 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:49,943 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:49,944 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:50,908 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:50,909 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:50,912 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:50,914 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:50,923 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:1 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:50,925 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:50,933 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:50,936 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:50,937 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:50,939 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000
2020-09-25 16:21:50,940 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-09-25 16:21:50,943 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:50,945 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:50,948 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:50,949 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:51,911 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M27, cmd:M27
2020-09-25 16:21:51,912 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-09-25 16:21:51,916 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-09-25 16:21:51,916 - octoprint.plugins.flashforge - DEBUG - write() M119
~M27
2020-09-25 16:21:51,927 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:0 Y-max:0 Z-max:1 | MachineStatus: BUILDING_FROM_SD | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-09-25 16:21:51,929 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-09-25 16:21:51,930 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-09-25 16:21:51,932 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M27 Received. | SD printing byte 0/1000 | ok |
2020-09-25 16:21:51,933 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M27 Received.
2020-09-25 16:21:51,935 - octoprint.plugins.flashforge - DEBUG - buffering: SD printing byte 0/1000

@devslashlol
Copy link
Author

This might be a silly question, but is this the internal SD card? The finder has two usb ports, so I can only assume that this SD card is internal to the unit...

@Mrnt
Copy link
Owner

Mrnt commented Sep 26, 2020

Yes there's an sd card embedded on the control board of most of these printers so when you connect using FlashPrint and upload a file it goes onto that internal SD card. On the display on the printer you can print files off the internal SD card or select the USB drive (if you have one plugged in).

@devslashlol
Copy link
Author

xyzCalibration_cube.gcode.txt
octoprint (10).log

here are files from this morning. log file is freshly new and shows the same behavior.

@Mrnt
Copy link
Owner

Mrnt commented Sep 27, 2020

Great, thanks for that. The file upload appears to be successful and the printer responds correctly to the M29 command which makes it look like it saved the file:
readraw() returns: CMD M29 Received. | Done saving file. | ok |
However clearly something is not quite right because when the printer is asked to print the file using the M23 command, it responds with:
readraw() returns: CMD M23 Received. | File opened: /data/xyzCalibration_cube.gcode Size: 0 | File selected | ok |
whereas my printer (not a Finder v2) responds with:
readraw() returns: CMD M23 Received. | File opened: 0:/user/xyzCalibration_cube.gcode Size: 250896 | File selected | ok |
Which makes me think that the file was in fact not transferred properly or not saved properly on the SD card. It could of course also be a firmware difference unfortunately.

Have you added any plugins besides the FlashForge one?

@Mrnt
Copy link
Owner

Mrnt commented Sep 27, 2020

And you have not filled up the memory on the SD card in the printer?

@Mrnt
Copy link
Owner

Mrnt commented Sep 27, 2020

Looking again at the log it appears you have several plugins installed. Perhaps try disabling all of the ones you added except the FlashForge one and then try uploading to SD again. Depending on the function of the plugin, some plugins will manipulate the file before it is uploaded to the SD card (I believe DisplayLayer does this) so it would be good to eliminate any of them as a possible cause.

@devslashlol
Copy link
Author

The FF memory is certainly not full. however, it does not show the 'xyz.gcode' file at all. I do have a number of plugins installed. I will report back.

@devslashlol
Copy link
Author

as an aside, if I try to stream the gcode to the finder directly, the printer responds, but makes horrible grinding noises and grossly over-extrudes.

@devslashlol
Copy link
Author

seems to be working, although not sure which plugin it might be having issues with. I think the one that presents a 'preheat' button might be it, but that's total conjecture.

@devslashlol
Copy link
Author

devslashlol commented Sep 28, 2020

I was able to do one print, but after I cancelled it, it didn't seem to work again. Power cycling, and trying again

edit: I tried with the xyz.gcode, then tried with one that had a much longer filename (And that did not seem to work, going to try with a shorter name)

@devslashlol
Copy link
Author

Ok, strange - xyz.gcode prints, but xy.gcode (a tile holder, much larger file) does not. I tried re-slicing the same model with what I thought were the same factory settings in S3D, and it .. doesn't seem to work. I do see the xyz.gcode on the onboard flash now. So this is weird. Let me play with it a little bit and see if I can get something repeatable for you to chew on. Thanks for working with me!

@devslashlol
Copy link
Author

...aaand now it's not working at all. :/

@devslashlol
Copy link
Author

not sure if this matters, but the port on the connection seems to be changing - when it connected before, it said 1:3, then 1:7 now 1:8...
image

@devslashlol
Copy link
Author

I think I figured it out - if the file is in a folder in octoprint, it doesn't load correctly. If I "Upload to SD" from the root, it works. If I try from a folder (in this case, "finder_test", it does not work. I think before I was changing directories unwittingly:
i.e. This print never worked:
2020-09-27 19:42:29,160 - octoprint.plugins.flashforge - INFO - Starting SDCard upload from finder_gcode/tiles3.gcode to finder_gcode/tiles3.gcode

this print did work:
2020-09-27 21:05:02,298 - octoprint.plugins.flashforge - INFO - Starting SDCard upload from tiles3.gcode to tiles3.gcode

...likely because there is no folder on the SD card called "finder_gcode" ?

@devslashlol
Copy link
Author

devslashlol commented Sep 28, 2020

...also, it seems that if you get the file to the printer, then the folder doesn't matter - although it is not clear if it transfers in a new file, or just uses the existing file. Anyway, I think I understand the problem. I went and deleted the xyz.gcode from the printer directly, and tried to load from the finder_tests folder in octoprint, and it did not print, as expected. what's interesting is that the printer display indicates that it has a job, and has the right name - but just never goes anywhere. It does 'home', but then that's it.

in short: if the file does not exist on the SD card, I have to Upload to SD from the root of octoprint. If the file name already exists, it appears to not matter what folder in octoprint I 'upload to SD' card, although I am not sure if it actually does file transfer or it just thinks it does - and runs whatever it has that has the same file name (old file).

@devslashlol
Copy link
Author

octoprint (16).log

my most recent log if it is helpful. this does not have a faulty load, but a successful one.

@Mrnt
Copy link
Owner

Mrnt commented Sep 28, 2020

Oh yes the file path is totally screwed up. On my printers the upload path should always be /user/ eg /user/xyzCalibration.gcode. It looks like the Finder 2 is expecting the upload paths to be /data/ eg /data/xyzCalibration.gcode. I don't quite understand yet how we ended up with subfolders in the path name eg /user/finder_gcode/xyzCalibration_cube.gcode.

@Mrnt
Copy link
Owner

Mrnt commented Sep 28, 2020

Not sure whether it is relevant - but what web browser are you using for the 'Upload to SD'?

@Mrnt
Copy link
Owner

Mrnt commented Sep 28, 2020

not sure if this matters, but the port on the connection seems to be changing - when it connected before, it said 1:3, then 1:7 now 1:8...
image

This will happen if you reboot the printer which you sometimes have to do if the connection isn't closed properly - eg for some reason the plugin did not get all of the response from the printer before the connection was closed. It reconnects on the Pi/computer and ends up taking a new port number (I'm guessing the USB stack is not sure if the previous port is still in use).

@Mrnt
Copy link
Owner

Mrnt commented Sep 28, 2020

I think I will do a special version for you to try, but please tell me how you are able to get a subfolder name into the filename when using 'Upload to SD' because I cannot reproduce it using Safari, Chrome or Firefox (I don't have access to a Window box right now).

@devslashlol
Copy link
Author

devslashlol commented Sep 28, 2020

Shoot, I'm sorry - I should have shown those steps. I'm just making a folder in Octoprint here in the GUI - just hitting 'create folder', and that is where the finder_gcode comes from... and what I mean when I say 'root' vs in the folder. (see next comment).
image

@devslashlol
Copy link
Author

2020-10-01 07:22:29,344 - octoprint.filemanager.analysis - INFO - Starting analysis of local:finder_gcode/xyz.gcode
2020-10-01 07:22:29,349 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,352 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python2 -m octoprint analysis gcode --speed-x=4800 --speed-y=4800 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/finder_gcode/xyz.gcode
2020-10-01 07:22:29,396 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,405 - octoprint.plugins.flashforge - INFO - Starting SDCard upload from finder_gcode/xyz.gcode to data/xyz.gcode
2020-10-01 07:22:29,519 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,522 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,524 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,526 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,530 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,533 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,535 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,539 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,541 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,545 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,546 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,549 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,550 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,551 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,553 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,555 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,557 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,559 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,560 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,562 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,564 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,566 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:29,569 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:30,396 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 07:22:30,396 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 07:22:30,398 - octoprint.plugins.flashforge - DEBUG - readraw() TIMEOUT
2020-10-01 07:22:30,399 - octoprint.plugins.flashforge - DEBUG - readraw() returns:
2020-10-01 07:22:30,400 - octoprint.plugins.flashforge - DEBUG - enable_keep_alive(False)
2020-10-01 07:22:30,400 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 07:22:30,403 - octoprint.plugins.flashforge - DEBUG - sendcommand() M104 S0 T0
2020-10-01 07:22:30,405 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 07:22:30,406 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 07:22:30,407 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:1 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 07:22:30,409 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 07:22:30,417 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M104 Received. | ok |
2020-10-01 07:22:30,417 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 07:22:30,418 - octoprint.plugins.flashforge - DEBUG - sendcommand() M104 S0 T1
2020-10-01 07:22:30,418 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 07:22:30,419 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 07:22:30,432 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M104 Received. | ok |
2020-10-01 07:22:30,432 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 07:22:30,433 - octoprint.plugins.flashforge - DEBUG - sendcommand() M140 S0
2020-10-01 07:22:30,433 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 07:22:30,435 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 07:22:30,446 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M140 Received. | ok |
2020-10-01 07:22:30,446 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 07:22:30,447 - octoprint.plugins.flashforge - DEBUG - sendcommand() M28 250929 0:/data/xyz.gcode
2020-10-01 07:22:30,448 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 07:22:30,449 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 5000
2020-10-01 07:22:31,102 - octoprint.filemanager.analysis - INFO - Analysis of entry local:finder_gcode/xyz.gcode finished, needed 1.76s
2020-10-01 07:22:31,141 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,143 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,145 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,146 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,149 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,151 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,153 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,155 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,158 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,160 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,161 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 07:22:31,163 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()

@devslashlol
Copy link
Author

I don't think it likes the /data bit.

@devslashlol
Copy link
Author

connection details:
Performing autodetection with 7 port/baudrate candidates: FlashForge Finder 3D Printer, port:1:26@115200, FlashForge Finder 3D Printer, port:1:26@250000, FlashForge Finder 3D Printer, port:1:26@230400, FlashForge Finder 3D Printer, port:1:26@57600, FlashForge Finder 3D Printer, port:1:26@38400, FlashForge Finder 3D Printer, port:1:26@19200, FlashForge Finder 3D Printer, port:1:26@9600
Trying port FlashForge Finder 3D Printer, port:1:26, baudrate 115200
Handshake attempt #1 with timeout 2.0s
Connected to: <octoprint_flashforge.flashforge.FlashForge object at 0xab482070>, starting monitor
Send: M601 S0
Recv: CMD M601 Received.
Recv: Control Success.
Recv: ok
Changing monitoring state from "Detecting serial connection" to "Operational"
Send: M115
Recv: CMD M115 Received.
Recv: Machine Type: Flashforge Finder
Recv: Machine Name: My 3D Printer
Recv: FIRMWARE_NAME: FlashForge VER: v2.7.2.366 F2.12 20191224
Recv: SN: SNFFF226299
Recv: X: 140 Y: 140 Z: 140
Recv: Tool Count:1
Recv: ok
Send: M21
Communication timeout while idle, trying to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
Send: M20
Communication timeout while idle, trying to trigger response from printer. Configure long running commands or increase communication timeout if that happens regularly on specific commands or long moves.
Send: M146 r255 g255 b255
Recv: CMD M146 Received.
Recv: ok
Send: M105
Recv: CMD M105 Received.
Recv: T0:26 /0 B:0/0
Recv: ok
Send: M105
Recv: CMD M105 Received.
Recv: T0:26 /0 B:0/0
Recv: ok
Send: M105
Recv: CMD M105 Received.
Recv: T0:26 /0 B:0/0
Recv: ok
Send: M105
Recv: CMD M105 Received.
Recv: T0:26 /0 B:0/0
Recv: ok
Send: M105
Recv: CMD M105 Received.
Recv: T0:26 /0 B:0/0
Recv: ok

I noticed it has a timeout up front, which seems weird. Timeouts are all specified as in the config page (5,2,2 etc)

@Mrnt
Copy link
Owner

Mrnt commented Oct 1, 2020

re M21 - I just looked again log file you uploaded and it seems the printer never responded to the M21 command at connect. That would cause the OctoPrint to wait for a response and eventually generate a timeout at which point it sends an M105 (get temperature) command to see if the printer is still there. I cannot tell if the command does anything on any of the FlashForge printers so maybe the answer is to just stop OctoPrint from sending it. Probably likewise for the M20 command which the printer is supposed to reply with the list of files on the SD card. The older printers at least respond to the command (even though they don't provide a list of files), so no timeout occurs.

re SD card uploads - with the dev version does it never start the upload process? Does the printer not respond to the sendcommand() M28 250929 0:/data/xyz.gcode?

@Mrnt
Copy link
Owner

Mrnt commented Oct 1, 2020

I just pushed an update to the dev branch to eliminate the M20, M21 commands. Please reinstall from the dev URL (as described above) - that should eliminate the communication timeout messages immediately after connecting. Can't tell yet whether that has an impact on the SD Upload issue.

@devslashlol
Copy link
Author

Ok, that seemed to resolve the timeouts (m20/21), but the streaming still isn't working. It has 'broken' between the 'stable' 2.4 and the dev 2.4... so I can't print anything at all at least Upload from SD-wise

@devslashlol
Copy link
Author

Here is what the log says:
2020-10-01 15:00:14,593 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 15:00:14,597 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 15:00:14,601 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 15:00:14,612 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:48 /0 B:0/0 | ok |
2020-10-01 15:00:14,612 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 15:00:14,613 - octoprint.plugins.flashforge - DEBUG - buffering: T0:48 /0 B:0/0
2020-10-01 15:00:14,615 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 15:00:14,618 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,620 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 15:00:14,623 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,626 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,628 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:14,695 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:14,696 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:14,705 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:14,706 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,708 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:16,698 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:16,699 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:16,708 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:16,710 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:16,710 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:16,844 - tornado.application - ERROR - Uncaught exception GET /downloads/logs/octoprint.log (::ffff:192.168.1.173)
Traceback (most recent call last):
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/web.py", line 1592, in _execute
result = yield result
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/gen.py", line 1133, in run
value = future.result()
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/concurrent.py", line 261, in result
raise_exc_info(self._exc_info)
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/gen.py", line 1147, in run
yielded = self.gen.send(value)
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/web.py", line 2528, in get
yield self.flush()
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/web.py", line 1000, in flush
return self.request.connection.write(chunk, callback=callback)
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/http1connection.py", line 451, in write
self._pending_write = self.stream.write(self._format_chunk(chunk))
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/http1connection.py", line 424, in _format_chunk
"Tried to write more data than Content-Length")
HTTPOutputError: Tried to write more data than Content-Length
2020-10-01 15:00:16,846 - tornado.general - ERROR - Cannot send error response after headers written

@Mrnt
Copy link
Owner

Mrnt commented Oct 1, 2020

Not sure what is going on there - did some of the log not paste in correctly? We should at least see the M28 command being issued.

@devslashlol
Copy link
Author

more log:
2020-10-01 14:58:46,962 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:46,967 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:46,976 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:46,982 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:46,985 - octoprint.filemanager.analysis - INFO - Starting analysis of local:finder_gcode/xyz.gcode
2020-10-01 14:58:46,987 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:46,989 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python2 -m octoprint analysis gcode --speed-x=4800 --speed-y=4800 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/finder_gcode/xyz.gcode
2020-10-01 14:58:46,996 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,012 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,048 - octoprint.plugins.flashforge - INFO - Starting SDCard upload from finder_gcode/xyz.gcode to data/xyz.gcode
2020-10-01 14:58:47,093 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,096 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,098 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,100 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,104 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,106 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,108 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,111 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,114 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,117 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,118 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,120 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,122 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,124 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,126 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,128 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,129 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,131 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,133 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,136 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,138 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,139 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:47,142 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,537 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:58:48,538 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:58:48,547 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:58:48,548 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:48,548 - octoprint.plugins.flashforge - DEBUG - enable_keep_alive(False)
2020-10-01 14:58:48,552 - octoprint.plugins.flashforge - DEBUG - sendcommand() M104 S0 T0
2020-10-01 14:58:48,554 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:58:48,555 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 14:58:48,564 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M104 Received. | ok |
2020-10-01 14:58:48,564 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 14:58:48,564 - octoprint.plugins.flashforge - DEBUG - sendcommand() M104 S0 T1
2020-10-01 14:58:48,566 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:58:48,567 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 14:58:48,577 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M104 Received. | ok |
2020-10-01 14:58:48,577 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 14:58:48,578 - octoprint.plugins.flashforge - DEBUG - sendcommand() M140 S0
2020-10-01 14:58:48,579 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:58:48,580 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 14:58:48,590 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M140 Received. | ok |
2020-10-01 14:58:48,590 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 14:58:48,591 - octoprint.plugins.flashforge - DEBUG - sendcommand() M28 250929 0:/data/xyz.gcode
2020-10-01 14:58:48,592 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:58:48,593 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 5000
2020-10-01 14:58:48,719 - octoprint.filemanager.analysis - INFO - Analysis of entry local:finder_gcode/xyz.gcode finished, needed 1.73s
2020-10-01 14:58:48,742 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,744 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,746 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,747 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,750 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,752 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,754 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,756 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,761 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,764 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,765 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,768 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,769 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,770 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,771 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,773 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,774 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,776 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,777 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,779 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,780 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,782 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:48,783 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:49,533 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:58:49,534 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:58:49,537 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:58:53,595 - octoprint.plugins.flashforge - DEBUG - readraw() TIMEOUT
2020-10-01 14:58:53,595 - octoprint.plugins.flashforge - DEBUG - readraw() returns:
2020-10-01 14:58:53,596 - octoprint.plugins.flashforge - INFO - Upload failed: file transfer not started
2020-10-01 14:58:53,596 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:58:53,596 - octoprint.plugins.flashforge - DEBUG - enable_keep_alive(True)
2020-10-01 14:58:53,597 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:58:53,613 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:54 /0 B:0/0 | ok |
2020-10-01 14:58:53,614 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:58:53,614 - octoprint.plugins.flashforge - DEBUG - buffering: T0:54 /0 B:0/0
2020-10-01 14:58:53,616 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:58:53,618 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:53,625 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:58:53,632 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:53,635 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:53,636 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:58:53,683 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,685 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,689 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,692 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,696 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,698 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,703 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,705 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,709 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,713 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,717 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,721 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,724 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:53,729 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:58:54,536 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:58:54,537 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:58:54,540 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:58:54,541 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:58:54,550 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:54 /0 B:0/0 | ok |
2020-10-01 14:58:54,551 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:58:54,553 - octoprint.plugins.flashforge - DEBUG - buffering: T0:54 /0 B:0/0
2020-10-01 14:58:54,554 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:58:54,557 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:54,559 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:58:54,561 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:54,564 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:54,565 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:58:55,546 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:58:55,547 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:58:55,556 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:58:55,558 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:55,565 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:58:57,550 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:58:57,551 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:58:57,567 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:58:57,569 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:57,577 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:58:59,540 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:58:59,541 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:58:59,543 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:58:59,548 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:58:59,554 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:58:59,556 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:58:59,559 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:53 /0 B:0/0 | ok |
2020-10-01 14:58:59,560 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:58:59,562 - octoprint.plugins.flashforge - DEBUG - buffering: T0:53 /0 B:0/0
2020-10-01 14:58:59,563 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:58:59,565 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:59,568 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:58:59,571 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:59,574 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:59,575 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:58:59,576 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:58:59,579 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:58:59,580 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:01,559 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:01,560 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:01,569 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:01,571 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:01,578 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:03,562 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:03,563 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:03,571 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:03,573 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:03,579 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:04,543 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:04,544 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:04,546 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:04,553 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:04,561 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:52 /0 B:0/0 | ok |
2020-10-01 14:59:04,561 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:04,563 - octoprint.plugins.flashforge - DEBUG - buffering: T0:52 /0 B:0/0
2020-10-01 14:59:04,565 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:04,568 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:04,570 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:04,572 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:04,575 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:04,576 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:05,566 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:05,567 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:05,577 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:05,578 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:05,583 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:07,570 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:07,571 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:07,580 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:07,582 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:07,587 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:09,546 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:09,547 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:09,549 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:09,550 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:09,563 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:52 /0 B:0/0 | ok |
2020-10-01 14:59:09,564 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:09,565 - octoprint.plugins.flashforge - DEBUG - buffering: T0:52 /0 B:0/0
2020-10-01 14:59:09,567 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:09,569 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:09,571 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:09,574 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:09,574 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:09,576 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:09,579 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:09,581 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:09,586 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:09,588 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:09,589 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:11,580 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:11,582 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:11,590 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:11,591 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:11,598 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:13,585 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:13,585 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:13,595 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:13,597 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:13,604 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:14,092 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,104 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,148 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,153 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,157 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,162 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,167 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,172 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,177 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,179 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,182 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,183 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,185 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,186 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,188 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,189 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,191 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,192 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,193 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,195 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,196 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,198 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,199 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:14,549 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:14,549 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:14,550 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:14,553 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:14,560 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:51 /0 B:0/0 | ok |
2020-10-01 14:59:14,561 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:14,561 - octoprint.plugins.flashforge - DEBUG - buffering: T0:51 /0 B:0/0
2020-10-01 14:59:14,562 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:14,563 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:14,564 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:14,565 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:14,567 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:14,567 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:15,588 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:15,589 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:15,600 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:15,601 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:15,607 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:17,592 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:17,593 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:17,601 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:17,605 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:17,610 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:18,497 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,501 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,512 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,517 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,520 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,521 - octoprint.filemanager.analysis - INFO - Starting analysis of local:xyz.gcode
2020-10-01 14:59:18,524 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,534 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python2 -m octoprint analysis gcode --speed-x=4800 --speed-y=4800 --max-t=10 --throttle=0.0 --throttle-lines=100 /home/pi/.octoprint/uploads/xyz.gcode
2020-10-01 14:59:18,577 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,584 - octoprint.plugins.flashforge - INFO - Starting SDCard upload from xyz.gcode to data/xyz.gcode
2020-10-01 14:59:18,754 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,757 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,760 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,762 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,765 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,769 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,771 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,774 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,777 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,781 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,783 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,785 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,787 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,789 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,791 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,793 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,794 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,796 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,798 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,801 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,803 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:18,805 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:19,551 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:19,551 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:19,553 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:19,556 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:19,564 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:51 /0 B:0/0 | ok |
2020-10-01 14:59:19,564 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:19,565 - octoprint.plugins.flashforge - DEBUG - buffering: T0:51 /0 B:0/0
2020-10-01 14:59:19,566 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:19,567 - octoprint.plugins.flashforge - DEBUG - enable_keep_alive(False)
2020-10-01 14:59:19,568 - octoprint.plugins.flashforge - DEBUG - sendcommand() M104 S0 T0
2020-10-01 14:59:19,569 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:19,569 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:59:19,571 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 14:59:19,581 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M104 Received. | ok |
2020-10-01 14:59:19,582 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 14:59:19,583 - octoprint.plugins.flashforge - DEBUG - sendcommand() M104 S0 T1
2020-10-01 14:59:19,583 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:59:19,585 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 14:59:19,595 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M104 Received. | ok |
2020-10-01 14:59:19,595 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 14:59:19,595 - octoprint.plugins.flashforge - DEBUG - sendcommand() M140 S0
2020-10-01 14:59:19,596 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:59:19,597 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 2000
2020-10-01 14:59:19,608 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M140 Received. | ok |
2020-10-01 14:59:19,608 - octoprint.plugins.flashforge - DEBUG - sendcommand() got an ok
2020-10-01 14:59:19,609 - octoprint.plugins.flashforge - DEBUG - sendcommand() M28 250929 0:/data/xyz.gcode
2020-10-01 14:59:19,610 - octoprint.plugins.flashforge - DEBUG - writeraw() called by thread FlashForge.SD_Uploader
2020-10-01 14:59:19,611 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: FlashForge.SD_Uploader, timeout: 5000
2020-10-01 14:59:20,358 - octoprint.filemanager.analysis - INFO - Analysis of entry local:xyz.gcode finished, needed 1.84s
2020-10-01 14:59:20,475 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,477 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,478 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,481 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,482 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,485 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,487 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,489 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,491 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,494 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,494 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,496 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,497 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,498 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,499 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,501 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,502 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,504 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,506 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,507 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,509 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,510 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:20,511 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,553 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:24,554 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:24,612 - octoprint.plugins.flashforge - DEBUG - readraw() TIMEOUT
2020-10-01 14:59:24,613 - octoprint.plugins.flashforge - DEBUG - readraw() returns:
2020-10-01 14:59:24,614 - octoprint.plugins.flashforge - INFO - Upload failed: file transfer not started
2020-10-01 14:59:24,616 - octoprint.plugins.flashforge - DEBUG - enable_keep_alive(True)
2020-10-01 14:59:24,620 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:24,633 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:24,636 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:24,637 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:24,638 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:24,641 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:24,647 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:50 /0 B:0/0 | ok |
2020-10-01 14:59:24,647 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:24,648 - octoprint.plugins.flashforge - DEBUG - buffering: T0:50 /0 B:0/0
2020-10-01 14:59:24,650 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:24,652 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:24,654 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:24,657 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:24,659 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:24,660 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:24,695 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,697 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,701 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,704 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,708 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,710 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,714 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,717 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,721 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,724 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,728 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,731 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,734 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:24,737 - octoprint.plugins.flashforge - DEBUG - get_extension_tree()
2020-10-01 14:59:26,605 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:26,607 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:26,616 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:26,617 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:26,624 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:28,609 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:28,609 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:28,619 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:28,624 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:28,628 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:29,557 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:29,558 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:29,561 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:29,568 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:29,578 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:50 /0 B:0/0 | ok |
2020-10-01 14:59:29,579 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:29,581 - octoprint.plugins.flashforge - DEBUG - buffering: T0:50 /0 B:0/0
2020-10-01 14:59:29,582 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:29,585 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:29,587 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:29,590 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:29,593 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:29,595 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:30,613 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:30,614 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:30,622 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:30,624 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:30,631 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:32,617 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:32,618 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:32,628 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:32,630 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:32,639 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:34,561 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:34,562 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:34,565 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:34,573 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:34,581 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:50 /0 B:0/0 | ok |
2020-10-01 14:59:34,582 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:34,583 - octoprint.plugins.flashforge - DEBUG - buffering: T0:50 /0 B:0/0
2020-10-01 14:59:34,585 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:34,588 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:34,590 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:34,592 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:34,595 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:34,597 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:34,621 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:34,622 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:34,631 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:34,632 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:34,633 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:36,625 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:36,626 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:36,636 - octoprint.plugins.flashforge - DEBUG - readraw() TIMEOUT
2020-10-01 14:59:36,636 - octoprint.plugins.flashforge - DEBUG - readraw() returns:
2020-10-01 14:59:36,639 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:36,647 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:36,650 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:36,652 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:36,653 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:38,629 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:38,630 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:38,640 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:38,641 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:38,650 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:39,565 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:39,566 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:39,569 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:39,570 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:39,584 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:49 /0 B:0/0 | ok |
2020-10-01 14:59:39,585 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:39,586 - octoprint.plugins.flashforge - DEBUG - buffering: T0:49 /0 B:0/0
2020-10-01 14:59:39,588 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:39,591 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:39,592 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:39,595 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:39,598 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:39,599 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:40,633 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:40,633 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:40,641 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:40,642 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:40,651 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:42,635 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:42,636 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:42,645 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:42,647 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:42,651 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:44,569 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:44,570 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:44,573 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:44,580 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:44,589 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:49 /0 B:0/0 | ok |
2020-10-01 14:59:44,590 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:44,592 - octoprint.plugins.flashforge - DEBUG - buffering: T0:49 /0 B:0/0
2020-10-01 14:59:44,594 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:44,596 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:44,599 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:44,601 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:44,604 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:44,605 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:44,638 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:44,639 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:44,648 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:44,651 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:44,652 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:46,642 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:46,643 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:46,652 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:46,654 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:46,661 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:48,646 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:48,647 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:48,657 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:48,658 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:48,659 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:49,573 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:49,573 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:49,576 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:49,582 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:49,590 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:49 /0 B:0/0 | ok |
2020-10-01 14:59:49,591 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:49,592 - octoprint.plugins.flashforge - DEBUG - buffering: T0:49 /0 B:0/0
2020-10-01 14:59:49,594 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:49,596 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:49,599 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:49,602 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:49,605 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:49,605 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:50,650 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:50,651 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:50,660 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:50,662 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:50,669 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:52,654 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:52,655 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:52,664 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:52,666 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:52,675 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:54,576 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:54,577 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:54,580 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:54,584 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:54,601 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:49 /0 B:0/0 | ok |
2020-10-01 14:59:54,602 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:54,604 - octoprint.plugins.flashforge - DEBUG - buffering: T0:49 /0 B:0/0
2020-10-01 14:59:54,605 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:54,608 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:54,610 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:54,613 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:54,615 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:54,617 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:54,658 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:54,659 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:54,669 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:54,671 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:54,672 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:56,662 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:56,664 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:56,672 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:56,674 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:56,681 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:58,667 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 14:59:58,667 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 14:59:58,677 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 14:59:58,679 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:58,688 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 14:59:59,580 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 14:59:59,581 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 14:59:59,584 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 14:59:59,589 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 14:59:59,603 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:48 /0 B:0/0 | ok |
2020-10-01 14:59:59,605 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 14:59:59,607 - octoprint.plugins.flashforge - DEBUG - buffering: T0:48 /0 B:0/0
2020-10-01 14:59:59,608 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 14:59:59,610 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:59,612 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 14:59:59,615 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:59,618 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 14:59:59,620 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:00,671 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:00,672 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:00,680 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:00,683 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:00,691 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:02,675 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:02,676 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:02,686 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:02,688 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:02,695 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:04,584 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 15:00:04,585 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 15:00:04,588 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 15:00:04,591 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 15:00:04,610 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:48 /0 B:0/0 | ok |
2020-10-01 15:00:04,611 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 15:00:04,613 - octoprint.plugins.flashforge - DEBUG - buffering: T0:48 /0 B:0/0
2020-10-01 15:00:04,614 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 15:00:04,617 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:04,619 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 15:00:04,621 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:04,625 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:04,625 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:04,679 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:04,680 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:04,689 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:04,691 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:04,692 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:06,682 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:06,683 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:06,693 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:06,695 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:06,696 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:08,686 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:08,687 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:08,696 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:08,698 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:08,706 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:09,588 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 15:00:09,589 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 15:00:09,592 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 15:00:09,600 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 15:00:09,608 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:48 /0 B:0/0 | ok |
2020-10-01 15:00:09,608 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 15:00:09,610 - octoprint.plugins.flashforge - DEBUG - buffering: T0:48 /0 B:0/0
2020-10-01 15:00:09,611 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 15:00:09,614 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:09,616 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 15:00:09,619 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:09,622 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:09,623 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:10,689 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:10,690 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:10,699 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:10,700 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:10,702 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:12,691 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:12,692 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:12,701 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:12,702 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:12,709 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:14,592 - octoprint.plugins.flashforge - DEBUG - rewrite_gcode(): gcode:M105, cmd:M105
2020-10-01 15:00:14,593 - octoprint.plugins.flashforge - DEBUG - is_sd_printing()
2020-10-01 15:00:14,597 - octoprint.plugins.flashforge - DEBUG - write() called by thread comm.sending_thread
2020-10-01 15:00:14,601 - octoprint.plugins.flashforge - DEBUG - write() M105
2020-10-01 15:00:14,612 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M105 Received. | T0:48 /0 B:0/0 | ok |
2020-10-01 15:00:14,612 - octoprint.plugins.flashforge - DEBUG - buffering: CMD M105 Received.
2020-10-01 15:00:14,613 - octoprint.plugins.flashforge - DEBUG - buffering: T0:48 /0 B:0/0
2020-10-01 15:00:14,615 - octoprint.plugins.flashforge - DEBUG - buffering: ok
2020-10-01 15:00:14,618 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,620 - octoprint.util.comm - INFO - Externally triggered heatup detected
2020-10-01 15:00:14,623 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,626 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,628 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:14,695 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:14,696 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:14,705 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:14,706 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:14,708 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:16,698 - octoprint.plugins.flashforge - DEBUG - write() called by thread FlashForge.Keep_Alive
2020-10-01 15:00:16,699 - octoprint.plugins.flashforge - DEBUG - write() M119
2020-10-01 15:00:16,708 - octoprint.plugins.flashforge - DEBUG - readraw() returns: CMD M119 Received. | Endstop: X-max:1 Y-max:0 Z-max:1 | MachineStatus: READY | MoveMode: READY | Status: S:1 L:0 J:0 F:0 | ok |
2020-10-01 15:00:16,710 - octoprint.plugins.flashforge - DEBUG - readline() called by thread comm._monitor
2020-10-01 15:00:16,710 - octoprint.plugins.flashforge - DEBUG - readraw() called by thread: comm._monitor, timeout: 2000
2020-10-01 15:00:16,844 - tornado.application - ERROR - Uncaught exception GET /downloads/logs/octoprint.log (::ffff:192.168.1.173)
Traceback (most recent call last):
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/web.py", line 1592, in _execute
result = yield result
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/gen.py", line 1133, in run
value = future.result()
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/concurrent.py", line 261, in result
raise_exc_info(self._exc_info)
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/gen.py", line 1147, in run
yielded = self.gen.send(value)
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/web.py", line 2528, in get
yield self.flush()
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/web.py", line 1000, in flush
return self.request.connection.write(chunk, callback=callback)
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/http1connection.py", line 451, in write
self._pending_write = self.stream.write(self._format_chunk(chunk))
File "/home/pi/oprint/local/lib/python2.7/site-packages/tornado/http1connection.py", line 424, in _format_chunk
"Tried to write more data than Content-Length")
HTTPOutputError: Tried to write more data than Content-Length
2020-10-01 15:00:16,846 - tornado.general - ERROR - Cannot send error response after headers written

@devslashlol
Copy link
Author

the shmutz at the end might not be your plugin

@Mrnt
Copy link
Owner

Mrnt commented Oct 1, 2020

Just changed it back to using /user/ as the upload path if you want to try reinstalling dev

@devslashlol
Copy link
Author

ok, doing so.

@devslashlol
Copy link
Author

ok, that fixed the Upload to SD thing. and the timeouts are gone. Let's see if the print carries through.. it usually stops reporting/auto reporting about 24 'bytes' in.. and it never reports the actual number of bytes.. it's always x/1000

@devslashlol
Copy link
Author

any tinkering with timeouts? the auto reports seem to work fine if there is no print job going, but once the print job gets going, the reports seem ... slow.

@Mrnt
Copy link
Owner

Mrnt commented Oct 1, 2020

Do you mean the print status when printing from the SD card? That looks to be set at a default of 1s using the "SD status interval (polling)" setting.

@devslashlol
Copy link
Author

Yes, I'm just saying that it stops auto updating after a while. In the case of the print I am running right now, the update stopped happening before the temp even got to 200C (the print is well under way at this point)
image

@devslashlol
Copy link
Author

image

@devslashlol
Copy link
Author

devslashlol commented Oct 1, 2020

I hit the 'print' button after it appeared to have stalled out to see if it would unstick, but to no avail
My guess is that the connection stealthily died due to something, because I have to power cycle the printer to get it to reconnect.

@Mrnt
Copy link
Owner

Mrnt commented Oct 1, 2020

Did the printer actually stop or is it OctoPrint no longer getting updates?

@devslashlol
Copy link
Author

Octoprint is no longer getting updates. The printer goes along happily once I do the Upload to SD card (with your latest refactor of the directory)

@Mrnt
Copy link
Owner

Mrnt commented Oct 1, 2020

Can you upload the complete log that you have right now so I can see the whole context?

@devslashlol
Copy link
Author

octoprint (6).log

@Mrnt
Copy link
Owner

Mrnt commented Oct 2, 2020

The printer seems to stop responding but the weird thing is the plugin does not generate a timeout waiting for a response from the printer.

@Mrnt
Copy link
Owner

Mrnt commented Oct 2, 2020

I did see this:

2020-10-01 07:28:19,469 - octoprint.plugins.pi_support - WARNING - This Raspberry Pi is reporting problems that might lead to bad performance or errors caused by overheating or insufficient power.
!!! FREQUENCY CAPPING DUE TO OVERHEATING REPORTED !!! Improve cooling on the Pi's CPU and GPU.

which may or may not be relevant

@devslashlol
Copy link
Author

Aye - I've seen that. I'm not sure if it is significant or not. I had no issues with my Creality that took commands right from the unit. I can't imagine octoprint being a hard-hidding application.
Still something to look into, I suppose.

@devslashlol
Copy link
Author

One thing is that the icon only blinks when there is an ongoing issue. It is not blinking - so for a temp issue (vs undervoltage issue), I am not seeing this when printing. That notification is from 7am in the morning, not now.
But I did go and move the Pi so that there is less of a 'heat pocket' underneath it and so that the natural convection can clear a little easier. I do have fins on the chips.

@devslashlol
Copy link
Author

devslashlol commented Oct 2, 2020

ok, I will admit that since I power cycled the Pi, and turned it a little so that natural convection works a little better, so far no hang ups. So you might be onto something with your notification of the warning. I never said I was a Good User!

edit - it quit around 125/1000. The screen saver/lock screen had also come up, not sure if that matters (I can't imagine it would, it shouldn't stop sending queries...)

@devslashlol
Copy link
Author

devslashlol commented Oct 2, 2020

Can we change the M27 polling frequency to something more reasonable like once every 30 seconds? 1/s seems excessive for something that takes hours...
edit: I see this parameter. Any reason not to change this to 30 or so?

@Mrnt
Copy link
Owner

Mrnt commented Oct 2, 2020

Sure - you can change M27 polling to whatever you want. The plugin is not setting those - they are the default for OctoPrint and certainly if your prints are typically hours long then an update every 30s makes more sense. I don't think it will affect the issue you are seeing where the connection between OctoPrint and the printer is effectively stalling.

Do you have the pi running a Linux desktop connected to a monitor? I know if I run OctoPrint on my Mac it tries to put the USB connections to sleep when the Mac tries to go to sleep causing the connection to the printer to drop, though it is pretty obvious in the log when that happens. So yes, if you have it running a desktop OS with power saving enabled I think there is a chance it will try to put the USB connections to sleep if it is trying to go into a low power or sleep state.

@Mrnt Mrnt added the Finder v2 Updated version of the original Finder label Oct 2, 2020
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Finder v2 Updated version of the original Finder
Projects
None yet
Development

No branches or pull requests

2 participants