2025-04-25T05:52:10.305Z,1745560330.305 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist
2025-04-25T05:52:10.307Z,1745560330.307 [CommandExec](IMPORTANT): got command restart logs
2025-04-25T05:55:14.706Z,1745560514.706 [DataOverHttps](IMPORTANT): SBD MTMSN=20250425T055507
2025-04-25T05:55:15.428Z,1745560515.428 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-25T05:55:15.428Z,1745560515.428 [Default:CheckIn:C.Wait] Stopped
2025-04-25T05:55:15.428Z,1745560515.428 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-25T05:55:15.428Z,1745560515.428 [Default:CheckIn:D] Running Loop=1
2025-04-25T05:55:15.838Z,1745560515.838 [Default:CheckIn:D] Stopped
2025-04-25T05:55:15.838Z,1745560515.838 [Default:CheckIn:E] Running Loop=1
2025-04-25T05:55:16.234Z,1745560516.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  18.928851 min 
2025-04-25T05:55:16.234Z,1745560516.234 [Default:CheckIn:E] Stopped
2025-04-25T05:55:16.234Z,1745560516.234 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-25T05:55:16.234Z,1745560516.234 [Default:CheckIn] Stopped
2025-04-25T05:55:16.235Z,1745560516.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T05:55:16.235Z,1745560516.235 [Default:CheckIn](INFO): Running loop #4
2025-04-25T05:55:16.235Z,1745560516.235 [Default:CheckIn] Running Loop=4
2025-04-25T05:55:16.235Z,1745560516.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-25T05:55:16.235Z,1745560516.235 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-25T05:55:18.262Z,1745560518.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,055511.00,A,3647.70266,N,12154.15721,W,0.505,157.71,250425,,,A*78
2025-04-25T05:55:18.268Z,1745560518.268 [NAL9602](INFO): GPS fix at 20250425T055511: (36.795044, -121.902620)
2025-04-25T05:55:18.287Z,1745560518.287 [Default:CheckIn:Read_GPS] Stopped
2025-04-25T05:55:18.287Z,1745560518.287 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-25T05:55:22.462Z,1745560522.462 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250425T040807/Courier0036.lzma
2025-04-25T05:55:22.468Z,1745560522.468 [DataOverHttps](INFO): Received command: sched 20250425T13 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs;"
2025-04-25T05:55:22.519Z,1745560522.519 [CommandExec](IMPORTANT): got command schedule 20250425T130000 "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs;"
2025-04-25T05:55:22.520Z,1745560522.520 [CommandExec](IMPORTANT): Scheduled #10: "configSet CBIT.runNavErrorCritical 1 bool persist; restart logs;", AT TIME: 2025-04-25T13:00:00Z
2025-04-25T05:55:23.064Z,1745560523.064 [DataOverHttps](INFO): Moved sent file to Logs/20250425T040807/Courier0036.lzma.bak
2025-04-25T05:55:23.065Z,1745560523.065 [DataOverHttps](INFO): SBD MOMSN=24727476
2025-04-25T05:55:38.208Z,1745560538.208 [NAL9602](INFO): SBD MO Status=1, MOMSN=20514, MT Status=0, MTMSN=0
2025-04-25T05:55:38.208Z,1745560538.208 [NAL9602](INFO): No messages in MT queue
2025-04-25T05:55:38.972Z,1745560538.972 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20250425T055210/Courier0000.lzma
2025-04-25T05:55:39.987Z,1745560539.987 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0000.lzma.bak
2025-04-25T05:55:39.987Z,1745560539.987 [DataOverHttps](INFO): SBD MOMSN=24727479
2025-04-25T05:55:56.108Z,1745560556.108 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250425T055210/Courier0006.lzma
2025-04-25T05:55:57.111Z,1745560557.111 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0006.lzma.bak
2025-04-25T05:55:57.111Z,1745560557.111 [DataOverHttps](IMPORTANT): SBD MOMSN=24727486, MTMSN=20250425T055549
2025-04-25T05:55:59.602Z,1745560559.602 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T05:56:04.584Z,1745560564.584 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Latitude 36.79317 degree;set keepstation.Longitude -121.98069 degree;set keepstation.Depth 20 m" 60gx 1 2
2025-04-25T05:56:04.609Z,1745560564.609 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Latitude 36.79317 degree;set keepstation.Longitude -121.98069 degree;set keepstation.Depth 20 m" 60gx 1 2.000000
2025-04-25T05:56:04.610Z,1745560564.610 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=60gx
2025-04-25T05:56:04.611Z,1745560564.611 [CommandExec](IMPORTANT): Scheduled #11 (#1 of 2 with id='60gx'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 11 h;set keepstation.NeedCommsTime 120 min;set keepstation.Latitude 36.79317 degree;set keepstation.Longitude -121.98069 degree;set keepstation.Depth 20 m", ASAP
2025-04-25T05:56:05.850Z,1745560565.850 [DataOverHttps](IMPORTANT): SBD MTMSN=20250425T055558
2025-04-25T05:56:08.893Z,1745560568.893 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-25T05:56:13.083Z,1745560573.083 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20250425T040807/Express0037.lzma
2025-04-25T05:56:13.086Z,1745560573.086 [DataOverHttps](INFO): Received command: sched asap "set keepstation.Speed 1.0 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 60gx 2 2
2025-04-25T05:56:13.156Z,1745560573.156 [CommandExec](IMPORTANT): got command schedule asap "set keepstation.Speed 1.0 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run" 60gx 2 2.000000
2025-04-25T05:56:13.157Z,1745560573.157 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=60gx
2025-04-25T05:56:13.158Z,1745560573.158 [CommandExec](IMPORTANT): Scheduled #12 (#2 of 2 with id='60gx'): "set keepstation.Speed 1.0 m/s;set keepstation.SinkOffSurface 1 bool;set keepstation.MaxDepth 45 m;set keepstation:NeedComms.SurfaceSpeed 1 m/s;set keepstation:NeedComms.CommsTimeout 10 min;run", ASAP
2025-04-25T05:56:13.273Z,1745560573.273 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-04-25T05:56:13.277Z,1745560573.277 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-04-25T05:56:13.277Z,1745560573.277 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-04-25T05:56:13.282Z,1745560573.282 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-04-25T05:56:14.492Z,1745560574.492 [DataOverHttps](INFO): Moved sent file to Logs/20250425T040807/Express0037.lzma.bak
2025-04-25T05:56:14.493Z,1745560574.493 [DataOverHttps](INFO): SBD MOMSN=24727494
2025-04-25T05:56:14.681Z,1745560574.681 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="keepstation">
    <DefineArg Name="MissionTimeout">
        <Units:hour />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="ApproachDepth">
        <Units:meter />
        <Value>15</Value>
    </DefineArg>
    <DefineArg Name="DepthDeadband">
        <Units:meter />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>0.75</Value>
    </DefineArg>
    <DefineArg Name="Radius">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="SinkOffSurface">
        <False />
    </DefineArg>
    <DefineArg Name="SinkDepth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SinkDepthTimeout">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <DefineOutput Name="DiveMode">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="DoingComms">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="SurfaceSink">
        <Units:count />
        <Value>1</Value>
    </DefineOutput>
    <DefineOutput Name="TransitToStation">
        <Units:count />
        <Value>2</Value>
    </DefineOutput>
    <DefineOutput Name="StationKeep">
        <Units:count />
        <Value>3</Value>
    </DefineOutput>
    <DefineOutput Name="OnStation">
        <False />
    </DefineOutput>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Pitch>
        <Parallel />
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="SurfaceComms">
        <When>
            <Called />
            <Or>
                <Elapsed>
                    <Universal:time_fix />
                </Elapsed>
                <Gt>
                    <Arg Name="NeedCommsTime" />
                </Gt>
            </Or>
        </When>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="DoingComms" />
        </Assign>
        <Call RefId="NeedComms" />
        <Aggregate Id="setTransit">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Arg Name="OnStation" />
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="TransitToStation" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setStation">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Not>
                        <Arg Name="OnStation" />
                    </Not>
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="StationKeep" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setSink">
            <Sequence />
            <Break>
                <Not>
                    <Arg Name="SinkOffSurface" />
                </Not>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="SurfaceSink" />
            </Assign>
        </Aggregate>
    </Aggregate>
    <Call RefId="SurfaceComms" Id="StartingMission" />
    <Aggregate Id="DiveCmd">
        <When>
            <Called />
        </When>
        <Aggregate Id="SurfaceSink">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="SurfaceSink" />
                </Eq>
            </While>
            <Aggregate Id="sink">
                <Sequence />
                <Guidance:Buoyancy Id="BuoyancyHold">
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Units:cubic_centimeter />
                        <Value>NaN</Value>
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Units:meter_per_second />
                        <Value>0</Value>
                    </Setting>
                </Guidance:SetSpeed>
                <Syslog Severity="Info">
                    Sinking to 
                    <Arg Name="SinkDepth" />
                    <Units:meter />
                </Syslog>
                <Guidance:Pitch>
                    <Sequence />
                    <Timeout Duration="SinkDepthTimeout">
                        <Syslog Severity="Important">
                            Timed out while trying to reach target sink depth at current depth of 
                            <Universal:depth />
                            <Units:meter />
                        </Syslog>
                    </Timeout>
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="SinkDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Aggregate Id="sinkToTransit">
                    <Sequence />
                    <Break>
                        <Arg Name="OnStation" />
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="TransitToStation" />
                    </Assign>
                </Aggregate>
                <Aggregate Id="sinkToStation">
                    <Sequence />
                    <Break>
                        <Not>
                            <Arg Name="OnStation" />
                        </Not>
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="StationKeep" />
                    </Assign>
                </Aggregate>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="TransitToStation">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="TransitToStation" />
                </Eq>
            </While>
            <Aggregate Id="dive">
                <Sequence />
                <Guidance:Buoyancy>
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Control:VerticalControl.buoyancyNeutral />
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:Pitch>
                    <Parallel />
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="ApproachDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Arg Name="Speed" />
                    </Setting>
                </Guidance:SetSpeed>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="StationKeep">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="StationKeep" />
                </Eq>
            </While>
            <Assign>
                <Parallel />
                <Control:VerticalControl.depthDeadband />
                <Arg Name="DepthDeadband" />
            </Assign>
            <Guidance:Pitch>
                <Parallel />
                <Setting>
                    <Guidance:Pitch.depth />
                    <Arg Name="Depth" />
                </Setting>
            </Guidance:Pitch>
            <Guidance:KeepStation>
                <Parallel />
                <Setting>
                    <Guidance:KeepStation.latitude />
                    <Arg Name="Latitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.longitude />
                    <Arg Name="Longitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.radius />
                    <Arg Name="Radius" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.speed />
                    <Arg Name="Speed" />
                </Setting>
            </Guidance:KeepStation>
        </Aggregate>
    </Aggregate>
    <Aggregate Id="TransitToStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" />
        </Aggregate>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="StationKeep" />
        </Assign>
        <Assign>
            <Sequence />
            <Arg Name="OnStation" />
            <True />
        </Assign>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" />
        </Aggregate>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-04-25T05:56:14.691Z,1745560574.691 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-04-25T05:56:14.702Z,1745560574.702 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-04-25T05:56:14.705Z,1745560574.705 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-04-25T05:56:14.737Z,1745560574.737 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-04-25T05:56:14.744Z,1745560574.744 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-04-25T05:56:14.749Z,1745560574.749 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-04-25T05:56:14.753Z,1745560574.753 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-04-25T05:56:14.760Z,1745560574.760 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-04-25T05:56:14.763Z,1745560574.763 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-04-25T05:56:14.771Z,1745560574.771 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-04-25T05:56:14.775Z,1745560574.775 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-04-25T05:56:14.790Z,1745560574.790 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-04-25T05:56:14.810Z,1745560574.810 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-04-25T05:56:14.814Z,1745560574.814 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-04-25T05:56:14.829Z,1745560574.829 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-04-25T05:56:14.837Z,1745560574.837 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-04-25T05:56:14.842Z,1745560574.842 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-04-25T05:56:14.853Z,1745560574.853 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-04-25T05:56:14.858Z,1745560574.858 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-04-25T05:56:14.873Z,1745560574.873 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-04-25T05:56:14.874Z,1745560574.874 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-04-25T05:56:14.874Z,1745560574.874 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-04-25T05:56:14.875Z,1745560574.875 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-04-25T05:56:15.477Z,1745560575.477 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="NeedComms">
    <DefineArg Name="DiveInterval">
        <Units:hour />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="WaitForPitchUp">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SurfacePitch">
        <Units:degree />
        <Value>20</Value>
    </DefineArg>
    <DefineArg Name="SurfaceDepthRate">
        <Units:meter_per_second />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="SurfaceSpeed">
        <Units:meter_per_second />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="SurfacingTimeout">
        <Units:second />
        <Value>1000</Value>
    </DefineArg>
    <DefineArg Name="GPSTimeout">
        <Units:minute />
        <Value>7</Value>
    </DefineArg>
    <DefineArg Name="CommsTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <When>
        <Called />
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
                <Add>
                    <Arg Name="WaitForPitchUp" />
                </Add>
            </Gt>
        </Or>
        <Or>
            <Elapsed>
                <Universal:time_fix />
            </Elapsed>
            <Gt>
                <Arg Name="DiveInterval" />
            </Gt>
            <And>
                <Universal:platform_pitch_angle />
                <Gt>
                    <Units:degree />
                    <Value>0</Value>
                </Gt>
            </And>
        </Or>
    </When>
    <Syslog Severity="Info">
        last time_fix was: 
        <Universal:time_fix />
        <Units:epoch_second />
    </Syslog>
    <Guidance:GoToSurface>
        <Progression />
        <Setting>
            <Guidance:GoToSurface.pitch />
            <Arg Name="SurfacePitch" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.depthRate />
            <Arg Name="SurfaceDepthRate" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.speed />
            <Arg Name="SurfaceSpeed" />
        </Setting>
        <Setting>
            <Guidance:GoToSurface.surfaceTimeout />
            <Arg Name="SurfacingTimeout" />
        </Setting>
    </Guidance:GoToSurface>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">first GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="CommsTimeout">
            <Syslog Severity="Important">
                Comms timed out after 
                <Arg Name="CommsTimeout" />
                <Units:minute />
                minutes
            </Syslog>
        </Timeout>
        <Universal:platform_communications />
    </ReadDatum>
    <ReadDatum>
        <Timeout Duration="GPSTimeout">
            <Syslog Severity="Important">second GPS update timeout</Syslog>
        </Timeout>
        <Universal:time_fix />
    </ReadDatum>
</Aggregate>

2025-04-25T05:56:15.487Z,1745560575.487 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-04-25T05:56:15.495Z,1745560575.495 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-04-25T05:56:15.502Z,1745560575.502 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-04-25T05:56:15.527Z,1745560575.527 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-04-25T05:56:15.546Z,1745560575.546 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-04-25T05:56:15.557Z,1745560575.557 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-04-25T05:56:15.565Z,1745560575.565 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-04-25T05:56:15.581Z,1745560575.581 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-04-25T05:56:15.612Z,1745560575.612 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-25T05:56:15.679Z,1745560575.679 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-04-25T05:56:15.679Z,1745560575.679 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-04-25T05:56:15.688Z,1745560575.688 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-04-25T05:56:15.942Z,1745560575.942 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="StandardEnvelopes">
    <DefineArg Name="MinAltitude">
        <Units:meter />
        <Value>5</Value>
    </DefineArg>
    <DefineArg Name="MaxDepthIgnore">
        <Units:meter />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:meter />
        <Value>2000</Value>
    </DefineArg>
    <Parallel />
    <Guidance:AltitudeEnvelope>
        <Parallel />
        <Setting>
            <Guidance:AltitudeEnvelope.minAltitude />
            <Arg Name="MinAltitude" />
        </Setting>
        <Setting>
            <Guidance:AltitudeEnvelope.maxDepthIgnore />
            <Arg Name="MaxDepthIgnore" />
        </Setting>
    </Guidance:AltitudeEnvelope>
    <Guidance:DepthEnvelope>
        <Parallel />
        <Setting>
            <Guidance:DepthEnvelope.maxDepth />
            <Arg Name="MaxDepth" />
        </Setting>
    </Guidance:DepthEnvelope>
    <Guidance:OffshoreEnvelope>
        <Parallel />
        <Setting>
            <Guidance:OffshoreEnvelope.minOffshore />
            <Arg Name="MinOffshore" />
        </Setting>
    </Guidance:OffshoreEnvelope>
</Aggregate>

2025-04-25T05:56:15.955Z,1745560575.955 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-04-25T05:56:15.974Z,1745560575.974 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-04-25T05:56:15.978Z,1745560575.978 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-04-25T05:56:16.009Z,1745560576.009 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-04-25T05:56:16.010Z,1745560576.010 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-04-25T05:56:16.048Z,1745560576.048 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-04-25T05:56:16.074Z,1745560576.074 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-04-25T05:56:16.109Z,1745560576.109 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-04-25T05:56:16.109Z,1745560576.109 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-04-25T05:56:16.110Z,1745560576.110 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-04-25T05:56:16.178Z,1745560576.178 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="BackseatDriver">
    <DefineArg Name="EnableBackseat">
        <False />
    </DefineArg>
    <Parallel />
    <Guidance:BackseatDriver>
        <Sequence />
        <Setting>
            <Guidance:BackseatDriver.powerBackseat />
            <Arg Name="EnableBackseat" />
        </Setting>
    </Guidance:BackseatDriver>
</Aggregate>

2025-04-25T05:56:16.190Z,1745560576.190 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-04-25T05:56:16.197Z,1745560576.197 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-04-25T05:56:16.208Z,1745560576.208 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-04-25T05:56:16.209Z,1745560576.209 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-04-25T05:56:16.210Z,1745560576.210 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-04-25T05:56:16.438Z,1745560576.438 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Aggregate Id="PowerOnly">
    <DefineArg Name="SampleAll">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad1">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad2">
        <False />
    </DefineArg>
    <DefineArg Name="SampleLoad3">
        <False />
    </DefineArg>
    <DefineArg Name="EnabledPowerOnly">
        <Sensor:PowerOnly.loadAtStartup />
    </DefineArg>
    <Parallel />
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleAll" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.samplePowerOnly />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad1" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad1 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad2" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad2 />
    </ReadData>
    <ReadData Strategy="MinError">
        <While>
            <Arg Name="SampleLoad3" />
            <And>
                <Arg Name="EnabledPowerOnly" />
            </And>
        </While>
        <Sensor:PowerOnly.sampleLoad3 />
    </ReadData>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:hour />
            <Value>99999</Value>
        </Setting>
    </Guidance:Wait>
</Aggregate>

2025-04-25T05:56:16.444Z,1745560576.444 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-04-25T05:56:16.446Z,1745560576.446 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-04-25T05:56:16.466Z,1745560576.466 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-04-25T05:56:16.485Z,1745560576.485 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-04-25T05:56:16.489Z,1745560576.489 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool
2025-04-25T05:56:16.497Z,1745560576.497 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-04-25T05:56:16.499Z,1745560576.499 [keepstation:H.Pitch](DEBUG): Construct.
2025-04-25T05:56:16.590Z,1745560576.590 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-04-25T05:56:16.601Z,1745560576.601 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-04-25T05:56:16.624Z,1745560576.624 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-04-25T05:56:16.658Z,1745560576.658 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-04-25T05:56:16.662Z,1745560576.662 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-04-25T05:56:16.680Z,1745560576.680 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-04-25T05:56:16.691Z,1745560576.691 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-04-25T05:56:16.706Z,1745560576.706 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-04-25T05:56:16.752Z,1745560576.752 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-04-25T05:56:16.790Z,1745560576.790 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-04-25T05:56:16.848Z,1745560576.848 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

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

    assign in sequence DiveMode = DoingComms

    call refId="NeedComms"

    aggregate setTransit {
      run in sequence

      break if (
        SinkOffSurface
        or OnStation
      )

      assign in sequence DiveMode = TransitToStation
    }

    aggregate setStation {
      run in sequence

      break if (
        SinkOffSurface
        or not OnStation
      )

      assign in sequence DiveMode = StationKeep
    }

    aggregate setSink {
      run in sequence

      break if ( not SinkOffSurface )

      assign in sequence DiveMode = SurfaceSink
    }
  }

  call id="StartingMission" refId="SurfaceComms"

  aggregate DiveCmd {
    run when ( called )

    aggregate SurfaceSink {
      run while ( DiveMode == SurfaceSink )

      aggregate sink {
        run in sequence

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

          set position = NaN cc
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = 0 m/s
        }

        syslog info "Sinking to " + SinkDepth~meter

        behavior Guidance:Pitch {
          run in sequence

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

          set depth = SinkDepth
        }

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

          break if ( OnStation )

          assign in sequence DiveMode = TransitToStation
        }

        aggregate sinkToStation {
          run in sequence

          break if ( not OnStation )

          assign in sequence DiveMode = StationKeep
        }
      }
    }

    aggregate TransitToStation {
      run while ( DiveMode == TransitToStation )

      aggregate dive {
        run in sequence

        behavior Guidance:Buoyancy {
          run in parallel

          set position = Control:VerticalControl.buoyancyNeutral
        }

        behavior Guidance:Pitch {
          run in parallel

          set depth = ApproachDepth
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = Speed
        }
      }
    }

    aggregate StationKeep {
      run while ( DiveMode == StationKeep )

      assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

      behavior Guidance:Pitch {
        run in parallel

        set depth = Depth
      }

      behavior Guidance:KeepStation {
        run in parallel

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

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

    run in sequence

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

      call refId="DiveCmd"
    }

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

      set latitude = Latitude
      set longitude = Longitude
    }

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

  call id="OnStation" refId="NeedComms"

  aggregate KeepStation {
    run in sequence

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

      call refId="DiveCmd"
    }

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

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2025-04-25T05:56:16.849Z,1745560576.849 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-04-25T05:56:38.667Z,1745560598.667 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250425T055210/Courier0009.lzma
2025-04-25T05:56:39.671Z,1745560599.671 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0009.lzma.bak
2025-04-25T05:56:39.671Z,1745560599.671 [DataOverHttps](IMPORTANT): SBD MOMSN=24727501, MTMSN=20250425T055632
2025-04-25T05:56:52.793Z,1745560612.793 [DataOverHttps](INFO): Received command: sched 20250425T13 "load Science/sci2.tl;set sci2.MissionTimeout 10 h;set sci2.Lat1 36.79317 degree;set sci2.Lon1 -121.98069 degree;set sci2.Lat2 36.797 degree;set sci2.Lon2 -121.847 degree;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 90 m" 60i6 1 2
2025-04-25T05:56:54.186Z,1745560614.186 [DataOverHttps](IMPORTANT): SBD MTMSN=20250425T055646
2025-04-25T05:57:04.395Z,1745560624.395 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 11 hour
2025-04-25T05:57:04.396Z,1745560624.396 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 120 minute
2025-04-25T05:57:04.397Z,1745560624.397 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.79317 degree
2025-04-25T05:57:04.397Z,1745560624.397 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.98069 degree
2025-04-25T05:57:04.398Z,1745560624.398 [CommandExec](IMPORTANT): got command set keepstation.Depth 20 meter
2025-04-25T05:57:04.405Z,1745560624.405 [CommandExec](IMPORTANT): got command schedule 20250425T130000 "load Science/sci2.tl;set sci2.MissionTimeout 10 h;set sci2.Lat1 36.79317 degree;set sci2.Lon1 -121.98069 degree;set sci2.Lat2 36.797 degree;set sci2.Lon2 -121.847 degree;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 90 m" 60i6 1 2.000000
2025-04-25T05:57:04.406Z,1745560624.406 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=60i6
2025-04-25T05:57:04.407Z,1745560624.407 [CommandExec](IMPORTANT): Scheduled #13 (#1 of 2 with id='60i6'): "load Science/sci2.tl;set sci2.MissionTimeout 10 h;set sci2.Lat1 36.79317 degree;set sci2.Lon1 -121.98069 degree;set sci2.Lat2 36.797 degree;set sci2.Lon2 -121.847 degree;set sci2.YoYoMinDepth 4 m;set sci2.YoYoMaxDepth 90 m", AT TIME: 2025-04-25T13:00:00Z
2025-04-25T05:57:04.740Z,1745560624.740 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250425T055210/Courier0012.lzma
2025-04-25T05:57:04.742Z,1745560624.742 [DataOverHttps](INFO): Received command: sched 20250425T13 "set sci2.SinkOffSurface 1 bool;set sci2.SinkDepth 2 m;set sci2.YoYoLightTerminate 1 bool;set sci2.MaxDepth 105 m;run" 60i6 2 2
2025-04-25T05:57:09.846Z,1745560629.846 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0012.lzma.bak
2025-04-25T05:57:09.846Z,1745560629.846 [DataOverHttps](INFO): SBD MOMSN=24727504
2025-04-25T05:57:33.879Z,1745560653.879 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250425T055210/Courier0015.lzma
2025-04-25T05:57:34.882Z,1745560654.882 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0015.lzma.bak
2025-04-25T05:57:34.882Z,1745560654.882 [DataOverHttps](INFO): SBD MOMSN=24727509
2025-04-25T05:57:53.669Z,1745560673.669 [CommandExec](IMPORTANT): got command set keepstation.Speed 1.0 meter_per_second
2025-04-25T05:57:53.670Z,1745560673.670 [CommandExec](IMPORTANT): got command set keepstation.SinkOffSurface 1 bool
2025-04-25T05:57:53.671Z,1745560673.671 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 45 meter
2025-04-25T05:57:53.676Z,1745560673.676 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.SurfaceSpeed 1 meter_per_second
2025-04-25T05:57:53.677Z,1745560673.677 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 10 minute
2025-04-25T05:57:53.677Z,1745560673.677 [CommandExec](IMPORTANT): got command run
2025-04-25T05:57:53.684Z,1745560673.684 [CommandExec](IMPORTANT): Running 
2025-04-25T05:57:53.693Z,1745560673.693 [CommandExec](IMPORTANT): got command schedule 20250425T130000 "set sci2.SinkOffSurface 1 bool;set sci2.SinkDepth 2 m;set sci2.YoYoLightTerminate 1 bool;set sci2.MaxDepth 105 m;run" 60i6 2 2.000000
2025-04-25T05:57:53.694Z,1745560673.694 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=60i6
2025-04-25T05:57:53.695Z,1745560673.695 [CommandExec](IMPORTANT): Scheduled #14 (#2 of 2 with id='60i6'): "set sci2.SinkOffSurface 1 bool;set sci2.SinkDepth 2 m;set sci2.YoYoLightTerminate 1 bool;set sci2.MaxDepth 105 m;run", AT TIME: 2025-04-25T13:00:00Z
2025-04-25T05:57:53.984Z,1745560673.984 [Default] Stopped
2025-04-25T05:57:53.985Z,1745560673.985 [Default](DEBUG): Aggregate::uninitialize Default
2025-04-25T05:57:53.985Z,1745560673.985 [Default:B.GoToSurface] Stopped
2025-04-25T05:57:53.985Z,1745560673.985 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T05:57:53.985Z,1745560673.985 [Default:CheckIn] Stopped
2025-04-25T05:57:53.985Z,1745560673.985 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-25T05:57:53.985Z,1745560673.985 [Default:CheckIn:Read_Iridium] Stopped
2025-04-25T05:57:53.985Z,1745560673.985 [MissionManager](IMPORTANT): Started mission keepstation
2025-04-25T05:57:53.985Z,1745560673.985 [keepstation] Running Loop=1
2025-04-25T05:57:53.985Z,1745560673.985 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes] Running Loop=1
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-04-25T05:57:53.986Z,1745560673.986 [keepstation:BackseatDriver] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly:A] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly:B] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly:C] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly:D] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:H.Pitch] Running Loop=1
2025-04-25T05:57:53.987Z,1745560673.987 [keepstation:H.Pitch](DEBUG): Initialize.
2025-04-25T05:57:53.992Z,1745560673.992 [keepstation:B] Running Loop=1
2025-04-25T05:57:53.993Z,1745560673.993 [keepstation:H.Pitch] Running Loop=1
2025-04-25T05:57:53.993Z,1745560673.993 [keepstation:PowerOnly] Running Loop=1
2025-04-25T05:57:53.993Z,1745560673.993 [keepstation:PowerOnly:D] Stopped
2025-04-25T05:57:53.993Z,1745560673.993 [keepstation:PowerOnly:C] Stopped
2025-04-25T05:57:53.993Z,1745560673.993 [keepstation:PowerOnly:B] Stopped
2025-04-25T05:57:53.994Z,1745560673.994 [keepstation:PowerOnly:A] Stopped
2025-04-25T05:57:53.994Z,1745560673.994 [keepstation:BackseatDriver] Running Loop=1
2025-04-25T05:57:53.994Z,1745560673.994 [keepstation:StandardEnvelopes] Running Loop=1
2025-04-25T05:57:53.994Z,1745560673.994 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-04-25T05:57:53.994Z,1745560673.994 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-04-25T05:57:53.994Z,1745560673.994 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-04-25T05:57:53.995Z,1745560673.995 [keepstation:B] Stopped
2025-04-25T05:57:53.995Z,1745560673.995 [keepstation:D] Running Loop=1
2025-04-25T05:57:54.408Z,1745560674.408 [keepstation:D] Stopped
2025-04-25T05:57:54.408Z,1745560674.408 [keepstation:E] Running Loop=1
2025-04-25T05:57:54.786Z,1745560674.786 [keepstation:E] Stopped
2025-04-25T05:57:54.786Z,1745560674.786 [keepstation:StartingMission] Running Loop=1
2025-04-25T05:57:54.787Z,1745560674.787 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-04-25T05:57:55.196Z,1745560675.196 [keepstation:SurfaceComms] Running Loop=1
2025-04-25T05:57:55.196Z,1745560675.196 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-25T05:57:55.197Z,1745560675.197 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-25T05:57:55.197Z,1745560675.197 [keepstation:SurfaceComms:A] Stopped
2025-04-25T05:57:55.197Z,1745560675.197 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-25T05:57:55.197Z,1745560675.197 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-25T05:57:55.611Z,1745560675.611 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-04-25T05:57:55.611Z,1745560675.611 [keepstation:NeedComms] Running Loop=1
2025-04-25T05:57:55.611Z,1745560675.611 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-25T05:57:55.611Z,1745560675.611 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-25T05:57:55.611Z,1745560675.611 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-25T05:57:55.616Z,1745560675.616 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-25T05:57:55.616Z,1745560675.616 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-25T05:57:55.617Z,1745560675.617 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-25T05:57:55.617Z,1745560675.617 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-25T05:57:55.617Z,1745560675.617 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-25T05:57:55.618Z,1745560675.618 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-25T05:57:55.618Z,1745560675.618 [keepstation:NeedComms:A] Running Loop=1
2025-04-25T05:57:55.619Z,1745560675.619 [keepstation:NeedComms:A](INFO): last time_fix was:  1745560511.000000 second since 1970/01/01T00:00:00Z 
2025-04-25T05:57:55.619Z,1745560675.619 [keepstation:NeedComms:A] Stopped
2025-04-25T05:57:56.021Z,1745560676.021 [keepstation:NeedComms:C] Running Loop=1
2025-04-25T05:57:56.399Z,1745560676.399 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-25T05:57:58.002Z,1745560678.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,055750.00,A,3647.69491,N,12154.15371,W,1.011,186.88,250425,,,A*73
2025-04-25T05:57:58.005Z,1745560678.005 [NAL9602](INFO): GPS fix at 20250425T055750: (36.794915, -121.902562)
2025-04-25T05:57:58.018Z,1745560678.018 [keepstation:NeedComms:C] Stopped
2025-04-25T05:57:58.018Z,1745560678.018 [keepstation:NeedComms:D] Running Loop=1
2025-04-25T05:57:58.379Z,1745560678.379 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250425T055210/Courier0018.lzma
2025-04-25T05:57:58.442Z,1745560678.442 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-25T05:57:59.383Z,1745560679.383 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0018.lzma.bak
2025-04-25T05:57:59.383Z,1745560679.383 [DataOverHttps](INFO): SBD MOMSN=24727512
2025-04-25T05:58:15.511Z,1745560695.511 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250425T055210/Courier0021.lzma
2025-04-25T05:58:16.514Z,1745560696.514 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0021.lzma.bak
2025-04-25T05:58:16.514Z,1745560696.514 [DataOverHttps](INFO): SBD MOMSN=24727514
2025-04-25T05:58:25.897Z,1745560705.897 [NAL9602](INFO): SBD MO Status=1, MOMSN=20515, MT Status=0, MTMSN=0
2025-04-25T05:58:25.897Z,1745560705.897 [NAL9602](INFO): No messages in MT queue
2025-04-25T05:58:33.697Z,1745560713.697 [DataOverHttps](INFO): Sending 737 bytes from file Logs/20250425T055210/Express0001.lzma
2025-04-25T05:58:34.698Z,1745560714.698 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0001.lzma.bak
2025-04-25T05:58:34.698Z,1745560714.698 [DataOverHttps](INFO): SBD MOMSN=24727519
2025-04-25T05:58:53.037Z,1745560733.037 [DataOverHttps](INFO): Sending 80 bytes from file Logs/20250425T055210/Express0004.lzma
2025-04-25T05:58:54.038Z,1745560734.038 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0004.lzma.bak
2025-04-25T05:58:54.038Z,1745560734.038 [DataOverHttps](INFO): SBD MOMSN=24727530
2025-04-25T05:58:56.587Z,1745560736.587 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-25T05:59:10.365Z,1745560750.365 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20250425T055210/Express0007.lzma
2025-04-25T05:59:14.378Z,1745560754.378 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0007.lzma.bak
2025-04-25T05:59:14.378Z,1745560754.378 [DataOverHttps](INFO): SBD MOMSN=24727532
2025-04-25T05:59:30.536Z,1745560770.536 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20250425T055210/Express0010.lzma
2025-04-25T05:59:31.538Z,1745560771.538 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0010.lzma.bak
2025-04-25T05:59:31.538Z,1745560771.538 [DataOverHttps](INFO): SBD MOMSN=24727540
2025-04-25T05:59:47.788Z,1745560787.788 [DataOverHttps](INFO): Sending 352 bytes from file Logs/20250425T055210/Express0013.lzma
2025-04-25T05:59:48.790Z,1745560788.790 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0013.lzma.bak
2025-04-25T05:59:48.790Z,1745560788.790 [DataOverHttps](INFO): SBD MOMSN=24727546
2025-04-25T06:00:05.744Z,1745560805.744 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250425T055210/Express0016.lzma
2025-04-25T06:00:06.746Z,1745560806.746 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0016.lzma.bak
2025-04-25T06:00:06.746Z,1745560806.746 [DataOverHttps](INFO): SBD MOMSN=24727558
2025-04-25T06:00:22.633Z,1745560822.633 [DataOverHttps](INFO): Sending 441 bytes from file Logs/20250425T055210/Express0019.lzma
2025-04-25T06:00:23.634Z,1745560823.634 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0019.lzma.bak
2025-04-25T06:00:23.634Z,1745560823.634 [DataOverHttps](INFO): SBD MOMSN=24727566
2025-04-25T06:00:56.432Z,1745560856.432 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-04-25T06:01:12.489Z,1745560872.489 [DataOverHttps](INFO): Sending 482 bytes from file Logs/20250425T055210/Express0022.lzma
2025-04-25T06:01:13.490Z,1745560873.490 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0022.lzma.bak
2025-04-25T06:01:13.490Z,1745560873.490 [DataOverHttps](INFO): SBD MOMSN=24727578
2025-04-25T06:01:15.598Z,1745560875.598 [keepstation:NeedComms:D] Stopped
2025-04-25T06:01:15.598Z,1745560875.598 [keepstation:NeedComms:E] Running Loop=1
2025-04-25T06:01:15.990Z,1745560875.990 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-25T06:01:17.595Z,1745560877.595 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,060110.00,A,3647.68656,N,12154.15055,W,0.603,181.37,250425,,,A*7D
2025-04-25T06:01:17.597Z,1745560877.597 [NAL9602](INFO): GPS fix at 20250425T060110: (36.794776, -121.902509)
2025-04-25T06:01:17.630Z,1745560877.630 [keepstation:NeedComms:E] Stopped
2025-04-25T06:01:17.631Z,1745560877.631 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-25T06:01:17.636Z,1745560877.636 [keepstation:NeedComms] Stopped
2025-04-25T06:01:17.636Z,1745560877.636 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-25T06:01:17.636Z,1745560877.636 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-25T06:01:17.636Z,1745560877.636 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T06:01:18.053Z,1745560878.053 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-25T06:01:18.053Z,1745560878.053 [keepstation:SurfaceComms:B] Stopped
2025-04-25T06:01:18.053Z,1745560878.053 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-25T06:01:18.053Z,1745560878.053 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-25T06:01:18.053Z,1745560878.053 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-25T06:01:18.054Z,1745560878.054 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-25T06:01:18.432Z,1745560878.432 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-25T06:01:18.432Z,1745560878.432 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-25T06:01:18.432Z,1745560878.432 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-25T06:01:18.432Z,1745560878.432 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-25T06:01:18.432Z,1745560878.432 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-25T06:01:18.432Z,1745560878.432 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-25T06:01:18.852Z,1745560878.852 [keepstation:SurfaceComms:setStation] Stopped
2025-04-25T06:01:18.852Z,1745560878.852 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-25T06:01:18.852Z,1745560878.852 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-25T06:01:18.852Z,1745560878.852 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-25T06:01:18.853Z,1745560878.853 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-25T06:01:18.853Z,1745560878.853 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-25T06:01:19.258Z,1745560879.258 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-25T06:01:19.258Z,1745560879.258 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-25T06:01:19.258Z,1745560879.258 [keepstation:SurfaceComms:setSink] Stopped
2025-04-25T06:01:19.258Z,1745560879.258 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-25T06:01:19.258Z,1745560879.258 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-25T06:01:19.258Z,1745560879.258 [keepstation:SurfaceComms] Stopped
2025-04-25T06:01:19.258Z,1745560879.258 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-25T06:01:19.667Z,1745560879.667 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-04-25T06:01:19.667Z,1745560879.667 [keepstation:StartingMission] Stopped
2025-04-25T06:01:19.667Z,1745560879.667 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-04-25T06:01:19.667Z,1745560879.667 [keepstation:TransitToStation] Running Loop=1
2025-04-25T06:01:19.667Z,1745560879.667 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-04-25T06:01:19.672Z,1745560879.672 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-25T06:01:19.672Z,1745560879.672 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-04-25T06:01:19.672Z,1745560879.672 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-04-25T06:01:19.672Z,1745560879.672 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-04-25T06:01:19.672Z,1745560879.672 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-04-25T06:01:19.672Z,1745560879.672 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-04-25T06:01:19.673Z,1745560879.673 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.793171,-121.980690
2025-04-25T06:01:20.045Z,1745560880.045 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.793171,-121.980690
2025-04-25T06:01:20.046Z,1745560880.046 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-25T06:01:20.046Z,1745560880.046 [keepstation:DiveCmd] Running Loop=1
2025-04-25T06:01:20.046Z,1745560880.046 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-04-25T06:01:20.046Z,1745560880.046 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-25T06:01:20.046Z,1745560880.046 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-25T06:01:20.047Z,1745560880.047 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-25T06:01:20.052Z,1745560880.052 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-25T06:01:20.052Z,1745560880.052 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-25T06:01:20.052Z,1745560880.052 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-25T06:01:20.052Z,1745560880.052 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-25T06:01:20.052Z,1745560880.052 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-25T06:01:20.052Z,1745560880.052 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-25T06:01:20.052Z,1745560880.052 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-25T06:01:20.053Z,1745560880.053 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-25T06:01:20.053Z,1745560880.053 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-25T06:01:20.053Z,1745560880.053 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-25T06:01:20.053Z,1745560880.053 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-25T06:01:20.053Z,1745560880.053 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-25T06:01:20.053Z,1745560880.053 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-25T06:01:20.053Z,1745560880.053 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-25T06:01:20.054Z,1745560880.054 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-25T06:01:20.054Z,1745560880.054 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-25T06:01:20.054Z,1745560880.054 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-25T06:01:20.054Z,1745560880.054 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-25T06:01:20.054Z,1745560880.054 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-25T06:01:20.054Z,1745560880.054 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-25T06:01:20.054Z,1745560880.054 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-25T06:01:20.055Z,1745560880.055 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-25T06:01:20.080Z,1745560880.080 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-25T06:01:20.080Z,1745560880.080 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-25T06:01:20.080Z,1745560880.080 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-25T06:01:20.080Z,1745560880.080 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-25T06:01:20.080Z,1745560880.080 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-25T06:01:20.081Z,1745560880.081 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-25T06:01:41.583Z,1745560901.583 [NAL9602](INFO): SBD MO Status=1, MOMSN=20516, MT Status=0, MTMSN=0
2025-04-25T06:01:41.583Z,1745560901.583 [NAL9602](INFO): No messages in MT queue
2025-04-25T06:02:12.285Z,1745560932.285 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-25T06:03:27.156Z,1745561007.156 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T06:03:58.300Z,1745561038.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T06:03:58.468Z,1745561038.468 [Radio_Surface](INFO): Powering down
2025-04-25T06:03:59.304Z,1745561039.304 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-25T06:05:34.700Z,1745561134.700 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755371
2025-04-25T06:05:59.738Z,1745561159.738 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T06:06:05.001Z,1745561165.001 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-25T06:06:05.001Z,1745561165.001 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-25T06:06:05.001Z,1745561165.001 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T06:06:05.002Z,1745561165.002 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-25T06:06:05.413Z,1745561165.413 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-25T06:06:05.413Z,1745561165.413 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T06:06:05.413Z,1745561165.413 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-25T06:06:05.413Z,1745561165.413 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T06:06:05.413Z,1745561165.413 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-25T06:06:05.413Z,1745561165.413 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T06:06:05.413Z,1745561165.413 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-25T06:06:05.827Z,1745561165.827 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-25T06:06:05.827Z,1745561165.827 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-25T06:06:05.827Z,1745561165.827 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-25T06:06:05.827Z,1745561165.827 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-25T06:06:05.827Z,1745561165.827 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-25T06:06:05.827Z,1745561165.827 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-25T06:06:05.828Z,1745561165.828 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-25T06:06:05.828Z,1745561165.828 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-25T06:06:05.828Z,1745561165.828 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-25T06:06:05.828Z,1745561165.828 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-25T06:06:05.828Z,1745561165.828 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-25T06:06:05.828Z,1745561165.828 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-25T06:06:05.829Z,1745561165.829 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-25T06:06:05.829Z,1745561165.829 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-25T06:06:05.829Z,1745561165.829 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-04-25T06:06:05.829Z,1745561165.829 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-04-25T06:06:05.829Z,1745561165.829 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T06:06:05.830Z,1745561165.830 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-25T06:06:06.214Z,1745561166.214 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -0.45 mm (4 active estimators).
2025-04-25T06:09:24.320Z,1745561364.320 [ElevatorServo](ERROR): getPosition uart error serial timeout
2025-04-25T06:09:24.320Z,1745561364.320 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2025-04-25T06:09:24.320Z,1745561364.320 [ElevatorServo] Communications Fault, FailCount= 1
2025-04-25T06:09:24.320Z,1745561364.320 [ElevatorServo](ERROR): Communications Fault
2025-04-25T06:09:24.455Z,1745561364.455 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2025-04-25T06:09:24.653Z,1745561364.653 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-25T06:09:24.653Z,1745561364.653 [ElevatorServo](INFO): Powering down
2025-04-25T06:09:25.458Z,1745561365.458 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-25T06:09:25.577Z,1745561365.577 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-25T06:09:25.657Z,1745561365.657 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-04-25T06:09:25.657Z,1745561365.657 [ElevatorServo] No Fault, FailCount= 1
2025-04-25T06:15:59.795Z,1745561759.795 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T06:26:00.155Z,1745562360.155 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T06:29:57.837Z,1745562597.837 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-25T06:29:57.838Z,1745562597.838 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-04-25T06:29:57.838Z,1745562597.838 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-25T06:29:57.841Z,1745562597.841 [BPC1](INFO): Received data from all battery sticks.
2025-04-25T06:36:00.222Z,1745562960.222 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T06:46:00.603Z,1745563560.603 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T06:56:01.003Z,1745564161.003 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T06:59:46.164Z,1745564386.164 [RudderServo](ERROR): getPosition uart error serial timeout
2025-04-25T06:59:46.164Z,1745564386.164 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-04-25T06:59:46.165Z,1745564386.165 [RudderServo] Communications Fault, FailCount= 1
2025-04-25T06:59:46.165Z,1745564386.165 [RudderServo](ERROR): Communications Fault
2025-04-25T06:59:46.231Z,1745564386.231 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-04-25T06:59:46.503Z,1745564386.503 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-25T06:59:46.503Z,1745564386.503 [RudderServo](INFO): Powering down
2025-04-25T06:59:47.295Z,1745564387.295 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-25T06:59:47.413Z,1745564387.413 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-25T06:59:47.431Z,1745564387.431 [CBIT](INFO): Clearing failed state for component RudderServo
2025-04-25T06:59:47.431Z,1745564387.431 [RudderServo] No Fault, FailCount= 1
2025-04-25T07:06:01.382Z,1745564761.382 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T07:16:01.387Z,1745565361.387 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T07:26:01.746Z,1745565961.746 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T07:33:20.892Z,1745566400.892 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-04-25T07:35:48.448Z,1745566548.448 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-25T07:35:53.083Z,1745566553.083 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.898968
CHAN A1 (24V): 0.844297
CHAN A2 (12V): 0.924891
CHAN A3 (5V): 0.552281
CHAN B0 (3.3V): 0.372909
CHAN B1 (3.15aV): 0.481116
CHAN B2 (3.15bV): 0.480929
CHAN B3 (GND): 0.011550
OPEN: 0.026532
Full Scale: +/- 1 mA
2025-04-25T07:36:01.735Z,1745566561.735 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T07:36:32.446Z,1745566592.446 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-04-25T07:37:36.283Z,1745566656.283 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-25T07:37:36.296Z,1745566656.296 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-04-25T07:37:36.297Z,1745566656.297 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-25T07:37:36.299Z,1745566656.299 [BPC1](INFO): Received data from all battery sticks.
2025-04-25T07:46:02.118Z,1745567162.118 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T07:56:02.466Z,1745567762.466 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T08:01:17.612Z,1745568077.612 [keepstation:SurfaceComms] Running Loop=1
2025-04-25T08:01:17.612Z,1745568077.612 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-25T08:01:17.613Z,1745568077.613 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-25T08:01:17.614Z,1745568077.614 [keepstation:SurfaceComms:A] Stopped
2025-04-25T08:01:17.614Z,1745568077.614 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-25T08:01:17.616Z,1745568077.616 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-25T08:01:17.618Z,1745568077.618 [keepstation:NeedComms] Running Loop=1
2025-04-25T08:01:17.619Z,1745568077.619 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-25T08:01:17.619Z,1745568077.619 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-25T08:01:17.619Z,1745568077.619 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-25T08:01:17.622Z,1745568077.622 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-25T08:01:17.622Z,1745568077.622 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-25T08:01:17.622Z,1745568077.622 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-25T08:01:17.623Z,1745568077.623 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-25T08:01:17.623Z,1745568077.623 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-25T08:01:17.623Z,1745568077.623 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-25T08:01:17.623Z,1745568077.623 [keepstation:NeedComms:A] Running Loop=1
2025-04-25T08:01:17.641Z,1745568077.641 [keepstation:NeedComms:A](INFO): last time_fix was:  1745560870.000000 second since 1970/01/01T00:00:00Z 
2025-04-25T08:01:17.641Z,1745568077.641 [keepstation:NeedComms:A] Stopped
2025-04-25T08:01:18.017Z,1745568078.017 [keepstation:TransitToStation:Dive] Stopped
2025-04-25T08:01:18.017Z,1745568078.017 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-04-25T08:01:18.017Z,1745568078.017 [keepstation:TransitToStation:Dive:A] Stopped
2025-04-25T08:01:18.017Z,1745568078.017 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-25T08:01:18.018Z,1745568078.018 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-25T08:02:18.082Z,1745568138.082 [keepstation:NeedComms:C] Running Loop=1
2025-04-25T08:02:18.761Z,1745568138.761 [Radio_Surface](INFO): Powering up
2025-04-25T08:02:24.888Z,1745568144.888 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-25T08:02:39.077Z,1745568159.077 [NAL9602](INFO): SBD MO Status=1, MOMSN=20517, MT Status=0, MTMSN=0
2025-04-25T08:02:39.078Z,1745568159.078 [NAL9602](INFO): No messages in MT queue
2025-04-25T08:02:55.016Z,1745568175.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:02:58.466Z,1745568178.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080251.00,A,3647.55187,N,12157.78449,W,1.788,145.14,250425,,,A*7B
2025-04-25T08:02:58.469Z,1745568178.469 [NAL9602](INFO): GPS fix at 20250425T080251: (36.792531, -121.963075)
2025-04-25T08:02:58.500Z,1745568178.500 [UniversalFixResidualReporter](INFO): Fix residual: 20.3 %DT, over the last 6859.7 m. Residual distance 1395.8 m at bearing -87.0 degrees. Fix at (36.7925, -121.9631) with 5398.8 m made good.
2025-04-25T08:02:58.504Z,1745568178.504 [keepstation:NeedComms:C] Stopped
2025-04-25T08:02:58.505Z,1745568178.505 [keepstation:NeedComms:D] Running Loop=1
2025-04-25T08:03:22.225Z,1745568202.225 [NAL9602](INFO): SBD MO Status=1, MOMSN=20518, MT Status=0, MTMSN=0
2025-04-25T08:03:22.284Z,1745568202.284 [NAL9602](INFO): Sent 269 bytes from file Logs/20250425T055210/Courier0024.lzma
2025-04-25T08:03:22.284Z,1745568202.284 [NAL9602](INFO): Packets left to send: 0
2025-04-25T08:03:26.144Z,1745568206.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:03:41.665Z,1745568221.665 [NAL9602](INFO): SBD MO Status=1, MOMSN=20519, MT Status=0, MTMSN=0
2025-04-25T08:03:41.728Z,1745568221.728 [NAL9602](INFO): Sent 332 bytes from file Logs/20250425T055210/Express0025.lzma
2025-04-25T08:03:41.728Z,1745568221.728 [NAL9602](INFO): Packets left to send: 4
2025-04-25T08:03:52.322Z,1745568232.322 [NAL9602](INFO): SBD MO Status=1, MOMSN=20520, MT Status=0, MTMSN=0
2025-04-25T08:03:52.380Z,1745568232.380 [NAL9602](INFO): Sent 332 bytes from file Logs/20250425T055210/Express0025.lzma
2025-04-25T08:03:52.380Z,1745568232.380 [NAL9602](INFO): Packets left to send: 3
2025-04-25T08:03:57.268Z,1745568237.268 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:04:04.297Z,1745568244.297 [NAL9602](INFO): SBD MO Status=1, MOMSN=20521, MT Status=0, MTMSN=0
2025-04-25T08:04:04.345Z,1745568244.345 [NAL9602](INFO): Sent 332 bytes from file Logs/20250425T055210/Express0025.lzma
2025-04-25T08:04:04.345Z,1745568244.345 [NAL9602](INFO): Packets left to send: 2
2025-04-25T08:04:20.961Z,1745568260.961 [NAL9602](INFO): SBD MO Status=2, MOMSN=20522, MT Status=2, MTMSN=0
2025-04-25T08:04:20.961Z,1745568260.961 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T08:04:28.400Z,1745568268.400 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:04:36.949Z,1745568276.949 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003554
2025-04-25T08:04:47.755Z,1745568287.755 [DataOverHttps](INFO): Sending 1385 bytes from file Logs/20250425T055210/Express0025.lzma
2025-04-25T08:04:48.754Z,1745568288.754 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0025.lzma.bak
2025-04-25T08:04:48.754Z,1745568288.754 [DataOverHttps](INFO): SBD MOMSN=24728235
2025-04-25T08:04:50.867Z,1745568290.867 [keepstation:NeedComms:D] Stopped
2025-04-25T08:04:50.867Z,1745568290.867 [keepstation:NeedComms:E] Running Loop=1
2025-04-25T08:04:52.859Z,1745568292.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,080445.00,A,3647.54605,N,12157.77779,W,0.058,129.21,250425,,,A*7C
2025-04-25T08:04:52.861Z,1745568292.861 [NAL9602](INFO): GPS fix at 20250425T080445: (36.792434, -121.962963)
2025-04-25T08:04:52.903Z,1745568292.903 [keepstation:NeedComms:E] Stopped
2025-04-25T08:04:52.912Z,1745568292.912 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-25T08:04:52.912Z,1745568292.912 [keepstation:NeedComms] Stopped
2025-04-25T08:04:52.912Z,1745568292.912 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-25T08:04:52.912Z,1745568292.912 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-25T08:04:52.913Z,1745568292.913 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T08:04:53.310Z,1745568293.310 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-25T08:04:53.310Z,1745568293.310 [keepstation:SurfaceComms:B] Stopped
2025-04-25T08:04:53.310Z,1745568293.310 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-25T08:04:53.310Z,1745568293.310 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-25T08:04:53.310Z,1745568293.310 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-25T08:04:53.310Z,1745568293.310 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-25T08:04:53.733Z,1745568293.733 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-25T08:04:53.733Z,1745568293.733 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-25T08:04:53.733Z,1745568293.733 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-25T08:04:53.733Z,1745568293.733 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-25T08:04:53.733Z,1745568293.733 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-25T08:04:53.733Z,1745568293.733 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-25T08:04:54.110Z,1745568294.110 [keepstation:SurfaceComms:setStation] Stopped
2025-04-25T08:04:54.111Z,1745568294.111 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-25T08:04:54.111Z,1745568294.111 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-25T08:04:54.111Z,1745568294.111 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-25T08:04:54.111Z,1745568294.111 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-25T08:04:54.111Z,1745568294.111 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-25T08:04:54.509Z,1745568294.509 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-25T08:04:54.509Z,1745568294.509 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-25T08:04:54.509Z,1745568294.509 [keepstation:SurfaceComms:setSink] Stopped
2025-04-25T08:04:54.509Z,1745568294.509 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-25T08:04:54.509Z,1745568294.509 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-25T08:04:54.510Z,1745568294.510 [keepstation:SurfaceComms] Stopped
2025-04-25T08:04:54.510Z,1745568294.510 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-25T08:04:54.897Z,1745568294.897 [keepstation:TransitToStation:Dive] Running Loop=1
2025-04-25T08:04:54.897Z,1745568294.897 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-04-25T08:04:54.897Z,1745568294.897 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-04-25T08:04:54.898Z,1745568294.898 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-04-25T08:04:54.898Z,1745568294.898 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-25T08:04:54.898Z,1745568294.898 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-25T08:04:54.898Z,1745568294.898 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-25T08:04:54.898Z,1745568294.898 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T08:04:54.898Z,1745568294.898 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-25T08:04:54.899Z,1745568294.899 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-25T08:04:54.899Z,1745568294.899 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-25T08:04:54.899Z,1745568294.899 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-25T08:04:54.899Z,1745568294.899 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-25T08:04:54.899Z,1745568294.899 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-25T08:04:54.899Z,1745568294.899 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-25T08:04:54.924Z,1745568294.924 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-25T08:04:54.924Z,1745568294.924 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-25T08:05:24.873Z,1745568324.873 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-25T08:06:02.859Z,1745568362.859 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T08:10:35.748Z,1745568635.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:11:06.880Z,1745568666.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:11:13.296Z,1745568673.296 [Radio_Surface](INFO): Powering down
2025-04-25T08:11:38.004Z,1745568698.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:11:39.017Z,1745568699.017 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-25T08:12:45.280Z,1745568765.280 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739166
2025-04-25T08:13:37.014Z,1745568817.014 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-25T08:13:37.015Z,1745568817.015 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-25T08:13:37.015Z,1745568817.015 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T08:13:37.015Z,1745568817.015 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-25T08:13:37.431Z,1745568817.431 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-25T08:13:37.431Z,1745568817.431 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T08:13:37.437Z,1745568817.437 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-25T08:13:37.438Z,1745568817.438 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T08:13:37.438Z,1745568817.438 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-25T08:13:37.438Z,1745568817.438 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T08:13:37.438Z,1745568817.438 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-25T08:13:37.820Z,1745568817.820 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-04-25T08:13:37.821Z,1745568817.821 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-04-25T08:13:37.822Z,1745568817.822 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T08:13:37.823Z,1745568817.823 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-25T08:16:03.257Z,1745568963.257 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T08:16:32.484Z,1745568992.484 [RudderServo](ERROR): getPosition uart error serial timeout
2025-04-25T08:16:32.484Z,1745568992.484 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-04-25T08:16:32.484Z,1745568992.484 [RudderServo] Communications Fault, FailCount= 1
2025-04-25T08:16:32.484Z,1745568992.484 [RudderServo](ERROR): Communications Fault
2025-04-25T08:16:32.551Z,1745568992.551 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-04-25T08:16:32.757Z,1745568992.757 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-25T08:16:32.757Z,1745568992.757 [RudderServo](INFO): Powering down
2025-04-25T08:16:33.575Z,1745568993.575 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-25T08:16:33.693Z,1745568993.693 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-25T08:16:33.711Z,1745568993.711 [CBIT](INFO): Clearing failed state for component RudderServo
2025-04-25T08:16:33.711Z,1745568993.711 [RudderServo] No Fault, FailCount= 1
2025-04-25T08:26:03.609Z,1745569563.609 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T08:36:03.642Z,1745570163.642 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T08:42:21.406Z,1745570541.406 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.793171,-121.980690
2025-04-25T08:42:21.406Z,1745570541.406 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-04-25T08:42:21.406Z,1745570541.406 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-04-25T08:42:21.406Z,1745570541.406 [keepstation:TransitToStation:C] Running Loop=1
2025-04-25T08:42:21.820Z,1745570541.820 [keepstation:TransitToStation:C] Stopped
2025-04-25T08:42:21.820Z,1745570541.820 [keepstation:TransitToStation:D] Running Loop=1
2025-04-25T08:42:21.821Z,1745570541.821 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-25T08:42:21.821Z,1745570541.821 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-25T08:42:21.821Z,1745570541.821 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-25T08:42:21.821Z,1745570541.821 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-25T08:42:21.821Z,1745570541.821 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-25T08:42:21.822Z,1745570541.822 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-25T08:42:21.822Z,1745570541.822 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-25T08:42:21.822Z,1745570541.822 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-25T08:42:21.822Z,1745570541.822 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-25T08:42:21.823Z,1745570541.823 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-25T08:42:21.823Z,1745570541.823 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-25T08:42:21.823Z,1745570541.823 [keepstation:DiveCmd:TransitToStation] Stopped
2025-04-25T08:42:21.823Z,1745570541.823 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-04-25T08:42:21.823Z,1745570541.823 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-04-25T08:42:21.828Z,1745570541.828 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-04-25T08:42:21.828Z,1745570541.828 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-04-25T08:42:21.828Z,1745570541.828 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-25T08:42:21.828Z,1745570541.828 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-04-25T08:42:21.828Z,1745570541.828 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-04-25T08:42:21.828Z,1745570541.828 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-04-25T08:42:22.203Z,1745570542.203 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position -0.45 mm.
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation:D] Stopped
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation] Stopped
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation:Dive] Stopped
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation:Dive:A] Stopped
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-04-25T08:42:22.210Z,1745570542.210 [keepstation:OnStation] Running Loop=1
2025-04-25T08:42:22.211Z,1745570542.211 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-04-25T08:42:22.616Z,1745570542.616 [keepstation:NeedComms] Running Loop=1
2025-04-25T08:42:22.617Z,1745570542.617 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-25T08:42:22.617Z,1745570542.617 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-25T08:42:22.617Z,1745570542.617 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-25T08:42:22.617Z,1745570542.617 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-25T08:42:22.618Z,1745570542.618 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-25T08:42:22.618Z,1745570542.618 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-25T08:42:22.618Z,1745570542.618 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-25T08:42:22.618Z,1745570542.618 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-25T08:42:22.619Z,1745570542.619 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-25T08:42:22.619Z,1745570542.619 [keepstation:NeedComms:A] Running Loop=1
2025-04-25T08:42:22.621Z,1745570542.621 [keepstation:NeedComms:A](INFO): last time_fix was:  1745568285.000000 second since 1970/01/01T00:00:00Z 
2025-04-25T08:42:22.621Z,1745570542.621 [keepstation:NeedComms:A] Stopped
2025-04-25T08:43:20.975Z,1745570600.975 [keepstation:NeedComms:C] Running Loop=1
2025-04-25T08:43:21.716Z,1745570601.716 [Radio_Surface](INFO): Powering up
2025-04-25T08:43:27.864Z,1745570607.864 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-25T08:43:57.985Z,1745570637.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:44:04.664Z,1745570644.664 [NAL9602](INFO): SBD MO Status=2, MOMSN=20522, MT Status=2, MTMSN=0
2025-04-25T08:44:04.664Z,1745570644.664 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T08:44:23.651Z,1745570663.651 [NAL9602](INFO): SBD MO Status=2, MOMSN=20522, MT Status=2, MTMSN=0
2025-04-25T08:44:23.651Z,1745570663.651 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T08:44:29.108Z,1745570669.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:44:41.832Z,1745570681.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=20522, MT Status=2, MTMSN=0
2025-04-25T08:44:41.832Z,1745570681.832 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T08:45:00.232Z,1745570700.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:45:04.465Z,1745570704.465 [NAL9602](INFO): SBD MO Status=1, MOMSN=20522, MT Status=0, MTMSN=0
2025-04-25T08:45:04.465Z,1745570704.465 [NAL9602](INFO): No messages in MT queue
2025-04-25T08:45:05.663Z,1745570705.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084458.00,A,3647.44296,N,12158.53871,W,0.933,164.29,250425,,,A*70
2025-04-25T08:45:05.665Z,1745570705.665 [NAL9602](INFO): GPS fix at 20250425T084458: (36.790716, -121.975645)
2025-04-25T08:45:05.677Z,1745570705.677 [UniversalFixResidualReporter](INFO): Fix residual: 32.6 %DT, over the last 1744.4 m. Residual distance 568.4 m at bearing -61.6 degrees. Fix at (36.7907, -121.9756) with 1145.3 m made good.
2025-04-25T08:45:05.679Z,1745570705.679 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-25T08:45:05.682Z,1745570705.682 [keepstation:NeedComms:C] Stopped
2025-04-25T08:45:05.683Z,1745570705.683 [keepstation:NeedComms:D] Running Loop=1
2025-04-25T08:45:13.926Z,1745570713.926 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-25T08:45:13.926Z,1745570713.926 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-04-25T08:45:13.927Z,1745570713.927 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-25T08:45:13.950Z,1745570713.950 [BPC1](INFO): Received data from all battery sticks.
2025-04-25T08:45:29.684Z,1745570729.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=20523, MT Status=2, MTMSN=0
2025-04-25T08:45:29.684Z,1745570729.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T08:45:31.356Z,1745570731.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:45:39.797Z,1745570739.797 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003514
2025-04-25T08:45:47.987Z,1745570747.987 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250425T055210/Courier0027.lzma
2025-04-25T08:45:48.990Z,1745570748.990 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Courier0027.lzma.bak
2025-04-25T08:45:48.990Z,1745570748.990 [DataOverHttps](INFO): SBD MOMSN=24728419
2025-04-25T08:45:53.559Z,1745570753.559 [NAL9602](INFO): SBD MO Status=2, MOMSN=20523, MT Status=2, MTMSN=0
2025-04-25T08:45:53.559Z,1745570753.559 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T08:46:03.656Z,1745570763.656 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T08:46:06.205Z,1745570766.205 [DataOverHttps](INFO): Sending 523 bytes from file Logs/20250425T055210/Express0028.lzma
2025-04-25T08:46:07.206Z,1745570767.206 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0028.lzma.bak
2025-04-25T08:46:07.206Z,1745570767.206 [DataOverHttps](INFO): SBD MOMSN=24728422
2025-04-25T08:46:09.338Z,1745570769.338 [keepstation:NeedComms:D] Stopped
2025-04-25T08:46:09.339Z,1745570769.339 [keepstation:NeedComms:E] Running Loop=1
2025-04-25T08:46:11.323Z,1745570771.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,084604.00,A,3647.43836,N,12158.53619,W,0.622,147.95,250425,,,A*75
2025-04-25T08:46:11.325Z,1745570771.325 [NAL9602](INFO): GPS fix at 20250425T084604: (36.790639, -121.975603)
2025-04-25T08:46:11.368Z,1745570771.368 [keepstation:NeedComms:E] Stopped
2025-04-25T08:46:11.369Z,1745570771.369 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-25T08:46:11.369Z,1745570771.369 [keepstation:NeedComms] Stopped
2025-04-25T08:46:11.370Z,1745570771.370 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-25T08:46:11.370Z,1745570771.370 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-25T08:46:11.370Z,1745570771.370 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T08:46:11.768Z,1745570771.768 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:OnStation] Stopped
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:KeepStation] Running Loop=1
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-25T08:46:11.769Z,1745570771.769 [keepstation:KeepStation:B.Wait] Running Loop=1
2025-04-25T08:46:11.770Z,1745570771.770 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component.
2025-04-25T08:46:12.146Z,1745570772.146 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-25T08:46:43.753Z,1745570803.753 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-25T08:49:57.010Z,1745570997.010 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position -0.45 mm (4 active estimators).
2025-04-25T08:50:22.868Z,1745571022.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:50:54.016Z,1745571054.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:50:57.500Z,1745571057.500 [Radio_Surface](INFO): Powering down
2025-04-25T08:51:25.141Z,1745571085.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T08:51:26.144Z,1745571086.144 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-25T08:52:30.402Z,1745571150.402 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.744843
2025-04-25T08:55:38.005Z,1745571338.005 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-25T08:55:38.402Z,1745571338.402 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position -0.45 mm.
2025-04-25T08:56:03.853Z,1745571363.853 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T09:06:04.197Z,1745571964.197 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T09:16:04.534Z,1745572564.534 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T09:26:04.890Z,1745573164.890 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T09:35:48.846Z,1745573748.846 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-25T09:35:53.272Z,1745573753.272 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.901921
CHAN A1 (24V): 0.846678
CHAN A2 (12V): 0.927016
CHAN A3 (5V): 0.555028
CHAN B0 (3.3V): 0.373838
CHAN B1 (3.15aV): 0.484422
CHAN B2 (3.15bV): 0.484366
CHAN B3 (GND): 0.008103
OPEN: 0.006845
Full Scale: +/- 1 mA
2025-04-25T09:36:05.235Z,1745573765.235 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T09:46:05.579Z,1745574365.579 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T09:47:47.037Z,1745574467.037 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-04-25T09:52:49.178Z,1745574769.178 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-25T09:52:49.179Z,1745574769.179 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-04-25T09:52:49.180Z,1745574769.180 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-25T09:52:49.182Z,1745574769.182 [BPC1](INFO): Received data from all battery sticks.
2025-04-25T09:56:05.924Z,1745574965.924 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T10:06:06.277Z,1745575566.277 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T10:16:06.629Z,1745576166.629 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T10:26:06.979Z,1745576766.979 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T10:36:07.318Z,1745577367.318 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T10:46:07.663Z,1745577967.663 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T10:46:11.717Z,1745577971.717 [keepstation:SurfaceComms] Running Loop=1
2025-04-25T10:46:11.717Z,1745577971.717 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-25T10:46:11.718Z,1745577971.718 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-25T10:46:11.718Z,1745577971.718 [keepstation:SurfaceComms:A] Stopped
2025-04-25T10:46:11.718Z,1745577971.718 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-25T10:46:11.718Z,1745577971.718 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-25T10:46:11.720Z,1745577971.720 [keepstation:NeedComms] Running Loop=1
2025-04-25T10:46:11.720Z,1745577971.720 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-25T10:46:11.720Z,1745577971.720 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-25T10:46:11.720Z,1745577971.720 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-25T10:46:11.721Z,1745577971.721 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-25T10:46:11.721Z,1745577971.721 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-25T10:46:11.721Z,1745577971.721 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-25T10:46:11.722Z,1745577971.722 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-25T10:46:11.722Z,1745577971.722 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-25T10:46:11.722Z,1745577971.722 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-25T10:46:11.723Z,1745577971.723 [keepstation:NeedComms:A] Running Loop=1
2025-04-25T10:46:11.724Z,1745577971.724 [keepstation:NeedComms:A](INFO): last time_fix was:  1745570764.000000 second since 1970/01/01T00:00:00Z 
2025-04-25T10:46:11.724Z,1745577971.724 [keepstation:NeedComms:A] Stopped
2025-04-25T10:46:12.126Z,1745577972.126 [keepstation:KeepStation:Dive] Stopped
2025-04-25T10:46:12.126Z,1745577972.126 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-25T10:46:12.126Z,1745577972.126 [keepstation:KeepStation:Dive:A] Stopped
2025-04-25T10:46:12.126Z,1745577972.126 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-25T10:46:12.126Z,1745577972.126 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-25T10:46:12.127Z,1745577972.127 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-25T10:46:12.127Z,1745577972.127 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-25T10:46:12.127Z,1745577972.127 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-25T10:46:12.127Z,1745577972.127 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-25T10:46:12.127Z,1745577972.127 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-25T10:47:17.678Z,1745578037.678 [keepstation:NeedComms:C] Running Loop=1
2025-04-25T10:47:18.236Z,1745578038.236 [Radio_Surface](INFO): Powering up
2025-04-25T10:47:23.984Z,1745578043.984 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-25T10:47:37.050Z,1745578057.050 [NAL9602](INFO): SBD MO Status=1, MOMSN=20523, MT Status=0, MTMSN=0
2025-04-25T10:47:37.050Z,1745578057.050 [NAL9602](INFO): No messages in MT queue
2025-04-25T10:47:44.311Z,1745578064.311 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,104737.00,A,3647.11970,N,12158.18877,W,1.205,283.69,250425,,,D*7D
2025-04-25T10:47:44.314Z,1745578064.314 [NAL9602](INFO): GPS fix at 20250425T104737: (36.785328, -121.969813)
2025-04-25T10:47:44.326Z,1745578064.326 [UniversalFixResidualReporter](INFO): Fix residual: 221.2 %DT, over the last 601.4 m. Residual distance 1330.0 m at bearing -45.9 degrees. Fix at (36.7853, -121.9698) with 784.0 m made good.
2025-04-25T10:47:44.329Z,1745578064.329 [keepstation:NeedComms:C] Stopped
2025-04-25T10:47:44.329Z,1745578064.329 [keepstation:NeedComms:D] Running Loop=1
2025-04-25T10:47:44.368Z,1745578064.368 [CBIT](IMPORTANT): Navigation error has exceeded threshold.
2025-04-25T10:47:54.104Z,1745578074.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T10:48:18.067Z,1745578098.067 [NAL9602](INFO): SBD MO Status=2, MOMSN=20524, MT Status=2, MTMSN=0
2025-04-25T10:48:18.067Z,1745578098.067 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T10:48:25.232Z,1745578105.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T10:48:45.121Z,1745578125.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=20524, MT Status=2, MTMSN=0
2025-04-25T10:48:45.122Z,1745578125.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T10:48:56.361Z,1745578136.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T10:49:10.065Z,1745578150.065 [NAL9602](INFO): SBD MO Status=2, MOMSN=20524, MT Status=2, MTMSN=0
2025-04-25T10:49:10.065Z,1745578150.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T10:49:27.484Z,1745578167.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T10:49:35.977Z,1745578175.977 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003410
2025-04-25T10:49:38.355Z,1745578178.355 [NAL9602](INFO): SBD MO Status=1, MOMSN=20524, MT Status=0, MTMSN=0
2025-04-25T10:49:38.416Z,1745578178.416 [NAL9602](INFO): Sent 100 bytes from file Logs/20250425T055210/Courier0030.lzma
2025-04-25T10:49:38.416Z,1745578178.416 [NAL9602](INFO): Packets left to send: 0
2025-04-25T10:49:46.473Z,1745578186.473 [DataOverHttps](INFO): Sending 751 bytes from file Logs/20250425T055210/Express0031.lzma
2025-04-25T10:49:47.474Z,1745578187.474 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0031.lzma.bak
2025-04-25T10:49:47.474Z,1745578187.474 [DataOverHttps](INFO): SBD MOMSN=24729097
2025-04-25T10:49:49.705Z,1745578189.705 [keepstation:NeedComms:D] Stopped
2025-04-25T10:49:49.705Z,1745578189.705 [keepstation:NeedComms:E] Running Loop=1
2025-04-25T10:49:51.704Z,1745578191.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,104944.00,A,3647.11997,N,12158.18876,W,0.214,49.80,250425,,,D*4D
2025-04-25T10:49:51.706Z,1745578191.706 [NAL9602](INFO): GPS fix at 20250425T104944: (36.785333, -121.969813)
2025-04-25T10:49:51.734Z,1745578191.734 [keepstation:NeedComms:E] Stopped
2025-04-25T10:49:51.735Z,1745578191.735 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-25T10:49:51.735Z,1745578191.735 [keepstation:NeedComms] Stopped
2025-04-25T10:49:51.756Z,1745578191.756 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-25T10:49:51.756Z,1745578191.756 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-25T10:49:51.756Z,1745578191.756 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T10:49:52.145Z,1745578192.145 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-25T10:49:52.145Z,1745578192.145 [keepstation:SurfaceComms:B] Stopped
2025-04-25T10:49:52.145Z,1745578192.145 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-25T10:49:52.145Z,1745578192.145 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-25T10:49:52.145Z,1745578192.145 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-25T10:49:52.145Z,1745578192.145 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-25T10:49:52.547Z,1745578192.547 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-25T10:49:52.547Z,1745578192.547 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-25T10:49:52.547Z,1745578192.547 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-25T10:49:52.547Z,1745578192.547 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-25T10:49:52.547Z,1745578192.547 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-25T10:49:52.547Z,1745578192.547 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-25T10:49:52.956Z,1745578192.956 [keepstation:SurfaceComms:setStation] Stopped
2025-04-25T10:49:52.956Z,1745578192.956 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-25T10:49:52.956Z,1745578192.956 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-25T10:49:52.956Z,1745578192.956 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-25T10:49:52.956Z,1745578192.956 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-25T10:49:52.956Z,1745578192.956 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-25T10:49:53.372Z,1745578193.372 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-25T10:49:53.372Z,1745578193.372 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-25T10:49:53.372Z,1745578193.372 [keepstation:SurfaceComms:setSink] Stopped
2025-04-25T10:49:53.372Z,1745578193.372 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-25T10:49:53.373Z,1745578193.373 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-25T10:49:53.373Z,1745578193.373 [keepstation:SurfaceComms] Stopped
2025-04-25T10:49:53.373Z,1745578193.373 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-25T10:49:53.739Z,1745578193.739 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-25T10:49:53.744Z,1745578193.744 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-25T10:49:53.744Z,1745578193.744 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-25T10:49:53.744Z,1745578193.744 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-25T10:49:53.745Z,1745578193.745 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-25T10:49:53.746Z,1745578193.746 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-25T10:49:53.746Z,1745578193.746 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-25T10:49:53.746Z,1745578193.746 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-25T10:49:53.746Z,1745578193.746 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-25T10:50:24.019Z,1745578224.019 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-25T10:51:28.817Z,1745578288.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T10:51:59.940Z,1745578319.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T10:52:10.752Z,1745578330.752 [Radio_Surface](INFO): Powering down
2025-04-25T10:52:31.080Z,1745578351.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T10:52:32.084Z,1745578352.084 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-25T10:53:36.352Z,1745578416.352 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.771317
2025-04-25T10:54:16.730Z,1745578456.730 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-25T10:54:16.731Z,1745578456.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-25T10:54:16.731Z,1745578456.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T10:54:16.731Z,1745578456.731 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-25T10:54:17.147Z,1745578457.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-25T10:54:17.147Z,1745578457.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T10:54:17.147Z,1745578457.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-25T10:54:17.147Z,1745578457.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-25T10:54:17.147Z,1745578457.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T10:54:17.147Z,1745578457.147 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-25T10:54:17.537Z,1745578457.537 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-25T10:54:17.537Z,1745578457.537 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T10:54:17.537Z,1745578457.537 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-25T10:54:17.537Z,1745578457.537 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T10:54:17.538Z,1745578457.538 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T10:54:17.538Z,1745578457.538 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-25T10:54:17.538Z,1745578457.538 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T10:54:17.538Z,1745578457.538 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-25T10:54:17.538Z,1745578457.538 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-25T10:54:17.538Z,1745578457.538 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-25T10:54:17.538Z,1745578457.538 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-25T10:54:17.539Z,1745578457.539 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-25T10:54:17.943Z,1745578457.943 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-25T10:54:17.943Z,1745578457.943 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-25T10:54:17.943Z,1745578457.943 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-25T10:54:17.943Z,1745578457.943 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-25T10:54:17.943Z,1745578457.943 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-25T10:54:17.943Z,1745578457.943 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-25T10:54:17.944Z,1745578457.944 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-25T10:54:17.945Z,1745578457.945 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint
2025-04-25T11:00:28.021Z,1745578828.021 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-25T11:00:28.022Z,1745578828.022 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-04-25T11:00:28.023Z,1745578828.023 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-25T11:00:28.027Z,1745578828.027 [BPC1](INFO): Received data from all battery sticks.
2025-04-25T11:16:47.749Z,1745579807.749 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest
2025-04-25T11:16:48.146Z,1745579808.146 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-04-25T11:16:48.147Z,1745579808.147 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -0.45 mm.
2025-04-25T11:35:49.079Z,1745580949.079 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-25T11:35:53.509Z,1745580953.509 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.902138
CHAN A1 (24V): 0.846525
CHAN A2 (12V): 0.926750
CHAN A3 (5V): 0.551762
CHAN B0 (3.3V): 0.372099
CHAN B1 (3.15aV): 0.480950
CHAN B2 (3.15bV): 0.480778
CHAN B3 (GND): 0.006718
OPEN: 0.006645
Full Scale: +/- 1 mA
2025-04-25T12:08:05.826Z,1745582885.826 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-04-25T12:08:05.827Z,1745582885.827 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-04-25T12:08:05.828Z,1745582885.828 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-04-25T12:08:05.831Z,1745582885.831 [BPC1](INFO): Received data from all battery sticks.
2025-04-25T12:49:51.867Z,1745585391.867 [keepstation:SurfaceComms] Running Loop=1
2025-04-25T12:49:51.867Z,1745585391.867 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-04-25T12:49:51.868Z,1745585391.868 [keepstation:SurfaceComms:A] Running Loop=1
2025-04-25T12:49:51.868Z,1745585391.868 [keepstation:SurfaceComms:A] Stopped
2025-04-25T12:49:51.868Z,1745585391.868 [keepstation:SurfaceComms:B] Running Loop=1
2025-04-25T12:49:51.868Z,1745585391.868 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-04-25T12:49:51.870Z,1745585391.870 [keepstation:NeedComms] Running Loop=1
2025-04-25T12:49:51.870Z,1745585391.870 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-04-25T12:49:51.870Z,1745585391.870 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-04-25T12:49:51.870Z,1745585391.870 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-25T12:49:51.871Z,1745585391.871 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-04-25T12:49:51.871Z,1745585391.871 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-04-25T12:49:51.871Z,1745585391.871 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-04-25T12:49:51.872Z,1745585391.872 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-04-25T12:49:51.872Z,1745585391.872 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-25T12:49:51.873Z,1745585391.873 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-25T12:49:51.873Z,1745585391.873 [keepstation:NeedComms:A] Running Loop=1
2025-04-25T12:49:51.874Z,1745585391.874 [keepstation:NeedComms:A](INFO): last time_fix was:  1745578184.000000 second since 1970/01/01T00:00:00Z 
2025-04-25T12:49:51.874Z,1745585391.874 [keepstation:NeedComms:A] Stopped
2025-04-25T12:49:52.256Z,1745585392.256 [keepstation:KeepStation:Dive] Stopped
2025-04-25T12:49:52.256Z,1745585392.256 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive
2025-04-25T12:49:52.256Z,1745585392.256 [keepstation:KeepStation:Dive:A] Stopped
2025-04-25T12:49:52.256Z,1745585392.256 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A
2025-04-25T12:49:52.257Z,1745585392.257 [keepstation:DiveCmd:StationKeep] Stopped
2025-04-25T12:49:52.257Z,1745585392.257 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-04-25T12:49:52.257Z,1745585392.257 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-04-25T12:49:52.257Z,1745585392.257 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-04-25T12:49:52.257Z,1745585392.257 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-04-25T12:49:52.257Z,1745585392.257 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-04-25T12:51:08.676Z,1745585468.676 [keepstation:NeedComms:C] Running Loop=1
2025-04-25T12:51:09.196Z,1745585469.196 [Radio_Surface](INFO): Powering up
2025-04-25T12:51:14.516Z,1745585474.516 [DataOverHttps](INFO): Radio surface powered ON.
2025-04-25T12:51:28.095Z,1745585488.095 [NAL9602](INFO): SBD MO Status=0, MOMSN=20525, MT Status=0, MTMSN=0
2025-04-25T12:51:28.095Z,1745585488.095 [NAL9602](INFO): No messages in MT queue
2025-04-25T12:51:38.553Z,1745585498.553 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125131.00,A,3647.38554,N,12158.23418,W,0.428,0.00,250425,,,A*79
2025-04-25T12:51:38.556Z,1745585498.556 [NAL9602](INFO): GPS fix at 20250425T125131: (36.789759, -121.970570)
2025-04-25T12:51:38.592Z,1745585498.592 [UniversalFixResidualReporter](INFO): Fix residual: 74.1 %DT, over the last 1389.5 m. Residual distance 1029.9 m at bearing -64.8 degrees. Fix at (36.7898, -121.9706) with 496.8 m made good.
2025-04-25T12:51:38.595Z,1745585498.595 [keepstation:NeedComms:C] Stopped
2025-04-25T12:51:38.595Z,1745585498.595 [keepstation:NeedComms:D] Running Loop=1
2025-04-25T12:51:56.953Z,1745585516.953 [NAL9602](INFO): SBD MO Status=1, MOMSN=20526, MT Status=0, MTMSN=0
2025-04-25T12:51:57.004Z,1745585517.004 [NAL9602](INFO): Sent 98 bytes from file Logs/20250425T055210/Courier0033.lzma
2025-04-25T12:51:57.004Z,1745585517.004 [NAL9602](INFO): Packets left to send: 0
2025-04-25T12:52:17.537Z,1745585537.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=20527, MT Status=2, MTMSN=0
2025-04-25T12:52:17.537Z,1745585537.537 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T12:52:37.773Z,1745585557.773 [NAL9602](INFO): SBD MO Status=1, MOMSN=20527, MT Status=0, MTMSN=0
2025-04-25T12:52:37.820Z,1745585557.820 [NAL9602](INFO): Sent 332 bytes from file Logs/20250425T055210/Express0034.lzma
2025-04-25T12:52:37.820Z,1745585557.820 [NAL9602](INFO): Packets left to send: 2
2025-04-25T12:52:51.110Z,1745585571.110 [NAL9602](INFO): SBD MO Status=1, MOMSN=20528, MT Status=0, MTMSN=0
2025-04-25T12:52:51.164Z,1745585571.164 [NAL9602](INFO): Sent 332 bytes from file Logs/20250425T055210/Express0034.lzma
2025-04-25T12:52:51.164Z,1745585571.164 [NAL9602](INFO): Packets left to send: 1
2025-04-25T12:53:08.573Z,1745585588.573 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003407
2025-04-25T12:53:09.431Z,1745585589.431 [NAL9602](INFO): SBD MO Status=2, MOMSN=20529, MT Status=2, MTMSN=0
2025-04-25T12:53:09.432Z,1745585589.432 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-25T12:53:17.073Z,1745585597.073 [DataOverHttps](INFO): Sending 805 bytes from file Logs/20250425T055210/Express0034.lzma
2025-04-25T12:53:18.074Z,1745585598.074 [DataOverHttps](INFO): Moved sent file to Logs/20250425T055210/Express0034.lzma.bak
2025-04-25T12:53:18.074Z,1745585598.074 [DataOverHttps](INFO): SBD MOMSN=24729683
2025-04-25T12:53:20.380Z,1745585600.380 [keepstation:NeedComms:D] Stopped
2025-04-25T12:53:20.381Z,1745585600.381 [keepstation:NeedComms:E] Running Loop=1
2025-04-25T12:53:22.381Z,1745585602.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,125315.00,A,3647.38891,N,12158.23304,W,0.797,69.62,250425,,,D*4A
2025-04-25T12:53:22.388Z,1745585602.388 [NAL9602](INFO): GPS fix at 20250425T125315: (36.789815, -121.970551)
2025-04-25T12:53:22.431Z,1745585602.431 [keepstation:NeedComms:E] Stopped
2025-04-25T12:53:22.437Z,1745585602.437 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-04-25T12:53:22.437Z,1745585602.437 [keepstation:NeedComms] Stopped
2025-04-25T12:53:22.437Z,1745585602.437 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-04-25T12:53:22.437Z,1745585602.437 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-04-25T12:53:22.437Z,1745585602.437 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-25T12:53:22.827Z,1745585602.827 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-04-25T12:53:22.827Z,1745585602.827 [keepstation:SurfaceComms:B] Stopped
2025-04-25T12:53:22.827Z,1745585602.827 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-04-25T12:53:22.827Z,1745585602.827 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-04-25T12:53:22.827Z,1745585602.827 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-04-25T12:53:22.827Z,1745585602.827 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-04-25T12:53:23.249Z,1745585603.249 [keepstation:SurfaceComms:setTransit] Stopped
2025-04-25T12:53:23.249Z,1745585603.249 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-04-25T12:53:23.249Z,1745585603.249 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-04-25T12:53:23.249Z,1745585603.249 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-04-25T12:53:23.249Z,1745585603.249 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-04-25T12:53:23.249Z,1745585603.249 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-04-25T12:53:23.614Z,1745585603.614 [keepstation:SurfaceComms:setStation] Stopped
2025-04-25T12:53:23.614Z,1745585603.614 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-04-25T12:53:23.614Z,1745585603.614 [keepstation:SurfaceComms:setStation:A] Stopped
2025-04-25T12:53:23.614Z,1745585603.614 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-04-25T12:53:23.614Z,1745585603.614 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-04-25T12:53:23.615Z,1745585603.615 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-04-25T12:53:24.009Z,1745585604.009 [keepstation:SurfaceComms:setSink:A] Stopped
2025-04-25T12:53:24.009Z,1745585604.009 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink
2025-04-25T12:53:24.009Z,1745585604.009 [keepstation:SurfaceComms:setSink] Stopped
2025-04-25T12:53:24.009Z,1745585604.009 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-04-25T12:53:24.009Z,1745585604.009 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-04-25T12:53:24.009Z,1745585604.009 [keepstation:SurfaceComms] Stopped
2025-04-25T12:53:24.009Z,1745585604.009 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-04-25T12:53:24.494Z,1745585604.494 [keepstation:KeepStation:Dive] Running Loop=1
2025-04-25T12:53:24.494Z,1745585604.494 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive
2025-04-25T12:53:24.494Z,1745585604.494 [keepstation:KeepStation:Dive:A] Running Loop=1
2025-04-25T12:53:24.495Z,1745585604.495 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A
2025-04-25T12:53:24.495Z,1745585604.495 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-04-25T12:53:24.495Z,1745585604.495 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-04-25T12:53:24.495Z,1745585604.495 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-04-25T12:53:24.495Z,1745585604.495 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T12:53:24.495Z,1745585604.495 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-04-25T12:53:24.504Z,1745585604.504 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-04-25T12:53:24.504Z,1745585604.504 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-04-25T12:53:24.504Z,1745585604.504 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-04-25T12:53:24.504Z,1745585604.504 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-04-25T12:53:24.505Z,1745585604.505 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to  10.000000 m 
2025-04-25T12:53:24.505Z,1745585604.505 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-04-25T12:53:24.505Z,1745585604.505 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1
2025-04-25T12:53:24.505Z,1745585604.505 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize.
2025-04-25T12:53:55.080Z,1745585635.080 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-25T12:54:59.532Z,1745585699.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T12:55:30.656Z,1745585730.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T12:55:41.092Z,1745585741.092 [Radio_Surface](INFO): Powering down
2025-04-25T12:56:01.780Z,1745585761.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-25T12:56:02.784Z,1745585762.784 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-04-25T12:56:12.059Z,1745585772.059 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-04-25T12:57:07.052Z,1745585827.052 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.767365
2025-04-25T12:57:08.218Z,1745585828.218 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped
2025-04-25T12:57:08.219Z,1745585828.219 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1
2025-04-25T12:57:08.219Z,1745585828.219 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T12:57:08.219Z,1745585828.219 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1
2025-04-25T12:57:08.625Z,1745585828.625 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped
2025-04-25T12:57:08.625Z,1745585828.625 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit
2025-04-25T12:57:08.625Z,1745585828.625 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped
2025-04-25T12:57:08.625Z,1745585828.625 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1
2025-04-25T12:57:08.625Z,1745585828.625 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T12:57:08.625Z,1745585828.625 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1
2025-04-25T12:57:09.038Z,1745585829.038 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped
2025-04-25T12:57:09.038Z,1745585829.038 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T12:57:09.039Z,1745585829.039 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped
2025-04-25T12:57:09.039Z,1745585829.039 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation
2025-04-25T12:57:09.039Z,1745585829.039 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T12:57:09.039Z,1745585829.039 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-04-25T12:57:09.039Z,1745585829.039 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-04-25T12:57:09.039Z,1745585829.039 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-04-25T12:57:09.040Z,1745585829.040 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-04-25T12:57:09.040Z,1745585829.040 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-04-25T12:57:09.040Z,1745585829.040 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-04-25T12:57:09.040Z,1745585829.040 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink
2025-04-25T12:57:09.431Z,1745585829.431 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-04-25T12:57:09.431Z,1745585829.431 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-04-25T12:57:09.432Z,1745585829.432 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-04-25T12:57:09.432Z,1745585829.432 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-04-25T12:57:09.432Z,1745585829.432 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-04-25T12:57:09.432Z,1745585829.432 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-04-25T12:57:09.432Z,1745585829.432 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-04-25T12:57:09.433Z,1745585829.433 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint