Flywheeling in timer latency test

I have written an app to test timer interrupt handling latency under heavy load. Two latencies are calculated, given a timer with period T and tick number J:

• the difference between (MET at tick) and (MET at last tick + T)
• the difference between (MET at tick) and (MET at first tick) + (J * T))

For ease of use, the timing process starts when the app starts, but this is problem because the clock seems to be in FLYWHEEL mode at init, but the reported mode is INVALID. Some time after cFS starts up, flywheeling stops, and then I get a jump in the clock, and my measurements jump. I'm unsure whether or not it is a bug that the clock mode is INVALID, even though the clock is flywheeling.

I have tried, as a stop-gap measure, including the time utils header from cFE, and setting the MET to (0,0) during app init, because from the documentation, I was under the impression that setting the clock would put it in VALID mode.

Can anyone shed some light on this? I have tried sending to the mailing list, but my emails just disappear - no bounce or anything.

I am on the eval-cert3 branch.

Here is the log:

CFE_PSP: EEPROM Range (2) created: Start Address = 7FC658941000, Size = 00080000 Status = 0
CFE_PSP: Starting the cFE with a POWER ON reset.
CFE_PSP: Clearing out CFE CDS Shared memory segment.
CFE_PSP: Clearing out CFE Reset Shared memory segment.
CFE_PSP: Clearing out CFE User Reserved Shared memory segment.
1980-001-07:25:42.24332 POWER ON RESET due to Power Cycle (Power Cycle).
1980-001-07:25:42.24334 ES Startup: CFE_ES_Main in EARLY_INIT state
CFE_PSP: CFE_PSP_AttachExceptions Called
1980-001-07:25:42.24367 ES Startup: CFE_ES_Main entering CORE_STARTUP state
1980-001-07:25:42.24368 ES Startup: Starting Object Creation calls.
1980-001-07:25:42.24368 ES Startup: Calling CFE_ES_CDSEarlyInit
1980-001-07:25:42.24397 ES Startup: Calling CFE_EVS_EarlyInit
1980-001-07:25:42.24400 Event Log cleared following power-on reset
1980-001-07:25:42.24400 ES Startup: Calling CFE_SB_EarlyInit
1980-001-07:25:42.24449 ES Startup: Calling CFE_TIME_EarlyInit
1980-012-14:03:20.00000 ES Startup: Calling CFE_TBL_EarlyInit
1980-012-14:03:20.00060 ES Startup: Calling CFE_FS_EarlyInit
EVS Port1 66/1/CFE_EVS 1: cFE EVS Initialized. cFE DEVELOPMENT BUILD v6.8.0-rc1+dev490 (Codename: Bootes), Last Official Release: cfe v6.7.0
1980-012-14:03:20.15136 SB:Registered 4 events for filtering
EVS Port1 66/1/CFE_SB 1: cFE SB Initialized
EVS Port1 66/1/CFE_SB 14: No subscribers for MsgId 0x808,sender CFE_SB
EVS Port1 66/1/CFE_ES 1: cFE ES Initialized
EVS Port1 66/1/CFE_SB 14: No subscribers for MsgId 0x808,sender CFE_ES
EVS Port1 66/1/CFE_ES 2: cFS Versions: cfe v6.8.0-rc1+dev490, osal v5.1.0-rc1+dev367, psp v1.5.0-rc1+dev101. cFE chksm 63032
EVS Port1 66/1/CFE_SB 14: No subscribers for MsgId 0x808,sender CFE_ES
EVS Port1 66/1/CFE_ES 91: Version Info: Mission MISEU Demo, version [unknown]
EVS Port1 66/1/CFE_SB 14: No subscribers for MsgId 0x808,sender CFE_ES
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module core_api, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module core_private, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module es, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module evs, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module fs, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module sb, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module tbl, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module time, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module osal, version git:v6.0.0-rc1-103-g65c584f
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module psp, version git:v1.5.0-rc1-101-g79b944b
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module msg, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module sbr, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 91: Version Info: Core Module resourceid, version git:v7.0.0-rc1
EVS Port1 66/1/CFE_ES 92: Build 202104141734 by jack@canary, config sample
EVS Port1 66/1/CFE_TIME 1: cFE TIME Initialized
EVS Port1 66/1/CFE_TBL 1: cFE TBL Initialized. cFE DEVELOPMENT BUILD v6.8.0-rc1+dev490 (Codename: Bootes), Last Official Release: cfe v6.7.0
1980-012-14:03:20.50285 ES Startup: Finished ES CreateObject table entries.
1980-012-14:03:20.50288 ES Startup: CFE_ES_Main entering CORE_READY state
1980-012-14:03:20.50291 ES Startup: Opened ES App Startup file: /cf/cfe_es_startup.scr
1980-012-14:03:20.50312 ES Startup: Loading file: /cf/timprof.so, APP: TIMPROF
EVS Port1 66/1/TIMPROF 30: timprof: Events registered successfully.
1980-012-13:46:40.01003 clock_state: -1
1980-012-13:46:40.02003 clock_state: -1
1980-012-13:46:40.02003 timprof: Lat [us] -1, Cumulative: -1
1980-012-13:46:40.03003 clock_state: -1
1980-012-13:46:40.03003 timprof: Lat [us] -2, Cumulative: -4
1980-012-13:46:40.04003 clock_state: -1
1980-012-13:46:40.04003 timprof: Lat [us] 0, Cumulative: -7
1980-012-13:46:40.04983 ES Startup: CFE_ES_Main entering APPS_INIT state
1980-012-13:46:40.04991 ES Startup: CFE_ES_Main entering OPERATIONAL state
1980-012-13:46:40.05003 clock_state: -1
1980-012-13:46:40.05013 timprof: Lat [us] 101, Cumulative: 91
1980-012-13:46:40.06003 clock_state: -1
1980-012-13:46:40.06004 timprof: Lat [us] -97, Cumulative: 92
1980-012-13:46:40.07003 clock_state: -1
1980-012-13:46:40.07004 timprof: Lat [us] -1, Cumulative: 92
1980-012-13:46:40.08003 clock_state: -1
1980-012-13:46:40.08003 timprof: Lat [us] -1, Cumulative: 91
1980-012-13:46:40.09003 clock_state: -1
1980-012-13:46:40.09003 timprof: Lat [us] -2, Cumulative: 88
1980-012-13:46:40.10003 clock_state: -1
1980-012-13:46:40.10004 timprof: Lat [us] 3, Cumulative: 88
1980-012-13:46:40.11003 clock_state: -1
1980-012-13:46:40.11003 timprof: Lat [us] -3, Cumulative: 85
1980-012-13:46:40.12003 clock_state: -1
1980-012-13:46:40.12003 timprof: Lat [us] 1, Cumulative: 83
1980-012-13:46:40.13003 clock_state: -1
1980-012-13:46:40.13004 timprof: Lat [us] 2, Cumulative: 83
1980-012-13:46:40.14003 clock_state: -1
1980-012-13:46:40.14003 timprof: Lat [us] -3, Cumulative: 80
1980-012-13:46:40.15004 clock_state: -1
1980-012-13:46:40.15005 timprof: Lat [us] 16, Cumulative: 93
1980-012-13:46:40.16003 clock_state: -1
1980-012-13:46:40.16003 timprof: Lat [us] -16, Cumulative: 90
1980-012-13:46:40.17003 clock_state: -1
1980-012-13:46:40.17003 timprof: Lat [us] 1, Cumulative: 88
1980-012-13:46:40.18003 clock_state: -1
1980-012-13:46:40.18004 timprof: Lat [us] 8, Cumulative: 94
1980-012-13:46:40.19003 clock_state: -1
1980-012-13:46:40.19003 timprof: Lat [us] -9, Cumulative: 91
1980-012-13:46:40.20003 clock_state: -1
1980-012-13:46:40.20003 timprof: Lat [us] 1, Cumulative: 89
1980-012-13:46:40.21003 clock_state: -1
1980-012-13:46:40.21003 timprof: Lat [us] 1, Cumulative: 88
1980-012-13:46:40.22004 clock_state: -1
1980-012-13:46:40.22004 timprof: Lat [us] 8, Cumulative: 95
1980-012-13:46:40.23003 clock_state: -1
1980-012-13:46:40.23004 timprof: Lat [us] -8, Cumulative: 94
1980-012-13:46:40.24003 clock_state: -1
1980-012-13:46:40.24003 timprof: Lat [us] 0, Cumulative: 93
1980-012-13:46:40.25003 clock_state: -1
1980-012-13:46:40.25003 timprof: Lat [us] -1, Cumulative: 91
1980-012-13:46:40.26003 clock_state: -1
1980-012-13:46:40.26003 timprof: Lat [us] 1, Cumulative: 90
1980-012-13:46:40.27003 clock_state: -1
1980-012-13:46:40.27004 timprof: Lat [us] 1, Cumulative: 90
1980-012-13:46:40.28003 clock_state: -1
1980-012-13:46:40.28003 timprof: Lat [us] -1, Cumulative: 89
1980-012-13:46:40.29003 clock_state: -1
1980-012-13:46:40.29004 timprof: Lat [us] 5, Cumulative: 93
1980-012-13:46:40.30003 clock_state: -1
1980-012-13:46:40.30003 timprof: Lat [us] -7, Cumulative: 90
1980-012-13:46:40.31003 clock_state: -1
1980-012-13:46:40.31004 timprof: Lat [us] 3, Cumulative: 90
1980-012-13:46:40.32003 clock_state: -1
1980-012-13:46:40.32004 timprof: Lat [us] 4, Cumulative: 94
1980-012-13:46:40.33003 clock_state: -1
1980-012-13:46:40.33003 timprof: Lat [us] -6, Cumulative: 92
1980-012-13:46:40.34003 clock_state: -1
1980-012-13:46:40.34003 timprof: Lat [us] -2, Cumulative: 88
1980-012-13:46:40.35003 clock_state: -1
1980-012-13:46:40.35003 timprof: Lat [us] 2, Cumulative: 86
1980-012-13:46:40.36003 clock_state: -1
1980-012-13:46:40.36003 timprof: Lat [us] 0, Cumulative: 84
1980-012-13:46:40.37003 clock_state: -1
1980-012-13:46:40.37004 timprof: Lat [us] 1, Cumulative: 83
1980-012-13:46:40.38003 clock_state: -1
1980-012-13:46:40.38004 timprof: Lat [us] 7, Cumulative: 89
1980-012-13:46:40.39003 clock_state: -1
1980-012-13:46:40.39003 timprof: Lat [us] -8, Cumulative: 87
1980-012-13:46:40.40003 clock_state: -1
1980-012-13:46:40.40003 timprof: Lat [us] 1, Cumulative: 86
1980-012-13:46:40.41003 clock_state: -1
1980-012-13:46:40.41004 timprof: Lat [us] 6, Cumulative: 91
1980-012-13:46:40.42003 clock_state: -1
1980-012-13:46:40.42004 timprof: Lat [us] -2, Cumulative: 94
1980-012-13:46:40.43000 clock_state: -1
1980-012-13:46:40.43001 timprof: Lat [us] -27, Cumulative: 70
1980-012-13:46:40.44003 clock_state: -1
1980-012-13:46:40.44003 timprof: Lat [us] 21, Cumulative: 67
1980-012-13:46:40.45003 clock_state: -1
1980-012-13:46:40.45003 timprof: Lat [us] 2, Cumulative: 66
1980-012-13:46:40.46003 clock_state: -1
1980-012-13:46:40.46003 timprof: Lat [us] -2, Cumulative: 63
1980-012-13:46:40.47003 clock_state: -1
1980-012-13:46:40.47003 timprof: Lat [us] 0, Cumulative: 60
1980-012-13:46:40.48003 clock_state: -1
1980-012-13:46:40.48003 timprof: Lat [us] 1, Cumulative: 58
1980-012-13:46:40.49003 clock_state: -1
1980-012-13:46:40.49003 timprof: Lat [us] -1, Cumulative: 55
1980-012-13:46:40.50003 clock_state: -1
1980-012-13:46:40.50003 timprof: Lat [us] 2, Cumulative: 54
1980-012-13:46:40.51003 clock_state: -1
1980-012-13:46:40.51003 timprof: Lat [us] -1, Cumulative: 52
1980-012-13:46:40.52003 clock_state: -1
1980-012-13:46:40.52003 timprof: Lat [us] 0, Cumulative: 50
1980-012-13:46:40.53003 clock_state: -1
1980-012-13:46:40.53003 timprof: Lat [us] 1, Cumulative: 49
1980-012-13:46:40.54003 clock_state: -1
1980-012-13:46:40.54004 timprof: Lat [us] 1, Cumulative: 49
1980-012-13:46:40.55003 clock_state: -1
1980-012-13:46:40.55003 timprof: Lat [us] -2, Cumulative: 47
1980-012-13:46:40.56003 clock_state: -1
1980-012-13:46:40.56004 timprof: Lat [us] 2, Cumulative: 47
1980-012-13:46:40.57003 clock_state: -1
1980-012-13:46:40.57003 timprof: Lat [us] -2, Cumulative: 45
1980-012-13:46:40.58003 clock_state: -1
1980-012-13:46:40.58004 timprof: Lat [us] 2, Cumulative: 45
1980-012-13:46:40.59003 clock_state: -1
1980-012-13:46:40.59003 timprof: Lat [us] -3, Cumulative: 42
1980-012-13:46:40.60003 clock_state: -1
1980-012-13:46:40.60003 timprof: Lat [us] 2, Cumulative: 41
1980-012-13:46:40.61003 clock_state: -1
1980-012-13:46:40.61003 timprof: Lat [us] 0, Cumulative: 40
1980-012-13:46:40.62003 clock_state: -1
1980-012-13:46:40.62003 timprof: Lat [us] -1, Cumulative: 38
1980-012-13:46:40.63004 clock_state: -1
1980-012-13:46:40.63004 timprof: Lat [us] 8, Cumulative: 44
1980-012-13:46:40.64003 clock_state: -1
1980-012-13:46:40.64003 timprof: Lat [us] -7, Cumulative: 43
1980-012-13:46:40.65003 clock_state: -1
1980-012-13:46:40.65003 timprof: Lat [us] -1, Cumulative: 41
1980-012-13:46:40.66003 clock_state: -1
1980-012-13:46:40.66003 timprof: Lat [us] 0, Cumulative: 39
1980-012-13:46:40.67003 clock_state: -1
1980-012-13:46:40.67003 timprof: Lat [us] 1, Cumulative: 38
1980-012-13:46:40.68003 clock_state: -1
1980-012-13:46:40.68003 timprof: Lat [us] -1, Cumulative: 36
1980-012-13:46:40.69003 clock_state: -1
1980-012-13:46:40.69003 timprof: Lat [us] 1, Cumulative: 35
1980-012-13:46:40.70003 clock_state: -1
1980-012-13:46:40.70003 timprof: Lat [us] -1, Cumulative: 33
1980-012-13:46:40.71003 clock_state: -1
1980-012-13:46:40.71004 timprof: Lat [us] 3, Cumulative: 34
1980-012-13:46:40.72003 clock_state: -1
1980-012-13:46:40.72003 timprof: Lat [us] -3, Cumulative: 32
1980-012-13:46:40.73003 clock_state: -1
1980-012-13:46:40.73004 timprof: Lat [us] 2, Cumulative: 32
1980-012-13:46:40.74003 clock_state: -1
1980-012-13:46:40.74003 timprof: Lat [us] -2, Cumulative: 30
1980-012-13:46:40.75003 clock_state: -1
1980-012-13:46:40.75004 timprof: Lat [us] 2, Cumulative: 30
1980-012-13:46:40.76003 clock_state: -1
1980-012-13:46:40.76003 timprof: Lat [us] -3, Cumulative: 27
1980-012-13:46:40.77003 clock_state: -1
1980-012-13:46:40.77003 timprof: Lat [us] 1, Cumulative: 25
1980-012-13:46:40.78004 clock_state: -1
1980-012-13:46:40.78004 timprof: Lat [us] 9, Cumulative: 32
1980-012-13:46:40.79003 clock_state: -1
1980-012-13:46:40.79003 timprof: Lat [us] -10, Cumulative: 29
1980-012-13:46:40.80003 clock_state: -1
1980-012-13:46:40.80004 timprof: Lat [us] 3, Cumulative: 29
1980-012-13:46:40.81003 clock_state: -1
1980-012-13:46:40.81003 timprof: Lat [us] -3, Cumulative: 26
1980-012-13:46:40.82003 clock_state: -1
1980-012-13:46:40.82003 timprof: Lat [us] 0, Cumulative: 23
1980-012-13:46:40.83003 clock_state: -1
1980-012-13:46:40.83003 timprof: Lat [us] 1, Cumulative: 21
1980-012-13:46:40.84003 clock_state: -1
1980-012-13:46:40.84003 timprof: Lat [us] 0, Cumulative: 19
1980-012-13:46:40.85003 clock_state: -1
1980-012-13:46:40.85004 timprof: Lat [us] 8, Cumulative: 25
1980-012-13:46:40.86003 clock_state: -1
1980-012-13:46:40.86004 timprof: Lat [us] -3, Cumulative: 28
1980-012-13:46:40.87003 clock_state: -1
1980-012-13:46:40.87003 timprof: Lat [us] -5, Cumulative: 26
1980-012-13:46:40.88003 clock_state: -1
1980-012-13:46:40.88003 timprof: Lat [us] 1, Cumulative: 25
1980-012-13:46:40.89003 clock_state: -1
1980-012-13:46:40.89004 timprof: Lat [us] 1, Cumulative: 25
1980-012-13:46:40.90003 clock_state: -1
1980-012-13:46:40.90003 timprof: Lat [us] -2, Cumulative: 23
1980-012-13:46:40.91003 clock_state: -1
1980-012-13:46:40.91003 timprof: Lat [us] 0, Cumulative: 21
1980-012-13:46:40.92003 clock_state: -1
1980-012-13:46:40.92003 timprof: Lat [us] -1, Cumulative: 18
1980-012-13:46:40.93003 clock_state: -1
1980-012-13:46:40.93003 timprof: Lat [us] 2, Cumulative: 17
1980-012-13:46:40.94003 clock_state: -1
1980-012-13:46:40.94003 timprof: Lat [us] -2, Cumulative: 14
1980-012-13:46:40.95003 clock_state: -1
1980-012-13:46:40.95004 timprof: Lat [us] 2, Cumulative: 13
1980-012-13:46:40.96003 clock_state: -1
1980-012-13:46:40.96003 timprof: Lat [us] 0, Cumulative: 12
1980-012-13:46:40.97003 clock_state: -1
1980-012-13:46:40.97003 timprof: Lat [us] -2, Cumulative: 9
1980-012-13:46:40.98003 clock_state: -1
1980-012-13:46:40.98003 timprof: Lat [us] -1, Cumulative: 5
1980-012-13:46:40.99003 clock_state: -1
1980-012-13:46:40.99004 timprof: Lat [us] 5, Cumulative: 6
1980-012-13:46:41.00003 clock_state: -1
1980-012-13:46:41.00003 timprof: Lat [us] -3, Cumulative: 4
1980-012-13:46:41.01003 clock_state: -1
1980-012-13:46:41.01003 timprof: Lat [us] -1, Cumulative: 1
1980-012-13:46:41.02003 clock_state: -1
1980-012-13:46:41.02003 timprof: Lat [us] 0, Cumulative: -2
1980-012-13:46:41.03003 clock_state: -1
1980-012-13:46:41.03003 timprof: Lat [us] 0, Cumulative: -5
1980-012-13:46:41.04003 clock_state: -1
1980-012-13:46:41.04003 timprof: Lat [us] 2, Cumulative: -6
1980-012-13:46:41.05003 clock_state: -1
1980-012-13:46:41.05004 timprof: Lat [us] 1, Cumulative: -6
1980-012-13:46:41.06003 clock_state: -1
1980-012-13:46:41.06004 timprof: Lat [us] 4, Cumulative: -2
1980-012-13:46:41.07003 clock_state: -1
1980-012-13:46:41.07003 timprof: Lat [us] -7, Cumulative: -5
1980-012-13:46:41.08003 clock_state: -1
1980-012-13:46:41.08003 timprof: Lat [us] 0, Cumulative: -8
1980-012-13:46:41.09003 clock_state: -1
1980-012-13:46:41.09003 timprof: Lat [us] 0, Cumulative: -11
1980-012-13:46:41.10003 clock_state: -1
1980-012-13:46:41.10003 timprof: Lat [us] 2, Cumulative: -12
1980-012-13:46:41.11003 clock_state: -1
1980-012-13:46:41.11004 timprof: Lat [us] 2, Cumulative: -11
1980-012-13:46:41.12003 clock_state: -1
1980-012-13:46:41.12003 timprof: Lat [us] -4, Cumulative: -14
1980-012-13:46:41.13003 clock_state: -1
1980-012-13:46:41.13003 timprof: Lat [us] 2, Cumulative: -15
1980-012-13:46:41.14003 clock_state: -1
1980-012-13:46:41.14003 timprof: Lat [us] 0, Cumulative: -16
1980-012-13:46:41.15003 clock_state: -1
1980-012-13:46:41.15003 timprof: Lat [us] 0, Cumulative: -17
1980-012-13:46:41.16003 clock_state: -1
1980-012-13:46:41.16004 timprof: Lat [us] 0, Cumulative: -18
1980-012-13:46:41.17003 clock_state: -1
1980-012-13:46:41.17003 timprof: Lat [us] 0, Cumulative: -19
1980-012-13:46:41.18003 clock_state: -1
1980-012-13:46:41.18003 timprof: Lat [us] 0, Cumulative: -20
1980-012-13:46:41.19003 clock_state: -1
1980-012-13:46:41.19004 timprof: Lat [us] 0, Cumulative: -21
1980-012-13:46:41.20003 clock_state: -1
1980-012-13:46:41.20004 timprof: Lat [us] 1, Cumulative: -21
1980-012-13:46:41.21003 clock_state: -1
1980-012-13:46:41.21004 timprof: Lat [us] 1, Cumulative: -20
1980-012-13:46:41.22003 clock_state: -1
1980-012-13:46:41.22003 timprof: Lat [us] -3, Cumulative: -22
1980-012-13:46:41.23003 clock_state: -1
1980-012-13:46:41.23003 timprof: Lat [us] 0, Cumulative: -24
1980-012-13:46:41.24003 clock_state: -1
1980-012-13:46:41.24003 timprof: Lat [us] -1, Cumulative: -27
1980-012-13:46:41.25003 clock_state: -1
1980-012-13:46:41.25003 timprof: Lat [us] 2, Cumulative: -28
1980-012-13:46:41.26003 clock_state: -1
1980-012-13:46:41.26003 timprof: Lat [us] -2, Cumulative: -31
1980-012-13:46:41.27003 clock_state: -1
1980-012-13:46:41.27003 timprof: Lat [us] 1, Cumulative: -33
1980-012-13:46:41.28003 clock_state: -1
1980-012-13:46:41.28003 timprof: Lat [us] 0, Cumulative: -35
1980-012-13:46:41.29003 clock_state: -1
1980-012-13:46:41.29003 timprof: Lat [us] 0, Cumulative: -37
EVS Port1 66/1/CFE_TIME 21: Stop FLYWHEEL
1980-012-13:46:41.00692 clock_state: -1
1980-012-13:46:41.00693 timprof: Lat [us] -293108, Cumulative: -293147
1980-012-13:46:41.01692 clock_state: -1
1980-012-13:46:41.01693 timprof: Lat [us] 2, Cumulative: -586255
1980-012-13:46:41.02692 clock_state: -1
1980-012-13:46:41.02693 timprof: Lat [us] 1, Cumulative: -879362

Start with CFE_TIME_EarlyInit and the cFE time-utils header, then compare the reported INVALID state with the flywheeling behavior shown in the log. Confirm whether this startup sequence is expected and identify what documentation or behavior would need clarification; done means the state transition and the effect of setting MET are explained reproducibly.