Duet3 6XD based 3D printer freezes mid-print, then continues

awitc

Hi!

I am experiencing an issue, where my 3D printer performs well during the majority of the print, then at some point, it stops (both movements and extrusion), and after 5-10 seconds continues as if nothing happened. This can happen more than once, but the gaps between occurrences are fairly large, ie. 10-30 minutes.

This is not related to one specific gcode file (gcode looks alright) or part printed, and it occurs at different heights. My only suspicion is that the printer is processing a certain length of gcode and stops when it gets a new 'batch'.

I saw some similar posts asking about microstepping settings, here are mine:

M350 X32 Y32 Z32 E16 I0		; microstepping with/without interpolation

Best regards,
AW

jay_s_uk

@awitc what firmware version are you running?
best grab an M122 output when the issue next occurs

awitc

M122
=== Diagnostics ===
RepRapFirmware for Duet 3 MB6XD version 3.4.6 (2023-07-21 14:11:58) running on Duet 3 MB6XD v1.0 (SBC mode)
Board ID: 08DLM-956DA-M24S4-7JKD0-3SJ6P-1BJLU
Used output buffers: 1 of 40 (15 max)
=== RTOS ===
Static ram: 151692
Dynamic ram: 67564 of which 0 recycled
Never used RAM 128416, free system stack 154 words
Tasks: SBC(ready,0.7%,487) HEAT(notifyWait,0.1%,346) Move(notifyWait,0.3%,267) CanReceiv(notifyWait,0.0%,798) CanSender(notifyWait,0.0%,335) CanClock(delaying,0.0%,352) MAIN(running,98.8%,1125) IDLE(ready,0.1%,29), total 100.0%
Owned mutexes: HTTP(MAIN)
=== Platform ===
Last reset 00:02:50 ago, cause: software
Last software reset at 2023-09-06 11:57, reason: User, GCodes spinning, available RAM 128416, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00400000 BFAR 0x00000000 SP 0x00000000 Task SBC Freestk 0 n/a
Error status: 0x00
Step timer max interval 18721
MCU temperature: min 36.3, current 36.6, max 36.8
Supply voltage: min 23.9, current 23.9, max 24.0, under voltage events: 0, over voltage events: 0, power good: yes
12V rail voltage: min 11.8, current 11.9, max 12.3, under voltage events: 0
Heap OK, handles allocated/used 99/3, heap memory allocated/used/recyclable 2048/44/0, gc cycles 0
Events: 0 queued, 0 completed
Driver 0: ok
Driver 1: ok
Driver 2: ok
Driver 3: ok
Driver 4: ok
Driver 5: ok
Date/time: 2023-09-06 12:00:47
Slowest loop: 53.01ms; fastest: 0.04ms
=== Storage ===
Free file entries: 10
SD card 0 not detected, interface speed: 37.5MBytes/sec
SD card longest read time 0.0ms, write time 0.0ms, max retries 0
=== Move ===
DMs created 125, segments created 6, maxWait 60052ms, bed compensation in use: none, comp offset 0.000
=== MainDDARing ===
Scheduled moves 291, completed 291, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 2], CDDA state -1
=== AuxDDARing ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== Heat ===
Bed heaters 8 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
Heater 1 is on, I-accum = 0.3
Heater 2 is on, I-accum = 0.3
Heater 3 is on, I-accum = 0.3
Heater 4 is on, I-accum = 0.0
Heater 5 is on, I-accum = 0.0
Heater 6 is on, I-accum = 0.0
Heater 8 is on, I-accum = 0.1
=== GCodes ===
Segments left: 0
Movement lock held by null
HTTP* is doing "M122" in state(s) 0
Telnet is idle in state(s) 0
File* is doing "G1 X211.673004 Y236.014999 Z2.770000 E0.691060" 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 doing "G4 S1" in state(s) 0 0, running macro
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
Code queue is empty
=== CAN ===
Messages queued 1177, received 2085, lost 0, boc 0
Longest wait 1ms for reply type 6013, peak Tx sync delay 8, free buffers 50 (min 49), ts 649/649/0
Tx timeouts 0,0,0,0,0,0
=== SBC interface ===
Transfer state: 5, failed transfers: 0, checksum errors: 0
RX/TX seq numbers: 7327/7327
SPI underruns 0, overruns 0
State: 5, disconnects: 0, timeouts: 0 total, 0 by SBC, IAP RAM available 0x2b55c
Buffer RX/TX: 1704/3216-0, open files: 0
=== Duet Control Server ===
Duet Control Server v3.4.6
File /opt/dsf/sd/gcodes/correct_temps.gcode is selected, processing
HTTP:
Buffered code: M25
==> 24 bytes
File:
Buffered code: G1 X211.673 Y236.015 Z2.77 E0.69106
Buffered code: G1 X211.01 Y235.162 Z2.78 E0.67377
Buffered code: G1 X210.404 Y234.269 Z2.79 E0.67305
Buffered code: G1 X209.849 Y233.331 Z2.8 E0.67972
Buffered code: G1 X209.358 Y232.367 Z2.81 E0.67469
Buffered code: G1 X208.929 Y231.375 Z2.82 E0.67404
Buffered code: G1 X208.555 Y230.336 Z2.83 E0.68868
Buffered code: G1 X208.256 Y229.308 Z2.84 E0.66768
Buffered code: G1 X208.017 Y228.242 Z2.85 E0.68132
Buffered code: G1 X207.848 Y227.172 Z2.86 E0.67558
Buffered code: G1 X207.745 Y226.087 Z2.87 E0.67971
Buffered code: G1 X207.711 Y225 Z2.88 E0.67824
Buffered code: G1 X207.745 Y223.912 Z2.89 E0.67887
Buffered code: G1 X207.847 Y222.831 Z2.9 E0.67716
Buffered code: G1 X208.016 Y221.76 Z2.91 E0.6762
Buffered code: G1 X208.255 Y220.694 Z2.92 E0.68132
Buffered code: G1 X208.554 Y219.666 Z2.93 E0.66768
Buffered code: G1 X208.928 Y218.627 Z2.94 E0.68868
Buffered code: G1 X209.357 Y217.635 Z2.95 E0.67404
Buffered code: G1 X209.848 Y216.671 Z2.96 E0.67469
Buffered code: G1 X210.403 Y215.733 Z2.97 E0.67972
Buffered code: G1 X211.009 Y214.84 Z2.98 E0.67305
Buffered code: G1 X211.672 Y213.987 Z2.99 E0.67377
Buffered code: G1 X212.404 Y213.154 Z3 E0.69158
Buffered code: ;LAYER:2
Buffered code: ;MESH:budVase.stl
Buffered code: G1 X213.15 Y212.399 Z3.01 E0.66194
==> 1488 bytes
Pending code: G1 X213.977 Y211.661 Z3.02 E0.69126
Pending code: G1 X214.828 Y210.992 Z3.029 E0.67509
Pending code: G1 X215.718 Y210.38 Z3.039 E0.67362
Daemon:
Buffered code: G4 S1
==> 32 bytes
Executing macro daemon.g, started by system
Number of flush requests: 1
> Next stack level
Code buffer space: 1704
Configured SPI speed: 8000000Hz, TfrRdy pin glitches: 0
Full transfers per second: 39.45, max time between full transfers: 38.9ms, max pin wait times: 34.1ms/0.1ms
Codes per second: 2.73
Maximum length of RX/TX data transfers: 3778/1664

awitc

After a second occurence during the same print:

M122
=== Diagnostics ===
RepRapFirmware for Duet 3 MB6XD version 3.4.6 (2023-07-21 14:11:58) running on Duet 3 MB6XD v1.0 (SBC mode)
Board ID: 08DLM-956DA-M24S4-7JKD0-3SJ6P-1BJLU
Used output buffers: 1 of 40 (15 max)
=== RTOS ===
Static ram: 151692
Dynamic ram: 67564 of which 0 recycled
Never used RAM 128416, free system stack 154 words
Tasks: SBC(ready,0.8%,487) HEAT(notifyWait,0.1%,346) Move(notifyWait,1.4%,250) CanReceiv(notifyWait,0.0%,798) CanSender(notifyWait,0.0%,335) CanClock(delaying,0.0%,349) MAIN(running,97.7%,1125) IDLE(ready,0.0%,29), total 100.0%
Owned mutexes: HTTP(MAIN)
=== Platform ===
Last reset 00:10:45 ago, cause: software
Last software reset at 2023-09-06 11:57, reason: User, GCodes spinning, available RAM 128416, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00400000 BFAR 0x00000000 SP 0x00000000 Task SBC Freestk 0 n/a
Error status: 0x00
Step timer max interval 18554
MCU temperature: min 36.5, current 37.4, max 37.6
Supply voltage: min 23.9, current 23.9, max 24.0, under voltage events: 0, over voltage events: 0, power good: yes
12V rail voltage: min 11.8, current 11.9, max 12.3, under voltage events: 0
Heap OK, handles allocated/used 99/3, heap memory allocated/used/recyclable 2048/44/0, gc cycles 0
Events: 0 queued, 0 completed
Driver 0: ok
Driver 1: ok
Driver 2: ok
Driver 3: ok
Driver 4: ok
Driver 5: ok
Date/time: 2023-09-06 12:08:42
Slowest loop: 49.69ms; fastest: 0.04ms
=== Storage ===
Free file entries: 10
SD card 0 not detected, interface speed: 37.5MBytes/sec
SD card longest read time 0.0ms, write time 0.0ms, max retries 0
=== Move ===
DMs created 125, segments created 6, maxWait 69618ms, bed compensation in use: none, comp offset 0.000
=== MainDDARing ===
Scheduled moves 5478, completed 5451, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 2], CDDA state 3
=== AuxDDARing ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== Heat ===
Bed heaters 8 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
Heater 1 is on, I-accum = 0.2
Heater 2 is on, I-accum = 0.1
Heater 3 is on, I-accum = 0.2
Heater 4 is on, I-accum = 0.0
Heater 5 is on, I-accum = 0.0
Heater 6 is on, I-accum = 0.0
Heater 8 is on, I-accum = 0.2
=== GCodes ===
Segments left: 1
Movement lock held by null
HTTP* is doing "M122" in state(s) 0
Telnet is idle in state(s) 0
File* is doing "G1 X217.852005 Y226.837006 Z56.861000 E0.577180" 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 doing "G4 S1" in state(s) 0 0, running macro
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
Code queue is empty
=== CAN ===
Messages queued 4277, received 7604, lost 0, boc 0
Longest wait 0ms for reply type 0, peak Tx sync delay 9, free buffers 50 (min 50), ts 2376/2376/0
Tx timeouts 0,0,0,0,0,0
=== SBC interface ===
Transfer state: 5, failed transfers: 0, checksum errors: 0
RX/TX seq numbers: 26042/26042
SPI underruns 0, overruns 0
State: 5, disconnects: 0, timeouts: 0 total, 0 by SBC, IAP RAM available 0x2b55c
Buffer RX/TX: 1512/2968-0, open files: 0
=== Duet Control Server ===
Duet Control Server v3.4.6
File /opt/dsf/sd/gcodes/correct_temps.gcode is selected, processing
File:
Buffered code: G1 X217.852 Y226.837 Z56.861 E0.57718
Buffered code: G1 X217.681 Y225.923 Z56.881 E0.58002
Buffered code: G1 X217.639 Y225.463 Z56.891 E0.28813
Buffered code: G1 X217.641 Y224.539 Z56.911 E0.57637
Buffered code: G1 X217.761 Y223.613 Z56.931 E0.58244
Buffered code: G1 X217.863 Y223.164 Z56.94 E0.2872
Buffered code: G1 X218.152 Y222.289 Z56.96 E0.5748
Buffered code: G1 X218.545 Y221.46 Z56.98 E0.57227
Buffered code: G1 X219.051 Y220.667 Z57 E0.58677
Buffered code: ;LAYER:56
Buffered code: ;MESH:budVase.stl
Buffered code: G1 X219.647 Y219.972 Z57.02 E0.5711
Buffered code: G1 X219.976 Y219.627 Z57.03 E0.29736
Buffered code: G1 X220.677 Y219.052 Z57.05 E0.56555
Buffered code: G1 X221.455 Y218.562 Z57.07 E0.57353
Buffered code: G1 X221.871 Y218.365 Z57.08 E0.28712
Buffered code: G1 X222.73 Y218.026 Z57.1 E0.57604
Buffered code: G1 X223.621 Y217.8 Z57.121 E0.5734
Buffered code: G1 X224.536 Y217.673 Z57.141 E0.57622
Buffered code: G1 X225.452 Y217.675 Z57.161 E0.57138
Buffered code: G1 X226.373 Y217.792 Z57.181 E0.57911
Buffered code: G1 X227.266 Y218.036 Z57.201 E0.57745
Buffered code: G1 X228.121 Y218.378 Z57.221 E0.57441
Buffered code: G1 X228.533 Y218.578 Z57.231 E0.28568
Buffered code: G1 X229.309 Y219.072 Z57.251 E0.57381
Buffered code: G1 X230.027 Y219.672 Z57.272 E0.58367
Buffered code: G1 X230.324 Y219.983 Z57.281 E0.26824
==> 1488 bytes
Pending code: G1 X230.922 Y220.694 Z57.302 E0.57953
Pending code: G1 X231.412 Y221.472 Z57.322 E0.57353
Pending code: G1 X231.611 Y221.883 Z57.332 E0.28484
Daemon:
Buffered code: G4 S1
==> 32 bytes
Executing macro daemon.g, started by system
Number of flush requests: 1
> Next stack level
Code buffer space: 1376
Configured SPI speed: 8000000Hz, TfrRdy pin glitches: 0
Full transfers per second: 39.38, max time between full transfers: 47.7ms, max pin wait times: 47.1ms/2.2ms
Codes per second: 11.33
Maximum length of RX/TX data transfers: 3778/1664

jay_s_uk

@awitc one for @chrishamm

chrishamm

@awitc I suggest you replace the microSD card in the SBC with an A1- or A2-rated card like this. They're better suited for SBC operation and improve overall responsiveness of the SBC. The reason for sluggish movement in SBC mode is usually an overloaded SBC and/or poor IO performance.

awitc

Thanks for the replies, I have ordered a new SD card, it has 200/90 MB/s speeds:

Will give an update after I get the card.

awitc

@chrishamm

I've changed the SD card for the new one, however, during the first test print the issue occured again, here is the M122 result, done right after it paused and resumed:

8.09.2023, 12:34:59 	M122
=== Diagnostics ===
RepRapFirmware for Duet 3 MB6XD version 3.4.6 (2023-07-21 14:11:58) running on Duet 3 MB6XD v1.0 (SBC mode)
Board ID: 08DLM-956DA-M24S4-7JKD0-3SJ6P-1BJLU
Used output buffers: 1 of 40 (15 max)
=== RTOS ===
Static ram: 151692
Dynamic ram: 67564 of which 0 recycled
Never used RAM 128416, free system stack 154 words
Tasks: SBC(ready,0.6%,487) HEAT(notifyWait,0.1%,346) Move(notifyWait,0.2%,252) CanReceiv(notifyWait,0.0%,798) CanSender(notifyWait,0.0%,335) CanClock(delaying,0.0%,349) MAIN(running,99.0%,1232) IDLE(ready,0.1%,29), total 100.0%
Owned mutexes: HTTP(MAIN)
=== Platform ===
Last reset 00:20:18 ago, cause: software
Last software reset at 2023-09-08 12:14, reason: User, GCodes spinning, available RAM 131648, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00400000 BFAR 0x00000000 SP 0x00000000 Task SBC Freestk 0 n/a
Error status: 0x00
Step timer max interval 18681
MCU temperature: min 37.3, current 39.0, max 39.1
Supply voltage: min 23.9, current 23.9, max 24.0, under voltage events: 0, over voltage events: 0, power good: yes
12V rail voltage: min 11.8, current 12.2, max 12.4, under voltage events: 0
Heap OK, handles allocated/used 99/3, heap memory allocated/used/recyclable 2048/54/10, gc cycles 0
Events: 0 queued, 0 completed
Driver 0: ok
Driver 1: ok
Driver 2: ok
Driver 3: ok
Driver 4: ok
Driver 5: ok
Date/time: 2023-09-08 12:34:58
Slowest loop: 55.53ms; fastest: 0.03ms
=== Storage ===
Free file entries: 10
SD card 0 not detected, interface speed: 37.5MBytes/sec
SD card longest read time 0.0ms, write time 0.0ms, max retries 0
=== Move ===
DMs created 125, segments created 6, maxWait 551121ms, bed compensation in use: none, comp offset 0.000
=== MainDDARing ===
Scheduled moves 1345, completed 1320, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 1], CDDA state 3
=== AuxDDARing ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
=== Heat ===
Bed heaters 8 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
Heater 1 is on, I-accum = 0.3
Heater 2 is on, I-accum = 0.2
Heater 3 is on, I-accum = 0.3
Heater 4 is on, I-accum = 0.0
Heater 5 is on, I-accum = 0.0
Heater 6 is on, I-accum = 0.0
Heater 8 is on, I-accum = 0.1
=== GCodes ===
Segments left: 1
Movement lock held by null
HTTP* is doing "M122" in state(s) 0
Telnet is idle in state(s) 0
File* is doing "G1 X244.630997 Y217.218002 Z13.319000 E0.823970" 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 doing "G4 S30" in state(s) 0 0, running macro
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
Code queue is empty
=== CAN ===
Messages queued 10972, received 19471, lost 0, boc 0
Longest wait 1ms for reply type 6018, peak Tx sync delay 389, free buffers 50 (min 49), ts 6091/6090/0
Tx timeouts 0,0,0,0,0,0
=== SBC interface ===
Transfer state: 5, failed transfers: 0, checksum errors: 0
RX/TX seq numbers: 33732/33732
SPI underruns 0, overruns 0
State: 5, disconnects: 1, timeouts: 1 total, 1 by SBC, IAP RAM available 0x2b584
Buffer RX/TX: 3616/1704-3688, open files: 0
=== Duet Control Server ===
Duet Control Server v3.4.6
File /opt/dsf/sd/gcodes/CFFFP_budVase.gcode is selected, processing
File:
Buffered code: G1 X244.631 Y217.218 Z13.319 E0.82397
Buffered code: G1 X245.087 Y218.482 Z13.33 E0.83802
Buffered code: G1 X245.455 Y219.748 Z13.34 E0.82221
Buffered code: G1 X245.745 Y221.039 Z13.349 E0.82518
Buffered code: G1 X245.954 Y222.354 Z13.36 E0.83039
Buffered code: G1 X246.08 Y223.673 Z13.369 E0.82633
Buffered code: G1 X246.122 Y225 Z13.379 E0.82799
Buffered code: G1 X246.082 Y226.327 Z13.389 E0.82795
Buffered code: G1 X245.958 Y227.647 Z13.399 E0.82684
Buffered code: G1 X245.751 Y228.962 Z13.41 E0.8302
Buffered code: G1 X245.464 Y230.253 Z13.419 E0.82478
Buffered code: G1 X245.096 Y231.522 Z13.429 E0.82401
Buffered code: G1 X244.642 Y232.786 Z13.44 E0.8376
Buffered code: G1 X244.117 Y234 Z13.45 E0.82487
Buffered code: G1 X243.524 Y235.166 Z13.459 E0.81581
Buffered code: G1 X242.837 Y236.33 Z13.47 E0.84293
Buffered code: G1 X242.102 Y237.413 Z13.479 E0.81626
Buffered code: G1 X241.275 Y238.48 Z13.49 E0.84191
Buffered code: G1 X240.412 Y239.46 Z13.499 E0.81437
Buffered code: G1 X239.469 Y240.408 Z13.509 E0.8339
Buffered code: G1 X238.476 Y241.283 Z13.519 E0.8254
Buffered code: G1 X237.416 Y242.106 Z13.53 E0.83693
Buffered code: G1 X236.334 Y242.842 Z13.539 E0.81609
Buffered code: G1 X235.17 Y243.532 Z13.55 E0.84389
Buffered code: G1 X234.004 Y244.127 Z13.559 E0.81637
Buffered code: G1 X232.79 Y244.653 Z13.569 E0.82512
Buffered code: G1 X231.527 Y245.109 Z13.58 E0.83743
==> 1512 bytes
Pending code: G1 X230.257 Y245.479 Z13.59 E0.82496
Pending code: G1 X228.966 Y245.768 Z13.599 E0.82505
Pending code: G1 X227.649 Y245.978 Z13.61 E0.83172
Pending code: G1 X226.328 Y246.104 Z13.62 E0.82757
Pending code: G1 X225 Y246.146 Z13.63 E0.82861
Daemon:
Buffered code: G4 S30 ; wait 30 seconds
Buffered code: M106 P3 S0 ; disable fans
Buffered code: G4 S1
==> 104 bytes
Executing macro daemon.g, started by system
Number of flush requests: 1
> Next stack level
Code buffer space: 1856
Configured SPI speed: 8000000Hz, TfrRdy pin glitches: 0
Full transfers per second: 38.67, max time between full transfers: 74.3ms, max pin wait times: 64.8ms/5.6ms
Codes per second: 1.87
Maximum length of RX/TX data transfers: 3772/1392

awitc

I tried such microsteps:

M350 X16 Y16 Z16 E16 I1

but the same problem, back to back, in short time interval, in the middle of the print. Also I find the printer to be way louder in this interpolation mode.

awitc

I might have tracked the issue down, asked a question with more information here. If my daemon.g was the culprit, sorry for the waste of time.