"power up + xx:yy:zz" message in log files

[mhackney]

I'm using the new logging features (RRF >= 3.2.0-beta3 M929) to store in a log file. Normally, we see each log line prefaced with the date/time of the entry. But I'm seeing large groups of log messages over multiple days (so the hours wrap around) with this "power up " message.

An M905 shows the correct data and time (at least at the time I run it).

I'd greatly prefer having the explicit date/time at the start of each log line. Anyone have any idea on why I see this isn't the case?

thanks,
Michael

[dc42]

When the Duet powers up, it doesn't know the date/time until an instance of DWC connects to it and informs it of the date/time. That's why it isn't included in the power up message.

[T3P3Tony]

@mhackney would an acceptable work around be for a date-time stamp to be logged when DWC connects and the clock is set?

[mhackney]

@t3p3tony not sure I understand this, can you explain more please?

We don't expect our users to use the web interface so we need a mechanism to make sure log entries are time stamped w/o a DWC connection.

[mhackney]

Ok, I understand the behavior now. This is the very start of a log file. So, if I understand correctly, the DWC login occurred at 00:00:19 after power up, the clock was set and time stamps used after that.

power up + 00:00:15 [info] Event logging started at level warn

power up + 00:00:15 [info] Running: Duet Ethernet 1.02 or later: 3.2 (2021-01-05)

power up + 00:00:17 [warn] Network running, IP address = X.X.X.X

power up + 00:00:19 [warn] HTTP client Z.Z.Z.Z login succeeded
2021-02-26 18:43:10 [warn] Date and time set at 
power up + 00:00:19
2021-02-26 18:43:11 [warn] HTTP client Z.Z.Z.Z login succeeded