Print jobs sometimes fail to complete

[dc42]

Duet Forum Discussion Thread

https://forum.duet3d.com/topic/33151/does-not-completely-finish-the-last-layer-of-the-print-3-5-0-b4

Which Duet products are you using?

• Duet2-Wifi
• Duet2-Ethernet
• Duet Expansion Breakout Board
• Duex2
• Duex5
• Duet2-Maestro
• Maestro Dual Driver Expansion
• Duet3-6HC
• Duet3-3HC
• Duet3-1XD
• Duet3-1LC
• Duet3-Tool Distribution Board
• Duet3-Mini5+
• Duet3-Mini2+
• Raspberry Pi or other SBC
• SmartEffector
• Magnetic Filament Sensor
• Laser Filament Sensor
• PT100 Daughterboard
• Thermocouple Daughterboard
• PanelDue
• Other
• None

Firmware Version

3.5.0-beta.4

Duet Web Control Version

3.5.0-beta.4

Are you using a Single Board Computer (RaspberryPi) with your Duet?

• Yes I use a SBC.
• No I do not use a SBC.

Please upload the results of sending M122 in the gcode console.

The following was taken on a toolchanger. Printing had stopped with the nozzle still on the print. DWC showed still printing but at 100%. Note that the File input is syncing but File2 is not. I have seen this same situation once before. After pressing Pause and Cancel, the tool crashed into the Z axis when the tool was being unloaded.

23/08/2023, 19:25:00 	m122
=== Diagnostics ===
RepRapFirmware for Duet 3 MB6HC version 3.5.0-beta.4+ (2023-08-15 14:30:07) running on Duet 3 MB6HC v1.01 (standalone mode)
Board ID: 08DJM-956BA-NA3TJ-6J1FG-3SN6P-TAAUU
Used output buffers: 3 of 40 (36 max)
=== RTOS ===
Static ram: 154852
Dynamic ram: 126156 of which 0 recycled
Never used RAM 61216, free system stack 117 words
Tasks: NETWORK(1,ready,27.3%,182) ETHERNET(5,nWait,0.1%,443) LASER(5,nWait,0.0%,174) HEAT(3,nWait,0.0%,323) Move(4,nWait,1.6%,154) CanReceiv(6,nWait,0.1%,670) CanSender(5,nWait,0.0%,328) CanClock(7,delaying,0.0%,349) TMC(4,nWait,8.9%,59) MAIN(1,running,61.9%,137) IDLE(0,ready,0.0%,30), total 100.0%
Owned mutexes: LwipCore(NETWORK)
=== Platform ===
Last reset 00:38:50 ago, cause: software
Last software reset at 2023-08-20 20:34, reason: MemoryProtectionFault mmarValid daccViol, Gcodes spinning, available RAM 64648, slot 2
Software reset code 0x0163 HFSR 0x00000000 CFSR 0x00000082 ICSR 0x0043c804 BFAR 0x00000041 SP 0x2041bc20 Task MAIN Freestk 2212 ok
Stack: 00000000 00000000 00000000 2042dfc8 00000000 00467455 004053be 41030000 00498519 2042dfb8 00000000 2042ecd8 00467455 2042e9f0 2041bcd4 2042ecd0 0046d76f fffc0001 2042e9ff 00000000 2042bb28 204309d8 204251bc 2042d328 0046dba9 20425bd8 2042e9f4
Error status: 0x00
Aux0 errors 0,0,0
MCU temperature: min 48.4, current 56.4, max 56.8
Supply voltage: min 24.1, current 24.2, max 24.2, under voltage events: 0, over voltage events: 0, power good: yes
12V rail voltage: min 12.0, current 12.1, max 12.2, under voltage events: 0
Heap OK, handles allocated/used 99/0, heap memory allocated/used/recyclable 2048/716/716, gc cycles 0
Events: 3 queued, 3 completed
Driver 0: standstill, SG min n/a, mspos 8, reads 51821, writes 11 timeouts 0
Driver 1: standstill, SG min 0, mspos 776, reads 51799, writes 33 timeouts 0
Driver 2: standstill, SG min 0, mspos 472, reads 51799, writes 33 timeouts 0
Driver 3: standstill, SG min n/a, mspos 8, reads 51818, writes 14 timeouts 0
Driver 4: standstill, SG min n/a, mspos 8, reads 51818, writes 14 timeouts 0
Driver 5: standstill, SG min 15, mspos 264, reads 51791, writes 41 timeouts 0
Date/time: 2023-08-23 19:25:00
Slowest loop: 229.25ms; fastest: 0.06ms
=== Storage ===
Free file entries: 18
SD card 0 detected, interface speed: 25.0MBytes/sec
SD card longest read time 3.9ms, write time 2.1ms, max retries 0
=== Move ===
DMs created 125, segments created 38, maxWait 186733ms, bed compensation in use: mesh, height map offset 0.000, ebfmin 0.00, ebfmax 0.00
no step interrupt scheduled
Moves shaped first try 11318, on retry 603, too short 4476, wrong shape 1320, maybepossible 1500
=== DDARing 0 ===
Scheduled moves 25157, completed 25157, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== DDARing 1 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== Heat ===
Bed heaters 0 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
Heater 0 is on, I-accum = 0.0
Heater 3 is on, I-accum = 0.0
=== GCodes ===
Movement locks held by null, null
HTTP is idle in state(s) 0
Telnet is idle in state(s) 0
File is idle in state(s) 0, sync state 1
USB is idle in state(s) 0
Aux is idle in state(s) 0
Trigger is idle in state(s) 0
Queue is idle in state(s) 0
LCD is idle in state(s) 0
SBC is idle in state(s) 0
Daemon is idle in state(s) 0
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
File2 is idle in state(s) 0
Queue2 is idle in state(s) 0
Q0 segments left 0, axes/extruders owned 0x20000003
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== Filament sensors ===
Extruder 2: no data received
Extruder 3: no data received
=== CAN ===
Messages queued 44774, received 132805, lost 0, boc 0
Longest wait 2ms for reply type 6053, peak Tx sync delay 379, free buffers 50 (min 48), ts 11653/11652/0
Tx timeouts 0,0,0,0,0,0
=== Network ===
Slowest loop: 217.24ms; fastest: 0.03ms
Responder states: MQTT(0) HTTP(0) HTTP(0) HTTP(0) HTTP(0) HTTP(0) HTTP(0) FTP(0) Telnet(0) Telnet(0)
HTTP sessions: 1 of 8
= Ethernet =
Interface state: active
Error counts: 0 0 0 1 0 0
Socket states: 5 2 2 2 2 0 0 0
=== Multicast handler ===
Responder is inactive, messages received 0, responses 0

The following was reported by the author of the forum thread:

M122
=== Diagnostics ===
RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-beta.4 (2023-06-08 23:40:14) running on Duet 3 Mini5plus WiFi (standalone mode)
Board ID: U51SX-F396U-D65J0-40KMW-M603Z-ZFNKD
Used output buffers: 1 of 40 (18 max)
=== RTOS ===
Static ram: 102996
Dynamic ram: 122548 of which 0 recycled
Never used RAM 16512, free system stack 188 words
Tasks: NETWORK(1,ready,46.8%,240) HEAT(3,nWait,0.1%,327) Move(4,nWait,0.0%,358) CanReceiv(6,nWait,0.0%,939) CanSender(5,nWait,0.0%,337) CanClock(7,delaying,0.0%,342) TMC(4,nWait,3.7%,116) MAIN(1,running,46.6%,704) IDLE(0,ready,0.1%,29) AIN(4,delaying,2.6%,266), total 100.0%
Owned mutexes:
=== Platform ===
Last reset 02:19:32 ago, cause: power up
Last software reset at 2023-07-25 21:46, reason: User, Gcodes spinning, available RAM 13112, slot 2
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x0044f000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
Error status: 0x00
MCU revision 3, ADC conversions started 8372702, completed 8372702, timed out 0, errs 0
MCU temperature: min 34.5, current 34.8, max 41.0
Supply voltage: min 23.9, current 24.0, max 24.0, under voltage events: 0, over voltage events: 0, power good: yes
Heap OK, handles allocated/used 0/0, heap memory allocated/used/recyclable 0/0/0, gc cycles 0
Events: 0 queued, 0 completed
Driver 0: standstill, SG min 0, read errors 0, write errors 0, ifcnt 11, reads 40203, writes 11, timeouts 0, DMA errors 0, CC errors 0
Driver 1: standstill, SG min 0, read errors 0, write errors 0, ifcnt 11, reads 40203, writes 11, timeouts 0, DMA errors 0, CC errors 0
Driver 2: standstill, SG min 0, read errors 0, write errors 0, ifcnt 10, reads 40204, writes 10, timeouts 0, DMA errors 0, CC errors 0
Driver 3: standstill, SG min 0, read errors 0, write errors 0, ifcnt 10, reads 40203, writes 10, timeouts 0, DMA errors 0, CC errors 0
Driver 4: standstill, SG min 0, read errors 0, write errors 0, ifcnt 11, reads 40203, writes 11, timeouts 0, DMA errors 0, CC errors 0
Driver 5: standstill, SG min 0, read errors 0, write errors 0, ifcnt 11, reads 40203, writes 11, timeouts 0, DMA errors 0, CC errors 0
Driver 6: standstill, SG min 0, read errors 0, write errors 0, ifcnt 10, reads 40203, writes 10, timeouts 0, DMA errors 0, CC errors 0
Date/time: 2023-08-21 22:03:31
Cache data hit count 4294967295
Slowest loop: 7.11ms; fastest: 0.16ms
=== Storage ===
Free file entries: 20
SD card 0 detected, interface speed: 22.5MBytes/sec
SD card longest read time 3.6ms, write time 0.0ms, max retries 0
=== Move ===
DMs created 83, segments created 0, maxWait 0ms, bed compensation in use: none, height map offset 0.000, ebfmin 0.00, ebfmax 0.00
no step interrupt scheduled
=== DDARing 0 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== DDARing 1 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== Heat ===
Bed heaters 0 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
Heater 1 is on, I-accum = 0.0
=== GCodes ===
Movement locks held by null, null
HTTP is idle in state(s) 0
Telnet is idle in state(s) 0
File is idle in state(s) 0
USB is idle in state(s) 0
Aux is idle in state(s) 0
Trigger is idle in state(s) 0
Queue is idle in state(s) 0
LCD is idle in state(s) 0
SBC is idle in state(s) 0
Daemon is idle in state(s) 0
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
File2 is idle in state(s) 0
Queue2 is idle in state(s) 0
Q0 segments left 0, axes/extruders owned 0x0000803
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== CAN ===
Messages queued 75351, received 0, lost 0, boc 0
Longest wait 0ms for reply type 0, peak Tx sync delay 0, free buffers 18 (min 18), ts 41863/0/0
Tx timeouts 0,0,41862,0,0,33487 last cancelled message type 4514 dest 127
=== Network ===
Slowest loop: 15.28ms; fastest: 0.00ms
Responder states: MQTT(0) HTTP(0) HTTP(0) HTTP(0) HTTP(0) FTP(0) Telnet(0)
HTTP sessions: 1 of 8
= WiFi =
Interface state: active
Module is connected to access point
Failed messages: pending 0, notready 0, noresp 0
Firmware version 1.26
MAC address f0:08:d1:03:81:a2
Module reset reason: Power up, Vcc 3.38, flash size 2097152, free heap 24792
WiFi IP address 192.168.32.177
Signal strength -45dBm, channel 0, mode 802.11n, reconnections 0
Clock register 00002002
Socket states: 0 0 0 0 0 0 0 0

What happened?

Prints do not always complete. The same print sometimes completes and sometimes does not. The head may be moved away from the print, or it may get left on the print. Some heaters may be turned off.

Observed by two forum users, see forum link. Also observed by dc42 on toolchanger.

[dc42]

May be fixed by the fix for issue #901.

[tristanryerparke]

I've also noticed this issue on my 6HC + 4x 1HCL machine running 3.5.0rc1

[dc42]

I just had a related issue on my delta. The print on the bed appears to be complete and the printer has homed, the extruder heater has its active and standby temperatures set to 0 and has cooled down. However the bed heater is still running. Tool 0 is still selected. DWC shows status Printing, 99.9% complete, 3.8mm extrusion remaining. Here's the M122; note that File is in sync state 1.

m122
=== Diagnostics ===
RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.1+ (2023-09-27 13:35:18) running on Duet 3 Mini5plus WiFi (standalone mode)
Board ID: NRH9T-N6BFL-D65J0-40KKU-NDW2Z-RDXL0
Used output buffers: 11 of 40 (40 max)
=== RTOS ===
Static ram: 102836
Dynamic ram: 122096 of which 12 recycled
Never used RAM 13112, free system stack 128 words
Tasks: NETWORK(2,nWait,32.5%,173) HEAT(3,nWait,0.1%,323) Move(4,nWait,3.6%,258) CanReceiv(6,nWait,0.0%,940) CanSender(5,nWait,0.0%,336) CanClock(7,delaying,0.0%,341) TMC(4,nWait,1.7%,74) MAIN(1,running,59.6%,344) IDLE(0,ready,0.4%,29) AIN(4,delaying,2.0%,264), total 100.0%
Owned mutexes: WiFi(NETWORK)
=== Platform ===
Last reset 02:48:18 ago, cause: power up
Last software reset at 2023-09-02 12:43, reason: User, Gcodes spinning, available RAM 13704, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00487000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
Error status: 0x04
Aux0 errors 0,0,0
MCU revision 3, ADC conversions started 10098694, completed 10098694, timed out 0, errs 0
MCU temperature: min 3.7, current 35.7, max 45.1
Supply voltage: min 26.8, current 27.0, max 27.1, under voltage events: 0, over voltage events: 0, power good: yes
Heap OK, handles allocated/used 99/1, heap memory allocated/used/recyclable 2048/144/104, gc cycles 0
Events: 0 queued, 0 completed
Driver 0: standstill, SG min 0, read errors 0, write errors 0, ifcnt 13, reads 6928, writes 13, timeouts 0, DMA errors 0, CC errors 0
Driver 1: standstill, SG min 0, read errors 0, write errors 0, ifcnt 13, reads 6928, writes 13, timeouts 0, DMA errors 0, CC errors 0
Driver 2: standstill, SG min 0, read errors 0, write errors 0, ifcnt 13, reads 6928, writes 13, timeouts 0, DMA errors 0, CC errors 0
Driver 3: standstill, SG min 0, read errors 0, write errors 0, ifcnt 13, reads 6928, writes 13, timeouts 0, DMA errors 0, CC errors 0
Driver 4: standstill, SG min 0, read errors 0, write errors 0, ifcnt 10, reads 6931, writes 10, timeouts 0, DMA errors 0, CC errors 0
Driver 5: not present
Driver 6: not present
Date/time: 2023-09-28 12:24:22
Cache data hit count 4294967295
Slowest loop: 463.15ms; fastest: 0.12ms
=== Storage ===
Free file entries: 17
SD card 0 detected, interface speed: 22.5MBytes/sec
SD card longest read time 6.8ms, write time 285.7ms, max retries 0
=== Move ===
DMs created 83, segments created 21, maxWait 951698ms, bed compensation in use: none, height map offset 0.000, ebfmin -1.00, ebfmax 1.00
no step interrupt scheduled
Moves shaped first try 0, on retry 0, too short 0, wrong shape 0, maybepossible 0
=== DDARing 0 ===
Scheduled moves 91356, completed 91356, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 13], CDDA state -1
=== DDARing 1 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== Heat ===
Bed heaters 0 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
Heater 0 is on, I-accum = 0.2
Heater 1 is on, I-accum = 0.3
=== GCodes ===
Movement locks held by null, null
HTTP is idle in state(s) 0
Telnet is idle in state(s) 0
File is idle in state(s) 0, sync state 1
USB is idle in state(s) 0
Aux is idle in state(s) 0
Trigger is idle in state(s) 0
Queue is idle in state(s) 0
LCD is idle in state(s) 0
SBC is idle in state(s) 0
Daemon is idle in state(s) 0
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
File2 is idle in state(s) 0
Queue2 is idle in state(s) 0
Q0 segments left 0, axes/extruders owned 0x0000803
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== Filament sensors ===
Extruder 0: pos 101.95, errs: frame 8 parity 0 ovrun 0 pol 0 ovdue 0
=== CAN ===
Messages queued 90886, received 0, lost 0, boc 0
Longest wait 0ms for reply type 0, peak Tx sync delay 0, free buffers 26 (min 26), ts 50494/0/0
Tx timeouts 0,0,50493,0,0,40391 last cancelled message type 30 dest 127
=== Network ===
Slowest loop: 323.71ms; fastest: 0.00ms
Responder states: MQTT(0) HTTP(0) HTTP(0) HTTP(0) HTTP(0) FTP(0) Telnet(0)
HTTP sessions: 1 of 8
=== WiFi ===
Interface state: active
Module is connected to access point 
Failed messages: pending 0, notrdy 0, noresp 0
Firmware version 1.27
MAC address f4:cf:a2:6f:9b:a4
Module reset reason: Power up, Vcc 3.37, flash size 2097152, free heap 26112
WiFi IP address 192.168.1.150
Signal strength -49dBm, channel 0, mode 802.11n, reconnections 0
Clock register 00002002
Socket states: 0 0 0 0 0 0 0 0

File stop.g:

M140 S0			; turn bed heater off
M568 P0 A0		; turn tool 0 heaters off
M106 P0 S0		; fan off
G28				; home

[dc42]

Fixed the issue that caused the File channel sometimes to remain in sync state 1 when the print completed. This may or may not fix the original issue.

[dc42]

Fixed the issue with print jobs on the delta sometimes ending up with the File channel stuck in a sync state. This will likely fix the other issue too.

[dc42]

Confirmed fixed by forum users, see original forum thread.