2025-02-22T00:24:09.961Z,1740183849.961 [CommandExec](IMPORTANT): got command restart logs
2025-02-22T00:24:25.338Z,1740183865.338 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T00:29:04.172Z,1740184144.172 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-22T00:29:04.172Z,1740184144.172 [Default:CheckIn:C.Wait] Stopped
2025-02-22T00:29:04.172Z,1740184144.172 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T00:29:04.172Z,1740184144.172 [Default:CheckIn:D] Running Loop=1
2025-02-22T00:29:04.582Z,1740184144.582 [Default:CheckIn:D] Stopped
2025-02-22T00:29:04.582Z,1740184144.582 [Default:CheckIn:E] Running Loop=1
2025-02-22T00:29:04.985Z,1740184144.985 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  5.753233 min 
2025-02-22T00:29:04.985Z,1740184144.985 [Default:CheckIn:E] Stopped
2025-02-22T00:29:04.986Z,1740184144.986 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-22T00:29:04.986Z,1740184144.986 [Default:CheckIn] Stopped
2025-02-22T00:29:04.986Z,1740184144.986 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-22T00:29:04.986Z,1740184144.986 [Default:CheckIn](INFO): Running loop #2
2025-02-22T00:29:04.986Z,1740184144.986 [Default:CheckIn] Running Loop=2
2025-02-22T00:29:04.986Z,1740184144.986 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-22T00:29:04.986Z,1740184144.986 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-22T00:29:06.977Z,1740184146.977 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002906.00,A,3647.62855,N,12150.81358,W,0.544,181.08,220225,,,A*7B
2025-02-22T00:29:06.980Z,1740184146.980 [NAL9602](INFO): GPS fix at 20250222T002906: (36.793809, -121.846893)
2025-02-22T00:29:07.030Z,1740184147.030 [Default:CheckIn:Read_GPS] Stopped
2025-02-22T00:29:07.030Z,1740184147.030 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-22T00:29:17.787Z,1740184157.787 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250221T232728/Courier0024.lzma
2025-02-22T00:29:18.785Z,1740184158.785 [DataOverHttps](INFO): Moved sent file to Logs/20250221T232728/Courier0024.lzma.bak
2025-02-22T00:29:18.785Z,1740184158.785 [DataOverHttps](INFO): SBD MOMSN=23971597
2025-02-22T00:29:27.726Z,1740184167.726 [NAL9602](INFO): SBD MO Status=2, MOMSN=13096, MT Status=2, MTMSN=0
2025-02-22T00:29:27.726Z,1740184167.726 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T00:29:35.824Z,1740184175.824 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250222T002409/Courier0000.lzma
2025-02-22T00:29:36.825Z,1740184176.825 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Courier0000.lzma.bak
2025-02-22T00:29:36.825Z,1740184176.825 [DataOverHttps](INFO): SBD MOMSN=23971599
2025-02-22T00:29:39.038Z,1740184179.038 [NAL9602](INFO): SBD MO Status=0, MOMSN=13096, MT Status=0, MTMSN=0
2025-02-22T00:29:39.038Z,1740184179.038 [NAL9602](INFO): No messages in MT queue
2025-02-22T00:29:55.555Z,1740184195.555 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20250221T232728/Express0025.lzma
2025-02-22T00:29:56.557Z,1740184196.557 [DataOverHttps](INFO): Moved sent file to Logs/20250221T232728/Express0025.lzma.bak
2025-02-22T00:29:56.557Z,1740184196.557 [DataOverHttps](INFO): SBD MOMSN=23971603
2025-02-22T00:30:09.744Z,1740184209.744 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T00:30:12.897Z,1740184212.897 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20250222T002409/Express0001.lzma
2025-02-22T00:30:13.897Z,1740184213.897 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Express0001.lzma.bak
2025-02-22T00:30:13.897Z,1740184213.897 [DataOverHttps](INFO): SBD MOMSN=23971607
2025-02-22T00:30:15.422Z,1740184215.422 [Default:CheckIn:Read_Iridium] Stopped
2025-02-22T00:30:15.422Z,1740184215.422 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-22T00:30:15.422Z,1740184215.422 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-22T00:31:46.875Z,1740184306.875 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:32:48.760Z,1740184368.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:35:16.130Z,1740184516.130 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-22T00:35:16.130Z,1740184516.130 [Default:CheckIn:C.Wait] Stopped
2025-02-22T00:35:16.130Z,1740184516.130 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T00:35:16.130Z,1740184516.130 [Default:CheckIn:D] Running Loop=1
2025-02-22T00:35:16.534Z,1740184516.534 [Default:CheckIn:D] Stopped
2025-02-22T00:35:16.534Z,1740184516.534 [Default:CheckIn:E] Running Loop=1
2025-02-22T00:35:16.939Z,1740184516.939 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  11.952434 min 
2025-02-22T00:35:16.939Z,1740184516.939 [Default:CheckIn:E] Stopped
2025-02-22T00:35:16.939Z,1740184516.939 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-22T00:35:16.939Z,1740184516.939 [Default:CheckIn] Stopped
2025-02-22T00:35:16.939Z,1740184516.939 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-22T00:35:16.940Z,1740184516.940 [Default:CheckIn](INFO): Running loop #3
2025-02-22T00:35:16.940Z,1740184516.940 [Default:CheckIn] Running Loop=3
2025-02-22T00:35:16.940Z,1740184516.940 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-22T00:35:16.940Z,1740184516.940 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-22T00:35:18.958Z,1740184518.958 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003518.00,A,3647.56366,N,12150.81004,W,0.641,179.50,220225,,,A*73
2025-02-22T00:35:18.961Z,1740184518.961 [NAL9602](INFO): GPS fix at 20250222T003518: (36.792728, -121.846834)
2025-02-22T00:35:18.972Z,1740184518.972 [Default:CheckIn:Read_GPS] Stopped
2025-02-22T00:35:18.972Z,1740184518.972 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-22T00:35:26.872Z,1740184526.872 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250222T002409/Courier0006.lzma
2025-02-22T00:35:27.873Z,1740184527.873 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Courier0006.lzma.bak
2025-02-22T00:35:27.874Z,1740184527.874 [DataOverHttps](IMPORTANT): SBD MOMSN=23971612, MTMSN=20250222T003526
2025-02-22T00:35:31.886Z,1740184531.886 [NAL9602](INFO): SBD MO Status=0, MOMSN=13097, MT Status=0, MTMSN=0
2025-02-22T00:35:31.886Z,1740184531.886 [NAL9602](INFO): No messages in MT queue
2025-02-22T00:35:35.659Z,1740184535.659 [DataOverHttps](INFO): Received command: configset verticalcontrol.kpdepthbuoy 50 ratio persist
2025-02-22T00:35:36.088Z,1740184536.088 [CommandExec](IMPORTANT): got command configSet VerticalControl.kpDepthBuoy 50 ratio persist
2025-02-22T00:35:44.323Z,1740184544.323 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250222T002409/Express0007.lzma
2025-02-22T00:35:45.326Z,1740184545.326 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Express0007.lzma.bak
2025-02-22T00:35:45.326Z,1740184545.326 [DataOverHttps](IMPORTANT): SBD MOMSN=23971617, MTMSN=20250222T003544
2025-02-22T00:35:53.047Z,1740184553.047 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree" 23yze 1 2
2025-02-22T00:35:53.096Z,1740184553.096 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree" 23yze 1 2.000000
2025-02-22T00:35:53.096Z,1740184553.096 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=23yze
2025-02-22T00:35:53.097Z,1740184553.097 [CommandExec](IMPORTANT): Scheduled #51 (#1 of 2 with id='23yze'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 45 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree", ASAP
2025-02-22T00:35:54.311Z,1740184554.311 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T003553
2025-02-22T00:36:01.623Z,1740184561.623 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T002409/Courier0009.lzma
2025-02-22T00:36:01.625Z,1740184561.625 [DataOverHttps](INFO): Received command: sched asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 23yze 2 2
2025-02-22T00:36:01.639Z,1740184561.639 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 23yze 2 2.000000
2025-02-22T00:36:01.639Z,1740184561.639 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=23yze
2025-02-22T00:36:01.640Z,1740184561.640 [CommandExec](IMPORTANT): Scheduled #52 (#2 of 2 with id='23yze'): "set keepstation:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2025-02-22T00:36:01.773Z,1740184561.773 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-02-22T00:36:01.773Z,1740184561.773 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-02-22T00:36:01.774Z,1740184561.774 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-02-22T00:36:01.777Z,1740184561.777 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-02-22T00:36:02.555Z,1740184562.555 [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="MaxDepth">
        <Units:meter />
        <Value>45</Value>
    </DefineArg>
    <DefineArg Name="MinOffshore">
        <Units:kilometer />
        <Value>2</Value>
    </DefineArg>
    <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>
    <Call RefId="NeedComms" Id="StartingMission" />
    <Aggregate Id="TransitToStation">
        <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>
        <Guidance:Waypoint Id="Wpt1">
            <Sequence />
            <Setting>
                <Guidance:Waypoint.latitude />
                <Arg Name="Latitude" />
            </Setting>
            <Setting>
                <Guidance:Waypoint.longitude />
                <Arg Name="Longitude" />
            </Setting>
        </Guidance:Waypoint>
    </Aggregate>
    <Call RefId="NeedComms" Id="OnStation" />
    <Aggregate Id="KeepStation">
        <Sequence />
        <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>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Arg Name="MissionTimeout" />
            </Setting>
        </Guidance:Wait>
    </Aggregate>
</Mission>

2025-02-22T00:36:02.560Z,1740184562.560 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-02-22T00:36:02.563Z,1740184562.563 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-02-22T00:36:02.637Z,1740184562.637 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T00:36:02.656Z,1740184562.656 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-02-22T00:36:02.659Z,1740184562.659 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-02-22T00:36:02.693Z,1740184562.693 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-02-22T00:36:02.695Z,1740184562.695 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-02-22T00:36:02.741Z,1740184562.741 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-02-22T00:36:02.748Z,1740184562.748 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-02-22T00:36:02.760Z,1740184562.760 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-02-22T00:36:02.762Z,1740184562.762 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-02-22T00:36:02.768Z,1740184562.768 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-02-22T00:36:02.769Z,1740184562.769 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-22T00:36:02.769Z,1740184562.769 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-22T00:36:02.770Z,1740184562.770 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-22T00:36:03.237Z,1740184563.237 [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-02-22T00:36:03.241Z,1740184563.241 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-02-22T00:36:03.244Z,1740184563.244 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-22T00:36:03.246Z,1740184563.246 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-22T00:36:03.248Z,1740184563.248 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-02-22T00:36:03.251Z,1740184563.251 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-22T00:36:03.253Z,1740184563.253 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-22T00:36:03.255Z,1740184563.255 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-02-22T00:36:03.259Z,1740184563.259 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-02-22T00:36:03.265Z,1740184563.265 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-22T00:36:03.334Z,1740184563.334 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-22T00:36:03.335Z,1740184563.335 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-22T00:36:03.336Z,1740184563.336 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-22T00:36:03.468Z,1740184563.468 [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-02-22T00:36:03.473Z,1740184563.473 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-22T00:36:03.476Z,1740184563.476 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-22T00:36:03.479Z,1740184563.479 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-22T00:36:03.482Z,1740184563.482 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-22T00:36:03.484Z,1740184563.484 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-22T00:36:03.518Z,1740184563.518 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-22T00:36:03.524Z,1740184563.524 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-22T00:36:03.532Z,1740184563.532 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-22T00:36:03.532Z,1740184563.532 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-22T00:36:03.533Z,1740184563.533 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-22T00:36:03.562Z,1740184563.562 [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-02-22T00:36:03.565Z,1740184563.565 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-02-22T00:36:03.567Z,1740184563.567 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-22T00:36:03.569Z,1740184563.569 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-22T00:36:03.570Z,1740184563.570 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-22T00:36:03.571Z,1740184563.571 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-22T00:36:03.712Z,1740184563.712 [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-02-22T00:36:03.716Z,1740184563.716 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-02-22T00:36:03.718Z,1740184563.718 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-02-22T00:36:03.720Z,1740184563.720 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-02-22T00:36:03.722Z,1740184563.722 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-02-22T00:36:03.725Z,1740184563.725 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-22T00:36:03.731Z,1740184563.731 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-22T00:36:03.733Z,1740184563.733 [keepstation:H.Pitch](DEBUG): Construct.
2025-02-22T00:36:03.740Z,1740184563.740 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-22T00:36:03.742Z,1740184563.742 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2025-02-22T00:36:03.747Z,1740184563.747 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2025-02-22T00:36:03.777Z,1740184563.777 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-22T00:36:03.825Z,1740184563.825 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2025-02-22T00:36:03.837Z,1740184563.837 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2025-02-22T00:36:03.850Z,1740184563.850 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2025-02-22T00:36:03.913Z,1740184563.913 [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.
      """

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

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

  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
  }

  call id="StartingMission" refId="NeedComms"

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

    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
    }

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

      set latitude = Latitude
      set longitude = Longitude
    }
  }

  call id="OnStation" refId="NeedComms"

  aggregate KeepStation {
    run in sequence

    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
    }

    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-02-22T00:36:03.914Z,1740184563.914 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-02-22T00:36:07.164Z,1740184567.164 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Courier0009.lzma.bak
2025-02-22T00:36:07.164Z,1740184567.164 [DataOverHttps](INFO): SBD MOMSN=23971622
2025-02-22T00:36:25.527Z,1740184585.527 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 45 minute
2025-02-22T00:36:25.528Z,1740184585.528 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute
2025-02-22T00:36:25.529Z,1740184585.529 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree
2025-02-22T00:36:25.530Z,1740184585.530 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2025-02-22T00:36:36.429Z,1740184596.429 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool
2025-02-22T00:36:36.430Z,1740184596.430 [CommandExec](IMPORTANT): got command run
2025-02-22T00:36:36.432Z,1740184596.432 [CommandExec](IMPORTANT): Running 
2025-02-22T00:36:36.561Z,1740184596.561 [Default] Stopped
2025-02-22T00:36:36.561Z,1740184596.561 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-22T00:36:36.561Z,1740184596.561 [Default:B.GoToSurface] Stopped
2025-02-22T00:36:36.562Z,1740184596.562 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T00:36:36.562Z,1740184596.562 [Default:CheckIn] Stopped
2025-02-22T00:36:36.562Z,1740184596.562 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-22T00:36:36.562Z,1740184596.562 [Default:CheckIn:Read_Iridium] Stopped
2025-02-22T00:36:36.562Z,1740184596.562 [MissionManager](IMPORTANT): Started mission keepstation
2025-02-22T00:36:36.562Z,1740184596.562 [keepstation] Running Loop=1
2025-02-22T00:36:36.562Z,1740184596.562 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-02-22T00:36:36.562Z,1740184596.562 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T00:36:36.563Z,1740184596.563 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-02-22T00:36:36.563Z,1740184596.563 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T00:36:36.563Z,1740184596.563 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-22T00:36:36.563Z,1740184596.563 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T00:36:36.563Z,1740184596.563 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-22T00:36:36.563Z,1740184596.563 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T00:36:36.563Z,1740184596.563 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly:A] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly:B] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly:C] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly:D] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-02-22T00:36:36.564Z,1740184596.564 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-22T00:36:36.565Z,1740184596.565 [keepstation:H.Pitch] Running Loop=1
2025-02-22T00:36:36.565Z,1740184596.565 [keepstation:H.Pitch](DEBUG): Initialize.
2025-02-22T00:36:36.565Z,1740184596.565 [keepstation:B] Running Loop=1
2025-02-22T00:36:36.565Z,1740184596.565 [keepstation:H.Pitch] Running Loop=1
2025-02-22T00:36:36.566Z,1740184596.566 [keepstation:PowerOnly] Running Loop=1
2025-02-22T00:36:36.566Z,1740184596.566 [keepstation:PowerOnly:D] Stopped
2025-02-22T00:36:36.566Z,1740184596.566 [keepstation:PowerOnly:C] Stopped
2025-02-22T00:36:36.566Z,1740184596.566 [keepstation:PowerOnly:B] Stopped
2025-02-22T00:36:36.566Z,1740184596.566 [keepstation:PowerOnly:A] Stopped
2025-02-22T00:36:36.566Z,1740184596.566 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T00:36:36.566Z,1740184596.566 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-02-22T00:36:36.582Z,1740184596.582 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T00:36:36.582Z,1740184596.582 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T00:36:36.583Z,1740184596.583 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T00:36:36.583Z,1740184596.583 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T00:36:36.584Z,1740184596.584 [keepstation:B] Stopped
2025-02-22T00:36:36.584Z,1740184596.584 [keepstation:D] Running Loop=1
2025-02-22T00:36:36.716Z,1740184596.716 [BackseatComponent](INFO): Powering up
2025-02-22T00:36:36.717Z,1740184596.717 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-02-22T00:36:36.959Z,1740184596.959 [keepstation:D] Stopped
2025-02-22T00:36:36.959Z,1740184596.959 [keepstation:E] Running Loop=1
2025-02-22T00:36:37.363Z,1740184597.363 [keepstation:E] Stopped
2025-02-22T00:36:37.363Z,1740184597.363 [keepstation:StartingMission] Running Loop=1
2025-02-22T00:36:37.364Z,1740184597.364 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-02-22T00:36:37.768Z,1740184597.768 [keepstation:NeedComms] Running Loop=1
2025-02-22T00:36:37.768Z,1740184597.768 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T00:36:37.768Z,1740184597.768 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T00:36:37.768Z,1740184597.768 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T00:36:37.769Z,1740184597.769 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T00:36:37.769Z,1740184597.769 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T00:36:37.769Z,1740184597.769 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T00:36:37.770Z,1740184597.770 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T00:36:37.770Z,1740184597.770 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T00:36:37.770Z,1740184597.770 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T00:36:37.771Z,1740184597.771 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T00:36:37.772Z,1740184597.772 [keepstation:NeedComms:A](INFO): last time_fix was:  1740184518.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T00:36:37.772Z,1740184597.772 [keepstation:NeedComms:A] Stopped
2025-02-22T00:36:38.164Z,1740184598.164 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-22T00:36:38.165Z,1740184598.165 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T00:36:38.572Z,1740184598.572 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T00:36:40.179Z,1740184600.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003639.00,A,3647.54828,N,12150.80823,W,1.205,205.23,220225,,,A*75
2025-02-22T00:36:40.181Z,1740184600.181 [NAL9602](INFO): GPS fix at 20250222T003639: (36.792471, -121.846804)
2025-02-22T00:36:40.212Z,1740184600.212 [keepstation:NeedComms:C] Stopped
2025-02-22T00:36:40.212Z,1740184600.212 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T00:36:40.609Z,1740184600.609 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-22T00:36:50.678Z,1740184610.678 [NAL9602](INFO): SBD MO Status=0, MOMSN=13098, MT Status=0, MTMSN=0
2025-02-22T00:36:50.678Z,1740184610.678 [NAL9602](INFO): No messages in MT queue
2025-02-22T00:37:03.743Z,1740184623.743 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T002409/Courier0012.lzma
2025-02-22T00:37:10.769Z,1740184630.769 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Courier0012.lzma.bak
2025-02-22T00:37:10.769Z,1740184630.769 [DataOverHttps](INFO): SBD MOMSN=23971625
2025-02-22T00:37:21.379Z,1740184641.379 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T00:37:27.511Z,1740184647.511 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T002409/Courier0015.lzma
2025-02-22T00:37:28.513Z,1740184648.513 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Courier0015.lzma.bak
2025-02-22T00:37:28.513Z,1740184648.513 [DataOverHttps](INFO): SBD MOMSN=23971627
2025-02-22T00:37:45.247Z,1740184665.247 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250222T002409/Courier0018.lzma
2025-02-22T00:37:45.789Z,1740184665.789 [BackseatComponent](IMPORTANT): IBASE811F-02: Running bioacoustics backseat app. Logging to: 20250222T003744
2025-02-22T00:37:46.249Z,1740184666.249 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Courier0018.lzma.bak
2025-02-22T00:37:46.249Z,1740184666.249 [DataOverHttps](INFO): SBD MOMSN=23971631
2025-02-22T00:38:06.366Z,1740184686.366 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250222T002409/Express0010.lzma
2025-02-22T00:38:07.365Z,1740184687.365 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Express0010.lzma.bak
2025-02-22T00:38:07.365Z,1740184687.365 [DataOverHttps](INFO): SBD MOMSN=23971634
2025-02-22T00:38:23.971Z,1740184703.971 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20250222T002409/Express0013.lzma
2025-02-22T00:38:24.973Z,1740184704.973 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Express0013.lzma.bak
2025-02-22T00:38:24.973Z,1740184704.973 [DataOverHttps](INFO): SBD MOMSN=23971638
2025-02-22T00:38:41.583Z,1740184721.583 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20250222T002409/Express0016.lzma
2025-02-22T00:38:42.585Z,1740184722.585 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Express0016.lzma.bak
2025-02-22T00:38:42.585Z,1740184722.585 [DataOverHttps](INFO): SBD MOMSN=23971644
2025-02-22T00:38:45.092Z,1740184725.092 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1975
2025-02-22T00:38:59.311Z,1740184739.311 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20250222T002409/Express0019.lzma
2025-02-22T00:39:00.313Z,1740184740.313 [DataOverHttps](INFO): Moved sent file to Logs/20250222T002409/Express0019.lzma.bak
2025-02-22T00:39:00.313Z,1740184740.313 [DataOverHttps](INFO): SBD MOMSN=23971652
2025-02-22T00:39:02.108Z,1740184742.108 [keepstation:NeedComms:D] Stopped
2025-02-22T00:39:02.109Z,1740184742.109 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T00:39:02.481Z,1740184742.481 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T00:39:04.081Z,1740184744.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003903.00,A,3647.52354,N,12150.80252,W,0.836,162.52,220225,,,A*76
2025-02-22T00:39:04.093Z,1740184744.093 [NAL9602](INFO): GPS fix at 20250222T003903: (36.792059, -121.846709)
2025-02-22T00:39:04.176Z,1740184744.176 [keepstation:NeedComms:E] Stopped
2025-02-22T00:39:04.177Z,1740184744.177 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T00:39:04.177Z,1740184744.177 [keepstation:NeedComms] Stopped
2025-02-22T00:39:04.177Z,1740184744.177 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T00:39:04.177Z,1740184744.177 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T00:39:04.177Z,1740184744.177 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T00:39:04.554Z,1740184744.554 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-02-22T00:39:04.554Z,1740184744.554 [keepstation:StartingMission] Stopped
2025-02-22T00:39:04.555Z,1740184744.555 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-02-22T00:39:04.555Z,1740184744.555 [keepstation:TransitToStation] Running Loop=1
2025-02-22T00:39:04.567Z,1740184744.567 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-02-22T00:39:04.567Z,1740184744.567 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T00:39:04.567Z,1740184744.567 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-22T00:39:04.567Z,1740184744.567 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T00:39:04.567Z,1740184744.567 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-02-22T00:39:04.568Z,1740184744.568 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T00:39:04.568Z,1740184744.568 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-02-22T00:39:04.568Z,1740184744.568 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-02-22T00:39:04.568Z,1740184744.568 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-22T00:39:04.569Z,1740184744.569 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T00:39:04.953Z,1740184744.953 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T00:39:04.954Z,1740184744.954 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T00:39:04.954Z,1740184744.954 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T00:39:04.954Z,1740184744.954 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T00:39:22.177Z,1740184762.177 [NAL9602](INFO): SBD MO Status=2, MOMSN=13099, MT Status=2, MTMSN=0
2025-02-22T00:39:22.177Z,1740184762.177 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T00:39:39.974Z,1740184779.974 [NAL9602](INFO): SBD MO Status=2, MOMSN=13099, MT Status=2, MTMSN=0
2025-02-22T00:39:39.974Z,1740184779.974 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T00:40:32.716Z,1740184832.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:40:52.587Z,1740184852.587 [Radio_Surface](INFO): Powering down
2025-02-22T00:41:03.856Z,1740184863.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:41:04.860Z,1740184864.860 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T00:42:41.244Z,1740184961.244 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.738480
2025-02-22T00:42:46.737Z,1740184966.737 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1976
2025-02-22T00:44:06.338Z,1740185046.338 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-02-22T00:44:36.000Z,1740185077.000 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T00:52:03.864Z,1740185523.864 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-02-22T00:52:03.864Z,1740185523.864 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-02-22T00:52:03.864Z,1740185523.864 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-22T00:52:03.865Z,1740185523.865 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-02-22T00:52:03.865Z,1740185523.865 [keepstation:TransitToStation] Stopped
2025-02-22T00:52:03.865Z,1740185523.865 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-02-22T00:52:03.865Z,1740185523.865 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-02-22T00:52:03.866Z,1740185523.866 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-22T00:52:03.866Z,1740185523.866 [keepstation:TransitToStation:B.Pitch] Stopped
2025-02-22T00:52:03.866Z,1740185523.866 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-02-22T00:52:03.866Z,1740185523.866 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-02-22T00:52:03.866Z,1740185523.866 [keepstation:OnStation] Running Loop=1
2025-02-22T00:52:03.866Z,1740185523.866 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-02-22T00:52:04.268Z,1740185524.268 [keepstation:NeedComms] Running Loop=1
2025-02-22T00:52:04.268Z,1740185524.268 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T00:52:04.268Z,1740185524.268 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T00:52:04.268Z,1740185524.268 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T00:52:04.269Z,1740185524.269 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T00:52:04.269Z,1740185524.269 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T00:52:04.269Z,1740185524.269 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T00:52:04.270Z,1740185524.270 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T00:52:04.270Z,1740185524.270 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T00:52:04.270Z,1740185524.270 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T00:52:04.270Z,1740185524.270 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T00:52:04.272Z,1740185524.272 [keepstation:NeedComms:A](INFO): last time_fix was:  1740184743.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T00:52:04.272Z,1740185524.272 [keepstation:NeedComms:A] Stopped
2025-02-22T00:52:55.253Z,1740185575.253 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T00:52:55.963Z,1740185575.963 [Radio_Surface](INFO): Powering up
2025-02-22T00:53:01.804Z,1740185581.804 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-22T00:53:27.952Z,1740185607.952 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-02-22T00:53:28.036Z,1740185608.036 [NAL9602](ERROR): received: 
+CSQ:0

OK099, 2, 0, 0, 0

OK
2025-02-22T00:53:31.948Z,1740185611.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:54:03.096Z,1740185643.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:54:03.334Z,1740185643.334 [NAL9602](INFO): SBD MO Status=0, MOMSN=13099, MT Status=0, MTMSN=0
2025-02-22T00:54:03.334Z,1740185643.334 [NAL9602](INFO): No messages in MT queue
2025-02-22T00:54:04.546Z,1740185644.546 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005403.00,A,3647.72407,N,12150.83477,W,0.408,358.52,220225,,,A*76
2025-02-22T00:54:04.549Z,1740185644.549 [NAL9602](INFO): GPS fix at 20250222T005403: (36.795401, -121.847246)
2025-02-22T00:54:04.561Z,1740185644.561 [UniversalFixResidualReporter](INFO): Fix residual: 38.3 %DT, over the last 631.2 m. Residual distance 241.6 m at bearing 9.0 degrees. Fix at (36.7954, -121.8472) with 374.7 m made good.
2025-02-22T00:54:04.568Z,1740185644.568 [keepstation:NeedComms:C] Stopped
2025-02-22T00:54:04.568Z,1740185644.568 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T00:54:29.445Z,1740185669.445 [NAL9602](INFO): SBD MO Status=1, MOMSN=13100, MT Status=0, MTMSN=0
2025-02-22T00:54:29.495Z,1740185669.495 [NAL9602](INFO): Sent 205 bytes from file Logs/20250222T002409/Courier0021.lzma
2025-02-22T00:54:29.496Z,1740185669.496 [NAL9602](INFO): Packets left to send: 0
2025-02-22T00:54:34.219Z,1740185674.219 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:54:40.406Z,1740185680.406 [NAL9602](INFO): SBD MO Status=1, MOMSN=13101, MT Status=0, MTMSN=0
2025-02-22T00:54:40.461Z,1740185680.461 [NAL9602](INFO): Sent 332 bytes from file Logs/20250222T002409/Express0022.lzma
2025-02-22T00:54:40.461Z,1740185680.461 [NAL9602](INFO): Packets left to send: 1
2025-02-22T00:54:50.982Z,1740185690.982 [NAL9602](INFO): SBD MO Status=1, MOMSN=13102, MT Status=0, MTMSN=0
2025-02-22T00:54:51.032Z,1740185691.032 [NAL9602](INFO): Sent 90 bytes from file Logs/20250222T002409/Express0022.lzma
2025-02-22T00:54:51.032Z,1740185691.032 [NAL9602](INFO): Packets left to send: 0
2025-02-22T00:54:58.646Z,1740185698.646 [NAL9602](INFO): SBD MO Status=0, MOMSN=13103, MT Status=0, MTMSN=0
2025-02-22T00:54:58.732Z,1740185698.732 [keepstation:NeedComms:D] Stopped
2025-02-22T00:54:58.732Z,1740185698.732 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T00:55:01.065Z,1740185701.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005500.00,A,3647.71599,N,12150.83485,W,1.438,183.24,220225,,,A*7B
2025-02-22T00:55:01.068Z,1740185701.068 [NAL9602](INFO): GPS fix at 20250222T005500: (36.795266, -121.847247)
2025-02-22T00:55:01.087Z,1740185701.087 [keepstation:NeedComms:E] Stopped
2025-02-22T00:55:01.088Z,1740185701.088 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T00:55:01.088Z,1740185701.088 [keepstation:NeedComms] Stopped
2025-02-22T00:55:01.088Z,1740185701.088 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T00:55:01.088Z,1740185701.088 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T00:55:01.088Z,1740185701.088 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T00:55:01.509Z,1740185701.509 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-02-22T00:55:01.509Z,1740185701.509 [keepstation:OnStation] Stopped
2025-02-22T00:55:01.509Z,1740185701.509 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-02-22T00:55:01.509Z,1740185701.509 [keepstation:KeepStation] Running Loop=1
2025-02-22T00:55:01.509Z,1740185701.509 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-02-22T00:55:01.509Z,1740185701.509 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T00:55:01.510Z,1740185701.510 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T00:55:01.510Z,1740185701.510 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-02-22T00:55:01.510Z,1740185701.510 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T00:55:01.510Z,1740185701.510 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-02-22T00:55:01.510Z,1740185701.510 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-02-22T00:55:01.510Z,1740185701.510 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-02-22T00:55:01.949Z,1740185701.949 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T00:55:01.950Z,1740185701.950 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T00:55:01.950Z,1740185701.950 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T00:55:05.348Z,1740185705.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:55:17.640Z,1740185717.640 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003200
2025-02-22T00:55:33.287Z,1740185733.287 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T00:57:08.087Z,1740185828.087 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:57:21.955Z,1740185841.955 [Radio_Surface](INFO): Powering down
2025-02-22T00:57:39.221Z,1740185859.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T00:57:40.224Z,1740185860.224 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T00:58:19.388Z,1740185899.388 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T00:58:32.314Z,1740185912.314 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T00:58:45.646Z,1740185925.646 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T00:58:58.979Z,1740185938.979 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T00:59:12.310Z,1740185952.310 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T00:59:16.616Z,1740185956.616 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.751755
2025-02-22T00:59:25.642Z,1740185965.642 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T00:59:38.166Z,1740185978.166 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T01:04:37.531Z,1740186277.531 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-22T01:04:46.830Z,1740186286.830 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-22T01:04:46.830Z,1740186286.830 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-22T01:04:46.833Z,1740186286.833 [BPC1](INFO): Received data from all battery sticks.
2025-02-22T01:21:36.857Z,1740187296.857 [keepstation](INFO): Timed out from 2025-02-22T00:36:36.6Z
2025-02-22T01:21:36.857Z,1740187296.857 [MissionManager](INFO): keepstation is completed.
2025-02-22T01:21:36.857Z,1740187296.857 [MissionManager](INFO): Uninitializing Mission keepstation
2025-02-22T01:21:36.857Z,1740187296.857 [keepstation] Stopped
2025-02-22T01:21:36.857Z,1740187296.857 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-02-22T01:21:36.857Z,1740187296.857 [keepstation:StandardEnvelopes] Stopped
2025-02-22T01:21:36.857Z,1740187296.857 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:BackseatDriver] Stopped
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:PowerOnly] Stopped
2025-02-22T01:21:36.858Z,1740187296.858 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:PowerOnly:E.Wait] Stopped
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:H.Pitch] Stopped
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:KeepStation] Stopped
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:KeepStation:A] Stopped
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:KeepStation:B.Pitch] Stopped
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:KeepStation:C.KeepStation] Stopped
2025-02-22T01:21:36.859Z,1740187296.859 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-02-22T01:21:36.860Z,1740187296.860 [keepstation:KeepStation:D.Wait] Stopped
2025-02-22T01:21:36.860Z,1740187296.860 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T01:21:37.261Z,1740187297.261 [MissionManager](IMPORTANT): Started mission Default
2025-02-22T01:21:37.261Z,1740187297.261 [Default] Running Loop=1
2025-02-22T01:21:37.261Z,1740187297.261 [Default](DEBUG): Aggregate::initialize Default
2025-02-22T01:21:37.261Z,1740187297.261 [Default:B.GoToSurface] Running Loop=1
2025-02-22T01:21:37.261Z,1740187297.261 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T01:21:37.262Z,1740187297.262 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-22T01:21:37.262Z,1740187297.262 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-22T01:21:37.262Z,1740187297.262 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-22T01:21:37.262Z,1740187297.262 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-22T01:21:37.263Z,1740187297.263 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T01:21:37.264Z,1740187297.264 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T01:21:37.264Z,1740187297.264 [Default:A.Wait] Running Loop=1
2025-02-22T01:21:37.264Z,1740187297.264 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-22T01:21:37.896Z,1740187297.896 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-02-22T01:21:37.896Z,1740187297.896 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-02-22T01:21:37.976Z,1740187297.976 [Radio_Surface](INFO): Powering up
2025-02-22T01:21:44.132Z,1740187304.132 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-22T01:21:50.334Z,1740187310.334 [Default:A.Wait](INFO): Done Waiting.
2025-02-22T01:21:50.334Z,1740187310.334 [Default:A.Wait] Stopped
2025-02-22T01:21:50.334Z,1740187310.334 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T01:21:56.093Z,1740187316.093 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-02-22T01:21:56.093Z,1740187316.093 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-02-22T01:21:56.093Z,1740187316.093 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-02-22T01:22:14.252Z,1740187334.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:22:41.503Z,1740187361.503 [Default:CheckIn] Running Loop=1
2025-02-22T01:22:41.504Z,1740187361.504 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-22T01:22:41.504Z,1740187361.504 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-22T01:22:45.379Z,1740187365.379 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:23:04.929Z,1740187384.929 [NAL9602](INFO): SBD MO Status=0, MOMSN=13104, MT Status=0, MTMSN=0
2025-02-22T01:23:04.929Z,1740187384.929 [NAL9602](INFO): No messages in MT queue
2025-02-22T01:23:06.137Z,1740187386.137 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012305.00,A,3647.60441,N,12150.89727,W,1.886,213.60,220225,,,A*78
2025-02-22T01:23:06.140Z,1740187386.140 [NAL9602](INFO): GPS fix at 20250222T012305: (36.793407, -121.848288)
2025-02-22T01:23:06.151Z,1740187386.151 [Default:CheckIn:Read_GPS] Stopped
2025-02-22T01:23:06.151Z,1740187386.151 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-22T01:23:16.509Z,1740187396.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:23:22.793Z,1740187402.793 [NAL9602](INFO): SBD MO Status=1, MOMSN=13105, MT Status=0, MTMSN=0
2025-02-22T01:23:22.843Z,1740187402.843 [NAL9602](INFO): Sent 202 bytes from file Logs/20250222T002409/Courier0024.lzma
2025-02-22T01:23:22.844Z,1740187402.844 [NAL9602](INFO): Packets left to send: 0
2025-02-22T01:23:42.661Z,1740187422.661 [NAL9602](INFO): SBD MO Status=2, MOMSN=13106, MT Status=2, MTMSN=0
2025-02-22T01:23:42.661Z,1740187422.661 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T01:23:47.631Z,1740187427.631 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T01:23:56.416Z,1740187436.416 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T012355
2025-02-22T01:23:56.420Z,1740187436.420 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003393
2025-02-22T01:24:06.995Z,1740187446.995 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250222T002409/Express0025.lzma
2025-02-22T01:24:06.997Z,1740187446.997 [DataOverHttps](INFO): Received command: schedule resume; restart logs