2025-02-22T19:30:53.548Z,1740252653.548 [CommandExec](IMPORTANT): got command restart logs
2025-02-22T19:32:35.390Z,1740252755.390 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-22T19:32:35.391Z,1740252755.391 [Default:CheckIn:C.Wait] Stopped
2025-02-22T19:32:35.391Z,1740252755.391 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T19:32:35.391Z,1740252755.391 [Default:CheckIn:D] Running Loop=1
2025-02-22T19:32:35.781Z,1740252755.781 [Default:CheckIn:D] Stopped
2025-02-22T19:32:35.781Z,1740252755.781 [Default:CheckIn:E] Running Loop=1
2025-02-22T19:32:36.178Z,1740252756.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  5.858898 min 
2025-02-22T19:32:36.178Z,1740252756.178 [Default:CheckIn:E] Stopped
2025-02-22T19:32:36.178Z,1740252756.178 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-22T19:32:36.178Z,1740252756.178 [Default:CheckIn] Stopped
2025-02-22T19:32:36.178Z,1740252756.178 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-22T19:32:36.179Z,1740252756.179 [Default:CheckIn](INFO): Running loop #2
2025-02-22T19:32:36.179Z,1740252756.179 [Default:CheckIn] Running Loop=2
2025-02-22T19:32:36.179Z,1740252756.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-22T19:32:36.179Z,1740252756.179 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-22T19:32:38.174Z,1740252758.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193237.00,A,3647.81216,N,12150.81866,W,0.233,105.68,220225,,,D*75
2025-02-22T19:32:38.177Z,1740252758.177 [NAL9602](INFO): GPS fix at 20250222T193237: (36.796869, -121.846978)
2025-02-22T19:32:38.232Z,1740252758.232 [Default:CheckIn:Read_GPS] Stopped
2025-02-22T19:32:38.232Z,1740252758.232 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-22T19:32:52.304Z,1740252772.304 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250222T171348/Courier0021.lzma
2025-02-22T19:32:52.857Z,1740252772.857 [DataOverHttps](INFO): Moved sent file to Logs/20250222T171348/Courier0021.lzma.bak
2025-02-22T19:32:52.857Z,1740252772.857 [DataOverHttps](INFO): SBD MOMSN=23972751
2025-02-22T19:32:54.085Z,1740252774.085 [NAL9602](INFO): SBD MO Status=0, MOMSN=13197, MT Status=0, MTMSN=0
2025-02-22T19:32:54.085Z,1740252774.085 [NAL9602](INFO): No messages in MT queue
2025-02-22T19:33:17.371Z,1740252797.371 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250222T193053/Courier0000.lzma
2025-02-22T19:33:18.377Z,1740252798.377 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Courier0000.lzma.bak
2025-02-22T19:33:18.377Z,1740252798.377 [DataOverHttps](INFO): SBD MOMSN=23972753
2025-02-22T19:33:24.841Z,1740252804.841 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T19:33:39.427Z,1740252819.427 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20250222T171348/Express0022.lzma
2025-02-22T19:33:40.429Z,1740252820.429 [DataOverHttps](INFO): Moved sent file to Logs/20250222T171348/Express0022.lzma.bak
2025-02-22T19:33:40.429Z,1740252820.429 [DataOverHttps](INFO): SBD MOMSN=23972758
2025-02-22T19:33:57.621Z,1740252837.621 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20250222T193053/Express0001.lzma
2025-02-22T19:33:58.621Z,1740252838.621 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0001.lzma.bak
2025-02-22T19:33:58.621Z,1740252838.621 [DataOverHttps](INFO): SBD MOMSN=23972761
2025-02-22T19:34:22.727Z,1740252862.727 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250222T193053/Express0004.lzma
2025-02-22T19:34:23.729Z,1740252863.729 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0004.lzma.bak
2025-02-22T19:34:23.729Z,1740252863.729 [DataOverHttps](INFO): SBD MOMSN=23972765
2025-02-22T19:34:25.955Z,1740252865.955 [Default:CheckIn:Read_Iridium] Stopped
2025-02-22T19:34:25.956Z,1740252865.956 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-22T19:34:25.956Z,1740252865.956 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-22T19:39:26.632Z,1740253166.632 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-22T19:39:26.632Z,1740253166.632 [Default:CheckIn:C.Wait] Stopped
2025-02-22T19:39:26.633Z,1740253166.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T19:39:26.633Z,1740253166.633 [Default:CheckIn:D] Running Loop=1
2025-02-22T19:39:27.045Z,1740253167.045 [Default:CheckIn:D] Stopped
2025-02-22T19:39:27.045Z,1740253167.045 [Default:CheckIn:E] Running Loop=1
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  12.713305 min 
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn:E] Stopped
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn] Stopped
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn](INFO): Running loop #3
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn] Running Loop=3
2025-02-22T19:39:27.441Z,1740253167.441 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-22T19:39:27.442Z,1740253167.442 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-22T19:39:29.446Z,1740253169.446 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193928.00,A,3647.78354,N,12150.79661,W,0.214,188.51,220225,,,D*75
2025-02-22T19:39:29.448Z,1740253169.448 [NAL9602](INFO): GPS fix at 20250222T193928: (36.796392, -121.846610)
2025-02-22T19:39:29.459Z,1740253169.459 [Default:CheckIn:Read_GPS] Stopped
2025-02-22T19:39:29.459Z,1740253169.459 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-22T19:39:36.759Z,1740253176.759 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250222T193053/Courier0006.lzma
2025-02-22T19:39:37.761Z,1740253177.761 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Courier0006.lzma.bak
2025-02-22T19:39:37.761Z,1740253177.761 [DataOverHttps](INFO): SBD MOMSN=23972767
2025-02-22T19:39:44.814Z,1740253184.814 [NAL9602](INFO): SBD MO Status=0, MOMSN=13198, MT Status=0, MTMSN=0
2025-02-22T19:39:44.814Z,1740253184.814 [NAL9602](INFO): No messages in MT queue
2025-02-22T19:39:53.924Z,1740253193.924 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250222T193053/Express0007.lzma
2025-02-22T19:39:54.925Z,1740253194.925 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0007.lzma.bak
2025-02-22T19:39:54.925Z,1740253194.925 [DataOverHttps](INFO): SBD MOMSN=23972770
2025-02-22T19:39:56.551Z,1740253196.551 [Default:CheckIn:Read_Iridium] Stopped
2025-02-22T19:39:56.551Z,1740253196.551 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-22T19:39:56.551Z,1740253196.551 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-22T19:40:03.403Z,1740253203.403 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T19:40:15.528Z,1740253215.528 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T19:40:16.730Z,1740253216.730 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T19:40:29.659Z,1740253229.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T19:40:34.985Z,1740253234.985 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T194034
2025-02-22T19:40:42.188Z,1740253242.188 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T19:40:42.643Z,1740253242.643 [DataOverHttps](INFO): Received command: configset verticalcontrol.kpdepthbuoy 35 ratio persist;
2025-02-22T19:40:43.151Z,1740253243.151 [CommandExec](IMPORTANT): got command configSet VerticalControl.kpDepthBuoy 35 ratio persist
2025-02-22T19:40:55.110Z,1740253255.110 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T19:41:07.230Z,1740253267.230 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T19:41:20.198Z,1740253280.198 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T19:42:15.845Z,1740253335.845 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T194214
2025-02-22T19:42:23.549Z,1740253343.549 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 8 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree" 25g1t 1 2
2025-02-22T19:42:23.620Z,1740253343.620 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 8 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree" 25g1t 1 2.000000
2025-02-22T19:42:23.620Z,1740253343.620 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=25g1t
2025-02-22T19:42:23.621Z,1740253343.621 [CommandExec](IMPORTANT): Scheduled #79 (#1 of 2 with id='25g1t'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 8 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree", ASAP
2025-02-22T19:42:24.549Z,1740253344.549 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T194223
2025-02-22T19:42:32.495Z,1740253352.495 [DataOverHttps](INFO): Received command: sched asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 25g1t 2 2
2025-02-22T19:42:32.586Z,1740253352.586 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 25g1t 2 2.000000
2025-02-22T19:42:32.587Z,1740253352.587 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=25g1t
2025-02-22T19:42:32.588Z,1740253352.588 [CommandExec](IMPORTANT): Scheduled #80 (#2 of 2 with id='25g1t'): "set keepstation:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2025-02-22T19:42:32.708Z,1740253352.708 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl
2025-02-22T19:42:32.708Z,1740253352.708 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-02-22T19:42:32.708Z,1740253352.708 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-02-22T19:42:32.710Z,1740253352.710 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-02-22T19:42:33.497Z,1740253353.497 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T194232
2025-02-22T19:42:33.530Z,1740253353.530 [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-22T19:42:33.538Z,1740253353.538 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-02-22T19:42:33.540Z,1740253353.540 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-02-22T19:42:33.542Z,1740253353.542 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-02-22T19:42:33.544Z,1740253353.544 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-02-22T19:42:33.547Z,1740253353.547 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-02-22T19:42:33.549Z,1740253353.549 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-02-22T19:42:33.579Z,1740253353.579 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-02-22T19:42:33.581Z,1740253353.581 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-02-22T19:42:33.592Z,1740253353.592 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-02-22T19:42:33.594Z,1740253353.594 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-02-22T19:42:33.601Z,1740253353.601 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-02-22T19:42:33.601Z,1740253353.601 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-22T19:42:33.602Z,1740253353.602 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-22T19:42:33.603Z,1740253353.603 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-22T19:42:33.865Z,1740253353.865 [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-22T19:42:33.870Z,1740253353.870 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-02-22T19:42:33.873Z,1740253353.873 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-22T19:42:33.875Z,1740253353.875 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-22T19:42:33.877Z,1740253353.877 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-02-22T19:42:33.879Z,1740253353.879 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-22T19:42:33.881Z,1740253353.881 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-22T19:42:33.884Z,1740253353.884 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-02-22T19:42:33.886Z,1740253353.886 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-02-22T19:42:33.892Z,1740253353.892 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-22T19:42:33.907Z,1740253353.907 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-22T19:42:33.908Z,1740253353.908 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-22T19:42:33.912Z,1740253353.912 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-22T19:42:34.033Z,1740253354.033 [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-22T19:42:34.037Z,1740253354.037 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-22T19:42:34.040Z,1740253354.040 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-22T19:42:34.043Z,1740253354.043 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-22T19:42:34.045Z,1740253354.045 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-22T19:42:34.046Z,1740253354.046 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-22T19:42:34.052Z,1740253354.052 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-22T19:42:34.058Z,1740253354.058 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-22T19:42:34.065Z,1740253354.065 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-22T19:42:34.065Z,1740253354.065 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-22T19:42:34.066Z,1740253354.066 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-22T19:42:34.118Z,1740253354.118 [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-22T19:42:34.121Z,1740253354.121 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-02-22T19:42:34.131Z,1740253354.131 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-22T19:42:34.134Z,1740253354.134 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-22T19:42:34.135Z,1740253354.135 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-22T19:42:34.136Z,1740253354.136 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-22T19:42:34.307Z,1740253354.307 [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-22T19:42:34.310Z,1740253354.310 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-02-22T19:42:34.312Z,1740253354.312 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-02-22T19:42:34.314Z,1740253354.314 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-02-22T19:42:34.321Z,1740253354.321 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-02-22T19:42:34.323Z,1740253354.323 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-22T19:42:34.330Z,1740253354.330 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-22T19:42:34.361Z,1740253354.361 [keepstation:H.Pitch](DEBUG): Construct.
2025-02-22T19:42:34.374Z,1740253354.374 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-22T19:42:34.377Z,1740253354.377 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2025-02-22T19:42:34.399Z,1740253354.399 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2025-02-22T19:42:34.401Z,1740253354.401 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-22T19:42:34.409Z,1740253354.409 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2025-02-22T19:42:34.414Z,1740253354.414 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2025-02-22T19:42:34.420Z,1740253354.420 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2025-02-22T19:42:34.430Z,1740253354.430 [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-22T19:42:34.430Z,1740253354.430 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation
2025-02-22T19:42:45.172Z,1740253365.172 [DataOverHttps](INFO): Received command: schedule clear; schedule resume
2025-02-22T19:42:46.173Z,1740253366.173 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T194245
2025-02-22T19:42:57.774Z,1740253377.774 [DataOverHttps](INFO): Received command: sched 20250222T2245 "set keepstation.depthdeadband 1 meter"
2025-02-22T19:43:05.972Z,1740253385.972 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 8 hour
2025-02-22T19:43:05.973Z,1740253385.973 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 3 hour
2025-02-22T19:43:05.974Z,1740253385.974 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree
2025-02-22T19:43:05.974Z,1740253385.974 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree
2025-02-22T19:43:05.979Z,1740253385.979 [CommandExec](IMPORTANT): got command schedule clear
2025-02-22T19:43:05.979Z,1740253385.979 [CommandExec](IMPORTANT): Cleared 0 scheduled commands.
2025-02-22T19:43:05.987Z,1740253385.987 [CommandExec](IMPORTANT): got command schedule resume
2025-02-22T19:43:05.987Z,1740253385.987 [CommandExec](IMPORTANT): Scheduling is resumed
2025-02-22T19:43:05.991Z,1740253385.991 [CommandExec](IMPORTANT): got command schedule 20250222T224500 "set keepstation.depthdeadband 1 meter"
2025-02-22T19:43:05.992Z,1740253385.992 [CommandExec](IMPORTANT): Scheduled #81: "set keepstation.depthdeadband 1 meter", AT TIME: 2025-02-22T22:45:00Z
2025-02-22T19:43:24.420Z,1740253404.420 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool
2025-02-22T19:43:24.420Z,1740253404.420 [CommandExec](IMPORTANT): got command run
2025-02-22T19:43:24.422Z,1740253404.422 [CommandExec](IMPORTANT): Running 
2025-02-22T19:43:24.432Z,1740253404.432 [Default] Stopped
2025-02-22T19:43:24.432Z,1740253404.432 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-22T19:43:24.432Z,1740253404.432 [Default:B.GoToSurface] Stopped
2025-02-22T19:43:24.432Z,1740253404.432 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T19:43:24.433Z,1740253404.433 [Default:CheckIn] Stopped
2025-02-22T19:43:24.433Z,1740253404.433 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-22T19:43:24.433Z,1740253404.433 [Default:CheckIn:C.Wait] Stopped
2025-02-22T19:43:24.433Z,1740253404.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-22T19:43:24.433Z,1740253404.433 [MissionManager](IMPORTANT): Started mission keepstation
2025-02-22T19:43:24.433Z,1740253404.433 [keepstation] Running Loop=1
2025-02-22T19:43:24.433Z,1740253404.433 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-02-22T19:43:24.433Z,1740253404.433 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T19:43:24.434Z,1740253404.434 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-02-22T19:43:24.435Z,1740253404.435 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-22T19:43:24.435Z,1740253404.435 [keepstation:PowerOnly] Running Loop=1
2025-02-22T19:43:24.435Z,1740253404.435 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-02-22T19:43:24.435Z,1740253404.435 [keepstation:PowerOnly:A] Running Loop=1
2025-02-22T19:43:24.443Z,1740253404.443 [keepstation:PowerOnly:B] Running Loop=1
2025-02-22T19:43:24.443Z,1740253404.443 [keepstation:PowerOnly:C] Running Loop=1
2025-02-22T19:43:24.443Z,1740253404.443 [keepstation:PowerOnly:D] Running Loop=1
2025-02-22T19:43:24.443Z,1740253404.443 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-02-22T19:43:24.444Z,1740253404.444 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-22T19:43:24.444Z,1740253404.444 [keepstation:H.Pitch] Running Loop=1
2025-02-22T19:43:24.444Z,1740253404.444 [keepstation:H.Pitch](DEBUG): Initialize.
2025-02-22T19:43:24.444Z,1740253404.444 [keepstation:B] Running Loop=1
2025-02-22T19:43:24.444Z,1740253404.444 [keepstation:H.Pitch] Running Loop=1
2025-02-22T19:43:24.445Z,1740253404.445 [keepstation:PowerOnly] Running Loop=1
2025-02-22T19:43:24.445Z,1740253404.445 [keepstation:PowerOnly:D] Stopped
2025-02-22T19:43:24.445Z,1740253404.445 [keepstation:PowerOnly:C] Stopped
2025-02-22T19:43:24.445Z,1740253404.445 [keepstation:PowerOnly:B] Stopped
2025-02-22T19:43:24.445Z,1740253404.445 [keepstation:PowerOnly:A] Stopped
2025-02-22T19:43:24.445Z,1740253404.445 [keepstation:BackseatDriver] Running Loop=1
2025-02-22T19:43:24.446Z,1740253404.446 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2025-02-22T19:43:24.571Z,1740253404.571 [BackseatComponent](INFO): Powering up
2025-02-22T19:43:24.572Z,1740253404.572 [BackseatComponent](INFO): Subscribing to LCM channels.
2025-02-22T19:43:24.884Z,1740253404.884 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-22T19:43:24.884Z,1740253404.884 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-22T19:43:24.885Z,1740253404.885 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-22T19:43:24.885Z,1740253404.885 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-22T19:43:24.885Z,1740253404.885 [keepstation:B] Stopped
2025-02-22T19:43:24.886Z,1740253404.886 [keepstation:D] Running Loop=1
2025-02-22T19:43:25.091Z,1740253405.091 [keepstation:D] Stopped
2025-02-22T19:43:25.092Z,1740253405.092 [keepstation:E] Running Loop=1
2025-02-22T19:43:25.495Z,1740253405.495 [keepstation:E] Stopped
2025-02-22T19:43:25.496Z,1740253405.496 [keepstation:StartingMission] Running Loop=1
2025-02-22T19:43:25.496Z,1740253405.496 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission
2025-02-22T19:43:25.904Z,1740253405.904 [keepstation:NeedComms] Running Loop=1
2025-02-22T19:43:25.904Z,1740253405.904 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T19:43:25.904Z,1740253405.904 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T19:43:25.904Z,1740253405.904 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T19:43:25.905Z,1740253405.905 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T19:43:25.905Z,1740253405.905 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T19:43:25.905Z,1740253405.905 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T19:43:25.906Z,1740253405.906 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T19:43:25.906Z,1740253405.906 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T19:43:25.906Z,1740253405.906 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T19:43:25.907Z,1740253405.907 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T19:43:25.909Z,1740253405.909 [keepstation:NeedComms:A](INFO): last time_fix was:  1740253168.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T19:43:25.909Z,1740253405.909 [keepstation:NeedComms:A] Stopped
2025-02-22T19:43:26.320Z,1740253406.320 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-22T19:43:26.321Z,1740253406.321 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T19:43:26.697Z,1740253406.697 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T19:43:28.302Z,1740253408.302 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194327.00,A,3647.76723,N,12150.78405,W,0.233,145.93,220225,,,D*76
2025-02-22T19:43:28.304Z,1740253408.304 [NAL9602](INFO): GPS fix at 20250222T194327: (36.796121, -121.846401)
2025-02-22T19:43:28.320Z,1740253408.320 [keepstation:NeedComms:C] Stopped
2025-02-22T19:43:28.320Z,1740253408.320 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T19:43:28.748Z,1740253408.748 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-22T19:43:36.655Z,1740253416.655 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250222T193053/Courier0009.lzma
2025-02-22T19:43:37.657Z,1740253417.657 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Courier0009.lzma.bak
2025-02-22T19:43:37.657Z,1740253417.657 [DataOverHttps](INFO): SBD MOMSN=23972783
2025-02-22T19:43:51.787Z,1740253431.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=13199, MT Status=2, MTMSN=0
2025-02-22T19:43:51.788Z,1740253431.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T19:43:55.553Z,1740253435.553 [DataOverHttps](INFO): Sending 742 bytes from file Logs/20250222T193053/Express0010.lzma
2025-02-22T19:43:56.553Z,1740253436.553 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0010.lzma.bak
2025-02-22T19:43:56.553Z,1740253436.553 [DataOverHttps](INFO): SBD MOMSN=23972786
2025-02-22T19:44:03.514Z,1740253443.514 [NAL9602](INFO): SBD MO Status=0, MOMSN=13199, MT Status=0, MTMSN=0
2025-02-22T19:44:03.514Z,1740253443.514 [NAL9602](INFO): No messages in MT queue
2025-02-22T19:44:13.432Z,1740253453.432 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250222T193053/Express0013.lzma
2025-02-22T19:44:14.433Z,1740253454.433 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0013.lzma.bak
2025-02-22T19:44:14.433Z,1740253454.433 [DataOverHttps](INFO): SBD MOMSN=23972816
2025-02-22T19:44:16.076Z,1740253456.076 [keepstation:NeedComms:D] Stopped
2025-02-22T19:44:16.076Z,1740253456.076 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T19:44:16.457Z,1740253456.457 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-22T19:44:18.046Z,1740253458.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194417.00,A,3647.76345,N,12150.78195,W,0.408,238.89,220225,,,D*76
2025-02-22T19:44:18.055Z,1740253458.055 [NAL9602](INFO): GPS fix at 20250222T194417: (36.796058, -121.846366)
2025-02-22T19:44:18.088Z,1740253458.088 [keepstation:NeedComms:E] Stopped
2025-02-22T19:44:18.089Z,1740253458.089 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T19:44:18.089Z,1740253458.089 [keepstation:NeedComms] Stopped
2025-02-22T19:44:18.089Z,1740253458.089 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T19:44:18.089Z,1740253458.089 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T19:44:18.089Z,1740253458.089 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T19:44:18.490Z,1740253458.490 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-02-22T19:44:18.490Z,1740253458.490 [keepstation:StartingMission] Stopped
2025-02-22T19:44:18.490Z,1740253458.490 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-02-22T19:44:18.491Z,1740253458.491 [keepstation:TransitToStation] Running Loop=1
2025-02-22T19:44:18.491Z,1740253458.491 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-02-22T19:44:18.491Z,1740253458.491 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T19:44:18.495Z,1740253458.495 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-22T19:44:18.495Z,1740253458.495 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T19:44:18.495Z,1740253458.495 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-02-22T19:44:18.495Z,1740253458.495 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T19:44:18.495Z,1740253458.495 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-02-22T19:44:18.496Z,1740253458.496 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-02-22T19:44:18.496Z,1740253458.496 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-22T19:44:18.497Z,1740253458.497 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T19:44:18.872Z,1740253458.872 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-22T19:44:18.873Z,1740253458.873 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-22T19:44:18.873Z,1740253458.873 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-22T19:44:18.874Z,1740253458.874 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-22T19:44:32.004Z,1740253472.004 [BackseatComponent](IMPORTANT): IBASE811F-02: Running bioacoustics backseat app. Logging to: 20250222T194430
2025-02-22T19:44:50.772Z,1740253490.772 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T19:45:16.692Z,1740253516.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:45:32.030Z,1740253532.030 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1975
2025-02-22T19:45:47.816Z,1740253547.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:46:18.939Z,1740253578.939 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:46:22.938Z,1740253582.938 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-22T19:46:26.816Z,1740253586.816 [Radio_Surface](INFO): Powering down
2025-02-22T19:46:28.233Z,1740253588.233 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-22T19:46:28.234Z,1740253588.234 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-22T19:46:28.236Z,1740253588.236 [BPC1](INFO): Received data from all battery sticks.
2025-02-22T19:46:50.080Z,1740253610.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:46:51.083Z,1740253611.083 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T19:47:24.220Z,1740253644.220 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.738678
2025-02-22T19:48:00.756Z,1740253680.756 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-02-22T19:48:00.756Z,1740253680.756 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-02-22T19:48:00.756Z,1740253680.756 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-22T19:48:00.757Z,1740253680.757 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-02-22T19:48:00.757Z,1740253680.757 [keepstation:TransitToStation] Stopped
2025-02-22T19:48:00.757Z,1740253680.757 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-02-22T19:48:00.757Z,1740253680.757 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-02-22T19:48:00.757Z,1740253680.757 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-22T19:48:00.757Z,1740253680.757 [keepstation:TransitToStation:B.Pitch] Stopped
2025-02-22T19:48:00.757Z,1740253680.757 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-02-22T19:48:00.758Z,1740253680.758 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-02-22T19:48:00.758Z,1740253680.758 [keepstation:OnStation] Running Loop=1
2025-02-22T19:48:00.758Z,1740253680.758 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-02-22T19:48:01.176Z,1740253681.176 [keepstation:NeedComms] Running Loop=1
2025-02-22T19:48:01.176Z,1740253681.176 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T19:48:01.176Z,1740253681.176 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T19:48:01.176Z,1740253681.176 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T19:48:01.177Z,1740253681.177 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T19:48:01.177Z,1740253681.177 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T19:48:01.177Z,1740253681.177 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T19:48:01.178Z,1740253681.178 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T19:48:01.178Z,1740253681.178 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T19:48:01.178Z,1740253681.178 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T19:48:01.178Z,1740253681.178 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T19:48:01.180Z,1740253681.180 [keepstation:NeedComms:A](INFO): last time_fix was:  1740253457.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T19:48:01.180Z,1740253681.180 [keepstation:NeedComms:A] Stopped
2025-02-22T19:48:56.049Z,1740253736.049 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T19:48:56.699Z,1740253736.699 [Radio_Surface](INFO): Powering up
2025-02-22T19:49:02.624Z,1740253742.624 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-22T19:49:07.041Z,1740253747.041 [NAL9602](INFO): SBD MO Status=0, MOMSN=13200, MT Status=0, MTMSN=0
2025-02-22T19:49:07.041Z,1740253747.041 [NAL9602](INFO): No messages in MT queue
2025-02-22T19:49:08.258Z,1740253748.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194907.00,A,3647.81931,N,12150.80336,W,1.438,69.63,220225,,,D*47
2025-02-22T19:49:08.260Z,1740253748.260 [NAL9602](INFO): GPS fix at 20250222T194907: (36.796988, -121.846723)
2025-02-22T19:49:08.277Z,1740253748.277 [keepstation:NeedComms:C] Stopped
2025-02-22T19:49:08.277Z,1740253748.277 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T19:49:23.693Z,1740253763.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=13201, MT Status=2, MTMSN=0
2025-02-22T19:49:23.693Z,1740253763.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-22T19:49:31.785Z,1740253771.785 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1976
2025-02-22T19:49:32.744Z,1740253772.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:49:54.124Z,1740253794.124 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T19:50:03.868Z,1740253803.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:50:34.995Z,1740253834.995 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:51:06.147Z,1740253866.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:51:28.825Z,1740253888.825 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003938
2025-02-22T19:51:36.515Z,1740253896.515 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250222T193053/Courier0015.lzma
2025-02-22T19:51:37.517Z,1740253897.517 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Courier0015.lzma.bak
2025-02-22T19:51:37.517Z,1740253897.517 [DataOverHttps](INFO): SBD MOMSN=23972820
2025-02-22T19:52:01.001Z,1740253921.001 [DataOverHttps](INFO): Sending 311 bytes from file Logs/20250222T193053/Express0016.lzma
2025-02-22T19:52:02.001Z,1740253922.001 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0016.lzma.bak
2025-02-22T19:52:02.001Z,1740253922.001 [DataOverHttps](INFO): SBD MOMSN=23972823
2025-02-22T19:52:06.040Z,1740253926.040 [keepstation:NeedComms:D] Stopped
2025-02-22T19:52:06.040Z,1740253926.040 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T19:52:08.022Z,1740253928.022 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195207.00,A,3647.82284,N,12150.77500,W,0.564,135.95,220225,,,D*78
2025-02-22T19:52:08.025Z,1740253928.025 [NAL9602](INFO): GPS fix at 20250222T195207: (36.797047, -121.846250)
2025-02-22T19:52:08.074Z,1740253928.074 [keepstation:NeedComms:E] Stopped
2025-02-22T19:52:08.075Z,1740253928.075 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T19:52:08.075Z,1740253928.075 [keepstation:NeedComms] Stopped
2025-02-22T19:52:08.075Z,1740253928.075 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T19:52:08.091Z,1740253928.091 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T19:52:08.091Z,1740253928.091 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T19:52:08.466Z,1740253928.466 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-02-22T19:52:08.466Z,1740253928.466 [keepstation:OnStation] Stopped
2025-02-22T19:52:08.467Z,1740253928.467 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-02-22T19:52:08.467Z,1740253928.467 [keepstation:KeepStation] Running Loop=1
2025-02-22T19:52:08.467Z,1740253928.467 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-02-22T19:52:08.471Z,1740253928.471 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T19:52:08.471Z,1740253928.471 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T19:52:08.471Z,1740253928.471 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-02-22T19:52:08.471Z,1740253928.471 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T19:52:08.472Z,1740253928.472 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-02-22T19:52:08.472Z,1740253928.472 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-02-22T19:52:08.472Z,1740253928.472 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-02-22T19:52:08.857Z,1740253928.857 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-22T19:52:08.857Z,1740253928.857 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-22T19:52:08.858Z,1740253928.858 [keepstation:KeepStation:A] Running Loop=1
2025-02-22T19:52:52.906Z,1740253972.906 [NAL9602](INFO): SBD MO Status=1, MOMSN=13201, MT Status=0, MTMSN=0
2025-02-22T19:52:52.906Z,1740253972.906 [NAL9602](INFO): No messages in MT queue
2025-02-22T19:53:23.608Z,1740254003.608 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T19:53:45.023Z,1740254025.023 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:54:16.148Z,1740254056.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:54:22.308Z,1740254062.308 [Radio_Surface](INFO): Powering down
2025-02-22T19:54:47.272Z,1740254087.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T19:54:48.275Z,1740254088.275 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T19:55:52.544Z,1740254152.544 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735184
2025-02-22T20:47:35.426Z,1740257255.426 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T20:47:47.934Z,1740257267.934 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T20:48:01.267Z,1740257281.267 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T20:48:13.796Z,1740257293.796 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T20:48:26.314Z,1740257306.314 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T20:48:39.646Z,1740257319.646 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T20:48:52.978Z,1740257332.978 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T20:53:58.403Z,1740257638.403 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-22T20:54:00.834Z,1740257640.834 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-22T20:54:00.835Z,1740257640.835 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-22T20:54:00.837Z,1740257640.837 [BPC1](INFO): Received data from all battery sticks.
2025-02-22T21:55:07.536Z,1740261307.536 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T21:55:20.462Z,1740261320.462 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T21:55:32.992Z,1740261332.992 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T21:55:46.318Z,1740261346.318 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T21:55:58.844Z,1740261358.844 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T21:56:12.174Z,1740261372.174 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T21:56:25.104Z,1740261385.104 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T22:01:29.313Z,1740261689.313 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-22T22:01:34.169Z,1740261694.169 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-22T22:01:34.170Z,1740261694.170 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-22T22:01:34.173Z,1740261694.173 [BPC1](INFO): Received data from all battery sticks.
2025-02-22T22:45:05.387Z,1740264305.387 [CommandExec](IMPORTANT): got command set keepstation.DepthDeadband 1 meter
2025-02-22T23:02:08.164Z,1740265328.164 [keepstation:NeedComms] Running Loop=1
2025-02-22T23:02:08.164Z,1740265328.164 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-22T23:02:08.164Z,1740265328.164 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-22T23:02:08.164Z,1740265328.164 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-22T23:02:08.165Z,1740265328.165 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-22T23:02:08.165Z,1740265328.165 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-22T23:02:08.165Z,1740265328.165 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-22T23:02:08.166Z,1740265328.166 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-22T23:02:08.166Z,1740265328.166 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-22T23:02:08.166Z,1740265328.166 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-22T23:02:08.167Z,1740265328.167 [keepstation:NeedComms:A] Running Loop=1
2025-02-22T23:02:08.168Z,1740265328.168 [keepstation:NeedComms:A](INFO): last time_fix was:  1740253927.000000 second since 1970/01/01T00:00:00Z 
2025-02-22T23:02:08.168Z,1740265328.168 [keepstation:NeedComms:A] Stopped
2025-02-22T23:02:41.235Z,1740265361.235 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T23:02:54.072Z,1740265374.072 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T23:03:07.118Z,1740265387.118 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T23:03:20.103Z,1740265400.103 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T23:03:33.385Z,1740265413.385 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T23:03:46.470Z,1740265426.470 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T23:03:50.666Z,1740265430.666 [keepstation:NeedComms:C] Running Loop=1
2025-02-22T23:03:51.372Z,1740265431.372 [Radio_Surface](INFO): Powering up
2025-02-22T23:03:57.375Z,1740265437.375 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-22T23:03:59.131Z,1740265439.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-22T23:04:07.618Z,1740265447.618 [NAL9602](INFO): SBD MO Status=1, MOMSN=13202, MT Status=0, MTMSN=0
2025-02-22T23:04:07.618Z,1740265447.618 [NAL9602](INFO): No messages in MT queue
2025-02-22T23:04:27.504Z,1740265467.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T23:04:58.632Z,1740265498.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T23:05:02.965Z,1740265502.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230502.00,A,3647.60662,N,12151.14075,W,2.080,208.01,220225,,,A*7D
2025-02-22T23:05:02.977Z,1740265502.977 [NAL9602](INFO): GPS fix at 20250222T230502: (36.793444, -121.852346)
2025-02-22T23:05:02.989Z,1740265502.989 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-22T23:05:02.996Z,1740265502.996 [keepstation:NeedComms:C] Stopped
2025-02-22T23:05:02.996Z,1740265502.996 [keepstation:NeedComms:D] Running Loop=1
2025-02-22T23:05:19.233Z,1740265519.233 [NAL9602](INFO): SBD MO Status=1, MOMSN=13203, MT Status=0, MTMSN=0
2025-02-22T23:05:19.291Z,1740265519.291 [NAL9602](INFO): Sent 85 bytes from file Logs/20250222T193053/Courier0018.lzma
2025-02-22T23:05:19.292Z,1740265519.292 [NAL9602](INFO): Packets left to send: 0
2025-02-22T23:05:29.756Z,1740265529.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T23:05:32.453Z,1740265532.453 [NAL9602](INFO): SBD MO Status=1, MOMSN=13204, MT Status=0, MTMSN=0
2025-02-22T23:05:32.515Z,1740265532.515 [NAL9602](INFO): Sent 332 bytes from file Logs/20250222T193053/Express0019.lzma
2025-02-22T23:05:32.516Z,1740265532.516 [NAL9602](INFO): Packets left to send: 1
2025-02-22T23:05:42.838Z,1740265542.838 [NAL9602](INFO): SBD MO Status=1, MOMSN=13205, MT Status=0, MTMSN=0
2025-02-22T23:05:42.892Z,1740265542.892 [NAL9602](INFO): Sent 15 bytes from file Logs/20250222T193053/Express0019.lzma
2025-02-22T23:05:42.892Z,1740265542.892 [NAL9602](INFO): Packets left to send: 0
2025-02-22T23:05:49.627Z,1740265549.627 [NAL9602](INFO): SBD MO Status=0, MOMSN=13206, MT Status=0, MTMSN=0
2025-02-22T23:05:49.720Z,1740265549.720 [keepstation:NeedComms:D] Stopped
2025-02-22T23:05:49.721Z,1740265549.721 [keepstation:NeedComms:E] Running Loop=1
2025-02-22T23:05:52.066Z,1740265552.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230551.00,A,3647.59925,N,12151.13718,W,0.778,176.41,220225,,,A*7A
2025-02-22T23:05:52.068Z,1740265552.068 [NAL9602](INFO): GPS fix at 20250222T230551: (36.793321, -121.852286)
2025-02-22T23:05:52.088Z,1740265552.088 [keepstation:NeedComms:E] Stopped
2025-02-22T23:05:52.089Z,1740265552.089 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-22T23:05:52.089Z,1740265552.089 [keepstation:NeedComms] Stopped
2025-02-22T23:05:52.089Z,1740265552.089 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-22T23:05:52.089Z,1740265552.089 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-22T23:05:52.089Z,1740265552.089 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-22T23:06:00.935Z,1740265560.935 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T23:06:11.125Z,1740265571.125 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003891
2025-02-22T23:06:24.196Z,1740265584.196 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-22T23:07:32.456Z,1740265652.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T23:07:49.112Z,1740265669.112 [Radio_Surface](INFO): Powering down
2025-02-22T23:08:03.592Z,1740265683.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-22T23:08:04.595Z,1740265684.595 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-22T23:09:00.469Z,1740265740.469 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-22T23:09:06.097Z,1740265746.097 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-22T23:09:06.928Z,1740265746.928 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-22T23:09:06.928Z,1740265746.928 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-22T23:09:06.931Z,1740265746.931 [BPC1](INFO): Received data from all battery sticks.
2025-02-22T23:09:40.984Z,1740265780.984 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755142
2025-02-22T23:21:14.156Z,1740266474.156 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-02-22T23:21:14.556Z,1740266474.556 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-02-22T23:21:14.556Z,1740266474.556 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 11.65 mm.
2025-02-22T23:21:14.557Z,1740266474.557 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 30.00 deg, mass-position 11.65 mm.
2025-02-22T23:21:14.558Z,1740266474.558 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 11.65 mm.
2025-02-22T23:25:47.411Z,1740266747.411 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-22T23:25:58.315Z,1740266758.315 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001227
CHAN A1 (24V): -0.000415
CHAN A2 (12V): 0.000442
CHAN A3 (5V): -0.001769
CHAN B0 (3.3V): -0.000808
CHAN B1 (3.15aV): -0.001529
CHAN B2 (3.15bV): -0.001291
CHAN B3 (GND): -0.002295
OPEN: 0.004606
Full Scale: +/- 1 mA
2025-02-23T00:10:13.645Z,1740269413.645 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T00:10:27.384Z,1740269427.384 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T00:10:40.310Z,1740269440.310 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T00:10:53.642Z,1740269453.642 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T00:11:06.570Z,1740269466.570 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T00:11:19.902Z,1740269479.902 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T00:11:32.835Z,1740269492.835 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T00:16:39.462Z,1740269799.462 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-23T00:16:40.292Z,1740269800.292 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-23T00:16:40.292Z,1740269800.292 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-23T00:16:40.295Z,1740269800.295 [BPC1](INFO): Received data from all battery sticks.
2025-02-23T01:17:47.414Z,1740273467.414 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T01:18:00.323Z,1740273480.323 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T01:18:13.250Z,1740273493.250 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T01:18:25.774Z,1740273505.774 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T01:18:38.298Z,1740273518.298 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T01:18:51.226Z,1740273531.226 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T01:19:03.346Z,1740273543.346 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T01:21:17.881Z,1740273677.881 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-02-23T01:24:09.570Z,1740273849.570 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-23T01:24:12.001Z,1740273852.001 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-23T01:24:12.001Z,1740273852.001 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-23T01:24:12.025Z,1740273852.025 [BPC1](INFO): Received data from all battery sticks.
2025-02-23T02:06:06.932Z,1740276366.932 [keepstation:NeedComms] Running Loop=1
2025-02-23T02:06:06.932Z,1740276366.932 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-23T02:06:06.932Z,1740276366.932 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-23T02:06:06.932Z,1740276366.932 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-23T02:06:06.933Z,1740276366.933 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-23T02:06:06.933Z,1740276366.933 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-23T02:06:06.933Z,1740276366.933 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-23T02:06:06.934Z,1740276366.934 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-23T02:06:06.934Z,1740276366.934 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-23T02:06:06.934Z,1740276366.934 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-23T02:06:06.935Z,1740276366.935 [keepstation:NeedComms:A] Running Loop=1
2025-02-23T02:06:06.936Z,1740276366.936 [keepstation:NeedComms:A](INFO): last time_fix was:  1740265551.000000 second since 1970/01/01T00:00:00Z 
2025-02-23T02:06:06.936Z,1740276366.936 [keepstation:NeedComms:A] Stopped
2025-02-23T02:07:47.609Z,1740276467.609 [keepstation:NeedComms:C] Running Loop=1
2025-02-23T02:07:48.228Z,1740276468.228 [Radio_Surface](INFO): Powering up
2025-02-23T02:07:54.115Z,1740276474.115 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-23T02:09:06.134Z,1740276546.134 [NAL9602](INFO): SBD MO Status=0, MOMSN=13207, MT Status=0, MTMSN=0
2025-02-23T02:09:06.134Z,1740276546.134 [NAL9602](INFO): No messages in MT queue
2025-02-23T02:09:21.894Z,1740276561.894 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020921.00,A,3647.58928,N,12151.18103,W,1.711,176.65,230225,,,A*70
2025-02-23T02:09:21.896Z,1740276561.896 [NAL9602](INFO): GPS fix at 20250223T020921: (36.793155, -121.853017)
2025-02-23T02:09:21.909Z,1740276561.909 [UniversalFixResidualReporter](INFO): Fix residual: 84.3 %DT, over the last 778.0 m. Residual distance 655.9 m at bearing 59.0 degrees. Fix at (36.7932, -121.8530) with 67.6 m made good.
2025-02-23T02:09:21.910Z,1740276561.910 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-23T02:09:21.916Z,1740276561.916 [keepstation:NeedComms:C] Stopped
2025-02-23T02:09:21.916Z,1740276561.916 [keepstation:NeedComms:D] Running Loop=1
2025-02-23T02:09:45.002Z,1740276585.002 [NAL9602](INFO): SBD MO Status=2, MOMSN=13208, MT Status=2, MTMSN=0
2025-02-23T02:09:45.002Z,1740276585.002 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-23T02:09:56.977Z,1740276596.977 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003769
2025-02-23T02:10:04.503Z,1740276604.503 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20250222T193053/Courier0021.lzma
2025-02-23T02:10:05.505Z,1740276605.505 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Courier0021.lzma.bak
2025-02-23T02:10:05.505Z,1740276605.505 [DataOverHttps](INFO): SBD MOMSN=23972842
2025-02-23T02:10:13.305Z,1740276613.305 [NAL9602](INFO): SBD MO Status=2, MOMSN=13208, MT Status=2, MTMSN=0
2025-02-23T02:10:13.305Z,1740276613.305 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-23T02:10:21.908Z,1740276621.908 [DataOverHttps](INFO): Sending 404 bytes from file Logs/20250222T193053/Express0022.lzma
2025-02-23T02:10:22.909Z,1740276622.909 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0022.lzma.bak
2025-02-23T02:10:22.909Z,1740276622.909 [DataOverHttps](INFO): SBD MOMSN=23972845
2025-02-23T02:10:24.640Z,1740276624.640 [keepstation:NeedComms:D] Stopped
2025-02-23T02:10:24.640Z,1740276624.640 [keepstation:NeedComms:E] Running Loop=1
2025-02-23T02:10:26.638Z,1740276626.638 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021025.00,A,3647.58620,N,12151.18083,W,1.205,154.01,230225,,,A*70
2025-02-23T02:10:26.640Z,1740276626.640 [NAL9602](INFO): GPS fix at 20250223T021025: (36.793103, -121.853014)
2025-02-23T02:10:26.696Z,1740276626.696 [keepstation:NeedComms:E] Stopped
2025-02-23T02:10:26.697Z,1740276626.697 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-23T02:10:26.697Z,1740276626.697 [keepstation:NeedComms] Stopped
2025-02-23T02:10:26.697Z,1740276626.697 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-23T02:10:26.697Z,1740276626.697 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-23T02:10:26.697Z,1740276626.697 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-23T02:10:59.376Z,1740276659.376 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-23T02:11:25.880Z,1740276685.880 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 11.65 mm (1 active estimators).
2025-02-23T02:11:55.084Z,1740276715.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-23T02:12:26.208Z,1740276746.208 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-23T02:12:26.431Z,1740276746.431 [Radio_Surface](INFO): Powering down
2025-02-23T02:12:27.211Z,1740276747.211 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-23T02:14:09.624Z,1740276849.624 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.783478
2025-02-23T02:25:18.995Z,1740277518.995 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T02:25:31.922Z,1740277531.922 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T02:25:45.265Z,1740277545.265 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T02:25:58.182Z,1740277558.182 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T02:26:11.514Z,1740277571.514 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T02:26:24.848Z,1740277584.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T02:26:37.370Z,1740277597.370 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T02:27:36.367Z,1740277656.367 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-02-23T02:31:40.369Z,1740277900.369 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-23T02:31:45.631Z,1740277905.631 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-23T02:31:45.639Z,1740277905.639 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-23T02:31:45.642Z,1740277905.642 [BPC1](INFO): Received data from all battery sticks.
2025-02-23T03:32:52.759Z,1740281572.759 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T03:33:05.663Z,1740281585.663 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T03:33:18.186Z,1740281598.186 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T03:33:31.115Z,1740281611.115 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T03:33:44.042Z,1740281624.042 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T03:33:56.970Z,1740281636.970 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T03:34:09.092Z,1740281649.092 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-23T03:38:01.804Z,1740281881.804 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-02-23T03:39:15.321Z,1740281955.321 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-23T03:39:17.349Z,1740281957.349 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0.
2025-02-23T03:39:17.349Z,1740281957.349 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-23T03:39:17.352Z,1740281957.352 [BPC1](INFO): Received data from all battery sticks.
2025-02-23T03:43:24.611Z,1740282204.611 [keepstation](INFO): Timed out from 2025-02-22T19:43:24.4Z
2025-02-23T03:43:24.611Z,1740282204.611 [MissionManager](INFO): keepstation is completed.
2025-02-23T03:43:24.611Z,1740282204.611 [MissionManager](INFO): Uninitializing Mission keepstation
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation] Stopped
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes] Stopped
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:BackseatDriver] Stopped
2025-02-23T03:43:24.612Z,1740282204.612 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:PowerOnly] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:PowerOnly:E.Wait] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:H.Pitch] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:KeepStation] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:KeepStation:A] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:KeepStation:B.Pitch] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:KeepStation:C.KeepStation] Stopped
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-02-23T03:43:24.613Z,1740282204.613 [keepstation:KeepStation:D.Wait] Stopped
2025-02-23T03:43:24.614Z,1740282204.614 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-02-23T03:43:24.657Z,1740282204.657 [BackseatComponent](INFO): Sent LCM shutdown request.
2025-02-23T03:43:24.658Z,1740282204.658 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2025-02-23T03:43:25.027Z,1740282205.027 [MissionManager](IMPORTANT): Started mission Default
2025-02-23T03:43:25.027Z,1740282205.027 [Default] Running Loop=1
2025-02-23T03:43:25.028Z,1740282205.028 [Default](DEBUG): Aggregate::initialize Default
2025-02-23T03:43:25.028Z,1740282205.028 [Default:B.GoToSurface] Running Loop=1
2025-02-23T03:43:25.028Z,1740282205.028 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-23T03:43:25.028Z,1740282205.028 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-23T03:43:25.028Z,1740282205.028 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-23T03:43:25.029Z,1740282205.029 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-23T03:43:25.029Z,1740282205.029 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-23T03:43:25.029Z,1740282205.029 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-23T03:43:25.029Z,1740282205.029 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-23T03:43:25.030Z,1740282205.030 [Default:A.Wait] Running Loop=1
2025-02-23T03:43:25.030Z,1740282205.030 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-23T03:43:25.417Z,1740282205.417 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (2 active estimators).
2025-02-23T03:43:25.703Z,1740282205.703 [Radio_Surface](INFO): Powering up
2025-02-23T03:43:31.920Z,1740282211.920 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-23T03:43:38.221Z,1740282218.221 [Default:A.Wait](INFO): Done Waiting.
2025-02-23T03:43:38.221Z,1740282218.221 [Default:A.Wait] Stopped
2025-02-23T03:43:38.221Z,1740282218.221 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-23T03:43:42.672Z,1740282222.672 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2025-02-23T03:43:42.672Z,1740282222.672 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2025-02-23T03:43:42.673Z,1740282222.673 [LcmUniversalReporter](INFO): Deactivating messaging.
2025-02-23T03:44:02.059Z,1740282242.059 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-23T03:44:33.187Z,1740282273.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-23T03:44:36.625Z,1740282276.625 [Default:CheckIn] Running Loop=1
2025-02-23T03:44:36.625Z,1740282276.625 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-23T03:44:36.625Z,1740282276.625 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-23T03:45:03.674Z,1740282303.674 [NAL9602](INFO): SBD MO Status=2, MOMSN=13208, MT Status=2, MTMSN=0
2025-02-23T03:45:03.674Z,1740282303.674 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-23T03:45:04.311Z,1740282304.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-23T03:45:14.595Z,1740282314.595 [NAL9602](INFO): SBD MO Status=1, MOMSN=13208, MT Status=0, MTMSN=0
2025-02-23T03:45:14.595Z,1740282314.595 [NAL9602](INFO): No messages in MT queue
2025-02-23T03:45:15.794Z,1740282315.794 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,034515.00,A,3647.75215,N,12150.92826,W,0.408,331.18,230225,,,A*78
2025-02-23T03:45:15.797Z,1740282315.797 [NAL9602](INFO): GPS fix at 20250223T034515: (36.795869, -121.848804)
2025-02-23T03:45:15.809Z,1740282315.809 [UniversalFixResidualReporter](INFO): Fix residual: 26.7 %DT, over the last 805.1 m. Residual distance 215.0 m at bearing 38.2 degrees. Fix at (36.7959, -121.8488) with 484.9 m made good.
2025-02-23T03:45:15.810Z,1740282315.810 [Default:CheckIn:Read_GPS] Stopped
2025-02-23T03:45:15.810Z,1740282315.810 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-23T03:45:35.451Z,1740282335.451 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-23T03:45:36.500Z,1740282336.500 [NAL9602](INFO): SBD MO Status=1, MOMSN=13209, MT Status=0, MTMSN=0
2025-02-23T03:45:36.547Z,1740282336.547 [NAL9602](INFO): Sent 94 bytes from file Logs/20250222T193053/Courier0024.lzma
2025-02-23T03:45:36.548Z,1740282336.548 [NAL9602](INFO): Packets left to send: 0
2025-02-23T03:45:43.833Z,1740282343.833 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003832
2025-02-23T03:45:51.527Z,1740282351.527 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250222T193053/Express0025.lzma
2025-02-23T03:45:52.529Z,1740282352.529 [DataOverHttps](INFO): Moved sent file to Logs/20250222T193053/Express0025.lzma.bak
2025-02-23T03:45:52.529Z,1740282352.529 [DataOverHttps](INFO): SBD MOMSN=23972851
2025-02-23T03:45:54.164Z,1740282354.164 [Default:CheckIn:Read_Iridium] Stopped
2025-02-23T03:45:54.164Z,1740282354.164 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-23T03:45:54.164Z,1740282354.164 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-23T03:45:54.953Z,1740282354.953 [NAL9602](INFO): SBD MO Status=2, MOMSN=13210, MT Status=2, MTMSN=0
2025-02-23T03:45:54.953Z,1740282354.953 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-23T03:46:25.684Z,1740282385.684 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-23T03:49:05.021Z,1740282545.021 [DataOverHttps](IMPORTANT): SBD MTMSN=20250223T034904
2025-02-23T03:49:15.823Z,1740282555.823 [DataOverHttps](INFO): Received command: restart logs