2025-06-11T04:00:10.293Z,1749614410.293 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-06-11T04:00:10.294Z,1749614410.294 [MissionManager](INFO): Uninitializing Mission sci2_vtyoyo
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo] Stopped
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo](DEBUG): Aggregate::uninitialize sci2_vtyoyo
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science] Stopped
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science:B] Stopped
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science:C] Stopped
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science:E] Stopped
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science:OceanCurrent] Stopped
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science:OceanCurrent](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Science:OceanCurrent
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:Science:OceanCurrent:A.] Stopped
2025-06-11T04:00:10.302Z,1749614410.302 [sci2_vtyoyo:StandardEnvelopes] Stopped
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:StandardEnvelopes](DEBUG): Aggregate::uninitialize sci2_vtyoyo:StandardEnvelopes
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:BackseatDriver] Stopped
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:BackseatDriver](DEBUG): Aggregate::uninitialize sci2_vtyoyo:BackseatDriver
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:BackseatDriver:A.BackseatDriver] Stopped
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:PowerOnly] Stopped
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:PowerOnly](DEBUG): Aggregate::uninitialize sci2_vtyoyo:PowerOnly
2025-06-11T04:00:10.303Z,1749614410.303 [sci2_vtyoyo:PowerOnly:E.Wait] Stopped
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap] Stopped
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive] Stopped
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive:A] Stopped
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive:A](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink] Stopped
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive:A:SurfaceSink](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:SurfaceSink
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive:A:YoYoDive] Stopped
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive:A:YoYoDive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive
2025-06-11T04:00:10.304Z,1749614410.304 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive] Stopped
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:A.Pitch] Stopped
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy] Stopped
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed] Stopped
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope] Stopped
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize.
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope] Stopped
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-06-11T04:00:10.305Z,1749614410.305 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor] Stopped
2025-06-11T04:00:10.306Z,1749614410.306 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:F.YoYoPARLicor](DEBUG): Uninitialize Value-Terminated YoYo.
2025-06-11T04:00:10.307Z,1749614410.307 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo] Stopped
2025-06-11T04:00:10.307Z,1749614410.307 [sci2_vtyoyo:Lap:Dive:A:YoYoDive:dive:G.YoYo](DEBUG): Uninitialize YoYoComponent.
2025-06-11T04:00:10.307Z,1749614410.307 [sci2_vtyoyo:Lap:Wpt2] Stopped
2025-06-11T04:00:10.307Z,1749614410.307 [sci2_vtyoyo:Lap:Wpt2](DEBUG): Aggregate::uninitialize sci2_vtyoyo:Lap:Wpt2
2025-06-11T04:00:10.307Z,1749614410.307 [sci2_vtyoyo:Lap:Wpt2:A.Waypoint] Stopped
2025-06-11T04:00:10.307Z,1749614410.307 [sci2_vtyoyo:Lap:Wpt2:A.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-06-11T04:00:10.559Z,1749614410.559 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-06-11T04:00:10.560Z,1749614410.560 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-06-11T04:00:10.577Z,1749614410.577 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-06-11T04:00:10.781Z,1749614410.781 [WetLabsBB2FL](INFO): Powering down
2025-06-11T04:00:13.013Z,1749614413.013 [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" PriorityHere="false" />
        <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" PriorityHere="false" />
    <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" PriorityHere="false" />
        </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" PriorityHere="false" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" PriorityHere="false" />
        </Aggregate>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-06-11T04:00:13.031Z,1749614413.031 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-06-11T04:00:13.033Z,1749614413.033 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-06-11T04:00:13.035Z,1749614413.035 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-06-11T04:00:13.041Z,1749614413.041 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-06-11T04:00:13.044Z,1749614413.044 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-06-11T04:00:13.050Z,1749614413.050 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-06-11T04:00:13.052Z,1749614413.052 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-06-11T04:00:13.059Z,1749614413.059 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-06-11T04:00:13.065Z,1749614413.065 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-06-11T04:00:13.067Z,1749614413.067 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-06-11T04:00:13.073Z,1749614413.073 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-06-11T04:00:13.076Z,1749614413.076 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-06-11T04:00:13.078Z,1749614413.078 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-06-11T04:00:13.080Z,1749614413.080 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-06-11T04:00:13.092Z,1749614413.092 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-06-11T04:00:13.100Z,1749614413.100 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-06-11T04:00:13.114Z,1749614413.114 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-06-11T04:00:13.120Z,1749614413.120 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-06-11T04:00:13.124Z,1749614413.124 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-06-11T04:00:13.131Z,1749614413.131 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-06-11T04:00:13.132Z,1749614413.132 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-06-11T04:00:13.132Z,1749614413.132 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-06-11T04:00:13.139Z,1749614413.139 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-06-11T04:00:13.675Z,1749614413.675 [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-06-11T04:00:13.688Z,1749614413.688 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-06-11T04:00:13.699Z,1749614413.699 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-06-11T04:00:13.710Z,1749614413.710 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-06-11T04:00:13.717Z,1749614413.717 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-06-11T04:00:13.731Z,1749614413.731 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-06-11T04:00:13.741Z,1749614413.741 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-06-11T04:00:13.743Z,1749614413.743 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-06-11T04:00:13.752Z,1749614413.752 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-06-11T04:00:13.777Z,1749614413.777 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-11T04:00:13.812Z,1749614413.812 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-06-11T04:00:13.812Z,1749614413.812 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-06-11T04:00:13.814Z,1749614413.814 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-06-11T04:00:13.882Z,1749614413.882 [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>1.5</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-06-11T04:00:13.886Z,1749614413.886 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-06-11T04:00:13.888Z,1749614413.888 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-06-11T04:00:13.892Z,1749614413.892 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-06-11T04:00:13.895Z,1749614413.895 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-06-11T04:00:13.896Z,1749614413.896 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-06-11T04:00:13.902Z,1749614413.902 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-06-11T04:00:13.908Z,1749614413.908 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-06-11T04:00:13.943Z,1749614413.943 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-06-11T04:00:13.943Z,1749614413.943 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-06-11T04:00:13.944Z,1749614413.944 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-06-11T04:00:13.963Z,1749614413.963 [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-06-11T04:00:13.976Z,1749614413.976 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-06-11T04:00:13.978Z,1749614413.978 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-06-11T04:00:13.981Z,1749614413.981 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-06-11T04:00:13.981Z,1749614413.981 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-06-11T04:00:13.982Z,1749614413.982 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-06-11T04:00:14.175Z,1749614414.175 [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-06-11T04:00:14.179Z,1749614414.179 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-06-11T04:00:14.180Z,1749614414.180 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-06-11T04:00:14.183Z,1749614414.183 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-06-11T04:00:14.184Z,1749614414.184 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-06-11T04:00:14.187Z,1749614414.187 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-06-11T04:00:14.194Z,1749614414.194 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-06-11T04:00:14.195Z,1749614414.195 [keepstation:H.Pitch](DEBUG): Construct.
2025-06-11T04:00:14.216Z,1749614414.216 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-06-11T04:00:14.218Z,1749614414.218 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-06-11T04:00:14.222Z,1749614414.222 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-06-11T04:00:14.270Z,1749614414.270 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-06-11T04:00:14.273Z,1749614414.273 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-06-11T04:00:14.281Z,1749614414.281 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-06-11T04:00:14.286Z,1749614414.286 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-06-11T04:00:14.290Z,1749614414.290 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-06-11T04:00:14.299Z,1749614414.299 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-06-11T04:00:14.310Z,1749614414.310 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-06-11T04:00:14.328Z,1749614414.328 [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 priorityHere=false 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" priorityHere=false 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 priorityHere=false 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" priorityHere=false refId="NeedComms"

  aggregate KeepStation {
    run in sequence

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

      call priorityHere=false 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-06-11T04:00:14.328Z,1749614414.328 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-06-11T04:00:14.476Z,1749614414.476 [MissionManager](IMPORTANT): Started mission Default
2025-06-11T04:00:14.476Z,1749614414.476 [Default] Running Loop=1
2025-06-11T04:00:14.481Z,1749614414.481 [Default](DEBUG): Aggregate::initialize Default
2025-06-11T04:00:14.481Z,1749614414.481 [Default:B.GoToSurface] Running Loop=1
2025-06-11T04:00:14.481Z,1749614414.481 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T04:00:14.481Z,1749614414.481 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-11T04:00:14.482Z,1749614414.482 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-11T04:00:14.482Z,1749614414.482 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-11T04:00:14.482Z,1749614414.482 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-11T04:00:14.482Z,1749614414.482 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T04:00:14.483Z,1749614414.483 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T04:00:14.483Z,1749614414.483 [Default:A.Wait] Running Loop=1
2025-06-11T04:00:14.483Z,1749614414.483 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-11T04:00:14.876Z,1749614414.876 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (3 active estimators).
2025-06-11T04:00:15.102Z,1749614415.102 [Radio_Surface](INFO): Powering up
2025-06-11T04:00:21.053Z,1749614421.053 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T04:00:27.533Z,1749614427.533 [Default:A.Wait](INFO): Done Waiting.
2025-06-11T04:00:27.537Z,1749614427.537 [Default:A.Wait] Stopped
2025-06-11T04:00:27.537Z,1749614427.537 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T04:00:33.950Z,1749614433.950 [Default:CheckIn] Running Loop=1
2025-06-11T04:00:33.950Z,1749614433.950 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T04:00:33.950Z,1749614433.950 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T04:00:36.200Z,1749614436.200 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour
2025-06-11T04:00:36.201Z,1749614436.201 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute
2025-06-11T04:00:36.202Z,1749614436.202 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.75608 degree
2025-06-11T04:00:36.203Z,1749614436.203 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.87786 degree
2025-06-11T04:00:36.204Z,1749614436.204 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter
2025-06-11T04:00:51.173Z,1749614451.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T04:01:00.568Z,1749614460.568 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second
2025-06-11T04:01:00.569Z,1749614460.569 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool
2025-06-11T04:01:00.570Z,1749614460.570 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter
2025-06-11T04:01:00.571Z,1749614460.571 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second
2025-06-11T04:01:00.572Z,1749614460.572 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute
2025-06-11T04:01:00.572Z,1749614460.572 [CommandExec](IMPORTANT): got command run
2025-06-11T04:01:00.598Z,1749614460.598 [CommandExec](IMPORTANT): Running 
2025-06-11T04:01:00.702Z,1749614460.702 [Default] Stopped
2025-06-11T04:01:00.702Z,1749614460.702 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-11T04:01:00.702Z,1749614460.702 [Default:B.GoToSurface] Stopped
2025-06-11T04:01:00.702Z,1749614460.702 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T04:01:00.702Z,1749614460.702 [Default:CheckIn] Stopped
2025-06-11T04:01:00.702Z,1749614460.702 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T04:01:00.702Z,1749614460.702 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T04:01:00.703Z,1749614460.703 [MissionManager](IMPORTANT): Started mission keepstation
2025-06-11T04:01:00.703Z,1749614460.703 [keepstation] Running Loop=1
2025-06-11T04:01:00.703Z,1749614460.703 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-06-11T04:01:00.703Z,1749614460.703 [keepstation:StandardEnvelopes] Running Loop=1
2025-06-11T04:01:00.703Z,1749614460.703 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-06-11T04:01:00.703Z,1749614460.703 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-06-11T04:01:00.703Z,1749614460.703 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:BackseatDriver] Running Loop=1
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:PowerOnly] Running Loop=1
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:PowerOnly:A] Running Loop=1
2025-06-11T04:01:00.704Z,1749614460.704 [keepstation:PowerOnly:B] Running Loop=1
2025-06-11T04:01:00.705Z,1749614460.705 [keepstation:PowerOnly:C] Running Loop=1
2025-06-11T04:01:00.705Z,1749614460.705 [keepstation:PowerOnly:D] Running Loop=1
2025-06-11T04:01:00.705Z,1749614460.705 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-06-11T04:01:00.705Z,1749614460.705 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-06-11T04:01:00.705Z,1749614460.705 [keepstation:H.Pitch] Running Loop=1
2025-06-11T04:01:00.705Z,1749614460.705 [keepstation:H.Pitch](DEBUG): Initialize.
2025-06-11T04:01:00.705Z,1749614460.705 [keepstation:B] Running Loop=1
2025-06-11T04:01:00.706Z,1749614460.706 [keepstation:H.Pitch] Running Loop=1
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:PowerOnly] Running Loop=1
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:PowerOnly:D] Stopped
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:PowerOnly:C] Stopped
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:PowerOnly:B] Stopped
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:PowerOnly:A] Stopped
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:BackseatDriver] Running Loop=1
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:StandardEnvelopes] Running Loop=1
2025-06-11T04:01:00.707Z,1749614460.707 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-06-11T04:01:00.708Z,1749614460.708 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-06-11T04:01:00.708Z,1749614460.708 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-06-11T04:01:00.708Z,1749614460.708 [keepstation:B] Stopped
2025-06-11T04:01:00.709Z,1749614460.709 [keepstation:D] Running Loop=1
2025-06-11T04:01:01.139Z,1749614461.139 [keepstation:D] Stopped
2025-06-11T04:01:01.139Z,1749614461.139 [keepstation:E] Running Loop=1
2025-06-11T04:01:01.544Z,1749614461.544 [keepstation:E] Stopped
2025-06-11T04:01:01.544Z,1749614461.544 [keepstation:StartingMission] Running Loop=1
2025-06-11T04:01:01.544Z,1749614461.544 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-06-11T04:01:01.906Z,1749614461.906 [keepstation:SurfaceComms] Running Loop=1
2025-06-11T04:01:01.906Z,1749614461.906 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-11T04:01:01.906Z,1749614461.906 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-11T04:01:01.906Z,1749614461.906 [keepstation:SurfaceComms:A] Stopped
2025-06-11T04:01:01.906Z,1749614461.906 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-11T04:01:01.906Z,1749614461.906 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-11T04:01:02.316Z,1749614462.316 [keepstation:NeedComms] Running Loop=1
2025-06-11T04:01:02.316Z,1749614462.316 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T04:01:02.316Z,1749614462.316 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T04:01:02.316Z,1749614462.316 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T04:01:02.317Z,1749614462.317 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T04:01:02.317Z,1749614462.317 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T04:01:02.318Z,1749614462.318 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T04:01:02.318Z,1749614462.318 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T04:01:02.318Z,1749614462.318 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T04:01:02.319Z,1749614462.319 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T04:01:02.319Z,1749614462.319 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T04:01:02.320Z,1749614462.320 [keepstation:NeedComms:A](INFO): last time_fix was:  1749612713.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T04:01:02.320Z,1749614462.320 [keepstation:NeedComms:A] Stopped
2025-06-11T04:01:02.727Z,1749614462.727 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T04:01:03.153Z,1749614463.153 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-11T04:01:22.301Z,1749614482.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T04:01:22.500Z,1749614482.500 [NAL9602](INFO): SBD MO Status=2, MOMSN=21563, MT Status=2, MTMSN=0
2025-06-11T04:01:22.500Z,1749614482.500 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T04:01:53.441Z,1749614513.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T04:02:24.565Z,1749614544.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T04:02:33.093Z,1749614553.093 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003199
2025-06-11T04:03:11.574Z,1749614591.574 [NAL9602](INFO): SBD MO Status=0, MOMSN=21563, MT Status=0, MTMSN=0
2025-06-11T04:03:11.574Z,1749614591.574 [NAL9602](INFO): No messages in MT queue
2025-06-11T04:03:12.804Z,1749614592.804 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040307.00,A,3641.38790,N,12151.56571,W,0.622,103.65,110625,,,A*79
2025-06-11T04:03:12.806Z,1749614592.806 [NAL9602](INFO): GPS fix at 20250611T040307: (36.689798, -121.859429)
2025-06-11T04:03:12.818Z,1749614592.818 [UniversalFixResidualReporter](INFO): Fix residual: 6.3 %DT, over the last 1330.0 m. Residual distance 84.1 m at bearing -79.7 degrees. Fix at (36.6898, -121.8594) with 1158.3 m made good.
2025-06-11T04:03:12.821Z,1749614592.821 [keepstation:NeedComms:C] Stopped
2025-06-11T04:03:12.821Z,1749614592.821 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T04:03:13.231Z,1749614593.231 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-11T04:03:20.605Z,1749614600.605 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20250610T121500/Courier0057.lzma
2025-06-11T04:03:21.610Z,1749614601.610 [DataOverHttps](INFO): Moved sent file to Logs/20250610T121500/Courier0057.lzma.bak
2025-06-11T04:03:21.611Z,1749614601.611 [DataOverHttps](INFO): SBD MOMSN=25184274
2025-06-11T04:03:38.648Z,1749614618.648 [DataOverHttps](INFO): Sending 327 bytes from file Logs/20250611T040000/Courier0000.lzma
2025-06-11T04:03:39.647Z,1749614619.647 [DataOverHttps](INFO): Moved sent file to Logs/20250611T040000/Courier0000.lzma.bak
2025-06-11T04:03:39.647Z,1749614619.647 [DataOverHttps](INFO): SBD MOMSN=25184322
2025-06-11T04:03:45.129Z,1749614625.129 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T04:03:56.040Z,1749614636.040 [DataOverHttps](INFO): Sending 803 bytes from file Logs/20250610T121500/Express0058.lzma
2025-06-11T04:03:57.039Z,1749614637.039 [DataOverHttps](INFO): Moved sent file to Logs/20250610T121500/Express0058.lzma.bak
2025-06-11T04:03:57.039Z,1749614637.039 [DataOverHttps](INFO): SBD MOMSN=25184327
2025-06-11T04:04:13.299Z,1749614653.299 [DataOverHttps](INFO): Sending 985 bytes from file Logs/20250611T040000/Express0001.lzma
2025-06-11T04:04:14.299Z,1749614654.299 [DataOverHttps](INFO): Moved sent file to Logs/20250611T040000/Express0001.lzma.bak
2025-06-11T04:04:14.299Z,1749614654.299 [DataOverHttps](INFO): SBD MOMSN=25184333
2025-06-11T04:04:15.834Z,1749614655.834 [keepstation:NeedComms:D] Stopped
2025-06-11T04:04:15.834Z,1749614655.834 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T04:04:16.243Z,1749614656.243 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-11T04:04:17.836Z,1749614657.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,040412.00,A,3641.38310,N,12151.55405,W,0.661,230.22,110625,,,A*70
2025-06-11T04:04:17.839Z,1749614657.839 [NAL9602](INFO): GPS fix at 20250611T040412: (36.689718, -121.859234)
2025-06-11T04:04:17.931Z,1749614657.931 [keepstation:NeedComms:E] Stopped
2025-06-11T04:04:17.932Z,1749614657.932 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T04:04:17.932Z,1749614657.932 [keepstation:NeedComms] Stopped
2025-06-11T04:04:17.932Z,1749614657.932 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T04:04:17.932Z,1749614657.932 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T04:04:17.932Z,1749614657.932 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T04:04:18.288Z,1749614658.288 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-11T04:04:18.288Z,1749614658.288 [keepstation:SurfaceComms:B] Stopped
2025-06-11T04:04:18.288Z,1749614658.288 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-11T04:04:18.289Z,1749614658.289 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-11T04:04:18.293Z,1749614658.293 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-11T04:04:18.293Z,1749614658.293 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-11T04:04:18.707Z,1749614658.707 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-11T04:04:18.707Z,1749614658.707 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-11T04:04:18.707Z,1749614658.707 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-11T04:04:18.707Z,1749614658.707 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-11T04:04:18.707Z,1749614658.707 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-11T04:04:18.707Z,1749614658.707 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-11T04:04:19.074Z,1749614659.074 [keepstation:SurfaceComms:setStation] Stopped
2025-06-11T04:04:19.074Z,1749614659.074 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-11T04:04:19.074Z,1749614659.074 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-11T04:04:19.074Z,1749614659.074 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-11T04:04:19.074Z,1749614659.074 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-11T04:04:19.074Z,1749614659.074 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-11T04:04:19.509Z,1749614659.509 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-11T04:04:19.509Z,1749614659.509 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-11T04:04:19.509Z,1749614659.509 [keepstation:SurfaceComms:setSink] Stopped
2025-06-11T04:04:19.509Z,1749614659.509 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-11T04:04:19.510Z,1749614659.510 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-11T04:04:19.510Z,1749614659.510 [keepstation:SurfaceComms] Stopped
2025-06-11T04:04:19.510Z,1749614659.510 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-11T04:04:19.912Z,1749614659.912 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-06-11T04:04:19.912Z,1749614659.912 [keepstation:StartingMission] Stopped
2025-06-11T04:04:19.912Z,1749614659.912 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-06-11T04:04:19.912Z,1749614659.912 [keepstation:TransitToStation] Running Loop=1
2025-06-11T04:04:19.913Z,1749614659.913 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-06-11T04:04:19.917Z,1749614659.917 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-11T04:04:19.917Z,1749614659.917 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-11T04:04:19.917Z,1749614659.917 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-11T04:04:19.917Z,1749614659.917 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-11T04:04:19.917Z,1749614659.917 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-06-11T04:04:19.917Z,1749614659.917 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-06-11T04:04:19.918Z,1749614659.918 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.756081,-121.877861
2025-06-11T04:04:20.300Z,1749614660.300 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.756081,-121.877861
2025-06-11T04:04:20.301Z,1749614660.301 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-11T04:04:20.305Z,1749614660.305 [keepstation:DiveCmd] Running Loop=1
2025-06-11T04:04:20.305Z,1749614660.305 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-06-11T04:04:20.305Z,1749614660.305 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T04:04:20.305Z,1749614660.305 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-11T04:04:20.305Z,1749614660.305 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-11T04:04:20.305Z,1749614660.305 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-11T04:04:20.306Z,1749614660.306 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-11T04:04:20.307Z,1749614660.307 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-11T04:04:20.308Z,1749614660.308 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-11T04:04:20.308Z,1749614660.308 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-11T04:04:20.308Z,1749614660.308 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-11T04:04:20.308Z,1749614660.308 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-11T04:04:20.309Z,1749614660.309 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-11T04:04:20.309Z,1749614660.309 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-11T04:04:20.337Z,1749614660.337 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-11T04:04:20.337Z,1749614660.337 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-11T04:04:20.337Z,1749614660.337 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-11T04:04:20.337Z,1749614660.337 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-11T04:04:20.337Z,1749614660.337 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T04:04:20.338Z,1749614660.338 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-11T04:04:20.339Z,1749614660.339 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-11T04:04:20.339Z,1749614660.339 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-11T04:04:20.339Z,1749614660.339 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-11T04:04:20.339Z,1749614660.339 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T04:04:20.339Z,1749614660.339 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T04:04:38.479Z,1749614678.479 [NAL9602](INFO): SBD MO Status=2, MOMSN=21564, MT Status=2, MTMSN=0
2025-06-11T04:04:38.479Z,1749614678.479 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T04:05:21.725Z,1749614721.725 [NAL9602](INFO): SBD MO Status=0, MOMSN=21564, MT Status=0, MTMSN=0
2025-06-11T04:05:21.726Z,1749614721.726 [NAL9602](INFO): No messages in MT queue
2025-06-11T04:05:52.411Z,1749614752.411 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T04:06:50.361Z,1749614810.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T04:07:07.657Z,1749614827.657 [Radio_Surface](INFO): Powering down
2025-06-11T04:07:21.485Z,1749614841.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T04:07:22.489Z,1749614842.489 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T04:07:54.054Z,1749614874.054 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-11T04:07:54.054Z,1749614874.054 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-11T04:07:54.054Z,1749614874.054 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T04:07:54.054Z,1749614874.054 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-11T04:07:54.426Z,1749614874.426 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-11T04:07:54.427Z,1749614874.427 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T04:07:54.427Z,1749614874.427 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-11T04:07:54.427Z,1749614874.427 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T04:07:54.427Z,1749614874.427 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-11T04:07:54.427Z,1749614874.427 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T04:07:54.427Z,1749614874.427 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-11T04:07:54.830Z,1749614874.830 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-11T04:07:54.830Z,1749614874.830 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-11T04:07:54.830Z,1749614874.830 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-11T04:07:54.830Z,1749614874.830 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-11T04:07:54.831Z,1749614874.831 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-11T04:07:54.832Z,1749614874.832 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-11T04:07:54.832Z,1749614874.832 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-11T04:07:54.832Z,1749614874.832 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-11T04:07:54.832Z,1749614874.832 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-11T04:07:54.832Z,1749614874.832 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-11T04:07:54.832Z,1749614874.832 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T04:07:54.833Z,1749614874.833 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-11T04:07:54.833Z,1749614874.833 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T04:07:54.833Z,1749614874.833 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-11T04:07:54.833Z,1749614874.833 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-11T04:07:54.833Z,1749614874.833 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-11T04:07:54.833Z,1749614874.833 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T04:07:54.833Z,1749614874.833 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-11T04:07:55.235Z,1749614875.235 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 31.28 mm (4 active estimators).
2025-06-11T04:08:25.754Z,1749614905.754 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.499237
2025-06-11T04:14:35.284Z,1749615275.284 [DVL_micro](ERROR): Failed to parse::WI,+00049,+00948,-00013,+00000,A
2025-06-11T04:23:19.726Z,1749615799.726 [DVL_micro](ERROR): Failed to parse:
:SA,-03.14,+04.75,331.4
2025-06-11T04:30:12.809Z,1749616212.809 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+0058.24,+0054.62,+00061.37
2025-06-11T04:30:12.809Z,1749616212.809 [DVL_micro](ERROR): Failed to parse:
:RD,+0058.24,+0054.62,+00061.37
2025-06-11T04:35:22.381Z,1749616522.381 [DVL_micro](ERROR): Failed to parse:
:RD,+0054.89,+0056.39,+0058.14,+0056.18
2025-06-11T04:58:57.052Z,1749617937.052 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,+00828,-00040,+00000,A
2025-06-11T04:58:57.052Z,1749617937.052 [DVL_micro](ERROR): Failed to parse:
:TS,+00828,-00040,+00000,A
2025-06-11T05:00:54.752Z,1749618054.752 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-06-11T05:04:18.088Z,1749618258.088 [keepstation:SurfaceComms] Running Loop=1
2025-06-11T05:04:18.089Z,1749618258.089 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-11T05:04:18.089Z,1749618258.089 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-11T05:04:18.089Z,1749618258.089 [keepstation:SurfaceComms:A] Stopped
2025-06-11T05:04:18.090Z,1749618258.090 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-11T05:04:18.090Z,1749618258.090 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-11T05:04:18.486Z,1749618258.486 [keepstation:TransitToStation:Dive] Stopped
2025-06-11T05:04:18.487Z,1749618258.487 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-11T05:04:18.487Z,1749618258.487 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-11T05:04:18.487Z,1749618258.487 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-11T05:04:18.487Z,1749618258.487 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-11T05:04:18.487Z,1749618258.487 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-11T05:04:18.487Z,1749618258.487 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-11T05:04:18.487Z,1749618258.487 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T05:04:18.488Z,1749618258.488 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-11T05:04:18.488Z,1749618258.488 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T05:04:18.488Z,1749618258.488 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-11T05:04:18.488Z,1749618258.488 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-11T05:04:18.488Z,1749618258.488 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-11T05:04:18.490Z,1749618258.490 [keepstation:NeedComms] Running Loop=1
2025-06-11T05:04:18.490Z,1749618258.490 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T05:04:18.490Z,1749618258.490 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T05:04:18.490Z,1749618258.490 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T05:04:18.490Z,1749618258.490 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T05:04:18.491Z,1749618258.491 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T05:04:18.491Z,1749618258.491 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T05:04:18.492Z,1749618258.492 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T05:04:18.492Z,1749618258.492 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T05:04:18.492Z,1749618258.492 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T05:04:18.492Z,1749618258.492 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T05:04:18.494Z,1749618258.494 [keepstation:NeedComms:A](INFO): last time_fix was:  1749614652.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T05:04:18.494Z,1749618258.494 [keepstation:NeedComms:A] Stopped
2025-06-11T05:05:13.644Z,1749618313.644 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T05:05:14.137Z,1749618314.137 [Radio_Surface](INFO): Powering up
2025-06-11T05:05:19.981Z,1749618319.981 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T05:05:50.113Z,1749618350.113 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T05:06:21.237Z,1749618381.237 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T05:06:37.268Z,1749618397.268 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-11T05:06:37.269Z,1749618397.269 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-11T05:06:37.272Z,1749618397.272 [BPC1](INFO): Received data from all battery sticks.
2025-06-11T05:06:52.377Z,1749618412.377 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T05:07:23.502Z,1749618443.502 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T05:07:32.333Z,1749618452.333 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003182
2025-06-11T05:09:27.743Z,1749618567.743 [NAL9602](INFO): SBD MO Status=2, MOMSN=21565, MT Status=2, MTMSN=0
2025-06-11T05:09:27.743Z,1749618567.743 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T05:09:37.452Z,1749618577.452 [NAL9602](INFO): SBD MO Status=0, MOMSN=21565, MT Status=0, MTMSN=0
2025-06-11T05:09:37.452Z,1749618577.452 [NAL9602](INFO): No messages in MT queue
2025-06-11T05:09:38.676Z,1749618578.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050933.00,A,3642.76793,N,12151.85850,W,0.739,155.00,110625,,,A*74
2025-06-11T05:09:38.682Z,1749618578.682 [NAL9602](INFO): GPS fix at 20250611T050933: (36.712799, -121.864308)
2025-06-11T05:09:38.738Z,1749618578.738 [UniversalFixResidualReporter](INFO): Fix residual: 4.9 %DT, over the last 2962.2 m. Residual distance 146.3 m at bearing -118.7 degrees. Fix at (36.7128, -121.8643) with 2606.0 m made good.
2025-06-11T05:09:38.740Z,1749618578.740 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.756081,-121.877861
2025-06-11T05:09:38.751Z,1749618578.751 [keepstation:NeedComms:C] Stopped
2025-06-11T05:09:38.751Z,1749618578.751 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T05:09:46.461Z,1749618586.461 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20250611T040000/Courier0003.lzma
2025-06-11T05:09:47.463Z,1749618587.463 [DataOverHttps](INFO): Moved sent file to Logs/20250611T040000/Courier0003.lzma.bak
2025-06-11T05:09:47.463Z,1749618587.463 [DataOverHttps](INFO): SBD MOMSN=25185126
2025-06-11T05:10:03.867Z,1749618603.867 [DataOverHttps](INFO): Sending 714 bytes from file Logs/20250611T040000/Express0004.lzma
2025-06-11T05:10:04.867Z,1749618604.867 [DataOverHttps](INFO): Moved sent file to Logs/20250611T040000/Express0004.lzma.bak
2025-06-11T05:10:04.867Z,1749618604.867 [DataOverHttps](INFO): SBD MOMSN=25185129
2025-06-11T05:10:06.665Z,1749618606.665 [keepstation:NeedComms:D] Stopped
2025-06-11T05:10:06.665Z,1749618606.665 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T05:10:08.660Z,1749618608.660 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,051003.00,A,3642.76489,N,12151.85600,W,1.730,166.07,110625,,,A*73
2025-06-11T05:10:08.663Z,1749618608.663 [NAL9602](INFO): GPS fix at 20250611T051003: (36.712748, -121.864267)
2025-06-11T05:10:08.703Z,1749618608.703 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.756081,-121.877861
2025-06-11T05:10:08.710Z,1749618608.710 [keepstation:NeedComms:E] Stopped
2025-06-11T05:10:08.711Z,1749618608.711 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T05:10:08.711Z,1749618608.711 [keepstation:NeedComms] Stopped
2025-06-11T05:10:08.711Z,1749618608.711 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T05:10:08.711Z,1749618608.711 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T05:10:08.711Z,1749618608.711 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T05:10:09.150Z,1749618609.150 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-11T05:10:09.150Z,1749618609.150 [keepstation:SurfaceComms:B] Stopped
2025-06-11T05:10:09.150Z,1749618609.150 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-11T05:10:09.150Z,1749618609.150 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-11T05:10:09.150Z,1749618609.150 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-11T05:10:09.151Z,1749618609.151 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-11T05:10:09.513Z,1749618609.513 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-11T05:10:09.513Z,1749618609.513 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-11T05:10:09.513Z,1749618609.513 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-11T05:10:09.513Z,1749618609.513 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-11T05:10:09.513Z,1749618609.513 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-11T05:10:09.514Z,1749618609.514 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-11T05:10:09.906Z,1749618609.906 [keepstation:SurfaceComms:setStation] Stopped
2025-06-11T05:10:09.906Z,1749618609.906 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-11T05:10:09.906Z,1749618609.906 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-11T05:10:09.906Z,1749618609.906 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-11T05:10:09.907Z,1749618609.907 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-11T05:10:09.907Z,1749618609.907 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-11T05:10:10.333Z,1749618610.333 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-11T05:10:10.334Z,1749618610.334 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-11T05:10:10.334Z,1749618610.334 [keepstation:SurfaceComms:setSink] Stopped
2025-06-11T05:10:10.334Z,1749618610.334 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-11T05:10:10.334Z,1749618610.334 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-11T05:10:10.334Z,1749618610.334 [keepstation:SurfaceComms] Stopped
2025-06-11T05:10:10.334Z,1749618610.334 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-11T05:10:10.714Z,1749618610.714 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-11T05:10:10.714Z,1749618610.714 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-11T05:10:10.714Z,1749618610.714 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-11T05:10:10.714Z,1749618610.714 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-11T05:10:10.715Z,1749618610.715 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-11T05:10:10.716Z,1749618610.716 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-11T05:10:10.716Z,1749618610.716 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-11T05:10:10.716Z,1749618610.716 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-11T05:10:10.716Z,1749618610.716 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-11T05:10:41.047Z,1749618641.047 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T05:12:09.249Z,1749618729.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T05:12:32.921Z,1749618752.921 [Radio_Surface](INFO): Powering down
2025-06-11T05:12:40.389Z,1749618760.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T05:12:41.393Z,1749618761.393 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T05:13:01.230Z,1749618781.230 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-11T05:13:01.230Z,1749618781.230 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-11T05:13:01.230Z,1749618781.230 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T05:13:01.230Z,1749618781.230 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-11T05:13:01.637Z,1749618781.637 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-11T05:13:01.637Z,1749618781.637 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T05:13:01.638Z,1749618781.638 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-11T05:13:01.638Z,1749618781.638 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T05:13:01.638Z,1749618781.638 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-11T05:13:01.638Z,1749618781.638 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T05:13:01.638Z,1749618781.638 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-11T05:13:02.042Z,1749618782.042 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-11T05:13:02.043Z,1749618782.043 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-11T05:13:02.043Z,1749618782.043 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T05:13:02.044Z,1749618782.044 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-11T05:13:46.682Z,1749618826.682 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.687317
2025-06-11T05:15:38.523Z,1749618938.523 [DVL_micro](ERROR): Failed to parse:
:BI,-00101,+00774,+00046,+00000,A
2025-06-11T05:39:21.233Z,1749620361.233 [DVL_micro](ERROR): Failed to parse::SA,-05.37,+00.48,325.3
2025-06-11T05:45:54.433Z,1749620754.433 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-06-11T05:45:54.433Z,1749620754.433 [DVL_micro] Communications Fault, FailCount= 1
2025-06-11T05:45:54.433Z,1749620754.433 [DVL_micro](ERROR): Communications Fault
2025-06-11T05:45:54.434Z,1749620754.434 [DVL_micro](ERROR): Failed to parse:
2025-06-11T05:45:54.544Z,1749620754.544 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-06-11T05:45:54.857Z,1749620754.857 [DVL_micro](INFO): Powering down
2025-06-11T05:45:55.700Z,1749620755.700 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-06-11T05:45:55.700Z,1749620755.700 [DVL_micro] No Fault, FailCount= 1
2025-06-11T05:50:01.620Z,1749621001.620 [DVL_micro](ERROR): Failed to parse:
:RD,+0054.24,+0059.39,+0065.58,+0058.83
2025-06-11T05:50:34.821Z,1749621034.821 [RudderServo](FAULT): Reading outside of valid range:0.000000

2025-06-11T05:50:34.821Z,1749621034.821 [RudderServo](ERROR): Rudder reporting null position
2025-06-11T05:50:35.565Z,1749621035.565 [RudderServo](ERROR): getPosition uart error serial timeout
2025-06-11T05:50:35.565Z,1749621035.565 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-06-11T05:50:35.565Z,1749621035.565 [RudderServo] Communications Fault, FailCount= 1
2025-06-11T05:50:35.565Z,1749621035.565 [RudderServo](ERROR): Communications Fault
2025-06-11T05:50:35.636Z,1749621035.636 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-06-11T05:50:35.720Z,1749621035.720 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-06-11T05:50:35.720Z,1749621035.720 [RudderServo](INFO): Powering down
2025-06-11T05:50:36.502Z,1749621036.502 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-11T05:50:36.622Z,1749621036.622 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-11T05:50:36.640Z,1749621036.640 [CBIT](INFO): Clearing failed state for component RudderServo
2025-06-11T05:50:36.640Z,1749621036.640 [RudderServo] No Fault, FailCount= 1
2025-06-11T06:10:09.014Z,1749622209.014 [keepstation:SurfaceComms] Running Loop=1
2025-06-11T06:10:09.014Z,1749622209.014 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-11T06:10:09.014Z,1749622209.014 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-11T06:10:09.014Z,1749622209.014 [keepstation:SurfaceComms:A] Stopped
2025-06-11T06:10:09.014Z,1749622209.014 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-11T06:10:09.015Z,1749622209.015 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-11T06:10:09.406Z,1749622209.406 [keepstation:TransitToStation:Dive] Stopped
2025-06-11T06:10:09.407Z,1749622209.407 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-11T06:10:09.407Z,1749622209.407 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-11T06:10:09.407Z,1749622209.407 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-11T06:10:09.407Z,1749622209.407 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-11T06:10:09.407Z,1749622209.407 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-11T06:10:09.407Z,1749622209.407 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-11T06:10:09.408Z,1749622209.408 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T06:10:09.408Z,1749622209.408 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-11T06:10:09.408Z,1749622209.408 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T06:10:09.408Z,1749622209.408 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-11T06:10:09.408Z,1749622209.408 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-11T06:10:09.408Z,1749622209.408 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-11T06:10:09.410Z,1749622209.410 [keepstation:NeedComms] Running Loop=1
2025-06-11T06:10:09.410Z,1749622209.410 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T06:10:09.410Z,1749622209.410 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T06:10:09.410Z,1749622209.410 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T06:10:09.410Z,1749622209.410 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T06:10:09.411Z,1749622209.411 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T06:10:09.411Z,1749622209.411 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T06:10:09.412Z,1749622209.412 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T06:10:09.412Z,1749622209.412 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T06:10:09.412Z,1749622209.412 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T06:10:09.412Z,1749622209.412 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T06:10:09.414Z,1749622209.414 [keepstation:NeedComms:A](INFO): last time_fix was:  1749618603.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T06:10:09.414Z,1749622209.414 [keepstation:NeedComms:A] Stopped
2025-06-11T06:10:55.011Z,1749622255.011 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T06:10:55.565Z,1749622255.565 [Radio_Surface](INFO): Powering up
2025-06-11T06:11:00.913Z,1749622260.913 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T06:11:31.033Z,1749622291.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T06:11:36.531Z,1749622296.531 [NAL9602](INFO): SBD MO Status=0, MOMSN=21566, MT Status=0, MTMSN=0
2025-06-11T06:11:36.531Z,1749622296.531 [NAL9602](INFO): No messages in MT queue
2025-06-11T06:11:37.746Z,1749622297.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061132.00,A,3644.20442,N,12152.24523,W,1.788,212.24,110625,,,A*79
2025-06-11T06:11:37.748Z,1749622297.748 [NAL9602](INFO): GPS fix at 20250611T061132: (36.736740, -121.870754)
2025-06-11T06:11:37.761Z,1749622297.761 [UniversalFixResidualReporter](INFO): Fix residual: 4.0 %DT, over the last 2848.1 m. Residual distance 113.1 m at bearing -115.0 degrees. Fix at (36.7367, -121.8708) with 2729.7 m made good.
2025-06-11T06:11:37.762Z,1749622297.762 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.756081,-121.877861
2025-06-11T06:11:37.765Z,1749622297.765 [keepstation:NeedComms:C] Stopped
2025-06-11T06:11:37.766Z,1749622297.766 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T06:11:54.451Z,1749622314.451 [NAL9602](INFO): SBD MO Status=1, MOMSN=21567, MT Status=0, MTMSN=0
2025-06-11T06:11:54.509Z,1749622314.509 [NAL9602](INFO): Sent 84 bytes from file Logs/20250611T040000/Courier0006.lzma
2025-06-11T06:11:54.509Z,1749622314.509 [NAL9602](INFO): Packets left to send: 0
2025-06-11T06:12:02.169Z,1749622322.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T06:12:13.304Z,1749622333.304 [NAL9602](INFO): SBD MO Status=1, MOMSN=21568, MT Status=0, MTMSN=0
2025-06-11T06:12:13.369Z,1749622333.369 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0007.lzma
2025-06-11T06:12:13.369Z,1749622333.369 [NAL9602](INFO): Packets left to send: 2
2025-06-11T06:12:23.028Z,1749622343.028 [NAL9602](INFO): SBD MO Status=1, MOMSN=21569, MT Status=0, MTMSN=0
2025-06-11T06:12:23.085Z,1749622343.085 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0007.lzma
2025-06-11T06:12:23.085Z,1749622343.085 [NAL9602](INFO): Packets left to send: 1
2025-06-11T06:12:31.607Z,1749622351.607 [NAL9602](INFO): SBD MO Status=1, MOMSN=21570, MT Status=0, MTMSN=0
2025-06-11T06:12:31.673Z,1749622351.673 [NAL9602](INFO): Sent 108 bytes from file Logs/20250611T040000/Express0007.lzma
2025-06-11T06:12:31.673Z,1749622351.673 [NAL9602](INFO): Packets left to send: 0
2025-06-11T06:12:33.321Z,1749622353.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T06:12:43.738Z,1749622363.738 [NAL9602](INFO): SBD MO Status=2, MOMSN=21571, MT Status=2, MTMSN=0
2025-06-11T06:12:43.738Z,1749622363.738 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T06:12:54.706Z,1749622374.706 [NAL9602](INFO): SBD MO Status=0, MOMSN=21571, MT Status=0, MTMSN=0
2025-06-11T06:12:54.784Z,1749622374.784 [keepstation:NeedComms:D] Stopped
2025-06-11T06:12:54.785Z,1749622374.785 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T06:12:57.135Z,1749622377.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,061251.00,A,3644.18986,N,12152.24449,W,1.322,170.00,110625,,,A*79
2025-06-11T06:12:57.137Z,1749622377.137 [NAL9602](INFO): GPS fix at 20250611T061251: (36.736498, -121.870741)
2025-06-11T06:12:57.169Z,1749622377.169 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.756081,-121.877861
2025-06-11T06:12:57.172Z,1749622377.172 [keepstation:NeedComms:E] Stopped
2025-06-11T06:12:57.173Z,1749622377.173 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T06:12:57.174Z,1749622377.174 [keepstation:NeedComms] Stopped
2025-06-11T06:12:57.174Z,1749622377.174 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T06:12:57.174Z,1749622377.174 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T06:12:57.174Z,1749622377.174 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T06:12:57.591Z,1749622377.591 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-11T06:12:57.591Z,1749622377.591 [keepstation:SurfaceComms:B] Stopped
2025-06-11T06:12:57.591Z,1749622377.591 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-11T06:12:57.591Z,1749622377.591 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-11T06:12:57.592Z,1749622377.592 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-11T06:12:57.592Z,1749622377.592 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-11T06:12:57.962Z,1749622377.962 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 31.28 mm.
2025-06-11T06:12:57.962Z,1749622377.962 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.28 mm.
2025-06-11T06:12:57.969Z,1749622377.969 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-11T06:12:57.969Z,1749622377.969 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-11T06:12:57.969Z,1749622377.969 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-11T06:12:57.969Z,1749622377.969 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-11T06:12:57.970Z,1749622377.970 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-11T06:12:57.970Z,1749622377.970 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-11T06:12:58.428Z,1749622378.428 [keepstation:SurfaceComms:setStation] Stopped
2025-06-11T06:12:58.429Z,1749622378.429 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-11T06:12:58.429Z,1749622378.429 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-11T06:12:58.429Z,1749622378.429 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-11T06:12:58.429Z,1749622378.429 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-11T06:12:58.429Z,1749622378.429 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-11T06:12:58.761Z,1749622378.761 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-11T06:12:58.761Z,1749622378.761 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-11T06:12:58.762Z,1749622378.762 [keepstation:SurfaceComms:setSink] Stopped
2025-06-11T06:12:58.762Z,1749622378.762 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-11T06:12:58.762Z,1749622378.762 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-11T06:12:58.762Z,1749622378.762 [keepstation:SurfaceComms] Stopped
2025-06-11T06:12:58.762Z,1749622378.762 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-11T06:12:59.214Z,1749622379.214 [keepstation:TransitToStation:Dive] Running Loop=1
2025-06-11T06:12:59.214Z,1749622379.214 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-06-11T06:12:59.215Z,1749622379.215 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-06-11T06:12:59.215Z,1749622379.215 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-06-11T06:12:59.216Z,1749622379.216 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T06:12:59.216Z,1749622379.216 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-11T06:12:59.216Z,1749622379.216 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-11T06:12:59.216Z,1749622379.216 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T06:12:59.217Z,1749622379.217 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T06:12:59.221Z,1749622379.221 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T06:12:59.221Z,1749622379.221 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T06:12:59.221Z,1749622379.221 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-11T06:12:59.222Z,1749622379.222 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-11T06:12:59.222Z,1749622379.222 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-11T06:12:59.223Z,1749622379.223 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-11T06:12:59.223Z,1749622379.223 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-11T06:12:59.223Z,1749622379.223 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-11T06:13:04.461Z,1749622384.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T06:13:13.505Z,1749622393.505 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003198
2025-06-11T06:13:29.134Z,1749622409.134 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T06:14:17.779Z,1749622457.779 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-11T06:14:17.780Z,1749622457.780 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-11T06:14:17.784Z,1749622457.784 [BPC1](INFO): Received data from all battery sticks.
2025-06-11T06:14:35.789Z,1749622475.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T06:15:06.913Z,1749622506.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T06:15:16.205Z,1749622516.205 [Radio_Surface](INFO): Powering down
2025-06-11T06:15:38.049Z,1749622538.049 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T06:15:39.054Z,1749622539.054 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T06:15:41.363Z,1749622541.363 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-11T06:15:41.364Z,1749622541.364 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-11T06:15:41.364Z,1749622541.364 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T06:15:41.364Z,1749622541.364 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-11T06:15:41.760Z,1749622541.760 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-11T06:15:41.760Z,1749622541.760 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T06:15:41.761Z,1749622541.761 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-11T06:15:41.777Z,1749622541.777 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T06:15:41.777Z,1749622541.777 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-11T06:15:41.777Z,1749622541.777 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T06:15:41.777Z,1749622541.777 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-11T06:15:42.208Z,1749622542.208 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-06-11T06:15:42.209Z,1749622542.209 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-06-11T06:15:42.210Z,1749622542.210 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-06-11T06:15:42.211Z,1749622542.211 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T06:15:42.212Z,1749622542.212 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-11T06:15:42.564Z,1749622542.564 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.28 mm (3 active estimators).
2025-06-11T06:16:46.322Z,1749622606.322 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747345
2025-06-11T06:21:38.348Z,1749622898.348 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+9999.99,+0057.20,+9999..68
2025-06-11T06:21:38.348Z,1749622898.348 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0057.20,+9999..68
2025-06-11T06:51:59.043Z,1749624719.043 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+0069.49,+0067.64,+9

2025-06-11T06:51:59.043Z,1749624719.043 [DVL_micro](ERROR): Failed to parse:
:RD,+0069.49,+0067.64,+9

2025-06-11T07:06:10.062Z,1749625570.062 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.756081,-121.877861
2025-06-11T07:06:10.062Z,1749625570.062 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-06-11T07:06:10.062Z,1749625570.062 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-06-11T07:06:10.063Z,1749625570.063 [keepstation:TransitToStation:C] Running Loop=1
2025-06-11T07:06:10.477Z,1749625570.477 [keepstation:TransitToStation:C] Stopped
2025-06-11T07:06:10.477Z,1749625570.477 [keepstation:TransitToStation:D] Running Loop=1
2025-06-11T07:06:10.478Z,1749625570.478 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-11T07:06:10.478Z,1749625570.478 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-11T07:06:10.479Z,1749625570.479 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-11T07:06:10.479Z,1749625570.479 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-11T07:06:10.479Z,1749625570.479 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-11T07:06:10.479Z,1749625570.479 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-11T07:06:10.479Z,1749625570.479 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-11T07:06:10.480Z,1749625570.480 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-11T07:06:10.480Z,1749625570.480 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-11T07:06:10.481Z,1749625570.481 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-11T07:06:10.482Z,1749625570.482 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-11T07:06:10.482Z,1749625570.482 [keepstation:DiveCmd:TransitToStation] Stopped
2025-06-11T07:06:10.482Z,1749625570.482 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-06-11T07:06:10.483Z,1749625570.483 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-06-11T07:06:10.483Z,1749625570.483 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-06-11T07:06:10.483Z,1749625570.483 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-06-11T07:06:10.483Z,1749625570.483 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T07:06:10.483Z,1749625570.483 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-06-11T07:06:10.483Z,1749625570.483 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-06-11T07:06:10.483Z,1749625570.483 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-06-11T07:06:10.909Z,1749625570.909 [keepstation:TransitToStation:D] Stopped
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:TransitToStation] Stopped
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:TransitToStation:Dive] Stopped
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:TransitToStation:Dive:A] Stopped
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:OnStation] Running Loop=1
2025-06-11T07:06:10.910Z,1749625570.910 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-06-11T07:06:11.268Z,1749625571.268 [keepstation:NeedComms] Running Loop=1
2025-06-11T07:06:11.268Z,1749625571.268 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T07:06:11.268Z,1749625571.268 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T07:06:11.268Z,1749625571.268 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T07:06:11.269Z,1749625571.269 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T07:06:11.269Z,1749625571.269 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T07:06:11.270Z,1749625571.270 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T07:06:11.270Z,1749625571.270 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T07:06:11.270Z,1749625571.270 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T07:06:11.271Z,1749625571.271 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T07:06:11.271Z,1749625571.271 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T07:06:11.272Z,1749625571.272 [keepstation:NeedComms:A](INFO): last time_fix was:  1749622371.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T07:06:11.273Z,1749625571.273 [keepstation:NeedComms:A] Stopped
2025-06-11T07:07:11.322Z,1749625631.322 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T07:07:11.841Z,1749625631.841 [Radio_Surface](INFO): Powering up
2025-06-11T07:07:17.965Z,1749625637.965 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T07:07:28.672Z,1749625648.672 [NAL9602](INFO): SBD MO Status=0, MOMSN=21572, MT Status=0, MTMSN=0
2025-06-11T07:07:28.672Z,1749625648.672 [NAL9602](INFO): No messages in MT queue
2025-06-11T07:07:29.887Z,1749625649.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,070724.00,A,3645.41142,N,12152.63472,W,0.544,212.79,110625,,,A*76
2025-06-11T07:07:29.889Z,1749625649.889 [NAL9602](INFO): GPS fix at 20250611T070724: (36.756857, -121.877245)
2025-06-11T07:07:29.902Z,1749625649.902 [UniversalFixResidualReporter](INFO): Fix residual: 4.6 %DT, over the last 2485.3 m. Residual distance 114.2 m at bearing -117.0 degrees. Fix at (36.7569, -121.8772) with 2336.8 m made good.
2025-06-11T07:07:29.926Z,1749625649.926 [keepstation:NeedComms:C] Stopped
2025-06-11T07:07:29.926Z,1749625649.926 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T07:07:44.524Z,1749625664.524 [NAL9602](INFO): SBD MO Status=1, MOMSN=21573, MT Status=0, MTMSN=0
2025-06-11T07:07:44.573Z,1749625664.573 [NAL9602](INFO): Sent 98 bytes from file Logs/20250611T040000/Courier0009.lzma
2025-06-11T07:07:44.573Z,1749625664.573 [NAL9602](INFO): Packets left to send: 0
2025-06-11T07:07:48.090Z,1749625668.090 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T07:07:53.786Z,1749625673.786 [NAL9602](INFO): SBD MO Status=1, MOMSN=21574, MT Status=0, MTMSN=0
2025-06-11T07:07:53.842Z,1749625673.842 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0010.lzma
2025-06-11T07:07:53.842Z,1749625673.842 [NAL9602](INFO): Packets left to send: 2
2025-06-11T07:08:04.256Z,1749625684.256 [NAL9602](INFO): SBD MO Status=1, MOMSN=21575, MT Status=0, MTMSN=0
2025-06-11T07:08:04.313Z,1749625684.313 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0010.lzma
2025-06-11T07:08:04.313Z,1749625684.313 [NAL9602](INFO): Packets left to send: 1
2025-06-11T07:08:11.025Z,1749625691.025 [NAL9602](INFO): SBD MO Status=1, MOMSN=21576, MT Status=0, MTMSN=0
2025-06-11T07:08:11.073Z,1749625691.073 [NAL9602](INFO): Sent 39 bytes from file Logs/20250611T040000/Express0010.lzma
2025-06-11T07:08:11.073Z,1749625691.073 [NAL9602](INFO): Packets left to send: 0
2025-06-11T07:08:18.764Z,1749625698.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=21577, MT Status=0, MTMSN=0
2025-06-11T07:08:18.852Z,1749625698.852 [keepstation:NeedComms:D] Stopped
2025-06-11T07:08:18.852Z,1749625698.852 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T07:08:19.213Z,1749625699.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T07:08:21.176Z,1749625701.176 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,070815.00,A,3645.40820,N,12152.64351,W,1.108,223.81,110625,,,A*7E
2025-06-11T07:08:21.179Z,1749625701.179 [NAL9602](INFO): GPS fix at 20250611T070815: (36.756803, -121.877392)
2025-06-11T07:08:21.193Z,1749625701.193 [keepstation:NeedComms:E] Stopped
2025-06-11T07:08:21.194Z,1749625701.194 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T07:08:21.194Z,1749625701.194 [keepstation:NeedComms] Stopped
2025-06-11T07:08:21.194Z,1749625701.194 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T07:08:21.194Z,1749625701.194 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T07:08:21.194Z,1749625701.194 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T07:08:21.614Z,1749625701.614 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-06-11T07:08:21.614Z,1749625701.614 [keepstation:OnStation] Stopped
2025-06-11T07:08:21.614Z,1749625701.614 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-06-11T07:08:21.614Z,1749625701.614 [keepstation:KeepStation] Running Loop=1
2025-06-11T07:08:21.615Z,1749625701.615 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-06-11T07:08:21.615Z,1749625701.615 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-11T07:08:21.615Z,1749625701.615 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-11T07:08:21.615Z,1749625701.615 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-11T07:08:21.615Z,1749625701.615 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-11T07:08:21.615Z,1749625701.615 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-06-11T07:08:21.615Z,1749625701.615 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-06-11T07:08:21.992Z,1749625701.992 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-11T07:08:50.339Z,1749625730.339 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T07:08:53.094Z,1749625733.094 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T07:09:21.473Z,1749625761.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T07:09:52.597Z,1749625792.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T07:10:14.073Z,1749625814.073 [Radio_Surface](INFO): Powering down
2025-06-11T07:10:23.721Z,1749625823.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T07:10:24.725Z,1749625824.725 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T07:21:55.656Z,1749626515.656 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-11T07:21:55.662Z,1749626515.662 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-11T07:21:55.665Z,1749626515.665 [BPC1](INFO): Received data from all battery sticks.
2025-06-11T07:26:29.968Z,1749626789.968 [DVL_micro](ERROR): Failed to parse:
:WI,+00072,+00062,+00010,+00000,A
2025-06-11T07:42:48.869Z,1749627768.869 [DVL_micro](ERROR): Failed to parse:
:SA,+16.81,+02.47,215.0
2025-06-11T08:08:21.272Z,1749629301.272 [keepstation:SurfaceComms] Running Loop=1
2025-06-11T08:08:21.272Z,1749629301.272 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-11T08:08:21.273Z,1749629301.273 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-11T08:08:21.273Z,1749629301.273 [keepstation:SurfaceComms:A] Stopped
2025-06-11T08:08:21.273Z,1749629301.273 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-11T08:08:21.273Z,1749629301.273 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-11T08:08:21.275Z,1749629301.275 [keepstation:NeedComms] Running Loop=1
2025-06-11T08:08:21.275Z,1749629301.275 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T08:08:21.275Z,1749629301.275 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T08:08:21.275Z,1749629301.275 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T08:08:21.276Z,1749629301.276 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T08:08:21.276Z,1749629301.276 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T08:08:21.276Z,1749629301.276 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T08:08:21.277Z,1749629301.277 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T08:08:21.277Z,1749629301.277 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T08:08:21.278Z,1749629301.278 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T08:08:21.278Z,1749629301.278 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T08:08:21.279Z,1749629301.279 [keepstation:NeedComms:A](INFO): last time_fix was:  1749625695.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T08:08:21.280Z,1749629301.280 [keepstation:NeedComms:A] Stopped
2025-06-11T08:08:21.656Z,1749629301.656 [keepstation:KeepStation:Dive] Stopped
2025-06-11T08:08:21.657Z,1749629301.657 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-06-11T08:08:21.657Z,1749629301.657 [keepstation:KeepStation:Dive:A] Stopped
2025-06-11T08:08:21.657Z,1749629301.657 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-06-11T08:08:21.657Z,1749629301.657 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-11T08:08:21.658Z,1749629301.658 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-11T08:08:21.658Z,1749629301.658 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-11T08:08:21.658Z,1749629301.658 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-11T08:08:21.658Z,1749629301.658 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-11T08:08:21.658Z,1749629301.658 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-11T08:09:24.768Z,1749629364.768 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T08:09:25.253Z,1749629365.253 [Radio_Surface](INFO): Powering up
2025-06-11T08:09:31.509Z,1749629371.509 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T08:09:38.506Z,1749629378.506 [NAL9602](INFO): SBD MO Status=0, MOMSN=21578, MT Status=0, MTMSN=0
2025-06-11T08:09:38.507Z,1749629378.507 [NAL9602](INFO): No messages in MT queue
2025-06-11T08:09:42.558Z,1749629382.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080937.00,A,3645.31007,N,12152.68031,W,9.661,330.27,110625,,,A*7D
2025-06-11T08:09:42.560Z,1749629382.560 [NAL9602](INFO): GPS fix at 20250611T080937: (36.755168, -121.878005)
2025-06-11T08:09:42.574Z,1749629382.574 [keepstation:NeedComms:C] Stopped
2025-06-11T08:09:42.575Z,1749629382.575 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T08:10:01.641Z,1749629401.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T08:10:14.207Z,1749629414.207 [NAL9602](INFO): SBD MO Status=1, MOMSN=21579, MT Status=0, MTMSN=0
2025-06-11T08:10:14.257Z,1749629414.257 [NAL9602](INFO): Sent 89 bytes from file Logs/20250611T040000/Courier0012.lzma
2025-06-11T08:10:14.257Z,1749629414.257 [NAL9602](INFO): Packets left to send: 0
2025-06-11T08:10:28.567Z,1749629428.567 [NAL9602](INFO): SBD MO Status=1, MOMSN=21580, MT Status=0, MTMSN=0
2025-06-11T08:10:28.621Z,1749629428.621 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0013.lzma
2025-06-11T08:10:28.621Z,1749629428.621 [NAL9602](INFO): Packets left to send: 1
2025-06-11T08:10:32.765Z,1749629432.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T08:10:47.291Z,1749629447.291 [NAL9602](INFO): SBD MO Status=2, MOMSN=21581, MT Status=2, MTMSN=0
2025-06-11T08:10:47.291Z,1749629447.291 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T08:10:50.529Z,1749629450.529 [DVL_micro](ERROR): only read 3 of 4 data items for beam range. Device response is::RD,+9999.99,+0088.62,+9999.995
2025-06-11T08:10:50.529Z,1749629450.529 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0088.62,+9999.995
2025-06-11T08:11:03.889Z,1749629463.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T08:11:17.256Z,1749629477.256 [NAL9602](INFO): SBD MO Status=1, MOMSN=21581, MT Status=0, MTMSN=0
2025-06-11T08:11:17.313Z,1749629477.313 [NAL9602](INFO): Sent 54 bytes from file Logs/20250611T040000/Express0013.lzma
2025-06-11T08:11:17.313Z,1749629477.313 [NAL9602](INFO): Packets left to send: 0
2025-06-11T08:11:31.103Z,1749629491.103 [NAL9602](INFO): SBD MO Status=2, MOMSN=21582, MT Status=2, MTMSN=0
2025-06-11T08:11:31.103Z,1749629491.103 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T08:11:35.013Z,1749629495.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T08:11:41.740Z,1749629501.740 [NAL9602](INFO): SBD MO Status=0, MOMSN=21582, MT Status=0, MTMSN=0
2025-06-11T08:11:41.839Z,1749629501.839 [keepstation:NeedComms:D] Stopped
2025-06-11T08:11:41.839Z,1749629501.839 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T08:11:44.178Z,1749629504.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,081139.00,A,3645.28824,N,12152.69750,W,1.497,176.78,110625,,,A*73
2025-06-11T08:11:44.181Z,1749629504.181 [NAL9602](INFO): GPS fix at 20250611T081139: (36.754804, -121.878292)
2025-06-11T08:11:44.195Z,1749629504.195 [keepstation:NeedComms:E] Stopped
2025-06-11T08:11:44.196Z,1749629504.196 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T08:11:44.196Z,1749629504.196 [keepstation:NeedComms] Stopped
2025-06-11T08:11:44.196Z,1749629504.196 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T08:11:44.196Z,1749629504.196 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T08:11:44.196Z,1749629504.196 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T08:11:44.596Z,1749629504.596 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-11T08:11:44.596Z,1749629504.596 [keepstation:SurfaceComms:B] Stopped
2025-06-11T08:11:44.596Z,1749629504.596 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-11T08:11:44.596Z,1749629504.596 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-11T08:11:44.597Z,1749629504.597 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-11T08:11:44.597Z,1749629504.597 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-11T08:11:44.801Z,1749629504.801 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003161
2025-06-11T08:11:44.980Z,1749629504.980 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-11T08:11:44.980Z,1749629504.980 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-11T08:11:44.981Z,1749629504.981 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-11T08:11:44.981Z,1749629504.981 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-11T08:11:44.981Z,1749629504.981 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-11T08:11:44.981Z,1749629504.981 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-11T08:11:45.403Z,1749629505.403 [keepstation:SurfaceComms:setStation] Stopped
2025-06-11T08:11:45.404Z,1749629505.404 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-11T08:11:45.404Z,1749629505.404 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-11T08:11:45.404Z,1749629505.404 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-11T08:11:45.404Z,1749629505.404 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-11T08:11:45.404Z,1749629505.404 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-11T08:11:45.802Z,1749629505.802 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-11T08:11:45.803Z,1749629505.803 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-11T08:11:45.803Z,1749629505.803 [keepstation:SurfaceComms:setSink] Stopped
2025-06-11T08:11:45.803Z,1749629505.803 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-11T08:11:45.803Z,1749629505.803 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-11T08:11:45.803Z,1749629505.803 [keepstation:SurfaceComms] Stopped
2025-06-11T08:11:45.803Z,1749629505.803 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-11T08:11:46.226Z,1749629506.226 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-11T08:11:46.226Z,1749629506.226 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-11T08:11:46.226Z,1749629506.226 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-11T08:11:46.226Z,1749629506.226 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-11T08:11:46.226Z,1749629506.226 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-11T08:11:46.227Z,1749629506.227 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-11T08:11:46.228Z,1749629506.228 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-11T08:11:46.228Z,1749629506.228 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-11T08:11:46.228Z,1749629506.228 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-11T08:11:46.228Z,1749629506.228 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-11T08:12:16.076Z,1749629536.076 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T08:13:05.013Z,1749629585.013 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:3.490000m. At depth:1.751745m.
2025-06-11T08:13:39.765Z,1749629619.765 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T08:13:57.185Z,1749629637.185 [Radio_Surface](INFO): Powering down
2025-06-11T08:14:10.897Z,1749629650.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T08:14:11.901Z,1749629651.901 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T08:14:37.085Z,1749629677.085 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-11T08:14:37.085Z,1749629677.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-11T08:14:37.085Z,1749629677.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T08:14:37.085Z,1749629677.085 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-11T08:14:37.492Z,1749629677.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-11T08:14:37.492Z,1749629677.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T08:14:37.492Z,1749629677.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-11T08:14:37.492Z,1749629677.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-11T08:14:37.492Z,1749629677.492 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T08:14:37.493Z,1749629677.493 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-11T08:14:37.900Z,1749629677.900 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-11T08:14:37.900Z,1749629677.900 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T08:14:37.900Z,1749629677.900 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-11T08:14:37.900Z,1749629677.900 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-11T08:14:37.901Z,1749629677.901 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-06-11T08:14:38.308Z,1749629678.308 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-11T08:14:38.308Z,1749629678.308 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-11T08:14:38.308Z,1749629678.308 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-11T08:14:38.308Z,1749629678.308 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-11T08:14:38.308Z,1749629678.308 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-11T08:14:38.308Z,1749629678.308 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-11T08:14:38.308Z,1749629678.308 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-11T08:14:38.310Z,1749629678.310 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-06-11T08:15:15.157Z,1749629715.157 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.514572
2025-06-11T08:26:56.173Z,1749630416.173 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-06-11T08:26:56.594Z,1749630416.594 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-06-11T08:26:56.594Z,1749630416.594 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 31.28 mm.
2025-06-11T08:29:32.520Z,1749630572.520 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-11T08:29:32.522Z,1749630572.522 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-11T08:29:32.524Z,1749630572.524 [BPC1](INFO): Received data from all battery sticks.
2025-06-11T08:59:06.870Z,1749632346.870 [DVL_micro](ERROR): Failed to parse:
:BI,-00030,-00241,-00044,+00000,I
2025-06-11T09:11:44.379Z,1749633104.379 [keepstation:SurfaceComms] Running Loop=1
2025-06-11T09:11:44.379Z,1749633104.379 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-11T09:11:44.379Z,1749633104.379 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-11T09:11:44.379Z,1749633104.379 [keepstation:SurfaceComms:A] Stopped
2025-06-11T09:11:44.379Z,1749633104.379 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-11T09:11:44.380Z,1749633104.380 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-11T09:11:44.381Z,1749633104.381 [keepstation:NeedComms] Running Loop=1
2025-06-11T09:11:44.382Z,1749633104.382 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T09:11:44.382Z,1749633104.382 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T09:11:44.382Z,1749633104.382 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T09:11:44.382Z,1749633104.382 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T09:11:44.383Z,1749633104.383 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T09:11:44.383Z,1749633104.383 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T09:11:44.383Z,1749633104.383 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T09:11:44.384Z,1749633104.384 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T09:11:44.384Z,1749633104.384 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T09:11:44.384Z,1749633104.384 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T09:11:44.402Z,1749633104.402 [keepstation:NeedComms:A](INFO): last time_fix was:  1749629499.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T09:11:44.402Z,1749633104.402 [keepstation:NeedComms:A] Stopped
2025-06-11T09:11:44.781Z,1749633104.781 [keepstation:KeepStation:Dive] Stopped
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:KeepStation:Dive:A] Stopped
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-11T09:11:44.782Z,1749633104.782 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-11T09:11:44.783Z,1749633104.783 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-11T09:12:58.235Z,1749633178.235 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T09:12:58.833Z,1749633178.833 [Radio_Surface](INFO): Powering up
2025-06-11T09:13:04.517Z,1749633184.517 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T09:13:34.654Z,1749633214.654 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T09:14:05.778Z,1749633245.778 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T09:14:36.901Z,1749633276.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T09:15:08.033Z,1749633308.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T09:15:18.902Z,1749633318.902 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003640
2025-06-11T09:16:29.918Z,1749633389.918 [NAL9602](INFO): SBD MO Status=0, MOMSN=21583, MT Status=0, MTMSN=0
2025-06-11T09:16:29.919Z,1749633389.919 [NAL9602](INFO): No messages in MT queue
2025-06-11T09:16:31.124Z,1749633391.124 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091625.00,A,3645.17608,N,12152.46671,W,0.350,253.80,110625,,,A*75
2025-06-11T09:16:31.126Z,1749633391.126 [NAL9602](INFO): GPS fix at 20250611T091625: (36.752935, -121.874445)
2025-06-11T09:16:31.139Z,1749633391.139 [UniversalFixResidualReporter](INFO): Fix residual: 23.5 %DT, over the last 1421.7 m. Residual distance 334.2 m at bearing -62.7 degrees. Fix at (36.7529, -121.8744) with 400.8 m made good.
2025-06-11T09:16:31.142Z,1749633391.142 [keepstation:NeedComms:C] Stopped
2025-06-11T09:16:31.142Z,1749633391.142 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T09:16:40.493Z,1749633400.493 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250611T040000/Courier0015.lzma
2025-06-11T09:16:41.495Z,1749633401.495 [DataOverHttps](INFO): Moved sent file to Logs/20250611T040000/Courier0015.lzma.bak
2025-06-11T09:16:41.495Z,1749633401.495 [DataOverHttps](INFO): SBD MOMSN=25187504
2025-06-11T09:16:57.818Z,1749633417.818 [DataOverHttps](INFO): Sending 709 bytes from file Logs/20250611T040000/Express0016.lzma
2025-06-11T09:16:58.819Z,1749633418.819 [DataOverHttps](INFO): Moved sent file to Logs/20250611T040000/Express0016.lzma.bak
2025-06-11T09:16:58.819Z,1749633418.819 [DataOverHttps](INFO): SBD MOMSN=25187508
2025-06-11T09:17:00.640Z,1749633420.640 [keepstation:NeedComms:D] Stopped
2025-06-11T09:17:00.640Z,1749633420.640 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T09:17:02.644Z,1749633422.644 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091657.00,A,3645.17605,N,12152.46994,W,0.408,353.86,110625,,,A*74
2025-06-11T09:17:02.646Z,1749633422.646 [NAL9602](INFO): GPS fix at 20250611T091657: (36.752934, -121.874499)
2025-06-11T09:17:02.689Z,1749633422.689 [keepstation:NeedComms:E] Stopped
2025-06-11T09:17:02.690Z,1749633422.690 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T09:17:02.690Z,1749633422.690 [keepstation:NeedComms] Stopped
2025-06-11T09:17:02.690Z,1749633422.690 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T09:17:02.691Z,1749633422.691 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T09:17:02.691Z,1749633422.691 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T09:17:03.103Z,1749633423.103 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-11T09:17:03.103Z,1749633423.103 [keepstation:SurfaceComms:B] Stopped
2025-06-11T09:17:03.103Z,1749633423.103 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-11T09:17:03.103Z,1749633423.103 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-11T09:17:03.103Z,1749633423.103 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-11T09:17:03.103Z,1749633423.103 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-11T09:17:03.525Z,1749633423.525 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-11T09:17:03.526Z,1749633423.526 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-11T09:17:03.526Z,1749633423.526 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-11T09:17:03.526Z,1749633423.526 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-11T09:17:03.526Z,1749633423.526 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-11T09:17:03.526Z,1749633423.526 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-11T09:17:03.892Z,1749633423.892 [keepstation:SurfaceComms:setStation] Stopped
2025-06-11T09:17:03.892Z,1749633423.892 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-11T09:17:03.892Z,1749633423.892 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-11T09:17:03.892Z,1749633423.892 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-11T09:17:03.893Z,1749633423.893 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-11T09:17:03.899Z,1749633423.899 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-11T09:17:04.299Z,1749633424.299 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-11T09:17:04.300Z,1749633424.300 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-11T09:17:04.300Z,1749633424.300 [keepstation:SurfaceComms:setSink] Stopped
2025-06-11T09:17:04.300Z,1749633424.300 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-11T09:17:04.300Z,1749633424.300 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-11T09:17:04.300Z,1749633424.300 [keepstation:SurfaceComms] Stopped
2025-06-11T09:17:04.300Z,1749633424.300 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-11T09:17:04.709Z,1749633424.709 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-11T09:17:04.709Z,1749633424.709 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-11T09:17:04.709Z,1749633424.709 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-11T09:17:04.709Z,1749633424.709 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-11T09:17:04.710Z,1749633424.710 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T09:17:04.710Z,1749633424.710 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-11T09:17:04.710Z,1749633424.710 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-11T09:17:04.710Z,1749633424.710 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T09:17:04.710Z,1749633424.710 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T09:17:04.710Z,1749633424.710 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T09:17:04.710Z,1749633424.710 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T09:17:04.711Z,1749633424.711 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-11T09:17:04.711Z,1749633424.711 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-11T09:17:04.711Z,1749633424.711 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-11T09:17:04.711Z,1749633424.711 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-11T09:17:04.711Z,1749633424.711 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-11T09:17:04.712Z,1749633424.712 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-11T09:17:35.411Z,1749633455.411 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T09:19:01.905Z,1749633541.905 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T09:19:32.393Z,1749633572.393 [Radio_Surface](INFO): Powering down
2025-06-11T09:19:33.029Z,1749633573.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T09:19:34.034Z,1749633574.034 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T09:20:17.381Z,1749633617.381 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-11T09:20:17.381Z,1749633617.381 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-11T09:20:17.382Z,1749633617.382 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T09:20:17.382Z,1749633617.382 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-11T09:20:17.790Z,1749633617.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-11T09:20:17.790Z,1749633617.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T09:20:17.790Z,1749633617.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-11T09:20:17.790Z,1749633617.790 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-11T09:20:17.791Z,1749633617.791 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T09:20:17.791Z,1749633617.791 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-11T09:20:18.198Z,1749633618.198 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-11T09:20:18.198Z,1749633618.198 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T09:20:18.198Z,1749633618.198 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-11T09:20:18.199Z,1749633618.199 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T09:20:18.199Z,1749633618.199 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T09:20:18.199Z,1749633618.199 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-11T09:20:18.199Z,1749633618.199 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T09:20:18.199Z,1749633618.199 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-11T09:20:18.199Z,1749633618.199 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T09:20:18.200Z,1749633618.200 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-11T09:20:18.200Z,1749633618.200 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-11T09:20:18.200Z,1749633618.200 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-06-11T09:20:18.620Z,1749633618.620 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-11T09:20:18.620Z,1749633618.620 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-11T09:20:18.620Z,1749633618.620 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-11T09:20:18.620Z,1749633618.620 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-11T09:20:18.620Z,1749633618.620 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-11T09:20:18.620Z,1749633618.620 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-11T09:20:18.621Z,1749633618.621 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-11T09:20:18.622Z,1749633618.622 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-06-11T09:21:09.438Z,1749633669.438 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759064
2025-06-11T09:26:16.077Z,1749633976.077 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-11T09:26:20.370Z,1749633980.370 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001551
CHAN A1 (24V): 0.004512
CHAN A2 (12V): -0.007750
CHAN A3 (5V): -0.012048
CHAN B0 (3.3V): -0.004850
CHAN B1 (3.15aV): -0.001721
CHAN B2 (3.15bV): -0.001403
CHAN B3 (GND): -0.006554
OPEN: 0.012156
Full Scale: +/- 1 mA
2025-06-11T09:33:39.932Z,1749634419.932 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-06-11T09:35:09.595Z,1749634509.595 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-06-11T09:37:09.178Z,1749634629.178 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-11T09:37:09.179Z,1749634629.179 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-11T09:37:09.182Z,1749634629.182 [BPC1](INFO): Received data from all battery sticks.
2025-06-11T10:17:02.905Z,1749637022.905 [keepstation:SurfaceComms] Running Loop=1
2025-06-11T10:17:02.905Z,1749637022.905 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-11T10:17:02.906Z,1749637022.906 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-11T10:17:02.906Z,1749637022.906 [keepstation:SurfaceComms:A] Stopped
2025-06-11T10:17:02.906Z,1749637022.906 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-11T10:17:02.906Z,1749637022.906 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-11T10:17:02.908Z,1749637022.908 [keepstation:NeedComms] Running Loop=1
2025-06-11T10:17:02.908Z,1749637022.908 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T10:17:02.908Z,1749637022.908 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T10:17:02.908Z,1749637022.908 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T10:17:02.909Z,1749637022.909 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T10:17:02.909Z,1749637022.909 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T10:17:02.910Z,1749637022.910 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T10:17:02.910Z,1749637022.910 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T10:17:02.910Z,1749637022.910 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T10:17:02.911Z,1749637022.911 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T10:17:02.911Z,1749637022.911 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T10:17:02.912Z,1749637022.912 [keepstation:NeedComms:A](INFO): last time_fix was:  1749633417.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T10:17:02.913Z,1749637022.913 [keepstation:NeedComms:A] Stopped
2025-06-11T10:17:03.293Z,1749637023.293 [keepstation:KeepStation:Dive] Stopped
2025-06-11T10:17:03.293Z,1749637023.293 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-06-11T10:17:03.293Z,1749637023.293 [keepstation:KeepStation:Dive:A] Stopped
2025-06-11T10:17:03.293Z,1749637023.293 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-06-11T10:17:03.294Z,1749637023.294 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-11T10:17:03.294Z,1749637023.294 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-11T10:17:03.294Z,1749637023.294 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-11T10:17:03.294Z,1749637023.294 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-11T10:17:03.294Z,1749637023.294 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-11T10:17:03.294Z,1749637023.294 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-11T10:18:18.550Z,1749637098.550 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T10:18:18.970Z,1749637098.970 [Radio_Surface](INFO): Powering up
2025-06-11T10:18:24.696Z,1749637104.696 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T10:18:49.246Z,1749637129.246 [NAL9602](INFO): SBD MO Status=0, MOMSN=21584, MT Status=0, MTMSN=0
2025-06-11T10:18:49.246Z,1749637129.246 [NAL9602](INFO): No messages in MT queue
2025-06-11T10:18:50.469Z,1749637130.469 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,101845.00,A,3645.29220,N,12152.92020,W,0.680,301.91,110625,,,A*73
2025-06-11T10:18:50.471Z,1749637130.471 [NAL9602](INFO): GPS fix at 20250611T101845: (36.754870, -121.882003)
2025-06-11T10:18:50.483Z,1749637130.483 [UniversalFixResidualReporter](INFO): Fix residual: 17.5 %DT, over the last 1587.9 m. Residual distance 277.8 m at bearing 95.8 degrees. Fix at (36.7549, -121.8820) with 702.4 m made good.
2025-06-11T10:18:50.497Z,1749637130.497 [keepstation:NeedComms:C] Stopped
2025-06-11T10:18:50.498Z,1749637130.498 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T10:18:54.813Z,1749637134.813 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T10:19:20.065Z,1749637160.065 [NAL9602](INFO): SBD MO Status=1, MOMSN=21585, MT Status=0, MTMSN=0
2025-06-11T10:19:20.114Z,1749637160.114 [NAL9602](INFO): Sent 97 bytes from file Logs/20250611T040000/Courier0018.lzma
2025-06-11T10:19:20.114Z,1749637160.114 [NAL9602](INFO): Packets left to send: 0
2025-06-11T10:19:25.957Z,1749637165.957 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T10:19:31.679Z,1749637171.679 [NAL9602](INFO): SBD MO Status=1, MOMSN=21586, MT Status=0, MTMSN=0
2025-06-11T10:19:31.729Z,1749637171.729 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0019.lzma
2025-06-11T10:19:31.729Z,1749637171.729 [NAL9602](INFO): Packets left to send: 2
2025-06-11T10:19:48.640Z,1749637188.640 [NAL9602](INFO): SBD MO Status=1, MOMSN=21587, MT Status=0, MTMSN=0
2025-06-11T10:19:48.690Z,1749637188.690 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0019.lzma
2025-06-11T10:19:48.690Z,1749637188.690 [NAL9602](INFO): Packets left to send: 1
2025-06-11T10:19:57.082Z,1749637197.082 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T10:20:01.491Z,1749637201.491 [NAL9602](INFO): SBD MO Status=1, MOMSN=21588, MT Status=0, MTMSN=0
2025-06-11T10:20:01.549Z,1749637201.549 [NAL9602](INFO): Sent 159 bytes from file Logs/20250611T040000/Express0019.lzma
2025-06-11T10:20:01.549Z,1749637201.549 [NAL9602](INFO): Packets left to send: 0
2025-06-11T10:20:10.840Z,1749637210.840 [NAL9602](INFO): SBD MO Status=0, MOMSN=21589, MT Status=0, MTMSN=0
2025-06-11T10:20:10.928Z,1749637210.928 [keepstation:NeedComms:D] Stopped
2025-06-11T10:20:10.929Z,1749637210.929 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T10:20:13.268Z,1749637213.268 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102008.00,A,3645.29602,N,12152.92356,W,0.330,85.21,110625,,,D*48
2025-06-11T10:20:13.271Z,1749637213.271 [NAL9602](INFO): GPS fix at 20250611T102008: (36.754934, -121.882059)
2025-06-11T10:20:13.300Z,1749637213.300 [keepstation:NeedComms:E] Stopped
2025-06-11T10:20:13.301Z,1749637213.301 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T10:20:13.301Z,1749637213.301 [keepstation:NeedComms] Stopped
2025-06-11T10:20:13.301Z,1749637213.301 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T10:20:13.301Z,1749637213.301 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T10:20:13.301Z,1749637213.301 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T10:20:13.707Z,1749637213.707 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-11T10:20:13.707Z,1749637213.707 [keepstation:SurfaceComms:B] Stopped
2025-06-11T10:20:13.707Z,1749637213.707 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-11T10:20:13.707Z,1749637213.707 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-11T10:20:13.707Z,1749637213.707 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-11T10:20:13.707Z,1749637213.707 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-11T10:20:14.090Z,1749637214.090 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-11T10:20:14.090Z,1749637214.090 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-11T10:20:14.090Z,1749637214.090 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-11T10:20:14.090Z,1749637214.090 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-11T10:20:14.090Z,1749637214.090 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-11T10:20:14.090Z,1749637214.090 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-11T10:20:14.504Z,1749637214.504 [keepstation:SurfaceComms:setStation] Stopped
2025-06-11T10:20:14.505Z,1749637214.505 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-11T10:20:14.505Z,1749637214.505 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-11T10:20:14.505Z,1749637214.505 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-11T10:20:14.505Z,1749637214.505 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-11T10:20:14.505Z,1749637214.505 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-11T10:20:14.889Z,1749637214.889 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-11T10:20:14.890Z,1749637214.890 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-11T10:20:14.890Z,1749637214.890 [keepstation:SurfaceComms:setSink] Stopped
2025-06-11T10:20:14.890Z,1749637214.890 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-11T10:20:14.890Z,1749637214.890 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-11T10:20:14.890Z,1749637214.890 [keepstation:SurfaceComms] Stopped
2025-06-11T10:20:14.890Z,1749637214.890 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-11T10:20:15.297Z,1749637215.297 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-11T10:20:15.297Z,1749637215.297 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-11T10:20:15.297Z,1749637215.297 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-11T10:20:15.297Z,1749637215.297 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-11T10:20:15.298Z,1749637215.298 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T10:20:15.298Z,1749637215.298 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-11T10:20:15.298Z,1749637215.298 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-11T10:20:15.298Z,1749637215.298 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T10:20:15.298Z,1749637215.298 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T10:20:15.298Z,1749637215.298 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T10:20:15.298Z,1749637215.298 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T10:20:15.299Z,1749637215.299 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-11T10:20:15.299Z,1749637215.299 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-11T10:20:15.299Z,1749637215.299 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-11T10:20:15.299Z,1749637215.299 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-11T10:20:15.299Z,1749637215.299 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-11T10:20:15.300Z,1749637215.300 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-11T10:20:28.205Z,1749637228.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T10:20:37.786Z,1749637237.786 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003165
2025-06-11T10:20:45.192Z,1749637245.192 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T10:21:56.370Z,1749637316.370 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:0.500000m. At depth:1.571791m.
2025-06-11T10:22:31.930Z,1749637351.930 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T10:22:50.109Z,1749637370.109 [Radio_Surface](INFO): Powering down
2025-06-11T10:23:03.053Z,1749637383.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T10:23:04.057Z,1749637384.057 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T10:23:40.930Z,1749637420.930 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-11T10:23:40.931Z,1749637420.931 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-11T10:23:40.931Z,1749637420.931 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T10:23:40.932Z,1749637420.932 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-11T10:23:41.347Z,1749637421.346 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-11T10:23:41.347Z,1749637421.347 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T10:23:41.347Z,1749637421.347 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-11T10:23:41.347Z,1749637421.347 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-11T10:23:41.347Z,1749637421.347 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T10:23:41.347Z,1749637421.347 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-11T10:23:41.755Z,1749637421.755 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-11T10:23:41.755Z,1749637421.755 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T10:23:41.755Z,1749637421.755 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-11T10:23:41.756Z,1749637421.756 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T10:23:41.756Z,1749637421.756 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T10:23:41.756Z,1749637421.756 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-11T10:23:41.756Z,1749637421.756 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T10:23:41.756Z,1749637421.756 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-11T10:23:41.756Z,1749637421.756 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T10:23:41.757Z,1749637421.757 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-11T10:23:41.757Z,1749637421.757 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-11T10:23:41.757Z,1749637421.757 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-06-11T10:23:42.145Z,1749637422.145 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-11T10:23:42.145Z,1749637422.145 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-11T10:23:42.146Z,1749637422.146 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-11T10:23:42.146Z,1749637422.146 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-11T10:23:42.146Z,1749637422.146 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-11T10:23:42.146Z,1749637422.146 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-11T10:23:42.146Z,1749637422.146 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-11T10:23:42.147Z,1749637422.147 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-06-11T10:24:08.313Z,1749637448.313 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731262
2025-06-11T10:33:10.078Z,1749637990.078 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-06-11T10:33:10.475Z,1749637990.475 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.28 mm.
2025-06-11T10:34:11.098Z,1749638051.098 [DVL_micro](ERROR): Failed to parse:
:SA,+19.53,+01.73,036.2
2025-06-11T10:44:46.567Z,1749638686.567 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-11T10:44:46.568Z,1749638686.568 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-11T10:44:46.571Z,1749638686.571 [BPC1](INFO): Received data from all battery sticks.
2025-06-11T10:56:20.636Z,1749639380.636 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+10.1,0000.0,1489.0,,+00018,+00068,+00005,+00000,A
2025-06-11T10:56:20.637Z,1749639380.637 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+10.1,0000.0,1489.0,,+00018,+00068,+00005,+00000,A
2025-06-11T11:20:13.637Z,1749640813.637 [keepstation:SurfaceComms] Running Loop=1
2025-06-11T11:20:13.637Z,1749640813.637 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-06-11T11:20:13.638Z,1749640813.638 [keepstation:SurfaceComms:A] Running Loop=1
2025-06-11T11:20:13.638Z,1749640813.638 [keepstation:SurfaceComms:A] Stopped
2025-06-11T11:20:13.638Z,1749640813.638 [keepstation:SurfaceComms:B] Running Loop=1
2025-06-11T11:20:13.638Z,1749640813.638 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-06-11T11:20:13.640Z,1749640813.640 [keepstation:NeedComms] Running Loop=1
2025-06-11T11:20:13.640Z,1749640813.640 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-06-11T11:20:13.640Z,1749640813.640 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-06-11T11:20:13.640Z,1749640813.640 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-11T11:20:13.641Z,1749640813.641 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-06-11T11:20:13.641Z,1749640813.641 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-06-11T11:20:13.642Z,1749640813.642 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-06-11T11:20:13.642Z,1749640813.642 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-06-11T11:20:13.642Z,1749640813.642 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-11T11:20:13.643Z,1749640813.643 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-11T11:20:13.643Z,1749640813.643 [keepstation:NeedComms:A] Running Loop=1
2025-06-11T11:20:13.644Z,1749640813.644 [keepstation:NeedComms:A](INFO): last time_fix was:  1749637208.000000 second since 1970/01/01T00:00:00Z 
2025-06-11T11:20:13.644Z,1749640813.644 [keepstation:NeedComms:A] Stopped
2025-06-11T11:20:14.043Z,1749640814.043 [keepstation:KeepStation:Dive] Stopped
2025-06-11T11:20:14.043Z,1749640814.043 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-06-11T11:20:14.043Z,1749640814.043 [keepstation:KeepStation:Dive:A] Stopped
2025-06-11T11:20:14.043Z,1749640814.043 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-06-11T11:20:14.044Z,1749640814.044 [keepstation:DiveCmd:StationKeep] Stopped
2025-06-11T11:20:14.044Z,1749640814.044 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-06-11T11:20:14.044Z,1749640814.044 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-06-11T11:20:14.044Z,1749640814.044 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-06-11T11:20:14.044Z,1749640814.044 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-06-11T11:20:14.044Z,1749640814.044 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-06-11T11:21:30.979Z,1749640890.979 [keepstation:NeedComms:C] Running Loop=1
2025-06-11T11:21:31.433Z,1749640891.433 [Radio_Surface](INFO): Powering up
2025-06-11T11:21:37.638Z,1749640897.638 [DataOverHttps](INFO): Radio surface powered ON.
2025-06-11T11:22:07.757Z,1749640927.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T11:22:10.954Z,1749640930.954 [NAL9602](INFO): SBD MO Status=0, MOMSN=21590, MT Status=0, MTMSN=0
2025-06-11T11:22:10.954Z,1749640930.954 [NAL9602](INFO): No messages in MT queue
2025-06-11T11:22:12.172Z,1749640932.172 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112206.00,A,3645.27829,N,12152.62758,W,0.389,88.91,110625,,,D*4D
2025-06-11T11:22:12.175Z,1749640932.175 [NAL9602](INFO): GPS fix at 20250611T112206: (36.754638, -121.877126)
2025-06-11T11:22:12.187Z,1749640932.187 [UniversalFixResidualReporter](INFO): Fix residual: 2.6 %DT, over the last 1085.2 m. Residual distance 28.2 m at bearing -63.8 degrees. Fix at (36.7546, -121.8771) with 440.7 m made good.
2025-06-11T11:22:12.190Z,1749640932.190 [keepstation:NeedComms:C] Stopped
2025-06-11T11:22:12.191Z,1749640932.191 [keepstation:NeedComms:D] Running Loop=1
2025-06-11T11:22:22.358Z,1749640942.358 [NAL9602](INFO): SBD MO Status=1, MOMSN=21591, MT Status=0, MTMSN=0
2025-06-11T11:22:22.409Z,1749640942.409 [NAL9602](INFO): Sent 99 bytes from file Logs/20250611T040000/Courier0021.lzma
2025-06-11T11:22:22.409Z,1749640942.409 [NAL9602](INFO): Packets left to send: 0
2025-06-11T11:22:33.292Z,1749640953.292 [NAL9602](INFO): SBD MO Status=1, MOMSN=21592, MT Status=0, MTMSN=0
2025-06-11T11:22:33.349Z,1749640953.349 [NAL9602](INFO): Sent 332 bytes from file Logs/20250611T040000/Express0022.lzma
2025-06-11T11:22:33.349Z,1749640953.349 [NAL9602](INFO): Packets left to send: 1
2025-06-11T11:22:38.881Z,1749640958.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T11:22:45.301Z,1749640965.301 [NAL9602](INFO): SBD MO Status=1, MOMSN=21593, MT Status=0, MTMSN=0
2025-06-11T11:22:45.353Z,1749640965.353 [NAL9602](INFO): Sent 270 bytes from file Logs/20250611T040000/Express0022.lzma
2025-06-11T11:22:45.354Z,1749640965.354 [NAL9602](INFO): Packets left to send: 0
2025-06-11T11:22:54.490Z,1749640974.490 [NAL9602](INFO): SBD MO Status=0, MOMSN=21594, MT Status=0, MTMSN=0
2025-06-11T11:22:54.578Z,1749640974.578 [keepstation:NeedComms:D] Stopped
2025-06-11T11:22:54.578Z,1749640974.578 [keepstation:NeedComms:E] Running Loop=1
2025-06-11T11:22:56.919Z,1749640976.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112251.00,A,3645.27807,N,12152.62559,W,0.641,244.54,110625,,,A*7F
2025-06-11T11:22:56.933Z,1749640976.933 [NAL9602](INFO): GPS fix at 20250611T112251: (36.754635, -121.877093)
2025-06-11T11:22:56.947Z,1749640976.947 [keepstation:NeedComms:E] Stopped
2025-06-11T11:22:56.948Z,1749640976.948 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-06-11T11:22:56.948Z,1749640976.948 [keepstation:NeedComms] Stopped
2025-06-11T11:22:56.948Z,1749640976.948 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-06-11T11:22:56.948Z,1749640976.948 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-06-11T11:22:56.948Z,1749640976.948 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T11:22:57.358Z,1749640977.358 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-06-11T11:22:57.358Z,1749640977.358 [keepstation:SurfaceComms:B] Stopped
2025-06-11T11:22:57.358Z,1749640977.358 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-06-11T11:22:57.358Z,1749640977.358 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-06-11T11:22:57.358Z,1749640977.358 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-06-11T11:22:57.358Z,1749640977.358 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-06-11T11:22:57.746Z,1749640977.746 [keepstation:SurfaceComms:setTransit] Stopped
2025-06-11T11:22:57.746Z,1749640977.746 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-06-11T11:22:57.746Z,1749640977.746 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-06-11T11:22:57.747Z,1749640977.747 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-06-11T11:22:57.747Z,1749640977.747 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-06-11T11:22:57.747Z,1749640977.747 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-06-11T11:22:58.146Z,1749640978.146 [keepstation:SurfaceComms:setStation] Stopped
2025-06-11T11:22:58.146Z,1749640978.146 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-06-11T11:22:58.146Z,1749640978.146 [keepstation:SurfaceComms:setStation:A] Stopped
2025-06-11T11:22:58.146Z,1749640978.146 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-06-11T11:22:58.147Z,1749640978.147 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-06-11T11:22:58.147Z,1749640978.147 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-06-11T11:22:58.559Z,1749640978.559 [keepstation:SurfaceComms:setSink:A] Stopped
2025-06-11T11:22:58.559Z,1749640978.559 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-06-11T11:22:58.560Z,1749640978.560 [keepstation:SurfaceComms:setSink] Stopped
2025-06-11T11:22:58.560Z,1749640978.560 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-06-11T11:22:58.560Z,1749640978.560 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-06-11T11:22:58.560Z,1749640978.560 [keepstation:SurfaceComms] Stopped
2025-06-11T11:22:58.560Z,1749640978.560 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-06-11T11:22:58.949Z,1749640978.949 [keepstation:KeepStation:Dive] Running Loop=1
2025-06-11T11:22:58.949Z,1749640978.949 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-06-11T11:22:58.949Z,1749640978.949 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-06-11T11:22:58.949Z,1749640978.949 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-06-11T11:22:58.950Z,1749640978.950 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-06-11T11:22:58.950Z,1749640978.950 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-06-11T11:22:58.950Z,1749640978.950 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-06-11T11:22:58.950Z,1749640978.950 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T11:22:58.950Z,1749640978.950 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-06-11T11:22:58.950Z,1749640978.950 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-06-11T11:22:58.950Z,1749640978.950 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-06-11T11:22:58.951Z,1749640978.951 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-06-11T11:22:58.951Z,1749640978.951 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-06-11T11:22:58.951Z,1749640978.951 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-06-11T11:22:58.951Z,1749640978.951 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-06-11T11:22:58.951Z,1749640978.951 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-06-11T11:22:58.951Z,1749640978.951 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-06-11T11:23:10.009Z,1749640990.009 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T11:23:29.237Z,1749641009.237 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T11:23:41.142Z,1749641021.142 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T11:23:49.954Z,1749641029.954 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003190
2025-06-11T11:25:13.865Z,1749641113.865 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T11:25:44.993Z,1749641144.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T11:26:12.661Z,1749641172.661 [Radio_Surface](INFO): Powering down
2025-06-11T11:26:16.135Z,1749641176.135 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T11:26:17.137Z,1749641177.137 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-06-11T11:26:52.075Z,1749641212.075 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-06-11T11:26:52.075Z,1749641212.075 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-06-11T11:26:52.075Z,1749641212.075 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T11:26:52.075Z,1749641212.075 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-06-11T11:26:52.474Z,1749641212.474 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-06-11T11:26:52.475Z,1749641212.475 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-06-11T11:26:52.475Z,1749641212.475 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-06-11T11:26:52.475Z,1749641212.475 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-06-11T11:26:52.475Z,1749641212.475 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T11:26:52.475Z,1749641212.475 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-06-11T11:26:52.865Z,1749641212.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-06-11T11:26:52.865Z,1749641212.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T11:26:52.865Z,1749641212.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-06-11T11:26:52.865Z,1749641212.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-06-11T11:26:52.866Z,1749641212.866 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-06-11T11:26:53.274Z,1749641213.274 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-06-11T11:26:53.274Z,1749641213.274 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-06-11T11:26:53.274Z,1749641213.274 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-06-11T11:26:53.274Z,1749641213.274 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-06-11T11:26:53.274Z,1749641213.274 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-06-11T11:26:53.275Z,1749641213.275 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-06-11T11:26:53.275Z,1749641213.275 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-06-11T11:27:21.394Z,1749641241.394 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.742828
2025-06-11T11:29:09.868Z,1749641349.868 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,+00114,-00145,-0
2025-06-11T11:29:09.869Z,1749641349.869 [DVL_micro](ERROR): Failed to parse:
:BI,+00114,-00145,-0
2025-06-11T11:29:11.835Z,1749641351.835 [DVL_micro](ERROR): Failed to parse:
:SA,+18.48,+01.90,026.9
2025-06-11T11:39:15.010Z,1749641955.010 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000000000,+00044,+00000,A
2025-06-11T11:39:15.010Z,1749641955.010 [DVL_micro](ERROR): Failed to parse:
:TS,000000000,+00044,+00000,A
2025-06-11T11:42:37.826Z,1749642157.826 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-06-11T11:42:38.279Z,1749642158.279 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 31.28 mm (1 active estimators).
2025-06-11T11:46:35.895Z,1749642395.895 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0055.60,000.00
2025-06-11T11:51:59.146Z,1749642719.146 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-06-11T11:52:25.384Z,1749642745.384 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-11T11:52:25.385Z,1749642745.385 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-06-11T11:52:25.387Z,1749642745.387 [BPC1](INFO): Received data from all battery sticks.
2025-06-11T12:05:30.372Z,1749643530.372 [DVL_micro](ERROR): Failed to parse:
:WI,-00017,+00129,+00018,+00000,A
2025-06-11T12:05:32.394Z,1749643532.394 [DVL_micro](ERROR): Failed to parse:
:WI,+00014,+00018,+00014,+00000,A
2025-06-11T12:05:34.413Z,1749643534.413 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0053.40,000.00
2025-06-11T12:05:36.443Z,1749643536.443 [DVL_micro](ERROR): Failed to parse:
:RD,+0073.14,+0064.43,+0057.62,+0065.08
2025-06-11T12:05:38.449Z,1749643538.449 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-06-11T12:05:38.450Z,1749643538.450 [DVL_micro] Communications Fault, FailCount= 1
2025-06-11T12:05:38.450Z,1749643538.450 [DVL_micro](ERROR): Communications Fault
2025-06-11T12:05:38.494Z,1749643538.494 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-06-11T12:05:38.945Z,1749643538.945 [DVL_micro](INFO): Powering down
2025-06-11T12:05:39.682Z,1749643539.682 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-06-11T12:05:39.682Z,1749643539.682 [DVL_micro] No Fault, FailCount= 1