2025-05-23T18:44:40.512Z,1748025880.512 [CommandExec](IMPORTANT): got command restart logs
2025-05-23T18:44:41.869Z,1748025881.869 [DataOverHttps](INFO): Moved sent file to Logs/20250523T181937/Express0016.lzma.bak
2025-05-23T18:44:41.870Z,1748025881.870 [DataOverHttps](INFO): SBD MOMSN=24967691
2025-05-23T18:45:00.722Z,1748025900.722 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250523T181937/Courier0018.lzma
2025-05-23T18:45:01.724Z,1748025901.724 [DataOverHttps](INFO): Moved sent file to Logs/20250523T181937/Courier0018.lzma.bak
2025-05-23T18:45:01.725Z,1748025901.725 [DataOverHttps](INFO): SBD MOMSN=24967707
2025-05-23T18:45:02.652Z,1748025902.652 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-23T18:45:17.522Z,1748025917.522 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20250523T181937/Express0019.lzma
2025-05-23T18:45:18.524Z,1748025918.524 [DataOverHttps](INFO): Moved sent file to Logs/20250523T181937/Express0019.lzma.bak
2025-05-23T18:45:18.525Z,1748025918.525 [DataOverHttps](INFO): SBD MOMSN=24967709
2025-05-23T18:45:20.035Z,1748025920.035 [Default:CheckIn:Read_Iridium] Stopped
2025-05-23T18:45:20.036Z,1748025920.036 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-23T18:45:20.036Z,1748025920.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-23T18:49:36.545Z,1748026176.545 [DataOverHttps](IMPORTANT): SBD MTMSN=20250523T184935
2025-05-23T18:49:43.899Z,1748026183.899 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 1 h;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.8078 degree;set keepstation.Longitude -121.80577 degree;set keepstation.Depth 7 m" 1muxv 1 2
2025-05-23T18:49:43.923Z,1748026183.923 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 1 h;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.8078 degree;set keepstation.Longitude -121.80577 degree;set keepstation.Depth 7 m" 1muxv 1 2.000000
2025-05-23T18:49:43.924Z,1748026183.924 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1muxv
2025-05-23T18:49:43.925Z,1748026183.925 [CommandExec](IMPORTANT): Scheduled #3 (#1 of 2 with id='1muxv'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 1 h;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.8078 degree;set keepstation.Longitude -121.80577 degree;set keepstation.Depth 7 m", ASAP
2025-05-23T18:49:44.900Z,1748026184.900 [DataOverHttps](IMPORTANT): SBD MTMSN=20250523T184943
2025-05-23T18:49:52.343Z,1748026192.343 [DataOverHttps](INFO): Received command: sched asap "set keepstation.ApproachDepth 7 m;set keepstation.Speed 1 m/s;set keepstation.MaxDepth 20 m;set keepstation.MinOffshore 0.5 km;run" 1muxv 2 2
2025-05-23T18:49:52.367Z,1748026192.367 [CommandExec](IMPORTANT): got command schedule asap "set keepstation.ApproachDepth 7 m;set keepstation.Speed 1 m/s;set keepstation.MaxDepth 20 m;set keepstation.MinOffshore 0.5 km;run" 1muxv 2 2.000000
2025-05-23T18:49:52.367Z,1748026192.367 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1muxv
2025-05-23T18:49:52.368Z,1748026192.368 [CommandExec](IMPORTANT): Scheduled #4 (#2 of 2 with id='1muxv'): "set keepstation.ApproachDepth 7 m;set keepstation.Speed 1 m/s;set keepstation.MaxDepth 20 m;set keepstation.MinOffshore 0.5 km;run", ASAP
2025-05-23T18:49:52.488Z,1748026192.488 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-05-23T18:49:52.489Z,1748026192.489 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-05-23T18:49:52.489Z,1748026192.489 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-05-23T18:49:52.493Z,1748026192.493 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-05-23T18:49:53.669Z,1748026193.669 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="keepstation">
    <DefineArg Name="MissionTimeout">
        <Units:hour />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="NeedCommsTime">
        <Units:minute />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="Latitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Longitude">
        <Units:degree />
        <Value>NaN</Value>
    </DefineArg>
    <DefineArg Name="Depth">
        <Units:meter />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="ApproachDepth">
        <Units:meter />
        <Value>15</Value>
    </DefineArg>
    <DefineArg Name="DepthDeadband">
        <Units:meter />
        <Value>4</Value>
    </DefineArg>
    <DefineArg Name="Speed">
        <Units:meter_per_second />
        <Value>0.75</Value>
    </DefineArg>
    <DefineArg Name="Radius">
        <Units:meter />
        <Value>200</Value>
    </DefineArg>
    <DefineArg Name="SinkOffSurface">
        <False />
    </DefineArg>
    <DefineArg Name="SinkDepth">
        <Units:meter />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="SinkDepthTimeout">
        <Units:minute />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <DefineOutput Name="DiveMode">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="DoingComms">
        <Units:count />
        <Value>0</Value>
    </DefineOutput>
    <DefineOutput Name="SurfaceSink">
        <Units:count />
        <Value>1</Value>
    </DefineOutput>
    <DefineOutput Name="TransitToStation">
        <Units:count />
        <Value>2</Value>
    </DefineOutput>
    <DefineOutput Name="StationKeep">
        <Units:count />
        <Value>3</Value>
    </DefineOutput>
    <DefineOutput Name="OnStation">
        <False />
    </DefineOutput>
    <Timeout Duration="MissionTimeout" />
    <Insert Id="NeedComms" Filename="Insert/NeedComms.tl" />
    <Assign>
        <Sequence />
        <Arg Name="NeedComms:DiveInterval" />
        <Arg Name="NeedCommsTime" />
    </Assign>
    <Insert Filename="Insert/StandardEnvelopes.tl" />
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MaxDepth" />
        <Arg Name="MaxDepth" />
    </Assign>
    <Assign>
        <Sequence />
        <Arg Name="StandardEnvelopes:MinOffshore" />
        <Arg Name="MinOffshore" />
    </Assign>
    <Insert Filename="Insert/BackseatDriver.tl" />
    <Insert Filename="Insert/PowerOnly.tl" />
    <Guidance:Pitch>
        <Parallel />
        <Setting>
            <Guidance:Pitch.massPosition />
            <Control:VerticalControl.massDefault />
        </Setting>
    </Guidance:Pitch>
    <Aggregate Id="SurfaceComms">
        <When>
            <Called />
            <Or>
                <Elapsed>
                    <Universal:time_fix />
                </Elapsed>
                <Gt>
                    <Arg Name="NeedCommsTime" />
                </Gt>
            </Or>
        </When>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="DoingComms" />
        </Assign>
        <Call RefId="NeedComms" PriorityHere="false" />
        <Aggregate Id="setTransit">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Arg Name="OnStation" />
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="TransitToStation" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setStation">
            <Sequence />
            <Break>
                <Arg Name="SinkOffSurface" />
                <Or>
                    <Not>
                        <Arg Name="OnStation" />
                    </Not>
                </Or>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="StationKeep" />
            </Assign>
        </Aggregate>
        <Aggregate Id="setSink">
            <Sequence />
            <Break>
                <Not>
                    <Arg Name="SinkOffSurface" />
                </Not>
            </Break>
            <Assign>
                <Sequence />
                <Arg Name="DiveMode" />
                <Arg Name="SurfaceSink" />
            </Assign>
        </Aggregate>
    </Aggregate>
    <Call RefId="SurfaceComms" Id="StartingMission" PriorityHere="false" />
    <Aggregate Id="DiveCmd">
        <When>
            <Called />
        </When>
        <Aggregate Id="SurfaceSink">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="SurfaceSink" />
                </Eq>
            </While>
            <Aggregate Id="sink">
                <Sequence />
                <Guidance:Buoyancy Id="BuoyancyHold">
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Units:cubic_centimeter />
                        <Value>NaN</Value>
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Units:meter_per_second />
                        <Value>0</Value>
                    </Setting>
                </Guidance:SetSpeed>
                <Syslog Severity="Info">
                    Sinking to 
                    <Arg Name="SinkDepth" />
                    <Units:meter />
                </Syslog>
                <Guidance:Pitch>
                    <Sequence />
                    <Timeout Duration="SinkDepthTimeout">
                        <Syslog Severity="Important">
                            Timed out while trying to reach target sink depth at current depth of 
                            <Universal:depth />
                            <Units:meter />
                        </Syslog>
                    </Timeout>
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="SinkDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Aggregate Id="sinkToTransit">
                    <Sequence />
                    <Break>
                        <Arg Name="OnStation" />
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="TransitToStation" />
                    </Assign>
                </Aggregate>
                <Aggregate Id="sinkToStation">
                    <Sequence />
                    <Break>
                        <Not>
                            <Arg Name="OnStation" />
                        </Not>
                    </Break>
                    <Assign>
                        <Sequence />
                        <Arg Name="DiveMode" />
                        <Arg Name="StationKeep" />
                    </Assign>
                </Aggregate>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="TransitToStation">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="TransitToStation" />
                </Eq>
            </While>
            <Aggregate Id="dive">
                <Sequence />
                <Guidance:Buoyancy>
                    <Parallel />
                    <Setting>
                        <Guidance:Buoyancy.position />
                        <Control:VerticalControl.buoyancyNeutral />
                    </Setting>
                </Guidance:Buoyancy>
                <Guidance:Pitch>
                    <Parallel />
                    <Setting>
                        <Guidance:Pitch.depth />
                        <Arg Name="ApproachDepth" />
                    </Setting>
                </Guidance:Pitch>
                <Guidance:SetSpeed>
                    <Parallel />
                    <Setting>
                        <Guidance:SetSpeed.speed />
                        <Arg Name="Speed" />
                    </Setting>
                </Guidance:SetSpeed>
            </Aggregate>
        </Aggregate>
        <Aggregate Id="StationKeep">
            <While>
                <Arg Name="DiveMode" />
                <Eq>
                    <Arg Name="StationKeep" />
                </Eq>
            </While>
            <Assign>
                <Parallel />
                <Control:VerticalControl.depthDeadband />
                <Arg Name="DepthDeadband" />
            </Assign>
            <Guidance:Pitch>
                <Parallel />
                <Setting>
                    <Guidance:Pitch.depth />
                    <Arg Name="Depth" />
                </Setting>
            </Guidance:Pitch>
            <Guidance:KeepStation>
                <Parallel />
                <Setting>
                    <Guidance:KeepStation.latitude />
                    <Arg Name="Latitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.longitude />
                    <Arg Name="Longitude" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.radius />
                    <Arg Name="Radius" />
                </Setting>
                <Setting>
                    <Guidance:KeepStation.speed />
                    <Arg Name="Speed" />
                </Setting>
            </Guidance:KeepStation>
        </Aggregate>
    </Aggregate>
    <Aggregate Id="TransitToStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" PriorityHere="false" />
        </Aggregate>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
        <Assign>
            <Sequence />
            <Arg Name="DiveMode" />
            <Arg Name="StationKeep" />
        </Assign>
        <Assign>
            <Sequence />
            <Arg Name="OnStation" />
            <True />
        </Assign>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" PriorityHere="false" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <Aggregate Id="Dive">
            <While>
                <Not>
                    <Arg Name="DiveMode" />
                    <Eq>
                        <Arg Name="DoingComms" />
                    </Eq>
                </Not>
            </While>
            <Call RefId="DiveCmd" PriorityHere="false" />
        </Aggregate>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-05-23T18:49:53.682Z,1748026193.682 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-05-23T18:49:53.685Z,1748026193.685 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-05-23T18:49:53.688Z,1748026193.688 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-05-23T18:49:53.692Z,1748026193.692 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-05-23T18:49:53.695Z,1748026193.695 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-05-23T18:49:53.698Z,1748026193.698 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-05-23T18:49:53.721Z,1748026193.721 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-05-23T18:49:53.738Z,1748026193.738 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-05-23T18:49:53.745Z,1748026193.745 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-05-23T18:49:53.749Z,1748026193.749 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool
2025-05-23T18:49:53.767Z,1748026193.767 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m
2025-05-23T18:49:53.770Z,1748026193.770 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min
2025-05-23T18:49:53.778Z,1748026193.778 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-05-23T18:49:53.781Z,1748026193.781 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-05-23T18:49:53.793Z,1748026193.793 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count
2025-05-23T18:49:53.797Z,1748026193.797 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count
2025-05-23T18:49:53.812Z,1748026193.812 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count
2025-05-23T18:49:53.816Z,1748026193.816 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count
2025-05-23T18:49:53.832Z,1748026193.832 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count
2025-05-23T18:49:53.837Z,1748026193.837 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool
2025-05-23T18:49:53.837Z,1748026193.837 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-05-23T18:49:53.837Z,1748026193.837 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-05-23T18:49:53.851Z,1748026193.851 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-05-23T18:49:54.218Z,1748026194.218 [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-05-23T18:49:54.224Z,1748026194.224 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-05-23T18:49:54.227Z,1748026194.227 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-05-23T18:49:54.231Z,1748026194.231 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-05-23T18:49:54.234Z,1748026194.234 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-05-23T18:49:54.237Z,1748026194.237 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-05-23T18:49:54.264Z,1748026194.264 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-05-23T18:49:54.271Z,1748026194.271 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-05-23T18:49:54.279Z,1748026194.279 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-05-23T18:49:54.290Z,1748026194.290 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-23T18:49:54.379Z,1748026194.379 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-05-23T18:49:54.379Z,1748026194.379 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-05-23T18:49:54.380Z,1748026194.380 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-05-23T18:49:54.528Z,1748026194.528 [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-05-23T18:49:54.543Z,1748026194.543 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-05-23T18:49:54.546Z,1748026194.546 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-05-23T18:49:54.553Z,1748026194.553 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-05-23T18:49:54.558Z,1748026194.558 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-05-23T18:49:54.568Z,1748026194.568 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-05-23T18:49:54.584Z,1748026194.584 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-05-23T18:49:54.607Z,1748026194.607 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-05-23T18:49:54.630Z,1748026194.630 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-05-23T18:49:54.630Z,1748026194.630 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-05-23T18:49:54.636Z,1748026194.636 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-05-23T18:49:54.675Z,1748026194.675 [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-05-23T18:49:54.683Z,1748026194.683 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-05-23T18:49:54.685Z,1748026194.685 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-05-23T18:49:54.689Z,1748026194.689 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-05-23T18:49:54.689Z,1748026194.689 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-05-23T18:49:54.699Z,1748026194.699 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-05-23T18:49:54.968Z,1748026194.968 [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-05-23T18:49:54.973Z,1748026194.973 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-05-23T18:49:54.975Z,1748026194.975 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-05-23T18:49:54.978Z,1748026194.978 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-05-23T18:49:54.981Z,1748026194.981 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-05-23T18:49:54.985Z,1748026194.985 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-05-23T18:49:54.992Z,1748026194.992 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-05-23T18:49:54.994Z,1748026194.994 [keepstation:H.Pitch](DEBUG): Construct.
2025-05-23T18:49:55.065Z,1748026195.065 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-05-23T18:49:55.069Z,1748026195.069 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct.
2025-05-23T18:49:55.086Z,1748026195.086 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct.
2025-05-23T18:49:55.131Z,1748026195.131 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-05-23T18:49:55.134Z,1748026195.134 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct.
2025-05-23T18:49:55.147Z,1748026195.147 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct.
2025-05-23T18:49:55.190Z,1748026195.190 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct.
2025-05-23T18:49:55.212Z,1748026195.212 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation.
2025-05-23T18:49:55.237Z,1748026195.237 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-05-23T18:49:55.273Z,1748026195.273 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait.
2025-05-23T18:49:55.313Z,1748026195.313 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

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

  timeout duration=MissionTimeout

  insert id="NeedComms" Insert/NeedComms.tl

  assign in sequence NeedComms:DiveInterval = NeedCommsTime

  insert Insert/StandardEnvelopes.tl

  assign in sequence StandardEnvelopes:MaxDepth = MaxDepth

  assign in sequence StandardEnvelopes:MinOffshore = MinOffshore

  insert Insert/BackseatDriver.tl

  insert Insert/PowerOnly.tl

  behavior Guidance:Pitch {
    run in parallel

    set massPosition = Control:VerticalControl.massDefault
  }

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

    assign in sequence DiveMode = DoingComms

    call priorityHere=false refId="NeedComms"

    aggregate setTransit {
      run in sequence

      break if (
        SinkOffSurface
        or OnStation
      )

      assign in sequence DiveMode = TransitToStation
    }

    aggregate setStation {
      run in sequence

      break if (
        SinkOffSurface
        or not OnStation
      )

      assign in sequence DiveMode = StationKeep
    }

    aggregate setSink {
      run in sequence

      break if ( not SinkOffSurface )

      assign in sequence DiveMode = SurfaceSink
    }
  }

  call id="StartingMission" priorityHere=false refId="SurfaceComms"

  aggregate DiveCmd {
    run when ( called )

    aggregate SurfaceSink {
      run while ( DiveMode == SurfaceSink )

      aggregate sink {
        run in sequence

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

          set position = NaN cc
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = 0 m/s
        }

        syslog info "Sinking to " + SinkDepth~meter

        behavior Guidance:Pitch {
          run in sequence

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

          set depth = SinkDepth
        }

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

          break if ( OnStation )

          assign in sequence DiveMode = TransitToStation
        }

        aggregate sinkToStation {
          run in sequence

          break if ( not OnStation )

          assign in sequence DiveMode = StationKeep
        }
      }
    }

    aggregate TransitToStation {
      run while ( DiveMode == TransitToStation )

      aggregate dive {
        run in sequence

        behavior Guidance:Buoyancy {
          run in parallel

          set position = Control:VerticalControl.buoyancyNeutral
        }

        behavior Guidance:Pitch {
          run in parallel

          set depth = ApproachDepth
        }

        behavior Guidance:SetSpeed {
          run in parallel

          set speed = Speed
        }
      }
    }

    aggregate StationKeep {
      run while ( DiveMode == StationKeep )

      assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband

      behavior Guidance:Pitch {
        run in parallel

        set depth = Depth
      }

      behavior Guidance:KeepStation {
        run in parallel

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

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

    run in sequence

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

      call priorityHere=false refId="DiveCmd"
    }

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

      set latitude = Latitude
      set longitude = Longitude
    }

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

  call id="OnStation" priorityHere=false refId="NeedComms"

  aggregate KeepStation {
    run in sequence

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

      call priorityHere=false refId="DiveCmd"
    }

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

      run in sequence

      set duration = MissionTimeout
    }
  }
}


2025-05-23T18:49:55.313Z,1748026195.313 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-05-23T18:50:11.988Z,1748026211.988 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 1 hour
2025-05-23T18:50:11.989Z,1748026211.989 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 20 minute
2025-05-23T18:50:11.990Z,1748026211.990 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.8078 degree
2025-05-23T18:50:11.990Z,1748026211.990 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.80577 degree
2025-05-23T18:50:11.991Z,1748026211.991 [CommandExec](IMPORTANT): got command set keepstation.Depth 7 meter
2025-05-23T18:50:20.683Z,1748026220.683 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-23T18:50:20.683Z,1748026220.683 [Default:CheckIn:C.Wait] Stopped
2025-05-23T18:50:20.683Z,1748026220.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-23T18:50:20.683Z,1748026220.683 [Default:CheckIn:D] Running Loop=1
2025-05-23T18:50:21.153Z,1748026221.153 [Default:CheckIn:D] Stopped
2025-05-23T18:50:21.153Z,1748026221.153 [Default:CheckIn:E] Running Loop=1
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  9.400446 min 
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn:E] Stopped
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn] Stopped
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn](INFO): Running loop #2
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn] Running Loop=2
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-23T18:50:21.512Z,1748026221.512 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-23T18:50:23.514Z,1748026223.514 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185022.00,A,3647.97551,N,12150.40330,W,0.486,344.66,230525,,,D*75
2025-05-23T18:50:23.539Z,1748026223.539 [NAL9602](INFO): GPS fix at 20250523T185022: (36.799592, -121.840055)
2025-05-23T18:50:23.565Z,1748026223.565 [Default:CheckIn:Read_GPS] Stopped
2025-05-23T18:50:23.565Z,1748026223.565 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-23T18:50:31.426Z,1748026231.426 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250523T184440/Courier0003.lzma
2025-05-23T18:50:31.864Z,1748026231.864 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Courier0003.lzma.bak
2025-05-23T18:50:31.865Z,1748026231.865 [DataOverHttps](INFO): SBD MOMSN=24967727
2025-05-23T18:50:33.123Z,1748026233.123 [CommandExec](IMPORTANT): got command set keepstation.ApproachDepth 7 meter
2025-05-23T18:50:33.124Z,1748026233.124 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second
2025-05-23T18:50:33.125Z,1748026233.125 [CommandExec](IMPORTANT): got command set keepstation.MaxDepth 20 meter
2025-05-23T18:50:33.126Z,1748026233.126 [CommandExec](IMPORTANT): got command set keepstation.MinOffshore 0.5 kilometer
2025-05-23T18:50:33.126Z,1748026233.126 [CommandExec](IMPORTANT): got command run
2025-05-23T18:50:33.188Z,1748026233.188 [CommandExec](IMPORTANT): Running 
2025-05-23T18:50:33.197Z,1748026233.197 [Default] Stopped
2025-05-23T18:50:33.197Z,1748026233.197 [Default](DEBUG): Aggregate::uninitialize Default
2025-05-23T18:50:33.197Z,1748026233.197 [Default:B.GoToSurface] Stopped
2025-05-23T18:50:33.197Z,1748026233.197 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-23T18:50:33.198Z,1748026233.198 [Default:CheckIn] Stopped
2025-05-23T18:50:33.198Z,1748026233.198 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-23T18:50:33.198Z,1748026233.198 [Default:CheckIn:Read_Iridium] Stopped
2025-05-23T18:50:33.198Z,1748026233.198 [MissionManager](IMPORTANT): Started mission keepstation
2025-05-23T18:50:33.198Z,1748026233.198 [keepstation] Running Loop=1
2025-05-23T18:50:33.198Z,1748026233.198 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes] Running Loop=1
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-05-23T18:50:33.203Z,1748026233.203 [keepstation:BackseatDriver] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly:A] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly:B] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly:C] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly:D] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-05-23T18:50:33.204Z,1748026233.204 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-05-23T18:50:33.205Z,1748026233.205 [keepstation:H.Pitch] Running Loop=1
2025-05-23T18:50:33.205Z,1748026233.205 [keepstation:H.Pitch](DEBUG): Initialize.
2025-05-23T18:50:33.205Z,1748026233.205 [keepstation:B] Running Loop=1
2025-05-23T18:50:33.206Z,1748026233.206 [keepstation:H.Pitch] Running Loop=1
2025-05-23T18:50:33.206Z,1748026233.206 [keepstation:PowerOnly] Running Loop=1
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:PowerOnly:D] Stopped
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:PowerOnly:C] Stopped
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:PowerOnly:B] Stopped
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:PowerOnly:A] Stopped
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:BackseatDriver] Running Loop=1
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:StandardEnvelopes] Running Loop=1
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-05-23T18:50:33.211Z,1748026233.211 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-05-23T18:50:33.212Z,1748026233.212 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-05-23T18:50:33.212Z,1748026233.212 [keepstation:B] Stopped
2025-05-23T18:50:33.212Z,1748026233.212 [keepstation:D] Running Loop=1
2025-05-23T18:50:33.583Z,1748026233.583 [keepstation:D] Stopped
2025-05-23T18:50:33.583Z,1748026233.583 [keepstation:E] Running Loop=1
2025-05-23T18:50:33.987Z,1748026233.987 [keepstation:E] Stopped
2025-05-23T18:50:33.988Z,1748026233.988 [keepstation:StartingMission] Running Loop=1
2025-05-23T18:50:33.988Z,1748026233.988 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-05-23T18:50:34.400Z,1748026234.400 [keepstation:SurfaceComms] Running Loop=1
2025-05-23T18:50:34.400Z,1748026234.400 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-05-23T18:50:34.400Z,1748026234.400 [keepstation:SurfaceComms:A] Running Loop=1
2025-05-23T18:50:34.400Z,1748026234.400 [keepstation:SurfaceComms:A] Stopped
2025-05-23T18:50:34.400Z,1748026234.400 [keepstation:SurfaceComms:B] Running Loop=1
2025-05-23T18:50:34.401Z,1748026234.401 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-05-23T18:50:34.792Z,1748026234.792 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-05-23T18:50:34.792Z,1748026234.792 [keepstation:NeedComms] Running Loop=1
2025-05-23T18:50:34.792Z,1748026234.792 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-05-23T18:50:34.792Z,1748026234.792 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-05-23T18:50:34.792Z,1748026234.792 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-23T18:50:34.792Z,1748026234.792 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-05-23T18:50:34.793Z,1748026234.793 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-05-23T18:50:34.793Z,1748026234.793 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-05-23T18:50:34.793Z,1748026234.793 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-05-23T18:50:34.794Z,1748026234.794 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-23T18:50:34.794Z,1748026234.794 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-23T18:50:34.794Z,1748026234.794 [keepstation:NeedComms:A] Running Loop=1
2025-05-23T18:50:34.796Z,1748026234.796 [keepstation:NeedComms:A](INFO): last time_fix was:  1748026222.000000 second since 1970/01/01T00:00:00Z 
2025-05-23T18:50:34.796Z,1748026234.796 [keepstation:NeedComms:A] Stopped
2025-05-23T18:50:35.209Z,1748026235.209 [keepstation:NeedComms:C] Running Loop=1
2025-05-23T18:50:35.618Z,1748026235.618 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-23T18:50:37.209Z,1748026237.209 [NAL9602](INFO): SBD MO Status=0, MOMSN=9450, MT Status=0, MTMSN=0
2025-05-23T18:50:37.209Z,1748026237.209 [NAL9602](INFO): No messages in MT queue
2025-05-23T18:50:38.421Z,1748026238.421 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185037.00,A,3647.97550,N,12150.40416,W,0.583,313.10,230525,,,D*74
2025-05-23T18:50:38.424Z,1748026238.424 [NAL9602](INFO): GPS fix at 20250523T185037: (36.799592, -121.840069)
2025-05-23T18:50:38.441Z,1748026238.441 [keepstation:NeedComms:C] Stopped
2025-05-23T18:50:38.441Z,1748026238.441 [keepstation:NeedComms:D] Running Loop=1
2025-05-23T18:50:38.857Z,1748026238.857 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-23T18:50:47.994Z,1748026247.994 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250523T184440/Courier0006.lzma
2025-05-23T18:50:48.997Z,1748026248.997 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Courier0006.lzma.bak
2025-05-23T18:50:48.998Z,1748026248.998 [DataOverHttps](INFO): SBD MOMSN=24967731
2025-05-23T18:51:04.958Z,1748026264.958 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250523T184440/Courier0009.lzma
2025-05-23T18:51:05.960Z,1748026265.960 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Courier0009.lzma.bak
2025-05-23T18:51:05.960Z,1748026265.960 [DataOverHttps](INFO): SBD MOMSN=24967735
2025-05-23T18:51:10.340Z,1748026270.340 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-23T18:51:21.792Z,1748026281.792 [DataOverHttps](INFO): Sending 905 bytes from file Logs/20250523T184440/Express0004.lzma
2025-05-23T18:51:22.792Z,1748026282.792 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0004.lzma.bak
2025-05-23T18:51:22.793Z,1748026282.793 [DataOverHttps](INFO): SBD MOMSN=24967744
2025-05-23T18:51:38.654Z,1748026298.654 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250523T184440/Express0007.lzma
2025-05-23T18:51:39.657Z,1748026299.657 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0007.lzma.bak
2025-05-23T18:51:39.657Z,1748026299.657 [DataOverHttps](INFO): SBD MOMSN=24967810
2025-05-23T18:51:55.599Z,1748026315.599 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250523T184440/Express0010.lzma
2025-05-23T18:51:56.600Z,1748026316.600 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0010.lzma.bak
2025-05-23T18:51:56.601Z,1748026316.601 [DataOverHttps](INFO): SBD MOMSN=24967812
2025-05-23T18:51:58.026Z,1748026318.026 [keepstation:NeedComms:D] Stopped
2025-05-23T18:51:58.027Z,1748026318.027 [keepstation:NeedComms:E] Running Loop=1
2025-05-23T18:51:58.423Z,1748026318.423 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-23T18:52:00.030Z,1748026320.030 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185159.00,A,3647.97835,N,12150.40913,W,1.244,277.20,230525,,,D*76
2025-05-23T18:52:00.032Z,1748026320.032 [NAL9602](INFO): GPS fix at 20250523T185159: (36.799639, -121.840152)
2025-05-23T18:52:00.061Z,1748026320.061 [keepstation:NeedComms:E] Stopped
2025-05-23T18:52:00.061Z,1748026320.061 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-05-23T18:52:00.062Z,1748026320.062 [keepstation:NeedComms] Stopped
2025-05-23T18:52:00.062Z,1748026320.062 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-05-23T18:52:00.062Z,1748026320.062 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-05-23T18:52:00.062Z,1748026320.062 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-23T18:52:00.470Z,1748026320.470 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B
2025-05-23T18:52:00.474Z,1748026320.474 [keepstation:SurfaceComms:B] Stopped
2025-05-23T18:52:00.475Z,1748026320.475 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-05-23T18:52:00.475Z,1748026320.475 [keepstation:SurfaceComms:setTransit] Running Loop=1
2025-05-23T18:52:00.475Z,1748026320.475 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit
2025-05-23T18:52:00.475Z,1748026320.475 [keepstation:SurfaceComms:setTransit:A] Running Loop=1
2025-05-23T18:52:00.865Z,1748026320.865 [keepstation:SurfaceComms:setTransit:A] Stopped
2025-05-23T18:52:00.865Z,1748026320.865 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit
2025-05-23T18:52:00.865Z,1748026320.865 [keepstation:SurfaceComms:setTransit] Stopped
2025-05-23T18:52:00.865Z,1748026320.865 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit
2025-05-23T18:52:00.865Z,1748026320.865 [keepstation:SurfaceComms:setStation] Running Loop=1
2025-05-23T18:52:00.865Z,1748026320.865 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation
2025-05-23T18:52:00.866Z,1748026320.866 [keepstation:SurfaceComms:setStation:A] Running Loop=1
2025-05-23T18:52:01.263Z,1748026321.263 [keepstation:SurfaceComms:setStation] Stopped
2025-05-23T18:52:01.263Z,1748026321.263 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation
2025-05-23T18:52:01.263Z,1748026321.263 [keepstation:SurfaceComms:setStation:A] Stopped
2025-05-23T18:52:01.264Z,1748026321.264 [keepstation:SurfaceComms:setSink] Running Loop=1
2025-05-23T18:52:01.264Z,1748026321.264 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink
2025-05-23T18:52:01.264Z,1748026321.264 [keepstation:SurfaceComms:setSink:A] Running Loop=1
2025-05-23T18:52:01.710Z,1748026321.710 [keepstation:SurfaceComms:setSink] Stopped
2025-05-23T18:52:01.710Z,1748026321.710 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink
2025-05-23T18:52:01.710Z,1748026321.710 [keepstation:SurfaceComms:setSink:A] Stopped
2025-05-23T18:52:01.710Z,1748026321.710 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms
2025-05-23T18:52:01.710Z,1748026321.710 [keepstation:SurfaceComms] Stopped
2025-05-23T18:52:01.710Z,1748026321.710 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-05-23T18:52:02.091Z,1748026322.091 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-05-23T18:52:02.091Z,1748026322.091 [keepstation:StartingMission] Stopped
2025-05-23T18:52:02.091Z,1748026322.091 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-05-23T18:52:02.091Z,1748026322.091 [keepstation:TransitToStation] Running Loop=1
2025-05-23T18:52:02.092Z,1748026322.092 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-05-23T18:52:02.092Z,1748026322.092 [keepstation:TransitToStation:Dive] Running Loop=1
2025-05-23T18:52:02.092Z,1748026322.092 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive
2025-05-23T18:52:02.092Z,1748026322.092 [keepstation:TransitToStation:Dive:A] Running Loop=1
2025-05-23T18:52:02.092Z,1748026322.092 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A
2025-05-23T18:52:02.092Z,1748026322.092 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-05-23T18:52:02.092Z,1748026322.092 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-05-23T18:52:02.093Z,1748026322.093 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.807800,-121.805771
2025-05-23T18:52:02.493Z,1748026322.493 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.807800,-121.805771
2025-05-23T18:52:02.494Z,1748026322.494 [keepstation:TransitToStation:Dive] Running Loop=1
2025-05-23T18:52:02.494Z,1748026322.494 [keepstation:DiveCmd] Running Loop=1
2025-05-23T18:52:02.494Z,1748026322.494 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd
2025-05-23T18:52:02.494Z,1748026322.494 [keepstation:DiveCmd:SurfaceSink] Running Loop=1
2025-05-23T18:52:02.494Z,1748026322.494 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink
2025-05-23T18:52:02.494Z,1748026322.494 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize.
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1
2025-05-23T18:52:02.499Z,1748026322.499 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize.
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize.
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:StationKeep] Running Loop=1
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:StationKeep:A] Running Loop=1
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1
2025-05-23T18:52:02.500Z,1748026322.500 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize.
2025-05-23T18:52:02.501Z,1748026322.501 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1
2025-05-23T18:52:02.501Z,1748026322.501 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:StationKeep] Stopped
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:StationKeep:A] Stopped
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:TransitToStation] Running Loop=1
2025-05-23T18:52:02.502Z,1748026322.502 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1
2025-05-23T18:52:02.506Z,1748026322.506 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1
2025-05-23T18:52:02.507Z,1748026322.507 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1
2025-05-23T18:52:02.507Z,1748026322.507 [keepstation:DiveCmd:SurfaceSink] Stopped
2025-05-23T18:52:02.507Z,1748026322.507 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink
2025-05-23T18:52:02.507Z,1748026322.507 [keepstation:DiveCmd:SurfaceSink:sink] Stopped
2025-05-23T18:52:02.508Z,1748026322.508 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink
2025-05-23T18:52:02.508Z,1748026322.508 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped
2025-05-23T18:52:02.508Z,1748026322.508 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-05-23T18:52:02.508Z,1748026322.508 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped
2025-05-23T18:52:02.508Z,1748026322.508 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize.
2025-05-23T18:52:02.508Z,1748026322.508 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped
2025-05-23T18:52:08.165Z,1748026328.165 [NAL9602](INFO): SBD MO Status=0, MOMSN=9451, MT Status=0, MTMSN=0
2025-05-23T18:52:08.166Z,1748026328.166 [NAL9602](INFO): No messages in MT queue
2025-05-23T18:52:38.840Z,1748026358.840 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-23T18:52:56.667Z,1748026376.667 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 8.45 mm (2 active estimators).
2025-05-23T18:53:05.519Z,1748026385.519 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.079174
2025-05-23T18:53:39.661Z,1748026419.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-23T18:53:56.915Z,1748026436.915 [Radio_Surface](INFO): Powering down
2025-05-23T18:54:14.811Z,1748026454.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-23T18:54:15.815Z,1748026455.815 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-05-23T19:12:00.111Z,1748027520.111 [keepstation:SurfaceComms] Running Loop=1
2025-05-23T19:12:00.112Z,1748027520.112 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms
2025-05-23T19:12:00.112Z,1748027520.112 [keepstation:SurfaceComms:A] Running Loop=1
2025-05-23T19:12:00.112Z,1748027520.112 [keepstation:SurfaceComms:A] Stopped
2025-05-23T19:12:00.112Z,1748027520.112 [keepstation:SurfaceComms:B] Running Loop=1
2025-05-23T19:12:00.112Z,1748027520.112 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B
2025-05-23T19:12:00.114Z,1748027520.114 [keepstation:NeedComms] Running Loop=1
2025-05-23T19:12:00.114Z,1748027520.114 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-05-23T19:12:00.115Z,1748027520.115 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-05-23T19:12:00.115Z,1748027520.115 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-23T19:12:00.115Z,1748027520.115 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-05-23T19:12:00.115Z,1748027520.115 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-05-23T19:12:00.116Z,1748027520.116 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-05-23T19:12:00.116Z,1748027520.116 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-05-23T19:12:00.116Z,1748027520.116 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-23T19:12:00.117Z,1748027520.117 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-23T19:12:00.117Z,1748027520.117 [keepstation:NeedComms:A] Running Loop=1
2025-05-23T19:12:00.119Z,1748027520.119 [keepstation:NeedComms:A](INFO): last time_fix was:  1748026319.000000 second since 1970/01/01T00:00:00Z 
2025-05-23T19:12:00.119Z,1748027520.119 [keepstation:NeedComms:A] Stopped
2025-05-23T19:12:00.532Z,1748027520.532 [keepstation:TransitToStation:Dive] Stopped
2025-05-23T19:12:00.532Z,1748027520.532 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive
2025-05-23T19:12:00.532Z,1748027520.532 [keepstation:TransitToStation:Dive:A] Stopped
2025-05-23T19:12:00.532Z,1748027520.532 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A
2025-05-23T19:12:00.532Z,1748027520.532 [keepstation:DiveCmd:TransitToStation] Stopped
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation:dive] Stopped
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped
2025-05-23T19:12:00.533Z,1748027520.533 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize.
2025-05-23T19:12:33.777Z,1748027553.777 [keepstation:NeedComms:C] Running Loop=1
2025-05-23T19:12:34.235Z,1748027554.235 [Radio_Surface](INFO): Powering up
2025-05-23T19:12:40.391Z,1748027560.391 [DataOverHttps](INFO): Radio surface powered ON.
2025-05-23T19:12:55.173Z,1748027575.173 [NAL9602](INFO): SBD MO Status=2, MOMSN=9452, MT Status=2, MTMSN=0
2025-05-23T19:12:55.173Z,1748027575.173 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-23T19:13:10.543Z,1748027590.543 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-23T19:13:18.600Z,1748027598.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=9452, MT Status=2, MTMSN=0
2025-05-23T19:13:18.600Z,1748027598.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-23T19:13:28.296Z,1748027608.296 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=9452, MT Status=1, MTMSN=1506
2025-05-23T19:13:28.296Z,1748027608.296 [NAL9602](INFO): Data available in MT queue
2025-05-23T19:13:28.795Z,1748027608.795 [NAL9602](INFO): Received command: stop
2025-05-23T19:13:28.818Z,1748027608.818 [CommandExec](IMPORTANT): got command stop
2025-05-23T19:13:28.818Z,1748027608.818 [CommandExec](IMPORTANT): Scheduling is paused
2025-05-23T19:13:28.819Z,1748027608.819 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-05-23T19:13:28.833Z,1748027608.833 [MissionManager](INFO): MissionManager is completed.
2025-05-23T19:13:28.833Z,1748027608.833 [MissionManager](INFO): Uninitializing Mission keepstation
2025-05-23T19:13:28.833Z,1748027608.833 [keepstation] Stopped
2025-05-23T19:13:28.833Z,1748027608.833 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-05-23T19:13:28.833Z,1748027608.833 [keepstation:NeedComms] Stopped
2025-05-23T19:13:28.833Z,1748027608.833 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-05-23T19:13:28.833Z,1748027608.833 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:NeedComms:C] Stopped
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes] Stopped
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-05-23T19:13:28.834Z,1748027608.834 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:BackseatDriver] Stopped
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:PowerOnly] Stopped
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:PowerOnly:E.Wait] Stopped
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:H.Pitch] Stopped
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:SurfaceComms] Stopped
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms
2025-05-23T19:13:28.835Z,1748027608.835 [keepstation:SurfaceComms:B] Stopped
2025-05-23T19:13:28.836Z,1748027608.836 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B
2025-05-23T19:13:28.836Z,1748027608.836 [keepstation:DiveCmd] Stopped
2025-05-23T19:13:28.836Z,1748027608.836 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd
2025-05-23T19:13:28.836Z,1748027608.836 [keepstation:TransitToStation] Stopped
2025-05-23T19:13:28.836Z,1748027608.836 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-05-23T19:13:28.836Z,1748027608.836 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-05-23T19:13:28.836Z,1748027608.836 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-05-23T19:13:29.120Z,1748027609.120 [MissionManager](IMPORTANT): Started mission Default
2025-05-23T19:13:29.121Z,1748027609.121 [Default] Running Loop=1
2025-05-23T19:13:29.121Z,1748027609.121 [Default](DEBUG): Aggregate::initialize Default
2025-05-23T19:13:29.121Z,1748027609.121 [Default:B.GoToSurface] Running Loop=1
2025-05-23T19:13:29.121Z,1748027609.121 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-23T19:13:29.121Z,1748027609.121 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-23T19:13:29.121Z,1748027609.121 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-23T19:13:29.122Z,1748027609.122 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-23T19:13:29.122Z,1748027609.122 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-23T19:13:29.123Z,1748027609.123 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-23T19:13:29.123Z,1748027609.123 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-23T19:13:29.124Z,1748027609.124 [Default:A.Wait] Running Loop=1
2025-05-23T19:13:29.124Z,1748027609.124 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-05-23T19:13:42.457Z,1748027622.457 [Default:A.Wait](INFO): Done Waiting.
2025-05-23T19:13:42.457Z,1748027622.457 [Default:A.Wait] Stopped
2025-05-23T19:13:42.458Z,1748027622.458 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-23T19:13:42.863Z,1748027622.863 [Default:CheckIn] Running Loop=1
2025-05-23T19:13:42.864Z,1748027622.864 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-23T19:13:42.864Z,1748027622.864 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-23T19:13:44.871Z,1748027624.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191344.00,A,3648.13918,N,12149.82546,W,0.641,4.39,230525,,,A*71
2025-05-23T19:13:44.873Z,1748027624.873 [NAL9602](INFO): GPS fix at 20250523T191344: (36.802320, -121.830424)
2025-05-23T19:13:44.899Z,1748027624.899 [UniversalFixResidualReporter](INFO): Fix residual: 3.7 %DT, over the last 1018.4 m. Residual distance 37.3 m at bearing 114.4 degrees. Fix at (36.8023, -121.8304) with 916.0 m made good.
2025-05-23T19:13:44.899Z,1748027624.899 [Default:CheckIn:Read_GPS] Stopped
2025-05-23T19:13:44.899Z,1748027624.899 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-23T19:13:45.685Z,1748027625.685 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-23T19:14:02.363Z,1748027642.363 [NAL9602](INFO): SBD MO Status=1, MOMSN=9453, MT Status=0, MTMSN=0
2025-05-23T19:14:02.425Z,1748027642.425 [NAL9602](INFO): Sent 204 bytes from file Logs/20250523T184440/Courier0012.lzma
2025-05-23T19:14:02.425Z,1748027642.425 [NAL9602](INFO): Packets left to send: 0
2025-05-23T19:14:16.663Z,1748027656.663 [NAL9602](INFO): SBD MO Status=2, MOMSN=9454, MT Status=2, MTMSN=0
2025-05-23T19:14:16.663Z,1748027656.663 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-23T19:14:20.827Z,1748027660.827 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-23T19:14:42.631Z,1748027682.631 [NAL9602](INFO): SBD MO Status=2, MOMSN=9454, MT Status=2, MTMSN=0
2025-05-23T19:14:42.632Z,1748027682.632 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-23T19:14:55.975Z,1748027695.975 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-23T19:15:19.117Z,1748027719.117 [NAL9602](INFO): SBD MO Status=1, MOMSN=9454, MT Status=0, MTMSN=0
2025-05-23T19:15:19.183Z,1748027719.183 [NAL9602](INFO): Sent 332 bytes from file Logs/20250523T184440/Express0013.lzma
2025-05-23T19:15:19.183Z,1748027719.183 [NAL9602](INFO): Packets left to send: 1
2025-05-23T19:15:31.127Z,1748027731.127 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-23T19:15:36.229Z,1748027736.229 [NAL9602](INFO): SBD MO Status=2, MOMSN=9455, MT Status=2, MTMSN=0
2025-05-23T19:15:36.230Z,1748027736.230 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-23T19:16:05.608Z,1748027765.608 [DataOverHttps](IMPORTANT): SBD MTMSN=20250523T191604
2025-05-23T19:16:05.615Z,1748027765.615 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006886
2025-05-23T19:16:14.336Z,1748027774.336 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20250523T184440/Express0013.lzma
2025-05-23T19:16:14.340Z,1748027774.340 [DataOverHttps](INFO): Received command: stop
2025-05-23T19:16:14.407Z,1748027774.407 [CommandExec](IMPORTANT): got command stop
2025-05-23T19:16:19.352Z,1748027779.352 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0013.lzma.bak
2025-05-23T19:16:19.353Z,1748027779.353 [DataOverHttps](INFO): SBD MOMSN=24967987
2025-05-23T19:16:36.596Z,1748027796.596 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-23T19:16:39.162Z,1748027799.162 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250523T184440/Courier0015.lzma
2025-05-23T19:16:40.164Z,1748027800.164 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Courier0015.lzma.bak
2025-05-23T19:16:40.165Z,1748027800.165 [DataOverHttps](INFO): SBD MOMSN=24967997
2025-05-23T19:16:56.434Z,1748027816.434 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20250523T184440/Express0016.lzma
2025-05-23T19:16:57.436Z,1748027817.436 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0016.lzma.bak
2025-05-23T19:16:57.437Z,1748027817.437 [DataOverHttps](INFO): SBD MOMSN=24967999
2025-05-23T19:16:58.857Z,1748027818.857 [Default:CheckIn:Read_Iridium] Stopped
2025-05-23T19:16:58.857Z,1748027818.857 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-23T19:16:58.857Z,1748027818.857 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-23T19:18:13.567Z,1748027893.567 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:18:26.091Z,1748027906.091 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:18:38.615Z,1748027918.615 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:18:51.138Z,1748027931.138 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:19:03.667Z,1748027943.667 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:19:16.590Z,1748027956.590 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:19:29.118Z,1748027969.118 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:21:59.428Z,1748028119.428 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-23T19:21:59.428Z,1748028119.428 [Default:CheckIn:C.Wait] Stopped
2025-05-23T19:21:59.428Z,1748028119.428 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-23T19:21:59.432Z,1748028119.432 [Default:CheckIn:D] Running Loop=1
2025-05-23T19:21:59.826Z,1748028119.826 [Default:CheckIn:D] Stopped
2025-05-23T19:21:59.826Z,1748028119.826 [Default:CheckIn:E] Running Loop=1
2025-05-23T19:22:00.223Z,1748028120.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  8.511750 min 
2025-05-23T19:22:00.223Z,1748028120.223 [Default:CheckIn:E] Stopped
2025-05-23T19:22:00.224Z,1748028120.224 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-23T19:22:00.224Z,1748028120.224 [Default:CheckIn] Stopped
2025-05-23T19:22:00.224Z,1748028120.224 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-23T19:22:00.224Z,1748028120.224 [Default:CheckIn](INFO): Running loop #2
2025-05-23T19:22:00.224Z,1748028120.224 [Default:CheckIn] Running Loop=2
2025-05-23T19:22:00.224Z,1748028120.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-23T19:22:00.224Z,1748028120.224 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-23T19:22:02.241Z,1748028122.241 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192201.00,A,3648.14657,N,12149.80917,W,1.030,80.00,230525,,,D*49
2025-05-23T19:22:02.244Z,1748028122.244 [NAL9602](INFO): GPS fix at 20250523T192201: (36.802443, -121.830153)
2025-05-23T19:22:02.255Z,1748028122.255 [Default:CheckIn:Read_GPS] Stopped
2025-05-23T19:22:02.255Z,1748028122.255 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-23T19:22:03.848Z,1748028123.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:22:03.857Z,1748028123.857 [NAL9602](FAULT): LCB fault:  Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-05-23T19:22:03.857Z,1748028123.857 [NAL9602] Hardware Fault, FailCount= 1
2025-05-23T19:22:03.857Z,1748028123.857 [NAL9602](ERROR): Hardware Fault
2025-05-23T19:22:03.891Z,1748028123.891 [CBIT](ERROR): Hardware Fault in component: NAL9602
2025-05-23T19:22:04.296Z,1748028124.296 [NAL9602](INFO): Powering down
2025-05-23T19:22:05.109Z,1748028125.109 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-23T19:22:05.109Z,1748028125.109 [NAL9602] No Fault, FailCount= 1
2025-05-23T19:22:05.867Z,1748028125.867 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:22:10.410Z,1748028130.410 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250523T184440/Courier0018.lzma
2025-05-23T19:22:11.415Z,1748028131.415 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Courier0018.lzma.bak
2025-05-23T19:22:11.415Z,1748028131.415 [DataOverHttps](INFO): SBD MOMSN=24968010
2025-05-23T19:22:19.198Z,1748028139.198 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:22:20.814Z,1748028140.814 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:22:31.223Z,1748028151.223 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250523T184440/Express0019.lzma
2025-05-23T19:22:32.225Z,1748028152.225 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0019.lzma.bak
2025-05-23T19:22:32.225Z,1748028152.225 [DataOverHttps](INFO): SBD MOMSN=24968013
2025-05-23T19:22:33.846Z,1748028153.846 [Default:CheckIn:Read_Iridium] Stopped
2025-05-23T19:22:33.847Z,1748028153.847 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-23T19:22:33.847Z,1748028153.847 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-23T19:22:34.185Z,1748028154.185 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:22:34.600Z,1748028154.600 [NAL9602](INFO): Powering up NAL9602
2025-05-23T19:22:35.791Z,1748028155.791 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:22:45.489Z,1748028165.489 [NAL9602](INFO): NAL9602 initialized
2025-05-23T19:22:49.123Z,1748028169.123 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:22:50.739Z,1748028170.739 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:04.475Z,1748028184.475 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:06.117Z,1748028186.117 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:16.598Z,1748028196.598 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-23T19:23:19.443Z,1748028199.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:21.038Z,1748028201.038 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:34.774Z,1748028214.774 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:35.988Z,1748028215.988 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:50.149Z,1748028230.149 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:23:50.935Z,1748028230.935 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:24:05.932Z,1748028245.932 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:24:06.286Z,1748028246.286 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:24:20.892Z,1748028260.892 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-05-23T19:24:21.240Z,1748028261.240 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-23T19:24:27.323Z,1748028267.323 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2025-05-23T19:24:27.326Z,1748028267.326 [BPC1](INFO): Received data from all battery sticks.
2025-05-23T19:27:34.377Z,1748028454.377 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-23T19:27:34.378Z,1748028454.378 [Default:CheckIn:C.Wait] Stopped
2025-05-23T19:27:34.378Z,1748028454.378 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-23T19:27:34.378Z,1748028454.378 [Default:CheckIn:D] Running Loop=1
2025-05-23T19:27:34.809Z,1748028454.809 [Default:CheckIn:D] Stopped
2025-05-23T19:27:34.809Z,1748028454.809 [Default:CheckIn:E] Running Loop=1
2025-05-23T19:27:35.171Z,1748028455.171 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  14.094807 min 
2025-05-23T19:27:35.171Z,1748028455.171 [Default:CheckIn:E] Stopped
2025-05-23T19:27:35.171Z,1748028455.171 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-23T19:27:35.171Z,1748028455.171 [Default:CheckIn] Stopped
2025-05-23T19:27:35.171Z,1748028455.171 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-23T19:27:35.171Z,1748028455.171 [Default:CheckIn](INFO): Running loop #3
2025-05-23T19:27:35.171Z,1748028455.171 [Default:CheckIn] Running Loop=3
2025-05-23T19:27:35.172Z,1748028455.172 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-23T19:27:35.172Z,1748028455.172 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-23T19:27:37.180Z,1748028457.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192735.00,A,3648.14861,N,12149.79620,W,0.680,174.07,230525,,,A*79
2025-05-23T19:27:37.184Z,1748028457.184 [NAL9602](INFO): GPS fix at 20250523T192735: (36.802477, -121.829937)
2025-05-23T19:27:37.199Z,1748028457.199 [Default:CheckIn:Read_GPS] Stopped
2025-05-23T19:27:37.199Z,1748028457.199 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-23T19:27:37.663Z,1748028457.663 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-05-23T19:27:44.963Z,1748028464.963 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250523T184440/Courier0021.lzma
2025-05-23T19:27:45.965Z,1748028465.965 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Courier0021.lzma.bak
2025-05-23T19:27:45.965Z,1748028465.965 [DataOverHttps](INFO): SBD MOMSN=24968093
2025-05-23T19:27:57.783Z,1748028477.783 [NAL9602](INFO): SBD MO Status=0, MOMSN=9455, MT Status=0, MTMSN=0
2025-05-23T19:27:57.783Z,1748028477.783 [NAL9602](INFO): No messages in MT queue
2025-05-23T19:28:03.827Z,1748028483.827 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20250523T184440/Express0022.lzma
2025-05-23T19:28:04.828Z,1748028484.828 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0022.lzma.bak
2025-05-23T19:28:04.829Z,1748028484.829 [DataOverHttps](INFO): SBD MOMSN=24968096
2025-05-23T19:28:06.329Z,1748028486.329 [Default:CheckIn:Read_Iridium] Stopped
2025-05-23T19:28:06.329Z,1748028486.329 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-23T19:28:06.329Z,1748028486.329 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-23T19:28:28.488Z,1748028508.488 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-23T19:33:06.862Z,1748028786.862 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-23T19:33:06.862Z,1748028786.862 [Default:CheckIn:C.Wait] Stopped
2025-05-23T19:33:06.862Z,1748028786.862 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-23T19:33:06.862Z,1748028786.862 [Default:CheckIn:D] Running Loop=1
2025-05-23T19:33:07.279Z,1748028787.279 [Default:CheckIn:D] Stopped
2025-05-23T19:33:07.279Z,1748028787.279 [Default:CheckIn:E] Running Loop=1
2025-05-23T19:33:07.687Z,1748028787.687 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  19.635968 min 
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn:E] Stopped
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn] Stopped
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn](INFO): Running loop #4
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn] Running Loop=4
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-23T19:33:07.688Z,1748028787.688 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-23T19:33:09.676Z,1748028789.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193308.00,A,3648.14714,N,12149.78450,W,0.428,320.33,230525,,,D*7A
2025-05-23T19:33:09.679Z,1748028789.679 [NAL9602](INFO): GPS fix at 20250523T193308: (36.802452, -121.829742)
2025-05-23T19:33:09.720Z,1748028789.720 [Default:CheckIn:Read_GPS] Stopped
2025-05-23T19:33:09.720Z,1748028789.720 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-23T19:33:18.302Z,1748028798.302 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250523T184440/Courier0024.lzma
2025-05-23T19:33:19.304Z,1748028799.304 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Courier0024.lzma.bak
2025-05-23T19:33:19.305Z,1748028799.305 [DataOverHttps](INFO): SBD MOMSN=24968116
2025-05-23T19:33:35.537Z,1748028815.537 [NAL9602](INFO): SBD MO Status=0, MOMSN=9456, MT Status=0, MTMSN=0
2025-05-23T19:33:35.537Z,1748028815.537 [NAL9602](INFO): No messages in MT queue
2025-05-23T19:33:35.645Z,1748028815.645 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250523T184440/Express0025.lzma
2025-05-23T19:33:36.641Z,1748028816.641 [DataOverHttps](INFO): Moved sent file to Logs/20250523T184440/Express0025.lzma.bak
2025-05-23T19:33:36.641Z,1748028816.641 [DataOverHttps](INFO): SBD MOMSN=24968161
2025-05-23T19:33:37.973Z,1748028817.973 [Default:CheckIn:Read_Iridium] Stopped
2025-05-23T19:33:37.973Z,1748028817.973 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-23T19:33:37.973Z,1748028817.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-23T19:34:06.257Z,1748028846.257 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-23T19:35:03.031Z,1748028903.031 [Waterlinked](INFO): Powering down
2025-05-23T19:35:32.296Z,1748028932.296 [DataOverHttps](IMPORTANT): SBD MTMSN=20250523T193531
2025-05-23T19:35:40.070Z,1748028940.070 [DataOverHttps](INFO): Received command: restart logs