power up + 00:00:00 [info] Event logging started at level debug
power up + 00:00:00 [info] Running: Duet 3 Mini5plus WiFi: 3.5beta2 (2023-02-08 17:42:04)
power up + 00:00:00 [debug] HTTP is enabled on port 80
power up + 00:00:00 [debug] FTP is disabled
power up + 00:00:00 [debug] TELNET is disabled
power up + 00:00:00 [warn] Error: Heater 2 not found
power up + 00:00:00 [warn] Error: Heater 2 does not exist
power up + 00:00:00 [info] G10 P0 X0 Y0 Z0[0x09][0x09][0x09][0x09][0x09][0x09][0x09][0x09][0x09][0x09]
power up + 00:00:00 [info] G10 P0 R0 S0[0x09][0x09][0x09][0x09][0x09][0x09][0x09][0x09][0x09][0x09]
power up + 00:00:00 [debug] start home max Z
power up + 00:00:00 [debug] === Diagnostics ===
power up + 00:00:00 [debug] RepRapFirmware for Duet 3 Mini 5+ version 3.5beta2 (2023-02-08 17:42:04) running on Duet 3 Mini5plus WiFi (standalone mode)
power up + 00:00:00 [debug] Board ID: 3R9YY-6T9RL-D65J0-40KMN-2UW2Z-RHYT0
power up + 00:00:00 [debug] Used output buffers: 1 of 40 (1 max)
power up + 00:00:00 [debug] Error in macro line 82 while starting up: Heater 2 not found
power up + 00:00:00 [debug] === RTOS ===
power up + 00:00:00 [debug] Static ram: 103012
power up + 00:00:00 [debug] Dynamic ram: 80788 of which 0 recycled
power up + 00:00:00 [debug] Never used RAM 55056, free system stack 206 words
power up + 00:00:00 [debug] Tasks:
power up + 00:00:00 [debug]  HEAT(notifyWait,0.0%,376)
power up + 00:00:00 [debug]  Move(notifyWait,0.0%,359)
power up + 00:00:00 [debug]  CanReceiv(notifyWait,0.0%,941)
power up + 00:00:00 [debug]  CanSender(notifyWait,0.0%,335)
power up + 00:00:00 [debug]  CanClock(delaying,0.0%,342)
power up + 00:00:00 [debug]  TMC(notifyWait,0.3%,115)
power up + 00:00:00 [debug]  MAIN(running,28.7%,447)
power up + 00:00:00 [debug]  IDLE(ready,71.1%,30)
power up + 00:00:00 [debug]  AIN(delaying,0.8%,273)
power up + 00:00:00 [debug] , total 100.9%
Owned mutexes:
power up + 00:00:00 [debug]  Trigger(MAIN)
power up + 00:00:00 [debug] === Platform ===
power up + 00:00:00 [debug] Last reset 00:00:00 ago, cause: software
power up + 00:00:00 [debug] Last software reset at 2023-03-10 09:41, reason: User, Gcodes spinning, available RAM 13900, slot 1
power up + 00:00:00 [debug] Software reset code 0x0003 HFSR 0x00000000 CFSR 0x00000000 ICSR 0x00000000 BFAR 0xe000ed38 SP 0x00000000 Task MAIN Freestk 0 n/a
power up + 00:00:00 [debug] Error status: 0x00
power up + 00:00:00 [debug] MCU revision 3, ADC conversions started 293, completed 293, timed out 0, errs 0
power up + 00:00:00 [debug] Step timer max interval 1490
power up + 00:00:00 [debug] MCU temperature: min 20.8, current 21.6, max 21.6
power up + 00:00:00 [debug] Supply voltage: min 23.8, current 23.9, max 24.0, under voltage events: 0, over voltage events: 0, power good: yes
power up + 00:00:00 [debug] Heap OK, handles allocated/used 99/2, heap memory allocated/used/recyclable 2048/52/20, gc cycles 0
power up + 00:00:00 [debug] Events: 0 queued, 0 completed
power up + 00:00:00 [debug] Driver 0: ok, SG min n/a, read errors 0, write errors 1, ifcnt 19, reads 0, writes 7, timeouts 0, DMA errors 0, CC errors 0
power up + 00:00:00 [debug] Driver 1: ok, SG min n/a, read errors 0, write errors 1, ifcnt 19, reads 0, writes 7, timeouts 0, DMA errors 0, CC errors 0
power up + 00:00:00 [debug] Driver 2: ok, SG min n/a, read errors 0, write errors 1, ifcnt 18, reads 0, writes 7, timeouts 0, DMA errors 0, CC errors 0
power up + 00:00:00 [debug] Driver 3: ok, SG min n/a, read errors 0, write errors 1, ifcnt 20, reads 0, writes 7, timeouts 0, DMA errors 0, CC errors 0
power up + 00:00:00 [debug] Driver 4: ok, SG min n/a, read errors 0, write errors 1, ifcnt 33, reads 0, writes 7, timeouts 0, DMA errors 0, CC errors 0
power up + 00:00:00 [debug] Driver 5: ok, SG min n/a, read errors 0, write errors 1, ifcnt 33, reads 0, writes 7, timeouts 0, DMA errors 0, CC errors 0
power up + 00:00:00 [debug] Driver 6: ok, SG min n/a, read errors 0, write errors 1, ifcnt 33, reads 0, writes 7, timeouts 0, DMA errors 0, CC errors 0
power up + 00:00:00 [debug] Date/time: 
power up + 00:00:00 [debug] 1970-01-01 00:00:00
power up + 00:00:00 [debug] Cache data hit count 241843
power up + 00:00:00 [debug] Slowest loop: 21.57ms; fastest: 0.00ms
power up + 00:00:00 [debug] === Storage ===
Free file entries: 17
power up + 00:00:00 [debug] SD card 0 detected, interface speed: 22.5MBytes/sec
power up + 00:00:00 [debug] SD card longest read time 0.8ms, write time 4.0ms, max retries 0
power up + 00:00:00 [debug] === Move ===
DMs created 83, segments created 0, maxWait 0ms, bed compensation in use: none, comp offset 0.000
power up + 00:00:00 [debug] no step interrupt scheduled
power up + 00:00:00 [debug] === DDARing 0 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
power up + 00:00:00 [debug] === DDARing 1 ===
Scheduled moves 0, completed 0, hiccups 0, stepErrors 0, LaErrors 0, Underruns [0, 0, 0], CDDA state -1
power up + 00:00:00 [debug] === Heat ===
power up + 00:00:00 [debug] Bed heaters 0 -1 -1 -1, chamber heaters 2 -1 -1 -1, ordering errs 0
power up + 00:00:00 [debug] Heater 1 is on, I-accum = 0.0
power up + 00:00:00 [debug] === GCodes ===
power up + 00:00:00 [debug] Movement locks held by null, null
power up + 00:00:00 [debug] HTTP is idle in state(s) 0
power up + 00:00:00 [debug] Telnet is idle in state(s) 0
power up + 00:00:00 [debug] File is idle in state(s) 0
power up + 00:00:00 [debug] USB is idle in state(s) 0
power up + 00:00:00 [debug] Aux is idle in state(s) 0
power up + 00:00:00 [debug] Trigger is ready with "M122" in state(s) 0 0 0, running macro
power up + 00:00:00 [debug] Queue is idle in state(s) 0
power up + 00:00:00 [debug] LCD is idle in state(s) 0
power up + 00:00:00 [debug] SBC is idle in state(s) 0
power up + 00:00:00 [debug] Daemon is idle in state(s) 0
power up + 00:00:00 [debug] Aux2 is idle in state(s) 0
power up + 00:00:00 [debug] Autopause is idle in state(s) 0
power up + 00:00:00 [debug] File2 is idle in state(s) 0
power up + 00:00:00 [debug] Queue2 is idle in state(s) 0
power up + 00:00:00 [debug] Q0 segments left 0, axes/extruders owned 0x0000803
power up + 00:00:00 [debug] Code queue 0 is empty
power up + 00:00:00 [debug] Q1 segments left 0, axes/extruders owned 0x0000000
power up + 00:00:00 [debug] Code queue 1 is empty
power up + 00:00:00 [debug] === Filament sensors ===
power up + 00:00:00 [debug] Extruder 0 sensor: ok
power up + 00:00:00 [debug] === CAN ===
power up + 00:00:00 [debug] Messages queued 3, received 0, lost 0, boc 0
power up + 00:00:00 [debug] Longest wait 0ms for reply type 0, peak Tx sync delay 0, free buffers 18 (min 18), ts 2/0/0
power up + 00:00:00 [debug] Tx timeouts 0,0,1,0,0,0 last cancelled message type 30 dest 127
power up + 00:00:00 [debug] === Network ===
power up + 00:00:00 [debug] Slowest loop: 0.00ms; fastest: 5726623.00ms
power up + 00:00:00 [debug] Responder states:
power up + 00:00:00 [debug] HTTP sessions: 0 of 8
power up + 00:00:00 [debug] = WiFi =
Network state is disabled
power up + 00:00:00 [debug] WiFi module is disabled
power up + 00:00:00 [debug] Failed messages: pending 0, notready 0, noresp 0
power up + 00:00:00 [debug] Socket states:
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug]  0
power up + 00:00:00 [debug] Drive 4 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 0
power up + 00:00:00 [debug] Drive 5 runs in reverse, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 0
power up + 00:00:00 [debug] Drive 6 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 0
power up + 00:00:00 [debug] stall detection setup
power up + 00:00:01 [debug] after stall detection setup
power up + 00:00:01 [debug] Drive 4 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (234.4 mm/sec), pwmScaleSum 23, pwmScaleAuto 0, pwmOfsAuto 83, pwmGradAuto 16, pos 503
power up + 00:00:01 [debug] Drive 5 runs in reverse, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (234.4 mm/sec), pwmScaleSum 23, pwmScaleAuto 0, pwmOfsAuto 83, pwmGradAuto 17, pos 527
power up + 00:00:01 [debug] Drive 6 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (234.4 mm/sec), pwmScaleSum 23, pwmScaleAuto 0, pwmOfsAuto 84, pwmGradAuto 14, pos 507
power up + 00:00:01 [debug] diag before stall detection setup
power up + 00:00:01 [debug] lower motor current
power up + 00:00:01 [debug] home max z
power up + 00:00:02 [debug] after revert back
power up + 00:00:02 [debug] The bed has landed
power up + 00:00:02 [info] G10 P0 X0 Y0 Z0
power up + 00:00:02 [debug] tool
power up + 00:00:02 [warn] Error: in file macro line 146 column 22: meta command: variable 'extruderName' already exists
power up + 00:00:02 [debug] Done!
power up + 00:00:02 [debug] RepRapFirmware for Duet 3 Mini 5+ is up and running.
power up + 00:00:03 [warn] WiFi module started
power up + 00:00:04 [warn] WiFi module is connected to access point gopronto, IP address 192.168.0.171
power up + 00:00:04 [warn] HTTP client 192.168.0.17 login succeeded
2023-03-10 09:41:43 [warn] Date and time set at power up + 00:00:04
2023-03-10 09:42:58 [debug] G28 Home all.g 
2023-03-10 09:42:58 [debug] Home Y
2023-03-10 09:42:58 [debug] Drive 0 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 8
2023-03-10 09:42:58 [debug] Drive 1 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 8
2023-03-10 09:42:58 [debug] stall detection setup
2023-03-10 09:42:58 [debug] lower motor current
2023-03-10 09:42:58 [debug] Drive 0 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 21, pwmScaleAuto 0, pwmOfsAuto 78, pwmGradAuto 13, pos 264
2023-03-10 09:42:58 [debug] Drive 1 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 33, pwmScaleAuto 0, pwmOfsAuto 72, pwmGradAuto 14, pos 264
2023-03-10 09:42:58 [debug] Home Y
2023-03-10 09:43:00 [debug] Drive 0 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 19, pwmScaleAuto 0, pwmOfsAuto 69, pwmGradAuto 19, pos 104
2023-03-10 09:43:00 [debug] Drive 1 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 23, pwmScaleAuto 1, pwmOfsAuto 81, pwmGradAuto 17, pos 424
2023-03-10 09:43:00 [debug] after revert  back
2023-03-10 09:43:02 [debug] Drive 0 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 8
2023-03-10 09:43:02 [debug] Drive 1 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 715
2023-03-10 09:43:02 [debug] home x stelh chop mode
2023-03-10 09:43:02 [debug] Drive 0 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 8
2023-03-10 09:43:02 [debug] Drive 1 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 715
2023-03-10 09:43:02 [debug] stall detection setup
2023-03-10 09:43:02 [debug] lower motor current
2023-03-10 09:43:02 [debug] Drive 0 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 16, pwmScaleAuto 0, pwmOfsAuto 75, pwmGradAuto 20, pos 616
2023-03-10 09:43:02 [debug] Drive 1 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 36, pwmScaleAuto 0, pwmOfsAuto 78, pwmGradAuto 19, pos 424
2023-03-10 09:43:02 [debug] home x
2023-03-10 09:43:05 [debug] Drive 0 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 54, pwmScaleAuto 0, pwmOfsAuto 69, pwmGradAuto 20, pos 294
2023-03-10 09:43:05 [debug] Drive 1 runs forwards, active low enable, timing fast, mode stealthChop, ccr 0x00053, toff 3, tblank 0, tpwmthrs 10 (937.5 mm/sec), pwmScaleSum 53, pwmScaleAuto 0, pwmOfsAuto 77, pwmGradAuto 19, pos 893
2023-03-10 09:43:05 [debug] after revert back
2023-03-10 09:43:05 [debug] Drive 0 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 552
2023-03-10 09:43:05 [debug] Drive 1 runs forwards, active low enable, timing fast, mode spreadCycle, ccr 0x00053, toff 3, tblank 0, hstart/hend/hdec 5/0/0, pos 360
2023-03-10 09:43:05 [debug] Probe Attached
2023-03-10 09:43:05 [debug] home Z.g 
2023-03-10 09:43:10 [info] Event logging stopped