2025-04-21T04:02:22.278Z,1745208142.278 [CommandExec](IMPORTANT): got command restart logs
2025-04-21T04:02:23.379Z,1745208143.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040221.00,A,3648.39064,N,12150.29786,W,0.972,21.52,210425,,,A*45
2025-04-21T04:02:23.382Z,1745208143.382 [NAL9602](INFO): GPS fix at 20250421T040221: (36.806511, -121.838298)
2025-04-21T04:02:23.405Z,1745208143.405 [Default:CheckIn:Read_GPS] Stopped
2025-04-21T04:02:23.405Z,1745208143.405 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-21T04:02:43.515Z,1745208163.515 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20214, MT Status=1, MTMSN=4301
2025-04-21T04:02:43.577Z,1745208163.577 [NAL9602](INFO): Sent 90 bytes from file Logs/20250420T130000/Courier0030.lzma
2025-04-21T04:02:43.577Z,1745208163.577 [NAL9602](INFO): Packets left to send: 0
2025-04-21T04:02:44.185Z,1745208164.185 [NAL9602](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Depth 20 m;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m" 53lm2 1 2
2025-04-21T04:02:44.205Z,1745208164.205 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Depth 20 m;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m" 53lm2 1 2.000000
2025-04-21T04:02:44.206Z,1745208164.206 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=53lm2
2025-04-21T04:02:44.207Z,1745208164.207 [CommandExec](IMPORTANT): Scheduled #16 (#1 of 2 with id='53lm2'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Depth 20 m;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m", ASAP
2025-04-21T04:02:52.241Z,1745208172.241 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:03:02.723Z,1745208182.723 [NAL9602](INFO): SBD MO Status=2, MOMSN=20215, MT Status=2, MTMSN=0
2025-04-21T04:03:02.723Z,1745208182.723 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T04:03:23.377Z,1745208203.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:03:26.038Z,1745208206.038 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=20215, MT Status=1, MTMSN=4302
2025-04-21T04:03:26.085Z,1745208206.085 [NAL9602](INFO): Sent 332 bytes from file Logs/20250420T130000/Express0031.lzma
2025-04-21T04:03:26.085Z,1745208206.085 [NAL9602](INFO): Packets left to send: 3
2025-04-21T04:03:26.665Z,1745208206.665 [NAL9602](INFO): Received command: sched asap "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 53lm2 2 2
2025-04-21T04:03:26.720Z,1745208206.720 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 53lm2 2 2.000000
2025-04-21T04:03:26.721Z,1745208206.721 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=53lm2
2025-04-21T04:03:26.722Z,1745208206.722 [CommandExec](IMPORTANT): Scheduled #17 (#2 of 2 with id='53lm2'): "set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run", ASAP
2025-04-21T04:03:26.868Z,1745208206.868 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-04-21T04:03:26.868Z,1745208206.868 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-04-21T04:03:26.901Z,1745208206.901 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-04-21T04:03:26.913Z,1745208206.913 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-04-21T04:03:29.308Z,1745208209.308 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="keepstation">
    <DefineArg Name="MissionTimeout">
        <Units:hour />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="ApproachDepth">
        <Units:meter />
        <Value>15</Value>
    </DefineArg>
    <DefineArg Name="DepthDeadband">
        <Units:meter />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>0.75</Value>
    </DefineArg>
    <DefineArg Name="Radius">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="SinkOffSurface">
        <False />
    </DefineArg>
    <DefineArg Name="SinkDepth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SinkDepthTimeout">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <DefineOutput Name="DiveMode">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="DoingComms">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="SurfaceSink">
        <Units:count />
        <Value>1</Value>
    </DefineOutput>
    <DefineOutput Name="TransitToStation">
        <Units:count />
        <Value>2</Value>
    </DefineOutput>
    <DefineOutput Name="StationKeep">
        <Units:count />
        <Value>3</Value>
    </DefineOutput>
    <DefineOutput Name="OnStation">
        <False />
    </DefineOutput>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Pitch>
        <Parallel />
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="SurfaceComms">
        <When>
            <Called />
            <Or>
                <Elapsed>
                    <Universal:time_fix />
                </Elapsed>
                <Gt>
                    <Arg Name="NeedCommsTime" />
                </Gt>
            </Or>
        </When>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="DoingComms" />
        </Assign>
        <Call RefId="NeedComms" />
        <Aggregate Id="setTransit">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Arg Name="OnStation" />
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="TransitToStation" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setStation">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Not>
                        <Arg Name="OnStation" />
                    </Not>
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="StationKeep" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setSink">
            <Sequence />
            <Break>
                <Not>
                    <Arg Name="SinkOffSurface" />
                </Not>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="SurfaceSink" />
            </Assign>
        </Aggregate>
    </Aggregate>
    <Call RefId="SurfaceComms" Id="StartingMission" />
    <Aggregate Id="DiveCmd">
        <When>
            <Called />
        </When>
        <Aggregate Id="SurfaceSink">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="SurfaceSink" />
                </Eq>
            </While>
            <Aggregate Id="sink">
                <Sequence />
                <Guidance:Buoyancy Id="BuoyancyHold">
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Units:cubic_centimeter />
                        <Value>NaN</Value>
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Units:meter_per_second />
                        <Value>0</Value>
                    </Setting>
                </Guidance:SetSpeed>
                <Syslog Severity="Info">
                    Sinking to 
                    <Arg Name="SinkDepth" />
                    <Units:meter />
                </Syslog>
                <Guidance:Pitch>
                    <Sequence />
                    <Timeout Duration="SinkDepthTimeout">
                        <Syslog Severity="Important">
                            Timed out while trying to reach target sink depth at current depth of 
                            <Universal:depth />
                            <Units:meter />
                        </Syslog>
                    </Timeout>
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="SinkDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Aggregate Id="sinkToTransit">
                    <Sequence />
                    <Break>
                        <Arg Name="OnStation" />
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="TransitToStation" />
                    </Assign>
                </Aggregate>
                <Aggregate Id="sinkToStation">
                    <Sequence />
                    <Break>
                        <Not>
                            <Arg Name="OnStation" />
                        </Not>
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="StationKeep" />
                    </Assign>
                </Aggregate>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="TransitToStation">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="TransitToStation" />
                </Eq>
            </While>
            <Aggregate Id="dive">
                <Sequence />
                <Guidance:Buoyancy>
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Control:VerticalControl.buoyancyNeutral />
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:Pitch>
                    <Parallel />
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="ApproachDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Arg Name="Speed" />
                    </Setting>
                </Guidance:SetSpeed>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="StationKeep">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="StationKeep" />
                </Eq>
            </While>
            <Assign>
                <Parallel />
                <Control:VerticalControl.depthDeadband />
                <Arg Name="DepthDeadband" />
            </Assign>
            <Guidance:Pitch>
                <Parallel />
                <Setting>
                    <Guidance:Pitch.depth />
                    <Arg Name="Depth" />
                </Setting>
            </Guidance:Pitch>
            <Guidance:KeepStation>
                <Parallel />
                <Setting>
                    <Guidance:KeepStation.latitude />
                    <Arg Name="Latitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.longitude />
                    <Arg Name="Longitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.radius />
                    <Arg Name="Radius" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.speed />
                    <Arg Name="Speed" />
                </Setting>
            </Guidance:KeepStation>
        </Aggregate>
    </Aggregate>
    <Aggregate Id="TransitToStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" />
        </Aggregate>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="StationKeep" />
        </Assign>
        <Assign>
            <Sequence />
            <Arg Name="OnStation" />
            <True />
        </Assign>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" />
        </Aggregate>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-04-21T04:03:29.332Z,1745208209.332 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-04-21T04:03:29.369Z,1745208209.369 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-04-21T04:03:29.372Z,1745208209.372 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-04-21T04:03:29.379Z,1745208209.379 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-04-21T04:03:29.385Z,1745208209.385 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-04-21T04:03:29.387Z,1745208209.387 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-04-21T04:03:29.410Z,1745208209.410 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-04-21T04:03:29.412Z,1745208209.412 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-04-21T04:03:29.423Z,1745208209.423 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-04-21T04:03:29.429Z,1745208209.429 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-04-21T04:03:29.431Z,1745208209.431 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-04-21T04:03:29.450Z,1745208209.450 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-04-21T04:03:29.452Z,1745208209.452 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-04-21T04:03:29.483Z,1745208209.483 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-04-21T04:03:29.498Z,1745208209.498 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-04-21T04:03:29.500Z,1745208209.500 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-04-21T04:03:29.516Z,1745208209.516 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-04-21T04:03:29.526Z,1745208209.526 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-04-21T04:03:29.537Z,1745208209.537 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-04-21T04:03:29.539Z,1745208209.539 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-04-21T04:03:29.540Z,1745208209.540 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-04-21T04:03:29.540Z,1745208209.540 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-04-21T04:03:29.549Z,1745208209.549 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-04-21T04:03:30.038Z,1745208210.038 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="NeedComms">
    <DefineArg Name="DiveInterval">
        <Units:hour />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="WaitForPitchUp">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SurfacePitch">
        <Units:degree />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="SurfaceDepthRate">
        <Units:meter_per_second />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="SurfaceSpeed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="SurfacingTimeout">
        <Units:second />
        <Value>1000</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <When>
        <Called />
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
                <Add>
                    <Arg Name="WaitForPitchUp" />
                </Add>
            </Gt>
        </Or>
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
            </Gt>
            <And>
                <Universal:platform_pitch_angle />
                <Gt>
                    <Units:degree />
                    <Value>0</Value>
                </Gt>
            </And>
        </Or>
    </When>
    <Syslog Severity="Info">
        last time_fix was: 
        <Universal:time_fix />
        <Units:epoch_second />
    </Syslog>
    <Guidance:GoToSurface>
        <Progression />
        <Setting>
            <Guidance:GoToSurface.pitch />
            <Arg Name="SurfacePitch" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.depthRate />
            <Arg Name="SurfaceDepthRate" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.speed />
            <Arg Name="SurfaceSpeed" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.surfaceTimeout />
            <Arg Name="SurfacingTimeout" />
        </Setting>
    </Guidance:GoToSurface>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">first GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="CommsTimeout">
            <Syslog Severity="Important">
                Comms timed out after 
                <Arg Name="CommsTimeout" />
                <Units:minute />
                minutes
            </Syslog>
        </Timeout>
        <Universal:platform_communications />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">second GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
</Aggregate>

2025-04-21T04:03:30.047Z,1745208210.047 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-04-21T04:03:30.054Z,1745208210.054 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-04-21T04:03:30.056Z,1745208210.056 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-04-21T04:03:30.115Z,1745208210.115 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-04-21T04:03:30.121Z,1745208210.121 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-04-21T04:03:30.123Z,1745208210.123 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-04-21T04:03:30.145Z,1745208210.145 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-04-21T04:03:30.148Z,1745208210.148 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-04-21T04:03:30.178Z,1745208210.178 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-21T04:03:30.250Z,1745208210.250 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-04-21T04:03:30.250Z,1745208210.250 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-04-21T04:03:30.251Z,1745208210.251 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-04-21T04:03:30.394Z,1745208210.394 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="StandardEnvelopes">
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepthIgnore">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:meter />
        <Value>2000</Value>
    </DefineArg>
    <Parallel />
    <Guidance:AltitudeEnvelope>
        <Parallel />
        <Setting>
            <Guidance:AltitudeEnvelope.minAltitude />
            <Arg Name="MinAltitude" />
        </Setting>
        <Setting>
            <Guidance:AltitudeEnvelope.maxDepthIgnore />
            <Arg Name="MaxDepthIgnore" />
        </Setting>
    </Guidance:AltitudeEnvelope>
    <Guidance:DepthEnvelope>
        <Parallel />
        <Setting>
            <Guidance:DepthEnvelope.maxDepth />
            <Arg Name="MaxDepth" />
        </Setting>
    </Guidance:DepthEnvelope>
    <Guidance:OffshoreEnvelope>
        <Parallel />
        <Setting>
            <Guidance:OffshoreEnvelope.minOffshore />
            <Arg Name="MinOffshore" />
        </Setting>
    </Guidance:OffshoreEnvelope>
</Aggregate>

2025-04-21T04:03:30.402Z,1745208210.402 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-04-21T04:03:30.404Z,1745208210.404 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-04-21T04:03:30.411Z,1745208210.411 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-04-21T04:03:30.433Z,1745208210.433 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-04-21T04:03:30.434Z,1745208210.434 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-04-21T04:03:30.444Z,1745208210.444 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-04-21T04:03:30.459Z,1745208210.459 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-04-21T04:03:30.523Z,1745208210.523 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-04-21T04:03:30.523Z,1745208210.523 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-04-21T04:03:30.537Z,1745208210.537 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-04-21T04:03:30.624Z,1745208210.624 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2025-04-21T04:03:30.635Z,1745208210.635 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-04-21T04:03:30.645Z,1745208210.645 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-04-21T04:03:30.648Z,1745208210.648 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-04-21T04:03:30.648Z,1745208210.648 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-04-21T04:03:30.661Z,1745208210.661 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-04-21T04:03:30.909Z,1745208210.909 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2025-04-21T04:03:30.921Z,1745208210.921 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-04-21T04:03:30.923Z,1745208210.923 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-04-21T04:03:30.933Z,1745208210.933 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-04-21T04:03:30.935Z,1745208210.935 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-04-21T04:03:31.018Z,1745208211.018 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-04-21T04:03:31.092Z,1745208211.092 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-04-21T04:03:31.174Z,1745208211.174 [keepstation:H.Pitch](DEBUG): Construct.
2025-04-21T04:03:31.245Z,1745208211.245 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-04-21T04:03:31.248Z,1745208211.248 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-04-21T04:03:31.270Z,1745208211.270 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-04-21T04:03:31.317Z,1745208211.317 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-04-21T04:03:31.320Z,1745208211.320 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-04-21T04:03:31.341Z,1745208211.341 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-04-21T04:03:31.354Z,1745208211.354 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-04-21T04:03:31.370Z,1745208211.370 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-04-21T04:03:31.406Z,1745208211.406 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-04-21T04:03:31.448Z,1745208211.448 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-04-21T04:03:31.507Z,1745208211.507 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission keepstation {
  """
  Vehicle transits to desired waypoint (or stays put if none is commanded)
  and stays within the specified radius.
  """

  arguments {
    MissionTimeout = 4 hour
      """
      Maximum duration of mission
      """

    NeedCommsTime = 45 minute
      """
      How often to surface for communications
      """

    Latitude = NaN degree
      """
      Latitude of waypoint to hold. If NaN, hold the latitude at start of
      mission.
      """

    Longitude = NaN degree
      """
      Longitude of waypoint to hold. If NaN, hold the longitude at start of
      mission.
      """

    Depth = 30 meter
      """
      Depth held during drift mode
      """

    ApproachDepth = 15 meter
      """
      Depth for initial approach to station.
      """

    DepthDeadband = 4 meter
      """
      How much vertical drift from the specified depth is allowed in drift
      mode
      """

    Speed = 0.75 meter_per_second
      """
      Vehicle transit speed.
      """

    Radius = 200 meter
      """
      Radius of circle around waypoint to hold.
      """

    SinkOffSurface = false
      """
      If true, use buoyancy only to decend to SinkDepth before driving to first target depth
      """

    SinkDepth = 10 meter
      """
      Initial depth to decend using only buoyancy before driving
      """

    SinkDepthTimeout = 10 minute
      """
      Max wait time for the vehicle to reach the target depth
      """

    MaxDepth = 45 meter
      """
      Maximum allowable depth during the mission.
      """

    MinOffshore = 2 kilometer
      """
      Minimum distance from the shoreline to maintain
      """
  }

  output {
    DiveMode = 0 count
      """
      Mission variable (don't change). The mission sets this variable to
      switch between dive modes. Initialized to DoingComms.
      """

    DoingComms = 0 count
      """
      Mission variable (don't change). The mission will run in this mode
      when doing surface comms.
      """

    SurfaceSink = 1 count
      """
      Mission variable (don't change). The mission will run in this mode
      when sinking from the surface using only buoyancy.
      """

    TransitToStation = 2 count
      """
      Mission variable (don't change). The mission will run in this mode
      when performing the transit to station
      """

    StationKeep = 3 count
      """
      Mission variable (don't change). The mission will run in this mode
      when keeping station
      """

    OnStation = false
      """
      Mission variable (don't change). Determines last mode (Transit or StationKeep)
      to resume after needcomms
      """
  }

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

  aggregate SurfaceComms {
    run when (
      called
      or ( elapsed ( Universal:time_fix ) > NeedCommsTime )
    )

    assign in sequence DiveMode = DoingComms

    call refId="NeedComms"

    aggregate setTransit {
      run in sequence

      break if (
        SinkOffSurface
        or OnStation
      )

      assign in sequence DiveMode = TransitToStation
    }

    aggregate setStation {
      run in sequence

      break if (
        SinkOffSurface
        or not OnStation
      )

      assign in sequence DiveMode = StationKeep
    }

    aggregate setSink {
      run in sequence

      break if ( not SinkOffSurface )

      assign in sequence DiveMode = SurfaceSink
    }
  }

  call id="StartingMission" refId="SurfaceComms"

  aggregate DiveCmd {
    run when ( called )

    aggregate SurfaceSink {
      run while ( DiveMode == SurfaceSink )

      aggregate sink {
        run in sequence

        behavior Guidance:Buoyancy id="BuoyancyHold" {
          run in parallel

          set position = NaN cc
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = 0 m/s
        }

        syslog info "Sinking to " + SinkDepth~meter

        behavior Guidance:Pitch {
          run in sequence

          timeout duration=SinkDepthTimeout {
            syslog important "Timed out while trying to reach target sink depth at current depth of "
                 + Universal:depth~meter
          }

          set depth = SinkDepth
        }

        # Once we've sunk, either resume transit or station keeping depths and speeds
        aggregate sinkToTransit {
          run in sequence

          break if ( OnStation )

          assign in sequence DiveMode = TransitToStation
        }

        aggregate sinkToStation {
          run in sequence

          break if ( not OnStation )

          assign in sequence DiveMode = StationKeep
        }
      }
    }

    aggregate TransitToStation {
      run while ( DiveMode == TransitToStation )

      aggregate dive {
        run in sequence

        behavior Guidance:Buoyancy {
          run in parallel

          set position = Control:VerticalControl.buoyancyNeutral
        }

        behavior Guidance:Pitch {
          run in parallel

          set depth = ApproachDepth
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = Speed
        }
      }
    }

    aggregate StationKeep {
      run while ( DiveMode == StationKeep )

      assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

      behavior Guidance:Pitch {
        run in parallel

        set depth = Depth
      }

      behavior Guidance:KeepStation {
        run in parallel

        set latitude = Latitude
        set longitude = Longitude
        set radius = Radius
        set speed = Speed
      }
    }
  }

  aggregate TransitToStation {
    """
    Need a separate aggregate for transit if we want to specify a
    different depth for the approach.
    """

    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call refId="DiveCmd"
    }

    behavior Guidance:Waypoint id="Wpt1" {
      run in sequence

      set latitude = Latitude
      set longitude = Longitude
    }

    assign in sequence DiveMode = StationKeep
    assign in sequence OnStation = true
  }

  call id="OnStation" refId="NeedComms"

  aggregate KeepStation {
    run in sequence

    aggregate Dive {
      run while ( not ( DiveMode == DoingComms ) )

      call refId="DiveCmd"
    }

    behavior Guidance:Wait {
      """
      Due to the way the KeepStation behavior is currently
      implemented, we must run it in parallel and use a Wait to keep
      from completing the mission before the timeout expires. However,
      this new version of the mission uses a top-level timeout so that
      the entire mission will not run for longer then MissionTimeout.
      """

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2025-04-21T04:03:31.508Z,1745208211.508 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-04-21T04:03:36.285Z,1745208216.285 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002997
2025-04-21T04:03:48.815Z,1745208228.815 [DataOverHttps](INFO): Sending 1132 bytes from file Logs/20250420T130000/Express0031.lzma
2025-04-21T04:03:49.815Z,1745208229.815 [DataOverHttps](INFO): Moved sent file to Logs/20250420T130000/Express0031.lzma.bak
2025-04-21T04:03:49.815Z,1745208229.815 [DataOverHttps](INFO): SBD MOMSN=24696607
2025-04-21T04:03:52.205Z,1745208232.205 [Default:CheckIn:Read_Iridium] Stopped
2025-04-21T04:03:52.205Z,1745208232.205 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-21T04:03:52.205Z,1745208232.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-21T04:03:59.851Z,1745208239.851 [NAL9602](INFO): SBD MO Status=1, MOMSN=20216, MT Status=0, MTMSN=0
2025-04-21T04:03:59.905Z,1745208239.905 [NAL9602](INFO): Sent 332 bytes from file Logs/20250420T130000/Express0031.lzma
2025-04-21T04:03:59.905Z,1745208239.905 [NAL9602](INFO): Packets left to send: 2
2025-04-21T04:04:02.829Z,1745208242.829 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour
2025-04-21T04:04:02.830Z,1745208242.830 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 120 minute
2025-04-21T04:04:02.830Z,1745208242.830 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter
2025-04-21T04:04:02.831Z,1745208242.831 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool
2025-04-21T04:04:02.832Z,1745208242.832 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter
2025-04-21T04:04:17.934Z,1745208257.934 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second
2025-04-21T04:04:17.935Z,1745208257.935 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute
2025-04-21T04:04:17.936Z,1745208257.936 [CommandExec](IMPORTANT): got command run
2025-04-21T04:04:17.943Z,1745208257.943 [CommandExec](IMPORTANT): Running 
2025-04-21T04:04:18.015Z,1745208258.015 [Default] Stopped
2025-04-21T04:04:18.015Z,1745208258.015 [Default](DEBUG): Aggregate::uninitialize Default
2025-04-21T04:04:18.015Z,1745208258.015 [Default:B.GoToSurface] Stopped
2025-04-21T04:04:18.015Z,1745208258.015 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-21T04:04:18.015Z,1745208258.015 [Default:CheckIn] Stopped
2025-04-21T04:04:18.016Z,1745208258.016 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-21T04:04:18.016Z,1745208258.016 [Default:CheckIn:C.Wait] Stopped
2025-04-21T04:04:18.016Z,1745208258.016 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-21T04:04:18.016Z,1745208258.016 [MissionManager](IMPORTANT): Started mission keepstation
2025-04-21T04:04:18.016Z,1745208258.016 [keepstation] Running Loop=1
2025-04-21T04:04:18.016Z,1745208258.016 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes] Running Loop=1
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-04-21T04:04:18.017Z,1745208258.017 [keepstation:BackseatDriver] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly:A] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly:B] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly:C] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly:D] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:H.Pitch] Running Loop=1
2025-04-21T04:04:18.018Z,1745208258.018 [keepstation:H.Pitch](DEBUG): Initialize.
2025-04-21T04:04:18.019Z,1745208258.019 [keepstation:B] Running Loop=1
2025-04-21T04:04:18.019Z,1745208258.019 [keepstation:H.Pitch] Running Loop=1
2025-04-21T04:04:18.020Z,1745208258.020 [keepstation:PowerOnly] Running Loop=1
2025-04-21T04:04:18.020Z,1745208258.020 [keepstation:PowerOnly:D] Stopped
2025-04-21T04:04:18.020Z,1745208258.020 [keepstation:PowerOnly:C] Stopped
2025-04-21T04:04:18.020Z,1745208258.020 [keepstation:PowerOnly:B] Stopped
2025-04-21T04:04:18.020Z,1745208258.020 [keepstation:PowerOnly:A] Stopped
2025-04-21T04:04:18.021Z,1745208258.021 [keepstation:BackseatDriver] Running Loop=1
2025-04-21T04:04:18.021Z,1745208258.021 [keepstation:StandardEnvelopes] Running Loop=1
2025-04-21T04:04:18.021Z,1745208258.021 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-04-21T04:04:18.021Z,1745208258.021 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-04-21T04:04:18.021Z,1745208258.021 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-04-21T04:04:18.022Z,1745208258.022 [keepstation:B] Stopped
2025-04-21T04:04:18.022Z,1745208258.022 [keepstation:D] Running Loop=1
2025-04-21T04:04:18.429Z,1745208258.429 [keepstation:D] Stopped
2025-04-21T04:04:18.429Z,1745208258.429 [keepstation:E] Running Loop=1
2025-04-21T04:04:18.821Z,1745208258.821 [keepstation:E] Stopped
2025-04-21T04:04:18.821Z,1745208258.821 [keepstation:StartingMission] Running Loop=1
2025-04-21T04:04:18.821Z,1745208258.821 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-04-21T04:04:19.221Z,1745208259.221 [keepstation:SurfaceComms] Running Loop=1
2025-04-21T04:04:19.221Z,1745208259.221 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-21T04:04:19.222Z,1745208259.222 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-21T04:04:19.222Z,1745208259.222 [keepstation:SurfaceComms:A] Stopped
2025-04-21T04:04:19.222Z,1745208259.222 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-21T04:04:19.222Z,1745208259.222 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-21T04:04:19.631Z,1745208259.631 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-04-21T04:04:19.631Z,1745208259.631 [keepstation:NeedComms] Running Loop=1
2025-04-21T04:04:19.631Z,1745208259.631 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-21T04:04:19.631Z,1745208259.631 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-21T04:04:19.631Z,1745208259.631 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-21T04:04:19.632Z,1745208259.632 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-21T04:04:19.632Z,1745208259.632 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-21T04:04:19.633Z,1745208259.633 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-21T04:04:19.633Z,1745208259.633 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-21T04:04:19.633Z,1745208259.633 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-21T04:04:19.634Z,1745208259.634 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-21T04:04:19.634Z,1745208259.634 [keepstation:NeedComms:A] Running Loop=1
2025-04-21T04:04:19.635Z,1745208259.635 [keepstation:NeedComms:A](INFO): last time_fix was:  1745208141.000000 second since 1970/01/01T00:00:00Z 
2025-04-21T04:04:19.635Z,1745208259.635 [keepstation:NeedComms:A] Stopped
2025-04-21T04:04:20.049Z,1745208260.049 [keepstation:NeedComms:C] Running Loop=1
2025-04-21T04:04:20.438Z,1745208260.438 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-21T04:04:22.133Z,1745208262.133 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040420.00,A,3648.38825,N,12150.28376,W,0.330,98.07,210425,,,A*4A
2025-04-21T04:04:22.135Z,1745208262.135 [NAL9602](INFO): GPS fix at 20250421T040420: (36.806471, -121.838063)
2025-04-21T04:04:22.148Z,1745208262.148 [keepstation:NeedComms:C] Stopped
2025-04-21T04:04:22.148Z,1745208262.148 [keepstation:NeedComms:D] Running Loop=1
2025-04-21T04:04:22.475Z,1745208262.475 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-21T04:04:29.765Z,1745208269.765 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20250421T040222/Courier0003.lzma
2025-04-21T04:04:30.766Z,1745208270.766 [DataOverHttps](INFO): Moved sent file to Logs/20250421T040222/Courier0003.lzma.bak
2025-04-21T04:04:30.767Z,1745208270.767 [DataOverHttps](INFO): SBD MOMSN=24696616
2025-04-21T04:04:49.380Z,1745208289.380 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250421T040222/Courier0006.lzma
2025-04-21T04:04:50.382Z,1745208290.382 [DataOverHttps](INFO): Moved sent file to Logs/20250421T040222/Courier0006.lzma.bak
2025-04-21T04:04:50.383Z,1745208290.383 [DataOverHttps](INFO): SBD MOMSN=24696620
2025-04-21T04:04:54.762Z,1745208294.762 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-21T04:05:07.222Z,1745208307.222 [DataOverHttps](INFO): Sending 850 bytes from file Logs/20250421T040222/Express0004.lzma
2025-04-21T04:05:08.222Z,1745208308.222 [DataOverHttps](INFO): Moved sent file to Logs/20250421T040222/Express0004.lzma.bak
2025-04-21T04:05:08.223Z,1745208308.223 [DataOverHttps](INFO): SBD MOMSN=24696624
2025-04-21T04:05:24.293Z,1745208324.293 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20250421T040222/Express0007.lzma
2025-04-21T04:05:25.295Z,1745208325.295 [DataOverHttps](INFO): Moved sent file to Logs/20250421T040222/Express0007.lzma.bak
2025-04-21T04:05:25.295Z,1745208325.295 [DataOverHttps](INFO): SBD MOMSN=24696637
2025-04-21T04:05:27.139Z,1745208327.139 [keepstation:NeedComms:D] Stopped
2025-04-21T04:05:27.139Z,1745208327.139 [keepstation:NeedComms:E] Running Loop=1
2025-04-21T04:05:27.497Z,1745208327.497 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-21T04:05:29.103Z,1745208329.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040527.00,A,3648.38623,N,12150.27581,W,1.536,98.81,210425,,,A*4A
2025-04-21T04:05:29.106Z,1745208329.106 [NAL9602](INFO): GPS fix at 20250421T040527: (36.806437, -121.837930)
2025-04-21T04:05:29.150Z,1745208329.150 [keepstation:NeedComms:E] Stopped
2025-04-21T04:05:29.151Z,1745208329.151 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-21T04:05:29.152Z,1745208329.152 [keepstation:NeedComms] Stopped
2025-04-21T04:05:29.152Z,1745208329.152 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-21T04:05:29.152Z,1745208329.152 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-21T04:05:29.152Z,1745208329.152 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-21T04:05:29.568Z,1745208329.568 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-21T04:05:29.568Z,1745208329.568 [keepstation:SurfaceComms:B] Stopped
2025-04-21T04:05:29.568Z,1745208329.568 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-21T04:05:29.568Z,1745208329.568 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-21T04:05:29.568Z,1745208329.568 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-21T04:05:29.568Z,1745208329.568 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-21T04:05:29.946Z,1745208329.946 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-21T04:05:29.946Z,1745208329.946 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-21T04:05:29.946Z,1745208329.946 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-21T04:05:29.946Z,1745208329.946 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-21T04:05:29.946Z,1745208329.946 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-21T04:05:29.946Z,1745208329.946 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-21T04:05:30.345Z,1745208330.345 [keepstation:SurfaceComms:setStation] Stopped
2025-04-21T04:05:30.345Z,1745208330.345 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-21T04:05:30.345Z,1745208330.345 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-21T04:05:30.345Z,1745208330.345 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-21T04:05:30.346Z,1745208330.346 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-21T04:05:30.346Z,1745208330.346 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-21T04:05:30.737Z,1745208330.737 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-21T04:05:30.738Z,1745208330.738 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-21T04:05:30.738Z,1745208330.738 [keepstation:SurfaceComms:setSink] Stopped
2025-04-21T04:05:30.738Z,1745208330.738 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-21T04:05:30.738Z,1745208330.738 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-21T04:05:30.738Z,1745208330.738 [keepstation:SurfaceComms] Stopped
2025-04-21T04:05:30.738Z,1745208330.738 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-21T04:05:31.186Z,1745208331.186 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-04-21T04:05:31.186Z,1745208331.186 [keepstation:StartingMission] Stopped
2025-04-21T04:05:31.186Z,1745208331.186 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-04-21T04:05:31.186Z,1745208331.186 [keepstation:TransitToStation] Running Loop=1
2025-04-21T04:05:31.187Z,1745208331.187 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-04-21T04:05:31.187Z,1745208331.187 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-21T04:05:31.187Z,1745208331.187 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-04-21T04:05:31.187Z,1745208331.187 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-04-21T04:05:31.187Z,1745208331.187 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-04-21T04:05:31.187Z,1745208331.187 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-04-21T04:05:31.187Z,1745208331.187 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd] Running Loop=1
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-21T04:05:31.574Z,1745208331.574 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-21T04:05:31.575Z,1745208331.575 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-21T04:05:31.576Z,1745208331.576 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-21T04:05:31.576Z,1745208331.576 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-21T04:05:31.576Z,1745208331.576 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-21T04:05:31.576Z,1745208331.576 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-21T04:05:31.576Z,1745208331.576 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-21T04:05:31.576Z,1745208331.576 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-21T04:05:31.576Z,1745208331.576 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-21T04:05:31.601Z,1745208331.601 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-21T04:05:31.601Z,1745208331.601 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-21T04:05:31.601Z,1745208331.601 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-21T04:05:31.602Z,1745208331.602 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-21T04:05:31.603Z,1745208331.603 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-21T04:05:31.603Z,1745208331.603 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-21T04:05:31.603Z,1745208331.603 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-21T04:05:31.603Z,1745208331.603 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-21T04:05:31.603Z,1745208331.603 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-21T04:05:31.604Z,1745208331.604 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-21T04:05:31.604Z,1745208331.604 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-21T04:05:46.484Z,1745208346.484 [NAL9602](INFO): SBD MO Status=0, MOMSN=20217, MT Status=0, MTMSN=0
2025-04-21T04:05:46.484Z,1745208346.484 [NAL9602](INFO): No messages in MT queue
2025-04-21T04:05:56.981Z,1745208356.981 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T04:06:17.181Z,1745208377.181 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-21T04:07:29.945Z,1745208449.945 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:07:46.373Z,1745208466.373 [Radio_Surface](INFO): Powering down
2025-04-21T04:07:55.790Z,1745208475.790 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-21T04:07:55.790Z,1745208475.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-21T04:07:55.790Z,1745208475.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T04:07:55.790Z,1745208475.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-21T04:07:56.174Z,1745208476.174 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-21T04:07:56.174Z,1745208476.174 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T04:07:56.174Z,1745208476.174 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-21T04:07:56.174Z,1745208476.174 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T04:07:56.175Z,1745208476.175 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-21T04:07:56.175Z,1745208476.175 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T04:07:56.175Z,1745208476.175 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-21T04:07:56.582Z,1745208476.582 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-21T04:07:56.583Z,1745208476.583 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-21T04:07:56.583Z,1745208476.583 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-21T04:07:56.583Z,1745208476.583 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-21T04:07:56.583Z,1745208476.583 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-21T04:07:56.583Z,1745208476.583 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-21T04:07:56.584Z,1745208476.584 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-21T04:07:56.585Z,1745208476.585 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-21T04:07:56.585Z,1745208476.585 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-21T04:07:56.585Z,1745208476.585 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T04:07:56.585Z,1745208476.585 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-21T04:08:01.069Z,1745208481.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:08:01.426Z,1745208481.426 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-04-21T04:08:01.426Z,1745208481.426 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-04-21T04:08:01.426Z,1745208481.427 [keepstation:TransitToStation:C] Running Loop=1
2025-04-21T04:08:01.846Z,1745208481.846 [keepstation:TransitToStation:C] Stopped
2025-04-21T04:08:01.846Z,1745208481.846 [keepstation:TransitToStation:D] Running Loop=1
2025-04-21T04:08:01.847Z,1745208481.847 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-21T04:08:01.847Z,1745208481.847 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-21T04:08:01.847Z,1745208481.847 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-21T04:08:01.847Z,1745208481.847 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-21T04:08:01.847Z,1745208481.847 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-21T04:08:01.847Z,1745208481.847 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-21T04:08:01.847Z,1745208481.847 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-21T04:08:01.848Z,1745208481.848 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-21T04:08:01.848Z,1745208481.848 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-21T04:08:01.849Z,1745208481.849 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-21T04:08:01.849Z,1745208481.849 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-21T04:08:01.849Z,1745208481.849 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-21T04:08:01.850Z,1745208481.849 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-21T04:08:01.850Z,1745208481.850 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-21T04:08:01.850Z,1745208481.850 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-21T04:08:01.850Z,1745208481.850 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-21T04:08:01.850Z,1745208481.850 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-21T04:08:01.850Z,1745208481.850 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-21T04:08:01.850Z,1745208481.850 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-21T04:08:01.850Z,1745208481.850 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-21T04:08:02.073Z,1745208482.073 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-21T04:08:02.258Z,1745208482.258 [keepstation:TransitToStation:D] Stopped
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:TransitToStation] Stopped
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:TransitToStation:Dive] Stopped
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:TransitToStation:Dive:A] Stopped
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:OnStation] Running Loop=1
2025-04-21T04:08:02.259Z,1745208482.259 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-04-21T04:08:02.636Z,1745208482.636 [keepstation:NeedComms] Running Loop=1
2025-04-21T04:08:02.636Z,1745208482.636 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-21T04:08:02.636Z,1745208482.636 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-21T04:08:02.636Z,1745208482.636 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-21T04:08:02.637Z,1745208482.637 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-21T04:08:02.637Z,1745208482.637 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-21T04:08:02.637Z,1745208482.637 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-21T04:08:02.638Z,1745208482.638 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-21T04:08:02.638Z,1745208482.638 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-21T04:08:02.638Z,1745208482.638 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-21T04:08:02.639Z,1745208482.639 [keepstation:NeedComms:A] Running Loop=1
2025-04-21T04:08:02.640Z,1745208482.640 [keepstation:NeedComms:A](INFO): last time_fix was:  1745208327.000000 second since 1970/01/01T00:00:00Z 
2025-04-21T04:08:02.640Z,1745208482.640 [keepstation:NeedComms:A] Stopped
2025-04-21T04:08:46.682Z,1745208526.682 [keepstation:NeedComms:C] Running Loop=1
2025-04-21T04:08:47.377Z,1745208527.377 [Radio_Surface](INFO): Powering up
2025-04-21T04:08:53.277Z,1745208533.277 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-21T04:09:05.326Z,1745208545.326 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.307861
2025-04-21T04:09:39.184Z,1745208579.184 [NAL9602](INFO): SBD MO Status=0, MOMSN=20218, MT Status=0, MTMSN=0
2025-04-21T04:09:39.184Z,1745208579.184 [NAL9602](INFO): No messages in MT queue
2025-04-21T04:09:40.392Z,1745208580.392 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040938.00,A,3648.36997,N,12150.24225,W,0.700,111.68,210425,,,A*7D
2025-04-21T04:09:40.394Z,1745208580.394 [NAL9602](INFO): GPS fix at 20250421T040938: (36.806166, -121.837371)
2025-04-21T04:09:40.409Z,1745208580.409 [keepstation:NeedComms:C] Stopped
2025-04-21T04:09:40.409Z,1745208580.409 [keepstation:NeedComms:D] Running Loop=1
2025-04-21T04:09:53.529Z,1745208593.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:10:03.945Z,1745208603.945 [NAL9602](INFO): SBD MO Status=2, MOMSN=20219, MT Status=2, MTMSN=0
2025-04-21T04:10:03.945Z,1745208603.945 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T04:10:22.238Z,1745208622.238 [NAL9602](INFO): SBD MO Status=2, MOMSN=20219, MT Status=2, MTMSN=0
2025-04-21T04:10:22.238Z,1745208622.238 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T04:10:24.657Z,1745208624.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:10:48.198Z,1745208648.198 [NAL9602](INFO): SBD MO Status=2, MOMSN=20219, MT Status=2, MTMSN=0
2025-04-21T04:10:48.198Z,1745208648.198 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T04:10:55.785Z,1745208655.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:11:04.349Z,1745208664.349 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002983
2025-04-21T04:11:11.868Z,1745208671.868 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250421T040222/Courier0009.lzma
2025-04-21T04:11:12.870Z,1745208672.870 [DataOverHttps](INFO): Moved sent file to Logs/20250421T040222/Courier0009.lzma.bak
2025-04-21T04:11:12.871Z,1745208672.871 [DataOverHttps](INFO): SBD MOMSN=24696664
2025-04-21T04:11:13.346Z,1745208673.346 [NAL9602](INFO): SBD MO Status=2, MOMSN=20219, MT Status=2, MTMSN=0
2025-04-21T04:11:13.346Z,1745208673.346 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T04:11:29.214Z,1745208689.214 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250421T040222/Express0010.lzma
2025-04-21T04:11:30.215Z,1745208690.215 [DataOverHttps](INFO): Moved sent file to Logs/20250421T040222/Express0010.lzma.bak
2025-04-21T04:11:30.215Z,1745208690.215 [DataOverHttps](INFO): SBD MOMSN=24696667
2025-04-21T04:11:31.974Z,1745208691.974 [keepstation:NeedComms:D] Stopped
2025-04-21T04:11:31.975Z,1745208691.975 [keepstation:NeedComms:E] Running Loop=1
2025-04-21T04:11:34.017Z,1745208694.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,041132.00,A,3648.36474,N,12150.22541,W,0.369,114.00,210425,,,D*78
2025-04-21T04:11:34.019Z,1745208694.019 [NAL9602](INFO): GPS fix at 20250421T041132: (36.806079, -121.837090)
2025-04-21T04:11:34.090Z,1745208694.090 [keepstation:NeedComms:E] Stopped
2025-04-21T04:11:34.091Z,1745208694.091 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-21T04:11:34.091Z,1745208694.091 [keepstation:NeedComms] Stopped
2025-04-21T04:11:34.091Z,1745208694.091 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-21T04:11:34.091Z,1745208694.091 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-21T04:11:34.091Z,1745208694.091 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-21T04:11:34.410Z,1745208694.410 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-04-21T04:11:34.410Z,1745208694.410 [keepstation:OnStation] Stopped
2025-04-21T04:11:34.410Z,1745208694.410 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-04-21T04:11:34.410Z,1745208694.410 [keepstation:KeepStation] Running Loop=1
2025-04-21T04:11:34.410Z,1745208694.410 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-04-21T04:11:34.411Z,1745208694.411 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-21T04:11:34.411Z,1745208694.411 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-21T04:11:34.411Z,1745208694.411 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-21T04:11:34.411Z,1745208694.411 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-21T04:11:34.411Z,1745208694.411 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-04-21T04:11:34.411Z,1745208694.411 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-04-21T04:11:34.810Z,1745208694.810 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-21T04:12:06.674Z,1745208726.674 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-21T04:13:34.317Z,1745208814.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:13:54.446Z,1745208834.446 [Radio_Surface](INFO): Powering down
2025-04-21T04:14:05.453Z,1745208845.453 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T04:14:06.457Z,1745208846.457 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-21T04:15:10.725Z,1745208910.725 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.887207
2025-04-21T04:15:57.363Z,1745208957.363 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T04:25:57.713Z,1745209557.713 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T04:35:58.060Z,1745210158.060 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T04:45:58.402Z,1745210758.402 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T04:53:28.458Z,1745211208.458 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T04:53:28.459Z,1745211208.459 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-21T04:53:28.462Z,1745211208.462 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T04:55:58.747Z,1745211358.747 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T05:05:59.089Z,1745211959.089 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T05:15:59.435Z,1745212559.435 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T05:25:59.779Z,1745213159.779 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T05:36:00.135Z,1745213760.135 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T05:46:00.486Z,1745214360.486 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T05:56:00.825Z,1745214960.825 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T05:57:44.235Z,1745215064.235 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-04-21T06:01:05.852Z,1745215265.852 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T06:01:05.853Z,1745215265.853 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-21T06:01:05.855Z,1745215265.855 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T06:06:01.166Z,1745215561.166 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T06:11:34.078Z,1745215894.078 [keepstation:SurfaceComms] Running Loop=1
2025-04-21T06:11:34.079Z,1745215894.079 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-21T06:11:34.079Z,1745215894.079 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-21T06:11:34.079Z,1745215894.079 [keepstation:SurfaceComms:A] Stopped
2025-04-21T06:11:34.079Z,1745215894.079 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-21T06:11:34.079Z,1745215894.079 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-21T06:11:34.085Z,1745215894.085 [keepstation:NeedComms] Running Loop=1
2025-04-21T06:11:34.085Z,1745215894.085 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-21T06:11:34.086Z,1745215894.086 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-21T06:11:34.086Z,1745215894.086 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-21T06:11:34.086Z,1745215894.086 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-21T06:11:34.086Z,1745215894.086 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-21T06:11:34.087Z,1745215894.087 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-21T06:11:34.087Z,1745215894.087 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-21T06:11:34.087Z,1745215894.087 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-21T06:11:34.088Z,1745215894.088 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-21T06:11:34.088Z,1745215894.088 [keepstation:NeedComms:A] Running Loop=1
2025-04-21T06:11:34.094Z,1745215894.094 [keepstation:NeedComms:A](INFO): last time_fix was:  1745208692.000000 second since 1970/01/01T00:00:00Z 
2025-04-21T06:11:34.094Z,1745215894.094 [keepstation:NeedComms:A] Stopped
2025-04-21T06:11:34.472Z,1745215894.472 [keepstation:KeepStation:Dive] Stopped
2025-04-21T06:11:34.472Z,1745215894.472 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-21T06:11:34.472Z,1745215894.472 [keepstation:KeepStation:Dive:A] Stopped
2025-04-21T06:11:34.472Z,1745215894.472 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-21T06:11:34.473Z,1745215894.473 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-21T06:11:34.473Z,1745215894.473 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-21T06:11:34.473Z,1745215894.473 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-21T06:11:34.473Z,1745215894.473 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-21T06:11:34.473Z,1745215894.473 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-21T06:11:34.473Z,1745215894.473 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-21T06:12:39.926Z,1745215959.926 [keepstation:NeedComms:C] Running Loop=1
2025-04-21T06:12:40.617Z,1745215960.617 [Radio_Surface](INFO): Powering up
2025-04-21T06:12:46.065Z,1745215966.065 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-21T06:13:16.193Z,1745215996.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T06:13:16.675Z,1745215996.675 [NAL9602](INFO): SBD MO Status=1, MOMSN=20219, MT Status=0, MTMSN=0
2025-04-21T06:13:16.675Z,1745215996.675 [NAL9602](INFO): No messages in MT queue
2025-04-21T06:13:17.892Z,1745215997.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061315.00,A,3648.71326,N,12149.90154,W,0.933,107.76,210425,,,A*7C
2025-04-21T06:13:17.895Z,1745215997.895 [NAL9602](INFO): GPS fix at 20250421T061315: (36.811888, -121.831692)
2025-04-21T06:13:17.908Z,1745215997.908 [keepstation:NeedComms:C] Stopped
2025-04-21T06:13:17.908Z,1745215997.908 [keepstation:NeedComms:D] Running Loop=1
2025-04-21T06:13:32.938Z,1745216012.938 [NAL9602](INFO): SBD MO Status=1, MOMSN=20220, MT Status=0, MTMSN=0
2025-04-21T06:13:32.985Z,1745216012.985 [NAL9602](INFO): Sent 207 bytes from file Logs/20250421T040222/Courier0012.lzma
2025-04-21T06:13:32.985Z,1745216012.985 [NAL9602](INFO): Packets left to send: 0
2025-04-21T06:13:47.317Z,1745216027.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T06:13:48.242Z,1745216028.242 [NAL9602](INFO): SBD MO Status=1, MOMSN=20221, MT Status=0, MTMSN=0
2025-04-21T06:13:48.301Z,1745216028.301 [NAL9602](INFO): Sent 332 bytes from file Logs/20250421T040222/Express0013.lzma
2025-04-21T06:13:48.301Z,1745216028.301 [NAL9602](INFO): Packets left to send: 1
2025-04-21T06:14:08.810Z,1745216048.810 [NAL9602](INFO): SBD MO Status=1, MOMSN=20222, MT Status=0, MTMSN=0
2025-04-21T06:14:08.857Z,1745216048.857 [NAL9602](INFO): Sent 205 bytes from file Logs/20250421T040222/Express0013.lzma
2025-04-21T06:14:08.857Z,1745216048.857 [NAL9602](INFO): Packets left to send: 0
2025-04-21T06:14:17.038Z,1745216057.038 [NAL9602](INFO): SBD MO Status=0, MOMSN=20223, MT Status=0, MTMSN=0
2025-04-21T06:14:17.127Z,1745216057.127 [keepstation:NeedComms:D] Stopped
2025-04-21T06:14:17.127Z,1745216057.127 [keepstation:NeedComms:E] Running Loop=1
2025-04-21T06:14:18.441Z,1745216058.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T06:14:19.469Z,1745216059.469 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061417.00,A,3648.71437,N,12149.89663,W,0.194,96.11,210425,,,D*4D
2025-04-21T06:14:19.471Z,1745216059.471 [NAL9602](INFO): GPS fix at 20250421T061417: (36.811906, -121.831610)
2025-04-21T06:14:19.484Z,1745216059.484 [keepstation:NeedComms:E] Stopped
2025-04-21T06:14:19.485Z,1745216059.485 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-21T06:14:19.485Z,1745216059.485 [keepstation:NeedComms] Stopped
2025-04-21T06:14:19.486Z,1745216059.486 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-21T06:14:19.486Z,1745216059.486 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-21T06:14:19.486Z,1745216059.486 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-21T06:14:19.902Z,1745216059.902 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-21T06:14:19.902Z,1745216059.902 [keepstation:SurfaceComms:B] Stopped
2025-04-21T06:14:19.902Z,1745216059.902 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-21T06:14:19.902Z,1745216059.902 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-21T06:14:19.902Z,1745216059.902 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-21T06:14:19.902Z,1745216059.902 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-21T06:14:20.280Z,1745216060.280 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-21T06:14:20.281Z,1745216060.281 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-21T06:14:20.281Z,1745216060.281 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-21T06:14:20.281Z,1745216060.281 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-21T06:14:20.281Z,1745216060.281 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-21T06:14:20.281Z,1745216060.281 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-21T06:14:20.693Z,1745216060.693 [keepstation:SurfaceComms:setStation] Stopped
2025-04-21T06:14:20.693Z,1745216060.693 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-21T06:14:20.693Z,1745216060.693 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-21T06:14:20.693Z,1745216060.693 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-21T06:14:20.693Z,1745216060.693 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-21T06:14:20.694Z,1745216060.694 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-21T06:14:21.103Z,1745216061.103 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-21T06:14:21.103Z,1745216061.103 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-21T06:14:21.103Z,1745216061.103 [keepstation:SurfaceComms:setSink] Stopped
2025-04-21T06:14:21.103Z,1745216061.103 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-21T06:14:21.103Z,1745216061.103 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-21T06:14:21.103Z,1745216061.103 [keepstation:SurfaceComms] Stopped
2025-04-21T06:14:21.103Z,1745216061.103 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-21T06:14:21.495Z,1745216061.495 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-21T06:14:21.496Z,1745216061.496 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-21T06:14:21.496Z,1745216061.496 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-21T06:14:21.496Z,1745216061.496 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-21T06:14:21.496Z,1745216061.496 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-21T06:14:21.497Z,1745216061.497 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-21T06:14:21.498Z,1745216061.498 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-21T06:14:21.498Z,1745216061.498 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-21T06:14:21.498Z,1745216061.498 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-21T06:14:21.498Z,1745216061.498 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-21T06:14:49.569Z,1745216089.569 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T06:14:51.786Z,1745216091.786 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-21T06:14:58.301Z,1745216098.301 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003495
2025-04-21T06:15:51.514Z,1745216151.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T06:16:01.275Z,1745216161.275 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T06:16:22.637Z,1745216182.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T06:16:31.306Z,1745216191.306 [Radio_Surface](INFO): Powering down
2025-04-21T06:16:39.689Z,1745216199.689 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-21T06:16:39.689Z,1745216199.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-21T06:16:39.689Z,1745216199.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T06:16:39.689Z,1745216199.689 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-21T06:16:40.073Z,1745216200.073 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-21T06:16:40.073Z,1745216200.073 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T06:16:40.073Z,1745216200.073 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-21T06:16:40.074Z,1745216200.074 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-21T06:16:40.074Z,1745216200.074 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T06:16:40.074Z,1745216200.074 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-21T06:16:40.493Z,1745216200.493 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-21T06:16:40.493Z,1745216200.493 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T06:16:40.493Z,1745216200.493 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-21T06:16:40.493Z,1745216200.493 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-21T06:16:40.494Z,1745216200.494 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-21T06:16:40.886Z,1745216200.886 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-21T06:16:40.886Z,1745216200.886 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-21T06:16:40.887Z,1745216200.887 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-21T06:16:40.887Z,1745216200.887 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-21T06:16:40.887Z,1745216200.887 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-21T06:16:40.887Z,1745216200.887 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-21T06:16:40.887Z,1745216200.887 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-21T06:16:53.765Z,1745216213.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T06:16:54.769Z,1745216214.769 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-21T06:17:59.037Z,1745216279.037 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739319
2025-04-21T06:26:01.629Z,1745216761.629 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T06:36:01.970Z,1745217361.970 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T06:46:02.312Z,1745217962.312 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T06:56:02.656Z,1745218562.656 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T07:06:03.020Z,1745219163.020 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T07:08:44.206Z,1745219324.206 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T07:08:44.207Z,1745219324.207 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-21T07:08:44.209Z,1745219324.209 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T07:16:03.359Z,1745219763.359 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T07:26:03.703Z,1745220363.703 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T07:36:04.050Z,1745220964.050 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T07:46:04.389Z,1745221564.389 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T07:56:04.742Z,1745222164.742 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T08:06:05.083Z,1745222765.083 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T08:14:19.603Z,1745223259.603 [keepstation:SurfaceComms] Running Loop=1
2025-04-21T08:14:19.603Z,1745223259.603 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-21T08:14:19.603Z,1745223259.603 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-21T08:14:19.604Z,1745223259.604 [keepstation:SurfaceComms:A] Stopped
2025-04-21T08:14:19.604Z,1745223259.604 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-21T08:14:19.604Z,1745223259.604 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-21T08:14:19.606Z,1745223259.606 [keepstation:NeedComms] Running Loop=1
2025-04-21T08:14:19.606Z,1745223259.606 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-21T08:14:19.606Z,1745223259.606 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-21T08:14:19.606Z,1745223259.606 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-21T08:14:19.606Z,1745223259.606 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-21T08:14:19.607Z,1745223259.607 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-21T08:14:19.607Z,1745223259.607 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-21T08:14:19.608Z,1745223259.608 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-21T08:14:19.608Z,1745223259.608 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-21T08:14:19.608Z,1745223259.608 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-21T08:14:19.609Z,1745223259.609 [keepstation:NeedComms:A] Running Loop=1
2025-04-21T08:14:19.610Z,1745223259.610 [keepstation:NeedComms:A](INFO): last time_fix was:  1745216057.000000 second since 1970/01/01T00:00:00Z 
2025-04-21T08:14:19.610Z,1745223259.610 [keepstation:NeedComms:A] Stopped
2025-04-21T08:14:20.009Z,1745223260.009 [keepstation:KeepStation:Dive] Stopped
2025-04-21T08:14:20.010Z,1745223260.010 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-21T08:14:20.010Z,1745223260.010 [keepstation:KeepStation:Dive:A] Stopped
2025-04-21T08:14:20.010Z,1745223260.010 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-21T08:14:20.010Z,1745223260.010 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-21T08:14:20.010Z,1745223260.010 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-21T08:14:20.010Z,1745223260.010 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-21T08:14:20.010Z,1745223260.010 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-21T08:14:20.011Z,1745223260.011 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-21T08:14:20.011Z,1745223260.011 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-21T08:15:15.761Z,1745223315.761 [keepstation:NeedComms:C] Running Loop=1
2025-04-21T08:15:16.285Z,1745223316.285 [Radio_Surface](INFO): Powering up
2025-04-21T08:15:22.209Z,1745223322.209 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-21T08:15:35.543Z,1745223335.543 [NAL9602](INFO): SBD MO Status=2, MOMSN=20224, MT Status=2, MTMSN=0
2025-04-21T08:15:35.543Z,1745223335.543 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T08:15:52.337Z,1745223352.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T08:16:04.686Z,1745223364.686 [NAL9602](INFO): SBD MO Status=2, MOMSN=20224, MT Status=2, MTMSN=0
2025-04-21T08:16:04.686Z,1745223364.686 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T08:16:05.051Z,1745223365.051 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T08:16:17.958Z,1745223377.958 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T08:16:17.959Z,1745223377.959 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6.
2025-04-21T08:16:17.962Z,1745223377.962 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T08:16:23.461Z,1745223383.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T08:16:23.610Z,1745223383.610 [NAL9602](INFO): SBD MO Status=0, MOMSN=20224, MT Status=0, MTMSN=0
2025-04-21T08:16:23.610Z,1745223383.610 [NAL9602](INFO): No messages in MT queue
2025-04-21T08:16:24.826Z,1745223384.826 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081622.00,A,3649.25345,N,12149.45319,W,0.175,28.94,210425,,,D*4A
2025-04-21T08:16:24.828Z,1745223384.828 [NAL9602](INFO): GPS fix at 20250421T081622: (36.820891, -121.824220)
2025-04-21T08:16:24.842Z,1745223384.842 [keepstation:NeedComms:C] Stopped
2025-04-21T08:16:24.842Z,1745223384.842 [keepstation:NeedComms:D] Running Loop=1
2025-04-21T08:16:43.961Z,1745223403.961 [NAL9602](INFO): SBD MO Status=1, MOMSN=20225, MT Status=0, MTMSN=0
2025-04-21T08:16:44.013Z,1745223404.013 [NAL9602](INFO): Sent 88 bytes from file Logs/20250421T040222/Courier0015.lzma
2025-04-21T08:16:44.013Z,1745223404.013 [NAL9602](INFO): Packets left to send: 0
2025-04-21T08:16:54.597Z,1745223414.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T08:16:56.029Z,1745223416.029 [NAL9602](INFO): SBD MO Status=1, MOMSN=20226, MT Status=0, MTMSN=0
2025-04-21T08:16:56.089Z,1745223416.089 [NAL9602](INFO): Sent 332 bytes from file Logs/20250421T040222/Express0016.lzma
2025-04-21T08:16:56.090Z,1745223416.090 [NAL9602](INFO): Packets left to send: 1
2025-04-21T08:17:07.637Z,1745223427.637 [NAL9602](INFO): SBD MO Status=1, MOMSN=20227, MT Status=0, MTMSN=0
2025-04-21T08:17:07.685Z,1745223427.685 [NAL9602](INFO): Sent 106 bytes from file Logs/20250421T040222/Express0016.lzma
2025-04-21T08:17:07.685Z,1745223427.685 [NAL9602](INFO): Packets left to send: 0
2025-04-21T08:17:16.669Z,1745223436.669 [NAL9602](INFO): SBD MO Status=0, MOMSN=20228, MT Status=0, MTMSN=0
2025-04-21T08:17:16.750Z,1745223436.750 [keepstation:NeedComms:D] Stopped
2025-04-21T08:17:16.751Z,1745223436.751 [keepstation:NeedComms:E] Running Loop=1
2025-04-21T08:17:19.086Z,1745223439.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081717.00,A,3649.25551,N,12149.45319,W,0.583,322.01,210425,,,D*76
2025-04-21T08:17:19.089Z,1745223439.089 [NAL9602](INFO): GPS fix at 20250421T081717: (36.820925, -121.824220)
2025-04-21T08:17:19.102Z,1745223439.102 [keepstation:NeedComms:E] Stopped
2025-04-21T08:17:19.103Z,1745223439.103 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-21T08:17:19.103Z,1745223439.103 [keepstation:NeedComms] Stopped
2025-04-21T08:17:19.103Z,1745223439.103 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-21T08:17:19.103Z,1745223439.103 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-21T08:17:19.103Z,1745223439.103 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-21T08:17:19.528Z,1745223439.528 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-21T08:17:19.528Z,1745223439.528 [keepstation:SurfaceComms:B] Stopped
2025-04-21T08:17:19.528Z,1745223439.528 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-21T08:17:19.528Z,1745223439.528 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-21T08:17:19.545Z,1745223439.545 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-21T08:17:19.545Z,1745223439.545 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-21T08:17:19.910Z,1745223439.910 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-21T08:17:19.910Z,1745223439.910 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-21T08:17:19.910Z,1745223439.910 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-21T08:17:19.910Z,1745223439.910 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-21T08:17:19.910Z,1745223439.910 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-21T08:17:19.910Z,1745223439.910 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-21T08:17:20.325Z,1745223440.325 [keepstation:SurfaceComms:setStation] Stopped
2025-04-21T08:17:20.325Z,1745223440.325 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-21T08:17:20.325Z,1745223440.325 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-21T08:17:20.325Z,1745223440.325 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-21T08:17:20.325Z,1745223440.325 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-21T08:17:20.325Z,1745223440.325 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-21T08:17:20.714Z,1745223440.714 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-21T08:17:20.714Z,1745223440.714 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-21T08:17:20.714Z,1745223440.714 [keepstation:SurfaceComms:setSink] Stopped
2025-04-21T08:17:20.714Z,1745223440.714 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-21T08:17:20.714Z,1745223440.714 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-21T08:17:20.714Z,1745223440.714 [keepstation:SurfaceComms] Stopped
2025-04-21T08:17:20.714Z,1745223440.714 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-21T08:17:21.127Z,1745223441.127 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-21T08:17:21.127Z,1745223441.127 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-21T08:17:21.127Z,1745223441.127 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-21T08:17:21.127Z,1745223441.127 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-21T08:17:21.127Z,1745223441.127 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-21T08:17:21.128Z,1745223441.128 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-21T08:17:21.129Z,1745223441.129 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-21T08:17:21.129Z,1745223441.129 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-21T08:17:21.129Z,1745223441.129 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-21T08:17:21.129Z,1745223441.129 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-21T08:17:25.737Z,1745223445.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T08:17:34.841Z,1745223454.841 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003127
2025-04-21T08:17:51.812Z,1745223471.812 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-21T08:18:58.753Z,1745223538.753 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T08:19:25.217Z,1745223565.217 [Radio_Surface](INFO): Powering down
2025-04-21T08:19:27.576Z,1745223567.576 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-21T08:19:27.576Z,1745223567.576 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-21T08:19:27.576Z,1745223567.576 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T08:19:27.577Z,1745223567.577 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-21T08:19:27.972Z,1745223567.972 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-21T08:19:27.972Z,1745223567.972 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T08:19:27.972Z,1745223567.972 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-21T08:19:27.972Z,1745223567.972 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-21T08:19:27.988Z,1745223567.988 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T08:19:27.989Z,1745223567.989 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-21T08:19:28.380Z,1745223568.380 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-21T08:19:28.380Z,1745223568.380 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T08:19:28.380Z,1745223568.380 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-21T08:19:28.380Z,1745223568.380 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T08:19:28.381Z,1745223568.381 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T08:19:28.381Z,1745223568.381 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-21T08:19:28.381Z,1745223568.381 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T08:19:28.381Z,1745223568.381 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-21T08:19:28.381Z,1745223568.381 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-21T08:19:28.382Z,1745223568.382 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-21T08:19:28.382Z,1745223568.382 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-21T08:19:28.382Z,1745223568.382 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-21T08:19:28.785Z,1745223568.785 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-21T08:19:28.785Z,1745223568.785 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-21T08:19:28.785Z,1745223568.785 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-21T08:19:28.785Z,1745223568.785 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-21T08:19:28.785Z,1745223568.785 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-21T08:19:28.786Z,1745223568.786 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-21T08:19:28.786Z,1745223568.786 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-21T08:19:29.881Z,1745223569.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T08:19:30.886Z,1745223570.886 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-21T08:20:35.142Z,1745223635.142 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.303986
2025-04-21T08:26:05.100Z,1745223965.100 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T08:36:05.444Z,1745224565.444 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T08:46:05.792Z,1745225165.792 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T08:56:06.139Z,1745225766.139 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-21T09:23:51.840Z,1745227431.840 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T09:23:51.841Z,1745227431.841 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-04-21T09:23:51.844Z,1745227431.844 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T09:34:43.929Z,1745228083.929 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-21T09:34:48.362Z,1745228088.362 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007209
CHAN A1 (24V): 0.021556
CHAN A2 (12V): -0.005738
CHAN A3 (5V): -0.002421
CHAN B0 (3.3V): -0.001184
CHAN B1 (3.15aV): -0.001421
CHAN B2 (3.15bV): -0.001053
CHAN B3 (GND): 0.000074
OPEN: 0.006774
Full Scale: +/- 1 mA
2025-04-21T10:06:08.553Z,1745229968.553 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-04-21T10:17:19.208Z,1745230639.208 [keepstation:SurfaceComms] Running Loop=1
2025-04-21T10:17:19.208Z,1745230639.208 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-21T10:17:19.209Z,1745230639.209 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-21T10:17:19.209Z,1745230639.209 [keepstation:SurfaceComms:A] Stopped
2025-04-21T10:17:19.209Z,1745230639.209 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-21T10:17:19.209Z,1745230639.209 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-21T10:17:19.211Z,1745230639.211 [keepstation:NeedComms] Running Loop=1
2025-04-21T10:17:19.211Z,1745230639.211 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-21T10:17:19.211Z,1745230639.211 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-21T10:17:19.211Z,1745230639.211 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-21T10:17:19.212Z,1745230639.212 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-21T10:17:19.212Z,1745230639.212 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-21T10:17:19.213Z,1745230639.213 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-21T10:17:19.213Z,1745230639.213 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-21T10:17:19.213Z,1745230639.213 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-21T10:17:19.214Z,1745230639.214 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-21T10:17:19.214Z,1745230639.214 [keepstation:NeedComms:A] Running Loop=1
2025-04-21T10:17:19.215Z,1745230639.215 [keepstation:NeedComms:A](INFO): last time_fix was:  1745223437.000000 second since 1970/01/01T00:00:00Z 
2025-04-21T10:17:19.215Z,1745230639.215 [keepstation:NeedComms:A] Stopped
2025-04-21T10:17:19.612Z,1745230639.612 [keepstation:KeepStation:Dive] Stopped
2025-04-21T10:17:19.612Z,1745230639.612 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-21T10:17:19.613Z,1745230639.613 [keepstation:KeepStation:Dive:A] Stopped
2025-04-21T10:17:19.613Z,1745230639.613 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-21T10:17:19.613Z,1745230639.613 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-21T10:17:19.613Z,1745230639.613 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-21T10:17:19.613Z,1745230639.613 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-21T10:17:19.613Z,1745230639.613 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-21T10:17:19.613Z,1745230639.613 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-21T10:17:19.614Z,1745230639.614 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-21T10:19:42.631Z,1745230782.631 [keepstation:NeedComms:C] Running Loop=1
2025-04-21T10:19:43.165Z,1745230783.165 [Radio_Surface](INFO): Powering up
2025-04-21T10:19:48.693Z,1745230788.693 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-21T10:20:04.439Z,1745230804.439 [NAL9602](INFO): SBD MO Status=0, MOMSN=20229, MT Status=0, MTMSN=0
2025-04-21T10:20:04.439Z,1745230804.439 [NAL9602](INFO): No messages in MT queue
2025-04-21T10:20:18.813Z,1745230818.813 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T10:20:23.422Z,1745230823.422 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102021.00,A,3649.71287,N,12149.53260,W,0.855,358.88,210425,,,A*74
2025-04-21T10:20:23.424Z,1745230823.424 [NAL9602](INFO): GPS fix at 20250421T102021: (36.828548, -121.825543)
2025-04-21T10:20:23.454Z,1745230823.454 [keepstation:NeedComms:C] Stopped
2025-04-21T10:20:23.455Z,1745230823.455 [keepstation:NeedComms:D] Running Loop=1
2025-04-21T10:20:38.478Z,1745230838.478 [NAL9602](INFO): SBD MO Status=1, MOMSN=20230, MT Status=0, MTMSN=0
2025-04-21T10:20:38.525Z,1745230838.525 [NAL9602](INFO): Sent 90 bytes from file Logs/20250421T040222/Courier0018.lzma
2025-04-21T10:20:38.525Z,1745230838.525 [NAL9602](INFO): Packets left to send: 0
2025-04-21T10:20:49.634Z,1745230849.634 [NAL9602](INFO): SBD MO Status=1, MOMSN=20231, MT Status=0, MTMSN=0
2025-04-21T10:20:49.686Z,1745230849.686 [NAL9602](INFO): Sent 332 bytes from file Logs/20250421T040222/Express0019.lzma
2025-04-21T10:20:49.686Z,1745230849.686 [NAL9602](INFO): Packets left to send: 2
2025-04-21T10:20:49.937Z,1745230849.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T10:20:58.026Z,1745230858.026 [NAL9602](INFO): SBD MO Status=1, MOMSN=20232, MT Status=0, MTMSN=0
2025-04-21T10:20:58.073Z,1745230858.073 [NAL9602](INFO): Sent 332 bytes from file Logs/20250421T040222/Express0019.lzma
2025-04-21T10:20:58.073Z,1745230858.073 [NAL9602](INFO): Packets left to send: 1
2025-04-21T10:21:06.033Z,1745230866.033 [NAL9602](INFO): SBD MO Status=1, MOMSN=20233, MT Status=0, MTMSN=0
2025-04-21T10:21:06.081Z,1745230866.081 [NAL9602](INFO): Sent 169 bytes from file Logs/20250421T040222/Express0019.lzma
2025-04-21T10:21:06.081Z,1745230866.081 [NAL9602](INFO): Packets left to send: 0
2025-04-21T10:21:14.280Z,1745230874.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=20234, MT Status=0, MTMSN=0
2025-04-21T10:21:14.368Z,1745230874.368 [keepstation:NeedComms:D] Stopped
2025-04-21T10:21:14.383Z,1745230874.383 [keepstation:NeedComms:E] Running Loop=1
2025-04-21T10:21:16.702Z,1745230876.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102114.00,A,3649.72129,N,12149.53175,W,0.272,354.23,210425,,,D*77
2025-04-21T10:21:16.704Z,1745230876.704 [NAL9602](INFO): GPS fix at 20250421T102114: (36.828688, -121.825529)
2025-04-21T10:21:16.741Z,1745230876.741 [keepstation:NeedComms:E] Stopped
2025-04-21T10:21:16.742Z,1745230876.742 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-21T10:21:16.743Z,1745230876.743 [keepstation:NeedComms] Stopped
2025-04-21T10:21:16.743Z,1745230876.743 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-21T10:21:16.743Z,1745230876.743 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-21T10:21:16.743Z,1745230876.743 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-21T10:21:17.148Z,1745230877.148 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-21T10:21:17.149Z,1745230877.149 [keepstation:SurfaceComms:B] Stopped
2025-04-21T10:21:17.149Z,1745230877.149 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-21T10:21:17.149Z,1745230877.149 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-21T10:21:17.149Z,1745230877.149 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-21T10:21:17.149Z,1745230877.149 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-21T10:21:17.562Z,1745230877.562 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-21T10:21:17.562Z,1745230877.562 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-21T10:21:17.562Z,1745230877.562 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-21T10:21:17.562Z,1745230877.562 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-21T10:21:17.562Z,1745230877.562 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-21T10:21:17.562Z,1745230877.562 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-21T10:21:17.934Z,1745230877.934 [keepstation:SurfaceComms:setStation] Stopped
2025-04-21T10:21:17.934Z,1745230877.934 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-21T10:21:17.934Z,1745230877.934 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-21T10:21:17.934Z,1745230877.934 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-21T10:21:17.934Z,1745230877.934 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-21T10:21:17.934Z,1745230877.934 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-21T10:21:18.339Z,1745230878.339 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-21T10:21:18.339Z,1745230878.339 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-21T10:21:18.339Z,1745230878.339 [keepstation:SurfaceComms:setSink] Stopped
2025-04-21T10:21:18.340Z,1745230878.340 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-21T10:21:18.340Z,1745230878.340 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-21T10:21:18.340Z,1745230878.340 [keepstation:SurfaceComms] Stopped
2025-04-21T10:21:18.340Z,1745230878.340 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-21T10:21:18.731Z,1745230878.731 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-21T10:21:18.732Z,1745230878.732 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-21T10:21:18.732Z,1745230878.732 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-21T10:21:18.732Z,1745230878.732 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-21T10:21:18.733Z,1745230878.733 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-21T10:21:18.734Z,1745230878.734 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-21T10:21:18.734Z,1745230878.734 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-21T10:21:18.734Z,1745230878.734 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-21T10:21:18.734Z,1745230878.734 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-21T10:21:21.069Z,1745230881.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T10:21:48.613Z,1745230908.613 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-21T10:21:52.209Z,1745230912.209 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T10:22:55.017Z,1745230975.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T10:23:22.557Z,1745231002.557 [Radio_Surface](INFO): Powering down
2025-04-21T10:23:26.141Z,1745231006.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T10:23:27.145Z,1745231007.145 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-21T10:23:28.015Z,1745231008.015 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-21T10:23:28.015Z,1745231008.015 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-21T10:23:28.015Z,1745231008.015 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T10:23:28.015Z,1745231008.015 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-21T10:23:28.430Z,1745231008.430 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-21T10:23:28.430Z,1745231008.430 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T10:23:28.430Z,1745231008.430 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-21T10:23:28.430Z,1745231008.430 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-21T10:23:28.430Z,1745231008.430 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T10:23:28.430Z,1745231008.430 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-21T10:23:28.819Z,1745231008.819 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-21T10:23:28.820Z,1745231008.820 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T10:23:28.820Z,1745231008.820 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-21T10:23:28.820Z,1745231008.820 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-21T10:23:28.821Z,1745231008.821 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-21T10:23:29.224Z,1745231009.224 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-21T10:23:29.225Z,1745231009.225 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-21T10:23:29.225Z,1745231009.225 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-21T10:23:29.225Z,1745231009.225 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-21T10:23:29.225Z,1745231009.225 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-21T10:23:29.225Z,1745231009.225 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-21T10:23:29.225Z,1745231009.225 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-21T10:31:29.170Z,1745231489.170 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T10:31:29.171Z,1745231489.171 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-21T10:31:29.174Z,1745231489.174 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T11:36:56.885Z,1745235416.885 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-04-21T11:39:06.172Z,1745235546.172 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T11:39:06.173Z,1745235546.173 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-21T11:39:06.176Z,1745235546.176 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T12:21:16.895Z,1745238076.895 [keepstation:SurfaceComms] Running Loop=1
2025-04-21T12:21:16.895Z,1745238076.895 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-21T12:21:16.895Z,1745238076.895 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-21T12:21:16.896Z,1745238076.896 [keepstation:SurfaceComms:A] Stopped
2025-04-21T12:21:16.896Z,1745238076.896 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-21T12:21:16.896Z,1745238076.896 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-21T12:21:16.898Z,1745238076.898 [keepstation:NeedComms] Running Loop=1
2025-04-21T12:21:16.898Z,1745238076.898 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-21T12:21:16.898Z,1745238076.898 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-21T12:21:16.898Z,1745238076.898 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-21T12:21:16.898Z,1745238076.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-21T12:21:16.899Z,1745238076.899 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-21T12:21:16.899Z,1745238076.899 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-21T12:21:16.900Z,1745238076.900 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-21T12:21:16.900Z,1745238076.900 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-21T12:21:16.900Z,1745238076.900 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-21T12:21:16.901Z,1745238076.901 [keepstation:NeedComms:A] Running Loop=1
2025-04-21T12:21:16.902Z,1745238076.902 [keepstation:NeedComms:A](INFO): last time_fix was:  1745230874.000000 second since 1970/01/01T00:00:00Z 
2025-04-21T12:21:16.902Z,1745238076.902 [keepstation:NeedComms:A] Stopped
2025-04-21T12:21:17.269Z,1745238077.269 [keepstation:KeepStation:Dive] Stopped
2025-04-21T12:21:17.269Z,1745238077.269 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-21T12:21:17.269Z,1745238077.269 [keepstation:KeepStation:Dive:A] Stopped
2025-04-21T12:21:17.269Z,1745238077.269 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-21T12:21:17.270Z,1745238077.270 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-21T12:21:17.270Z,1745238077.270 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-21T12:21:17.270Z,1745238077.270 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-21T12:21:17.270Z,1745238077.270 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-21T12:21:17.270Z,1745238077.270 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-21T12:21:17.270Z,1745238077.270 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-21T12:21:33.135Z,1745238093.135 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2025-04-21T12:21:33.136Z,1745238093.136 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2025-04-21T12:21:33.136Z,1745238093.136 [BuoyancyServo] Communications Fault, FailCount= 1
2025-04-21T12:21:33.136Z,1745238093.136 [BuoyancyServo](ERROR): Communications Fault
2025-04-21T12:21:33.204Z,1745238093.204 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2025-04-21T12:21:35.050Z,1745238095.050 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-21T12:21:35.050Z,1745238095.050 [BuoyancyServo](INFO): Powering down
2025-04-21T12:21:35.942Z,1745238095.942 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2025-04-21T12:21:35.942Z,1745238095.942 [BuoyancyServo] No Fault, FailCount= 1
2025-04-21T12:21:36.277Z,1745238096.277 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-21T12:21:36.278Z,1745238096.278 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-21T12:22:25.146Z,1745238145.146 [keepstation:NeedComms:C] Running Loop=1
2025-04-21T12:22:25.713Z,1745238145.713 [Radio_Surface](INFO): Powering up
2025-04-21T12:22:31.553Z,1745238151.553 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-21T12:22:38.055Z,1745238158.055 [NAL9602](INFO): SBD MO Status=0, MOMSN=20235, MT Status=0, MTMSN=0
2025-04-21T12:22:38.056Z,1745238158.056 [NAL9602](INFO): No messages in MT queue
2025-04-21T12:22:39.270Z,1745238159.270 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122237.00,A,3650.11697,N,12149.57000,W,0.972,0.00,210425,,,A*72
2025-04-21T12:22:39.272Z,1745238159.272 [NAL9602](INFO): GPS fix at 20250421T122237: (36.835283, -121.826167)
2025-04-21T12:22:39.285Z,1745238159.285 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2025-04-21T12:22:39.288Z,1745238159.288 [keepstation:NeedComms:C] Stopped
2025-04-21T12:22:39.288Z,1745238159.288 [keepstation:NeedComms:D] Running Loop=1
2025-04-21T12:23:01.606Z,1745238181.606 [NAL9602](INFO): SBD MO Status=2, MOMSN=20236, MT Status=2, MTMSN=0
2025-04-21T12:23:01.606Z,1745238181.606 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T12:23:01.673Z,1745238181.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T12:23:13.165Z,1745238193.165 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2025-04-21T12:23:13.165Z,1745238193.165 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2025-04-21T12:23:13.165Z,1745238193.165 [BuoyancyServo] Communications Fault, FailCount= 2
2025-04-21T12:23:13.165Z,1745238193.165 [BuoyancyServo](ERROR): Communications Fault
2025-04-21T12:23:13.193Z,1745238193.193 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2025-04-21T12:23:15.099Z,1745238195.099 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-21T12:23:15.099Z,1745238195.099 [BuoyancyServo](INFO): Powering down
2025-04-21T12:23:15.928Z,1745238195.928 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2025-04-21T12:23:15.928Z,1745238195.928 [BuoyancyServo] No Fault, FailCount= 2
2025-04-21T12:23:16.326Z,1745238196.326 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-21T12:23:16.333Z,1745238196.333 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-21T12:23:21.545Z,1745238201.545 [NAL9602](INFO): SBD MO Status=1, MOMSN=20236, MT Status=0, MTMSN=0
2025-04-21T12:23:21.601Z,1745238201.601 [NAL9602](INFO): Sent 90 bytes from file Logs/20250421T040222/Courier0021.lzma
2025-04-21T12:23:21.601Z,1745238201.601 [NAL9602](INFO): Packets left to send: 0
2025-04-21T12:23:32.798Z,1745238212.798 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T12:23:34.418Z,1745238214.418 [NAL9602](INFO): SBD MO Status=1, MOMSN=20237, MT Status=0, MTMSN=0
2025-04-21T12:23:34.477Z,1745238214.477 [NAL9602](INFO): Sent 332 bytes from file Logs/20250421T040222/Express0022.lzma
2025-04-21T12:23:34.477Z,1745238214.477 [NAL9602](INFO): Packets left to send: 1
2025-04-21T12:23:46.587Z,1745238226.587 [NAL9602](INFO): SBD MO Status=1, MOMSN=20238, MT Status=0, MTMSN=0
2025-04-21T12:23:46.637Z,1745238226.637 [NAL9602](INFO): Sent 226 bytes from file Logs/20250421T040222/Express0022.lzma
2025-04-21T12:23:46.637Z,1745238226.637 [NAL9602](INFO): Packets left to send: 0
2025-04-21T12:23:55.250Z,1745238235.250 [NAL9602](INFO): SBD MO Status=0, MOMSN=20239, MT Status=0, MTMSN=0
2025-04-21T12:23:55.353Z,1745238235.353 [keepstation:NeedComms:D] Stopped
2025-04-21T12:23:55.353Z,1745238235.353 [keepstation:NeedComms:E] Running Loop=1
2025-04-21T12:23:57.684Z,1745238237.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122355.00,A,3650.13049,N,12149.57090,W,0.622,325.90,210425,,,A*7E
2025-04-21T12:23:57.687Z,1745238237.687 [NAL9602](INFO): GPS fix at 20250421T122355: (36.835508, -121.826182)
2025-04-21T12:23:57.707Z,1745238237.707 [keepstation:NeedComms:E] Stopped
2025-04-21T12:23:57.708Z,1745238237.708 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-21T12:23:57.708Z,1745238237.708 [keepstation:NeedComms] Stopped
2025-04-21T12:23:57.708Z,1745238237.708 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-21T12:23:57.708Z,1745238237.708 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-21T12:23:57.709Z,1745238237.709 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-21T12:23:58.117Z,1745238238.117 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-21T12:23:58.118Z,1745238238.118 [keepstation:SurfaceComms:B] Stopped
2025-04-21T12:23:58.118Z,1745238238.118 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-21T12:23:58.118Z,1745238238.118 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-21T12:23:58.118Z,1745238238.118 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-21T12:23:58.118Z,1745238238.118 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-21T12:23:58.496Z,1745238238.496 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-21T12:23:58.496Z,1745238238.496 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-21T12:23:58.496Z,1745238238.496 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-21T12:23:58.496Z,1745238238.496 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-21T12:23:58.496Z,1745238238.496 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-21T12:23:58.496Z,1745238238.496 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-21T12:23:58.902Z,1745238238.902 [keepstation:SurfaceComms:setStation] Stopped
2025-04-21T12:23:58.902Z,1745238238.902 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-21T12:23:58.902Z,1745238238.902 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-21T12:23:58.902Z,1745238238.902 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-21T12:23:58.902Z,1745238238.902 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-21T12:23:58.902Z,1745238238.902 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-21T12:23:59.319Z,1745238239.319 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-21T12:23:59.320Z,1745238239.320 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-21T12:23:59.320Z,1745238239.320 [keepstation:SurfaceComms:setSink] Stopped
2025-04-21T12:23:59.320Z,1745238239.320 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-21T12:23:59.320Z,1745238239.320 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-21T12:23:59.320Z,1745238239.320 [keepstation:SurfaceComms] Stopped
2025-04-21T12:23:59.320Z,1745238239.320 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-21T12:23:59.708Z,1745238239.708 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-21T12:23:59.708Z,1745238239.708 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-21T12:23:59.708Z,1745238239.708 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-21T12:23:59.708Z,1745238239.708 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-21T12:23:59.725Z,1745238239.725 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-21T12:23:59.725Z,1745238239.725 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-21T12:23:59.725Z,1745238239.725 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-21T12:23:59.725Z,1745238239.725 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T12:23:59.725Z,1745238239.725 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-21T12:23:59.725Z,1745238239.725 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-21T12:23:59.726Z,1745238239.726 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-21T12:23:59.726Z,1745238239.726 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-21T12:23:59.726Z,1745238239.726 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-21T12:23:59.726Z,1745238239.726 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-21T12:23:59.726Z,1745238239.726 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-21T12:23:59.727Z,1745238239.727 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-21T12:23:59.727Z,1745238239.727 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-21T12:24:03.921Z,1745238243.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T12:24:29.998Z,1745238269.998 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-21T12:24:35.053Z,1745238275.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T12:25:06.193Z,1745238306.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T12:25:37.317Z,1745238337.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T12:26:00.657Z,1745238360.657 [Radio_Surface](INFO): Powering down
2025-04-21T12:26:08.461Z,1745238368.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T12:26:09.465Z,1745238369.465 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-21T12:26:12.227Z,1745238372.227 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-21T12:26:12.227Z,1745238372.227 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-21T12:26:12.227Z,1745238372.227 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T12:26:12.227Z,1745238372.227 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-21T12:26:12.620Z,1745238372.620 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-21T12:26:12.620Z,1745238372.620 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-21T12:26:12.621Z,1745238372.621 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-21T12:26:12.621Z,1745238372.621 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-21T12:26:12.621Z,1745238372.621 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T12:26:12.621Z,1745238372.621 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-21T12:26:13.031Z,1745238373.031 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-21T12:26:13.031Z,1745238373.031 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T12:26:13.031Z,1745238373.031 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-21T12:26:13.031Z,1745238373.031 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-21T12:26:13.032Z,1745238373.032 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T12:26:13.032Z,1745238373.032 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-21T12:26:13.032Z,1745238373.032 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-21T12:26:13.032Z,1745238373.032 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-21T12:26:13.032Z,1745238373.032 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-21T12:26:13.032Z,1745238373.032 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-21T12:26:13.032Z,1745238373.032 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-21T12:26:13.033Z,1745238373.033 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-21T12:26:13.436Z,1745238373.436 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-21T12:26:13.436Z,1745238373.436 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-21T12:26:13.436Z,1745238373.436 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-21T12:26:13.436Z,1745238373.436 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-21T12:26:13.436Z,1745238373.436 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-21T12:26:13.436Z,1745238373.436 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-21T12:26:13.436Z,1745238373.436 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-21T12:42:56.993Z,1745239376.993 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-04-21T12:46:44.045Z,1745239604.045 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T12:46:44.046Z,1745239604.046 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-21T12:46:44.048Z,1745239604.048 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T13:54:22.230Z,1745243662.230 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-21T13:54:22.231Z,1745243662.231 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-21T13:54:22.234Z,1745243662.234 [BPC1](INFO): Received data from all battery sticks.
2025-04-21T14:23:57.884Z,1745245437.884 [keepstation:SurfaceComms] Running Loop=1
2025-04-21T14:23:57.884Z,1745245437.884 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-21T14:23:57.884Z,1745245437.884 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-21T14:23:57.885Z,1745245437.885 [keepstation:SurfaceComms:A] Stopped
2025-04-21T14:23:57.885Z,1745245437.885 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-21T14:23:57.885Z,1745245437.885 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-21T14:23:57.886Z,1745245437.886 [keepstation:NeedComms] Running Loop=1
2025-04-21T14:23:57.887Z,1745245437.887 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-21T14:23:57.887Z,1745245437.887 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-21T14:23:57.887Z,1745245437.887 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-21T14:23:57.887Z,1745245437.887 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-21T14:23:57.887Z,1745245437.887 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-21T14:23:57.888Z,1745245437.888 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-21T14:23:57.888Z,1745245437.888 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-21T14:23:57.889Z,1745245437.889 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-21T14:23:57.889Z,1745245437.889 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-21T14:23:57.889Z,1745245437.889 [keepstation:NeedComms:A] Running Loop=1
2025-04-21T14:23:57.891Z,1745245437.891 [keepstation:NeedComms:A](INFO): last time_fix was:  1745238235.000000 second since 1970/01/01T00:00:00Z 
2025-04-21T14:23:57.891Z,1745245437.891 [keepstation:NeedComms:A] Stopped
2025-04-21T14:23:58.230Z,1745245438.230 [keepstation:KeepStation:Dive] Stopped
2025-04-21T14:23:58.230Z,1745245438.230 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-21T14:23:58.230Z,1745245438.230 [keepstation:KeepStation:Dive:A] Stopped
2025-04-21T14:23:58.230Z,1745245438.230 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-21T14:23:58.231Z,1745245438.231 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-21T14:23:58.231Z,1745245438.231 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-21T14:23:58.231Z,1745245438.231 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-21T14:23:58.231Z,1745245438.231 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-21T14:23:58.231Z,1745245438.231 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-21T14:23:58.231Z,1745245438.231 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-21T14:25:16.209Z,1745245516.209 [keepstation:NeedComms:C] Running Loop=1
2025-04-21T14:25:16.765Z,1745245516.765 [Radio_Surface](INFO): Powering up
2025-04-21T14:25:22.965Z,1745245522.965 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-21T14:25:47.707Z,1745245547.707 [NAL9602](INFO): SBD MO Status=2, MOMSN=20240, MT Status=2, MTMSN=0
2025-04-21T14:25:47.707Z,1745245547.707 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-21T14:25:53.085Z,1745245553.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T14:26:24.213Z,1745245584.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T14:26:55.337Z,1745245615.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T14:27:26.469Z,1745245646.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-21T14:27:35.887Z,1745245655.887 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004611
2025-04-21T14:27:42.442Z,1745245662.442 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=20240, MT Status=1, MTMSN=4303
2025-04-21T14:27:42.442Z,1745245662.442 [NAL9602](INFO): Data available in MT queue
2025-04-21T14:27:42.937Z,1745245662.937 [NAL9602](INFO): Received command: restart logs