1LC - extruder randomly stopping/reversing - ≤3.5.0-rc1

[dc42]

@Exerqtor are you saying that when the issue occurs, the hot end stops heating and cools down?

Last reset 00:17:36 ago, cause: VDD brownout

Looks like the 1LC lost power.

[Exerqtor]

@dc42 said in 1LC - extruder randomly stopping/reversing - ≤3.5.0-r1:

@Exerqtor are you saying that when the issue occurs, the hot end stops heating and cools down?

No nothing happens except it stops feeding filament. The cooling down part in the temp chart is from after I've cancelled the print.

I only included the temp charts to show that the temps are stable (I've updated the "caption" for the chart images on the original posts to reflect on that now).

Last reset 00:17:36 ago, cause: VDD brownout

Looks like the 1LC lost power.

That's from the reset point is from when i rebooted the mainboard after installing 3.5.0-rc3+, I've got relays on the 24v lines controlled with PS_ON.

[Exerqtor]

@dc42 Got any ideas for things i can do to try helping you narrowing down whats causing this?

Needless to say it renders the machine useless.

[Exerqtor]

@dc42 I've looked at the commits you did to 3.5-dev yesterday, are these potential solutions for this bug?

[dc42]

@Exerqtor yes, possibly.

[Exerqtor]

@dc42 said in 1LC - extruder randomly stopping/reversing - ≤3.5.0-r1:

@Exerqtor yes, possibly.

Bad news then i guess, i just installed and tested the 3.5-dev build chrisham built a couple hours ago and tried a test print. And it's the exact same behaviour as before.

M122 & M122 B121 when it was extruding as it should:

2024-02-15 16:16:01 [debug] === Diagnostics ===
2024-02-15 16:16:01 [debug] RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.3+2 (2024-02-15 13:14:48) running on Duet 3 Mini5plus WiFi (standalone mode)
2024-02-15 16:16:01 [debug] Board ID: XNHXF-HR6KL-K65J0-409N2-K9W1Z-RV2MZ
2024-02-15 16:16:01 [debug] Used output buffers: 1 of 40 (40 max)
2024-02-15 16:16:01 [debug] === RTOS ===
2024-02-15 16:16:01 [debug] Static ram: 103264
2024-02-15 16:16:01 [debug] Dynamic ram: 128464 of which 0 recycled
2024-02-15 16:16:01 [debug] Never used RAM 5648, free system stack 139 words
2024-02-15 16:16:01 [debug] Tasks:
2024-02-15 16:16:01 [debug]  NETWORK(1,ready,15.0%,196)
2024-02-15 16:16:01 [debug]  HEAT(3,nWait 6,0.0%,330)
2024-02-15 16:16:01 [debug]  Move(4,nWait 6,0.4%,243)
2024-02-15 16:16:01 [debug]  CanReceiv(6,nWait 1,0.1%,798)
2024-02-15 16:16:01 [debug]  CanSender(5,nWait 7,0.0%,337)
2024-02-15 16:16:01 [debug]  CanClock(7,delaying,0.0%,340)
2024-02-15 16:16:01 [debug]  TMC(4,nWait 6,0.8%,68)
2024-02-15 16:16:01 [debug]  MAIN(1,running,81.8%,577)
2024-02-15 16:16:01 [debug]  IDLE(0,ready,1.0%,30)
2024-02-15 16:16:01 [debug]  AIN(4,delaying,0.9%,260)
2024-02-15 16:16:01 [debug] , total 100.0%
Owned mutexes:
2024-02-15 16:16:01 [debug]  WiFi(NETWORK)
2024-02-15 16:16:01 [debug] === Platform ===
2024-02-15 16:16:01 [debug] Last reset 00:17:18 ago, cause: software
2024-02-15 16:16:01 [debug] Last software reset at 2024-02-15 15:58, reason: User, Gcodes spinning, available RAM 12024, slot 2
2024-02-15 16:16:01 [debug] Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
2024-02-15 16:16:01 [debug] Error status: 0x04
2024-02-15 16:16:01 [debug] Aux0 errors 0,0,0
2024-02-15 16:16:01 [debug] MCU revision 3, ADC conversions started 1039400, completed 1039398, timed out 0, errs 0
2024-02-15 16:16:01 [debug] MCU temperature: min 35.1, current 46.4, max 46.7
2024-02-15 16:16:01 [debug] Supply voltage: min 2.9, current 23.9, max 24.5, under voltage events: 0, over voltage events: 0, power good: yes
2024-02-15 16:16:01 [debug] Heap OK, handles allocated/used 198/33, heap memory allocated/used/recyclable 2048/516/56, gc cycles 249
2024-02-15 16:16:01 [debug] Events: 0 queued, 0 completed
2024-02-15 16:16:01 [debug] Driver 0: standstill, SG min 0, read errors 0, write errors 0, ifcnt 46, reads 54608, writes 46, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:16:01 [debug] Driver 1: ok, SG min 0, read errors 0, write errors 0, ifcnt 46, reads 54608, writes 46, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:16:01 [debug] Driver 2: ok, SG min 0, read errors 0, write errors 0, ifcnt 25, reads 54628, writes 25, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:16:01 [debug] Driver 3: ok, SG min 0, read errors 0, write errors 0, ifcnt 24, reads 54629, writes 24, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:16:01 [debug] Driver 4: ok, SG min 0, read errors 0, write errors 0, ifcnt 25, reads 54629, writes 25, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:16:01 [debug] Driver 5: not present
2024-02-15 16:16:01 [debug] Driver 6: not present
2024-02-15 16:16:01 [debug] Date/time: 
2024-02-15 16:16:01 [debug] 2024-02-15 16:16:01
2024-02-15 16:16:01 [debug] Cache data hit count 1679802919
2024-02-15 16:16:01 [debug] Slowest loop: 219.43ms; fastest: 0.09ms
2024-02-15 16:16:01 [debug] === Storage ===
Free file entries: 16
2024-02-15 16:16:01 [debug] SD card 0 detected, interface speed: 22.5MBytes/sec
2024-02-15 16:16:01 [debug] SD card longest read time 7.5ms, write time 4.6ms, max retries 0
2024-02-15 16:16:01 [debug] === Move ===
DMs created 83, segments created 16, maxWait 400258ms, bed compensation in use: mesh, height map offset 0.000, max steps late 1, min interval 0, bad calcs 0, ebfmin 0.00, ebfmax 0.00
2024-02-15 16:16:01 [debug] next step interrupt due in 19 ticks, enabled
2024-02-15 16:16:01 [debug] Moves shaped first try 68, on retry 0, too short 25, wrong shape 1411, maybepossible 4
2024-02-15 16:16:01 [debug] === DDARing 0 ===
Scheduled moves 1676, completed 1654, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 1], CDDA state 3
2024-02-15 16:16:01 [debug] === DDARing 1 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
2024-02-15 16:16:01 [debug] === Heat ===
2024-02-15 16:16:01 [debug] Bed heaters 0 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
2024-02-15 16:16:01 [debug] Heater 0 is on, I-accum = 0.5
2024-02-15 16:16:01 [debug] Heater 1 is on, I-accum = 0.0
2024-02-15 16:16:01 [debug] === GCodes ===
2024-02-15 16:16:01 [debug] Movement locks held by null, null
2024-02-15 16:16:01 [debug] HTTP is idle in state(s) 0
2024-02-15 16:16:01 [debug] Telnet is idle in state(s) 0
2024-02-15 16:16:01 [debug] File is idle in state(s) 3
2024-02-15 16:16:01 [debug] USB is idle in state(s) 0
2024-02-15 16:16:01 [debug] Aux is idle in state(s) 0
2024-02-15 16:16:01 [debug] Trigger is idle in state(s) 0
2024-02-15 16:16:01 [debug] Queue is idle in state(s) 0
2024-02-15 16:16:01 [debug] LCD is idle in state(s) 0
2024-02-15 16:16:01 [debug] SBC is idle in state(s) 0
2024-02-15 16:16:01 [debug] Daemon is doing "G4 P250" in state(s) 0 0, running macro
2024-02-15 16:16:01 [debug] Aux2 is idle in state(s) 0
2024-02-15 16:16:01 [debug] Autopause is idle in state(s) 0
2024-02-15 16:16:01 [debug] File2 is idle in state(s) 0, sync state 1
2024-02-15 16:16:01 [debug] Queue2 is idle in state(s) 0
2024-02-15 16:16:01 [debug] Q0 segments left 4, axes/extruders owned 0x0000807
2024-02-15 16:16:01 [debug] Code queue 0 is empty
2024-02-15 16:16:01 [debug] Q1 segments left 0, axes/extruders owned 0x0000000
2024-02-15 16:16:01 [debug] Code queue 1 is empty
2024-02-15 16:16:01 [debug] === Filament sensors ===
check 0 clear 0
2024-02-15 16:16:01 [debug] Extruder 0 sensor: no filament
2024-02-15 16:16:01 [debug] === CAN ===
2024-02-15 16:16:01 [debug] Messages queued 10994, received 21506, lost 0, errs 1229, boc 0
2024-02-15 16:16:01 [debug] Longest wait 2ms for reply type 6031, peak Tx sync delay 45006, free buffers 26 (min 25), ts 5195/5193/0
2024-02-15 16:16:01 [debug] Tx timeouts 0,0,1,0,0,0 last cancelled message type 30 dest 127
2024-02-15 16:16:01 [debug] === Network ===
2024-02-15 16:16:01 [debug] Slowest loop: 13.08ms; fastest: 0.00ms
2024-02-15 16:16:01 [debug] Responder states:
2024-02-15 16:16:01 [debug]  MQTT(0)
2024-02-15 16:16:01 [debug]  HTTP(2)
2024-02-15 16:16:01 [debug]  HTTP(0)
2024-02-15 16:16:01 [debug]  HTTP(0)
2024-02-15 16:16:01 [debug]  HTTP(0)
2024-02-15 16:16:01 [debug]  FTP(0)
2024-02-15 16:16:01 [debug]  Telnet(0)
2024-02-15 16:16:01 [debug] HTTP sessions: 1 of 8
2024-02-15 16:16:01 [debug] === WiFi ===
Interface state: active
Module is connected to access point 
Failed messages: pending 0, notrdy 0, noresp 0
2024-02-15 16:16:01 [debug] Firmware version 2.1beta6
2024-02-15 16:16:01 [debug] MAC address c4:5b:be:ce:91:93
2024-02-15 16:16:01 [debug] Module reset reason: Power up, Vcc 3.38, flash size 2097152, free heap 36456
2024-02-15 16:16:01 [debug] WiFi IP address 192.168.10.x
2024-02-15 16:16:01 [debug] Signal strength -48dBm, channel 6, mode 802.11n, reconnections 0
2024-02-15 16:16:01 [debug] Clock register 00002001
2024-02-15 16:16:01 [debug] Socket states:
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:01 [debug]  0
2024-02-15 16:16:04 [debug] Diagnostics for board 121:
2024-02-15 16:16:04 [debug] Duet TOOL1LC rev 1.1 or later firmware version 3.5.0-rc.3+ (2024-02-15 13:22:28)
Bootloader ID: SAMC21 bootloader version 2.4 (2021-12-10)
All averaging filters OK
2024-02-15 16:16:04 [debug] Never used RAM 3608, free system stack 87 words
Tasks: Move(3,nWait 7,0.0%,91) HEAT(2,nWait 6,0.4%,99) CanAsync(5,nWait 4,0.0%,51) CanRecv(3,nWait 1,0.0%,73) CanClock(5,nWait 1,0.0%,61) ACCEL(3,nWait 6,0.0%,53) TMC(2,nWait 6,3.4%,53) MAIN(1,running,91.2%,317) IDLE(0,ready,0.0%,27) AIN(2,delaying,4.9%,112), total 100.0%
Owned mutexes:
Last reset 00:17:21 ago, cause: power up
2024-02-15 16:16:04 [debug] Last software reset data not available
2024-02-15 16:16:04 [debug] Driver 0: pos 0, 568.8 steps/mm, ok, SG min 0, read errors 0, write errors 0, ifcnt 12, reads 61756, writes 12, timeouts 0, DMA errors 0, CC errors 0, steps req 0 done 221092
2024-02-15 16:16:04 [debug] Moves scheduled 1506, completed 1505, in progress 1, hiccups 8, segs 18, step errors 0, maxLate 0 maxPrep 296, maxOverdue 44, maxInc 44, mcErrs 0, gcmErrs 0, ebfmin 0.00 max 1.00
Peak sync jitter -2/9, peak Rx sync delay 214, resyncs 0/0, no timer interrupt scheduled
VIN voltage: min 23.3, current 24.1, max 24.4
MCU temperature: min 39.7C, current 55.6C, max 55.6C
2024-02-15 16:16:04 [debug] Last sensors broadcast 0x00000012 found 2 1 ticks ago, 0 ordering errs, loop time 1
CAN messages queued 21590, send timeouts 0, received 11048, lost 0, errs 0, boc 0, free buffers 18, min 18, error reg 0
dup 0, oos 0/0/0/0, bm 0, wbm 0, rxMotionDelay 338, adv 35679/105203
2024-02-15 16:16:04 [debug] Accelerometer: LIS3DH, status: 00
I2C bus errors 0, naks 3, contentions 0, other errors 0
=== Filament sensors ===
Interrupt 5726621 to 0us, poll 4 to 1481us
Driver 0: ok

M122 & M122 B121 when it had stopped extruding:

2024-02-15 16:18:11 [debug] === Diagnostics ===
2024-02-15 16:18:11 [debug] RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.3+2 (2024-02-15 13:14:48) running on Duet 3 Mini5plus WiFi (standalone mode)
2024-02-15 16:18:11 [debug] Board ID: XNHXF-HR6KL-K65J0-409N2-K9W1Z-RV2MZ
2024-02-15 16:18:11 [debug] Used output buffers: 1 of 40 (40 max)
2024-02-15 16:18:11 [debug] === RTOS ===
2024-02-15 16:18:11 [debug] Static ram: 103264
2024-02-15 16:18:11 [debug] Dynamic ram: 128464 of which 0 recycled
2024-02-15 16:18:11 [debug] Never used RAM 5120, free system stack 139 words
2024-02-15 16:18:11 [debug] Tasks:
2024-02-15 16:18:11 [debug]  NETWORK(1,ready,17.5%,196)
2024-02-15 16:18:11 [debug]  HEAT(3,nWait 6,0.0%,330)
2024-02-15 16:18:11 [debug]  Move(4,nWait 6,2.4%,242)
2024-02-15 16:18:11 [debug]  CanReceiv(6,nWait 1,0.1%,798)
2024-02-15 16:18:11 [debug]  CanSender(5,nWait 7,0.0%,337)
2024-02-15 16:18:11 [debug]  CanClock(7,delaying,0.0%,340)
2024-02-15 16:18:11 [debug]  TMC(4,nWait 6,0.9%,68)
2024-02-15 16:18:11 [debug]  MAIN(1,running,77.2%,577)
2024-02-15 16:18:11 [debug]  IDLE(0,ready,0.9%,30)
2024-02-15 16:18:11 [debug]  AIN(4,delaying,0.9%,260)
2024-02-15 16:18:11 [debug] , total 100.0%
Owned mutexes:
2024-02-15 16:18:11 [debug] === Platform ===
2024-02-15 16:18:11 [debug] Last reset 00:19:29 ago, cause: software
2024-02-15 16:18:11 [debug] Last software reset at 2024-02-15 15:58, reason: User, Gcodes spinning, available RAM 12024, slot 2
2024-02-15 16:18:11 [debug] Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
2024-02-15 16:18:11 [debug] Error status: 0x04
2024-02-15 16:18:11 [debug] Aux0 errors 0,0,0
2024-02-15 16:18:11 [debug] MCU revision 3, ADC conversions started 1169660, completed 1169659, timed out 0, errs 0
2024-02-15 16:18:11 [debug] MCU temperature: min 46.0, current 46.4, max 46.6
2024-02-15 16:18:11 [debug] Supply voltage: min 23.7, current 23.7, max 24.3, under voltage events: 0, over voltage events: 0, power good: yes
2024-02-15 16:18:11 [debug] Heap OK, handles allocated/used 198/33, heap memory allocated/used/recyclable 2048/708/248, gc cycles 277
2024-02-15 16:18:11 [debug] Events: 0 queued, 0 completed
2024-02-15 16:18:11 [debug] Driver 0: ok, SG min 0, read errors 0, write errors 0, ifcnt 46, reads 6852, writes 0, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:18:11 [debug] Driver 1: ok, SG min 0, read errors 0, write errors 0, ifcnt 46, reads 6852, writes 0, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:18:11 [debug] Driver 2: ok, SG min 0, read errors 0, write errors 0, ifcnt 25, reads 6853, writes 0, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:18:11 [debug] Driver 3: ok, SG min 0, read errors 0, write errors 0, ifcnt 24, reads 6852, writes 0, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:18:11 [debug] Driver 4: ok, SG min 0, read errors 0, write errors 0, ifcnt 25, reads 6853, writes 0, timeouts 0, DMA errors 0, CC errors 0
2024-02-15 16:18:11 [debug] Driver 5: not present
2024-02-15 16:18:11 [debug] Driver 6: not present
2024-02-15 16:18:11 [debug] Date/time: 
2024-02-15 16:18:11 [debug] 2024-02-15 16:18:11
2024-02-15 16:18:11 [debug] Cache data hit count 1862287218
2024-02-15 16:18:11 [debug] Slowest loop: 40.78ms; fastest: 0.14ms
2024-02-15 16:18:11 [debug] === Storage ===
Free file entries: 16
2024-02-15 16:18:11 [debug] SD card 0 detected, interface speed: 22.5MBytes/sec
2024-02-15 16:18:11 [debug] SD card longest read time 9.1ms, write time 4.8ms, max retries 0
2024-02-15 16:18:11 [debug] === Move ===
DMs created 83, segments created 38, maxWait 852ms, bed compensation in use: mesh, height map offset 0.000, max steps late 1, min interval 0, bad calcs 0, ebfmin 0.00, ebfmax 0.00
2024-02-15 16:18:11 [debug] next step interrupt due in 26 ticks, enabled
2024-02-15 16:18:11 [debug] Moves shaped first try 267, on retry 62, too short 102, wrong shape 1241, maybepossible 57
2024-02-15 16:18:11 [debug] === DDARing 0 ===
Scheduled moves 4017, completed 3977, hiccups 0, stepErrors 0, LaErrors 2, Underruns [0, 0, 0], CDDA state 3
2024-02-15 16:18:11 [debug] === DDARing 1 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
2024-02-15 16:18:11 [debug] === Heat ===
2024-02-15 16:18:11 [debug] Bed heaters 0 -1 -1 -1, chamber heaters -1 -1 -1 -1, ordering errs 0
2024-02-15 16:18:11 [debug] Heater 0 is on, I-accum = 0.5
2024-02-15 16:18:11 [debug] Heater 1 is on, I-accum = 0.0
2024-02-15 16:18:11 [debug] === GCodes ===
2024-02-15 16:18:11 [debug] Movement locks held by null, null
2024-02-15 16:18:11 [debug] HTTP is idle in state(s) 0
2024-02-15 16:18:11 [debug] Telnet is idle in state(s) 0
2024-02-15 16:18:11 [debug] File is doing "G1 X213.608 Y214.933 E.00193" in state(s) 0
2024-02-15 16:18:11 [debug] USB is idle in state(s) 0
2024-02-15 16:18:11 [debug] Aux is idle in state(s) 0
2024-02-15 16:18:11 [debug] Trigger is idle in state(s) 0
2024-02-15 16:18:11 [debug] Queue is idle in state(s) 0
2024-02-15 16:18:11 [debug] LCD is idle in state(s) 0
2024-02-15 16:18:11 [debug] SBC is idle in state(s) 0
2024-02-15 16:18:11 [debug] Daemon is doing "G4 P250" in state(s) 0 0, running macro
2024-02-15 16:18:11 [debug] Aux2 is idle in state(s) 0
2024-02-15 16:18:11 [debug] Autopause is idle in state(s) 0
2024-02-15 16:18:11 [debug] File2 is idle in state(s) 0, sync state 1
2024-02-15 16:18:11 [debug] Queue2 is idle in state(s) 0
2024-02-15 16:18:11 [debug] Q0 segments left 1, axes/extruders owned 0x0000807
2024-02-15 16:18:11 [debug] Code queue 0 is empty
2024-02-15 16:18:11 [debug] Q1 segments left 0, axes/extruders owned 0x0000000
2024-02-15 16:18:11 [debug] Code queue 1 is empty
2024-02-15 16:18:11 [debug] === Filament sensors ===
check 0 clear 0
2024-02-15 16:18:11 [debug] Extruder 0 sensor: no filament
2024-02-15 16:18:11 [debug] === CAN ===
2024-02-15 16:18:11 [debug] Messages queued 3206, received 2701, lost 0, errs 0, boc 0
2024-02-15 16:18:11 [debug] Longest wait 6ms for reply type 6024, peak Tx sync delay 9, free buffers 26 (min 25), ts 651/651/0
2024-02-15 16:18:11 [debug] Tx timeouts 0,0,0,0,0,0
2024-02-15 16:18:11 [debug] === Network ===
2024-02-15 16:18:11 [debug] Slowest loop: 19.53ms; fastest: 0.06ms
2024-02-15 16:18:11 [debug] Responder states:
2024-02-15 16:18:11 [debug]  MQTT(0)
2024-02-15 16:18:11 [debug]  HTTP(0)
2024-02-15 16:18:11 [debug]  HTTP(0)
2024-02-15 16:18:11 [debug]  HTTP(0)
2024-02-15 16:18:11 [debug]  HTTP(0)
2024-02-15 16:18:11 [debug]  FTP(0)
2024-02-15 16:18:11 [debug]  Telnet(0)
2024-02-15 16:18:11 [debug] HTTP sessions: 1 of 8
2024-02-15 16:18:11 [debug] === WiFi ===
Interface state: active
Module is connected to access point 
Failed messages: pending 0, notrdy 0, noresp 0
2024-02-15 16:18:11 [debug] Firmware version 2.1beta6
2024-02-15 16:18:11 [debug] MAC address c4:5b:be:ce:91:93
2024-02-15 16:18:11 [debug] Module reset reason: Power up, Vcc 3.38, flash size 2097152, free heap 43160
2024-02-15 16:18:11 [debug] WiFi IP address 192.168.10.x
2024-02-15 16:18:11 [debug] Signal strength -49dBm, channel 6, mode 802.11n, reconnections 0
2024-02-15 16:18:11 [debug] Clock register 00002001
2024-02-15 16:18:11 [debug] Socket states:
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:11 [debug]  0
2024-02-15 16:18:14 [debug] Diagnostics for board 121:
2024-02-15 16:18:14 [debug] Duet TOOL1LC rev 1.1 or later firmware version 3.5.0-rc.3+ (2024-02-15 13:22:28)
Bootloader ID: SAMC21 bootloader version 2.4 (2021-12-10)
All averaging filters OK

(Had to grab them from the debug file because DWC disconnected and i lost the console printout).

[dc42]

@Exerqtor thanks for testing this again. I'm puzzles that ebfmax is showing as zero in the Move section of both M122 reports. After doing some printing, it's normally close to 1.0.

[Exerqtor]

@dc42 No problem, yeah i have no idea what could be the cause 😓

[Exerqtor]

@dc42 i saw you added some more debug features for the expansionboard firmware on friday, do you have a build with that i could try to run? Or do you need to put some more things in it first?

[dc42]

@Exerqtor you are very welcome to try the new Duet 3 Mini TOOL1LC and files at https://www.dropbox.com/scl/fo/p0136wx04h8xf6ejwdnn9/h?rlkey=efrfwyb6o5tqid11gustz3uvy&dl=0. The changes clear up some issues I was having with debug output, caused by variables that were output even when they should not have been used in the current context. I don't think they should change the printing behaviour, but I could be wrong.

[Exerqtor]

@dc42 I'll try it this evening, maybe it will give some more insight into whats causing the stops (one might hope).

[dc42]

@Exerqtor please use the updated (this morning) main board file from there too.

[Exerqtor]

@dc42 said in 1LC - extruder randomly stopping/reversing - ≤3.5.0-r1:

@Exerqtor please use the updated (this morning) main board file from there too.

Just had time to install and test those binaries, and the behaviour is the same (as expected), here are the treports:

Extruding as normal:

M122
=== Diagnostics ===
RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.3+ (2024-02-19 14:31:40) running on Duet 3 Mini5plus WiFi (standalone mode)
Board ID: XNHXF-HR6KL-K65J0-409N2-K9W1Z-RV2MZ
Used output buffers: 13 of 40 (38 max)
=== RTOS ===
Static ram: 103264
Dynamic ram: 128464 of which 0 recycled
Never used RAM 5696, free system stack 135 words
Tasks: NETWORK(1,ready,104.7%,234) HEAT(3,nWait 6,0.4%,326) Move(4,nWait 6,0.1%,241) CanReceiv(6,nWait 1,0.9%,771) CanSender(5,nWait 7,0.0%,337) CanClock(7,delaying,0.1%,340) TMC(4,nWait 6,14.1%,68) MAIN(1,running,76.0%,591) IDLE(0,ready,13.8%,30) AIN(4,delaying,14.6%,260), total 224.8%
Owned mutexes:
=== Platform ===
Last reset 21:57:15 ago, cause: software
Last software reset at 2024-02-19 18:20, reason: User, Gcodes spinning, available RAM 12072, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
Error status: 0x00
Aux0 errors 0,0,0
MCU revision 3, ADC conversions started 79036613, completed 79036613, timed out 0, errs 0
MCU temperature: min 33.2, current 40.4, max 40.4
Supply voltage: min 2.9, current 24.1, max 24.4, under voltage events: 0, over voltage events: 0, power good: yes
Heap OK, handles allocated/used 198/33, heap memory allocated/used/recyclable 2048/1668/1208, gc cycles 17303
Events: 0 queued, 0 completed
Driver 0: standstill, SG min 0, read errors 0, write errors 0, ifcnt 31, reads 30953, writes 31, timeouts 0, DMA errors 0, CC errors 0
Driver 1: ok, SG min 0, read errors 0, write errors 0, ifcnt 31, reads 30953, writes 31, timeouts 0, DMA errors 0, CC errors 0
Driver 2: ok, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 30963, writes 20, timeouts 0, DMA errors 0, CC errors 0
Driver 3: ok, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 30963, writes 20, timeouts 0, DMA errors 0, CC errors 0
Driver 4: ok, SG min 0, read errors 0, write errors 0, ifcnt 21, reads 30963, writes 21, timeouts 0, DMA errors 0, CC errors 0
Driver 5: not present
Driver 6: not present
Date/time: 2024-02-20 16:18:00
Cache data hit count 4294967295
Slowest loop: 222.67ms; fastest: 0.09ms
=== Storage ===
Free file entries: 16
SD card 0 detected, interface speed: 22.5MBytes/sec
SD card longest read time 6.5ms, write time 7.6ms, max retries 0
=== Move ===
DMs created 83, segments created 14, maxWait 78892045ms, bed compensation in use: mesh, height map offset 0.000, max steps late 1, min interval 0, bad calcs 0, ebfmin 0.00, ebfmax 0.00
next step interrupt due in 42 ticks, enabled
Moves shaped first try 45, on retry 0, too short 4, wrong shape 270, maybepossible 1
=== DDARing 0 ===
Scheduled moves 466, completed 429, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state 3
=== 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.6
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) 3
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 P250" in state(s) 0 0, running macro
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 5, axes/extruders owned 0x0000807
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== Filament sensors ===
check 0 clear 0
Extruder 0 sensor: no filament
=== CAN ===
Messages queued 711746, received 1620463, lost 0, errs 1232, boc 0
Longest wait 4ms for reply type 6013, peak Tx sync delay 45480, free buffers 26 (min 25), ts 395179/395177/0
Tx timeouts 0,0,1,0,0,0 last cancelled message type 30 dest 127
=== Network ===
Slowest loop: 11.33ms; 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 2.1beta7
MAC address c4:5b:be:ce:91:93
Module reset reason: Power up, Vcc 3.38, flash size 2097152, free heap 42848
WiFi IP address 192.168.10.x
Signal strength -53dBm, channel 6, mode 802.11n, reconnections 0
Clock register 00002001
Socket states: 0 0 0 0 0 0 0 0
	M122 B121
Diagnostics for board 121:
Duet TOOL1LC rev 1.1 or later firmware version 3.5.0-rc.3+ (2024-02-15 13:22:28)
Bootloader ID: SAMC21 bootloader version 2.4 (2021-12-10)
All averaging filters OK
Never used RAM 3728, free system stack 95 words
Tasks: Move(3,nWait 7,0.0%,91) HEAT(2,nWait 6,6.4%,99) CanAsync(5,nWait 4,0.0%,49) CanRecv(3,nWait 1,0.2%,73) CanClock(5,nWait 1,0.4%,61) ACCEL(3,nWait 6,0.0%,53) TMC(2,nWait 6,58.7%,53) MAIN(1,running,74.2%,317) IDLE(0,ready,0.0%,27) AIN(2,delaying,84.7%,112), total 224.6%
Owned mutexes:
Last reset 21:57:20 ago, cause: power up
Last software reset data not available
Driver 0: pos 0, 568.8 steps/mm, ok, SG min 0, read errors 0, write errors 0, ifcnt 12, reads 1875, writes 12, timeouts 6, DMA errors 0, CC errors 0, failedOp 0x72, steps req 0 done 60570
Moves scheduled 315, completed 314, in progress 1, hiccups 7, segs 13, step errors 0, maxLate 0 maxPrep 279, maxOverdue 35, maxInc 27, mcErrs 0, gcmErrs 0, ebfmin 0.00 max 1.00
Peak sync jitter -3/10, peak Rx sync delay 221, resyncs 0/0, no timer interrupt scheduled
VIN voltage: min 23.3, current 24.0, max 24.4
MCU temperature: min 38.5C, current 48.5C, max 48.5C
Last sensors broadcast 0x00000012 found 2 62 ticks ago, 0 ordering errs, loop time 1
CAN messages queued 1620552, send timeouts 0, received 711820, lost 0, errs 0, boc 0, free buffers 18, min 18, error reg 0
dup 0, oos 0/0/0/0, bm 0, wbm 0, rxMotionDelay 325, adv 35688/74645
Accelerometer: LIS3DH, status: 00
I2C bus errors 0, naks 3, contentions 0, other errors 0
=== Filament sensors ===
Interrupt 5726621 to 0us, poll 2 to 1440us
Driver 0: ok

Not extruding:

M122
=== Diagnostics ===
RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.3+ (2024-02-19 14:31:40) running on Duet 3 Mini5plus WiFi (standalone mode)
Board ID: XNHXF-HR6KL-K65J0-409N2-K9W1Z-RV2MZ
Used output buffers: 13 of 40 (39 max)
=== RTOS ===
Static ram: 103264
Dynamic ram: 128464 of which 0 recycled
Never used RAM 5120, free system stack 134 words
Tasks: NETWORK(1,ready,17.4%,234) HEAT(3,nWait 6,0.0%,326) Move(4,nWait 6,2.4%,241) CanReceiv(6,nWait 1,0.1%,771) CanSender(5,nWait 7,0.0%,337) CanClock(7,delaying,0.0%,340) TMC(4,nWait 6,0.9%,68) MAIN(1,running,77.3%,591) IDLE(0,ready,1.0%,30) AIN(4,delaying,0.9%,260), total 100.0%
Owned mutexes:
=== Platform ===
Last reset 22:01:35 ago, cause: software
Last software reset at 2024-02-19 18:20, reason: User, Gcodes spinning, available RAM 12072, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
Error status: 0x00
Aux0 errors 0,0,0
MCU revision 3, ADC conversions started 79296559, completed 79296558, timed out 0, errs 0
MCU temperature: min 40.2, current 46.0, max 46.6
Supply voltage: min 23.6, current 24.2, max 24.3, under voltage events: 0, over voltage events: 0, power good: yes
Heap OK, handles allocated/used 198/33, heap memory allocated/used/recyclable 2048/484/24, gc cycles 17360
Events: 0 queued, 0 completed
Driver 0: standstill, SG min 0, read errors 0, write errors 0, ifcnt 31, reads 13673, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 1: ok, SG min 0, read errors 0, write errors 0, ifcnt 31, reads 13673, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 2: ok, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 13674, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 3: ok, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 13674, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 4: ok, SG min 0, read errors 0, write errors 0, ifcnt 21, reads 13673, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 5: not present
Driver 6: not present
Date/time: 2024-02-20 16:22:20
Cache data hit count 4294967295
Slowest loop: 37.44ms; fastest: 0.10ms
=== Storage ===
Free file entries: 16
SD card 0 detected, interface speed: 22.5MBytes/sec
SD card longest read time 8.9ms, write time 4.5ms, max retries 0
=== Move ===
DMs created 83, segments created 38, maxWait 2730ms, bed compensation in use: mesh, height map offset 0.000, max steps late 1, min interval 0, bad calcs 0, ebfmin 0.00, ebfmax 0.00
next step interrupt due in 19 ticks, enabled
Moves shaped first try 281, on retry 62, too short 121, wrong shape 2345, maybepossible 58
=== DDARing 0 ===
Scheduled moves 3991, completed 3953, hiccups 0, stepErrors 0, LaErrors 2, Underruns [0, 0, 0], CDDA state 3
=== 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.5
Heater 1 is on, I-accum = 0.0
=== GCodes ===
Movement locks held by File, null
HTTP is idle in state(s) 0
Telnet is idle in state(s) 0
File is doing "G11" 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 P250" in state(s) 0 0, running macro
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
File2 is idle in state(s) 0, sync state 1
Queue2 is idle in state(s) 0
Q0 segments left 0, axes/extruders owned 0x0000807
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== Filament sensors ===
check 0 clear 0
Extruder 0 sensor: no filament
=== CAN ===
Messages queued 5562, received 5362, lost 0, errs 0, boc 0
Longest wait 6ms for reply type 6024, peak Tx sync delay 197, free buffers 26 (min 25), ts 1299/1299/0
Tx timeouts 0,0,0,0,0,0
=== Network ===
Slowest loop: 19.84ms; fastest: 0.06ms
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 2.1beta7
MAC address c4:5b:be:ce:91:93
Module reset reason: Power up, Vcc 3.38, flash size 2097152, free heap 42848
WiFi IP address 192.168.10.x
Signal strength -53dBm, channel 6, mode 802.11n, reconnections 0
Clock register 00002001
Socket states: 0 0 0 0 0 0 0 0
M122 B121
Diagnostics for board 121:
Duet TOOL1LC rev 1.1 or later firmware version 3.5.0-rc.3+ (2024-02-15 13:22:28)
Bootloader ID: SAMC21 bootloader version 2.4 (2021-12-10)
All averaging filters OK
Never used RAM 3176, free system stack 73 words
Tasks: Move(3,nWait 7,0.3%,87) HEAT(2,nWait 6,0.4%,97) CanAsync(5,nWait 4,0.0%,49) CanRecv(3,nWait 1,0.1%,73) CanClock(5,nWait 1,0.0%,61) ACCEL(3,nWait 6,0.0%,53) TMC(2,nWait 6,3.5%,53) MAIN(1,running,90.6%,317) IDLE(0,ready,0.0%,27) AIN(2,delaying,5.1%,112), total 100.0%
Owned mutexes:
Last reset 22:01:41 ago, cause: power up
Last software reset data not available
Driver 0: pos 0, 568.8 steps/mm, standstill, SG min 0, read errors 0, write errors 0, ifcnt 12, reads 64518, writes 0, timeouts 0, DMA errors 0, CC errors 0, steps req 0 done 324593
Moves scheduled 2815, completed 2815, in progress 0, hiccups 93, segs 36, step errors 0, maxLate 0 maxPrep 885, maxOverdue 97, maxInc 69, mcErrs 0, gcmErrs 0, ebfmin 0.00 max inf
Peak sync jitter -1/9, peak Rx sync delay 230, resyncs 0/0, no timer interrupt scheduled
VIN voltage: min 23.9, current 24.1, max 24.4
MCU temperature: min 38.5C, current 54.3C, max 54.3C
Last sensors broadcast 0x00000012 found 2 158 ticks ago, 0 ordering errs, loop time 1
CAN messages queued 5379, send timeouts 0, received 5621, lost 0, errs 0, boc 0, free buffers 18, min 18, error reg 0
dup 0, oos 0/0/0/0, bm 0, wbm 0, rxMotionDelay 369, adv 36183/98932
Accelerometer: LIS3DH, status: 00
I2C bus errors 0, naks 0, contentions 0, other errors 0
=== Filament sensors ===
Interrupt 5726621 to 0us, poll 9 to 1716us
Driver 0: ok

Regarding when it stops extruding, i'm 99.9% sure it is when the inner & outer walls/perimeters of layer 3/0.65mm is done and when it goes over to the other part(s) of that layer (still using the same sliced job as supplied earlinger without alterations).

[dc42]

@Exerqtor thanks for testing. I've tracked down a bug in the input shaping code that could result in the main board asking the tool board to apply IS to a move when it was not possible. This could be relevant to your issue. So please try the new main board firmware at https://www.dropbox.com/scl/fo/p0136wx04h8xf6ejwdnn9/h?rlkey=efrfwyb6o5tqid11gustz3uvy&dl=0.

[Exerqtor]

@dc42 said in 1LC - extruder randomly stopping/reversing - ≤3.5.0-r1:

@Exerqtor thanks for testing. I've tracked down a bug in the input shaping code that could result in the main board asking the tool board to apply IS to a move when it was not possible. This could be relevant to your issue. So please try the new main board firmware at https://www.dropbox.com/scl/fo/p0136wx04h8xf6ejwdnn9/h?rlkey=efrfwyb6o5tqid11gustz3uvy&dl=0.

Ok I tested those binaries (I see that it's been updated/changed just two hours ago at the time of posting this reply) and it's still the same unfortunately. Here is some reports.

Extruding:

M122
=== Diagnostics ===
RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.3+ (2024-02-21 12:51:12) running on Duet 3 Mini5plus WiFi (standalone mode)
Board ID: XNHXF-HR6KL-K65J0-409N2-K9W1Z-RV2MZ
Used output buffers: 15 of 40 (30 max)
=== RTOS ===
Static ram: 103264
Dynamic ram: 128424 of which 0 recycled
Never used RAM 3680, free system stack 144 words
Tasks: NETWORK(2,nWait 7,17.6%,182) HEAT(3,nWait 6,0.0%,351) Move(4,nWait 6,0.2%,241) CanReceiv(6,nWait 1,0.1%,796) CanSender(5,nWait 7,0.0%,337) CanClock(7,delaying,0.0%,340) TMC(4,nWait 6,0.8%,68) MAIN(1,running,78.9%,602) IDLE(0,ready,1.5%,30) AIN(4,delaying,0.9%,260), total 100.0%
Owned mutexes:
=== Platform ===
Last reset 00:04:16 ago, cause: software
Last software reset at 2024-02-19 18:20, reason: User, Gcodes spinning, available RAM 12072, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
Error status: 0x00
Aux0 errors 0,0,0
MCU revision 3, ADC conversions started 257248, completed 257248, timed out 0, errs 0
MCU temperature: min 36.5, current 41.7, max 41.7
Supply voltage: min 2.6, current 24.1, max 24.4, under voltage events: 0, over voltage events: 0, power good: yes
Heap OK, handles allocated/used 198/33, heap memory allocated/used/recyclable 4096/2932/2472, gc cycles 59
Events: 0 queued, 0 completed
Driver 0: standstill, SG min 0, read errors 0, write errors 0, ifcnt 32, reads 13469, writes 32, timeouts 0, DMA errors 0, CC errors 0
Driver 1: ok, SG min 0, read errors 0, write errors 0, ifcnt 32, reads 13468, writes 32, timeouts 0, DMA errors 0, CC errors 0
Driver 2: ok, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 13480, writes 20, timeouts 0, DMA errors 0, CC errors 0
Driver 3: ok, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 13480, writes 20, timeouts 0, DMA errors 0, CC errors 0
Driver 4: ok, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 13481, writes 20, timeouts 0, DMA errors 0, CC errors 0
Driver 5: not present
Driver 6: not present
Date/time: 2024-02-21 15:59:49
Cache data hit count 419633128
Slowest loop: 224.31ms; fastest: 0.09ms
=== Storage ===
Free file entries: 16
SD card 0 detected, interface speed: 22.5MBytes/sec
SD card longest read time 6.5ms, write time 63.1ms, max retries 0
=== Move ===
DMs created 83, segments created 14, maxWait 126549ms, bed compensation in use: mesh, height map offset 0.000, max steps late 1, min interval 0, bad calcs 0, ebfmin 0.00, ebfmax 0.00
next step interrupt due in 98 ticks, enabled
Moves shaped first try 43, on retry 0, too short 2, wrong shape 156, maybepossible 1
=== DDARing 0 ===
Scheduled moves 345, completed 306, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state 3
=== 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.5
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) 3
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 P250" in state(s) 0 0, running macro
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 4, axes/extruders owned 0x0000807
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== Filament sensors ===
check 0 clear 0
Extruder 0 sensor: no filament
=== CAN ===
Messages queued 2616, received 5457, lost 0, errs 1228, boc 0
Longest wait 2ms for reply type 6053, peak Tx sync delay 44848, free buffers 26 (min 25), ts 1285/1283/0
Tx timeouts 0,0,1,0,0,0 last cancelled message type 30 dest 127
=== Network ===
Slowest loop: 12.23ms; 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 2.1beta7
MAC address c4:5b:be:ce:91:93
Module reset reason: Power up, Vcc 3.38, flash size 2097152, free heap 43040
WiFi IP address 192.168.10.x
Signal strength -48dBm, channel 6, mode 802.11n, reconnections 0
Clock register 00002001
Socket states: 0 0 0 0 0 0 0 0
M122 B121
Diagnostics for board 121:
Duet TOOL1LC rev 1.1 or later firmware version 3.5.0-rc.3+ (2024-02-15 13:22:28)
Bootloader ID: SAMC21 bootloader version 2.4 (2021-12-10)
All averaging filters OK
Never used RAM 3728, free system stack 93 words
Tasks: Move(3,nWait 7,0.0%,91) HEAT(2,nWait 6,0.4%,99) CanAsync(5,nWait 4,0.0%,49) CanRecv(3,nWait 1,0.0%,73) CanClock(5,nWait 1,0.0%,61) ACCEL(3,nWait 6,0.0%,53) TMC(2,delaying,3.4%,53) MAIN(1,running,91.2%,317) IDLE(0,ready,0.0%,27) AIN(2,delaying,4.9%,112), total 100.0%
Owned mutexes:
Last reset 00:04:19 ago, cause: power up
Last software reset data not available
Driver 0: pos 0, 568.8 steps/mm, ok, SG min 0, read errors 0, write errors 0, ifcnt 12, reads 64109, writes 12, timeouts 0, DMA errors 0, CC errors 0, steps req 0 done 45910
Moves scheduled 193, completed 192, in progress 1, hiccups 7, segs 12, step errors 0, maxLate 0 maxPrep 277, maxOverdue 35, maxInc 20, mcErrs 0, gcmErrs 0, ebfmin 0.00 max 1.00
Peak sync jitter -2/9, peak Rx sync delay 213, resyncs 0/0, no timer interrupt scheduled
VIN voltage: min 23.3, current 24.1, max 24.4
MCU temperature: min 49.5C, current 54.5C, max 54.5C
Last sensors broadcast 0x00000012 found 2 26 ticks ago, 0 ordering errs, loop time 1
CAN messages queued 5541, send timeouts 0, received 2686, lost 0, errs 0, boc 0, free buffers 18, min 18, error reg 0
dup 0, oos 0/0/0/0, bm 0, wbm 0, rxMotionDelay 327, adv 35687/74643
Accelerometer: LIS3DH, status: 00
I2C bus errors 0, naks 3, contentions 0, other errors 0
=== Filament sensors ===
Interrupt 5726621 to 0us, poll 2 to 1462us
Driver 0: ok

Not extruding:

=== Diagnostics ===
RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.3+ (2024-02-21 12:51:12) running on Duet 3 Mini5plus WiFi (standalone mode)
Board ID: XNHXF-HR6KL-K65J0-409N2-K9W1Z-RV2MZ
Used output buffers: 21 of 40 (40 max)
=== RTOS ===
Static ram: 103264
Dynamic ram: 128424 of which 0 recycled
Never used RAM 3104, free system stack 128 words
Tasks: NETWORK(2,nWait 7,17.9%,182) HEAT(3,nWait 6,0.0%,332) Move(4,nWait 6,2.4%,241) CanReceiv(6,nWait 1,0.1%,773) CanSender(5,nWait 7,0.0%,328) CanClock(7,delaying,0.0%,340) TMC(4,nWait 6,0.9%,68) MAIN(1,running,76.7%,602) IDLE(0,ready,1.1%,30) AIN(4,delaying,0.9%,260), total 100.0%
Owned mutexes:
=== Platform ===
Last reset 00:08:55 ago, cause: software
Last software reset at 2024-02-19 18:20, reason: User, Gcodes spinning, available RAM 12072, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
Error status: 0x04
Aux0 errors 0,0,0
MCU revision 3, ADC conversions started 535900, completed 535900, timed out 0, errs 0
MCU temperature: min 41.5, current 46.2, max 46.6
Supply voltage: min 23.6, current 24.1, max 24.4, under voltage events: 0, over voltage events: 0, power good: yes
Heap OK, handles allocated/used 198/33, heap memory allocated/used/recyclable 4096/4036/3576, gc cycles 85
Events: 0 queued, 0 completed
Driver 0: standstill, SG min 0, read errors 0, write errors 0, ifcnt 32, reads 14657, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 1: ok, SG min 0, read errors 0, write errors 0, ifcnt 32, reads 14658, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 2: standstill, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 14658, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 3: standstill, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 14657, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 4: standstill, SG min 0, read errors 0, write errors 0, ifcnt 20, reads 14657, writes 0, timeouts 0, DMA errors 0, CC errors 0
Driver 5: not present
Driver 6: not present
Date/time: 2024-02-21 16:04:28
Cache data hit count 832601058
Slowest loop: 37.19ms; fastest: 0.10ms
=== Storage ===
Free file entries: 16
SD card 0 detected, interface speed: 22.5MBytes/sec
SD card longest read time 9.0ms, write time 2.1ms, max retries 0
=== Move ===
DMs created 83, segments created 38, maxWait 2730ms, bed compensation in use: mesh, height map offset 0.000, max steps late 1, min interval 0, bad calcs 0, ebfmin 0.00, ebfmax 0.00
next step interrupt due in 13 ticks, enabled
Moves shaped first try 301, on retry 70, too short 131, wrong shape 2601, maybepossible 65
=== DDARing 0 ===
Scheduled moves 4136, completed 4120, hiccups 0, stepErrors 0, LaErrors 2, Underruns [0, 0, 0], CDDA state 3
=== 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.5
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) 3
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 P250" in state(s) 0 0, running macro
Aux2 is idle in state(s) 0
Autopause is idle in state(s) 0
File2 is idle in state(s) 0, sync state 1
Queue2 is idle in state(s) 0
Q0 segments left 5, axes/extruders owned 0x0000807
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== Filament sensors ===
check 0 clear 0
Extruder 0 sensor: no filament
=== CAN ===
Messages queued 5975, received 5742, lost 0, errs 0, boc 0
Longest wait 5ms for reply type 6024, peak Tx sync delay 264, free buffers 26 (min 24), ts 1393/1393/0
Tx timeouts 0,0,0,0,0,0
=== Network ===
Slowest loop: 19.71ms; fastest: 0.06ms
Responder states: MQTT(0) HTTP(0) HTTP(0) HTTP(0) HTTP(0) FTP(0) Telnet(0)
HTTP sessions: 2 of 8
=== WiFi ===
Interface state: active
Module is connected to access point 
Failed messages: pending 0, notrdy 0, noresp 0
Firmware version 2.1beta7
MAC address c4:5b:be:ce:91:93
Module reset reason: Power up, Vcc 3.38, flash size 2097152, free heap 42308
WiFi IP address 192.168.10.x
Signal strength -49dBm, channel 6, mode 802.11n, reconnections 0
Clock register 00002001
Socket states: 0 0 0 0 0 0 0 0
M122 B121
Diagnostics for board 121:
Duet TOOL1LC rev 1.1 or later firmware version 3.5.0-rc.3+ (2024-02-15 13:22:28)
Bootloader ID: SAMC21 bootloader version 2.4 (2021-12-10)
All averaging filters OK
Never used RAM 3200, free system stack 73 words
Tasks: Move(3,nWait 7,0.3%,91) HEAT(2,nWait 6,0.4%,91) CanAsync(5,nWait 4,0.0%,49) CanRecv(3,nWait 1,0.1%,73) CanClock(5,nWait 1,0.0%,61) ACCEL(3,nWait 6,0.0%,53) TMC(2,nWait 6,3.5%,53) MAIN(1,running,90.6%,317) IDLE(0,ready,0.0%,27) AIN(2,delaying,5.1%,112), total 100.0%
Owned mutexes:
Last reset 00:08:59 ago, cause: power up
Last software reset data not available
Driver 0: pos 0, 568.8 steps/mm, standstill, SG min 0, read errors 0, write errors 0, ifcnt 12, reads 8596, writes 0, timeouts 0, DMA errors 0, CC errors 0, steps req 0 done 340567
Moves scheduled 2825, completed 2825, in progress 0, hiccups 118, segs 34, step errors 0, maxLate 0 maxPrep 880, maxOverdue 94, maxInc 76, mcErrs 0, gcmErrs 0, ebfmin 0.00 max inf
Peak sync jitter -1/9, peak Rx sync delay 215, resyncs 0/0, no timer interrupt scheduled
VIN voltage: min 23.9, current 24.1, max 24.4
MCU temperature: min 49.5C, current 58.6C, max 58.6C
Last sensors broadcast 0x00000012 found 2 225 ticks ago, 0 ordering errs, loop time 0
CAN messages queued 5772, send timeouts 0, received 6029, lost 0, errs 0, boc 0, free buffers 18, min 18, error reg 0
dup 0, oos 0/0/0/0, bm 0, wbm 0, rxMotionDelay 349, adv 35572/98928
Accelerometer: LIS3DH, status: 00
I2C bus errors 0, naks 0, contentions 0, other errors 0
=== Filament sensors ===
Interrupt 5726621 to 0us, poll 9 to 1828us
Driver 0: ok

[dc42]

@Exerqtor do you only see this issue when input shaping is enabled? If so, please try the new main board binary at https://www.dropbox.com/scl/fo/p0136wx04h8xf6ejwdnn9/h?rlkey=efrfwyb6o5tqid11gustz3uvy&dl=0. It fixes a bug that could cause RRF to send expansion boards moves that were impossible to implement in time, which could have been causing the tool board to get confused. It also avoids sending tiny acceleration moves, which I spotted was happening quite often.

[Exerqtor]

@dc42 I haven't tried running a print in a while with IS disabled so i can't say if that and determining factor.
But i did try the print with IS enabled and the binaries you linked to just now (that's 2 hours old according to dropbox).

---

I'm running a new test now with IS disabled, give me a couple minutes and i'll report back on how that turns out.

[Exerqtor]

@dc42 Ok that's something else, with IS disabled it totally shits the bed from the get go. It won't even do the purge line and stops extruding right off the bat (can't feel any feeding movement at all when i touch the filament going into the reverse bowden either.)
Reports:

M122
=== Diagnostics ===
RepRapFirmware for Duet 3 Mini 5+ version 3.5.0-rc.3+ (2024-02-21 12:51:12) running on Duet 3 Mini5plus WiFi (standalone mode)
Board ID: XNHXF-HR6KL-K65J0-409N2-K9W1Z-RV2MZ
Used output buffers: 1 of 40 (40 max)
=== RTOS ===
Static ram: 103264
Dynamic ram: 128424 of which 0 recycled
Never used RAM 3080, free system stack 124 words
Tasks: NETWORK(1,ready,16.0%,182) HEAT(3,nWait 6,0.0%,332) Move(4,nWait 6,0.5%,241) CanReceiv(6,nWait 1,0.1%,773) CanSender(5,nWait 7,0.0%,328) CanClock(7,delaying,0.0%,340) TMC(4,nWait 6,0.8%,68) MAIN(1,running,80.7%,602) IDLE(0,ready,0.9%,30) AIN(4,delaying,0.9%,260), total 100.0%
Owned mutexes:
=== Platform ===
Last reset 00:38:59 ago, cause: software
Last software reset at 2024-02-19 18:20, reason: User, Gcodes spinning, available RAM 12072, slot 1
Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
Error status: 0x04
Aux0 errors 0,0,0
MCU revision 3, ADC conversions started 2340236, completed 2340235, timed out 0, errs 0
MCU temperature: min 39.6, current 46.0, max 46.4
Supply voltage: min 23.4, current 24.0, max 24.5, under voltage events: 0, over voltage events: 0, power good: yes
Heap OK, handles allocated/used 198/32, heap memory allocated/used/recyclable 4096/2492/2044, gc cycles 261
Events: 0 queued, 0 completed
Driver 0: ok, SG min 0, read errors 0, write errors 0, ifcnt 51, reads 29396, writes 19, timeouts 0, DMA errors 0, CC errors 0
Driver 1: ok, SG min 0, read errors 0, write errors 0, ifcnt 51, reads 29396, writes 19, timeouts 0, DMA errors 0, CC errors 0
Driver 2: ok, SG min 0, read errors 0, write errors 0, ifcnt 28, reads 29407, writes 8, timeouts 0, DMA errors 0, CC errors 0
Driver 3: ok, SG min 0, read errors 0, write errors 0, ifcnt 27, reads 29409, writes 7, timeouts 0, DMA errors 0, CC errors 0
Driver 4: ok, SG min 0, read errors 0, write errors 0, ifcnt 27, reads 29409, writes 7, timeouts 0, DMA errors 0, CC errors 0
Driver 5: not present
Driver 6: not present
Date/time: 2024-02-21 16:34:32
Cache data hit count 3897963068
Slowest loop: 223.37ms; fastest: 0.09ms
=== Storage ===
Free file entries: 16
SD card 0 detected, interface speed: 22.5MBytes/sec
SD card longest read time 9.3ms, write time 4.5ms, max retries 0
=== Move ===
DMs created 83, segments created 39, maxWait 1368993ms, bed compensation in use: mesh, height map offset 0.000, max steps late 1, min interval 0, bad calcs 0, ebfmin 0.00, ebfmax 0.00
next step interrupt due in 84 ticks, enabled
Moves shaped first try 879, on retry 264, too short 252, wrong shape 3751, maybepossible 153
=== DDARing 0 ===
Scheduled moves 758, completed 737, hiccups 0, stepErrors 0, LaErrors 10, Underruns [0, 0, 2], CDDA state 3
=== 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.4
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) 3
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 0, running macro
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 3, axes/extruders owned 0x0000807
Code queue 0 is empty
Q1 segments left 0, axes/extruders owned 0x0000000
Code queue 1 is empty
=== Filament sensors ===
check 0 clear 0
Extruder 0 sensor: no filament
=== CAN ===
Messages queued 22739, received 37190, lost 0, errs 0, boc 0
Longest wait 6ms for reply type 6024, peak Tx sync delay 210, free buffers 26 (min 24), ts 9021/9021/0
Tx timeouts 0,0,0,0,0,0
=== Network ===
Slowest loop: 207.53ms; fastest: 0.06ms
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 2.1beta7
MAC address c4:5b:be:ce:91:93
Module reset reason: Power up, Vcc 3.37, flash size 2097152, free heap 36344
WiFi IP address 192.168.10.x
Signal strength -51dBm, channel 6, mode 802.11n, reconnections 0
Clock register 00002001
Socket states: 0 0 0 0 0 0 0 0
	M122 B121
Diagnostics for board 121:
Duet TOOL1LC rev 1.1 or later firmware version 3.5.0-rc.3+ (2024-02-15 13:22:28)
Bootloader ID: SAMC21 bootloader version 2.4 (2021-12-10)
All averaging filters OK
Never used RAM 3200, free system stack 73 words
Tasks: Move(3,nWait 7,0.1%,91) HEAT(2,nWait 6,0.4%,91) CanAsync(5,nWait 4,0.0%,49) CanRecv(3,nWait 1,0.0%,73) CanClock(5,nWait 1,0.0%,61) ACCEL(3,nWait 6,0.0%,53) TMC(2,nWait 6,3.4%,53) MAIN(1,running,91.1%,317) IDLE(0,ready,0.0%,27) AIN(2,delaying,4.9%,112), total 100.0%
Owned mutexes:
Last reset 00:39:01 ago, cause: power up
Last software reset data not available
Driver 0: pos 0, 568.8 steps/mm, standstill, SG min 0, read errors 0, write errors 0, ifcnt 14, reads 48253, writes 2, timeouts 0, DMA errors 0, CC errors 0, steps req 0 done 139106
Moves scheduled 3211, completed 3211, in progress 0, hiccups 193, segs 34, step errors 0, maxLate 0 maxPrep 884, maxOverdue 0, maxInc 0, mcErrs 0, gcmErrs 0, ebfmin 0.00 max inf
Peak sync jitter -2/10, peak Rx sync delay 219, resyncs 0/0, no timer interrupt scheduled
VIN voltage: min 23.3, current 24.1, max 24.4
MCU temperature: min 49.5C, current 62.7C, max 62.7C
Last sensors broadcast 0x00000012 found 2 193 ticks ago, 0 ordering errs, loop time 0
CAN messages queued 37145, send timeouts 0, received 22662, lost 0, errs 0, boc 0, free buffers 18, min 18, error reg 0
dup 0, oos 0/0/0/0, bm 0, wbm 0, rxMotionDelay 342, adv 35802/74653
Accelerometer: LIS3DH, status: 00
I2C bus errors 0, naks 0, contentions 0, other errors 0
=== Filament sensors ===
Interrupt 5726621 to 0us, poll 5 to 1478us
Driver 0: ok

[dc42]

@Exerqtor where did you get the tool board firmware from? The date reported by M122 doesn't appear to match the one on Dropbox that I installed on my toolchanger.

[Exerqtor]

@dc42 I grabbed the toolboard firmware from the same dropbox folder, thats at least what I meant to do 🤔 I'll re-download and install it again in a couple minutes to make sure.