2025-09-11T03:30:00.448Z,1757561400.448 [CommandExec](IMPORTANT): got command restart logs
2025-09-11T03:30:11.944Z,1757561411.944 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist
2025-09-11T03:30:11.990Z,1757561411.990 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-09-11T03:30:11.994Z,1757561411.994 [MissionManager](INFO): Uninitializing Mission profile_station
2025-09-11T03:30:11.998Z,1757561411.998 [profile_station] Stopped
2025-09-11T03:30:11.998Z,1757561411.998 [profile_station](DEBUG): Aggregate::uninitialize profile_station
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science](DEBUG): Aggregate::uninitialize profile_station:Science
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:B] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:C] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:E] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:OceanCurrent] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:OceanCurrent](DEBUG): Aggregate::uninitialize profile_station:Science:OceanCurrent
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:OceanCurrent:A.] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:PeakDetectChl] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize profile_station:Science:PeakDetectChl
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth] Stopped
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Uninitialize.
2025-09-11T03:30:11.999Z,1757561411.999 [profile_station:StandardEnvelopes] Stopped
2025-09-11T03:30:11.000Z,1757561412.000 [profile_station:StandardEnvelopes](DEBUG): Aggregate::uninitialize profile_station:StandardEnvelopes
2025-09-11T03:30:11.000Z,1757561412.000 [profile_station:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-09-11T03:30:11.000Z,1757561412.000 [profile_station:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-09-11T03:30:11.000Z,1757561412.000 [profile_station:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-09-11T03:30:11.000Z,1757561412.000 [profile_station:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-09-11T03:30:11.000Z,1757561412.000 [profile_station:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-09-11T03:30:12.000Z,1757561412.000 [profile_station:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-09-11T03:30:12.000Z,1757561412.000 [profile_station:BackseatDriver] Stopped
2025-09-11T03:30:12.000Z,1757561412.000 [profile_station:BackseatDriver](DEBUG): Aggregate::uninitialize profile_station:BackseatDriver
2025-09-11T03:30:12.000Z,1757561412.000 [profile_station:BackseatDriver:A.BackseatDriver] Stopped
2025-09-11T03:30:12.000Z,1757561412.000 [profile_station:PowerOnly] Stopped
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:PowerOnly](DEBUG): Aggregate::uninitialize profile_station:PowerOnly
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:PowerOnly:E.Wait] Stopped
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd] Stopped
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd](DEBUG): Aggregate::uninitialize profile_station:DiveCmd
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd:SurfaceSink] Stopped
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:SurfaceSink
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd:YoYoDive] Stopped
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd:YoYoDive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd:YoYoDive:dive] Stopped
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd:YoYoDive:dive](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive
2025-09-11T03:30:12.001Z,1757561412.001 [profile_station:DiveCmd:YoYoDive:dive:A.Pitch] Stopped
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy] Stopped
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed] Stopped
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed] Stopped
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed](DEBUG): Aggregate::uninitialize profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed] Stopped
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:SetSpeed:ProfileSpeed:A.SetSpeed](DEBUG): Uninitialize.
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope] Stopped
2025-09-11T03:30:12.002Z,1757561412.002 [profile_station:DiveCmd:YoYoDive:dive:D.DepthEnvelope](DEBUG): Uninitialize.
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope] Stopped
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:DiveCmd:YoYoDive:dive:E.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo] Stopped
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:DiveCmd:YoYoDive:dive:F.YoYo](DEBUG): Uninitialize YoYoComponent.
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper] Stopped
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper:A] Stopped
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper:Dive] Stopped
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper:Dive](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper:Dive:A] Stopped
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper:Dive:A](DEBUG): Aggregate::uninitialize profile_station:CircleWrapper:Dive:A
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper:C.Circle] Stopped
2025-09-11T03:30:12.003Z,1757561412.003 [profile_station:CircleWrapper:C.Circle](DEBUG): Uninitialize.
2025-09-11T03:30:12.084Z,1757561412.084 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-09-11T03:30:12.084Z,1757561412.084 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-09-11T03:30:12.219Z,1757561412.219 [WetLabsBB2FL](INFO): Powering down
2025-09-11T03:30:12.272Z,1757561412.272 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-09-11T03:30:12.272Z,1757561412.272 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-09-11T03:30:12.300Z,1757561412.300 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-09-11T03:30:14.771Z,1757561414.771 [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-09-11T03:30:14.795Z,1757561414.795 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-09-11T03:30:14.797Z,1757561414.797 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-09-11T03:30:14.803Z,1757561414.803 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-09-11T03:30:14.806Z,1757561414.806 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-09-11T03:30:14.812Z,1757561414.812 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-09-11T03:30:14.818Z,1757561414.818 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-09-11T03:30:14.821Z,1757561414.821 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-09-11T03:30:14.827Z,1757561414.827 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-09-11T03:30:14.829Z,1757561414.829 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-09-11T03:30:14.832Z,1757561414.832 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-09-11T03:30:14.875Z,1757561414.875 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-09-11T03:30:14.877Z,1757561414.877 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-09-11T03:30:14.887Z,1757561414.887 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-09-11T03:30:14.889Z,1757561414.889 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-09-11T03:30:14.911Z,1757561414.911 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-09-11T03:30:14.913Z,1757561414.913 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-09-11T03:30:14.920Z,1757561414.920 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-09-11T03:30:14.927Z,1757561414.927 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-09-11T03:30:14.941Z,1757561414.941 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-09-11T03:30:14.948Z,1757561414.948 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-09-11T03:30:14.948Z,1757561414.948 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-09-11T03:30:14.949Z,1757561414.949 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-09-11T03:30:14.950Z,1757561414.950 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-09-11T03:30:15.491Z,1757561415.491 [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-09-11T03:30:15.500Z,1757561415.500 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-09-11T03:30:15.511Z,1757561415.511 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-09-11T03:30:15.513Z,1757561415.513 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-09-11T03:30:15.515Z,1757561415.515 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-09-11T03:30:15.518Z,1757561415.518 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-09-11T03:30:15.528Z,1757561415.528 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-09-11T03:30:15.530Z,1757561415.530 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-09-11T03:30:15.536Z,1757561415.536 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-09-11T03:30:15.547Z,1757561415.547 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-11T03:30:15.587Z,1757561415.587 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-09-11T03:30:15.587Z,1757561415.587 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-09-11T03:30:15.588Z,1757561415.588 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-09-11T03:30:15.798Z,1757561415.798 [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-09-11T03:30:15.805Z,1757561415.805 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-09-11T03:30:15.816Z,1757561415.816 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-09-11T03:30:15.818Z,1757561415.818 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-09-11T03:30:15.820Z,1757561415.820 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-09-11T03:30:15.821Z,1757561415.821 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-09-11T03:30:15.836Z,1757561415.836 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-09-11T03:30:15.886Z,1757561415.886 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-09-11T03:30:15.913Z,1757561415.913 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-09-11T03:30:15.913Z,1757561415.913 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-09-11T03:30:15.914Z,1757561415.914 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-09-11T03:30:15.949Z,1757561415.949 [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-09-11T03:30:15.961Z,1757561415.961 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-09-11T03:30:15.967Z,1757561415.967 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-09-11T03:30:15.969Z,1757561415.969 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-09-11T03:30:15.969Z,1757561415.969 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-09-11T03:30:15.976Z,1757561415.976 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-09-11T03:30:16.363Z,1757561416.363 [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-09-11T03:30:16.371Z,1757561416.371 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-09-11T03:30:16.373Z,1757561416.373 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-09-11T03:30:16.379Z,1757561416.379 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-09-11T03:30:16.381Z,1757561416.381 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-09-11T03:30:16.384Z,1757561416.384 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-09-11T03:30:16.402Z,1757561416.402 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-09-11T03:30:16.404Z,1757561416.404 [keepstation:H.Pitch](DEBUG): Construct.
2025-09-11T03:30:16.492Z,1757561416.492 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-09-11T03:30:16.505Z,1757561416.505 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-09-11T03:30:16.528Z,1757561416.528 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-09-11T03:30:16.602Z,1757561416.602 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-09-11T03:30:16.605Z,1757561416.605 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-09-11T03:30:16.615Z,1757561416.615 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-09-11T03:30:16.641Z,1757561416.641 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-09-11T03:30:16.649Z,1757561416.649 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-09-11T03:30:16.677Z,1757561416.677 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-09-11T03:30:16.701Z,1757561416.701 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-09-11T03:30:16.745Z,1757561416.745 [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-09-11T03:30:16.745Z,1757561416.745 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-09-11T03:30:16.946Z,1757561416.946 [MissionManager](IMPORTANT): Started mission Default
2025-09-11T03:30:16.946Z,1757561416.946 [Default] Running Loop=1
2025-09-11T03:30:16.946Z,1757561416.946 [Default](DEBUG): Aggregate::initialize Default
2025-09-11T03:30:16.950Z,1757561416.950 [Default:B.GoToSurface] Running Loop=1
2025-09-11T03:30:16.950Z,1757561416.950 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T03:30:16.951Z,1757561416.951 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-11T03:30:16.951Z,1757561416.951 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-11T03:30:16.951Z,1757561416.951 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-11T03:30:16.952Z,1757561416.952 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-11T03:30:16.952Z,1757561416.952 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T03:30:16.952Z,1757561416.952 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T03:30:16.953Z,1757561416.953 [Default:A.Wait] Running Loop=1
2025-09-11T03:30:16.953Z,1757561416.953 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-09-11T03:30:17.307Z,1757561417.307 [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-09-11T03:30:17.531Z,1757561417.531 [Radio_Surface](INFO): Powering up
2025-09-11T03:30:22.927Z,1757561422.927 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T03:30:30.071Z,1757561430.071 [Default:A.Wait](INFO): Done Waiting.
2025-09-11T03:30:30.071Z,1757561430.071 [Default:A.Wait] Stopped
2025-09-11T03:30:30.071Z,1757561430.071 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-09-11T03:30:30.239Z,1757561430.239 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-09-11T03:30:30.239Z,1757561430.239 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-09-11T03:30:30.240Z,1757561430.240 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-09-11T03:30:45.192Z,1757561445.192 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour
2025-09-11T03:30:45.193Z,1757561445.193 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute
2025-09-11T03:30:45.193Z,1757561445.193 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter
2025-09-11T03:30:45.194Z,1757561445.194 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second
2025-09-11T03:30:45.195Z,1757561445.195 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool
2025-09-11T03:30:53.059Z,1757561453.059 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:30:53.859Z,1757561453.859 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute
2025-09-11T03:30:53.860Z,1757561453.860 [CommandExec](IMPORTANT): got command run
2025-09-11T03:30:53.862Z,1757561453.862 [CommandExec](IMPORTANT): Running 
2025-09-11T03:30:54.113Z,1757561454.113 [Default] Stopped
2025-09-11T03:30:54.113Z,1757561454.113 [Default](DEBUG): Aggregate::uninitialize Default
2025-09-11T03:30:54.113Z,1757561454.113 [Default:B.GoToSurface] Stopped
2025-09-11T03:30:54.113Z,1757561454.113 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T03:30:54.113Z,1757561454.113 [MissionManager](IMPORTANT): Started mission keepstation
2025-09-11T03:30:54.114Z,1757561454.114 [keepstation] Running Loop=1
2025-09-11T03:30:54.114Z,1757561454.114 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-09-11T03:30:54.114Z,1757561454.114 [keepstation:StandardEnvelopes] Running Loop=1
2025-09-11T03:30:54.114Z,1757561454.114 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-09-11T03:30:54.114Z,1757561454.114 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-09-11T03:30:54.114Z,1757561454.114 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:BackseatDriver] Running Loop=1
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:PowerOnly] Running Loop=1
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-09-11T03:30:54.115Z,1757561454.115 [keepstation:PowerOnly:A] Running Loop=1
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:PowerOnly:B] Running Loop=1
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:PowerOnly:C] Running Loop=1
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:PowerOnly:D] Running Loop=1
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:H.Pitch] Running Loop=1
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:H.Pitch](DEBUG): Initialize.
2025-09-11T03:30:54.116Z,1757561454.116 [keepstation:B] Running Loop=1
2025-09-11T03:30:54.117Z,1757561454.117 [keepstation:H.Pitch] Running Loop=1
2025-09-11T03:30:54.117Z,1757561454.117 [keepstation:PowerOnly] Running Loop=1
2025-09-11T03:30:54.118Z,1757561454.118 [keepstation:PowerOnly:D] Stopped
2025-09-11T03:30:54.118Z,1757561454.118 [keepstation:PowerOnly:C] Stopped
2025-09-11T03:30:54.118Z,1757561454.118 [keepstation:PowerOnly:B] Stopped
2025-09-11T03:30:54.118Z,1757561454.118 [keepstation:PowerOnly:A] Stopped
2025-09-11T03:30:54.118Z,1757561454.118 [keepstation:BackseatDriver] Running Loop=1
2025-09-11T03:30:54.118Z,1757561454.118 [keepstation:StandardEnvelopes] Running Loop=1
2025-09-11T03:30:54.118Z,1757561454.118 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-09-11T03:30:54.119Z,1757561454.119 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-09-11T03:30:54.119Z,1757561454.119 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-09-11T03:30:54.120Z,1757561454.120 [keepstation:B] Stopped
2025-09-11T03:30:54.120Z,1757561454.120 [keepstation:D] Running Loop=1
2025-09-11T03:30:54.556Z,1757561454.556 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.83 mm (4 active estimators).
2025-09-11T03:30:54.598Z,1757561454.598 [keepstation:D] Stopped
2025-09-11T03:30:54.598Z,1757561454.598 [keepstation:E] Running Loop=1
2025-09-11T03:30:55.102Z,1757561455.102 [keepstation:E] Stopped
2025-09-11T03:30:55.102Z,1757561455.102 [keepstation:StartingMission] Running Loop=1
2025-09-11T03:30:55.102Z,1757561455.102 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-09-11T03:30:55.446Z,1757561455.446 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T03:30:55.446Z,1757561455.446 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T03:30:55.462Z,1757561455.462 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T03:30:55.463Z,1757561455.463 [keepstation:SurfaceComms:A] Stopped
2025-09-11T03:30:55.463Z,1757561455.463 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T03:30:55.463Z,1757561455.463 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T03:30:55.856Z,1757561455.856 [keepstation:NeedComms] Running Loop=1
2025-09-11T03:30:55.856Z,1757561455.856 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T03:30:55.856Z,1757561455.856 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T03:30:55.856Z,1757561455.856 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T03:30:55.857Z,1757561455.857 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T03:30:55.857Z,1757561455.857 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T03:30:55.857Z,1757561455.857 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T03:30:55.858Z,1757561455.858 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T03:30:55.858Z,1757561455.858 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T03:30:55.858Z,1757561455.858 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T03:30:55.859Z,1757561455.859 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T03:30:55.860Z,1757561455.860 [keepstation:NeedComms:A](INFO): last time_fix was:  1757559287.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T03:30:55.860Z,1757561455.860 [keepstation:NeedComms:A] Stopped
2025-09-11T03:31:20.770Z,1757561480.770 [DVL_micro](ERROR): Failed to parse:
:WI,-00198,-00028,-00159,+00000,A
2025-09-11T03:31:24.183Z,1757561484.183 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:31:55.307Z,1757561515.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:31:56.107Z,1757561516.107 [Radio_Surface](INFO): Powering down
2025-09-11T03:31:56.311Z,1757561516.311 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T03:31:58.654Z,1757561518.654 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T03:31:59.053Z,1757561519.053 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-11T03:32:02.167Z,1757561522.167 [Radio_Surface](INFO): Powering up
2025-09-11T03:32:08.359Z,1757561528.359 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T03:32:26.171Z,1757561546.171 [NAL9602](INFO): SBD MO Status=2, MOMSN=23166, MT Status=2, MTMSN=0
2025-09-11T03:32:26.171Z,1757561546.171 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T03:32:38.487Z,1757561558.487 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:32:49.208Z,1757561569.208 [NAL9602](INFO): SBD MO Status=2, MOMSN=23166, MT Status=2, MTMSN=0
2025-09-11T03:32:49.208Z,1757561569.208 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T03:33:04.155Z,1757561584.155 [NAL9602](INFO): SBD MO Status=2, MOMSN=23166, MT Status=2, MTMSN=0
2025-09-11T03:33:04.156Z,1757561584.156 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T03:33:09.611Z,1757561589.611 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:33:10.243Z,1757561590.243 [DVL_micro](ERROR): Failed to parse:
:SA,-01.67,-05.82,234.8
2025-09-11T03:33:40.747Z,1757561620.747 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:34:11.871Z,1757561651.871 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:34:24.944Z,1757561664.944 [NAL9602](INFO): SBD MO Status=2, MOMSN=23166, MT Status=2, MTMSN=0
2025-09-11T03:34:24.944Z,1757561664.944 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T03:34:30.436Z,1757561670.436 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004319
2025-09-11T03:34:42.317Z,1757561682.317 [NAL9602](INFO): SBD MO Status=2, MOMSN=23166, MT Status=2, MTMSN=0
2025-09-11T03:34:42.318Z,1757561682.318 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T03:34:59.693Z,1757561699.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=23166, MT Status=2, MTMSN=0
2025-09-11T03:34:59.693Z,1757561699.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T03:35:09.815Z,1757561709.815 [DVL_micro](ERROR): Failed to parse:
:SA,-01.52,-02.45,237.7
2025-09-11T03:37:02.511Z,1757561822.511 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T03:37:06.153Z,1757561826.153 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033705.00,A,3646.63426,N,12150.77768,W,0.836,100.59,110925,,,D*79
2025-09-11T03:37:06.155Z,1757561826.155 [NAL9602](INFO): GPS fix at 20250911T033705: (36.777238, -121.846295)
2025-09-11T03:37:06.169Z,1757561826.169 [UniversalFixResidualReporter](INFO): Fix residual: 1.7 %DT, over the last 1459.3 m. Residual distance 25.5 m at bearing -146.6 degrees. Fix at (36.7772, -121.8463) with 725.4 m made good.
2025-09-11T03:37:06.172Z,1757561826.172 [keepstation:NeedComms:C] Stopped
2025-09-11T03:37:06.172Z,1757561826.172 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T03:37:06.589Z,1757561826.589 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-11T03:37:14.130Z,1757561834.130 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250910T130135/Courier0060.lzma
2025-09-11T03:37:25.172Z,1757561845.172 [DataOverHttps](INFO): Moved sent file to Logs/20250910T130135/Courier0060.lzma.bak
2025-09-11T03:37:25.173Z,1757561845.173 [DataOverHttps](INFO): SBD MOMSN=25962380
2025-09-11T03:37:38.871Z,1757561858.871 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T03:37:51.531Z,1757561871.531 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20250911T033000/Courier0000.lzma
2025-09-11T03:38:02.572Z,1757561882.572 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0000.lzma.bak
2025-09-11T03:38:02.572Z,1757561882.572 [DataOverHttps](INFO): SBD MOMSN=25962383
2025-09-11T03:38:28.880Z,1757561908.880 [DataOverHttps](INFO): Sending 1035 bytes from file Logs/20250910T130135/Express0061.lzma
2025-09-11T03:38:39.920Z,1757561919.920 [DataOverHttps](INFO): Moved sent file to Logs/20250910T130135/Express0061.lzma.bak
2025-09-11T03:38:39.920Z,1757561919.920 [DataOverHttps](INFO): SBD MOMSN=25962387
2025-09-11T03:39:06.241Z,1757561946.241 [DataOverHttps](INFO): Sending 1103 bytes from file Logs/20250911T033000/Express0001.lzma
2025-09-11T03:39:17.280Z,1757561957.280 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0001.lzma.bak
2025-09-11T03:39:17.280Z,1757561957.280 [DataOverHttps](INFO): SBD MOMSN=25962392
2025-09-11T03:39:18.688Z,1757561958.688 [keepstation:NeedComms:D] Stopped
2025-09-11T03:39:18.688Z,1757561958.688 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T03:39:19.105Z,1757561959.105 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-11T03:39:20.682Z,1757561960.682 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033919.00,A,3646.61780,N,12150.76700,W,0.875,154.93,110925,,,D*78
2025-09-11T03:39:20.684Z,1757561960.684 [NAL9602](INFO): GPS fix at 20250911T033919: (36.776963, -121.846117)
2025-09-11T03:39:20.726Z,1757561960.726 [keepstation:NeedComms:E] Stopped
2025-09-11T03:39:20.736Z,1757561960.736 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T03:39:20.736Z,1757561960.736 [keepstation:NeedComms] Stopped
2025-09-11T03:39:20.736Z,1757561960.736 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T03:39:20.736Z,1757561960.736 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T03:39:20.736Z,1757561960.736 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T03:39:21.152Z,1757561961.152 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T03:39:21.153Z,1757561961.153 [keepstation:SurfaceComms:B] Stopped
2025-09-11T03:39:21.153Z,1757561961.153 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T03:39:21.153Z,1757561961.153 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T03:39:21.153Z,1757561961.153 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T03:39:21.153Z,1757561961.153 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T03:39:21.507Z,1757561961.507 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 30.83 mm.
2025-09-11T03:39:21.508Z,1757561961.508 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm.
2025-09-11T03:39:21.517Z,1757561961.517 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T03:39:21.517Z,1757561961.517 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T03:39:21.517Z,1757561961.517 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T03:39:21.517Z,1757561961.517 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T03:39:21.517Z,1757561961.517 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T03:39:21.518Z,1757561961.518 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T03:39:21.952Z,1757561961.952 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T03:39:21.952Z,1757561961.952 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T03:39:21.952Z,1757561961.952 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T03:39:21.952Z,1757561961.952 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T03:39:21.952Z,1757561961.952 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T03:39:21.952Z,1757561961.952 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T03:39:22.315Z,1757561962.315 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T03:39:22.316Z,1757561962.316 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T03:39:22.316Z,1757561962.316 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T03:39:22.316Z,1757561962.316 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T03:39:22.316Z,1757561962.316 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T03:39:22.316Z,1757561962.316 [keepstation:SurfaceComms] Stopped
2025-09-11T03:39:22.316Z,1757561962.316 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T03:39:22.720Z,1757561962.720 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-09-11T03:39:22.720Z,1757561962.720 [keepstation:StartingMission] Stopped
2025-09-11T03:39:22.720Z,1757561962.720 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-09-11T03:39:22.720Z,1757561962.720 [keepstation:TransitToStation] Running Loop=1
2025-09-11T03:39:22.720Z,1757561962.720 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-09-11T03:39:22.721Z,1757561962.721 [keepstation:TransitToStation:Dive] Running Loop=1
2025-09-11T03:39:22.721Z,1757561962.721 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-09-11T03:39:22.721Z,1757561962.721 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-09-11T03:39:22.721Z,1757561962.721 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-09-11T03:39:22.721Z,1757561962.721 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-09-11T03:39:22.721Z,1757561962.721 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:TransitToStation:C] Running Loop=1
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:TransitToStation:Dive] Running Loop=1
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:DiveCmd] Running Loop=1
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T03:39:23.147Z,1757561963.147 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-09-11T03:39:23.148Z,1757561963.148 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T03:39:23.149Z,1757561963.149 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T03:39:23.150Z,1757561963.150 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T03:39:23.150Z,1757561963.150 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T03:39:23.150Z,1757561963.150 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T03:39:23.150Z,1757561963.150 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T03:39:23.150Z,1757561963.150 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:TransitToStation] Stopped
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-09-11T03:39:23.155Z,1757561963.155 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T03:39:23.156Z,1757561963.156 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T03:39:23.157Z,1757561963.157 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T03:39:23.157Z,1757561963.157 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T03:39:23.157Z,1757561963.157 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T03:39:23.157Z,1757561963.157 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T03:39:23.157Z,1757561963.157 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T03:39:23.552Z,1757561963.552 [keepstation:TransitToStation:C] Stopped
2025-09-11T03:39:23.552Z,1757561963.552 [keepstation:TransitToStation:D] Running Loop=1
2025-09-11T03:39:23.552Z,1757561963.552 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T03:39:23.552Z,1757561963.552 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T03:39:23.552Z,1757561963.552 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T03:39:23.552Z,1757561963.552 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T03:39:23.553Z,1757561963.553 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T03:39:23.553Z,1757561963.553 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T03:39:23.553Z,1757561963.553 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T03:39:23.553Z,1757561963.553 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T03:39:23.553Z,1757561963.553 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T03:39:23.554Z,1757561963.554 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T03:39:23.558Z,1757561963.558 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T03:39:23.559Z,1757561963.559 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T03:39:23.951Z,1757561963.951 [keepstation:TransitToStation:D] Stopped
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:TransitToStation] Stopped
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:TransitToStation:Dive] Stopped
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:TransitToStation:Dive:A] Stopped
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:OnStation] Running Loop=1
2025-09-11T03:39:23.952Z,1757561963.952 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-09-11T03:39:24.346Z,1757561964.346 [keepstation:NeedComms] Running Loop=1
2025-09-11T03:39:24.346Z,1757561964.346 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T03:39:24.346Z,1757561964.346 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T03:39:24.346Z,1757561964.346 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T03:39:24.351Z,1757561964.351 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T03:39:24.351Z,1757561964.351 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T03:39:24.351Z,1757561964.351 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T03:39:24.352Z,1757561964.352 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T03:39:24.352Z,1757561964.352 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T03:39:24.352Z,1757561964.352 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T03:39:24.353Z,1757561964.353 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T03:39:24.354Z,1757561964.354 [keepstation:NeedComms:A](INFO): last time_fix was:  1757561959.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T03:39:24.354Z,1757561964.354 [keepstation:NeedComms:A] Stopped
2025-09-11T03:39:24.739Z,1757561964.739 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T03:39:51.387Z,1757561991.387 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T03:39:51.470Z,1757561991.470 [NAL9602](ERROR): received: 
+CSQ:0

OK166, 2, 0, 0, 0

OK
2025-09-11T03:40:22.513Z,1757562022.513 [NAL9602](INFO): SBD MO Status=1, MOMSN=23166, MT Status=0, MTMSN=0
2025-09-11T03:40:22.514Z,1757562022.514 [NAL9602](INFO): No messages in MT queue
2025-09-11T03:40:23.706Z,1757562023.706 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034022.00,A,3646.61026,N,12150.76235,W,0.486,151.52,110925,,,D*7E
2025-09-11T03:40:23.708Z,1757562023.708 [NAL9602](INFO): GPS fix at 20250911T034022: (36.776838, -121.846039)
2025-09-11T03:40:23.767Z,1757562023.767 [keepstation:NeedComms:C] Stopped
2025-09-11T03:40:23.767Z,1757562023.767 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T03:40:26.499Z,1757562026.499 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250911T033000/Courier0003.lzma
2025-09-11T03:40:37.540Z,1757562037.540 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0003.lzma.bak
2025-09-11T03:40:37.540Z,1757562037.540 [DataOverHttps](INFO): SBD MOMSN=25962410
2025-09-11T03:40:55.764Z,1757562055.764 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T03:41:04.328Z,1757562064.328 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250911T033000/Express0004.lzma
2025-09-11T03:41:15.368Z,1757562075.368 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0004.lzma.bak
2025-09-11T03:41:15.369Z,1757562075.369 [DataOverHttps](INFO): SBD MOMSN=25962414
2025-09-11T03:41:16.749Z,1757562076.749 [keepstation:NeedComms:D] Stopped
2025-09-11T03:41:16.750Z,1757562076.750 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T03:41:18.734Z,1757562078.734 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034117.00,A,3646.60265,N,12150.75759,W,0.875,152.95,110925,,,D*79
2025-09-11T03:41:18.737Z,1757562078.737 [NAL9602](INFO): GPS fix at 20250911T034117: (36.776711, -121.845960)
2025-09-11T03:41:18.808Z,1757562078.808 [keepstation:NeedComms:E] Stopped
2025-09-11T03:41:18.809Z,1757562078.809 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T03:41:18.809Z,1757562078.809 [keepstation:NeedComms] Stopped
2025-09-11T03:41:18.809Z,1757562078.809 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T03:41:18.809Z,1757562078.809 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T03:41:18.809Z,1757562078.809 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T03:41:19.179Z,1757562079.179 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-09-11T03:41:19.179Z,1757562079.179 [keepstation:OnStation] Stopped
2025-09-11T03:41:19.179Z,1757562079.179 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation] Running Loop=1
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-09-11T03:41:19.180Z,1757562079.180 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-09-11T03:41:19.566Z,1757562079.566 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T03:41:49.465Z,1757562109.465 [NAL9602](INFO): SBD MO Status=1, MOMSN=23167, MT Status=0, MTMSN=0
2025-09-11T03:41:49.465Z,1757562109.465 [NAL9602](INFO): No messages in MT queue
2025-09-11T03:42:20.137Z,1757562140.137 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T03:42:48.463Z,1757562168.463 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-11T03:43:19.587Z,1757562199.587 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:43:44.747Z,1757562224.747 [Radio_Surface](INFO): Powering down
2025-09-11T03:43:50.711Z,1757562230.711 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T03:43:51.715Z,1757562231.715 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T03:45:06.017Z,1757562306.017 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.676727
2025-09-11T03:46:37.493Z,1757562397.493 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T03:46:37.494Z,1757562397.494 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T03:46:37.495Z,1757562397.495 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T03:46:37.496Z,1757562397.496 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T03:46:37.499Z,1757562397.499 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T04:01:27.103Z,1757563287.103 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-09-11T04:01:27.503Z,1757563287.503 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm (3 active estimators).
2025-09-11T04:12:47.227Z,1757563967.227 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-09-11T04:17:21.541Z,1757564241.541 [DVL_micro](ERROR): Failed to parse:
:SA,-03.19,-00.71,269.2
2025-09-11T04:17:23.547Z,1757564243.547 [DVL_micro](ERROR): Failed to parse:
:SA,-03.17,-00.66,269.0
2025-09-11T04:27:22.378Z,1757564842.378 [DVL_micro](ERROR): DVL uart error: serial timeout
2025-09-11T04:27:22.378Z,1757564842.378 [DVL_micro] Communications Fault, FailCount= 1
2025-09-11T04:27:22.378Z,1757564842.378 [DVL_micro](ERROR): Communications Fault
2025-09-11T04:27:22.379Z,1757564842.379 [DVL_micro](ERROR): Failed to parse:
2025-09-11T04:27:22.554Z,1757564842.554 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-09-11T04:27:22.803Z,1757564842.803 [DVL_micro](INFO): Powering down
2025-09-11T04:27:23.702Z,1757564843.702 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-09-11T04:27:23.702Z,1757564843.702 [DVL_micro] No Fault, FailCount= 1
2025-09-11T04:41:19.013Z,1757565679.013 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T04:41:19.013Z,1757565679.013 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T04:41:19.013Z,1757565679.013 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T04:41:19.013Z,1757565679.013 [keepstation:SurfaceComms:A] Stopped
2025-09-11T04:41:19.013Z,1757565679.013 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T04:41:19.014Z,1757565679.014 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T04:41:19.409Z,1757565679.409 [keepstation:KeepStation:Dive] Stopped
2025-09-11T04:41:19.409Z,1757565679.409 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T04:41:19.409Z,1757565679.409 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T04:41:19.409Z,1757565679.409 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T04:41:19.410Z,1757565679.410 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T04:41:19.410Z,1757565679.410 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T04:41:19.410Z,1757565679.410 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T04:41:19.410Z,1757565679.410 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T04:41:19.410Z,1757565679.410 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T04:41:19.410Z,1757565679.410 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T04:41:19.428Z,1757565679.428 [keepstation:NeedComms] Running Loop=1
2025-09-11T04:41:19.428Z,1757565679.428 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T04:41:19.428Z,1757565679.428 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T04:41:19.428Z,1757565679.428 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T04:41:19.429Z,1757565679.429 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T04:41:19.429Z,1757565679.429 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T04:41:19.429Z,1757565679.429 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T04:41:19.430Z,1757565679.430 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T04:41:19.430Z,1757565679.430 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T04:41:19.434Z,1757565679.434 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T04:41:19.435Z,1757565679.435 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T04:41:19.436Z,1757565679.436 [keepstation:NeedComms:A](INFO): last time_fix was:  1757562077.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T04:41:19.436Z,1757565679.436 [keepstation:NeedComms:A] Stopped
2025-09-11T04:43:07.861Z,1757565787.861 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T04:43:08.227Z,1757565788.227 [Radio_Surface](INFO): Powering up
2025-09-11T04:43:14.451Z,1757565794.451 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T04:43:37.725Z,1757565817.725 [NAL9602](INFO): SBD MO Status=2, MOMSN=23168, MT Status=2, MTMSN=0
2025-09-11T04:43:37.725Z,1757565817.725 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T04:43:44.571Z,1757565824.571 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T04:43:53.469Z,1757565833.469 [NAL9602](INFO): SBD MO Status=2, MOMSN=23168, MT Status=2, MTMSN=0
2025-09-11T04:43:53.469Z,1757565833.469 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T04:44:15.719Z,1757565855.719 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T04:44:19.727Z,1757565859.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=23168, MT Status=2, MTMSN=0
2025-09-11T04:44:19.728Z,1757565859.728 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T04:44:46.851Z,1757565886.851 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T04:45:17.975Z,1757565917.975 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T04:45:36.604Z,1757565936.604 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003242
2025-09-11T04:46:10.835Z,1757565970.835 [DVL_micro](ERROR): Failed to parse:


2025-09-11T04:48:12.517Z,1757566092.517 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T04:48:18.085Z,1757566098.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044817.00,A,3646.57868,N,12150.82411,W,0.797,162.52,110925,,,A*7D
2025-09-11T04:48:18.088Z,1757566098.088 [NAL9602](INFO): GPS fix at 20250911T044817: (36.776311, -121.847069)
2025-09-11T04:48:18.129Z,1757566098.129 [UniversalFixResidualReporter](INFO): Fix residual: 0.4 %DT, over the last 1134.4 m. Residual distance 4.7 m at bearing 13.0 degrees. Fix at (36.7763, -121.8471) with 108.3 m made good.
2025-09-11T04:48:18.136Z,1757566098.136 [keepstation:NeedComms:C] Stopped
2025-09-11T04:48:18.137Z,1757566098.137 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T04:48:26.603Z,1757566106.603 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20250911T033000/Courier0006.lzma
2025-09-11T04:48:37.228Z,1757566117.228 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0006.lzma.bak
2025-09-11T04:48:37.228Z,1757566117.228 [DataOverHttps](INFO): SBD MOMSN=25962534
2025-09-11T04:48:50.808Z,1757566130.808 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T04:49:04.081Z,1757566144.081 [DataOverHttps](INFO): Sending 561 bytes from file Logs/20250911T033000/Express0007.lzma
2025-09-11T04:49:15.128Z,1757566155.128 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0007.lzma.bak
2025-09-11T04:49:15.128Z,1757566155.128 [DataOverHttps](INFO): SBD MOMSN=25962537
2025-09-11T04:49:16.692Z,1757566156.692 [keepstation:NeedComms:D] Stopped
2025-09-11T04:49:16.693Z,1757566156.693 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T04:49:18.690Z,1757566158.690 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,044917.00,A,3646.56679,N,12150.82966,W,1.322,220.52,110925,,,D*75
2025-09-11T04:49:18.696Z,1757566158.696 [NAL9602](INFO): GPS fix at 20250911T044917: (36.776113, -121.847161)
2025-09-11T04:49:18.743Z,1757566158.743 [keepstation:NeedComms:E] Stopped
2025-09-11T04:49:18.744Z,1757566158.744 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T04:49:18.744Z,1757566158.744 [keepstation:NeedComms] Stopped
2025-09-11T04:49:18.744Z,1757566158.744 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T04:49:18.744Z,1757566158.744 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T04:49:18.744Z,1757566158.744 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T04:49:19.148Z,1757566159.148 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T04:49:19.148Z,1757566159.148 [keepstation:SurfaceComms:B] Stopped
2025-09-11T04:49:19.148Z,1757566159.148 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T04:49:19.148Z,1757566159.148 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T04:49:19.148Z,1757566159.148 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T04:49:19.148Z,1757566159.148 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T04:49:19.524Z,1757566159.524 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T04:49:19.525Z,1757566159.525 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T04:49:19.525Z,1757566159.525 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T04:49:19.525Z,1757566159.525 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T04:49:19.525Z,1757566159.525 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T04:49:19.525Z,1757566159.525 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T04:49:20.089Z,1757566160.089 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T04:49:20.089Z,1757566160.089 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T04:49:20.089Z,1757566160.089 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T04:49:20.089Z,1757566160.089 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T04:49:20.089Z,1757566160.089 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T04:49:20.089Z,1757566160.089 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T04:49:20.387Z,1757566160.387 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T04:49:20.387Z,1757566160.387 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T04:49:20.387Z,1757566160.387 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T04:49:20.387Z,1757566160.387 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T04:49:20.387Z,1757566160.387 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T04:49:20.387Z,1757566160.387 [keepstation:SurfaceComms] Stopped
2025-09-11T04:49:20.387Z,1757566160.387 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T04:49:20.735Z,1757566160.735 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T04:49:20.735Z,1757566160.735 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T04:49:20.736Z,1757566160.736 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T04:49:20.736Z,1757566160.736 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T04:49:20.736Z,1757566160.736 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T04:49:20.736Z,1757566160.736 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T04:49:20.736Z,1757566160.736 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T04:49:20.737Z,1757566160.737 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T04:49:20.737Z,1757566160.737 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T04:49:20.737Z,1757566160.737 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T04:49:20.737Z,1757566160.737 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T04:49:20.737Z,1757566160.737 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T04:49:20.737Z,1757566160.737 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T04:49:20.738Z,1757566160.738 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T04:49:20.738Z,1757566160.738 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T04:49:20.738Z,1757566160.738 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T04:49:20.738Z,1757566160.738 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T04:49:49.389Z,1757566189.389 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T04:49:49.462Z,1757566189.462 [NAL9602](ERROR): received: 
+CSQ:1

OK168, 2, 0, 0, 0

OK
2025-09-11T04:50:04.749Z,1757566204.749 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-09-11T04:51:50.031Z,1757566310.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T04:52:01.627Z,1757566321.627 [Radio_Surface](INFO): Powering down
2025-09-11T04:52:21.155Z,1757566341.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T04:52:22.160Z,1757566342.160 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T04:52:48.776Z,1757566368.776 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T04:52:48.777Z,1757566368.777 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T04:52:48.777Z,1757566368.777 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T04:52:48.777Z,1757566368.777 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T04:52:49.185Z,1757566369.185 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T04:52:49.185Z,1757566369.185 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T04:52:49.185Z,1757566369.185 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T04:52:49.185Z,1757566369.185 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T04:52:49.185Z,1757566369.185 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T04:52:49.185Z,1757566369.185 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T04:52:49.618Z,1757566369.618 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T04:52:49.618Z,1757566369.618 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T04:52:49.619Z,1757566369.619 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T04:52:49.619Z,1757566369.619 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T04:52:49.620Z,1757566369.620 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T04:52:49.620Z,1757566369.620 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T04:52:49.620Z,1757566369.620 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T04:52:49.620Z,1757566369.620 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T04:52:49.620Z,1757566369.620 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T04:52:49.620Z,1757566369.620 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T04:52:49.620Z,1757566369.620 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T04:52:49.621Z,1757566369.621 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T04:52:50.000Z,1757566370.000 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T04:52:50.001Z,1757566370.001 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T04:52:50.001Z,1757566370.001 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T04:52:50.001Z,1757566370.001 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T04:52:50.001Z,1757566370.001 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T04:52:50.001Z,1757566370.001 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T04:52:50.001Z,1757566370.001 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T04:53:35.475Z,1757566415.475 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.267532
2025-09-11T04:54:15.632Z,1757566455.632 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T04:54:15.632Z,1757566455.632 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T04:54:15.633Z,1757566455.633 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T04:54:15.634Z,1757566455.634 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T04:54:15.637Z,1757566455.637 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T04:54:20.472Z,1757566460.472 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T04:54:51.170Z,1757566491.170 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T04:55:33.606Z,1757566533.606 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+15.7,0000.0,1489.0,000
2025-09-11T04:59:59.996Z,1757566799.996 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-11T05:00:04.348Z,1757566804.348 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001750
CHAN A1 (24V): 0.014434
CHAN A2 (12V): -0.018507
CHAN A3 (5V): -0.011195
CHAN B0 (3.3V): -0.009760
CHAN B1 (3.15aV): -0.008577
CHAN B2 (3.15bV): -0.008268
CHAN B3 (GND): -0.011677
OPEN: 0.007954
Full Scale: +/- 1 mA
2025-09-11T05:07:01.625Z,1757567221.625 [DVL_micro](ERROR): Failed to parse:
:SA,+01.77,+00.68,074.9
2025-09-11T05:18:07.817Z,1757567887.817 [DVL_micro](ERROR): Failed to parse:
:WI,+00184,-00528,+00045,+00000,A
2025-09-11T05:18:09.840Z,1757567889.840 [DVL_micro](ERROR): Failed to parse:
:BI,+00127,-00032,-00111,+00000,A
2025-09-11T05:18:11.848Z,1757567891.848 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0037.00,000.00
2025-09-11T05:18:13.871Z,1757567893.871 [DVL_micro](ERROR): Failed to parse:
:RD,+0039.99,+0040.33,+9999.99,+0040.74
2025-09-11T05:18:14.262Z,1757567894.262 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-09-11T05:18:14.271Z,1757567894.271 [DVL_micro] Communications Fault, FailCount= 1
2025-09-11T05:18:14.271Z,1757567894.271 [DVL_micro](ERROR): Communications Fault
2025-09-11T05:18:14.324Z,1757567894.324 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-09-11T05:18:14.755Z,1757567894.755 [DVL_micro](INFO): Powering down
2025-09-11T05:18:15.543Z,1757567895.543 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-09-11T05:18:15.543Z,1757567895.543 [DVL_micro] No Fault, FailCount= 1
2025-09-11T05:36:14.185Z,1757568974.185 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-09-11T05:44:58.336Z,1757569498.336 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-09-11T05:49:18.927Z,1757569758.927 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T05:49:18.927Z,1757569758.927 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T05:49:18.927Z,1757569758.927 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T05:49:18.927Z,1757569758.927 [keepstation:SurfaceComms:A] Stopped
2025-09-11T05:49:18.927Z,1757569758.927 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T05:49:18.928Z,1757569758.928 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T05:49:19.321Z,1757569759.321 [keepstation:KeepStation:Dive] Stopped
2025-09-11T05:49:19.321Z,1757569759.321 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T05:49:19.321Z,1757569759.321 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T05:49:19.321Z,1757569759.321 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T05:49:19.322Z,1757569759.322 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T05:49:19.322Z,1757569759.322 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T05:49:19.322Z,1757569759.322 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T05:49:19.322Z,1757569759.322 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T05:49:19.322Z,1757569759.322 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T05:49:19.322Z,1757569759.322 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T05:49:19.324Z,1757569759.324 [keepstation:NeedComms] Running Loop=1
2025-09-11T05:49:19.324Z,1757569759.324 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T05:49:19.324Z,1757569759.324 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T05:49:19.324Z,1757569759.324 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T05:49:19.325Z,1757569759.325 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T05:49:19.325Z,1757569759.325 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T05:49:19.325Z,1757569759.325 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T05:49:19.326Z,1757569759.326 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T05:49:19.326Z,1757569759.326 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T05:49:19.327Z,1757569759.327 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T05:49:19.327Z,1757569759.327 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T05:49:19.328Z,1757569759.328 [keepstation:NeedComms:A](INFO): last time_fix was:  1757566157.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T05:49:19.328Z,1757569759.328 [keepstation:NeedComms:A] Stopped
2025-09-11T05:50:51.358Z,1757569851.358 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T05:50:51.919Z,1757569851.919 [Radio_Surface](INFO): Powering up
2025-09-11T05:50:57.723Z,1757569857.723 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T05:51:24.091Z,1757569884.091 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T05:51:24.176Z,1757569884.176 [NAL9602](ERROR): received: 
+CSQ:0

OK168, 2, 0, 0, 0

OK
2025-09-11T05:51:27.843Z,1757569887.843 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T05:51:58.979Z,1757569918.979 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T05:52:09.345Z,1757569929.345 [NAL9602](INFO): SBD MO Status=1, MOMSN=23168, MT Status=0, MTMSN=0
2025-09-11T05:52:09.345Z,1757569929.345 [NAL9602](INFO): No messages in MT queue
2025-09-11T05:52:10.565Z,1757569930.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,055209.00,A,3646.60195,N,12150.95600,W,1.011,202.05,110925,,,A*7C
2025-09-11T05:52:10.568Z,1757569930.568 [NAL9602](INFO): GPS fix at 20250911T055209: (36.776699, -121.849267)
2025-09-11T05:52:10.609Z,1757569930.609 [UniversalFixResidualReporter](INFO): Fix residual: 2.3 %DT, over the last 891.1 m. Residual distance 20.7 m at bearing -72.0 degrees. Fix at (36.7767, -121.8493) with 198.5 m made good.
2025-09-11T05:52:10.616Z,1757569930.616 [keepstation:NeedComms:C] Stopped
2025-09-11T05:52:10.616Z,1757569930.616 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T05:52:25.976Z,1757569945.976 [NAL9602](INFO): SBD MO Status=1, MOMSN=23169, MT Status=0, MTMSN=0
2025-09-11T05:52:26.035Z,1757569946.035 [NAL9602](INFO): Sent 98 bytes from file Logs/20250911T033000/Courier0009.lzma
2025-09-11T05:52:26.035Z,1757569946.035 [NAL9602](INFO): Packets left to send: 0
2025-09-11T05:52:30.103Z,1757569950.103 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T05:52:35.757Z,1757569955.757 [NAL9602](INFO): SBD MO Status=1, MOMSN=23170, MT Status=0, MTMSN=0
2025-09-11T05:52:35.819Z,1757569955.819 [NAL9602](INFO): Sent 332 bytes from file Logs/20250911T033000/Express0010.lzma
2025-09-11T05:52:35.819Z,1757569955.819 [NAL9602](INFO): Packets left to send: 2
2025-09-11T05:52:54.858Z,1757569974.858 [NAL9602](INFO): SBD MO Status=2, MOMSN=23171, MT Status=2, MTMSN=0
2025-09-11T05:52:54.859Z,1757569974.859 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T05:53:01.227Z,1757569981.227 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T05:53:15.488Z,1757569995.488 [NAL9602](INFO): SBD MO Status=1, MOMSN=23171, MT Status=0, MTMSN=0
2025-09-11T05:53:15.539Z,1757569995.539 [NAL9602](INFO): Sent 332 bytes from file Logs/20250911T033000/Express0010.lzma
2025-09-11T05:53:15.539Z,1757569995.539 [NAL9602](INFO): Packets left to send: 1
2025-09-11T05:53:19.995Z,1757569999.995 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003251
2025-09-11T05:53:27.821Z,1757570007.821 [DataOverHttps](INFO): Sending 746 bytes from file Logs/20250911T033000/Express0010.lzma
2025-09-11T05:53:37.784Z,1757570017.784 [NAL9602](INFO): SBD MO Status=2, MOMSN=23172, MT Status=2, MTMSN=0
2025-09-11T05:53:37.784Z,1757570017.784 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T05:53:38.860Z,1757570018.860 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0010.lzma.bak
2025-09-11T05:53:38.860Z,1757570018.860 [DataOverHttps](INFO): SBD MOMSN=25962692
2025-09-11T05:53:40.231Z,1757570020.231 [keepstation:NeedComms:D] Stopped
2025-09-11T05:53:40.232Z,1757570020.232 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T05:53:42.299Z,1757570022.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,055341.00,A,3646.59136,N,12150.96903,W,0.700,230.55,110925,,,D*7A
2025-09-11T05:53:42.311Z,1757570022.311 [NAL9602](INFO): GPS fix at 20250911T055341: (36.776523, -121.849484)
2025-09-11T05:53:42.368Z,1757570022.368 [keepstation:NeedComms:E] Stopped
2025-09-11T05:53:42.368Z,1757570022.368 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T05:53:42.369Z,1757570022.369 [keepstation:NeedComms] Stopped
2025-09-11T05:53:42.369Z,1757570022.369 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T05:53:42.369Z,1757570022.369 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T05:53:42.369Z,1757570022.369 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T05:53:42.696Z,1757570022.696 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T05:53:42.696Z,1757570022.696 [keepstation:SurfaceComms:B] Stopped
2025-09-11T05:53:42.696Z,1757570022.696 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T05:53:42.696Z,1757570022.696 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T05:53:42.696Z,1757570022.696 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T05:53:42.696Z,1757570022.696 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T05:53:43.052Z,1757570023.052 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T05:53:43.052Z,1757570023.052 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T05:53:43.052Z,1757570023.052 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T05:53:43.052Z,1757570023.052 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T05:53:43.052Z,1757570023.052 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T05:53:43.052Z,1757570023.052 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T05:53:43.473Z,1757570023.473 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T05:53:43.474Z,1757570023.474 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T05:53:43.474Z,1757570023.474 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T05:53:43.474Z,1757570023.474 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T05:53:43.474Z,1757570023.474 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T05:53:43.474Z,1757570023.474 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T05:53:43.900Z,1757570023.900 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T05:53:43.900Z,1757570023.900 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T05:53:43.900Z,1757570023.900 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T05:53:43.900Z,1757570023.900 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T05:53:43.900Z,1757570023.900 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T05:53:43.900Z,1757570023.900 [keepstation:SurfaceComms] Stopped
2025-09-11T05:53:43.900Z,1757570023.900 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T05:53:44.310Z,1757570024.310 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T05:53:44.310Z,1757570024.310 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T05:53:44.310Z,1757570024.310 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T05:53:44.314Z,1757570024.314 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T05:53:44.315Z,1757570024.315 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T05:53:44.316Z,1757570024.316 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T05:53:44.317Z,1757570024.317 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T05:53:44.317Z,1757570024.317 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T05:53:44.318Z,1757570024.318 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T05:53:44.318Z,1757570024.318 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T05:54:14.552Z,1757570054.552 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T05:55:11.719Z,1757570111.719 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-11T05:55:42.843Z,1757570142.843 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T05:56:02.203Z,1757570162.203 [Radio_Surface](INFO): Powering down
2025-09-11T05:56:13.975Z,1757570173.975 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T05:56:14.979Z,1757570174.979 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T05:56:53.762Z,1757570213.762 [DVL_micro](ERROR): only read 2 of 4 data items for beam range. Device response is::RD,+0059.02,+005757.14
2025-09-11T05:56:53.763Z,1757570213.763 [DVL_micro](ERROR): Failed to parse:
:RD,+0059.02,+005757.14
2025-09-11T05:57:11.103Z,1757570231.103 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T05:57:11.103Z,1757570231.103 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T05:57:11.103Z,1757570231.103 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T05:57:11.103Z,1757570231.103 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T05:57:11.514Z,1757570231.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T05:57:11.514Z,1757570231.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T05:57:11.514Z,1757570231.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T05:57:11.514Z,1757570231.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T05:57:11.514Z,1757570231.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T05:57:11.514Z,1757570231.514 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T05:57:11.935Z,1757570231.935 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T05:57:11.936Z,1757570231.936 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T05:57:11.936Z,1757570231.936 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T05:57:11.936Z,1757570231.936 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T05:57:11.936Z,1757570231.936 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T05:57:11.936Z,1757570231.936 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T05:57:11.937Z,1757570231.937 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T05:57:11.937Z,1757570231.937 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T05:57:11.937Z,1757570231.937 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T05:57:11.937Z,1757570231.937 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T05:57:11.937Z,1757570231.937 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T05:57:11.937Z,1757570231.937 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T05:57:12.315Z,1757570232.315 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T05:57:12.315Z,1757570232.315 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T05:57:12.315Z,1757570232.315 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T05:57:12.316Z,1757570232.316 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T05:57:12.316Z,1757570232.316 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T05:57:12.316Z,1757570232.316 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T05:57:12.316Z,1757570232.316 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T05:57:29.299Z,1757570249.299 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.502060
2025-09-11T06:01:50.660Z,1757570510.660 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T06:01:50.660Z,1757570510.660 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T06:01:50.661Z,1757570510.661 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T06:01:50.662Z,1757570510.662 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T06:01:50.666Z,1757570510.666 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T06:05:56.298Z,1757570756.298 [DVL_micro](ERROR): Failed to parse:
:RD,+0046.70,+0047.95,+0048.49,+0047.77
2025-09-11T06:06:00.337Z,1757570760.337 [DVL_micro](ERROR): Failed to parse:
:BI,-00093,+00178,-00014,+00000,A
2025-09-11T06:06:02.369Z,1757570762.369 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0043.30,000.00
2025-09-11T06:06:04.395Z,1757570764.395 [DVL_micro](ERROR): Failed to parse:
:SA,-02.99,-00.28,195.6
2025-09-11T06:08:34.266Z,1757570914.266 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,+00016,+00150,.52
2025-09-11T06:08:34.266Z,1757570914.266 [DVL_micro](ERROR): Failed to parse:
:BI,+00016,+00150,.52
2025-09-11T06:23:23.483Z,1757571803.483 [DVL_micro](ERROR): Failed to parse:
:SA,+00.28,-00.45,141.2
2025-09-11T06:23:25.486Z,1757571805.486 [DVL_micro](ERROR): Failed to parse::SA,+00.35,-00.33,141.7
2025-09-11T06:23:27.497Z,1757571807.497 [DVL_micro](ERROR): Failed to parse:
:SA,+00.16,-00.27,142.6
2025-09-11T06:24:30.132Z,1757571870.132 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-09-11T06:34:10.879Z,1757572450.879 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-09-11T06:46:35.887Z,1757573195.887 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,.20
2025-09-11T06:46:35.887Z,1757573195.887 [DVL_micro](ERROR): Failed to parse:
:RD,.20
2025-09-11T06:53:42.476Z,1757573622.476 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T06:53:42.476Z,1757573622.476 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T06:53:42.477Z,1757573622.477 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T06:53:42.477Z,1757573622.477 [keepstation:SurfaceComms:A] Stopped
2025-09-11T06:53:42.477Z,1757573622.477 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T06:53:42.477Z,1757573622.477 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T06:53:42.892Z,1757573622.892 [keepstation:KeepStation:Dive] Stopped
2025-09-11T06:53:42.892Z,1757573622.892 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T06:53:42.892Z,1757573622.892 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T06:53:42.892Z,1757573622.892 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T06:53:42.893Z,1757573622.893 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T06:53:42.893Z,1757573622.893 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T06:53:42.893Z,1757573622.893 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T06:53:42.893Z,1757573622.893 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T06:53:42.893Z,1757573622.893 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T06:53:42.893Z,1757573622.893 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T06:53:42.895Z,1757573622.895 [keepstation:NeedComms] Running Loop=1
2025-09-11T06:53:42.895Z,1757573622.895 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T06:53:42.895Z,1757573622.895 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T06:53:42.895Z,1757573622.895 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T06:53:42.896Z,1757573622.896 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T06:53:42.896Z,1757573622.896 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T06:53:42.896Z,1757573622.896 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T06:53:42.897Z,1757573622.897 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T06:53:42.897Z,1757573622.897 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T06:53:42.897Z,1757573622.897 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T06:53:42.898Z,1757573622.898 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T06:53:42.899Z,1757573622.899 [keepstation:NeedComms:A](INFO): last time_fix was:  1757570021.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T06:53:42.900Z,1757573622.900 [keepstation:NeedComms:A] Stopped
2025-09-11T06:55:02.701Z,1757573702.701 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T06:55:03.379Z,1757573703.379 [Radio_Surface](INFO): Powering up
2025-09-11T06:55:08.719Z,1757573708.719 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T06:55:38.839Z,1757573738.839 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T06:55:42.250Z,1757573742.250 [NAL9602](INFO): SBD MO Status=2, MOMSN=23172, MT Status=2, MTMSN=0
2025-09-11T06:55:42.250Z,1757573742.250 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T06:56:08.110Z,1757573768.110 [NAL9602](INFO): SBD MO Status=2, MOMSN=23172, MT Status=2, MTMSN=0
2025-09-11T06:56:08.110Z,1757573768.110 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T06:56:09.963Z,1757573769.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T06:56:41.095Z,1757573801.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T06:56:59.418Z,1757573819.418 [NAL9602](INFO): SBD MO Status=2, MOMSN=23172, MT Status=2, MTMSN=0
2025-09-11T06:56:59.418Z,1757573819.418 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T06:57:09.517Z,1757573829.517 [NAL9602](INFO): SBD MO Status=1, MOMSN=23172, MT Status=0, MTMSN=0
2025-09-11T06:57:09.517Z,1757573829.517 [NAL9602](INFO): No messages in MT queue
2025-09-11T06:57:10.727Z,1757573830.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065709.00,A,3646.61329,N,12151.03036,W,1.419,260.23,110925,,,D*7A
2025-09-11T06:57:10.729Z,1757573830.729 [NAL9602](INFO): GPS fix at 20250911T065709: (36.776888, -121.850506)
2025-09-11T06:57:10.742Z,1757573830.742 [UniversalFixResidualReporter](INFO): Fix residual: 2.0 %DT, over the last 934.8 m. Residual distance 18.4 m at bearing 6.3 degrees. Fix at (36.7769, -121.8505) with 99.7 m made good.
2025-09-11T06:57:10.745Z,1757573830.745 [keepstation:NeedComms:C] Stopped
2025-09-11T06:57:10.746Z,1757573830.746 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T06:57:12.223Z,1757573832.223 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T06:57:27.374Z,1757573847.374 [NAL9602](INFO): SBD MO Status=2, MOMSN=23173, MT Status=2, MTMSN=0
2025-09-11T06:57:27.374Z,1757573847.374 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T06:57:30.763Z,1757573850.763 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003254
2025-09-11T06:57:38.582Z,1757573858.582 [DataOverHttps](INFO): Sending 100 bytes from file Logs/20250911T033000/Courier0012.lzma
2025-09-11T06:57:49.640Z,1757573869.640 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0012.lzma.bak
2025-09-11T06:57:49.641Z,1757573869.641 [DataOverHttps](INFO): SBD MOMSN=25962963
2025-09-11T06:58:02.930Z,1757573882.930 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T06:58:15.979Z,1757573895.979 [DataOverHttps](INFO): Sending 450 bytes from file Logs/20250911T033000/Express0013.lzma
2025-09-11T06:58:27.020Z,1757573907.020 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0013.lzma.bak
2025-09-11T06:58:27.020Z,1757573907.020 [DataOverHttps](INFO): SBD MOMSN=25962966
2025-09-11T06:58:28.401Z,1757573908.401 [keepstation:NeedComms:D] Stopped
2025-09-11T06:58:28.402Z,1757573908.402 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T06:58:30.414Z,1757573910.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,065829.00,A,3646.61063,N,12151.04480,W,0.914,260.24,110925,,,D*72
2025-09-11T06:58:30.416Z,1757573910.416 [NAL9602](INFO): GPS fix at 20250911T065829: (36.776844, -121.850747)
2025-09-11T06:58:30.471Z,1757573910.471 [keepstation:NeedComms:E] Stopped
2025-09-11T06:58:30.472Z,1757573910.472 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T06:58:30.472Z,1757573910.472 [keepstation:NeedComms] Stopped
2025-09-11T06:58:30.472Z,1757573910.472 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T06:58:30.472Z,1757573910.472 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T06:58:30.472Z,1757573910.472 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T06:58:30.839Z,1757573910.839 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T06:58:30.840Z,1757573910.840 [keepstation:SurfaceComms:B] Stopped
2025-09-11T06:58:30.840Z,1757573910.840 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T06:58:30.840Z,1757573910.840 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T06:58:30.840Z,1757573910.840 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T06:58:30.840Z,1757573910.840 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T06:58:31.216Z,1757573911.216 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T06:58:31.216Z,1757573911.216 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T06:58:31.216Z,1757573911.216 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T06:58:31.216Z,1757573911.216 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T06:58:31.216Z,1757573911.216 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T06:58:31.216Z,1757573911.216 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T06:58:31.704Z,1757573911.704 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T06:58:31.704Z,1757573911.704 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T06:58:31.704Z,1757573911.704 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T06:58:31.704Z,1757573911.704 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T06:58:31.704Z,1757573911.704 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T06:58:31.704Z,1757573911.704 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T06:58:32.141Z,1757573912.141 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T06:58:32.141Z,1757573912.141 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T06:58:32.141Z,1757573912.141 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T06:58:32.141Z,1757573912.141 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T06:58:32.142Z,1757573912.142 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T06:58:32.142Z,1757573912.142 [keepstation:SurfaceComms] Stopped
2025-09-11T06:58:32.142Z,1757573912.142 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T06:58:32.544Z,1757573912.544 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T06:58:32.545Z,1757573912.545 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T06:58:32.545Z,1757573912.545 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T06:58:32.545Z,1757573912.545 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T06:58:32.545Z,1757573912.545 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T06:58:32.545Z,1757573912.545 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T06:58:32.546Z,1757573912.546 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T06:58:32.546Z,1757573912.546 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T06:58:32.546Z,1757573912.546 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T06:58:32.546Z,1757573912.546 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T06:58:32.546Z,1757573912.546 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T06:58:32.546Z,1757573912.546 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T06:58:32.546Z,1757573912.546 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T06:58:32.579Z,1757573912.579 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T06:58:32.579Z,1757573912.579 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T06:58:32.579Z,1757573912.579 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T06:58:32.579Z,1757573912.579 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T07:00:29.852Z,1757574029.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T07:01:00.983Z,1757574060.983 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T07:01:11.879Z,1757574071.879 [Radio_Surface](INFO): Powering down
2025-09-11T07:01:32.107Z,1757574092.107 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T07:01:33.111Z,1757574093.111 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T07:02:41.333Z,1757574161.333 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T07:02:41.334Z,1757574161.334 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T07:02:41.335Z,1757574161.335 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T07:02:41.336Z,1757574161.336 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T07:02:41.739Z,1757574161.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T07:02:41.739Z,1757574161.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T07:02:41.739Z,1757574161.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T07:02:41.739Z,1757574161.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T07:02:41.739Z,1757574161.739 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T07:02:41.740Z,1757574161.740 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T07:02:42.119Z,1757574162.119 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T07:02:42.119Z,1757574162.119 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T07:02:42.119Z,1757574162.119 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T07:02:42.119Z,1757574162.119 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T07:02:42.119Z,1757574162.119 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T07:02:42.119Z,1757574162.119 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T07:02:42.120Z,1757574162.120 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T07:02:42.120Z,1757574162.120 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T07:02:42.120Z,1757574162.120 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T07:02:42.120Z,1757574162.120 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T07:02:42.120Z,1757574162.120 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T07:02:42.120Z,1757574162.120 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T07:02:42.532Z,1757574162.532 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T07:02:42.532Z,1757574162.532 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T07:02:42.532Z,1757574162.532 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T07:02:42.533Z,1757574162.533 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T07:02:42.533Z,1757574162.533 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T07:02:42.533Z,1757574162.533 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T07:02:42.533Z,1757574162.533 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T07:02:47.439Z,1757574167.439 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.763306
2025-09-11T07:03:32.612Z,1757574212.612 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T07:03:43.521Z,1757574223.521 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,+00099,+00000,A
2025-09-11T07:03:43.521Z,1757574223.521 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,+00099,+00000,A
2025-09-11T07:04:03.324Z,1757574243.324 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T07:05:37.852Z,1757574337.852 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-09-11T07:09:25.715Z,1757574565.715 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T07:09:25.716Z,1757574565.716 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T07:09:25.717Z,1757574565.717 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T07:09:25.718Z,1757574565.718 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T07:09:25.721Z,1757574565.721 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T07:11:16.405Z,1757574676.405 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000,+00016,-00051,+00014,+00000,A
2025-09-11T07:11:16.406Z,1757574676.406 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+00016,-00051,+00014,+00000,A
2025-09-11T07:16:49.302Z,1757575009.302 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000244,-00021,+00000,A
2025-09-11T07:16:49.302Z,1757575009.302 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000244,-00021,+00000,A
2025-09-11T07:17:25.262Z,1757575045.262 [DVL_micro](ERROR): Failed to parse:
:SA,-01.69,+00.85,322.8
2025-09-11T07:21:51.094Z,1757575311.094 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-09-11T07:31:36.765Z,1757575896.765 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-09-11T07:31:37.189Z,1757575897.189 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-09-11T07:31:37.190Z,1757575897.190 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 30.83 mm.
2025-09-11T07:40:47.018Z,1757576447.018 [DVL_micro](ERROR): only read 0 of 4 data items for beam range. Device response is::RD7.87
2025-09-11T07:40:47.018Z,1757576447.018 [DVL_micro](ERROR): Failed to parse:
:RD7.87
2025-09-11T07:54:46.533Z,1757577286.533 [DVL_micro](ERROR): Failed to parse:
:SA,-03.01,+01.03,019.1
2025-09-11T07:58:30.769Z,1757577510.769 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T07:58:30.769Z,1757577510.769 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T07:58:30.769Z,1757577510.769 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T07:58:30.769Z,1757577510.769 [keepstation:SurfaceComms:A] Stopped
2025-09-11T07:58:30.770Z,1757577510.770 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T07:58:30.770Z,1757577510.770 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T07:58:31.156Z,1757577511.156 [keepstation:KeepStation:Dive] Stopped
2025-09-11T07:58:31.156Z,1757577511.156 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T07:58:31.157Z,1757577511.157 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T07:58:31.160Z,1757577511.160 [keepstation:NeedComms] Running Loop=1
2025-09-11T07:58:31.160Z,1757577511.160 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T07:58:31.160Z,1757577511.160 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T07:58:31.160Z,1757577511.160 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T07:58:31.160Z,1757577511.160 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T07:58:31.161Z,1757577511.161 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T07:58:31.161Z,1757577511.161 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T07:58:31.161Z,1757577511.161 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T07:58:31.162Z,1757577511.162 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T07:58:31.162Z,1757577511.162 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T07:58:31.162Z,1757577511.162 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T07:58:31.164Z,1757577511.164 [keepstation:NeedComms:A](INFO): last time_fix was:  1757573909.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T07:58:31.164Z,1757577511.164 [keepstation:NeedComms:A] Stopped
2025-09-11T07:59:56.820Z,1757577596.820 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T07:59:57.435Z,1757577597.435 [Radio_Surface](INFO): Powering up
2025-09-11T08:00:02.703Z,1757577602.703 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T08:00:29.543Z,1757577629.543 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T08:00:29.626Z,1757577629.626 [NAL9602](ERROR): received: 
+CSQ:0

OK173, 2, 0, 0, 0

OK
2025-09-11T08:00:32.823Z,1757577632.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T08:00:50.528Z,1757577650.528 [NAL9602](INFO): SBD MO Status=2, MOMSN=23173, MT Status=2, MTMSN=0
2025-09-11T08:00:50.528Z,1757577650.528 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T08:01:03.947Z,1757577663.947 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T08:01:10.328Z,1757577670.328 [NAL9602](INFO): SBD MO Status=2, MOMSN=23173, MT Status=2, MTMSN=0
2025-09-11T08:01:10.328Z,1757577670.328 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T08:01:34.972Z,1757577694.972 [NAL9602](INFO): SBD MO Status=2, MOMSN=23173, MT Status=2, MTMSN=0
2025-09-11T08:01:34.972Z,1757577694.972 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T08:01:35.071Z,1757577695.071 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T08:02:06.195Z,1757577726.195 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T08:02:24.776Z,1757577744.776 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004330
2025-09-11T08:02:27.495Z,1757577747.495 [NAL9602](INFO): SBD MO Status=1, MOMSN=23173, MT Status=0, MTMSN=0
2025-09-11T08:02:27.496Z,1757577747.496 [NAL9602](INFO): No messages in MT queue
2025-09-11T08:02:28.707Z,1757577748.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080227.00,A,3646.57072,N,12151.15379,W,1.166,219.03,110925,,,D*7E
2025-09-11T08:02:28.709Z,1757577748.709 [NAL9602](INFO): GPS fix at 20250911T080227: (36.776179, -121.852563)
2025-09-11T08:02:28.721Z,1757577748.721 [UniversalFixResidualReporter](INFO): Fix residual: 2.1 %DT, over the last 1009.3 m. Residual distance 20.7 m at bearing 44.5 degrees. Fix at (36.7762, -121.8526) with 177.9 m made good.
2025-09-11T08:02:28.725Z,1757577748.725 [keepstation:NeedComms:C] Stopped
2025-09-11T08:02:28.725Z,1757577748.725 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T08:02:38.818Z,1757577758.818 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20250911T033000/Courier0015.lzma
2025-09-11T08:02:49.860Z,1757577769.860 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0015.lzma.bak
2025-09-11T08:02:49.861Z,1757577769.861 [DataOverHttps](INFO): SBD MOMSN=25963385
2025-09-11T08:03:00.643Z,1757577780.643 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T08:03:17.084Z,1757577797.084 [DataOverHttps](INFO): Sending 527 bytes from file Logs/20250911T033000/Express0016.lzma
2025-09-11T08:03:32.140Z,1757577812.140 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0016.lzma.bak
2025-09-11T08:03:32.141Z,1757577812.141 [DataOverHttps](INFO): SBD MOMSN=25963390
2025-09-11T08:03:33.391Z,1757577813.391 [keepstation:NeedComms:D] Stopped
2025-09-11T08:03:33.391Z,1757577813.391 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T08:03:35.390Z,1757577815.390 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080334.00,A,3646.56846,N,12151.16237,W,0.797,181.13,110925,,,D*71
2025-09-11T08:03:35.392Z,1757577815.392 [NAL9602](INFO): GPS fix at 20250911T080334: (36.776141, -121.852706)
2025-09-11T08:03:35.433Z,1757577815.433 [keepstation:NeedComms:E] Stopped
2025-09-11T08:03:35.438Z,1757577815.438 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T08:03:35.438Z,1757577815.438 [keepstation:NeedComms] Stopped
2025-09-11T08:03:35.439Z,1757577815.439 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T08:03:35.439Z,1757577815.439 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T08:03:35.439Z,1757577815.439 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T08:03:35.836Z,1757577815.836 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T08:03:35.836Z,1757577815.836 [keepstation:SurfaceComms:B] Stopped
2025-09-11T08:03:35.836Z,1757577815.836 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T08:03:35.836Z,1757577815.836 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T08:03:35.836Z,1757577815.836 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T08:03:35.836Z,1757577815.836 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T08:03:36.220Z,1757577816.220 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T08:03:36.220Z,1757577816.220 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T08:03:36.221Z,1757577816.221 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T08:03:36.221Z,1757577816.221 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T08:03:36.221Z,1757577816.221 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T08:03:36.221Z,1757577816.221 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T08:03:36.628Z,1757577816.628 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T08:03:36.628Z,1757577816.628 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T08:03:36.628Z,1757577816.628 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T08:03:36.628Z,1757577816.628 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T08:03:36.628Z,1757577816.628 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T08:03:36.628Z,1757577816.628 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T08:03:37.057Z,1757577817.057 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T08:03:37.057Z,1757577817.057 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T08:03:37.057Z,1757577817.057 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T08:03:37.057Z,1757577817.057 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T08:03:37.057Z,1757577817.057 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T08:03:37.058Z,1757577817.058 [keepstation:SurfaceComms] Stopped
2025-09-11T08:03:37.058Z,1757577817.058 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T08:03:37.455Z,1757577817.455 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T08:03:37.455Z,1757577817.455 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T08:03:37.455Z,1757577817.455 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T08:03:37.456Z,1757577817.456 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T08:03:37.456Z,1757577817.456 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T08:03:37.456Z,1757577817.456 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T08:03:37.456Z,1757577817.456 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T08:03:37.456Z,1757577817.456 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T08:03:37.456Z,1757577817.456 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T08:03:37.457Z,1757577817.457 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T08:03:37.457Z,1757577817.457 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T08:03:37.457Z,1757577817.457 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T08:03:37.457Z,1757577817.457 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T08:03:37.457Z,1757577817.457 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T08:03:37.457Z,1757577817.457 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T08:03:37.458Z,1757577817.458 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T08:03:37.458Z,1757577817.458 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T08:03:55.189Z,1757577835.189 [DVL_micro](ERROR): Failed to parse::WI,+00128,+00339,+00316,+00000,A
2025-09-11T08:04:00.457Z,1757577840.457 [NAL9602](INFO): SBD MO Status=2, MOMSN=23174, MT Status=2, MTMSN=0
2025-09-11T08:04:00.458Z,1757577840.458 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T08:04:23.464Z,1757577863.464 [NAL9602](INFO): SBD MO Status=1, MOMSN=23174, MT Status=0, MTMSN=0
2025-09-11T08:04:23.464Z,1757577863.464 [NAL9602](INFO): No messages in MT queue
2025-09-11T08:04:54.181Z,1757577894.181 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T08:05:34.727Z,1757577934.727 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T08:06:05.851Z,1757577965.851 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T08:06:11.150Z,1757577971.150 [Radio_Surface](INFO): Powering down
2025-09-11T08:06:36.975Z,1757577996.975 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T08:06:37.979Z,1757577997.979 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T08:07:53.291Z,1757578073.291 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735184
2025-09-11T08:07:59.614Z,1757578079.614 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T08:07:59.614Z,1757578079.614 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T08:07:59.615Z,1757578079.615 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T08:07:59.615Z,1757578079.615 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T08:08:00.023Z,1757578080.023 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T08:08:00.023Z,1757578080.023 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T08:08:00.023Z,1757578080.023 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T08:08:00.024Z,1757578080.024 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T08:08:00.024Z,1757578080.024 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T08:08:00.024Z,1757578080.024 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T08:08:00.436Z,1757578080.436 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T08:08:00.436Z,1757578080.436 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T08:08:00.436Z,1757578080.436 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T08:08:00.437Z,1757578080.437 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T08:08:00.437Z,1757578080.437 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T08:08:00.437Z,1757578080.437 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T08:08:00.437Z,1757578080.437 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T08:08:00.437Z,1757578080.437 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T08:08:00.437Z,1757578080.437 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T08:08:00.437Z,1757578080.437 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T08:08:00.438Z,1757578080.438 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T08:08:00.438Z,1757578080.438 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T08:08:00.847Z,1757578080.847 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T08:08:00.847Z,1757578080.847 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T08:08:00.847Z,1757578080.847 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T08:08:00.847Z,1757578080.847 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T08:08:00.847Z,1757578080.847 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T08:08:00.847Z,1757578080.847 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T08:08:00.847Z,1757578080.847 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T08:17:02.992Z,1757578622.992 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T08:17:02.992Z,1757578622.992 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T08:17:02.993Z,1757578622.993 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T08:17:02.994Z,1757578622.994 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T08:17:02.997Z,1757578622.997 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T08:25:05.772Z,1757579105.772 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0044.10,000.00
2025-09-11T08:45:35.153Z,1757580335.153 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-09-11T08:54:13.472Z,1757580853.472 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-09-11T08:54:13.865Z,1757580853.865 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 30.83 mm.
2025-09-11T09:03:35.440Z,1757581415.440 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T09:03:35.440Z,1757581415.440 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T09:03:35.440Z,1757581415.440 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T09:03:35.440Z,1757581415.440 [keepstation:SurfaceComms:A] Stopped
2025-09-11T09:03:35.440Z,1757581415.440 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T09:03:35.441Z,1757581415.441 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T09:03:35.832Z,1757581415.832 [keepstation:KeepStation:Dive] Stopped
2025-09-11T09:03:35.833Z,1757581415.833 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T09:03:35.833Z,1757581415.833 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T09:03:35.833Z,1757581415.833 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T09:03:35.833Z,1757581415.833 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T09:03:35.833Z,1757581415.833 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T09:03:35.833Z,1757581415.833 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T09:03:35.833Z,1757581415.833 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T09:03:35.834Z,1757581415.834 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T09:03:35.834Z,1757581415.834 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T09:03:35.836Z,1757581415.836 [keepstation:NeedComms] Running Loop=1
2025-09-11T09:03:35.836Z,1757581415.836 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T09:03:35.836Z,1757581415.836 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T09:03:35.836Z,1757581415.836 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T09:03:35.836Z,1757581415.836 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T09:03:35.837Z,1757581415.837 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T09:03:35.837Z,1757581415.837 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T09:03:35.837Z,1757581415.837 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T09:03:35.838Z,1757581415.838 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T09:03:35.838Z,1757581415.838 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T09:03:35.838Z,1757581415.838 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T09:03:35.840Z,1757581415.840 [keepstation:NeedComms:A](INFO): last time_fix was:  1757577814.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T09:03:35.840Z,1757581415.840 [keepstation:NeedComms:A] Stopped
2025-09-11T09:03:45.203Z,1757581425.203 [DVL_micro](ERROR): Failed to parse:
:WI,-00026,+00082,-00006,+00000,A
2025-09-11T09:05:20.836Z,1757581520.836 [DVL_micro](ERROR): Failed to parse:
:SA,+15.36,-04.25,339.5
2025-09-11T09:05:26.990Z,1757581526.990 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T09:05:27.687Z,1757581527.687 [Radio_Surface](INFO): Powering up
2025-09-11T09:05:33.659Z,1757581533.659 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T09:06:03.779Z,1757581563.779 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T09:06:34.903Z,1757581594.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T09:07:06.031Z,1757581626.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T09:07:37.163Z,1757581657.163 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T09:07:55.827Z,1757581675.827 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003223
2025-09-11T09:10:31.608Z,1757581831.608 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T09:10:36.070Z,1757581836.070 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091035.00,A,3646.65781,N,12151.15137,W,0.097,290.72,110925,,,D*74
2025-09-11T09:10:36.074Z,1757581836.074 [NAL9602](INFO): GPS fix at 20250911T091035: (36.777630, -121.852523)
2025-09-11T09:10:36.093Z,1757581836.093 [UniversalFixResidualReporter](INFO): Fix residual: 0.3 %DT, over the last 891.8 m. Residual distance 2.8 m at bearing 169.0 degrees. Fix at (36.7776, -121.8525) with 166.4 m made good.
2025-09-11T09:10:36.097Z,1757581836.097 [keepstation:NeedComms:C] Stopped
2025-09-11T09:10:36.097Z,1757581836.097 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T09:10:43.826Z,1757581843.826 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20250911T033000/Courier0018.lzma
2025-09-11T09:10:54.868Z,1757581854.868 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0018.lzma.bak
2025-09-11T09:10:54.868Z,1757581854.868 [DataOverHttps](INFO): SBD MOMSN=25963569
2025-09-11T09:11:08.436Z,1757581868.436 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T09:11:21.180Z,1757581881.180 [DataOverHttps](INFO): Sending 537 bytes from file Logs/20250911T033000/Express0019.lzma
2025-09-11T09:11:32.220Z,1757581892.220 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0019.lzma.bak
2025-09-11T09:11:32.220Z,1757581892.220 [DataOverHttps](INFO): SBD MOMSN=25963572
2025-09-11T09:11:33.497Z,1757581893.497 [keepstation:NeedComms:D] Stopped
2025-09-11T09:11:33.498Z,1757581893.498 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T09:11:35.502Z,1757581895.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091134.00,A,3646.66169,N,12151.15756,W,0.778,327.60,110925,,,D*7E
2025-09-11T09:11:35.504Z,1757581895.504 [NAL9602](INFO): GPS fix at 20250911T091134: (36.777695, -121.852626)
2025-09-11T09:11:35.549Z,1757581895.549 [keepstation:NeedComms:E] Stopped
2025-09-11T09:11:35.549Z,1757581895.549 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T09:11:35.549Z,1757581895.549 [keepstation:NeedComms] Stopped
2025-09-11T09:11:35.549Z,1757581895.549 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T09:11:35.550Z,1757581895.550 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T09:11:35.550Z,1757581895.550 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T09:11:35.972Z,1757581895.972 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T09:11:35.972Z,1757581895.972 [keepstation:SurfaceComms:B] Stopped
2025-09-11T09:11:35.973Z,1757581895.973 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T09:11:35.973Z,1757581895.973 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T09:11:35.973Z,1757581895.973 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T09:11:35.973Z,1757581895.973 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T09:11:36.364Z,1757581896.364 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T09:11:36.364Z,1757581896.364 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T09:11:36.364Z,1757581896.364 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T09:11:36.364Z,1757581896.364 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T09:11:36.364Z,1757581896.364 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T09:11:36.364Z,1757581896.364 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T09:11:36.744Z,1757581896.744 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T09:11:36.744Z,1757581896.744 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T09:11:36.744Z,1757581896.744 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T09:11:36.744Z,1757581896.744 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T09:11:36.744Z,1757581896.744 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T09:11:36.744Z,1757581896.744 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T09:11:37.182Z,1757581897.182 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T09:11:37.182Z,1757581897.182 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T09:11:37.182Z,1757581897.182 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T09:11:37.182Z,1757581897.182 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T09:11:37.182Z,1757581897.182 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T09:11:37.186Z,1757581897.186 [keepstation:SurfaceComms] Stopped
2025-09-11T09:11:37.186Z,1757581897.186 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T09:11:37.547Z,1757581897.547 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T09:11:37.547Z,1757581897.547 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T09:11:37.547Z,1757581897.547 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T09:11:37.547Z,1757581897.547 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T09:11:37.548Z,1757581897.548 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T09:11:37.548Z,1757581897.548 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T09:11:37.548Z,1757581897.548 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T09:11:37.548Z,1757581897.548 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T09:11:37.548Z,1757581897.548 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T09:11:37.548Z,1757581897.548 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T09:11:37.549Z,1757581897.549 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T09:11:37.549Z,1757581897.549 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T09:11:37.549Z,1757581897.549 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T09:11:37.549Z,1757581897.549 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T09:11:37.549Z,1757581897.549 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T09:11:37.549Z,1757581897.549 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T09:11:37.550Z,1757581897.550 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T09:12:06.254Z,1757581926.254 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T09:12:06.330Z,1757581926.330 [NAL9602](ERROR): received: 
+CSQ:0

OK174, 0, 0, 0, 0

OK
2025-09-11T09:13:35.216Z,1757582015.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T09:14:06.352Z,1757582046.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T09:14:31.478Z,1757582071.478 [Radio_Surface](INFO): Powering down
2025-09-11T09:14:37.491Z,1757582077.491 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T09:14:38.495Z,1757582078.495 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T09:15:52.791Z,1757582152.791 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747131
2025-09-11T09:16:07.043Z,1757582167.043 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T09:16:07.043Z,1757582167.043 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T09:16:07.043Z,1757582167.043 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T09:16:07.043Z,1757582167.043 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T09:16:07.428Z,1757582167.428 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T09:16:07.428Z,1757582167.428 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T09:16:07.428Z,1757582167.428 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T09:16:07.428Z,1757582167.428 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T09:16:07.428Z,1757582167.428 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T09:16:07.428Z,1757582167.428 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T09:16:07.824Z,1757582167.824 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T09:16:07.824Z,1757582167.824 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T09:16:07.824Z,1757582167.824 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T09:16:07.824Z,1757582167.824 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T09:16:07.825Z,1757582167.825 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T09:16:08.232Z,1757582168.232 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T09:16:08.233Z,1757582168.233 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T09:16:08.233Z,1757582168.233 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T09:16:08.233Z,1757582168.233 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T09:16:08.233Z,1757582168.233 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T09:16:08.233Z,1757582168.233 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T09:16:08.233Z,1757582168.233 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T09:16:37.707Z,1757582197.707 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T09:17:08.412Z,1757582228.412 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T09:17:19.326Z,1757582239.326 [DVL_micro](ERROR): Failed to parse:
:RD,+0057.58,+0056.83,+0056.20,+0056.68
2025-09-11T09:18:21.154Z,1757582301.154 [DVL_micro](ERROR): DVL BIT error. See manual. Result code: -102
Device respsone is::TS,00000000000000,35.0,+14.5,0000.0,14890075,-00102,+00000,A
2025-09-11T09:18:21.156Z,1757582301.156 [DVL_micro](ERROR): Failed to parse:
:RD,+0052.33,+0052.70,+0053.58,+0052.62
2025-09-11T09:21:37.473Z,1757582497.473 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-09-11T09:24:38.476Z,1757582678.476 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T09:24:38.477Z,1757582678.477 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T09:24:38.477Z,1757582678.477 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T09:24:38.479Z,1757582678.479 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T09:24:38.482Z,1757582678.482 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T09:27:09.565Z,1757582829.565 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,+00037,-00026,+00000,A
2025-09-11T09:27:09.565Z,1757582829.565 [DVL_micro](ERROR): Failed to parse:
:TS,+00037,-00026,+00000,A
2025-09-11T10:11:35.581Z,1757585495.581 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T10:11:35.581Z,1757585495.581 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T10:11:35.582Z,1757585495.582 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T10:11:35.582Z,1757585495.582 [keepstation:SurfaceComms:A] Stopped
2025-09-11T10:11:35.582Z,1757585495.582 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T10:11:35.582Z,1757585495.582 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T10:11:35.994Z,1757585495.994 [keepstation:KeepStation:Dive] Stopped
2025-09-11T10:11:35.994Z,1757585495.994 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T10:11:35.994Z,1757585495.994 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T10:11:35.994Z,1757585495.994 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T10:11:35.995Z,1757585495.995 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T10:11:35.995Z,1757585495.995 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T10:11:35.995Z,1757585495.995 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T10:11:35.995Z,1757585495.995 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T10:11:35.995Z,1757585495.995 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T10:11:35.996Z,1757585495.996 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T10:11:35.998Z,1757585495.998 [keepstation:NeedComms] Running Loop=1
2025-09-11T10:11:35.999Z,1757585495.999 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T10:11:35.999Z,1757585495.999 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T10:11:35.999Z,1757585495.999 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T10:11:35.999Z,1757585495.999 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T10:11:36.000Z,1757585496.000 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T10:11:36.001Z,1757585496.001 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T10:11:36.001Z,1757585496.001 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T10:11:36.002Z,1757585496.002 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T10:11:36.002Z,1757585496.002 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T10:11:36.003Z,1757585496.003 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T10:11:36.005Z,1757585496.005 [keepstation:NeedComms:A](INFO): last time_fix was:  1757581894.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T10:11:36.005Z,1757585496.005 [keepstation:NeedComms:A] Stopped
2025-09-11T10:13:15.961Z,1757585595.961 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T10:13:16.392Z,1757585596.392 [Radio_Surface](INFO): Powering up
2025-09-11T10:13:22.083Z,1757585602.083 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T10:13:48.706Z,1757585628.706 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T10:13:48.778Z,1757585628.778 [NAL9602](ERROR): received: 
+CSQ:0

OK174, 0, 0, 0, 0

OK
2025-09-11T10:13:52.203Z,1757585632.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T10:14:23.327Z,1757585663.327 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T10:14:54.451Z,1757585694.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T10:15:17.991Z,1757585717.991 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.00000000.00,0064.50,000.00
2025-09-11T10:15:17.991Z,1757585717.991 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.00000000.00,0064.50,000.00
2025-09-11T10:15:25.591Z,1757585725.591 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T10:15:44.243Z,1757585744.243 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003264
2025-09-11T10:15:59.196Z,1757585759.196 [NAL9602](INFO): SBD MO Status=1, MOMSN=23175, MT Status=0, MTMSN=0
2025-09-11T10:15:59.196Z,1757585759.196 [NAL9602](INFO): No messages in MT queue
2025-09-11T10:16:00.421Z,1757585760.421 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,101559.00,A,3646.58564,N,12151.21343,W,0.214,306.50,110925,,,D*75
2025-09-11T10:16:00.427Z,1757585760.427 [NAL9602](INFO): GPS fix at 20250911T101559: (36.776427, -121.853557)
2025-09-11T10:16:00.473Z,1757585760.473 [keepstation:NeedComms:C] Stopped
2025-09-11T10:16:00.473Z,1757585760.473 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T10:16:04.219Z,1757585764.219 [DataOverHttps](INFO): Sending 88 bytes from file Logs/20250911T033000/Courier0021.lzma
2025-09-11T10:16:15.260Z,1757585775.260 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0021.lzma.bak
2025-09-11T10:16:15.260Z,1757585775.260 [DataOverHttps](INFO): SBD MOMSN=25963679
2025-09-11T10:16:32.421Z,1757585792.421 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T10:16:41.712Z,1757585801.712 [DataOverHttps](INFO): Sending 432 bytes from file Logs/20250911T033000/Express0022.lzma
2025-09-11T10:16:52.752Z,1757585812.752 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0022.lzma.bak
2025-09-11T10:16:52.752Z,1757585812.752 [DataOverHttps](INFO): SBD MOMSN=25963685
2025-09-11T10:16:54.258Z,1757585814.258 [keepstation:NeedComms:D] Stopped
2025-09-11T10:16:54.259Z,1757585814.259 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T10:16:56.283Z,1757585816.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,101655.00,A,3646.58911,N,12151.21521,W,0.700,328.64,110925,,,D*7D
2025-09-11T10:16:56.285Z,1757585816.285 [NAL9602](INFO): GPS fix at 20250911T101655: (36.776485, -121.853587)
2025-09-11T10:16:56.316Z,1757585816.316 [keepstation:NeedComms:E] Stopped
2025-09-11T10:16:56.317Z,1757585816.317 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T10:16:56.317Z,1757585816.317 [keepstation:NeedComms] Stopped
2025-09-11T10:16:56.317Z,1757585816.317 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T10:16:56.317Z,1757585816.317 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T10:16:56.317Z,1757585816.317 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T10:16:56.724Z,1757585816.724 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T10:16:56.724Z,1757585816.724 [keepstation:SurfaceComms:B] Stopped
2025-09-11T10:16:56.724Z,1757585816.724 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T10:16:56.724Z,1757585816.724 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T10:16:56.724Z,1757585816.724 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T10:16:56.724Z,1757585816.724 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T10:16:57.131Z,1757585817.131 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T10:16:57.131Z,1757585817.131 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T10:16:57.131Z,1757585817.131 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T10:16:57.131Z,1757585817.131 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T10:16:57.131Z,1757585817.131 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T10:16:57.131Z,1757585817.131 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T10:16:57.573Z,1757585817.573 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T10:16:57.573Z,1757585817.573 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T10:16:57.573Z,1757585817.573 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T10:16:57.573Z,1757585817.573 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T10:16:57.573Z,1757585817.573 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T10:16:57.573Z,1757585817.573 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T10:16:57.968Z,1757585817.968 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T10:16:57.968Z,1757585817.968 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T10:16:57.968Z,1757585817.968 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T10:16:57.968Z,1757585817.968 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T10:16:57.968Z,1757585817.968 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T10:16:57.968Z,1757585817.968 [keepstation:SurfaceComms] Stopped
2025-09-11T10:16:57.968Z,1757585817.968 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T10:16:58.331Z,1757585818.331 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T10:16:58.332Z,1757585818.332 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T10:16:58.332Z,1757585818.332 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T10:16:58.332Z,1757585818.332 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T10:16:58.332Z,1757585818.332 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T10:16:58.332Z,1757585818.332 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T10:16:58.333Z,1757585818.333 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T10:16:58.333Z,1757585818.333 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T10:16:58.333Z,1757585818.333 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T10:16:58.333Z,1757585818.333 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T10:16:58.333Z,1757585818.333 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T10:16:58.333Z,1757585818.333 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T10:16:58.333Z,1757585818.333 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T10:16:58.334Z,1757585818.334 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T10:16:58.334Z,1757585818.334 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T10:16:58.334Z,1757585818.334 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T10:16:58.334Z,1757585818.334 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T10:17:29.482Z,1757585849.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=23176, MT Status=2, MTMSN=0
2025-09-11T10:17:29.482Z,1757585849.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T10:18:00.516Z,1757585880.516 [NAL9602](INFO): SBD MO Status=2, MOMSN=23176, MT Status=2, MTMSN=0
2025-09-11T10:18:00.517Z,1757585880.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-11T10:18:26.711Z,1757585906.711 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-09-11T10:18:57.835Z,1757585937.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T10:19:23.447Z,1757585963.447 [Radio_Surface](INFO): Powering down
2025-09-11T10:19:28.963Z,1757585968.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T10:19:29.967Z,1757585969.967 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T10:20:43.263Z,1757586043.263 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.599258
2025-09-11T10:21:02.746Z,1757586062.746 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T10:21:02.747Z,1757586062.747 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T10:21:02.747Z,1757586062.747 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T10:21:02.747Z,1757586062.747 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T10:21:03.137Z,1757586063.137 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T10:21:03.138Z,1757586063.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T10:21:03.138Z,1757586063.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T10:21:03.138Z,1757586063.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T10:21:03.138Z,1757586063.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T10:21:03.138Z,1757586063.138 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T10:21:03.539Z,1757586063.539 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T10:21:03.539Z,1757586063.539 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T10:21:03.539Z,1757586063.539 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T10:21:03.539Z,1757586063.539 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T10:21:03.540Z,1757586063.540 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T10:21:03.968Z,1757586063.968 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T10:21:03.968Z,1757586063.968 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T10:21:03.968Z,1757586063.968 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T10:21:03.968Z,1757586063.968 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T10:21:03.968Z,1757586063.968 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T10:21:03.968Z,1757586063.968 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T10:21:03.968Z,1757586063.968 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T10:21:58.455Z,1757586118.455 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T10:22:29.192Z,1757586149.192 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T10:32:16.575Z,1757586736.575 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T10:32:16.576Z,1757586736.576 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T10:32:16.577Z,1757586736.577 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T10:32:16.578Z,1757586736.578 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T10:32:16.580Z,1757586736.580 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T10:49:06.194Z,1757587746.194 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.2,0000.0,1489.0,000
2025-09-11T11:00:00.279Z,1757588400.279 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-11T11:00:04.761Z,1757588404.761 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000616
CHAN A1 (24V): 0.015520
CHAN A2 (12V): -0.007751
CHAN A3 (5V): -0.004273
CHAN B0 (3.3V): -0.011237
CHAN B1 (3.15aV): -0.008891
CHAN B2 (3.15bV): -0.008320
CHAN B3 (GND): -0.006483
OPEN: 0.006557
Full Scale: +/- 1 mA
2025-09-11T11:16:56.346Z,1757589416.346 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T11:16:56.346Z,1757589416.346 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T11:16:56.347Z,1757589416.347 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T11:16:56.347Z,1757589416.347 [keepstation:SurfaceComms:A] Stopped
2025-09-11T11:16:56.347Z,1757589416.347 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T11:16:56.347Z,1757589416.347 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T11:16:56.349Z,1757589416.349 [keepstation:NeedComms] Running Loop=1
2025-09-11T11:16:56.349Z,1757589416.349 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T11:16:56.349Z,1757589416.349 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T11:16:56.349Z,1757589416.349 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T11:16:56.349Z,1757589416.349 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T11:16:56.350Z,1757589416.350 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T11:16:56.350Z,1757589416.350 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T11:16:56.351Z,1757589416.351 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T11:16:56.351Z,1757589416.351 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T11:16:56.351Z,1757589416.351 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T11:16:56.352Z,1757589416.352 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T11:16:56.353Z,1757589416.353 [keepstation:NeedComms:A](INFO): last time_fix was:  1757585815.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T11:16:56.353Z,1757589416.353 [keepstation:NeedComms:A] Stopped
2025-09-11T11:16:56.724Z,1757589416.724 [keepstation:KeepStation:Dive] Stopped
2025-09-11T11:16:56.724Z,1757589416.724 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T11:16:56.724Z,1757589416.724 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T11:16:56.724Z,1757589416.724 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T11:16:56.725Z,1757589416.725 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T11:16:56.725Z,1757589416.725 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T11:16:56.725Z,1757589416.725 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T11:16:56.725Z,1757589416.725 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T11:16:56.725Z,1757589416.725 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T11:16:56.725Z,1757589416.725 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T11:18:16.847Z,1757589496.847 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T11:18:17.275Z,1757589497.275 [Radio_Surface](INFO): Powering up
2025-09-11T11:18:22.647Z,1757589502.647 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T11:18:49.579Z,1757589529.579 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T11:18:49.663Z,1757589529.663 [NAL9602](ERROR): received: 
+CSQ:0

OK176, 2, 0, 0, 0

OK
2025-09-11T11:18:52.767Z,1757589532.767 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T11:19:23.904Z,1757589563.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T11:19:55.031Z,1757589595.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T11:20:25.748Z,1757589625.748 [DVL_micro](ERROR): Failed to parse::SA,-03.25,+03.34,063.6
2025-09-11T11:20:26.164Z,1757589626.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T11:20:27.757Z,1757589627.757 [DVL_micro](ERROR): Failed to parse:
:SA,-02.58,+04.45,066.1
2025-09-11T11:20:45.720Z,1757589645.720 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003245
2025-09-11T11:23:21.487Z,1757589801.487 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T11:23:31.181Z,1757589811.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112330.00,A,3646.65543,N,12151.11734,W,0.447,16.96,110925,,,D*4A
2025-09-11T11:23:31.184Z,1757589811.184 [NAL9602](INFO): GPS fix at 20250911T112330: (36.777591, -121.851956)
2025-09-11T11:23:31.240Z,1757589811.240 [keepstation:NeedComms:C] Stopped
2025-09-11T11:23:31.240Z,1757589811.240 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T11:23:37.578Z,1757589817.578 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20250911T033000/Courier0024.lzma
2025-09-11T11:23:48.620Z,1757589828.620 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Courier0024.lzma.bak
2025-09-11T11:23:48.621Z,1757589828.621 [DataOverHttps](INFO): SBD MOMSN=25963864
2025-09-11T11:24:03.904Z,1757589843.904 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T11:24:14.936Z,1757589854.936 [DataOverHttps](INFO): Sending 682 bytes from file Logs/20250911T033000/Express0025.lzma
2025-09-11T11:24:25.976Z,1757589865.976 [DataOverHttps](INFO): Moved sent file to Logs/20250911T033000/Express0025.lzma.bak
2025-09-11T11:24:25.977Z,1757589865.977 [DataOverHttps](INFO): SBD MOMSN=25963867
2025-09-11T11:24:27.363Z,1757589867.363 [keepstation:NeedComms:D] Stopped
2025-09-11T11:24:27.364Z,1757589867.364 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T11:24:29.366Z,1757589869.366 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112428.00,A,3646.66090,N,12151.11205,W,1.147,53.79,110925,,,D*4F
2025-09-11T11:24:29.384Z,1757589869.384 [NAL9602](INFO): GPS fix at 20250911T112428: (36.777682, -121.851867)
2025-09-11T11:24:29.407Z,1757589869.407 [keepstation:NeedComms:E] Stopped
2025-09-11T11:24:29.408Z,1757589869.408 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T11:24:29.408Z,1757589869.408 [keepstation:NeedComms] Stopped
2025-09-11T11:24:29.408Z,1757589869.408 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T11:24:29.408Z,1757589869.408 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T11:24:29.408Z,1757589869.408 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T11:24:29.809Z,1757589869.809 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T11:24:29.809Z,1757589869.809 [keepstation:SurfaceComms:B] Stopped
2025-09-11T11:24:29.809Z,1757589869.809 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T11:24:29.809Z,1757589869.809 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T11:24:29.809Z,1757589869.809 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T11:24:29.809Z,1757589869.809 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T11:24:30.194Z,1757589870.194 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T11:24:30.194Z,1757589870.194 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T11:24:30.194Z,1757589870.194 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T11:24:30.194Z,1757589870.194 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T11:24:30.214Z,1757589870.214 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T11:24:30.215Z,1757589870.215 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T11:24:30.593Z,1757589870.593 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T11:24:30.593Z,1757589870.593 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T11:24:30.593Z,1757589870.593 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T11:24:30.593Z,1757589870.593 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T11:24:30.593Z,1757589870.593 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T11:24:30.593Z,1757589870.593 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T11:24:31.023Z,1757589871.023 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T11:24:31.023Z,1757589871.023 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T11:24:31.024Z,1757589871.024 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T11:24:31.024Z,1757589871.024 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T11:24:31.024Z,1757589871.024 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T11:24:31.024Z,1757589871.024 [keepstation:SurfaceComms] Stopped
2025-09-11T11:24:31.024Z,1757589871.024 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T11:24:31.428Z,1757589871.428 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T11:24:31.428Z,1757589871.428 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T11:24:31.428Z,1757589871.428 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T11:24:31.428Z,1757589871.428 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T11:24:31.429Z,1757589871.429 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T11:24:31.430Z,1757589871.430 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T11:24:31.430Z,1757589871.430 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T11:24:31.430Z,1757589871.430 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T11:24:31.438Z,1757589871.438 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T11:25:00.129Z,1757589900.129 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T11:25:00.222Z,1757589900.222 [NAL9602](ERROR): received: 
+CSQ:0

OK176, 2, 0, 0, 0

OK
2025-09-11T11:26:59.715Z,1757590019.715 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T11:27:30.863Z,1757590050.863 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T11:27:30.912Z,1757590050.912 [Radio_Surface](INFO): Powering down
2025-09-11T11:27:31.867Z,1757590051.867 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T11:28:22.088Z,1757590102.088 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T11:28:22.088Z,1757590102.088 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T11:28:22.088Z,1757590102.088 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T11:28:22.088Z,1757590102.088 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T11:28:22.510Z,1757590102.510 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T11:28:22.510Z,1757590102.510 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T11:28:22.511Z,1757590102.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T11:28:22.511Z,1757590102.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T11:28:22.511Z,1757590102.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T11:28:22.511Z,1757590102.511 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T11:28:22.887Z,1757590102.887 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T11:28:22.888Z,1757590102.888 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T11:28:22.888Z,1757590102.888 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T11:28:22.888Z,1757590102.888 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T11:28:22.888Z,1757590102.888 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T11:28:22.888Z,1757590102.888 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T11:28:22.888Z,1757590102.888 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T11:28:22.889Z,1757590102.889 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T11:28:22.889Z,1757590102.889 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T11:28:22.889Z,1757590102.889 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T11:28:22.889Z,1757590102.889 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T11:28:22.889Z,1757590102.889 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T11:28:23.296Z,1757590103.296 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T11:28:23.296Z,1757590103.296 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T11:28:23.296Z,1757590103.296 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T11:28:23.297Z,1757590103.297 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T11:28:23.297Z,1757590103.297 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T11:28:23.297Z,1757590103.297 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T11:28:23.297Z,1757590103.297 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T11:28:46.332Z,1757590126.332 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,0054.30,000.00
2025-09-11T11:28:50.369Z,1757590130.369 [DVL_micro](ERROR): Failed to parse:
:WI,-00110,-00172,-00052,+00000,A
2025-09-11T11:28:52.375Z,1757590132.375 [DVL_micro](ERROR): Failed to parse:
:BI,-00127,-00086,-00077,+00000,A
2025-09-11T11:28:54.398Z,1757590134.398 [DVL_micro](ERROR): Failed to parse:
:BI,-00152,-00075,-00110,+00000,A
2025-09-11T11:28:56.426Z,1757590136.426 [DVL_micro](ERROR): Failed to parse:
:RD,+0058.55,+0057.99,+0056.87,+0058.33
2025-09-11T11:28:58.436Z,1757590138.436 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-09-11T11:28:58.436Z,1757590138.436 [DVL_micro] Communications Fault, FailCount= 1
2025-09-11T11:28:58.436Z,1757590138.436 [DVL_micro](ERROR): Communications Fault
2025-09-11T11:28:58.461Z,1757590138.461 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-09-11T11:28:58.931Z,1757590138.931 [DVL_micro](INFO): Powering down
2025-09-11T11:28:59.671Z,1757590139.671 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-09-11T11:28:59.672Z,1757590139.672 [DVL_micro] No Fault, FailCount= 1
2025-09-11T11:29:18.323Z,1757590158.323 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.791321
2025-09-11T11:29:31.555Z,1757590171.555 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-11T11:30:02.265Z,1757590202.265 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T11:39:46.955Z,1757590786.955 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2025-09-11T11:39:46.955Z,1757590786.955 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2025-09-11T11:39:46.955Z,1757590786.955 [BuoyancyServo] Communications Fault, FailCount= 1
2025-09-11T11:39:46.955Z,1757590786.955 [BuoyancyServo](ERROR): Communications Fault
2025-09-11T11:39:46.969Z,1757590786.969 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2025-09-11T11:39:48.905Z,1757590788.905 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-09-11T11:39:48.905Z,1757590788.905 [BuoyancyServo](INFO): Powering down
2025-09-11T11:39:49.699Z,1757590789.699 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2025-09-11T11:39:49.699Z,1757590789.699 [BuoyancyServo] No Fault, FailCount= 1
2025-09-11T11:39:50.119Z,1757590790.119 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-09-11T11:39:50.120Z,1757590790.120 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-09-11T11:39:56.608Z,1757590796.608 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T11:39:56.609Z,1757590796.609 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T11:39:56.610Z,1757590796.610 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T11:39:56.611Z,1757590796.611 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T11:39:56.613Z,1757590796.613 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T11:42:46.225Z,1757590966.225 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+12.7,0000.039.70,000.00
2025-09-11T11:42:46.225Z,1757590966.225 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+12.7,0000.039.70,000.00
2025-09-11T11:58:35.649Z,1757591915.649 [DVL_micro](ERROR): Failed to parse:
:SA,+02.11,+00.07,235.0
2025-09-11T11:58:37.655Z,1757591917.655 [DVL_micro](ERROR): Failed to parse:
:SA,+01.90,+00.06,236.0
2025-09-11T12:11:37.776Z,1757592697.776 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+0053.37,+0054.62,+0052.70
2025-09-11T12:24:29.437Z,1757593469.437 [keepstation:SurfaceComms] Running Loop=1
2025-09-11T12:24:29.437Z,1757593469.437 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-09-11T12:24:29.437Z,1757593469.437 [keepstation:SurfaceComms:A] Running Loop=1
2025-09-11T12:24:29.438Z,1757593469.438 [keepstation:SurfaceComms:A] Stopped
2025-09-11T12:24:29.438Z,1757593469.438 [keepstation:SurfaceComms:B] Running Loop=1
2025-09-11T12:24:29.438Z,1757593469.438 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-09-11T12:24:29.829Z,1757593469.829 [keepstation:KeepStation:Dive] Stopped
2025-09-11T12:24:29.829Z,1757593469.829 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-09-11T12:24:29.829Z,1757593469.829 [keepstation:KeepStation:Dive:A] Stopped
2025-09-11T12:24:29.829Z,1757593469.829 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-09-11T12:24:29.830Z,1757593469.830 [keepstation:DiveCmd:StationKeep] Stopped
2025-09-11T12:24:29.830Z,1757593469.830 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-09-11T12:24:29.830Z,1757593469.830 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-09-11T12:24:29.830Z,1757593469.830 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-09-11T12:24:29.830Z,1757593469.830 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-09-11T12:24:29.830Z,1757593469.830 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-09-11T12:24:29.832Z,1757593469.832 [keepstation:NeedComms] Running Loop=1
2025-09-11T12:24:29.832Z,1757593469.832 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-09-11T12:24:29.832Z,1757593469.832 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-09-11T12:24:29.832Z,1757593469.832 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T12:24:29.833Z,1757593469.833 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-09-11T12:24:29.833Z,1757593469.833 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-09-11T12:24:29.834Z,1757593469.834 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-09-11T12:24:29.834Z,1757593469.834 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-09-11T12:24:29.834Z,1757593469.834 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T12:24:29.835Z,1757593469.835 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T12:24:29.835Z,1757593469.835 [keepstation:NeedComms:A] Running Loop=1
2025-09-11T12:24:29.837Z,1757593469.837 [keepstation:NeedComms:A](INFO): last time_fix was:  1757589868.000000 second since 1970/01/01T00:00:00Z 
2025-09-11T12:24:29.837Z,1757593469.837 [keepstation:NeedComms:A] Stopped
2025-09-11T12:25:27.274Z,1757593527.274 [DVL_micro](ERROR): Failed to parse:
:SA,+14.07,-03.61,220.9
2025-09-11T12:25:49.074Z,1757593549.074 [keepstation:NeedComms:C] Running Loop=1
2025-09-11T12:25:49.559Z,1757593549.559 [Radio_Surface](INFO): Powering up
2025-09-11T12:25:55.415Z,1757593555.415 [DataOverHttps](INFO): Radio surface powered ON.
2025-09-11T12:26:21.788Z,1757593581.788 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T12:26:21.862Z,1757593581.862 [NAL9602](ERROR): received: 
+CSQ:0

OK176, 2, 0, 0, 0

OK
2025-09-11T12:26:25.551Z,1757593585.551 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T12:26:55.725Z,1757593615.725 [NAL9602](INFO): SBD MO Status=1, MOMSN=23176, MT Status=0, MTMSN=0
2025-09-11T12:26:55.725Z,1757593615.725 [NAL9602](INFO): No messages in MT queue
2025-09-11T12:26:56.675Z,1757593616.675 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T12:26:57.047Z,1757593617.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122656.00,A,3646.74217,N,12151.03854,W,0.836,15.87,110925,,,A*4C
2025-09-11T12:26:57.051Z,1757593617.051 [NAL9602](INFO): GPS fix at 20250911T122656: (36.779036, -121.850642)
2025-09-11T12:26:57.104Z,1757593617.104 [keepstation:NeedComms:C] Stopped
2025-09-11T12:26:57.104Z,1757593617.104 [keepstation:NeedComms:D] Running Loop=1
2025-09-11T12:27:14.413Z,1757593634.413 [NAL9602](INFO): SBD MO Status=1, MOMSN=23177, MT Status=0, MTMSN=0
2025-09-11T12:27:14.463Z,1757593634.463 [NAL9602](INFO): Sent 89 bytes from file Logs/20250911T033000/Courier0027.lzma
2025-09-11T12:27:14.463Z,1757593634.463 [NAL9602](INFO): Packets left to send: 0
2025-09-11T12:27:26.199Z,1757593646.199 [NAL9602](INFO): SBD MO Status=1, MOMSN=23178, MT Status=0, MTMSN=0
2025-09-11T12:27:26.259Z,1757593646.259 [NAL9602](INFO): Sent 332 bytes from file Logs/20250911T033000/Express0028.lzma
2025-09-11T12:27:26.259Z,1757593646.259 [NAL9602](INFO): Packets left to send: 1
2025-09-11T12:27:27.803Z,1757593647.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T12:27:37.733Z,1757593657.733 [NAL9602](INFO): SBD MO Status=1, MOMSN=23179, MT Status=0, MTMSN=0
2025-09-11T12:27:37.791Z,1757593657.791 [NAL9602](INFO): Sent 227 bytes from file Logs/20250911T033000/Express0028.lzma
2025-09-11T12:27:37.791Z,1757593657.791 [NAL9602](INFO): Packets left to send: 0
2025-09-11T12:27:47.428Z,1757593667.428 [NAL9602](INFO): SBD MO Status=0, MOMSN=23180, MT Status=0, MTMSN=0
2025-09-11T12:27:47.516Z,1757593667.516 [keepstation:NeedComms:D] Stopped
2025-09-11T12:27:47.516Z,1757593667.516 [keepstation:NeedComms:E] Running Loop=1
2025-09-11T12:27:49.859Z,1757593669.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,122748.00,A,3646.74962,N,12151.03082,W,1.089,18.31,110925,,,A*45
2025-09-11T12:27:49.861Z,1757593669.861 [NAL9602](INFO): GPS fix at 20250911T122748: (36.779160, -121.850514)
2025-09-11T12:27:49.875Z,1757593669.875 [keepstation:NeedComms:E] Stopped
2025-09-11T12:27:49.876Z,1757593669.876 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-09-11T12:27:49.876Z,1757593669.876 [keepstation:NeedComms] Stopped
2025-09-11T12:27:49.876Z,1757593669.876 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-09-11T12:27:49.876Z,1757593669.876 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-09-11T12:27:49.876Z,1757593669.876 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-11T12:27:50.292Z,1757593670.292 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-09-11T12:27:50.292Z,1757593670.292 [keepstation:SurfaceComms:B] Stopped
2025-09-11T12:27:50.292Z,1757593670.292 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-09-11T12:27:50.292Z,1757593670.292 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-09-11T12:27:50.292Z,1757593670.292 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-09-11T12:27:50.292Z,1757593670.292 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-09-11T12:27:50.671Z,1757593670.671 [keepstation:SurfaceComms:setTransit] Stopped
2025-09-11T12:27:50.671Z,1757593670.671 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-09-11T12:27:50.671Z,1757593670.671 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-09-11T12:27:50.671Z,1757593670.671 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-09-11T12:27:50.671Z,1757593670.671 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-09-11T12:27:50.671Z,1757593670.671 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-09-11T12:27:51.092Z,1757593671.092 [keepstation:SurfaceComms:setStation] Stopped
2025-09-11T12:27:51.092Z,1757593671.092 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-09-11T12:27:51.092Z,1757593671.092 [keepstation:SurfaceComms:setStation:A] Stopped
2025-09-11T12:27:51.093Z,1757593671.093 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-09-11T12:27:51.093Z,1757593671.093 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-09-11T12:27:51.093Z,1757593671.093 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-09-11T12:27:51.484Z,1757593671.484 [keepstation:SurfaceComms:setSink:A] Stopped
2025-09-11T12:27:51.484Z,1757593671.484 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-09-11T12:27:51.485Z,1757593671.485 [keepstation:SurfaceComms:setSink] Stopped
2025-09-11T12:27:51.485Z,1757593671.485 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-09-11T12:27:51.485Z,1757593671.485 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-09-11T12:27:51.485Z,1757593671.485 [keepstation:SurfaceComms] Stopped
2025-09-11T12:27:51.485Z,1757593671.485 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-09-11T12:27:51.891Z,1757593671.891 [keepstation:KeepStation:Dive] Running Loop=1
2025-09-11T12:27:51.891Z,1757593671.891 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-09-11T12:27:51.891Z,1757593671.891 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-09-11T12:27:51.891Z,1757593671.891 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-09-11T12:27:51.892Z,1757593671.892 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-09-11T12:27:51.893Z,1757593671.893 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-09-11T12:27:51.893Z,1757593671.893 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-09-11T12:27:51.893Z,1757593671.893 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-09-11T12:27:51.893Z,1757593671.893 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-09-11T12:27:58.927Z,1757593678.927 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T12:28:17.832Z,1757593697.832 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003247
2025-09-11T12:28:22.589Z,1757593702.589 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-11T12:29:31.679Z,1757593771.679 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T12:29:43.805Z,1757593783.805 [DVL_micro](ERROR): Failed to parse:
:SA,-01.98,-01.95,228.7
2025-09-11T12:30:02.803Z,1757593802.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T12:30:09.763Z,1757593809.763 [Radio_Surface](INFO): Powering down
2025-09-11T12:30:33.927Z,1757593833.927 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T12:30:34.931Z,1757593834.931 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-09-11T12:30:57.751Z,1757593857.751 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-09-11T12:30:57.751Z,1757593857.751 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-09-11T12:30:57.751Z,1757593857.751 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T12:30:57.752Z,1757593857.752 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-09-11T12:30:58.146Z,1757593858.146 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-09-11T12:30:58.146Z,1757593858.146 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-09-11T12:30:58.146Z,1757593858.146 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-09-11T12:30:58.146Z,1757593858.146 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-09-11T12:30:58.147Z,1757593858.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T12:30:58.147Z,1757593858.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-09-11T12:30:58.548Z,1757593858.548 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-09-11T12:30:58.549Z,1757593858.549 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T12:30:58.549Z,1757593858.549 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-09-11T12:30:58.549Z,1757593858.549 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-09-11T12:30:58.549Z,1757593858.549 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T12:30:58.549Z,1757593858.549 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-09-11T12:30:58.550Z,1757593858.550 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-09-11T12:30:58.550Z,1757593858.550 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-09-11T12:30:58.550Z,1757593858.550 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-09-11T12:30:58.550Z,1757593858.550 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-09-11T12:30:58.550Z,1757593858.550 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-09-11T12:30:58.550Z,1757593858.550 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-09-11T12:30:58.965Z,1757593858.965 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-09-11T12:30:58.965Z,1757593858.965 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-09-11T12:30:58.965Z,1757593858.965 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-09-11T12:30:58.965Z,1757593858.965 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-09-11T12:30:58.965Z,1757593858.965 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-09-11T12:30:58.965Z,1757593858.965 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-09-11T12:30:58.966Z,1757593858.966 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-09-11T12:31:51.242Z,1757593911.242 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.725739
2025-09-11T12:43:59.087Z,1757594639.087 [DVL_micro](ERROR): Failed to parse:
:WI,+00010,-00158,-00064,+00000,A
2025-09-11T12:44:01.095Z,1757594641.095 [DVL_micro](ERROR): Failed to parse:
:WI,-00094,-00387,-00006,+00000,A
2025-09-11T12:44:05.161Z,1757594645.161 [DVL_micro](ERROR): Failed to parse:
:RD,+0058.33,+0062.49,+9999.99,+0058.18
2025-09-11T12:44:07.157Z,1757594647.157 [DVL_micro](ERROR): Failed to parse:
:RD,+0058.18,+0062.27,+9999.99,+0057.70
2025-09-11T12:44:09.182Z,1757594649.182 [DVL_micro](ERROR): Failed to parse:
:RD,+0058.14,+0062.37,+9999.99,+0057.49
2025-09-11T12:47:31.993Z,1757594851.993 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T12:47:31.994Z,1757594851.994 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-11T12:47:31.995Z,1757594851.995 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-11T12:47:31.996Z,1757594851.996 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-11T12:47:31.999Z,1757594851.999 [BPC1](INFO): Received data from all battery sticks.
2025-09-11T12:54:15.998Z,1757595255.998 [DVL_micro](ERROR): Failed to parse:
:SA,-01.50,-00.56,172.0