Moves to back corners and spews filament

Get Help
1

Since the Update to 1.4.0 about 50% of the time when I start a print the normal start code executes to prime the nozzle. The printer then proceeds to move to the far rear corner and push through filament at an extreme rate until I reset the printer.

No changes made to printer firmware as was previously working fine and issue only occurs about 50% of the time. Currently reprinting the same G-code 10 times a day.

I have tried compiling the Gcode from Cura on 2 different computers and Simplify 3D. Issue occurs independant of the Gcode source and same GCode works sometimes and not others.

2020-05-01 17:02:12,486 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-05-01 17:02:22,604 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 2199}
2020-05-01 17:06:42,313 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.5
2020-05-01 17:14:26,264 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.14
2020-05-01 17:17:12,490 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-05-01 17:17:22,594 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 3099}
2020-05-01 17:19:53,511 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.5
2020-05-01 17:19:53,694 - octoprint.server.util.flask - INFO - Passively logging in user lyndon from ::ffff:192.168.1.5
2020-05-01 17:19:53,696 - octoprint.access.users - INFO - Logged in user: lyndon
2020-05-01 17:19:53,794 - octoprint.server.util.sockjs - INFO - User lyndon logged in on the socket from client ::ffff:192.168.1.5
2020-05-01 17:21:10,277 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.5
2020-05-01 17:23:40,780 - octoprint.util.comm - INFO - Finished in 3302.500 s.
2020-05-01 17:23:40,782 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Finishing"
2020-05-01 17:23:40,800 - octoprint.printer.standard.job - INFO - Print job done - origin: local, path: New_Mask_Strap_Plain_9mm_2.gcode, owner: lyndon
2020-05-01 17:23:40,858 - octoprint.util.comm - INFO - Changing monitoring state from "Finishing" to "Operational"
2020-05-01 17:23:41,318 - octoprint.plugins.tracking - INFO - Sent tracking event print_done, payload: {'origin': u'local', 'file': '605d4ad319e8e31b8734f073639378bfdf24b61b', u'elapsed': 3302}
2020-05-01 17:32:12,494 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-05-01 17:32:22,626 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 3999}
2020-05-01 17:47:12,497 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-05-01 17:47:22,645 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 4899}
2020-05-01 17:48:29,479 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.14
2020-05-01 17:48:29,631 - octoprint.server.util.flask - INFO - Passively logging in user lyndon from ::ffff:192.168.1.14
2020-05-01 17:48:29,632 - octoprint.access.users - INFO - Logged in user: lyndon
2020-05-01 17:48:29,736 - octoprint.server.util.sockjs - INFO - User lyndon logged in on the socket from client ::ffff:192.168.1.14
2020-05-01 17:48:47,733 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2020-05-01 17:48:47,750 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: New_Mask_Strap_Plain_9mm_2.gcode, owner: lyndon, user: lyndon
2020-05-01 17:48:47,769 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-01 17:48:47,802 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2020-05-01 17:48:48,118 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': u'local', 'file': '605d4ad319e8e31b8734f073639378bfdf24b61b'}
2020-05-01 18:01:56,855 - octoprint.server.util.flask - INFO - Passively logging in user lyndon from ::ffff:192.168.1.5
2020-05-01 18:01:56,861 - octoprint.access.users - INFO - Logged in user: lyndon
2020-05-01 18:01:57,471 - octoprint.server.util.sockjs - INFO - New connection from client: ::ffff:192.168.1.5
2020-05-01 18:01:57,591 - octoprint.server.util.flask - INFO - Passively logging in user lyndon from ::ffff:192.168.1.5
2020-05-01 18:01:57,593 - octoprint.access.users - INFO - Logged in user: lyndon
2020-05-01 18:01:59,456 - octoprint.server.util.sockjs - INFO - User lyndon logged in on the socket from client ::ffff:192.168.1.5
2020-05-01 18:02:10,330 - octoprint.server.util.sockjs - INFO - Client connection closed: ::ffff:192.168.1.5
2020-05-01 18:02:12,499 - octoprint.server.heartbeat - INFO - Server heartbeat <3
2020-05-01 18:02:20,259 - octoprint.util.comm - WARNING - Printer sent 'start' while printing. External reset? Aborting job since printer lost state.
2020-05-01 18:02:20,273 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-01 18:02:20,286 - octoprint.util.comm - INFO - Changing monitoring state from "Printing" to "Cancelling"
2020-05-01 18:02:20,302 - octoprint.util.comm - INFO - Force-sending M108 to the printer
2020-05-01 18:02:20,327 - octoprint.printer.standard.job - INFO - Print job cancelled - origin: local, path: New_Mask_Strap_Plain_9mm_2.gcode, owner: lyndon, user: None
2020-05-01 18:02:20,764 - octoprint.plugins.tracking - INFO - Sent tracking event print_cancelled, payload: {'origin': u'local', 'file': '605d4ad319e8e31b8734f073639378bfdf24b61b', u'elapsed': 812}
2020-05-01 18:02:21,043 - octoprint.plugins.tracking - INFO - Sent tracking event print_failed, payload: {'origin': u'local', u'reason': u'cancelled', 'file': '605d4ad319e8e31b8734f073639378bfdf24b61b', u'elapsed': 812}
2020-05-01 18:02:23,372 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-01 18:02:23,474 - octoprint.util.comm - INFO - Changing monitoring state from "Cancelling" to "Operational"
2020-05-01 18:02:27,598 - octoprint.plugins.tracking - INFO - Sent tracking event ping, payload: {'octoprint_uptime': 5799}
2020-05-01 18:02:38,833 - octoprint.util.comm - INFO - Changing monitoring state from "Operational" to "Starting"
2020-05-01 18:02:38,858 - octoprint.printer.standard.job - INFO - Print job started - origin: local, path: New_Mask_Strap_Plain_9mm_2.gcode, owner: lyndon, user: lyndon
2020-05-01 18:02:38,907 - octoprint.util.comm - INFO - M110 detected, setting current line number to 0
2020-05-01 18:02:38,942 - octoprint.util.comm - INFO - Changing monitoring state from "Starting" to "Printing"
2020-05-01 18:02:39,254 - octoprint.plugins.tracking - INFO - Sent tracking event print_started, payload: {'origin': u'local', 'file': '605d4ad319e8e31b8734f073639378bfdf24b61b'}

Version 0.16.0, running on Raspberry Pi 3 Model B Rev 1.2
Octoprint Version 1.4.0

Any help apprceiated

2

Please upload a full octoprint.log and a serial.log :slight_smile:

3

octoprint (2).log (27.2 KB) serial (2).log (945.3 KB)

Thanks for the Info, Wasnt sure where to turn on the serial log file.

I have trimmed the Serial log to about 20 minutes before the issue occurs at 11:22, then Manually set the temps to heat up and started the Same exact Gcode again and worked perfected at 11:24.
Didn't reupload the code or even click to push it into the print queue just hit the print button.

Would be great to figure this out as printing Mask Straps for Health care worker and have wasted a lot of material if i have stepped away from the printer while it was starting.

1 Like