# provenance (ASCII header; payload below is the verbatim boot log from run_boot_test.py)
# commit      : 1d4b6a17  fix(console): whole-line exclusive during the un-registered window
#               built in the READ-ONLY detached worktree D:/Code/DTU Code/_wt/bench-23-1d4b6a17
#               (main tree is at the same commit but carries ui lane task-6 uncommitted work,
#               so the image was deliberately NOT built from the main tree)
# build       : @build 2026-10-26 19:43, rc=0, "0 Error(s), 0 Warning(s)"
#               Program Size: Code=228812 RO-data=25060 RW-data=1456 ZI-data=135976
# image stamp : fw build: Oct 26 2026 19:43:23 suite=NONE dstr=off (product, destructive OFF)
# flash       : -P 0x08000000 -Rst -Run -> Programming Complete; log captured; THEN -CmpFile
#               -> READBACK: OK (no difference found)   [order per the reset-semantics rule]
# this boot   : the FLASH boot (task-30 also ran 4 further reset-only boots, see the other file)
# verdict     : run_boot_test rc=0 PASS; verifier rc=0 PASS ([ERR]=0, both victim lines byte-exact,
#               anchor x1, no Fault, comm init OK, heap identical to previous round)

[+0ms] [----/--/-- --:--:--][APP] boot: syslog init OK
[+0ms] [----/--/-- --:--:--][APP] fw build: Oct 26 2026 19:43:23 suite=NONE dstr=off
[+10ms] [----/--/-- --:--:--][APP] reset cause: software (NVIC_SystemReset) (CSR=0x14000000)
[+21ms] PREVRUN seg=2 seq=2157782617 t=9536985
[+21ms] TICKRUN uw=9536997 n=9536997 duw=12
[+21ms] [----/--/-- --:--:--][RTC] sd2506_init: OK
[+32ms] [----/--/-- --:--:--][APP] SD2506 init: OK (ret=0)
[+32ms] [2026-10-26 11:45:40][CLK] sys_clock_init: RTC initial time 2026-10-26 11:45:40
[+42ms] [2026-10-26 11:45:40][CLK] sys_clock_init: wall clock base = unix 1793015140, base_tick=139
[+53ms] [2026-10-26 11:45:40][APP] RS485 init: OK (UART4, DE=PA15)
[+63ms] [2026-10-26 11:45:40][NAND] Resetting NAND...
[+84ms] [2026-10-26 11:45:40][NAND] Reading NAND ID...
[+84ms] [2026-10-26 11:45:40][NAND] NAND ID: MID=0xC8, DID=0x52
[+94ms] [2026-10-26 11:45:40][NAND] Scanning bad blocks...
[+221ms] [2026-10-26 11:45:40][NAND] BBT scan: 0 bad blocks found
[+231ms] [2026-10-26 11:45:40][NAND] Enabling ECC...
[+231ms] [2026-10-26 11:45:40][NAND] Unlocking block protection...
[+242ms] [2026-10-26 11:45:40][NAND] Locating BBT pool...
[+242ms] [2026-10-26 11:45:40][NAND] Loading persisted BBT...
[+252ms] [2026-10-26 11:45:40][NAND] NAND init OK
[+252ms] [2026-10-26 11:45:40][APP] NAND init: OK
[+262ms] [2026-10-26 11:45:40][ADC] Init OK
[+262ms] [2026-10-26 11:45:40][APP] ADC init: OK
[+262ms] [2026-10-26 11:45:40][APP] ADC input layer init: OK
[+273ms] [2026-10-26 11:45:40][APP] phasor layer init: OK (inst=5)
[+273ms] [2026-10-26 11:45:40][APP] freq layer init: OK
[+283ms] [2026-10-26 11:45:40][CFG] register: section 'adc_cal' (9 keys)
[+283ms] [2026-10-26 11:45:40][CFG] register: section 'adc_cal_ofs' (8 keys)
[+294ms] [2026-10-26 11:45:40][APP] adc cal init: OK (k_q16 tpafe=10000000 stm32=52800000)
[+294ms] [2026-10-26 11:45:40][CFG] register: section 'run_par' (9 keys)
[+304ms] [2026-10-26 11:45:40][APP] run par init: OK (db9 k/b via adc_cal, evt=1<<8)
[+315ms] [2026-10-26 11:45:40][CFG] register: section 'comm' (2 keys)
[+315ms] [2026-10-26 11:45:40][APP] comm init: OK (ca=1 port=2404)
[+325ms] [2026-10-26 11:45:40][APP] protection init: OK (set=40 elm=9)
[+335ms] [2026-10-26 11:45:40][APP] SD2506 ID: 2512153425482405
[+335ms] [2026-10-26 11:45:40][APP] SD2506 time: 2026-10-26 11:45:41 (week 1)
[+346ms] [2026-10-26 11:45:40][APP] SD2506 temp: 25 C
[+346ms] [2026-10-26 11:45:40][APP] SD2506 battery: 3.12 V
[+357ms] [2026-10-26 11:45:40][APP] App init OK
[+357ms] [2026-10-26 11:45:40][NAND_FTL] Initializing NAND FTL...
[+388ms] [2026-10-26 11:45:40][PROT] uncalibrated -> protection blocked (UA/UN/IA VALID not all set)
[+461ms] [2026-10-26 11:45:40][NAND_FTL] Map resumed: 14094/97504 sectors used
[+461ms] [2026-10-26 11:45:40][NAND_FTL] Map head=49568 tail=2486 epoch=7
[+473ms] [2026-10-26 11:45:40][NAND_FTL] NAND FTL ready (capacity: 97504 pages = 190 MB)
[+555ms] [2026-10-26 11:45:40] [FS] FATFS mounted
[+618ms] [2026-10-26 11:45:40][SYSLOG] runlog boot entry: power on
[+618ms] [2026-10-26 11:45:40][CFG] register: section 'network' (4 keys)
[+628ms] [2026-10-26 11:45:40][CFG] register: section 'ftp' (3 keys)
[+639ms] [2026-10-26 11:45:40][CFG] load: OK (957B, heap free 16552B)
[+639ms] [2026-10-26 11:45:40][NET_CFG] load: OK ip=192.168.1.100
[+650ms] [2026-10-26 11:45:40] [CFG] prot set loaded (/prot_set.bin)
[+712ms] [2026-10-26 11:45:40][ADC_TASK] first: UA=11430 UB=1063 UC=900 UN=11453 IA=11452 IB=1050 IC=1035 IN=919 T=1002
[+723ms] [2026-10-26 11:45:40][ADC_TASK] phasor(first) C01-IA-F1: Re=0 Im=0 rms=0 (x0.1)
[+733ms] [2026-10-26 11:45:40][ADC_TASK] phasor(first) C02-UA-F1: Re=0 Im=0 rms=0 (x0.1)
[+733ms] [2026-10-26 11:45:40][ADC_TASK] phasor(first) C03-UN-F1: Re=0 Im=0 rms=0 (x0.1)
[+744ms] [2026-10-26 11:45:40][ADC_TASK] phasor(first) C04-IA-F2: Re=0 Im=0 rms=0 (x0.1)
[+755ms] [2026-10-26 11:45:40][ADC_TASK] phasor(first) C05-IA-F5: Re=0 Im=0 rms=0 (x0.1)
[+755ms] [2026-10-26 11:45:40][ADC_TASK] freq(first): f=0 valid=0 (x0.01)
[+766ms] [2026-10-26 11:45:40][NET_TASK] === Network Init ===
[+766ms] [2026-10-26 11:45:40][PROT] protect task start: cycle=20ms elm=9
[+776ms] [2026-10-26 11:45:40][NET] ch395f ver=0x4A fun_para=0x08
[+776ms] [2026-10-26 11:45:40][NET_TASK] net init: OK
[+787ms] [2026-10-26 11:45:40][NET_TASK] MAC: 3C:AB:72:53:2B:12
[+787ms] [2026-10-26 11:45:40][NET_TASK] IP_INF: IP=192.168.1.100 GW=192.168.1.1 MASK=255.255.255.0
[+798ms] MISS start snap=0x000001E7 req=0x000001F7 t=900
[+798ms] [2026-10-26 11:45:40][WD] [WD] armed: timeout ~2498ms (worst ~1701ms), miss_tol=800ms
[+854ms] [2026-10-26 11:45:40][UI_TASK] UI task: started, wait net ready
[+2714ms] [2026-10-26 11:45:42][NET_TASK] PHY: 100M FULL
[+2735ms] [2026-10-26 11:45:42][NET_TASK] netTask: started (single-socket mode)
[+2766ms] [2026-10-26 11:45:42] [FTP] using factory default password
[+2798ms] [2026-10-26 11:45:42][TIME_SYNC] time_sync: TCP listen on :8888 (sock=0)
[+2829ms] [2026-10-26 11:45:42][I104] i104: listening on 2404
[+2829ms] MISS start snap=0x000001E7 req=0x000001F7 t=2937
[+2840ms] [PROF] t=2942 uic=0 uip=0 uipn=0 uil=0 uiw=0 uis=0 uisn=0 adc=2481 adcm=2237 adcn=0 gl=0
[+2850ms] [PROF2] t=2942 defd=130981 defs=37447 netp=2991 netn=0 adcp=8 adcq=0 gl=0
[+2850ms] [2026-10-26 11:45:42] [FTP] waiting for connection...
[+2861ms] [2026-10-26 11:45:42][UI_TASK] UI task: net ready, init port
[+2861ms] [2026-10-26 11:45:42][UI_TASK] UI task: ui_port_init enter
[+2871ms] [2026-10-26 11:45:42][UI_PORT] theme: mono dark_bg=true applied
[+2882ms] [2026-10-26 11:45:42][UI_TASK] UI port init: OK
[+2882ms] [2026-10-26 11:45:42][UI_TASK] UI task: ui_menu_init enter
[+2892ms] [2026-10-26 11:45:42][UI_MENU] menu build start: total=15136 free=12692 big=12692
[+2903ms] [2026-10-26 11:45:42][UI_MENU_VIEW] view init: root frame w=66 h=107
[+2913ms] [2026-10-26 11:45:42][UI_MENU] menu build done: total=15020 free=11828 big=11816 used=3192
[+2913ms] [2026-10-26 11:45:42][UI_TASK] UI menu init: OK
[+2924ms] [2026-10-26 11:45:42][UI_STANDBY] standby: ready (idle=60000ms)
[+2924ms] [2026-10-26 11:45:42][UI_TASK] UI standby: ready (idle=60s)
[+2975ms] [2026-10-26 11:45:42][UI_TASK] UI net sink: started (port 7003)
[+4948ms] [PROF] t=5035 uic=176 uip=21962 uipn=2840 uil=10998 uiw=12055 uis=1186 uisn=0 adc=4573 adcm=2237 adcn=0 gl=0
[+4948ms] [PROF2] t=5035 defd=130981 defs=37447 netp=3012 netn=0 adcp=8 adcq=0 gl=0
[+5400ms] [2026-10-26 11:45:45] [STACK] default peak_used=2172/5120 free_min=2948 (57%)
[+5411ms] [2026-10-26 11:45:45] [STACK] net peak_used=1048/3840 free_min=2792 (72%)
[+5411ms] [2026-10-26 11:45:45] [STACK] ftp peak_used=740/4096 free_min=3356 (81%)
[+5421ms] [2026-10-26 11:45:45] [STACK] ui peak_used=4188/16384 free_min=12196 (74%)
[+5431ms] [2026-10-26 11:45:45] [STACK] timesync peak_used=616/2560 free_min=1944 (75%)
[+5431ms] [2026-10-26 11:45:45] [STACK] adc peak_used=920/2560 free_min=1640 (64%)
[+5442ms] [2026-10-26 11:45:45] [STACK] iec104 peak_used=540/2560 free_min=2020 (78%)
[+5452ms] [2026-10-26 11:45:45] [STACK] protect peak_used=492/2048 free_min=1556 (75%)
[+5452ms] [2026-10-26 11:45:45] [STACK] heap free=15624 min_ever=8464
[+6965ms] [PROF] t=7065 uic=354 uip=72975 uipn=3840 uil=10999 uiw=12055 uis=1186 uisn=0 adc=6603 adcm=2237 adcn=0 gl=0
[+6975ms] [PROF2] t=7065 defd=130981 defs=37447 netp=3988 netn=0 adcp=8 adcq=0 gl=0
[+8980ms] [PROF] t=9083 uic=536 uip=72975 uipn=9840 uil=10938 uiw=12055 uis=1205 uisn=0 adc=8621 adcm=2237 adcn=0 gl=0
[+8990ms] [PROF2] t=9083 defd=130981 defs=37447 netp=3988 netn=0 adcp=8 adcq=0 gl=0
[+10360ms] [2026-10-26 11:45:50][ADC_TASK] frames=10001 idle=0 skip=33 seq=10006 frst_err=0 ring=9968 adc1_rc=0 adc1_err=0 adc1_seq=10004 adc1_gap=35 adc1_hw=0 tgap=1
[+10371ms] [2026-10-26 11:45:50][ADC_TASK] phasor(hb) C01-IA-F1: Re=0 Im=0 rms=0 (x0.1)
[+10381ms] [2026-10-26 11:45:50][ADC_TASK] phasor(hb) C02-UA-F1: Re=0 Im=0 rms=0 (x0.1)
[+10392ms] [2026-10-26 11:45:50][ADC_TASK] phasor(hb) C03-UN-F1: Re=0 Im=0 rms=0 (x0.1)
[+10392ms] [2026-10-26 11:45:50][ADC_TASK] phasor(hb) C04-IA-F2: Re=0 Im=0 rms=0 (x0.1)
[+10402ms] [2026-10-26 11:45:50][ADC_TASK] phasor(hb) C05-IA-F5: Re=0 Im=0 rms=0 (x0.1)
[+10413ms] [2026-10-26 11:45:50][ADC_TASK] freq(hb): f=0 valid=0 (x0.01)
[+10413ms] [2026-10-26 11:45:50][ADC_TASK] cal: ua_mv=1743 un_mv=1747 ia_mv=1747 vl=0x00 cl=0xe6 rail=0x000 edge=0x000
[+10424ms] [2026-10-26 11:45:50][ADC_TASK] meas: seq=50 v=1 ua_mv=0 un_mv=0 ia_mv=0 f=0 fv=0
[+10981ms] [PROF] t=11084 uic=710 uip=81571 uipn=9839 uil=11000 uiw=12055 uis=1205 uisn=0 adc=10621 adcm=2879 adcn=0 gl=0
[+10992ms] [PROF2] t=11084 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+11455ms] [2026-10-26 11:45:51] [STACK] default peak_used=2172/5120 free_min=2948 (57%)
[+11455ms] [2026-10-26 11:45:51] [STACK] net peak_used=1048/3840 free_min=2792 (72%)
[+11466ms] [2026-10-26 11:45:51] [STACK] ftp peak_used=740/4096 free_min=3356 (81%)
[+11476ms] [2026-10-26 11:45:51] [STACK] ui peak_used=4188/16384 free_min=12196 (74%)
[+11476ms] [2026-10-26 11:45:51] [STACK] timesync peak_used=616/2560 free_min=1944 (75%)
[+11486ms] [2026-10-26 11:45:51] [STACK] adc peak_used=1000/2560 free_min=1560 (60%)
[+11497ms] [2026-10-26 11:45:51] [STACK] iec104 peak_used=540/2560 free_min=2020 (78%)
[+11497ms] [2026-10-26 11:45:51] [STACK] protect peak_used=492/2048 free_min=1556 (75%)
[+11507ms] [2026-10-26 11:45:51] [STACK] heap free=15624 min_ever=8464
[+13012ms] [PROF] t=13114 uic=888 uip=81571 uipn=2840 uil=10999 uiw=12055 uis=1205 uisn=0 adc=12651 adcm=2879 adcn=0 gl=0
[+13022ms] [PROF2] t=13114 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+15033ms] [PROF] t=15132 uic=1070 uip=81571 uipn=9839 uil=11000 uiw=12055 uis=1205 uisn=0 adc=14669 adcm=2879 adcn=0 gl=0
[+15043ms] [PROF2] t=15132 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+17051ms] [PROF] t=17150 uic=1252 uip=81571 uipn=9840 uil=10998 uiw=12055 uis=1205 uisn=0 adc=16687 adcm=2879 adcn=0 gl=0
[+17051ms] [PROF2] t=17150 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+19067ms] [PROF] t=19168 uic=1434 uip=81571 uipn=9839 uil=10895 uiw=12055 uis=1205 uisn=0 adc=18705 adcm=2879 adcn=0 gl=0
[+19077ms] [PROF2] t=19168 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+20363ms] [2026-10-26 11:46:00][ADC_TASK] frames=20000 idle=0 skip=93 seq=20006 frst_err=0 ring=19907 adc1_rc=0 adc1_err=0 adc1_seq=20004 adc1_gap=96 adc1_hw=0 tgap=2
[+20373ms] [2026-10-26 11:46:00][ADC_TASK] phasor(hb) C01-IA-F1: Re=0 Im=0 rms=0 (x0.1)
[+20384ms] [2026-10-26 11:46:00][ADC_TASK] phasor(hb) C02-UA-F1: Re=0 Im=0 rms=0 (x0.1)
[+20384ms] [2026-10-26 11:46:00][ADC_TASK] phasor(hb) C03-UN-F1: Re=0 Im=0 rms=0 (x0.1)
[+20394ms] [2026-10-26 11:46:00][ADC_TASK] phasor(hb) C04-IA-F2: Re=0 Im=0 rms=0 (x0.1)
[+20405ms] [2026-10-26 11:46:00][ADC_TASK] phasor(hb) C05-IA-F5: Re=0 Im=0 rms=0 (x0.1)
[+20405ms] [2026-10-26 11:46:00][ADC_TASK] freq(hb): f=0 valid=0 (x0.01)
[+20415ms] [2026-10-26 11:46:00][ADC_TASK] cal: ua_mv=1743 un_mv=1747 ia_mv=1747 vl=0x00 cl=0xe6 rail=0x000 edge=0x000
[+20426ms] [2026-10-26 11:46:00][ADC_TASK] meas: seq=100 v=1 ua_mv=0 un_mv=0 ia_mv=0 f=0 fv=0
[+21085ms] [PROF] t=21185 uic=1610 uip=84102 uipn=1840 uil=10997 uiw=73991 uis=73977 uisn=0 adc=20721 adcm=2879 adcn=0 gl=0
[+21095ms] [PROF2] t=21185 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+23097ms] [PROF] t=23203 uic=1792 uip=84102 uipn=9848 uil=10999 uiw=73991 uis=73977 uisn=0 adc=22740 adcm=2879 adcn=0 gl=0
[+23108ms] [PROF2] t=23203 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+25115ms] [PROF] t=25221 uic=1974 uip=84102 uipn=9839 uil=10999 uiw=73991 uis=73977 uisn=0 adc=24757 adcm=2879 adcn=0 gl=0
[+25125ms] [PROF2] t=25221 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+27135ms] [PROF] t=27239 uic=2156 uip=84102 uipn=9840 uil=10998 uiw=73991 uis=73977 uisn=0 adc=26775 adcm=2879 adcn=0 gl=0
[+27145ms] [PROF2] t=27239 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
[+29153ms] [PROF] t=29257 uic=2338 uip=84102 uipn=9840 uil=10999 uiw=73991 uis=73977 uisn=0 adc=28793 adcm=2879 adcn=0 gl=0
[+29163ms] [PROF2] t=29257 defd=130981 defs=37447 netp=5012 netn=0 adcp=8 adcq=0 gl=0
