Printer doesn't heat from gcode

Radeonx · May 14, 2020, 4:29pm

What is the problem?
My Printer doesn't heat up the extruder or bed befor starting the Print.
This problem just occurs, when I have the webcam connected.
Also the extruder dosn't seem to work, if i set temperature manually.

What did you already try to solve it?
I checked the GCode and the temperature and wait for temperature are set.

M104 S215 ; set extruder temp
M140 S70 ; set bed temp
M190 S70 ; wait for bed temp
M109 S215 ; wait for extruder temp

Logs
Octoprint.log

2020-05-14 17:23:35,350 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/webassets...
2020-05-14 17:23:35,395 - octoprint.server - INFO - Reset webasset folder /home/pi/.octoprint/generated/.webassets-cache...
2020-05-14 17:23:35,449 - octoprint.plugins.octolapse - INFO - Octolapse is loading assets.
2020-05-14 17:23:37,592 - octoprint.server - INFO - Shutting down intermediary server...
2020-05-14 17:23:37,924 - octoprint.server - INFO - Intermediary server shut down
2020-05-14 17:23:37,929 - octoprint.events - INFO - Processing startup event, this is our first event
2020-05-14 17:23:37,933 - octoprint.events - INFO - Adding 1 events to queue that were held back before startup event
2020-05-14 17:23:37,945 - octoprint.filemanager - INFO - Adding backlog items from all storage types to analysis queue...
2020-05-14 17:23:37,953 - octoprint.server - INFO - Autoconnect on startup is configured, trying to connect to the printer...
2020-05-14 17:23:38,023 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2020-05-14 17:23:38,520 - octoprint.filemanager.analysis - INFO - Starting analysis of local:Hull_Part_3_0.15mm_PLA_MK3S_11h15m.gcode
2020-05-14 17:23:38,526 - octoprint.filemanager.analysis - INFO - Invoking analysis command: /home/pi/oprint/bin/python2 -m octoprint analysis gcode --speed-x=6000 --speed-y=6000 --max-t=10 --throttle=0.01 --throttle-lines=100 /home/pi/.octoprint/uploads/Hull_Part_3_0.15mm_PLA_MK3S_11h15m.gcode
2020-05-14 17:23:38,529 - octoprint.filemanager - INFO - Added 1 items from storage type "local" to analysis queue
2020-05-14 17:23:41,439 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2020-05-14 17:23:41,549 - octoprint.server - INFO - Trying to connect to configured serial port /dev/ttyAMA0
2020-05-14 17:23:41,699 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2020-05-14 17:23:41,698 - octoprint.server.util.watchdog - INFO - Running initial scan on watched folder...
2020-05-14 17:23:41,708 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Detecting baudrate"
2020-05-14 17:23:41,720 - octoprint.server.util.watchdog - INFO - ... initial scan done.
2020-05-14 17:23:41,762 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _http._tcp
2020-05-14 17:23:41,803 - octoprint.plugins.discovery - INFO - Registered 'OctoPrint instance on octopi' for _octoprint._tcp
2020-05-14 17:23:41,817 - octoprint.plugins.discovery - INFO - Registered OctoPrint instance on octopi for SSDP
2020-05-14 17:23:41,908 - 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.
!!! UNDERVOLTAGE REPORTED !!! Make sure that the power supply and power cable are capable of supplying enough voltage and current to your Pi.
2020-05-14 17:23:41,921 - octoprint.server - INFO - Listening on http://127.0.0.1:5000
2020-05-14 17:23:41,930 - octoprint.plugins.octolapse - INFO - Loading existing settings file from: /home/pi/.octoprint/data/octolapse/settings.json.
2020-05-14 17:23:42,271 - octoprint.plugins.pluginmanager - INFO - Loaded plugin repository data from disk, was still valid
2020-05-14 17:23:42,881 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-14 17:23:43,048 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting baudrate" to "Operational"
2020-05-14 17:23:43,058 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-14 17:23:43,141 - octoprint.util.comm - INFO - Printer reports firmware name "Prusa-Firmware 3.8.1 based on Marlin"
2020-05-14 17:23:55,888 - octoprint.util.pip - INFO - Using "/home/pi/oprint/bin/python2 -m pip" as command to invoke pip
2020-05-14 17:23:56,106 - octoprint.util.pip - INFO - pip installs to /home/pi/oprint/lib/python2.7/site-packages (writable -> yes), --user flag needed -> no, virtual env -> yes
2020-05-14 17:23:56,412 - octoprint.util.pip - INFO - ==> pip ok -> yes
2020-05-14 17:23:56,915 - octoprint.plugins.pluginmanager - INFO - Loaded notice data from disk, was still valid
2020-05-14 17:23:57,183 - octoprint.server.util.sockjs - INFO - New connection from client: 2a02:1205:c693:cd10:a9b0:65f4:52a2:e113
2020-05-14 17:23:58,204 - octoprint.server.util.flask - INFO - Passively logging in user radeonx from 2a02:1205:c693:cd10:a9b0:65f4:52a2:e113
2020-05-14 17:23:58,223 - octoprint.access.users - INFO - Logged in user: radeonx
2020-05-14 17:23:58,312 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=de', 'path': '/', 'base_url': 'http://octopi/'}
2020-05-14 17:23:58,578 - octoprint.server.util.sockjs - INFO - User radeonx logged in on the socket from client 2a02:1205:c693:cd10:a9b0:65f4:52a2:e113
2020-05-14 17:24:44,984 - octoprint.server.preemptive_cache - INFO - ... done in 46.67s
2020-05-14 17:24:44,986 - octoprint.server.preemptive_cache - INFO - Preemptively caching / (ui _default) for {'query_string': 'l10n=de', 'path': '/', 'base_url': 'http://192.168.1.109/'}
2020-05-14 17:24:52,147 - octoprint.server.preemptive_cache - INFO - ... done in 7.16s

Additional information about your setup

OctoPrint version: 1.4.0
Powered from: Einsy Board or directly
Raspberry Pi: 3b+ with Webcam
Printer: Prusa i3 MK3S
Profile:
X: 250 mm
Y: 210 mm
Z: 210 mm

Individuelle Bounding Box: true
X: 0 250
Y: -4 220
Z: -1 210

Ewald_Ikemann · May 14, 2020, 5:42pm

Hello @Radeonx!

First there is this:

Check your power source. A charger is not suitable to run a Raspberry Pi.

Second:

Could you also please share the serial.log (you may have to enable it)

Radeonx · May 15, 2020, 3:11pm

Hi,
Thanks for your reply.
The Power source issue is fixed.

But the Printer still doesn't heat up befor starting to print.

Serial.log

2020-05-15 15:54:18,257 - Connecting to: /dev/ttyAMA0
2020-05-15 15:54:18,274 - Changing monitoring state from "Offline" to "Opening serial port"
2020-05-15 15:54:18,287 - Connected to: Serial<id=0x6f007b10, open=True>(port='/dev/ttyAMA0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2020-05-15 15:54:18,292 - Changing monitoring state from "Opening serial port" to "Connecting"
2020-05-15 15:54:48,380 - There was a timeout while trying to connect to the printer
2020-05-15 15:54:48,387 - Changing monitoring state from "Connecting" to "Offline"
2020-05-15 15:54:48,398 - Connection closed, closing down monitor
2020-05-15 15:57:21,436 - Connecting to: /dev/ttyAMA0
2020-05-15 15:57:21,453 - Changing monitoring state from "Offline" to "Opening serial port"
2020-05-15 15:57:21,463 - Connected to: Serial<id=0x6f040db0, open=True>(port='/dev/ttyAMA0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2020-05-15 15:57:21,463 - Changing monitoring state from "Opening serial port" to "Connecting"
2020-05-15 15:57:38,342 - Connection closed, closing down monitor
2020-05-15 15:57:38,347 - Changing monitoring state from "Connecting" to "Offline"
2020-05-15 15:57:43,127 - Changing monitoring state from "Offline" to "Detecting serial port"
2020-05-15 15:57:43,167 - Serial port list: ['/dev/ttyAMA0', u'/dev/ttyS0']
2020-05-15 15:57:43,167 - Trying /dev/ttyAMA0
2020-05-15 15:57:48,505 - Could not connect to or enter programming mode on /dev/ttyAMA0, might not be a printer or just not allow programming mode
2020-05-15 15:57:48,518 - Trying /dev/ttyS0
2020-05-15 15:57:53,851 - Could not connect to or enter programming mode on /dev/ttyS0, might not be a printer or just not allow programming mode
2020-05-15 15:57:53,865 - Changing monitoring state from "Detecting serial port" to "Error: Failed to autodetect serial port, please set it manually."
2020-05-15 15:57:53,873 - Failed to autodetect serial port, please set it manually.
2020-05-15 15:57:58,418 - Connecting to: /dev/ttyAMA0
2020-05-15 15:57:58,435 - Changing monitoring state from "Offline" to "Opening serial port"
2020-05-15 15:57:58,440 - Connected to: Serial<id=0x682f0610, open=True>(port='/dev/ttyAMA0', baudrate=115200, bytesize=8, parity='N', stopbits=1, timeout=10.0, xonxoff=False, rtscts=False, dsrdtr=False), starting monitor
2020-05-15 15:57:58,447 - Starting baud rate detection...
2020-05-15 15:57:58,450 - Changing monitoring state from "Opening serial port" to "Detecting baudrate"
2020-05-15 15:57:59,458 - Trying baudrate: 115200
2020-05-15 15:57:59,473 - Send: N0 M110 N0*125
2020-05-15 15:57:59,480 - Recv: echo:Unknown command: "e"(2)
2020-05-15 15:57:59,485 - Recv: ok
2020-05-15 15:57:59,491 - Changing monitoring state from "Detecting baudrate" to "Operational"
2020-05-15 15:57:59,514 - Send: N0 M110 N0*125
2020-05-15 15:57:59,526 - Recv: ok
2020-05-15 15:57:59,531 - Recv: ok
2020-05-15 15:57:59,537 - Send: N1 M115*39
2020-05-15 15:57:59,559 - Recv: FIRMWARE_NAME:Prusa-Firmware 3.8.1 based on Marlin FIRMWARE_URL:https://github.com/prusa3d/Prusa-Firmware PROTOCOL_VERSION:1.0 MACHINE_TYPE:Prusa i3 MK3S EXTRUDER_COUNT:1 UUID:00000000-0000-0000-0000-000000000000
2020-05-15 15:57:59,570 - Recv: ok
2020-05-15 15:57:59,576 - Send: M21
2020-05-15 15:57:59,589 - Recv: echo:SD card ok
2020-05-15 15:57:59,952 - Recv: ok
2020-05-15 15:57:59,956 - Send: M20
2020-05-15 15:57:59,959 - Recv: Begin file list
2020-05-15 15:58:00,590 - Recv: End file list
2020-05-15 15:58:00,598 - Recv: ok
2020-05-15 15:58:04,492 - Send: M105
2020-05-15 15:58:04,505 - Recv: ok T:40.0 /0.0 B:32.8 /0.0 T0:40.0 /0.0 @:0 B@:0 P:29.3 A:31.9
2020-05-15 15:58:09,494 - Send: M105
2020-05-15 15:58:09,506 - Recv: ok T:39.8 /0.0 B:32.5 /0.0 T0:39.8 /0.0 @:0 B@:0 P:29.3 A:31.9
2020-05-15 15:58:14,496 - Send: M105
2020-05-15 15:58:14,507 - Recv: ok T:39.6 /0.0 B:32.7 /0.0 T0:39.6 /0.0 @:0 B@:0 P:29.3 A:31.9
2020-05-15 15:58:19,497 - Send: M105
2020-05-15 15:58:19,512 - Recv: ok T:39.6 /0.0 B:32.4 /0.0 T0:39.6 /0.0 @:0 B@:0 P:29.4 A:31.9
2020-05-15 15:58:24,015 - Changing monitoring state from "Operational" to "Starting"
2020-05-15 15:58:24,076 - Changing monitoring state from "Starting" to "Printing"
2020-05-15 15:58:24,107 - Send: N0 M110 N0*125
2020-05-15 15:58:24,110 - Recv: ok
2020-05-15 15:58:24,116 - Send: N1 M73 P0 R299*22
2020-05-15 15:58:24,125 - Recv: NORMAL MODE: Percent done: 0; print time remaining in mins: 299
2020-05-15 15:58:24,131 - Recv: SILENT MODE: Percent done: 255; print time remaining in mins: -1
2020-05-15 15:58:24,134 - Recv: ok
2020-05-15 15:58:24,139 - Send: N2 M73 Q0 S302*22
2020-05-15 15:58:24,148 - Recv: NORMAL MODE: Percent done: 0; print time remaining in mins: 299
2020-05-15 15:58:24,153 - Recv: SILENT MODE: Percent done: 0; print time remaining in mins: 302
2020-05-15 15:58:24,155 - Recv: ok
2020-05-15 15:58:24,160 - Send: N3 M201 X1000 Y1000 Z1000 E5000*57
2020-05-15 15:58:24,166 - Recv: ok
2020-05-15 15:58:24,174 - Send: N4 M203 X200 Y200 Z12 E120*8
2020-05-15 15:58:24,180 - Recv: ok
2020-05-15 15:58:24,183 - Send: N5 M204 P1250 R1250 T1250*80
2020-05-15 15:58:24,190 - Recv: ok
2020-05-15 15:58:24,198 - Send: N6 M205 X8.00 Y8.00 Z0.40 E4.50*57
2020-05-15 15:58:24,204 - Recv: ok
2020-05-15 15:58:24,206 - Send: N7 M205 S0 T0*36
2020-05-15 15:58:24,214 - Recv: ok
2020-05-15 15:58:24,220 - Send: N8 M107*45
2020-05-15 15:58:24,224 - Recv: ok
2020-05-15 15:58:24,235 - Send: N9 M862.3 P "MK3S"*13
2020-05-15 15:58:24,239 - Recv: ok
2020-05-15 15:58:24,247 - Send: N10 M862.1 P0.4*91
2020-05-15 15:58:24,251 - Recv: ok
2020-05-15 15:58:24,259 - Send: N11 M115 U3.8.1*89
2020-05-15 15:58:24,263 - Recv: ok
2020-05-15 15:58:24,271 - Send: N12 G90*35
2020-05-15 15:58:24,275 - Recv: ok
2020-05-15 15:58:24,281 - Send: N13 M83*42
2020-05-15 15:58:24,302 - Recv: ok
2020-05-15 15:58:24,314 - Send: N14 G28 W*81
2020-05-15 15:58:24,999 - Recv: tmc2130_home_enter(axes_mask=0x01)
2020-05-15 15:58:25,697 - Recv:   0 step=61 mscnt= 984
2020-05-15 15:58:25,705 - Recv: tmc2130_goto_step 0 61 2 1000
2020-05-15 15:58:25,757 - Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x01
2020-05-15 15:58:25,868 - Recv: tmc2130_home_enter(axes_mask=0x02)
2020-05-15 15:58:26,299 - Recv: echo:busy: processing
2020-05-15 15:58:26,302 - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2020-05-15 15:58:28,364 - Recv: echo:busy: processing
2020-05-15 15:58:30,327 - Recv:   0 step=18 mscnt= 291
2020-05-15 15:58:30,334 - Recv: tmc2130_goto_step 1 49 2 1000
2020-05-15 15:58:30,423 - Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x02
2020-05-15 15:58:30,507 - Recv: echo:busy: processing
2020-05-15 15:58:32,572 - Recv: echo:busy: processing
2020-05-15 15:58:32,710 - Recv: ok
2020-05-15 15:58:32,715 - Send: N15 M400*19
2020-05-15 15:58:32,719 - Recv: ok
2020-05-15 15:58:32,724 - Send: N16 M114*16
2020-05-15 15:58:32,733 - Recv: X:12.00 Y:6.00 Z:0.15 E:0.00 Count X: 11.45 Y:6.59 Z:0.15 E:0.00
2020-05-15 15:58:32,742 - Recv: ok
2020-05-15 15:58:32,748 - Send: N17 M113 S2*87
2020-05-15 15:58:32,756 - Recv: ok
2020-05-15 15:58:32,777 - Send: N18 G80*40
2020-05-15 15:58:34,889 - Recv: echo:busy: processing
2020-05-15 15:58:36,993 - Recv: echo:busy: processing
2020-05-15 15:58:39,062 - Recv: echo:busy: processing
2020-05-15 15:58:41,132 - Recv: echo:busy: processing
2020-05-15 15:58:43,206 - Recv: echo:busy: processing
2020-05-15 15:58:45,298 - Recv: echo:busy: processing
2020-05-15 15:58:46,538 - Recv: ok
2020-05-15 15:58:46,557 - Send: N19 G1 Y-3.0 F1000.0*16
2020-05-15 15:58:46,564 - Recv: ok
2020-05-15 15:58:46,573 - Send: N20 M105*21
2020-05-15 15:58:46,582 - Recv: ok T:38.6 /0.0 B:32.2 /0.0 T0:38.6 /0.0 @:0 B@:0 P:29.2 A:31.8
2020-05-15 15:58:46,594 - Send: N21 G92 E0.0*106
2020-05-15 15:58:46,616 - Recv: ok
2020-05-15 15:58:46,626 - Send: N22 G1 X60.0 F1000.0*1
2020-05-15 15:58:46,653 - Recv: ok
2020-05-15 15:58:46,660 - Send: N23 M400*22
2020-05-15 15:58:48,740 - Recv: echo:busy: processing
2020-05-15 15:58:50,210 - Recv: ok
2020-05-15 15:58:50,222 - Send: N24 M114*17
2020-05-15 15:58:50,231 - Recv: X:60.00 Y:-3.00 Z:0.15 E:0.00 Count X: 59.45 Y:-2.41 Z:0.31 E:0.00
2020-05-15 15:58:50,242 - Recv: ok
2020-05-15 15:58:50,291 - Send: N25 G1 F4020.0*97
2020-05-15 15:58:50,299 - Recv: ok
2020-05-15 15:58:50,314 - Send: N26 G91*37
2020-05-15 15:58:50,324 - Recv: ok
2020-05-15 15:58:50,338 - Send: N27 G1 Z0.500 F6000.000*50
2020-05-15 15:58:50,348 - Recv: ok
2020-05-15 15:58:50,358 - Send: N28 G90*42
2020-05-15 15:58:50,371 - Recv: ok
2020-05-15 15:58:50,378 - Send: N29 G1 X247.500 Y217.770*18
2020-05-15 15:58:50,424 - Recv: ok
2020-05-15 15:58:50,431 - Send: N30 M400*20
2020-05-15 15:58:52,435 - Recv: echo:busy: processing
2020-05-15 15:58:53,325 - Recv: ok
2020-05-15 15:58:53,340 - Send: N31 M114*21
2020-05-15 15:58:53,350 - Recv: X:247.50 Y:211.91 Z:0.65 E:0.00 Count X: 246.96 Y:212.50 Z:1.26 E:0.00
2020-05-15 15:58:53,360 - Recv: ok
2020-05-15 15:58:53,766 - Send: N32 G1 X60.000 Y-3.000*0
2020-05-15 15:58:53,811 - Recv: ok
2020-05-15 15:58:53,814 - Send: N33 M400*23
2020-05-15 15:58:55,903 - Recv: echo:busy: processing
2020-05-15 15:58:56,358 - Recv: echo:enqueing "G1 E-1.000 F2700"
2020-05-15 15:58:56,364 - Recv: echo:enqueing "G1 Z10.652 F800.000"
2020-05-15 15:58:56,370 - Recv: echo:enqueing "CRASH_DETECTEDX"
2020-05-15 15:58:56,375 - Recv: echo: cold extrusion prevented
2020-05-15 15:58:57,220 - Recv: tmc2130_home_enter(axes_mask=0x01)
2020-05-15 15:58:58,153 - Recv:   0 step=50 mscnt= 806
2020-05-15 15:58:58,160 - Recv: tmc2130_goto_step 0 61 2 1000
2020-05-15 15:58:58,227 - Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x01
2020-05-15 15:58:58,339 - Recv: tmc2130_home_enter(axes_mask=0x02)
2020-05-15 15:58:58,548 - Recv: echo:busy: processing
2020-05-15 15:58:59,698 - Recv:   0 step=50 mscnt= 801
2020-05-15 15:58:59,711 - Recv: tmc2130_goto_step 1 49 2 1000
2020-05-15 15:58:59,759 - Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x02
2020-05-15 15:58:59,872 - Recv: echo:enqueing "CRASH_RECOVER"
2020-05-15 15:59:01,989 - Recv: echo:busy: processing
2020-05-15 15:59:02,234 - Recv: echo: cold extrusion prevented
2020-05-15 15:59:02,238 - Recv: Resend: 32
2020-05-15 15:59:02,248 - Recv: ok
2020-05-15 15:59:02,253 - Send: N32 G1 X60.000 Y-3.000*0
2020-05-15 15:59:02,257 - Recv: ok
2020-05-15 15:59:02,262 - Send: N33 M400*23
2020-05-15 15:59:02,266 - Recv: ok
2020-05-15 15:59:02,270 - Send: N34 M114*16
2020-05-15 15:59:02,772 - Recv: ok
2020-05-15 15:59:02,778 - Recv: X:60.00 Y:-3.00 Z:0.65 E:0.00 Count X: 59.45 Y:-2.41 Z:0.81 E:-1.00
2020-05-15 15:59:02,787 - Recv: ok
2020-05-15 15:59:02,825 - Send: N35 G91*39
2020-05-15 15:59:02,833 - Recv: ok
2020-05-15 15:59:02,843 - Send: N36 G1 Z-0.500*97
2020-05-15 15:59:02,855 - Recv: ok
2020-05-15 15:59:02,870 - Send: N37 G1 F3000.0*103
2020-05-15 15:59:02,887 - Recv: ok
2020-05-15 15:59:02,894 - Send: N38 G90*43
2020-05-15 15:59:02,918 - Recv: ok
2020-05-15 15:59:02,926 - Send: N39 G1 F1000.000*107
2020-05-15 15:59:02,939 - Recv: ok
2020-05-15 15:59:02,951 - Send: N40 M73 Q0 S302*32
2020-05-15 15:59:02,963 - Recv: NORMAL MODE: Percent done: 0; print time remaining in mins: 299
2020-05-15 15:59:02,968 - Recv: SILENT MODE: Percent done: 0; print time remaining in mins: 302
2020-05-15 15:59:02,971 - Recv: ok
2020-05-15 15:59:02,973 - Send: N41 M73 P0 R299*34
2020-05-15 15:59:02,981 - Recv: NORMAL MODE: Percent done: 0; print time remaining in mins: 299
2020-05-15 15:59:02,987 - Recv: SILENT MODE: Percent done: 0; print time remaining in mins: 302
2020-05-15 15:59:02,990 - Recv: ok
2020-05-15 15:59:03,002 - Send: N42 G1 X100.0 F1000.0*48
2020-05-15 15:59:03,023 - Recv: ok
2020-05-15 15:59:03,028 - Send: N43 G92 E0.0*110
2020-05-15 15:59:03,036 - Recv: ok
2020-05-15 15:59:03,041 - Send: N44 M221 S95*109
2020-05-15 15:59:03,048 - Recv: ok
2020-05-15 15:59:03,055 - Send: N45 G21*43
2020-05-15 15:59:03,060 - Recv: Unknown G code: $45 G21
2020-05-15 15:59:03,062 - Recv: ok
2020-05-15 15:59:03,068 - Send: N46 G90*34
2020-05-15 15:59:03,072 - Recv: ok
2020-05-15 15:59:03,079 - Send: N47 M83*43
2020-05-15 15:59:03,082 - Recv: ok
2020-05-15 15:59:03,087 - Send: N48 M900 K30*126
2020-05-15 15:59:05,207 - Recv: echo:busy: processing
2020-05-15 15:59:05,425 - Recv: echo:Advance K=30.00
2020-05-15 15:59:05,430 - Recv:  E/D=Auto
2020-05-15 15:59:05,433 - Recv: ok
2020-05-15 15:59:05,443 - Send: N49 G92 E0.0*100
2020-05-15 15:59:05,449 - Recv: ok
2020-05-15 15:59:05,461 - Send: N50 G1 F2100.0*102
2020-05-15 15:59:05,466 - Recv: ok
2020-05-15 15:59:05,477 - Send: N51 G1 Z0.600 F10800.000*15
2020-05-15 15:59:05,488 - Recv: ok
2020-05-15 15:59:05,500 - Send: N52 G1 X67.259 Y77.258*30
2020-05-15 15:59:05,518 - Recv: ok
2020-05-15 15:59:05,532 - Send: N53 M105*17
2020-05-15 15:59:05,542 - Recv: ok T:38.0 /0.0 B:31.9 /0.0 T0:38.0 /0.0 @:0 B@:0 P:29.2 A:31.9
2020-05-15 15:59:05,556 - Send: N54 G1 Z0.200*79
2020-05-15 15:59:05,569 - Recv: ok
2020-05-15 15:59:05,580 - Send: N55 G1 F2100.0*99
2020-05-15 15:59:05,599 - Recv: ok
2020-05-15 15:59:05,603 - Send: N56 M204 S1000*100
2020-05-15 15:59:05,608 - Recv: ok
2020-05-15 15:59:05,612 - Send: N57 G1 F1200.000*97
2020-05-15 15:59:05,618 - Recv: ok
2020-05-15 15:59:05,621 - Send: N58 G1 Y76.616 X67.604*23
2020-05-15 15:59:05,631 - Recv: ok
2020-05-15 15:59:05,634 - Send: N59 M400*27
2020-05-15 15:59:06,242 - Recv: ok
2020-05-15 15:59:06,246 - Send: N60 M114*17
2020-05-15 15:59:06,255 - Recv: X:67.60 Y:76.62 Z:0.20 E:0.00 Count X: 67.06 Y:77.20 Z:0.40 E:0.00
2020-05-15 15:59:06,261 - Recv: ok
2020-05-15 15:59:06,288 - Send: N61 G1 F4020.0*97
2020-05-15 15:59:06,294 - Recv: ok
2020-05-15 15:59:06,297 - Send: N62 G91*37
2020-05-15 15:59:06,302 - Recv: ok
2020-05-15 15:59:06,307 - Send: N63 G1 Z0.500 F6000.000*50
2020-05-15 15:59:06,331 - Recv: ok
2020-05-15 15:59:06,338 - Send: N64 G90*34
2020-05-15 15:59:06,355 - Recv: ok
2020-05-15 15:59:06,363 - Send: N65 G1 X247.500 Y217.770*26
2020-05-15 15:59:06,399 - Recv: ok
2020-05-15 15:59:06,405 - Send: N66 M400*23
2020-05-15 15:59:07,584 - Changing monitoring state from "Printing" to "Cancelling"
2020-05-15 15:59:07,975 - Recv: echo:enqueing "G1 E-1.000 F2700"
2020-05-15 15:59:07,988 - Recv: echo:enqueing "G1 Z10.702 F800.000"
2020-05-15 15:59:07,992 - Recv: echo:enqueing "CRASH_DETECTEDX"
2020-05-15 15:59:07,995 - Recv: echo: cold extrusion prevented
2020-05-15 15:59:08,837 - Recv: tmc2130_home_enter(axes_mask=0x01)
2020-05-15 15:59:10,052 - Recv: echo:busy: processing
2020-05-15 15:59:12,116 - Recv: echo:busy: processing
2020-05-15 15:59:14,180 - Recv: echo:busy: processing
2020-05-15 15:59:14,510 - Recv:   0 step=62 mscnt= 992
2020-05-15 15:59:14,512 - Recv: tmc2130_goto_step 0 61 2 1000
2020-05-15 15:59:14,569 - Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x01
2020-05-15 15:59:14,681 - Recv: tmc2130_home_enter(axes_mask=0x02)
2020-05-15 15:59:16,301 - Recv: echo:busy: processing
2020-05-15 15:59:18,368 - Recv: echo:busy: processing
2020-05-15 15:59:18,827 - Recv:   0 step=49 mscnt= 797
2020-05-15 15:59:18,831 - Recv: tmc2130_goto_step 1 49 2 1000
2020-05-15 15:59:18,888 - Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x02
2020-05-15 15:59:19,002 - Recv: echo:enqueing "CRASH_RECOVER"
2020-05-15 15:59:21,137 - Recv: echo:busy: processing
2020-05-15 15:59:23,201 - Recv: echo:busy: processing
2020-05-15 15:59:24,004 - Recv: echo: cold extrusion prevented
2020-05-15 15:59:24,012 - Recv: Resend: 65
2020-05-15 15:59:24,025 - Recv: ok
2020-05-15 15:59:24,030 - Send: N65 G1 X247.500 Y217.770*26
2020-05-15 15:59:24,031 - Recv: ok
2020-05-15 15:59:24,036 - Send: N66 M400*23
2020-05-15 15:59:24,045 - Recv: ok
2020-05-15 15:59:24,049 - Send: N67 M114*22
2020-05-15 15:59:24,988 - Recv: ok
2020-05-15 15:59:24,993 - Send: N68 M108*20
2020-05-15 15:59:24,997 - Recv: X:247.50 Y:211.91 Z:0.70 E:0.00 Count X: 246.96 Y:212.50 Z:1.31 E:-1.00
2020-05-15 15:59:25,006 - Recv: ok
2020-05-15 15:59:25,012 - Recv: Unknown M code: $68 M108
2020-05-15 15:59:25,015 - Send: N69 M84*32
2020-05-15 15:59:25,018 - Recv: ok
2020-05-15 15:59:25,024 - Send: N70 M104 T0 S0*22
2020-05-15 15:59:25,202 - Recv: ok
2020-05-15 15:59:25,209 - Send: N71 M105*17
2020-05-15 15:59:25,210 - Recv: ok
2020-05-15 15:59:25,219 - Send: N72 M140 S0*80
2020-05-15 15:59:25,220 - Recv: ok T:37.5 /0.0 B:31.9 /0.0 T0:37.5 /0.0 @:0 B@:0 P:29.1 A:32.0
2020-05-15 15:59:25,228 - Recv: ok
2020-05-15 15:59:25,235 - Send: N73 M106 S0*83
2020-05-15 15:59:25,240 - Recv: ok
2020-05-15 15:59:25,243 - Send: N74 G1 Z5*84
2020-05-15 15:59:25,434 - Recv: ok
2020-05-15 15:59:25,438 - Send: N75 G1 X0 Y180 F9000*125
2020-05-15 15:59:25,496 - Recv: ok
2020-05-15 15:59:25,500 - Changing monitoring state from "Cancelling" to "Operational"

GCode:

M73 P0 R75
M73 Q0 S75
M201 X1000 Y1000 Z1000 E5000 ; sets maximum accelerations, mm/sec^2
M203 X200 Y200 Z12 E120 ; sets maximum feedrates, mm/sec
M204 P1250 R1250 T1250 ; sets acceleration (P, T) and retract acceleration (R), mm/sec^2
M205 X8.00 Y8.00 Z0.40 E4.50 ; sets the jerk limits, mm/sec
M205 S0 T0 ; sets the minimum extruding and travel feed rate, mm/sec
M107
M862.3 P "MK3S" ; printer model check
M862.1 P0.4 ; nozzle diameter check
M115 U3.8.1 ; tell printer latest fw version
G90 ; use absolute coordinates
M83 ; extruder relative mode
M104 S215 ; set extruder temp
M140 S70 ; set bed temp
M190 S70 ; wait for bed temp
M109 S215 ; wait for extruder temp
G28 W ; home all without mesh bed level
G80 ; mesh bed leveling

Octoprint.logs

2020-05-15 15:57:21,454 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2020-05-15 15:57:21,464 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Connecting"
2020-05-15 15:57:38,347 - octoprint.util.comm - INFO - Changing monitoring state from "Connecting" to "Offline"
2020-05-15 15:57:43,129 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Detecting serial port"
2020-05-15 15:57:48,506 - octoprint.util.comm - INFO - Could not enter programming mode on /dev/ttyAMA0: u'Timeout'
2020-05-15 15:57:53,860 - octoprint.util.comm - INFO - Could not enter programming mode on /dev/ttyS0: u'Timeout'
2020-05-15 15:57:53,866 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting serial port" to "Error: Failed to autodetect serial port, please set it manually."
2020-05-15 15:57:58,436 - octoprint.util.comm - INFO - Changing monitoring state from "Offline" to "Opening serial port"
2020-05-15 15:57:58,451 - octoprint.util.comm - INFO - Changing monitoring state from "Opening serial port" to "Detecting baudrate"
2020-05-15 15:57:59,470 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-15 15:57:59,492 - octoprint.util.comm - INFO - Changing monitoring state from "Detecting baudrate" to "Operational"
2020-05-15 15:57:59,511 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-15 15:57:59,561 - octoprint.util.comm - INFO - Printer reports firmware name "Prusa-Firmware 3.8.1 based on Marlin"
2020-05-15 15:58:23,995 - octoprint.printer.standard.job - INFO - Print job selected - origin: local, path: Raspberry_Body_P3B-.gcode, owner: Radeonx, user: Radeonx
2020-05-15 15:58:24,016 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2020-05-15 15:58:24,026 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: Raspberry_Body_P3B-.gcode, owner: Radeonx, user: Radeonx
2020-05-15 15:58:24,077 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2020-05-15 15:58:24,105 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-15 15:58:26,302 - octoprint.util.comm - INFO - Printer seems to support the busy protocol, will adjust timeouts and set busy interval accordingly
2020-05-15 15:58:32,750 - octoprint.util.comm - INFO - Telling the printer to set the busy interval to our "communicationBusy" timeout - 1s = 2s
2020-05-15 15:59:02,241 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 32, current line = 34
| Last lines in terminal:
| Recv: ok
| Send: N33 M400*23
| Recv: echo:busy: processing
| Recv: echo:enqueing "G1 E-1.000 F2700"
| Recv: echo:enqueing "G1 Z10.652 F800.000"
| Recv: echo:enqueing "CRASH_DETECTEDX"
| Recv: echo: cold extrusion prevented
| Recv: tmc2130_home_enter(axes_mask=0x01)
| Recv:   0 step=50 mscnt= 806
| Recv: tmc2130_goto_step 0 61 2 1000
| Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x01
| Recv: tmc2130_home_enter(axes_mask=0x02)
| Recv: echo:busy: processing
| Recv:   0 step=50 mscnt= 801
| Recv: tmc2130_goto_step 1 49 2 1000
| Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x02
| Recv: echo:enqueing "CRASH_RECOVER"
| Recv: echo:busy: processing
| Recv: echo: cold extrusion prevented
| Recv: Resend: 32
2020-05-15 15:59:07,585 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Cancelling"
2020-05-15 15:59:07,609 - octoprint.util.comm - INFO - Force-sending M108 to the printer
2020-05-15 15:59:07,629 - octoprint.printer.standard.job - INFO - Print job cancelled - origin: local, path: Raspberry_Body_P3B-.gcode, owner: Radeonx, user: Radeonx
2020-05-15 15:59:24,018 - octoprint.util.comm - INFO - Got a resend request from the printer: requested line = 65, current line = 67
| Last lines in terminal:
| Recv: echo:enqueing "CRASH_DETECTEDX"
| Recv: echo: cold extrusion prevented
| Recv: tmc2130_home_enter(axes_mask=0x01)
| Recv: echo:busy: processing
| Recv: echo:busy: processing
| Recv: echo:busy: processing
| Recv:   0 step=62 mscnt= 992
| Recv: tmc2130_goto_step 0 61 2 1000
| Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x01
| Recv: tmc2130_home_enter(axes_mask=0x02)
| Recv: echo:busy: processing
| Recv: echo:busy: processing
| Recv:   0 step=49 mscnt= 797
| Recv: tmc2130_goto_step 1 49 2 1000
| Recv: tmc2130_home_exit tmc2130_sg_homing_axes_mask=0x02
| Recv: echo:enqueing "CRASH_RECOVER"
| Recv: echo:busy: processing
| Recv: echo:busy: processing
| Recv: echo: cold extrusion prevented
| Recv: Resend: 65
2020-05-15 15:59:25,501 - octoprint.util.comm - INFO - Changing monitoring state from "Cancelling" to "Operational"

Ewald_Ikemann · May 15, 2020, 6:44pm

Actually the four heat up commands are not sent to the printer.
What plugins do you have installed?
You may try in safemode.

Radeonx · May 15, 2020, 6:59pm

I just have the Plugin octolapse installed.
No other plugins.

I haven't tried the safemode.
Ill try it next.

Radeonx · May 17, 2020, 9:15am

Hi again,
When i boot in safemode the temperature is set before the print starts.
So i guess the problem is the Octolapse Plugin for me.

MisterD · June 2, 2020, 2:51pm

Check your logging setting in OctoLapse, turn off logging. If you have it in one of the debug modes the print job just goes through the motions but the bed and nozzle won't heat up. Happy Lapsing.