<html>
<head>
<meta http-equiv="Content-Type" content="text/html; charset=UTF-8">
</head>
<body text="#000000" bgcolor="#FFFFFF">
Hi Mark, Michael,<br>
<br>
The module updated itself to the 1d03 firmware early this morning,
on schedule, and the update was only done the one time (no double
updates as yet). 'metric list version' shows :<br>
<blockquote>3.2.008-126-g0291d03/ota_1/edge (build idf
v3.3-beta3-775-gdc1ca69 Jan 31 2020 08:10:46)<br>
</blockquote>
So it appears that the issue has been resolved. <br>
<br>
I haven't tried rebooting the module to look at the boot sequence,
as I thought I should let it sit for a day (to scan past the update
window a second time) to see if it remains stable. I'll give the
boot sequence a look in a day or so.<br>
<br>
Thanks for figuring this out! Great teamwork.<br>
<br>
Greg<br>
<br>
<br>
<br>
<div class="moz-cite-prefix">Mark Webb-Johnson wrote:<br>
</div>
<blockquote type="cite"
cite="mid:707C5D10-7439-4517-AD1B-12A8DE937F44@webb-johnson.net">
<meta http-equiv="Content-Type" content="text/html; charset=UTF-8">
I put some telemetry on this and see (for the first ten seconds of
boot):
<div class=""><br class="">
</div>
<blockquote style="margin: 0 0 0 40px; border: none; padding:
0px;" class="">
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(43) events: Initialising EVENTS (1200)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(612) events: SignalEvent: event 'config.mounted' queued
(0 waiting, 20 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(682) ovms_main: Starting HOUSEKEEPING...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(682) housekeeping: Initialising HOUSEKEEPING Framework...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(842) events: SignalEvent: event 'housekeeping.init'
queued (1 waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1022) housekeeping: Executing on CPU core 1</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1022) housekeeping: reset_reason: cpu0=12, cpu1=12</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1022) housekeeping: Initialising WATCHDOG...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1022) housekeeping: Starting PERIPHERALS...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1262) events: SignalEvent: event 'egpio.output.1.low'
queued (1 waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1262) housekeeping: Auto init max7317 (free: 193092
bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1272) housekeeping: Auto init ext12v (free: 193092 bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1282) housekeeping: Auto init dbc (free: 193092 bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1282) housekeeping: Auto init wifi (free: 193092 bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1462) housekeeping: Auto init modem (free: 149952 bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1472) housekeeping: Auto init vehicle (free: 149656
bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1482) events: SignalEvent: event 'power.can1.on' queued
(4 waiting, 16 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1492) events: SignalEvent: event 'egpio.output.2.low'
queued (5 waiting, 15 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1502) housekeeping: Auto init obd2ecu (free: 141484
bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1502) housekeeping: Auto init server v2 (free: 141484
bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1512) housekeeping: Auto init server v3 (free: 141484
bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1522) housekeeping: Auto init javascript (free: 141484
bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1522) housekeeping: Auto init done (free: 141484 bytes)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(1532) housekeeping: Starting USB console...</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">Firmware:
3.2.008-125-g1fca0362-dirty/factoents: </span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">SignalEvent:
event 'system.start' queued (7 waiting, 13 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(2022) events: SignalEvent: event 'ticker.1' queued (8
waiting, 12 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(2202) simcom: State: Enter CheckPowerOff state</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(2202) events: SignalEvent: event 'egpio.output.3.low'
queued (1 waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(2202) events: SignalEvent: event 'egpio.output.0.low'
queued (2 waiting, 18 free)</span></font></div>
<div class=""><span style="font-size: 14px; font-family:
"Andale Mono";" class="">I (3022) events:
SignalEvent: event 'ticker.1' queued (1 waiting, 19 free)</span></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(4022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(5022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(6022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(6022) sdcard: SD CARD has been inserted</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(6022) events: SignalEvent: event 'sd.insert' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(7022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(8022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(8022) events: SignalEvent: event 'sd.mounted' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(9022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(10022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(11022) events: SignalEvent: event 'ticker.1' queued (1
waiting, 19 free)</span></font></div>
<div class=""><font class="" face="Andale Mono"><span
style="font-style: normal; font-size: 14px;" class="">I
(11022) events: SignalEvent: event 'ticker.10' queued (1
waiting, 19 free)</span></font></div>
</blockquote>
<div class="">
<div><br class="">
</div>
<div>(that doesn’t include scheduled events, only the ones
from OvmsEvents::SignalEvent)</div>
<div><br class="">
</div>
<div>I think the issue was most likely triggered by the egpio
events introduced a while back. Agree with Thomas that this is
most likely just a simple queue overflow. Perhaps EGPIO is
flapping at startup, causing a flurry of these egpio events? I
am guessing that this is aggravated in Greg’s case as he is
doing some egpio and power work for HUD control.</div>
<div><br class="">
</div>
<div>I’ve increased the OVMS_HW_EVENT_QUEUE_SIZE to 40 on my
test build, and just released that to the production server
(for edge). As this sdkconfig file is not included in github,
I recommend all developers do the same for their local builds.</div>
<div><br class="">
</div>
<div>@Michael can you set the same on your production build
server?</div>
<div><br class="">
</div>
<div>@Greg can you check your existing firmware boot (via usb)
and check for 'queue overflow, event '%s’ dropped’ style
messages on startup (probably around the time is says
’Starting USB console…’.</div>
<div><br class="">
</div>
<div>@Greg please try the edge 3.2.008-126-g0291d03 firmware.
Update to it, reboot, and see if ‘metric list version’ shows
the version correctly. Hopefully this resolves (or works
around) the issue.</div>
<div><br class="">
</div>
<div>Regards, Mark.</div>
<div><br class="">
<blockquote type="cite" class="">
<div class="">On 30 Jan 2020, at 8:07 PM, Michael Balzer
<<a href="mailto:dexter@expeedo.de" class=""
moz-do-not-send="true">dexter@expeedo.de</a>> wrote:</div>
<br class="Apple-interchange-newline">
<div class="">
<meta http-equiv="Content-Type" content="text/html;
charset=UTF-8" class="">
<div class="">
<div class="moz-cite-prefix">Maybe
OVMS_HW_EVENT_QUEUE_SIZE is just too small now, with
the added events during boot and async event
processing?<br class="">
<br class="">
Regards,<br class="">
Michael<br>
</div>
</div>
</div>
</blockquote>
</div>
</div>
</blockquote>
{snip}<br>
</body>
</html>