2026-03-12T20:59:17.969Z,1773349157.969 [CommandExec](IMPORTANT): got command restart logs
2026-03-12T21:01:51.194Z,1773349311.194 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T210150
2026-03-12T21:01:58.611Z,1773349318.611 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.NeedCommsTime 15 min;set keepstation.Latitude 36.808 degree;set keepstation.Longitude -121.847 degree;run
2026-03-12T21:01:58.669Z,1773349318.669 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2026-03-12T21:01:58.671Z,1773349318.671 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2026-03-12T21:01:58.671Z,1773349318.671 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2026-03-12T21:01:58.674Z,1773349318.674 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2026-03-12T21:02:00.011Z,1773349320.011 [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="HandleNan">
            <Sequence />
            <Break>
                <Not>
                    <IsNaN>
                        <Arg Name="Latitude" />
                    </IsNaN>
                </Not>
                <And>
                    <Not>
                        <IsNaN>
                            <Arg Name="Longitude" />
                        </IsNaN>
                    </Not>
                </And>
            </Break>
            <Aggregate Id="SetOnStation">
                <Sequence />
                <Break>
                    <Not>
                        <IsNaN>
                            <Arg Name="Latitude" />
                        </IsNaN>
                    </Not>
                    <Or>
                        <Not>
                            <IsNaN>
                                <Arg Name="Longitude" />
                            </IsNaN>
                        </Not>
                    </Or>
                </Break>
                <Assign>
                    <Sequence />
                    <Arg Name="OnStation" />
                    <True />
                </Assign>
            </Aggregate>
            <Aggregate Id="SetLat">
                <Sequence />
                <Break>
                    <Not>
                        <IsNaN>
                            <Arg Name="Latitude" />
                        </IsNaN>
                    </Not>
                </Break>
                <Assign>
                    <Sequence />
                    <Arg Name="Latitude" />
                    <Universal:latitude_fix />
                </Assign>
            </Aggregate>
            <Aggregate Id="SetLon">
                <Sequence />
                <Break>
                    <Not>
                        <IsNaN>
                            <Arg Name="Longitude" />
                        </IsNaN>
                    </Not>
                </Break>
                <Assign>
                    <Sequence />
                    <Arg Name="Longitude" />
                    <Universal:longitude_fix />
                </Assign>
            </Aggregate>
        </Aggregate>
        <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>

2026-03-12T21:02:00.021Z,1773349320.021 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2026-03-12T21:02:00.024Z,1773349320.024 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2026-03-12T21:02:00.028Z,1773349320.028 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2026-03-12T21:02:00.031Z,1773349320.031 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2026-03-12T21:02:00.034Z,1773349320.034 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2026-03-12T21:02:00.037Z,1773349320.037 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2026-03-12T21:02:00.040Z,1773349320.040 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2026-03-12T21:02:00.044Z,1773349320.044 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2026-03-12T21:02:00.047Z,1773349320.047 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2026-03-12T21:02:00.070Z,1773349320.070 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2026-03-12T21:02:00.088Z,1773349320.088 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2026-03-12T21:02:00.097Z,1773349320.097 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2026-03-12T21:02:00.109Z,1773349320.109 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2026-03-12T21:02:00.113Z,1773349320.113 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2026-03-12T21:02:00.129Z,1773349320.129 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2026-03-12T21:02:00.133Z,1773349320.133 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2026-03-12T21:02:00.150Z,1773349320.150 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2026-03-12T21:02:00.154Z,1773349320.154 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2026-03-12T21:02:00.165Z,1773349320.165 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2026-03-12T21:02:00.172Z,1773349320.172 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2026-03-12T21:02:00.173Z,1773349320.173 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2026-03-12T21:02:00.173Z,1773349320.173 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2026-03-12T21:02:00.175Z,1773349320.175 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2026-03-12T21:02:00.590Z,1773349320.590 [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>1500</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="DoingCommsMode">
        <Units:count />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="RestoreMode">
        <Units:count />
        <Value>0</Value>
    </DefineArg>
    <DefineOutput Name="IsActive">
        <False />
    </DefineOutput>
    <DefineOutput Name="RequiredDiveMode">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <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>
    <Aggregate Id="setActive">
        <Sequence />
        <Assign>
            <Sequence />
            <Arg Name="RequiredDiveMode" />
            <Arg Name="DoingCommsMode" />
        </Assign>
        <Assign>
            <Sequence />
            <Arg Name="IsActive" />
            <True />
        </Assign>
    </Aggregate>
    <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 Id="setRestoreMode">
        <Sequence />
        <Assign>
            <Sequence />
            <Arg Name="RequiredDiveMode" />
            <Arg Name="RestoreMode" />
        </Assign>
    </Aggregate>
    <Aggregate Id="setInactive">
        <Sequence />
        <Assign>
            <Sequence />
            <Arg Name="IsActive" />
            <False />
        </Assign>
    </Aggregate>
</Aggregate>

2026-03-12T21:02:00.597Z,1773349320.597 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2026-03-12T21:02:00.601Z,1773349320.601 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2026-03-12T21:02:00.604Z,1773349320.604 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2026-03-12T21:02:00.607Z,1773349320.607 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2026-03-12T21:02:00.610Z,1773349320.610 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2026-03-12T21:02:00.665Z,1773349320.665 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1500.000000 s
2026-03-12T21:02:00.672Z,1773349320.672 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2026-03-12T21:02:00.680Z,1773349320.680 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2026-03-12T21:02:00.685Z,1773349320.685 [MissionManager](INFO): DefineArg keepstation:NeedComms.DoingCommsMode = 0.000000 count
2026-03-12T21:02:00.700Z,1773349320.700 [MissionManager](INFO): DefineArg keepstation:NeedComms.RestoreMode = 0.000000 count
2026-03-12T21:02:00.708Z,1773349320.708 [MissionManager](INFO): DefineOutput keepstation:NeedComms.IsActive = 0 bool
2026-03-12T21:02:00.711Z,1773349320.711 [MissionManager](INFO): DefineOutput keepstation:NeedComms.RequiredDiveMode = 0.000000 count
2026-03-12T21:02:00.783Z,1773349320.783 [keepstation:NeedComms:C.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-12T21:02:00.849Z,1773349320.849 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2026-03-12T21:02:00.849Z,1773349320.849 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2026-03-12T21:02:00.850Z,1773349320.850 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2026-03-12T21:02:00.991Z,1773349320.991 [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>

2026-03-12T21:02:01.001Z,1773349321.001 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2026-03-12T21:02:01.012Z,1773349321.012 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2026-03-12T21:02:01.015Z,1773349321.015 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2026-03-12T21:02:01.018Z,1773349321.018 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2026-03-12T21:02:01.024Z,1773349321.024 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2026-03-12T21:02:01.045Z,1773349321.045 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2026-03-12T21:02:01.068Z,1773349321.068 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2026-03-12T21:02:01.087Z,1773349321.087 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2026-03-12T21:02:01.092Z,1773349321.092 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2026-03-12T21:02:01.093Z,1773349321.093 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2026-03-12T21:02:01.164Z,1773349321.164 [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>

2026-03-12T21:02:01.172Z,1773349321.172 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2026-03-12T21:02:01.175Z,1773349321.175 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2026-03-12T21:02:01.190Z,1773349321.190 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2026-03-12T21:02:01.190Z,1773349321.190 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2026-03-12T21:02:01.191Z,1773349321.191 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2026-03-12T21:02:01.403Z,1773349321.403 [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>

2026-03-12T21:02:01.412Z,1773349321.412 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2026-03-12T21:02:01.420Z,1773349321.420 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2026-03-12T21:02:01.422Z,1773349321.422 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2026-03-12T21:02:01.426Z,1773349321.426 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2026-03-12T21:02:01.446Z,1773349321.446 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2026-03-12T21:02:01.466Z,1773349321.466 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2026-03-12T21:02:01.476Z,1773349321.476 [keepstation:H.Pitch](DEBUG): Construct.
2026-03-12T21:02:01.610Z,1773349321.610 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2026-03-12T21:02:01.617Z,1773349321.617 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2026-03-12T21:02:01.626Z,1773349321.626 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2026-03-12T21:02:01.664Z,1773349321.664 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2026-03-12T21:02:01.671Z,1773349321.671 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2026-03-12T21:02:01.681Z,1773349321.681 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2026-03-12T21:02:01.696Z,1773349321.696 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2026-03-12T21:02:01.712Z,1773349321.712 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2026-03-12T21:02:01.728Z,1773349321.728 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2026-03-12T21:02:01.760Z,1773349321.760 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2026-03-12T21:02:01.801Z,1773349321.801 [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 HandleNan {
      run in sequence

      break if (
        not isNaN ( Latitude )
        and not isNaN ( Longitude )
      )

      # If both lat and lon are nan, we are implicitly on station and should skip initial transit, so force OnStation to true
      aggregate SetOnStation {
        run in sequence

        break if (
          not isNaN ( Latitude )
          or not isNaN ( Longitude )
        )

        assign in sequence OnStation = true
      }

      # The "assigns" here mean we should only ever run these aggregates once
      # An initial NaN lat/lon should be set here during the initial surface NeedComms call
      # Use the most recent fix -- if there isn't one, the keepstation behavior will use Universal:lat/lon
      aggregate SetLat {
        run in sequence

        break if ( not isNaN ( Latitude ) )

        assign in sequence Latitude = Universal:latitude_fix
      }

      aggregate SetLon {
        run in sequence

        break if ( not isNaN ( Longitude ) )

        assign in sequence Longitude = Universal:longitude_fix
      }
    }

    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
    }
  }
}


2026-03-12T21:02:01.801Z,1773349321.801 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2026-03-12T21:02:12.027Z,1773349332.027 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 15 minute
2026-03-12T21:02:12.072Z,1773349332.072 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.808 degree
2026-03-12T21:02:12.073Z,1773349332.073 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2026-03-12T21:02:12.073Z,1773349332.073 [CommandExec](IMPORTANT): got command run
2026-03-12T21:02:12.091Z,1773349332.091 [CommandExec](IMPORTANT): Running 
2026-03-12T21:02:12.470Z,1773349332.470 [Default] Stopped
2026-03-12T21:02:12.470Z,1773349332.470 [Default](DEBUG): Aggregate::uninitialize Default
2026-03-12T21:02:12.470Z,1773349332.470 [Default:B.GoToSurface] Stopped
2026-03-12T21:02:12.471Z,1773349332.471 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T21:02:12.471Z,1773349332.471 [Default:CheckIn] Stopped
2026-03-12T21:02:12.471Z,1773349332.471 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-12T21:02:12.471Z,1773349332.471 [Default:CheckIn:C.Wait] Stopped
2026-03-12T21:02:12.471Z,1773349332.471 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T21:02:12.471Z,1773349332.471 [MissionManager](IMPORTANT): Started mission keepstation
2026-03-12T21:02:12.471Z,1773349332.471 [keepstation] Running Loop=1
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation](DEBUG): Aggregate::initialize keepstation
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes] Running Loop=1
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2026-03-12T21:02:12.472Z,1773349332.472 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:BackseatDriver] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly:A] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly:B] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly:C] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly:D] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly:E.Wait] Running Loop=1
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2026-03-12T21:02:12.473Z,1773349332.473 [keepstation:H.Pitch] Running Loop=1
2026-03-12T21:02:12.474Z,1773349332.474 [keepstation:H.Pitch](DEBUG): Initialize.
2026-03-12T21:02:12.474Z,1773349332.474 [keepstation:B] Running Loop=1
2026-03-12T21:02:12.475Z,1773349332.475 [keepstation:H.Pitch] Running Loop=1
2026-03-12T21:02:12.475Z,1773349332.475 [keepstation:PowerOnly] Running Loop=1
2026-03-12T21:02:12.475Z,1773349332.475 [keepstation:PowerOnly:D] Stopped
2026-03-12T21:02:12.476Z,1773349332.476 [keepstation:PowerOnly:C] Stopped
2026-03-12T21:02:12.476Z,1773349332.476 [keepstation:PowerOnly:B] Stopped
2026-03-12T21:02:12.476Z,1773349332.476 [keepstation:PowerOnly:A] Stopped
2026-03-12T21:02:12.476Z,1773349332.476 [keepstation:BackseatDriver] Running Loop=1
2026-03-12T21:02:12.476Z,1773349332.476 [keepstation:StandardEnvelopes] Running Loop=1
2026-03-12T21:02:12.476Z,1773349332.476 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2026-03-12T21:02:12.476Z,1773349332.476 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2026-03-12T21:02:12.477Z,1773349332.477 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2026-03-12T21:02:12.477Z,1773349332.477 [keepstation:B] Stopped
2026-03-12T21:02:12.477Z,1773349332.477 [keepstation:D] Running Loop=1
2026-03-12T21:02:12.884Z,1773349332.884 [keepstation:D] Stopped
2026-03-12T21:02:12.884Z,1773349332.884 [keepstation:E] Running Loop=1
2026-03-12T21:02:13.272Z,1773349333.272 [keepstation:E] Stopped
2026-03-12T21:02:13.273Z,1773349333.273 [keepstation:StartingMission] Running Loop=1
2026-03-12T21:02:13.273Z,1773349333.273 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2026-03-12T21:02:13.677Z,1773349333.677 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T21:02:13.677Z,1773349333.677 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T21:02:13.677Z,1773349333.677 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T21:02:13.677Z,1773349333.677 [keepstation:SurfaceComms:A] Stopped
2026-03-12T21:02:13.678Z,1773349333.678 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T21:02:13.678Z,1773349333.678 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T21:02:14.088Z,1773349334.088 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2026-03-12T21:02:14.088Z,1773349334.088 [keepstation:NeedComms] Running Loop=1
2026-03-12T21:02:14.088Z,1773349334.088 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T21:02:14.088Z,1773349334.088 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T21:02:14.088Z,1773349334.088 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T21:02:14.089Z,1773349334.089 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T21:02:14.089Z,1773349334.089 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T21:02:14.089Z,1773349334.089 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T21:02:14.090Z,1773349334.090 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T21:02:14.090Z,1773349334.090 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T21:02:14.090Z,1773349334.090 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T21:02:14.091Z,1773349334.091 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T21:02:14.092Z,1773349334.092 [keepstation:NeedComms:A](INFO): last time_fix was:  1773348950.000000 second since 1970/01/01T00:00:00Z 
2026-03-12T21:02:14.092Z,1773349334.092 [keepstation:NeedComms:A] Stopped
2026-03-12T21:02:14.092Z,1773349334.092 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T21:02:14.092Z,1773349334.092 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T21:02:14.093Z,1773349334.093 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T21:02:14.501Z,1773349334.501 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T21:02:14.501Z,1773349334.501 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T21:02:14.893Z,1773349334.893 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T21:02:14.893Z,1773349334.893 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T21:02:14.893Z,1773349334.893 [keepstation:NeedComms:setActive] Stopped
2026-03-12T21:02:14.893Z,1773349334.893 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T21:02:15.294Z,1773349335.294 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T21:02:15.704Z,1773349335.704 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-12T21:02:17.310Z,1773349337.310 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210216.00,A,3648.76091,N,12150.76223,W,0.564,136.93,120326,,,D*77
2026-03-12T21:02:17.312Z,1773349337.312 [NAL9602](INFO): GPS fix at 20260312T210216: (36.812682, -121.846037)
2026-03-12T21:02:17.326Z,1773349337.326 [keepstation:NeedComms:D] Stopped
2026-03-12T21:02:17.326Z,1773349337.326 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T21:02:17.742Z,1773349337.742 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-12T21:02:25.263Z,1773349345.263 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260312T192715/Courier0034.lzma
2026-03-12T21:02:26.265Z,1773349346.265 [DataOverHttps](INFO): Moved sent file to Logs/20260312T192715/Courier0034.lzma.bak
2026-03-12T21:02:26.265Z,1773349346.265 [DataOverHttps](INFO): SBD MOMSN=26725775
2026-03-12T21:02:42.239Z,1773349362.239 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20260312T205917/Courier0000.lzma
2026-03-12T21:02:43.241Z,1773349363.241 [DataOverHttps](INFO): Moved sent file to Logs/20260312T205917/Courier0000.lzma.bak
2026-03-12T21:02:43.241Z,1773349363.241 [DataOverHttps](INFO): SBD MOMSN=26725777
2026-03-12T21:02:44.382Z,1773349364.382 [NAL9602](INFO): SBD MO Status=0, MOMSN=32629, MT Status=0, MTMSN=0
2026-03-12T21:02:44.382Z,1773349364.382 [NAL9602](INFO): No messages in MT queue
2026-03-12T21:03:02.180Z,1773349382.180 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20260312T192715/Express0035.lzma
2026-03-12T21:03:03.181Z,1773349383.181 [DataOverHttps](INFO): Moved sent file to Logs/20260312T192715/Express0035.lzma.bak
2026-03-12T21:03:03.181Z,1773349383.181 [DataOverHttps](INFO): SBD MOMSN=26725788
2026-03-12T21:03:15.140Z,1773349395.140 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T21:03:19.248Z,1773349399.248 [DataOverHttps](INFO): Sending 651 bytes from file Logs/20260312T205917/Express0001.lzma
2026-03-12T21:03:20.241Z,1773349400.241 [DataOverHttps](INFO): Moved sent file to Logs/20260312T205917/Express0001.lzma.bak
2026-03-12T21:03:20.241Z,1773349400.241 [DataOverHttps](INFO): SBD MOMSN=26725794
2026-03-12T21:03:36.111Z,1773349416.111 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20260312T205917/Express0004.lzma
2026-03-12T21:03:37.113Z,1773349417.113 [DataOverHttps](INFO): Moved sent file to Logs/20260312T205917/Express0004.lzma.bak
2026-03-12T21:03:37.113Z,1773349417.113 [DataOverHttps](INFO): SBD MOMSN=26725811
2026-03-12T21:03:38.594Z,1773349418.594 [keepstation:NeedComms:E] Stopped
2026-03-12T21:03:38.594Z,1773349418.594 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T21:03:38.000Z,1773349419.000 [keepstation:NeedComms:F](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-12T21:03:40.603Z,1773349420.603 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210339.00,A,3648.75912,N,12150.74859,W,0.952,94.42,120326,,,D*43
2026-03-12T21:03:40.605Z,1773349420.605 [NAL9602](INFO): GPS fix at 20260312T210339: (36.812652, -121.845810)
2026-03-12T21:03:40.662Z,1773349420.662 [keepstation:NeedComms:F] Stopped
2026-03-12T21:03:40.662Z,1773349420.662 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T21:03:40.663Z,1773349420.663 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:03:40.663Z,1773349420.663 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T21:03:41.037Z,1773349421.037 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T21:03:41.037Z,1773349421.037 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T21:03:41.038Z,1773349421.038 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T21:03:41.038Z,1773349421.038 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:03:41.038Z,1773349421.038 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T21:03:41.038Z,1773349421.038 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T21:03:41.038Z,1773349421.038 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T21:03:41.437Z,1773349421.437 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T21:03:41.437Z,1773349421.437 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T21:03:41.437Z,1773349421.437 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T21:03:41.438Z,1773349421.438 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T21:03:41.438Z,1773349421.438 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T21:03:41.438Z,1773349421.438 [keepstation:NeedComms] Stopped
2026-03-12T21:03:41.438Z,1773349421.438 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T21:03:41.439Z,1773349421.439 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T21:03:41.439Z,1773349421.439 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:B] Stopped
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:03:41.860Z,1773349421.860 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T21:03:42.268Z,1773349422.268 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T21:03:42.268Z,1773349422.268 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:03:42.268Z,1773349422.268 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T21:03:42.268Z,1773349422.268 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:03:42.268Z,1773349422.268 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T21:03:42.269Z,1773349422.269 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T21:03:42.269Z,1773349422.269 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T21:03:42.269Z,1773349422.269 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T21:03:42.648Z,1773349422.648 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T21:03:42.648Z,1773349422.648 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2026-03-12T21:03:42.648Z,1773349422.648 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T21:03:42.648Z,1773349422.648 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T21:03:42.648Z,1773349422.648 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T21:03:42.648Z,1773349422.648 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T21:03:42.649Z,1773349422.649 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T21:03:43.050Z,1773349423.050 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T21:03:43.050Z,1773349423.050 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T21:03:43.050Z,1773349423.050 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T21:03:43.050Z,1773349423.050 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T21:03:43.050Z,1773349423.050 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T21:03:43.050Z,1773349423.050 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T21:03:43.465Z,1773349423.465 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T21:03:43.465Z,1773349423.465 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T21:03:43.465Z,1773349423.465 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T21:03:43.465Z,1773349423.465 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T21:03:43.465Z,1773349423.465 [keepstation:SurfaceComms] Stopped
2026-03-12T21:03:43.465Z,1773349423.465 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T21:03:43.853Z,1773349423.853 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2026-03-12T21:03:43.853Z,1773349423.853 [keepstation:StartingMission] Stopped
2026-03-12T21:03:43.853Z,1773349423.853 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2026-03-12T21:03:43.853Z,1773349423.853 [keepstation:TransitToStation] Running Loop=1
2026-03-12T21:03:43.854Z,1773349423.854 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2026-03-12T21:03:43.854Z,1773349423.854 [keepstation:TransitToStation:Dive] Running Loop=1
2026-03-12T21:03:43.854Z,1773349423.854 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2026-03-12T21:03:43.854Z,1773349423.854 [keepstation:TransitToStation:Dive:A] Running Loop=1
2026-03-12T21:03:43.854Z,1773349423.854 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2026-03-12T21:03:43.854Z,1773349423.854 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2026-03-12T21:03:43.854Z,1773349423.854 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2026-03-12T21:03:43.855Z,1773349423.855 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.807999,-121.847000
2026-03-12T21:03:44.254Z,1773349424.254 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.807999,-121.847000
2026-03-12T21:03:44.255Z,1773349424.255 [keepstation:TransitToStation:Dive] Running Loop=1
2026-03-12T21:03:44.255Z,1773349424.255 [keepstation:DiveCmd] Running Loop=1
2026-03-12T21:03:44.255Z,1773349424.255 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2026-03-12T21:03:44.255Z,1773349424.255 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2026-03-12T21:03:44.255Z,1773349424.255 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2026-03-12T21:03:44.255Z,1773349424.255 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2026-03-12T21:03:44.255Z,1773349424.255 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2026-03-12T21:03:44.256Z,1773349424.256 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T21:03:44.257Z,1773349424.257 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T21:03:44.258Z,1773349424.258 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T21:03:44.258Z,1773349424.258 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T21:03:44.258Z,1773349424.258 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2026-03-12T21:03:44.259Z,1773349424.259 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2026-03-12T21:03:44.260Z,1773349424.260 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2026-03-12T21:03:44.260Z,1773349424.260 [keepstation:DiveCmd:SurfaceSink] Stopped
2026-03-12T21:03:44.260Z,1773349424.260 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2026-03-12T21:03:44.260Z,1773349424.260 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2026-03-12T21:03:44.260Z,1773349424.260 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2026-03-12T21:03:44.261Z,1773349424.261 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2026-03-12T21:03:44.261Z,1773349424.261 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2026-03-12T21:03:44.261Z,1773349424.261 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2026-03-12T21:03:44.261Z,1773349424.261 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2026-03-12T21:03:44.261Z,1773349424.261 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2026-03-12T21:03:51.581Z,1773349431.581 [NAL9602](INFO): SBD MO Status=0, MOMSN=32630, MT Status=0, MTMSN=0
2026-03-12T21:03:51.581Z,1773349431.581 [NAL9602](INFO): No messages in MT queue
2026-03-12T21:04:22.283Z,1773349462.283 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T21:04:24.124Z,1773349464.124 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:04:24.377Z,1773349464.377 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:04:24.628Z,1773349464.628 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:04:27.905Z,1773349467.905 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:04:27.7296

2026-03-12T21:04:27.905Z,1773349467.905 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:04:29.967Z,1773349469.967 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 30.00 deg, mass-position -2.43 mm (4 active estimators).
2026-03-12T21:04:37.984Z,1773349477.984 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:04:38.236Z,1773349478.236 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:04:38.489Z,1773349478.489 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:04:41.765Z,1773349481.765 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:04:41.5797

2026-03-12T21:04:41.765Z,1773349481.765 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:04:51.884Z,1773349491.884 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:04:52.136Z,1773349492.136 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:04:52.389Z,1773349492.389 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:04:55.680Z,1773349495.680 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:04:55.4798

2026-03-12T21:04:55.680Z,1773349495.680 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:05:05.770Z,1773349505.770 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:05:05.770Z,1773349505.770 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:05:06.020Z,1773349506.020 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:05:06.272Z,1773349506.272 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:05:06.525Z,1773349506.525 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:05:09.036Z,1773349509.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:05:09.806Z,1773349509.806 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:05:09.6299

2026-03-12T21:05:09.806Z,1773349509.806 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:05:19.884Z,1773349519.884 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:05:20.136Z,1773349520.136 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:05:20.389Z,1773349520.389 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:05:23.665Z,1773349523.665 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:05:23.4799

2026-03-12T21:05:23.665Z,1773349523.665 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:05:30.403Z,1773349530.403 [Radio_Surface](INFO): Powering down
2026-03-12T21:05:33.744Z,1773349533.744 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:05:33.996Z,1773349533.996 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:05:34.249Z,1773349534.249 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:05:37.525Z,1773349537.525 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:05:37.3300

2026-03-12T21:05:37.525Z,1773349537.525 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:05:40.160Z,1773349540.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:05:41.164Z,1773349541.164 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T21:05:47.604Z,1773349547.604 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:05:47.857Z,1773349547.857 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:05:48.108Z,1773349548.108 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:05:51.385Z,1773349551.385 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:05:51.2301

2026-03-12T21:05:51.385Z,1773349551.385 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:06:01.476Z,1773349561.476 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:06:01.476Z,1773349561.476 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:06:01.728Z,1773349561.728 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:06:01.980Z,1773349561.980 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:06:02.232Z,1773349562.232 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:06:05.513Z,1773349565.513 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:06:05.3302

2026-03-12T21:06:05.514Z,1773349565.514 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:06:15.602Z,1773349575.602 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:06:15.852Z,1773349575.852 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:06:16.104Z,1773349576.104 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:06:19.382Z,1773349579.382 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:06:19.2303

2026-03-12T21:06:19.382Z,1773349579.382 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:06:29.465Z,1773349589.465 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:06:29.716Z,1773349589.716 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:06:29.968Z,1773349589.968 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:06:33.245Z,1773349593.245 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:06:33.0804

2026-03-12T21:06:33.245Z,1773349593.245 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:06:43.332Z,1773349603.332 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:06:43.585Z,1773349603.585 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:06:43.836Z,1773349603.836 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:06:47.113Z,1773349607.113 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:06:46.9304

2026-03-12T21:06:47.113Z,1773349607.113 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:06:57.196Z,1773349617.196 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:06:57.196Z,1773349617.196 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:06:57.448Z,1773349617.448 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:06:57.700Z,1773349617.700 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:06:57.952Z,1773349617.952 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:07:01.229Z,1773349621.229 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:07:01.0805

2026-03-12T21:07:01.229Z,1773349621.229 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:07:11.308Z,1773349631.308 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:07:11.560Z,1773349631.560 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:07:11.812Z,1773349631.812 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:07:15.090Z,1773349635.090 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:07:14.9306

2026-03-12T21:07:15.090Z,1773349635.090 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:07:17.588Z,1773349637.588 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.774384
2026-03-12T21:07:22.657Z,1773349642.657 [DAT](INFO): DAT read: Rx Time:21:07:21.8673

2026-03-12T21:07:22.657Z,1773349642.657 [DAT](INFO): Rx dataTimestamp_ set to:1773349642.656776
2026-03-12T21:07:23.164Z,1773349643.164 [DAT](INFO): DAT read: 21:07:21.8673 LVL= 32752, 32753, 31602, 10579, AGC= 74, IDX= 493, 0.28,-1.352,-2.472,-0.650,-1.573, PHS= 0.187,-0.914, 0.911, RAW= 173.2,  -2.3, CAL= 176.2,  -5.9, ROT= 333.8,   5.9

2026-03-12T21:07:23.165Z,1773349643.165 [DAT](INFO): got valid direction response:
 21:07:21.8673 LVL= 32752, 32753, 31602, 10579, AGC= 74, IDX= 493, 0.28,-1.352,-2.472,-0.650,-1.573, PHS= 0.187,-0.914, 0.911, RAW= 173.2,  -2.3, CAL= 176.2,  -5.9, ROT= 333.8,   5.9

2026-03-12T21:07:23.165Z,1773349643.165 [DAT](INFO): #Rx 32: Read direction message, but no range.
2026-03-12T21:07:23.166Z,1773349643.166 [DAT](INFO): direction in FSK: [0.892505,-0.439167,-0.102793]
2026-03-12T21:07:23.412Z,1773349643.412 [DAT](INFO): DAT read: 

2026-03-12T21:07:23.413Z,1773349643.413 [DAT](INFO): DAT read: DATA(0002):~~

2026-03-12T21:07:23.413Z,1773349643.413 [DAT](INFO): Got DATA 2
2026-03-12T21:07:23.414Z,1773349643.414 [DAT](INFO): DAT read: Source:012  Destination:001

2026-03-12T21:07:23.414Z,1773349643.414 [DAT](INFO): Got Src/Dest after DATA 
2026-03-12T21:07:23.414Z,1773349643.414 [DAT](INFO): DATA Src=12, Dst=1
2026-03-12T21:07:23.416Z,1773349643.416 [DAT](INFO): DAT read: CRC:Pass MPD:03.4 PSNR:11.1 AGC:71 SPD:+1.4 CCERR:009

2026-03-12T21:07:23.416Z,1773349643.416 [DAT](INFO): Got CRC:Pass
2026-03-12T21:07:23.416Z,1773349643.416 [DAT](INFO): Got CRC:Pass
2026-03-12T21:07:23.416Z,1773349643.416 [DAT](INFO): Incoming data is intended for us
2026-03-12T21:07:23.416Z,1773349643.416 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2026-03-12T21:07:23.416Z,1773349643.416 [DAT](INFO): Got ack
2026-03-12T21:07:23.416Z,1773349643.416 [DAT](INFO): DAT read: 

2026-03-12T21:07:23.417Z,1773349643.417 [DAT](INFO): DAT read: <EOP>

2026-03-12T21:07:23.417Z,1773349643.417 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:07:23.664Z,1773349643.664 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:07:23.916Z,1773349643.916 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:07:27.193Z,1773349647.193 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:07:27.0307

2026-03-12T21:07:27.193Z,1773349647.193 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:07:34.009Z,1773349654.009 [DAT](INFO): DAT read: Rx Time:21:07:33.2136

2026-03-12T21:07:34.009Z,1773349654.009 [DAT](INFO): Rx dataTimestamp_ set to:1773349654.008827
2026-03-12T21:07:34.525Z,1773349654.525 [DAT](INFO): DAT read: 21:07:33.2136 LVL= 28640, 28193, 20642,  6291, AGC= 74, IDX= 150,-0.11,-0.485,-1.501, 0.418,-0.515, PHS=-0.004,-1.001, 0.921, RAW= 178.7,   1.0, CAL= 181.9,  -3.1, ROT= 328.1,   3.1

2026-03-12T21:07:34.528Z,1773349654.528 [DAT](INFO): got valid direction response:
 21:07:33.2136 LVL= 28640, 28193, 20642,  6291, AGC= 74, IDX= 150,-0.11,-0.485,-1.501, 0.418,-0.515, PHS=-0.004,-1.001, 0.921, RAW= 178.7,   1.0, CAL= 181.9,  -3.1, ROT= 328.1,   3.1

2026-03-12T21:07:34.529Z,1773349654.529 [DAT](INFO): DAT read: 

2026-03-12T21:07:34.547Z,1773349654.547 [DAT](INFO): DAT read: DATA(0002):~~

2026-03-12T21:07:34.548Z,1773349654.548 [DAT](INFO): Got DATA 2
2026-03-12T21:07:34.549Z,1773349654.549 [DAT](INFO): DAT read: Source:012  Destination:001

2026-03-12T21:07:34.549Z,1773349654.549 [DAT](INFO): Got Src/Dest after DATA 
2026-03-12T21:07:34.549Z,1773349654.549 [DAT](INFO): DATA Src=12, Dst=1
2026-03-12T21:07:34.550Z,1773349654.550 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:10.6 AGC:72 SPD:+1.4 CCERR:008

2026-03-12T21:07:34.550Z,1773349654.550 [DAT](INFO): Got CRC:Pass
2026-03-12T21:07:34.550Z,1773349654.550 [DAT](INFO): Got CRC:Pass
2026-03-12T21:07:34.550Z,1773349654.550 [DAT](INFO): Incoming data is intended for us
2026-03-12T21:07:34.551Z,1773349654.551 [DAT](DEBUG): In parseResponses, got ack so set commsState_ = SENDING_VERIFIED
2026-03-12T21:07:34.551Z,1773349654.551 [DAT](INFO): Got ack
2026-03-12T21:07:34.551Z,1773349654.551 [DAT](INFO): DAT read: 

2026-03-12T21:07:34.551Z,1773349654.551 [DAT](INFO): DAT read: <EOP>

2026-03-12T21:07:34.552Z,1773349654.552 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:07:34.552Z,1773349654.552 [DAT](INFO): #Rx 33: Read direction message, but no range.
2026-03-12T21:07:34.553Z,1773349654.553 [DAT](INFO): direction in FSK: [0.847729,-0.527665,-0.054079]
2026-03-12T21:07:34.764Z,1773349654.764 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:07:35.016Z,1773349655.016 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:07:38.301Z,1773349658.301 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:07:38.1307

2026-03-12T21:07:38.301Z,1773349658.301 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:07:48.380Z,1773349668.380 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:07:48.632Z,1773349668.632 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:07:48.884Z,1773349668.884 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:07:52.161Z,1773349672.161 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:07:51.9808

2026-03-12T21:07:52.161Z,1773349672.161 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:08:02.240Z,1773349682.240 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:08:02.492Z,1773349682.492 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:08:02.744Z,1773349682.744 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:08:06.021Z,1773349686.021 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:08:05.8323

2026-03-12T21:08:06.021Z,1773349686.021 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:08:16.108Z,1773349696.108 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:08:16.360Z,1773349696.360 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:08:16.612Z,1773349696.612 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:08:19.889Z,1773349699.889 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:08:19.7310

2026-03-12T21:08:19.889Z,1773349699.889 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:08:29.968Z,1773349709.968 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:08:29.968Z,1773349709.968 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:08:30.220Z,1773349710.220 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:08:30.472Z,1773349710.472 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:08:30.724Z,1773349710.724 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:08:34.001Z,1773349714.001 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:08:33.8311

2026-03-12T21:08:34.001Z,1773349714.001 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:08:44.080Z,1773349724.080 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:08:44.332Z,1773349724.332 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:08:44.584Z,1773349724.584 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:08:47.862Z,1773349727.862 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:08:47.6811

2026-03-12T21:08:47.862Z,1773349727.862 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:08:57.940Z,1773349737.940 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:08:58.192Z,1773349738.192 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:08:58.444Z,1773349738.444 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:09:01.733Z,1773349741.733 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:09:01.5312

2026-03-12T21:09:01.733Z,1773349741.733 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:09:11.816Z,1773349751.816 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:09:12.068Z,1773349752.068 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:09:12.321Z,1773349752.321 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:09:15.597Z,1773349755.597 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:09:15.4313

2026-03-12T21:09:15.597Z,1773349755.597 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:09:25.692Z,1773349765.692 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:09:25.692Z,1773349765.692 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:09:25.944Z,1773349765.944 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:09:26.196Z,1773349766.196 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:09:26.448Z,1773349766.448 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:09:29.725Z,1773349769.725 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:09:29.5314

2026-03-12T21:09:29.725Z,1773349769.725 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:09:39.808Z,1773349779.808 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:09:40.060Z,1773349780.060 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:09:40.312Z,1773349780.312 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:09:43.589Z,1773349783.589 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:09:43.4315

2026-03-12T21:09:43.589Z,1773349783.589 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:09:53.668Z,1773349793.668 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:09:53.920Z,1773349793.920 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:09:54.173Z,1773349794.173 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:09:57.449Z,1773349797.449 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:09:57.2816

2026-03-12T21:09:57.449Z,1773349797.449 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:10:07.532Z,1773349807.532 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:10:07.784Z,1773349807.784 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:10:08.036Z,1773349808.036 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:10:11.317Z,1773349811.317 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:10:11.1316

2026-03-12T21:10:11.317Z,1773349811.317 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:10:21.396Z,1773349821.396 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:10:21.396Z,1773349821.396 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:10:21.648Z,1773349821.648 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:10:21.900Z,1773349821.900 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:10:22.152Z,1773349822.152 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:10:25.429Z,1773349825.429 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:10:25.2317

2026-03-12T21:10:25.429Z,1773349825.429 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:10:35.508Z,1773349835.508 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:10:35.760Z,1773349835.760 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:10:36.012Z,1773349836.012 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:10:39.289Z,1773349839.289 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:10:39.1318

2026-03-12T21:10:39.289Z,1773349839.289 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:10:49.377Z,1773349849.377 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:10:49.628Z,1773349849.628 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:10:49.880Z,1773349849.880 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:10:53.161Z,1773349853.161 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:10:52.9819

2026-03-12T21:10:53.161Z,1773349853.161 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:11:03.248Z,1773349863.248 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:11:03.500Z,1773349863.500 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:11:03.752Z,1773349863.752 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:11:07.029Z,1773349867.029 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:11:06.8320

2026-03-12T21:11:07.029Z,1773349867.029 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:11:17.136Z,1773349877.136 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:11:17.136Z,1773349877.136 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:11:17.388Z,1773349877.388 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:11:17.640Z,1773349877.640 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:11:17.892Z,1773349877.892 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:11:21.185Z,1773349881.185 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:11:20.9820

2026-03-12T21:11:21.185Z,1773349881.185 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:11:31.292Z,1773349891.292 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:11:31.544Z,1773349891.544 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:11:31.796Z,1773349891.796 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:11:35.073Z,1773349895.073 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:11:34.8821

2026-03-12T21:11:35.073Z,1773349895.073 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:11:45.180Z,1773349905.180 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:11:45.432Z,1773349905.432 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:11:45.684Z,1773349905.684 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:11:48.961Z,1773349908.961 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:11:48.7835

2026-03-12T21:11:48.961Z,1773349908.961 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:11:59.064Z,1773349919.064 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:11:59.316Z,1773349919.316 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:11:59.568Z,1773349919.568 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:12:02.845Z,1773349922.845 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:12:02.6823

2026-03-12T21:12:02.845Z,1773349922.845 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:12:12.928Z,1773349932.928 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:12:12.928Z,1773349932.928 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:12:13.180Z,1773349933.180 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:12:13.432Z,1773349933.432 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:12:13.684Z,1773349933.684 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:12:16.961Z,1773349936.961 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:12:16.7824

2026-03-12T21:12:16.961Z,1773349936.961 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:12:27.040Z,1773349947.040 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:12:27.292Z,1773349947.292 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:12:27.544Z,1773349947.544 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:12:30.821Z,1773349950.821 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:12:30.6325

2026-03-12T21:12:30.821Z,1773349950.821 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:12:40.901Z,1773349960.901 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:12:41.152Z,1773349961.152 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:12:41.404Z,1773349961.404 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:12:44.681Z,1773349964.681 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:12:44.5325

2026-03-12T21:12:44.681Z,1773349964.681 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:12:54.764Z,1773349974.764 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:12:55.016Z,1773349975.016 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:12:55.268Z,1773349975.268 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:12:58.545Z,1773349978.545 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:12:58.3826

2026-03-12T21:12:58.545Z,1773349978.545 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:13:08.632Z,1773349988.632 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:13:08.632Z,1773349988.632 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:13:08.884Z,1773349988.884 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:13:09.136Z,1773349989.136 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:13:09.388Z,1773349989.388 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:13:12.669Z,1773349992.669 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:13:12.4827

2026-03-12T21:13:12.669Z,1773349992.669 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:13:22.748Z,1773350002.748 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:13:23.000Z,1773350003.000 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:13:23.252Z,1773350003.252 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:13:26.529Z,1773350006.529 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:13:26.3328

2026-03-12T21:13:26.529Z,1773350006.529 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:13:36.616Z,1773350016.616 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:13:36.868Z,1773350016.868 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:13:37.122Z,1773350017.122 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:13:40.409Z,1773350020.409 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:13:40.2329

2026-03-12T21:13:40.410Z,1773350020.410 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:13:50.508Z,1773350030.508 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:13:50.760Z,1773350030.760 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:13:51.015Z,1773350031.015 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:13:54.301Z,1773350034.301 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:13:54.1330

2026-03-12T21:13:54.301Z,1773350034.301 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:14:04.392Z,1773350044.392 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:14:04.392Z,1773350044.392 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:14:04.646Z,1773350044.646 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:14:04.896Z,1773350044.896 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:14:05.148Z,1773350045.148 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:14:08.425Z,1773350048.425 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:14:08.2330

2026-03-12T21:14:08.425Z,1773350048.425 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:14:18.528Z,1773350058.528 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:14:18.780Z,1773350058.780 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:14:19.032Z,1773350059.032 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:14:22.317Z,1773350062.317 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:14:22.1331

2026-03-12T21:14:22.317Z,1773350062.317 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:14:32.396Z,1773350072.396 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:14:32.648Z,1773350072.648 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:14:32.900Z,1773350072.900 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:14:36.177Z,1773350076.177 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:14:35.9832

2026-03-12T21:14:36.177Z,1773350076.177 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:14:46.264Z,1773350086.264 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:14:46.516Z,1773350086.516 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT
2026-03-12T21:14:46.768Z,1773350086.768 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY
2026-03-12T21:14:50.045Z,1773350090.045 [DAT](INFO): DAT read: Forwarding Delay UpTx time:21:14:49.8833

2026-03-12T21:14:50.045Z,1773350090.045 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING
2026-03-12T21:15:00.124Z,1773350100.124 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED
2026-03-12T21:15:00.124Z,1773350100.124 [DAT](ERROR): Ack receipt timeout failure.
2026-03-12T21:15:00.376Z,1773350100.376 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER
2026-03-12T21:18:40.945Z,1773350320.945 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T21:18:40.945Z,1773350320.945 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T21:18:40.945Z,1773350320.945 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T21:18:40.946Z,1773350320.946 [keepstation:SurfaceComms:A] Stopped
2026-03-12T21:18:40.946Z,1773350320.946 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T21:18:40.946Z,1773350320.946 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T21:18:41.348Z,1773350321.348 [keepstation:TransitToStation:Dive] Stopped
2026-03-12T21:18:41.348Z,1773350321.348 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2026-03-12T21:18:41.348Z,1773350321.348 [keepstation:TransitToStation:Dive:A] Stopped
2026-03-12T21:18:41.348Z,1773350321.348 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2026-03-12T21:18:41.348Z,1773350321.348 [keepstation:DiveCmd:TransitToStation] Stopped
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2026-03-12T21:18:41.349Z,1773350321.349 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2026-03-12T21:18:41.351Z,1773350321.351 [keepstation:NeedComms] Running Loop=1
2026-03-12T21:18:41.351Z,1773350321.351 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T21:18:41.351Z,1773350321.351 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T21:18:41.351Z,1773350321.351 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T21:18:41.352Z,1773350321.352 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T21:18:41.352Z,1773350321.352 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T21:18:41.352Z,1773350321.352 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T21:18:41.353Z,1773350321.353 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T21:18:41.353Z,1773350321.353 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T21:18:41.353Z,1773350321.353 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T21:18:41.353Z,1773350321.353 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T21:18:41.355Z,1773350321.355 [keepstation:NeedComms:A](INFO): last time_fix was:  1773349419.000000 second since 1970/01/01T00:00:00Z 
2026-03-12T21:18:41.355Z,1773350321.355 [keepstation:NeedComms:A] Stopped
2026-03-12T21:18:41.355Z,1773350321.355 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T21:18:41.356Z,1773350321.356 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T21:18:41.356Z,1773350321.356 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T21:18:41.767Z,1773350321.767 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T21:18:41.767Z,1773350321.767 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T21:18:42.159Z,1773350322.159 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T21:18:42.160Z,1773350322.160 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T21:18:42.160Z,1773350322.160 [keepstation:NeedComms:setActive] Stopped
2026-03-12T21:18:42.160Z,1773350322.160 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T21:19:25.491Z,1773350365.491 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.807999,-121.847000
2026-03-12T21:19:25.491Z,1773350365.491 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2026-03-12T21:19:25.491Z,1773350365.491 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2026-03-12T21:19:25.491Z,1773350365.491 [keepstation:TransitToStation:C] Running Loop=1
2026-03-12T21:19:25.896Z,1773350365.896 [keepstation:TransitToStation:C] Stopped
2026-03-12T21:19:25.896Z,1773350365.896 [keepstation:TransitToStation:D] Running Loop=1
2026-03-12T21:19:25.897Z,1773350365.897 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T21:19:25.897Z,1773350365.897 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T21:19:25.897Z,1773350365.897 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T21:19:25.897Z,1773350365.897 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T21:19:25.897Z,1773350365.897 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T21:19:25.897Z,1773350365.897 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T21:19:25.897Z,1773350365.897 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T21:19:25.898Z,1773350365.898 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T21:19:25.898Z,1773350365.898 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T21:19:25.898Z,1773350365.898 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T21:19:25.899Z,1773350365.899 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T21:19:26.300Z,1773350366.300 [keepstation:TransitToStation:D] Stopped
2026-03-12T21:19:26.300Z,1773350366.300 [keepstation:TransitToStation:Dive] Running Loop=1
2026-03-12T21:19:26.300Z,1773350366.300 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2026-03-12T21:19:26.300Z,1773350366.300 [keepstation:TransitToStation:Dive:A] Running Loop=1
2026-03-12T21:19:26.300Z,1773350366.300 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:TransitToStation] Stopped
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:TransitToStation:Dive] Stopped
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:TransitToStation:Dive:A] Stopped
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:OnStation] Running Loop=1
2026-03-12T21:19:26.301Z,1773350366.301 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2026-03-12T21:19:46.243Z,1773350386.243 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T21:19:46.917Z,1773350386.917 [Radio_Surface](INFO): Powering up
2026-03-12T21:19:52.744Z,1773350392.744 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T21:20:00.141Z,1773350400.141 [NAL9602](INFO): SBD MO Status=0, MOMSN=32631, MT Status=0, MTMSN=0
2026-03-12T21:20:00.141Z,1773350400.141 [NAL9602](INFO): No messages in MT queue
2026-03-12T21:20:01.354Z,1773350401.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212000.00,A,3648.48703,N,12150.79476,W,1.127,158.29,120326,,,A*76
2026-03-12T21:20:01.365Z,1773350401.365 [NAL9602](INFO): GPS fix at 20260312T212000: (36.808117, -121.846579)
2026-03-12T21:20:01.378Z,1773350401.378 [UniversalFixResidualReporter](INFO): Fix residual: 8.6 %DT, over the last 572.2 m. Residual distance 49.3 m at bearing -126.1 degrees. Fix at (36.8081, -121.8466) with 508.9 m made good.
2026-03-12T21:20:01.382Z,1773350401.382 [keepstation:NeedComms:D] Stopped
2026-03-12T21:20:01.382Z,1773350401.382 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T21:20:22.864Z,1773350422.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:20:40.641Z,1773350440.641 [NAL9602](INFO): SBD MO Status=1, MOMSN=32632, MT Status=0, MTMSN=0
2026-03-12T21:20:40.692Z,1773350440.692 [NAL9602](INFO): Sent 207 bytes from file Logs/20260312T205917/Courier0006.lzma
2026-03-12T21:20:40.692Z,1773350440.692 [NAL9602](INFO): Packets left to send: 0
2026-03-12T21:20:53.278Z,1773350453.278 [NAL9602](INFO): SBD MO Status=1, MOMSN=32633, MT Status=0, MTMSN=0
2026-03-12T21:20:53.332Z,1773350453.332 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T205917/Express0007.lzma
2026-03-12T21:20:53.332Z,1773350453.332 [NAL9602](INFO): Packets left to send: 1
2026-03-12T21:20:53.988Z,1773350453.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:21:00.234Z,1773350460.234 [NAL9602](INFO): SBD MO Status=1, MOMSN=32634, MT Status=0, MTMSN=0
2026-03-12T21:21:00.288Z,1773350460.288 [NAL9602](INFO): Sent 76 bytes from file Logs/20260312T205917/Express0007.lzma
2026-03-12T21:21:00.288Z,1773350460.288 [NAL9602](INFO): Packets left to send: 0
2026-03-12T21:21:07.909Z,1773350467.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=32635, MT Status=0, MTMSN=0
2026-03-12T21:21:08.001Z,1773350468.001 [keepstation:NeedComms:E] Stopped
2026-03-12T21:21:08.001Z,1773350468.001 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T21:21:10.335Z,1773350470.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212109.00,A,3648.48037,N,12150.78901,W,0.078,146.37,120326,,,A*78
2026-03-12T21:21:10.337Z,1773350470.337 [NAL9602](INFO): GPS fix at 20260312T212109: (36.808006, -121.846484)
2026-03-12T21:21:10.352Z,1773350470.352 [keepstation:NeedComms:F] Stopped
2026-03-12T21:21:10.352Z,1773350470.352 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T21:21:10.352Z,1773350470.352 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:21:10.352Z,1773350470.352 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T21:21:10.781Z,1773350470.781 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T21:21:10.781Z,1773350470.781 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T21:21:10.781Z,1773350470.781 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T21:21:10.781Z,1773350470.781 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:21:10.781Z,1773350470.781 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T21:21:10.781Z,1773350470.781 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T21:21:10.781Z,1773350470.781 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T21:21:11.174Z,1773350471.174 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T21:21:11.174Z,1773350471.174 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T21:21:11.174Z,1773350471.174 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T21:21:11.174Z,1773350471.174 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T21:21:11.175Z,1773350471.175 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T21:21:11.175Z,1773350471.175 [keepstation:NeedComms] Stopped
2026-03-12T21:21:11.175Z,1773350471.175 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T21:21:11.175Z,1773350471.175 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T21:21:11.175Z,1773350471.175 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T21:21:11.557Z,1773350471.557 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2026-03-12T21:21:11.557Z,1773350471.557 [keepstation:OnStation] Stopped
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation] Running Loop=1
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation:Dive:A] Running Loop=1
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation:B.Wait] Running Loop=1
2026-03-12T21:21:11.558Z,1773350471.558 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2026-03-12T21:21:11.560Z,1773350471.560 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T21:21:11.561Z,1773350471.561 [keepstation:SurfaceComms:B] Stopped
2026-03-12T21:21:11.561Z,1773350471.561 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T21:21:11.561Z,1773350471.561 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T21:21:11.561Z,1773350471.561 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:21:11.561Z,1773350471.561 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T21:21:11.561Z,1773350471.561 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:21:11.561Z,1773350471.561 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T21:21:11.969Z,1773350471.969 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T21:21:11.971Z,1773350471.971 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T21:21:11.971Z,1773350471.971 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:21:11.971Z,1773350471.971 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T21:21:11.971Z,1773350471.971 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:21:11.972Z,1773350471.972 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T21:21:11.972Z,1773350471.972 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T21:21:11.972Z,1773350471.972 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T21:21:11.972Z,1773350471.972 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T21:21:12.373Z,1773350472.373 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T21:21:12.374Z,1773350472.374 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T21:21:12.374Z,1773350472.374 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T21:21:12.374Z,1773350472.374 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T21:21:12.374Z,1773350472.374 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T21:21:12.374Z,1773350472.374 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T21:21:12.790Z,1773350472.790 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T21:21:12.790Z,1773350472.790 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2026-03-12T21:21:12.790Z,1773350472.790 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T21:21:12.791Z,1773350472.791 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T21:21:12.791Z,1773350472.791 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T21:21:12.791Z,1773350472.791 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T21:21:12.791Z,1773350472.791 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T21:21:13.177Z,1773350473.177 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T21:21:13.177Z,1773350473.177 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T21:21:13.177Z,1773350473.177 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T21:21:13.177Z,1773350473.177 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T21:21:13.177Z,1773350473.177 [keepstation:SurfaceComms] Stopped
2026-03-12T21:21:13.177Z,1773350473.177 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T21:21:25.112Z,1773350485.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:21:42.656Z,1773350502.656 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T21:21:56.236Z,1773350516.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:22:27.368Z,1773350547.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:22:58.508Z,1773350578.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:23:29.632Z,1773350609.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:23:38.020Z,1773350618.020 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003046
2026-03-12T21:25:01.876Z,1773350701.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:25:33.004Z,1773350733.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:25:46.163Z,1773350746.163 [Radio_Surface](INFO): Powering down
2026-03-12T21:26:04.128Z,1773350764.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:26:05.132Z,1773350765.132 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T21:27:09.388Z,1773350829.388 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731247
2026-03-12T21:36:10.457Z,1773351370.457 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T21:36:10.457Z,1773351370.457 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T21:36:10.458Z,1773351370.458 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T21:36:10.458Z,1773351370.458 [keepstation:SurfaceComms:A] Stopped
2026-03-12T21:36:10.458Z,1773351370.458 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T21:36:10.458Z,1773351370.458 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T21:36:10.867Z,1773351370.867 [keepstation:KeepStation:Dive] Stopped
2026-03-12T21:36:10.868Z,1773351370.868 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2026-03-12T21:36:10.868Z,1773351370.868 [keepstation:KeepStation:Dive:A] Stopped
2026-03-12T21:36:10.868Z,1773351370.868 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2026-03-12T21:36:10.868Z,1773351370.868 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T21:36:10.868Z,1773351370.868 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T21:36:10.869Z,1773351370.869 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T21:36:10.869Z,1773351370.869 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T21:36:10.869Z,1773351370.869 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T21:36:10.869Z,1773351370.869 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T21:36:10.870Z,1773351370.870 [keepstation:NeedComms] Running Loop=1
2026-03-12T21:36:10.870Z,1773351370.870 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T21:36:10.871Z,1773351370.871 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T21:36:10.871Z,1773351370.871 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T21:36:10.871Z,1773351370.871 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T21:36:10.872Z,1773351370.872 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T21:36:10.872Z,1773351370.872 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T21:36:10.872Z,1773351370.872 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T21:36:10.873Z,1773351370.873 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T21:36:10.873Z,1773351370.873 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T21:36:10.873Z,1773351370.873 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T21:36:10.875Z,1773351370.875 [keepstation:NeedComms:A](INFO): last time_fix was:  1773350469.000000 second since 1970/01/01T00:00:00Z 
2026-03-12T21:36:10.875Z,1773351370.875 [keepstation:NeedComms:A] Stopped
2026-03-12T21:36:10.875Z,1773351370.875 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T21:36:10.875Z,1773351370.875 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T21:36:10.875Z,1773351370.875 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T21:36:11.277Z,1773351371.277 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T21:36:11.277Z,1773351371.277 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T21:36:11.670Z,1773351371.670 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T21:36:11.670Z,1773351371.670 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T21:36:11.670Z,1773351371.670 [keepstation:NeedComms:setActive] Stopped
2026-03-12T21:36:11.670Z,1773351371.670 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T21:38:12.508Z,1773351492.508 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T21:38:13.159Z,1773351493.159 [Radio_Surface](INFO): Powering up
2026-03-12T21:38:19.216Z,1773351499.216 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T21:38:41.978Z,1773351521.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=32636, MT Status=0, MTMSN=0
2026-03-12T21:38:41.978Z,1773351521.978 [NAL9602](INFO): No messages in MT queue
2026-03-12T21:38:43.191Z,1773351523.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213842.00,A,3648.45709,N,12150.77418,W,0.778,151.45,120326,,,A*76
2026-03-12T21:38:43.193Z,1773351523.193 [NAL9602](INFO): GPS fix at 20260312T213842: (36.807618, -121.846236)
2026-03-12T21:38:43.208Z,1773351523.208 [keepstation:NeedComms:D] Stopped
2026-03-12T21:38:43.208Z,1773351523.208 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T21:38:49.336Z,1773351529.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:38:59.840Z,1773351539.840 [NAL9602](INFO): SBD MO Status=1, MOMSN=32637, MT Status=0, MTMSN=0
2026-03-12T21:38:59.900Z,1773351539.900 [NAL9602](INFO): Sent 74 bytes from file Logs/20260312T205917/Courier0009.lzma
2026-03-12T21:38:59.900Z,1773351539.900 [NAL9602](INFO): Packets left to send: 0
2026-03-12T21:39:12.446Z,1773351552.446 [NAL9602](INFO): SBD MO Status=1, MOMSN=32638, MT Status=0, MTMSN=0
2026-03-12T21:39:12.500Z,1773351552.500 [NAL9602](INFO): Sent 230 bytes from file Logs/20260312T205917/Express0010.lzma
2026-03-12T21:39:12.500Z,1773351552.500 [NAL9602](INFO): Packets left to send: 0
2026-03-12T21:39:20.460Z,1773351560.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:39:20.526Z,1773351560.526 [NAL9602](INFO): SBD MO Status=0, MOMSN=32639, MT Status=0, MTMSN=0
2026-03-12T21:39:20.611Z,1773351560.611 [keepstation:NeedComms:E] Stopped
2026-03-12T21:39:20.611Z,1773351560.611 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T21:39:22.941Z,1773351562.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213922.00,A,3648.45527,N,12150.77207,W,1.186,81.64,120326,,,A*4E
2026-03-12T21:39:22.944Z,1773351562.944 [NAL9602](INFO): GPS fix at 20260312T213922: (36.807588, -121.846201)
2026-03-12T21:39:22.958Z,1773351562.958 [keepstation:NeedComms:F] Stopped
2026-03-12T21:39:22.958Z,1773351562.958 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T21:39:22.958Z,1773351562.958 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:39:22.958Z,1773351562.958 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T21:39:23.383Z,1773351563.383 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T21:39:23.384Z,1773351563.384 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T21:39:23.384Z,1773351563.384 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T21:39:23.384Z,1773351563.384 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:39:23.384Z,1773351563.384 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T21:39:23.384Z,1773351563.384 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T21:39:23.384Z,1773351563.384 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T21:39:23.768Z,1773351563.768 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T21:39:23.768Z,1773351563.768 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T21:39:23.768Z,1773351563.768 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T21:39:23.768Z,1773351563.768 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T21:39:23.769Z,1773351563.769 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T21:39:23.769Z,1773351563.769 [keepstation:NeedComms] Stopped
2026-03-12T21:39:23.769Z,1773351563.769 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T21:39:23.769Z,1773351563.769 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T21:39:23.769Z,1773351563.769 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:B] Stopped
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:39:24.185Z,1773351564.185 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T21:39:24.572Z,1773351564.572 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T21:39:24.573Z,1773351564.573 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:39:24.573Z,1773351564.573 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T21:39:24.573Z,1773351564.573 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:39:24.573Z,1773351564.573 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T21:39:24.573Z,1773351564.573 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T21:39:24.573Z,1773351564.573 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T21:39:24.573Z,1773351564.573 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T21:39:24.980Z,1773351564.980 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T21:39:24.981Z,1773351564.981 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T21:39:24.981Z,1773351564.981 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T21:39:24.981Z,1773351564.981 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T21:39:24.981Z,1773351564.981 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T21:39:24.981Z,1773351564.981 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T21:39:25.384Z,1773351565.384 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T21:39:25.385Z,1773351565.385 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2026-03-12T21:39:25.385Z,1773351565.385 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T21:39:25.385Z,1773351565.385 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T21:39:25.385Z,1773351565.385 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T21:39:25.385Z,1773351565.385 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T21:39:25.385Z,1773351565.385 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T21:39:25.801Z,1773351565.801 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T21:39:25.801Z,1773351565.801 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2026-03-12T21:39:25.801Z,1773351565.801 [keepstation:KeepStation:Dive:A] Running Loop=1
2026-03-12T21:39:25.801Z,1773351565.801 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2026-03-12T21:39:25.802Z,1773351565.802 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T21:39:25.802Z,1773351565.802 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T21:39:25.802Z,1773351565.802 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T21:39:25.802Z,1773351565.802 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T21:39:25.802Z,1773351565.802 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T21:39:25.802Z,1773351565.802 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T21:39:25.802Z,1773351565.802 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T21:39:25.804Z,1773351565.804 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T21:39:25.804Z,1773351565.804 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T21:39:25.804Z,1773351565.804 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T21:39:25.804Z,1773351565.804 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T21:39:25.804Z,1773351565.804 [keepstation:SurfaceComms] Stopped
2026-03-12T21:39:25.805Z,1773351565.805 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T21:39:51.592Z,1773351591.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:39:55.663Z,1773351595.663 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T21:40:22.724Z,1773351622.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:40:53.848Z,1773351653.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:41:24.985Z,1773351684.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:41:56.112Z,1773351716.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:42:04.512Z,1773351724.512 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003045
2026-03-12T21:42:08.932Z,1773351728.932 [DAT](INFO): DAT read: Rx Time:21:42:08.2381

2026-03-12T21:42:08.933Z,1773351728.933 [DAT](INFO): Rx dataTimestamp_ set to:1773351728.932544
2026-03-12T21:42:08.933Z,1773351728.933 [DAT](INFO): DAT read: 

2026-03-12T21:42:08.934Z,1773351728.934 [DAT](INFO): DAT read: $Packet for address 0

2026-03-12T21:42:08.934Z,1773351728.934 [DAT](INFO): received a packet notification
2026-03-12T21:42:52.221Z,1773351772.221 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2026-03-12T21:44:29.672Z,1773351869.672 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:45:00.800Z,1773351900.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:45:05.243Z,1773351905.243 [Radio_Surface](INFO): Powering down
2026-03-12T21:45:31.952Z,1773351931.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:45:32.956Z,1773351932.956 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T21:46:39.228Z,1773351999.228 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.758469
2026-03-12T21:54:23.074Z,1773352463.074 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T21:54:23.074Z,1773352463.074 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T21:54:23.074Z,1773352463.074 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T21:54:23.075Z,1773352463.075 [keepstation:SurfaceComms:A] Stopped
2026-03-12T21:54:23.075Z,1773352463.075 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T21:54:23.075Z,1773352463.075 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T21:54:23.077Z,1773352463.077 [keepstation:NeedComms] Running Loop=1
2026-03-12T21:54:23.077Z,1773352463.077 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T21:54:23.077Z,1773352463.077 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T21:54:23.077Z,1773352463.077 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T21:54:23.077Z,1773352463.077 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T21:54:23.078Z,1773352463.078 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T21:54:23.078Z,1773352463.078 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T21:54:23.078Z,1773352463.078 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T21:54:23.079Z,1773352463.079 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T21:54:23.079Z,1773352463.079 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T21:54:23.079Z,1773352463.079 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T21:54:23.081Z,1773352463.081 [keepstation:NeedComms:A](INFO): last time_fix was:  1773351562.000000 second since 1970/01/01T00:00:00Z 
2026-03-12T21:54:23.081Z,1773352463.081 [keepstation:NeedComms:A] Stopped
2026-03-12T21:54:23.081Z,1773352463.081 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T21:54:23.081Z,1773352463.081 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T21:54:23.081Z,1773352463.081 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T21:54:23.487Z,1773352463.487 [keepstation:KeepStation:Dive] Stopped
2026-03-12T21:54:23.487Z,1773352463.487 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2026-03-12T21:54:23.487Z,1773352463.487 [keepstation:KeepStation:Dive:A] Stopped
2026-03-12T21:54:23.487Z,1773352463.487 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2026-03-12T21:54:23.488Z,1773352463.488 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T21:54:23.488Z,1773352463.488 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T21:54:23.488Z,1773352463.488 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T21:54:23.488Z,1773352463.488 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T21:54:23.488Z,1773352463.488 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T21:54:23.488Z,1773352463.488 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T21:54:23.491Z,1773352463.491 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T21:54:23.491Z,1773352463.491 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T21:54:23.880Z,1773352463.880 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T21:54:23.880Z,1773352463.880 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T21:54:23.880Z,1773352463.880 [keepstation:NeedComms:setActive] Stopped
2026-03-12T21:54:23.880Z,1773352463.880 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T21:56:16.710Z,1773352576.710 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T21:56:17.180Z,1773352577.180 [Radio_Surface](INFO): Powering up
2026-03-12T21:56:22.656Z,1773352582.656 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T21:56:41.408Z,1773352601.408 [NAL9602](INFO): SBD MO Status=2, MOMSN=32640, MT Status=2, MTMSN=0
2026-03-12T21:56:41.408Z,1773352601.408 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T21:56:52.776Z,1773352612.776 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:56:53.934Z,1773352613.934 [NAL9602](INFO): SBD MO Status=2, MOMSN=32640, MT Status=2, MTMSN=0
2026-03-12T21:56:53.934Z,1773352613.934 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T21:57:14.130Z,1773352634.130 [NAL9602](INFO): SBD MO Status=2, MOMSN=32640, MT Status=2, MTMSN=0
2026-03-12T21:57:14.130Z,1773352634.130 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T21:57:23.903Z,1773352643.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:57:32.309Z,1773352652.309 [NAL9602](INFO): SBD MO Status=0, MOMSN=32640, MT Status=0, MTMSN=0
2026-03-12T21:57:32.309Z,1773352652.309 [NAL9602](INFO): No messages in MT queue
2026-03-12T21:57:33.522Z,1773352653.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215732.00,A,3648.43714,N,12150.80396,W,1.147,125.73,120326,,,A*76
2026-03-12T21:57:33.533Z,1773352653.533 [NAL9602](INFO): GPS fix at 20260312T215732: (36.807286, -121.846733)
2026-03-12T21:57:33.548Z,1773352653.548 [keepstation:NeedComms:D] Stopped
2026-03-12T21:57:33.549Z,1773352653.549 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T21:57:43.286Z,1773352663.286 [NAL9602](INFO): SBD MO Status=1, MOMSN=32641, MT Status=0, MTMSN=0
2026-03-12T21:57:43.340Z,1773352663.340 [NAL9602](INFO): Sent 72 bytes from file Logs/20260312T205917/Courier0012.lzma
2026-03-12T21:57:43.340Z,1773352663.340 [NAL9602](INFO): Packets left to send: 0
2026-03-12T21:57:55.048Z,1773352675.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:58:05.230Z,1773352685.230 [NAL9602](INFO): SBD MO Status=1, MOMSN=32642, MT Status=0, MTMSN=0
2026-03-12T21:58:05.280Z,1773352685.280 [NAL9602](INFO): Sent 225 bytes from file Logs/20260312T205917/Express0013.lzma
2026-03-12T21:58:05.280Z,1773352685.280 [NAL9602](INFO): Packets left to send: 0
2026-03-12T21:58:24.618Z,1773352704.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=32643, MT Status=0, MTMSN=0
2026-03-12T21:58:24.703Z,1773352704.703 [keepstation:NeedComms:E] Stopped
2026-03-12T21:58:24.704Z,1773352704.704 [keepstation:NeedComms:F] Running Loop=1
2026-03-12T21:58:26.180Z,1773352706.180 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:58:27.022Z,1773352707.022 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215826.00,A,3648.43477,N,12150.79922,W,0.272,146.66,120326,,,A*7C
2026-03-12T21:58:27.033Z,1773352707.033 [NAL9602](INFO): GPS fix at 20260312T215826: (36.807246, -121.846654)
2026-03-12T21:58:27.046Z,1773352707.046 [keepstation:NeedComms:F] Stopped
2026-03-12T21:58:27.047Z,1773352707.047 [keepstation:NeedComms:setRestoreMode] Running Loop=1
2026-03-12T21:58:27.047Z,1773352707.047 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::initialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:58:27.047Z,1773352707.047 [keepstation:NeedComms:setRestoreMode:A] Running Loop=1
2026-03-12T21:58:27.462Z,1773352707.462 [keepstation:NeedComms:setRestoreMode:A] Stopped
2026-03-12T21:58:27.463Z,1773352707.463 [keepstation:NeedComms:setRestoreMode](INFO): Completed keepstation:NeedComms:setRestoreMode
2026-03-12T21:58:27.463Z,1773352707.463 [keepstation:NeedComms:setRestoreMode] Stopped
2026-03-12T21:58:27.463Z,1773352707.463 [keepstation:NeedComms:setRestoreMode](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setRestoreMode
2026-03-12T21:58:27.463Z,1773352707.463 [keepstation:NeedComms:setInactive] Running Loop=1
2026-03-12T21:58:27.464Z,1773352707.464 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::initialize keepstation:NeedComms:setInactive
2026-03-12T21:58:27.464Z,1773352707.464 [keepstation:NeedComms:setInactive:A] Running Loop=1
2026-03-12T21:58:27.842Z,1773352707.842 [keepstation:NeedComms:setInactive:A] Stopped
2026-03-12T21:58:27.843Z,1773352707.843 [keepstation:NeedComms:setInactive](INFO): Completed keepstation:NeedComms:setInactive
2026-03-12T21:58:27.843Z,1773352707.843 [keepstation:NeedComms:setInactive] Stopped
2026-03-12T21:58:27.843Z,1773352707.843 [keepstation:NeedComms:setInactive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setInactive
2026-03-12T21:58:27.844Z,1773352707.844 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2026-03-12T21:58:27.844Z,1773352707.844 [keepstation:NeedComms] Stopped
2026-03-12T21:58:27.844Z,1773352707.844 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T21:58:27.844Z,1773352707.844 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T21:58:27.844Z,1773352707.844 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T21:58:28.253Z,1773352708.253 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2026-03-12T21:58:28.253Z,1773352708.253 [keepstation:SurfaceComms:B] Stopped
2026-03-12T21:58:28.253Z,1773352708.253 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T21:58:28.253Z,1773352708.253 [keepstation:SurfaceComms:HandleNan] Running Loop=1
2026-03-12T21:58:28.254Z,1773352708.254 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:58:28.254Z,1773352708.254 [keepstation:SurfaceComms:HandleNan:SetOnStation] Running Loop=1
2026-03-12T21:58:28.254Z,1773352708.254 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:58:28.254Z,1773352708.254 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Running Loop=1
2026-03-12T21:58:28.665Z,1773352708.665 [keepstation:SurfaceComms:HandleNan] Stopped
2026-03-12T21:58:28.665Z,1773352708.665 [keepstation:SurfaceComms:HandleNan](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan
2026-03-12T21:58:28.665Z,1773352708.665 [keepstation:SurfaceComms:HandleNan:SetOnStation] Stopped
2026-03-12T21:58:28.665Z,1773352708.665 [keepstation:SurfaceComms:HandleNan:SetOnStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:HandleNan:SetOnStation
2026-03-12T21:58:28.666Z,1773352708.666 [keepstation:SurfaceComms:HandleNan:SetOnStation:A] Stopped
2026-03-12T21:58:28.666Z,1773352708.666 [keepstation:SurfaceComms:setTransit] Running Loop=1
2026-03-12T21:58:28.666Z,1773352708.666 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2026-03-12T21:58:28.666Z,1773352708.666 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2026-03-12T21:58:29.056Z,1773352709.056 [keepstation:SurfaceComms:setTransit] Stopped
2026-03-12T21:58:29.056Z,1773352709.056 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2026-03-12T21:58:29.056Z,1773352709.056 [keepstation:SurfaceComms:setTransit:A] Stopped
2026-03-12T21:58:29.056Z,1773352709.056 [keepstation:SurfaceComms:setStation] Running Loop=1
2026-03-12T21:58:29.056Z,1773352709.056 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2026-03-12T21:58:29.057Z,1773352709.057 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2026-03-12T21:58:29.456Z,1773352709.456 [keepstation:SurfaceComms:setStation:A] Stopped
2026-03-12T21:58:29.456Z,1773352709.456 [keepstation:SurfaceComms:setStation](INFO): Completed keepstation:SurfaceComms:setStation
2026-03-12T21:58:29.456Z,1773352709.456 [keepstation:SurfaceComms:setStation] Stopped
2026-03-12T21:58:29.456Z,1773352709.456 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2026-03-12T21:58:29.457Z,1773352709.457 [keepstation:SurfaceComms:setSink] Running Loop=1
2026-03-12T21:58:29.457Z,1773352709.457 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2026-03-12T21:58:29.457Z,1773352709.457 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2026-03-12T21:58:29.865Z,1773352709.865 [keepstation:KeepStation:Dive] Running Loop=1
2026-03-12T21:58:29.865Z,1773352709.865 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2026-03-12T21:58:29.865Z,1773352709.865 [keepstation:KeepStation:Dive:A] Running Loop=1
2026-03-12T21:58:29.865Z,1773352709.865 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2026-03-12T21:58:29.865Z,1773352709.865 [keepstation:DiveCmd:StationKeep] Running Loop=1
2026-03-12T21:58:29.865Z,1773352709.865 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2026-03-12T21:58:29.866Z,1773352709.866 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2026-03-12T21:58:29.866Z,1773352709.866 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2026-03-12T21:58:29.866Z,1773352709.866 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2026-03-12T21:58:29.866Z,1773352709.866 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2026-03-12T21:58:29.866Z,1773352709.866 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2026-03-12T21:58:29.868Z,1773352709.868 [keepstation:SurfaceComms:setSink] Stopped
2026-03-12T21:58:29.868Z,1773352709.868 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2026-03-12T21:58:29.868Z,1773352709.868 [keepstation:SurfaceComms:setSink:A] Stopped
2026-03-12T21:58:29.868Z,1773352709.868 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2026-03-12T21:58:29.868Z,1773352709.868 [keepstation:SurfaceComms] Stopped
2026-03-12T21:58:29.868Z,1773352709.868 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T21:58:57.312Z,1773352737.312 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:58:59.340Z,1773352739.340 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T21:59:28.440Z,1773352768.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T21:59:59.564Z,1773352799.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:00:21.412Z,1773352821.412 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2026-03-12T22:00:30.696Z,1773352830.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:01:01.820Z,1773352861.820 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:01:41.488Z,1773352901.488 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003077
2026-03-12T22:03:36.332Z,1773353016.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:05:08.992Z,1773353108.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:05:35.584Z,1773353135.584 [Radio_Surface](INFO): Powering down
2026-03-12T22:05:40.116Z,1773353140.116 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:05:41.120Z,1773353141.120 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2026-03-12T22:07:16.544Z,1773353236.544 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.763336
2026-03-12T22:12:04.717Z,1773353524.717 [BPC1](FAULT): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2026-03-12T22:12:04.717Z,1773353524.717 [BPC1](FAULT): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.718Z,1773353524.718 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2026-03-12T22:12:04.719Z,1773353524.719 [BPC1](ERROR): Battery stick #30 (s/n: 015F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.720Z,1773353524.720 [BPC1](FAULT): Battery stick #37 (s/n: 0181) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.720Z,1773353524.720 [BPC1](FAULT): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.721Z,1773353524.721 [BPC1](FAULT): Battery stick #39 (s/n: 0189) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.722Z,1773353524.722 [BPC1](FAULT): Battery stick #40 (s/n: 01A4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.722Z,1773353524.722 [BPC1](FAULT): Battery stick #44 (s/n: 01BB) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.723Z,1773353524.723 [BPC1](FAULT): Battery stick #50 (s/n: 019C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.724Z,1773353524.724 [BPC1](FAULT): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.725Z,1773353524.725 [BPC1](FAULT): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.725Z,1773353524.725 [BPC1](FAULT): Battery stick #53 (s/n: 01EE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.726Z,1773353524.726 [BPC1](ERROR): Battery stick #56 (s/n: 0183) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.727Z,1773353524.727 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2026-03-12T22:12:04.728Z,1773353524.728 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x5AD0.
2026-03-12T22:12:04.728Z,1773353524.728 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 2.
2026-03-12T22:12:04.731Z,1773353524.731 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2026-03-12T22:13:27.150Z,1773353607.150 [keepstation:SurfaceComms] Running Loop=1
2026-03-12T22:13:27.150Z,1773353607.150 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2026-03-12T22:13:27.151Z,1773353607.151 [keepstation:SurfaceComms:A] Running Loop=1
2026-03-12T22:13:27.151Z,1773353607.151 [keepstation:SurfaceComms:A] Stopped
2026-03-12T22:13:27.151Z,1773353607.151 [keepstation:SurfaceComms:B] Running Loop=1
2026-03-12T22:13:27.153Z,1773353607.153 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2026-03-12T22:13:27.155Z,1773353607.155 [keepstation:NeedComms] Running Loop=1
2026-03-12T22:13:27.156Z,1773353607.156 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2026-03-12T22:13:27.156Z,1773353607.156 [keepstation:NeedComms:C.GoToSurface] Running Loop=1
2026-03-12T22:13:27.156Z,1773353607.156 [keepstation:NeedComms:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T22:13:27.157Z,1773353607.157 [keepstation:NeedComms:C.GoToSurface](INFO): Received depth rate setting nan m/s.
2026-03-12T22:13:27.157Z,1773353607.157 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2026-03-12T22:13:27.158Z,1773353607.158 [keepstation:NeedComms:C.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2026-03-12T22:13:27.158Z,1773353607.158 [keepstation:NeedComms:C.GoToSurface](INFO): Received surface timeout setting 1500.000000 seconds.
2026-03-12T22:13:27.158Z,1773353607.158 [keepstation:NeedComms:C.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T22:13:27.159Z,1773353607.159 [keepstation:NeedComms:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T22:13:27.159Z,1773353607.159 [keepstation:NeedComms:A] Running Loop=1
2026-03-12T22:13:27.161Z,1773353607.161 [keepstation:NeedComms:A](INFO): last time_fix was:  1773352706.000000 second since 1970/01/01T00:00:00Z 
2026-03-12T22:13:27.161Z,1773353607.161 [keepstation:NeedComms:A] Stopped
2026-03-12T22:13:27.161Z,1773353607.161 [keepstation:NeedComms:setActive] Running Loop=1
2026-03-12T22:13:27.161Z,1773353607.161 [keepstation:NeedComms:setActive](DEBUG): Aggregate::initialize keepstation:NeedComms:setActive
2026-03-12T22:13:27.161Z,1773353607.161 [keepstation:NeedComms:setActive:A] Running Loop=1
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:KeepStation:Dive] Stopped
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:KeepStation:Dive:A] Stopped
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:DiveCmd:StationKeep] Stopped
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:DiveCmd:StationKeep:A] Stopped
2026-03-12T22:13:27.562Z,1773353607.562 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2026-03-12T22:13:27.563Z,1773353607.563 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2026-03-12T22:13:27.563Z,1773353607.563 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2026-03-12T22:13:27.566Z,1773353607.566 [keepstation:NeedComms:setActive:A] Stopped
2026-03-12T22:13:27.566Z,1773353607.566 [keepstation:NeedComms:setActive:B] Running Loop=1
2026-03-12T22:13:27.962Z,1773353607.962 [keepstation:NeedComms:setActive:B] Stopped
2026-03-12T22:13:27.962Z,1773353607.962 [keepstation:NeedComms:setActive](INFO): Completed keepstation:NeedComms:setActive
2026-03-12T22:13:27.962Z,1773353607.962 [keepstation:NeedComms:setActive] Stopped
2026-03-12T22:13:27.962Z,1773353607.962 [keepstation:NeedComms:setActive](DEBUG): Aggregate::uninitialize keepstation:NeedComms:setActive
2026-03-12T22:15:06.213Z,1773353706.213 [keepstation:NeedComms:D] Running Loop=1
2026-03-12T22:15:06.844Z,1773353706.844 [Radio_Surface](INFO): Powering up
2026-03-12T22:15:12.540Z,1773353712.540 [DataOverHttps](INFO): Radio surface powered ON.
2026-03-12T22:15:42.660Z,1773353742.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:15:57.942Z,1773353757.942 [NAL9602](INFO): SBD MO Status=2, MOMSN=32644, MT Status=2, MTMSN=0
2026-03-12T22:15:57.942Z,1773353757.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T22:16:13.796Z,1773353773.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:16:15.714Z,1773353775.714 [NAL9602](INFO): SBD MO Status=2, MOMSN=32644, MT Status=2, MTMSN=0
2026-03-12T22:16:15.714Z,1773353775.714 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T22:16:30.661Z,1773353790.661 [NAL9602](INFO): SBD MO Status=2, MOMSN=32644, MT Status=2, MTMSN=0
2026-03-12T22:16:30.661Z,1773353790.661 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T22:16:40.366Z,1773353800.366 [NAL9602](INFO): SBD MO Status=0, MOMSN=32644, MT Status=0, MTMSN=0
2026-03-12T22:16:40.366Z,1773353800.366 [NAL9602](INFO): No messages in MT queue
2026-03-12T22:16:41.574Z,1773353801.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221640.00,A,3648.40951,N,12150.83834,W,0.019,95.44,120326,,,D*49
2026-03-12T22:16:41.576Z,1773353801.576 [NAL9602](INFO): GPS fix at 20260312T221640: (36.806825, -121.847306)
2026-03-12T22:16:41.590Z,1773353801.590 [keepstation:NeedComms:D] Stopped
2026-03-12T22:16:41.590Z,1773353801.590 [keepstation:NeedComms:E] Running Loop=1
2026-03-12T22:16:44.928Z,1773353804.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:17:06.690Z,1773353826.690 [NAL9602](INFO): SBD MO Status=1, MOMSN=32645, MT Status=0, MTMSN=0
2026-03-12T22:17:06.740Z,1773353826.740 [NAL9602](INFO): Sent 203 bytes from file Logs/20260312T205917/Courier0015.lzma
2026-03-12T22:17:06.740Z,1773353826.740 [NAL9602](INFO): Packets left to send: 0
2026-03-12T22:17:16.060Z,1773353836.060 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:17:27.395Z,1773353847.395 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=32646, MT Status=1, MTMSN=1200
2026-03-12T22:17:27.448Z,1773353847.448 [NAL9602](INFO): Sent 332 bytes from file Logs/20260312T205917/Express0016.lzma
2026-03-12T22:17:27.448Z,1773353847.448 [NAL9602](INFO): Packets left to send: 1
2026-03-12T22:17:27.872Z,1773353847.872 [NAL9602](INFO): Received command: stop
2026-03-12T22:17:27.968Z,1773353847.968 [CommandExec](IMPORTANT): got command stop
2026-03-12T22:17:27.968Z,1773353847.968 [CommandExec](IMPORTANT): Scheduling is paused
2026-03-12T22:17:27.968Z,1773353847.968 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2026-03-12T22:17:28.225Z,1773353848.225 [MissionManager](INFO): MissionManager is completed.
2026-03-12T22:17:28.225Z,1773353848.225 [MissionManager](INFO): Uninitializing Mission keepstation
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation] Stopped
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:NeedComms] Stopped
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:NeedComms:C.GoToSurface] Stopped
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:NeedComms:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:NeedComms:E] Stopped
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:StandardEnvelopes] Stopped
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2026-03-12T22:17:28.226Z,1773353848.226 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:BackseatDriver] Stopped
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:PowerOnly] Stopped
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:PowerOnly:E.Wait] Stopped
2026-03-12T22:17:28.227Z,1773353848.227 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:H.Pitch] Stopped
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:SurfaceComms] Stopped
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:SurfaceComms:B] Stopped
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:DiveCmd] Stopped
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:KeepStation] Stopped
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:KeepStation:B.Wait] Stopped
2026-03-12T22:17:28.228Z,1773353848.228 [keepstation:KeepStation:B.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T22:17:28.612Z,1773353848.612 [MissionManager](IMPORTANT): Started mission Default
2026-03-12T22:17:28.613Z,1773353848.613 [Default] Running Loop=1
2026-03-12T22:17:28.613Z,1773353848.613 [Default](DEBUG): Aggregate::initialize Default
2026-03-12T22:17:28.613Z,1773353848.613 [Default:B.GoToSurface] Running Loop=1
2026-03-12T22:17:28.613Z,1773353848.613 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-12T22:17:28.613Z,1773353848.613 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-12T22:17:28.613Z,1773353848.613 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-12T22:17:28.614Z,1773353848.614 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-12T22:17:28.614Z,1773353848.614 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-12T22:17:28.614Z,1773353848.614 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-12T22:17:28.615Z,1773353848.615 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-12T22:17:28.615Z,1773353848.615 [Default:A.Wait] Running Loop=1
2026-03-12T22:17:28.615Z,1773353848.615 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-03-12T22:17:41.941Z,1773353861.941 [Default:A.Wait](INFO): Done Waiting.
2026-03-12T22:17:41.941Z,1773353861.941 [Default:A.Wait] Stopped
2026-03-12T22:17:41.941Z,1773353861.941 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-03-12T22:17:42.352Z,1773353862.352 [Default:CheckIn] Running Loop=1
2026-03-12T22:17:42.352Z,1773353862.352 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-12T22:17:42.352Z,1773353862.352 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-12T22:17:44.358Z,1773353864.358 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221743.00,A,3648.40646,N,12150.83525,W,0.603,127.55,120326,,,A*7F
2026-03-12T22:17:44.361Z,1773353864.361 [NAL9602](INFO): GPS fix at 20260312T221743: (36.806774, -121.847254)
2026-03-12T22:17:44.409Z,1773353864.409 [Default:CheckIn:Read_GPS] Stopped
2026-03-12T22:17:44.409Z,1773353864.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-12T22:17:47.192Z,1773353867.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:18:13.134Z,1773353893.134 [NAL9602](INFO): SBD MO Status=2, MOMSN=32647, MT Status=2, MTMSN=0
2026-03-12T22:18:13.134Z,1773353893.134 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T22:18:18.316Z,1773353898.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-03-12T22:18:46.714Z,1773353926.714 [NAL9602](INFO): SBD MO Status=2, MOMSN=32647, MT Status=2, MTMSN=0
2026-03-12T22:18:46.714Z,1773353926.714 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-12T22:18:46.965Z,1773353926.965 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003727
2026-03-12T22:18:56.947Z,1773353936.947 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260312T205917/Courier0018.lzma
2026-03-12T22:18:57.949Z,1773353937.949 [DataOverHttps](INFO): Moved sent file to Logs/20260312T205917/Courier0018.lzma.bak
2026-03-12T22:18:57.949Z,1773353937.949 [DataOverHttps](INFO): SBD MOMSN=26726314
2026-03-12T22:19:13.803Z,1773353953.803 [DataOverHttps](INFO): Sending 415 bytes from file Logs/20260312T205917/Express0016.lzma
2026-03-12T22:19:14.805Z,1773353954.805 [DataOverHttps](INFO): Moved sent file to Logs/20260312T205917/Express0016.lzma.bak
2026-03-12T22:19:14.805Z,1773353954.805 [DataOverHttps](INFO): SBD MOMSN=26726323
2026-03-12T22:19:17.480Z,1773353957.480 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-12T22:19:23.245Z,1773353963.245 [DataOverHttps](IMPORTANT): SBD MTMSN=20260312T221922