2022-09-23T18:59:13.170Z,1663959553.170 [Supervisor](DEBUG): Initializing supervisor.
2022-09-23T18:59:13.174Z,1663959553.174 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-09-23T18:59:13.175Z,1663959553.175 [SyncHandler](INFO): Protected caller Thread ID is 7052
2022-09-23T18:59:13.175Z,1663959553.175 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-09-23T18:59:13.176Z,1663959553.176 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-09-23T18:59:13.177Z,1663959553.177 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7053
2022-09-23T18:59:13.181Z,1663959553.181 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-09-23T18:59:13.199Z,1663959553.199 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-09-23T18:59:13.200Z,1663959553.200 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-09-23T18:59:13.200Z,1663959553.200 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7054
2022-09-23T18:59:13.205Z,1663959553.205 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-09-23T18:59:13.206Z,1663959553.206 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-09-23T18:59:13.206Z,1663959553.206 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7055
2022-09-23T18:59:13.208Z,1663959553.208 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-09-23T18:59:13.209Z,1663959553.209 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-09-23T18:59:13.210Z,1663959553.210 [logger ThreadHandler](INFO): Protected caller Thread ID is 7056
2022-09-23T18:59:13.213Z,1663959553.213 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-09-23T18:59:13.214Z,1663959553.214 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-09-23T18:59:13.216Z,1663959553.216 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-09-23T18:59:13.645Z,1663959553.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-09-23T18:59:13.645Z,1663959553.645 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-09-23T18:59:13.865Z,1663959553.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-09-23T18:59:13.866Z,1663959553.866 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-09-23T18:59:13.945Z,1663959553.945 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-09-23T18:59:14.168Z,1663959554.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-09-23T18:59:14.169Z,1663959554.169 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-09-23T18:59:14.246Z,1663959554.246 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-09-23T18:59:14.341Z,1663959554.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-09-23T18:59:14.342Z,1663959554.342 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-09-23T18:59:14.685Z,1663959554.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-09-23T18:59:14.686Z,1663959554.686 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-09-23T18:59:14.818Z,1663959554.818 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-09-23T18:59:14.819Z,1663959554.819 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-09-23T18:59:15.338Z,1663959555.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-09-23T18:59:15.339Z,1663959555.339 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-09-23T18:59:15.531Z,1663959555.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-09-23T18:59:15.531Z,1663959555.531 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-09-23T18:59:15.918Z,1663959555.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-09-23T18:59:15.919Z,1663959555.919 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-09-23T18:59:16.205Z,1663959556.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-09-23T18:59:16.205Z,1663959556.205 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-09-23T18:59:16.315Z,1663959556.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-09-23T18:59:16.315Z,1663959556.315 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-09-23T18:59:17.049Z,1663959557.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-09-23T18:59:17.050Z,1663959557.050 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-09-23T18:59:17.391Z,1663959557.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-09-23T18:59:17.392Z,1663959557.392 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-09-23T18:59:17.618Z,1663959557.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-09-23T18:59:17.620Z,1663959557.620 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2022-09-23T18:59:17.621Z,1663959557.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2022-09-23T18:59:17.819Z,1663959557.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2022-09-23T18:59:17.911Z,1663959557.911 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2022-09-23T18:59:18.260Z,1663959558.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2022-09-23T18:59:18.407Z,1663959558.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2022-09-23T18:59:19.181Z,1663959559.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-09-23T18:59:19.181Z,1663959559.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2022-09-23T18:59:19.285Z,1663959559.285 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2022-09-23T18:59:19.397Z,1663959559.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2022-09-23T18:59:19.506Z,1663959559.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2022-09-23T18:59:19.652Z,1663959559.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2022-09-23T18:59:19.750Z,1663959559.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2022-09-23T18:59:19.843Z,1663959559.843 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-09-23T18:59:19.854Z,1663959559.854 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-09-23T18:59:19.865Z,1663959559.865 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-09-23T18:59:19.865Z,1663959559.865 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-09-23T18:59:19.960Z,1663959559.960 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-09-23T18:59:19.961Z,1663959559.961 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-09-23T18:59:20.012Z,1663959560.012 [VerticalControl](DEBUG): Construct VerticalControl.
2022-09-23T18:59:20.074Z,1663959560.074 [VerticalControl] Loaded
2022-09-23T18:59:20.074Z,1663959560.074 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-09-23T18:59:20.077Z,1663959560.077 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-09-23T18:59:20.119Z,1663959560.119 [HorizontalControl] Loaded
2022-09-23T18:59:20.119Z,1663959560.119 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-09-23T18:59:20.122Z,1663959560.122 [SpeedControl](DEBUG): Construct SpeedControl.
2022-09-23T18:59:20.125Z,1663959560.125 [SpeedControl] Loaded
2022-09-23T18:59:20.125Z,1663959560.125 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-09-23T18:59:20.128Z,1663959560.128 [LoopControl](DEBUG): Construct LoopControl.
2022-09-23T18:59:20.128Z,1663959560.128 [LoopControl] Loaded
2022-09-23T18:59:20.129Z,1663959560.129 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-09-23T18:59:20.129Z,1663959560.129 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-09-23T18:59:20.130Z,1663959560.130 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-09-23T18:59:20.253Z,1663959560.253 [BuoyancyServo] Loaded
2022-09-23T18:59:20.253Z,1663959560.253 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-09-23T18:59:20.274Z,1663959560.274 [ElevatorServo] Loaded
2022-09-23T18:59:20.274Z,1663959560.274 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-09-23T18:59:20.293Z,1663959560.293 [MassServo] Loaded
2022-09-23T18:59:20.294Z,1663959560.294 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-09-23T18:59:20.313Z,1663959560.313 [RudderServo] Loaded
2022-09-23T18:59:20.313Z,1663959560.313 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-09-23T18:59:20.328Z,1663959560.328 [ThrusterHE] Loaded
2022-09-23T18:59:20.329Z,1663959560.329 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2022-09-23T18:59:20.329Z,1663959560.329 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-09-23T18:59:20.330Z,1663959560.330 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-09-23T18:59:20.381Z,1663959560.381 [DepthRateCalculator] Loaded
2022-09-23T18:59:20.381Z,1663959560.381 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-09-23T18:59:20.386Z,1663959560.386 [PitchRateCalculator] Loaded
2022-09-23T18:59:20.386Z,1663959560.386 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-09-23T18:59:20.396Z,1663959560.396 [SpeedCalculator] Loaded
2022-09-23T18:59:20.396Z,1663959560.396 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-09-23T18:59:20.401Z,1663959560.401 [YawRateCalculator] Loaded
2022-09-23T18:59:20.401Z,1663959560.401 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-09-23T18:59:20.420Z,1663959560.420 [ElevatorOffsetCalculator] Loaded
2022-09-23T18:59:20.420Z,1663959560.420 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-09-23T18:59:20.421Z,1663959560.421 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-09-23T18:59:20.421Z,1663959560.421 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-09-23T18:59:20.533Z,1663959560.533 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-09-23T18:59:20.533Z,1663959560.533 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-09-23T18:59:20.548Z,1663959560.548 [NavChart] Loaded
2022-09-23T18:59:20.548Z,1663959560.548 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-09-23T18:59:20.554Z,1663959560.554 [UniversalFixResidualReporter] Loaded
2022-09-23T18:59:20.554Z,1663959560.554 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-09-23T18:59:20.555Z,1663959560.555 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-09-23T18:59:20.555Z,1663959560.555 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-09-23T18:59:20.946Z,1663959560.946 [AHRS_M2] Loaded
2022-09-23T18:59:20.946Z,1663959560.946 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-09-23T18:59:20.982Z,1663959560.982 [BackseatComponent] Loaded
2022-09-23T18:59:20.983Z,1663959560.983 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2022-09-23T18:59:20.984Z,1663959560.984 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2022-09-23T18:59:20.984Z,1663959560.984 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7138
2022-09-23T18:59:20.987Z,1663959560.987 [LcmUniversalReporter] Loaded
2022-09-23T18:59:20.987Z,1663959560.987 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2022-09-23T18:59:21.805Z,1663959561.805 [BPC1] Loaded
2022-09-23T18:59:21.806Z,1663959561.806 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-09-23T18:59:21.877Z,1663959561.877 [DataOverHttps] Loaded
2022-09-23T18:59:21.877Z,1663959561.877 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-09-23T18:59:21.878Z,1663959561.878 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2022-09-23T18:59:21.879Z,1663959561.879 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7139
2022-09-23T18:59:21.900Z,1663959561.900 [Depth_Keller] Loaded
2022-09-23T18:59:21.900Z,1663959561.900 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-09-23T18:59:21.905Z,1663959561.905 [DropWeight] Loaded
2022-09-23T18:59:21.906Z,1663959561.906 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-09-23T18:59:21.969Z,1663959561.969 [NAL9602] Loaded
2022-09-23T18:59:21.969Z,1663959561.969 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-09-23T18:59:21.000Z,1663959562.000 [Onboard] Loaded
2022-09-23T18:59:22.000Z,1663959562.000 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-09-23T18:59:22.001Z,1663959562.001 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2022-09-23T18:59:22.001Z,1663959562.001 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7140
2022-09-23T18:59:22.014Z,1663959562.014 [Power24vConverter] Loaded
2022-09-23T18:59:22.014Z,1663959562.014 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-09-23T18:59:22.028Z,1663959562.028 [Radio_Surface] Loaded
2022-09-23T18:59:22.028Z,1663959562.028 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-09-23T18:59:22.029Z,1663959562.029 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2022-09-23T18:59:22.029Z,1663959562.029 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7141
2022-09-23T18:59:22.123Z,1663959562.123 [DAT] Loaded
2022-09-23T18:59:22.124Z,1663959562.124 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2022-09-23T18:59:22.125Z,1663959562.125 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409904E0
2022-09-23T18:59:22.125Z,1663959562.125 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7142
2022-09-23T18:59:22.126Z,1663959562.126 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-09-23T18:59:22.126Z,1663959562.126 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-09-23T18:59:22.181Z,1663959562.181 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-09-23T18:59:22.182Z,1663959562.182 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-09-23T18:59:22.329Z,1663959562.329 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-09-23T18:59:22.329Z,1663959562.329 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-09-23T18:59:22.356Z,1663959562.356 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-09-23T18:59:22.357Z,1663959562.357 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-09-23T18:59:22.530Z,1663959562.530 [CTD_Seabird] Loaded
2022-09-23T18:59:22.531Z,1663959562.531 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-09-23T18:59:22.532Z,1663959562.532 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABB4E0
2022-09-23T18:59:22.532Z,1663959562.532 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7143
2022-09-23T18:59:22.553Z,1663959562.553 [PAR_Licor] Loaded
2022-09-23T18:59:22.553Z,1663959562.553 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-09-23T18:59:22.585Z,1663959562.585 [WetLabsBB2FL] Loaded
2022-09-23T18:59:22.585Z,1663959562.585 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-09-23T18:59:22.586Z,1663959562.586 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0
2022-09-23T18:59:22.587Z,1663959562.587 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7144
2022-09-23T18:59:22.587Z,1663959562.587 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-09-23T18:59:22.588Z,1663959562.588 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-09-23T18:59:22.896Z,1663959562.896 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-09-23T18:59:22.896Z,1663959562.896 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-09-23T18:59:23.175Z,1663959563.175 [SBIT](DEBUG): Construct Startup Built In Test.
2022-09-23T18:59:23.231Z,1663959563.231 [SBIT] Loaded
2022-09-23T18:59:23.231Z,1663959563.231 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-09-23T18:59:23.234Z,1663959563.234 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-09-23T18:59:23.287Z,1663959563.287 [IBIT] Loaded
2022-09-23T18:59:23.287Z,1663959563.287 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-09-23T18:59:23.293Z,1663959563.293 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-09-23T18:59:23.541Z,1663959563.541 [CBIT] Loaded
2022-09-23T18:59:23.543Z,1663959563.543 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-09-23T18:59:23.545Z,1663959563.545 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-09-23T18:59:23.551Z,1663959563.551 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-09-23T18:59:23.554Z,1663959563.554 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-09-23T18:59:23.565Z,1663959563.565 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-09-23T18:59:23.566Z,1663959563.566 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0
2022-09-23T18:59:23.567Z,1663959563.567 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7145
2022-09-23T18:59:23.571Z,1663959563.571 [Supervisor](INFO): Main Thread ID is 7051
2022-09-23T18:59:23.571Z,1663959563.571 [Supervisor](DEBUG): Running supervisor.
2022-09-23T18:59:23.572Z,1663959563.572 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7146
2022-09-23T18:59:23.573Z,1663959563.573 [CommandExec](INFO): Initializing the command executive.
2022-09-23T18:59:23.574Z,1663959563.574 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7147
2022-09-23T18:59:23.576Z,1663959563.576 [controlThread ThreadHandler](INFO): Handler Thread ID is 7148
2022-09-23T18:59:23.577Z,1663959563.577 [controlThread](DEBUG): Initializing ControlThread
2022-09-23T18:59:23.577Z,1663959563.577 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-09-23T18:59:23.579Z,1663959563.579 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-09-23T18:59:23.580Z,1663959563.580 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-09-23T18:59:23.580Z,1663959563.580 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-09-23T18:59:23.582Z,1663959563.582 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-09-23T18:59:23.582Z,1663959563.582 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-09-23T18:59:23.583Z,1663959563.583 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-09-23T18:59:23.583Z,1663959563.583 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-09-23T18:59:23.583Z,1663959563.583 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-09-23T18:59:23.584Z,1663959563.584 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-09-23T18:59:23.585Z,1663959563.585 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-09-23T18:59:23.594Z,1663959563.594 [SBIT](INFO): Initialize SBIT Component.
2022-09-23T18:59:23.595Z,1663959563.595 [SBIT](IMPORTANT): git: 2022-08-02
2022-09-23T18:59:23.595Z,1663959563.595 [SBIT](INFO): git hash: f84e5919489b70ff94ab72b61eefa777bac519a0
2022-09-23T18:59:23.595Z,1663959563.595 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-09-23T18:59:23.596Z,1663959563.596 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-09-23T18:59:23.597Z,1663959563.597 [SBIT](INFO): Beginning SBIT in 61.000000 seconds.
2022-09-23T18:59:23.598Z,1663959563.598 [IBIT](INFO): Initialize IBIT Component.
2022-09-23T18:59:23.599Z,1663959563.599 [CBIT](DEBUG): Initialize CBIT Component.
2022-09-23T18:59:23.600Z,1663959563.600 [logger ThreadHandler](INFO): Handler Thread ID is 7149
2022-09-23T18:59:23.610Z,1663959563.610 [CBIT](DEBUG): Initialized mux pins.
2022-09-23T18:59:23.610Z,1663959563.610 [CBIT](DEBUG): Initializing the watchdog timer.
2022-09-23T18:59:23.618Z,1663959563.618 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7150
2022-09-23T18:59:23.630Z,1663959563.630 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7151
2022-09-23T18:59:23.631Z,1663959563.631 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-09-23T18:59:23.634Z,1663959563.634 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-09-23T18:59:23.635Z,1663959563.635 [CBIT](DEBUG): Initializing heartbeat.
2022-09-23T18:59:23.642Z,1663959563.642 [Onboard ThreadHandler](INFO): Handler Thread ID is 7152
2022-09-23T18:59:23.660Z,1663959563.660 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7153
2022-09-23T18:59:23.678Z,1663959563.678 [DAT ThreadHandler](INFO): Handler Thread ID is 7154
2022-09-23T18:59:23.679Z,1663959563.679 [DAT](INFO): Powering up
2022-09-23T18:59:23.679Z,1663959563.679 [DAT](DEBUG): Initializing DAT.
2022-09-23T18:59:23.683Z,1663959563.683 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7156
2022-09-23T18:59:23.684Z,1663959563.684 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-09-23T18:59:23.687Z,1663959563.687 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7158
2022-09-23T18:59:23.690Z,1663959563.690 [WetLabsBB2FL](INFO): Powering up
2022-09-23T18:59:23.691Z,1663959563.691 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7160
2022-09-23T18:59:23.698Z,1663959563.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2022-09-23T18:59:23.699Z,1663959563.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2022-09-23T18:59:23.699Z,1663959563.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2022-09-23T18:59:23.699Z,1663959563.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2022-09-23T18:59:23.699Z,1663959563.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2022-09-23T18:59:23.699Z,1663959563.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2022-09-23T18:59:23.699Z,1663959563.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2022-09-23T18:59:23.699Z,1663959563.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2022-09-23T18:59:23.700Z,1663959563.700 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2022-09-23T18:59:23.700Z,1663959563.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2022-09-23T18:59:23.700Z,1663959563.700 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2022-09-23T18:59:23.700Z,1663959563.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2022-09-23T18:59:23.700Z,1663959563.700 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2022-09-23T18:59:23.700Z,1663959563.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2022-09-23T18:59:23.701Z,1663959563.701 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DD.000
2022-09-23T18:59:23.701Z,1663959563.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DD.000
2022-09-23T18:59:23.701Z,1663959563.701 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DE.000
2022-09-23T18:59:23.701Z,1663959563.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DE.000
2022-09-23T18:59:23.701Z,1663959563.701 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DF.000
2022-09-23T18:59:23.701Z,1663959563.701 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DF.000
2022-09-23T18:59:23.702Z,1663959563.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DG.000
2022-09-23T18:59:23.702Z,1663959563.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DG.000
2022-09-23T18:59:23.702Z,1663959563.702 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1DH.000
2022-09-23T18:59:23.702Z,1663959563.702 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1DH.000
2022-09-23T18:59:23.703Z,1663959563.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CC.000
2022-09-23T18:59:23.703Z,1663959563.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CC.000
2022-09-23T18:59:23.703Z,1663959563.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CD.000
2022-09-23T18:59:23.703Z,1663959563.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CD.000
2022-09-23T18:59:23.703Z,1663959563.703 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MN1CE.000
2022-09-23T18:59:23.703Z,1663959563.703 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MN1CE.000
2022-09-23T18:59:23.704Z,1663959563.704 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QE.000
2022-09-23T18:59:23.704Z,1663959563.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QE.000
2022-09-23T18:59:23.704Z,1663959563.704 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QF.000
2022-09-23T18:59:23.704Z,1663959563.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QF.000
2022-09-23T18:59:23.704Z,1663959563.704 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1QG.000
2022-09-23T18:59:23.704Z,1663959563.704 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1QG.000
2022-09-23T18:59:23.705Z,1663959563.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PB.000
2022-09-23T18:59:23.705Z,1663959563.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PB.000
2022-09-23T18:59:23.705Z,1663959563.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PC.000
2022-09-23T18:59:23.705Z,1663959563.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PC.000
2022-09-23T18:59:23.705Z,1663959563.705 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PD.000
2022-09-23T18:59:23.705Z,1663959563.705 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PD.000
2022-09-23T18:59:23.706Z,1663959563.706 [CBIT](DEBUG): Deactivating GF circuits.
2022-09-23T18:59:23.706Z,1663959563.706 [CBIT](DEBUG): Deactivating emergency mode.
2022-09-23T18:59:23.706Z,1663959563.706 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PE.000
2022-09-23T18:59:23.706Z,1663959563.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PE.000
2022-09-23T18:59:23.707Z,1663959563.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PF.000
2022-09-23T18:59:23.707Z,1663959563.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PF.000
2022-09-23T18:59:23.707Z,1663959563.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4WI1PG.000
2022-09-23T18:59:23.707Z,1663959563.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4WI1PG.000
2022-09-23T18:59:23.707Z,1663959563.707 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5WI22M.000
2022-09-23T18:59:23.708Z,1663959563.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5WI22M.000
2022-09-23T18:59:23.742Z,1663959563.742 [CBIT](DEBUG): Backplane powered.
2022-09-23T18:59:23.743Z,1663959563.743 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-09-23T18:59:23.755Z,1663959563.755 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-09-23T18:59:23.779Z,1663959563.779 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2022-09-23T18:59:23.780Z,1663959563.780 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-09-23T18:59:23.857Z,1663959563.857 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-09-23T18:59:23.858Z,1663959563.858 [Default:A.Wait](DEBUG): Construct Wait.
2022-09-23T18:59:23.860Z,1663959563.860 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-09-23T18:59:23.912Z,1663959563.912 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-09-23T18:59:23.914Z,1663959563.914 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-09-23T18:59:23.933Z,1663959563.933 [Default:E.Execute](DEBUG): Construct Execute.
2022-09-23T18:59:23.936Z,1663959563.936 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2022-09-23T18:59:23.950Z,1663959563.950 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2022-09-23T18:59:23.962Z,1663959563.962 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-09-23T18:59:24.029Z,1663959564.029 [Power24vConverter](INFO): Powering up.
2022-09-23T18:59:24.066Z,1663959564.066 [Radio_Surface](INFO): Powering up
2022-09-23T18:59:24.112Z,1663959564.112 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-09-23T18:59:24.118Z,1663959564.118 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-09-23T18:59:24.119Z,1663959564.119 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-09-23T18:59:24.126Z,1663959564.126 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-09-23T18:59:24.127Z,1663959564.127 [MassServo](DEBUG): Initializing EZServoServo.
2022-09-23T18:59:24.134Z,1663959564.134 [MassServo](DEBUG): Initializing MassServo.
2022-09-23T18:59:24.135Z,1663959564.135 [RudderServo](DEBUG): Initializing EZServoServo.
2022-09-23T18:59:24.142Z,1663959564.142 [RudderServo](DEBUG): Initializing RudderServo.
2022-09-23T18:59:24.143Z,1663959564.143 [ThrusterHE](DEBUG): Initializing EZServoServo.
2022-09-23T18:59:24.150Z,1663959564.150 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2022-09-23T18:59:25.098Z,1663959565.098 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-09-23T18:59:25.098Z,1663959565.098 [RudderServo](FAULT): Rudder failed to initialize
2022-09-23T18:59:25.098Z,1663959565.098 [RudderServo] Communications Fault, FailCount= 1
2022-09-23T18:59:25.098Z,1663959565.098 [RudderServo](ERROR): Communications Fault
2022-09-23T18:59:25.102Z,1663959565.102 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-09-23T18:59:25.359Z,1663959565.359 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-09-23T18:59:25.359Z,1663959565.359 [RudderServo](INFO): Powering down
2022-09-23T18:59:25.798Z,1663959565.798 [WetLabsBB2FL](INFO): Powering down
2022-09-23T18:59:26.027Z,1663959566.027 [RudderServo](DEBUG): Initializing EZServoServo.
2022-09-23T18:59:26.147Z,1663959566.147 [RudderServo](DEBUG): Initializing RudderServo.
2022-09-23T18:59:26.151Z,1663959566.151 [CBIT](INFO): Clearing failed state for component RudderServo
2022-09-23T18:59:26.151Z,1663959566.151 [RudderServo] No Fault, FailCount= 1
2022-09-23T18:59:29.974Z,1663959569.974 [ThrusterHE](ERROR): Zero Speed Commanded.
2022-09-23T18:59:30.715Z,1663959570.715 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-09-23T18:59:33.755Z,1663959573.755 [CBIT](CRITICAL): Environmental Failure. Press:14.895909 PSI. Humidity:49%. Temp:24 C. ABORTING MISSION
2022-09-23T18:59:33.755Z,1663959573.755 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2022-09-23T18:59:34.164Z,1663959574.164 [CommandExec](FAULT): Scheduling is paused
2022-09-23T18:59:34.165Z,1663959574.165 [CBIT](INFO): Critical error at 20220923T185933
2022-09-23T18:59:37.736Z,1663959577.736 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004698
2022-09-23T18:59:38.548Z,1663959578.548 [DAT](INFO): commRate: 800
2022-09-23T18:59:40.614Z,1663959580.614 [DAT](INFO): entering command mode
2022-09-23T18:59:40.815Z,1663959580.815 [DAT](INFO): setting verbose to 3
2022-09-23T18:59:41.068Z,1663959581.068 [DAT](INFO): set verbose to 3
2022-09-23T18:59:41.068Z,1663959581.068 [DAT](INFO): setting DatVerbose to 27440
2022-09-23T18:59:41.320Z,1663959581.320 [DAT](INFO): set DatVerbose to 27440
2022-09-23T18:59:41.320Z,1663959581.320 [DAT](INFO): setting transmit power to 8
2022-09-23T18:59:41.571Z,1663959581.571 [DAT](INFO): set transmit power to 8
2022-09-23T18:59:41.572Z,1663959581.572 [DAT](INFO): setting local address to 5
2022-09-23T18:59:41.824Z,1663959581.824 [DAT](INFO): set local address to 5
2022-09-23T18:59:41.825Z,1663959581.825 [DAT](INFO): Setting time to: 18:59:41 And date to:9/23/2022
2022-09-23T18:59:42.076Z,1663959582.076 [DAT](INFO): Local DAT time set to Fri Sep 23, 2022  18:59:41  

2022-09-23T18:59:52.271Z,1663959592.271 [NAL9602](INFO): Powering up NAL9602
2022-09-23T19:00:03.184Z,1663959603.184 [NAL9602](INFO): NAL9602 initialized
2022-09-23T19:00:08.919Z,1663959608.919 [CommandExec](IMPORTANT): got command ! 1 > /dev/loadC3
2022-09-23T19:00:25.036Z,1663959625.036 [SBIT](IMPORTANT): Beginning Startup BIT
2022-09-23T19:00:25.072Z,1663959625.072 [CBIT](IMPORTANT): Beginning ground fault scan
2022-09-23T19:00:35.929Z,1663959635.929 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.020348
CHAN A1 (24V): -0.007798
CHAN A2 (12V): -0.004230
CHAN A3 (5V): -0.002757
CHAN B0 (3.3V): -0.001061
CHAN B1 (3.15aV): -0.001038
CHAN B2 (3.15bV): -0.001361
CHAN B3 (GND): 0.000753
OPEN: 0.004855
Full Scale: +/- 1 mA
2022-09-23T19:00:40.781Z,1663959640.781 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadC3
2022-09-23T19:01:19.162Z,1663959679.162 [SBIT](IMPORTANT): SBIT PASSED
2022-09-23T19:01:19.163Z,1663959679.163 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-09-23T19:01:19.163Z,1663959679.163 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): BackseatComponent.alwaysOn=0 bool;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=30 ampere_hour;

2022-09-23T19:01:19.164Z,1663959679.164 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2022-09-23T19:01:19.165Z,1663959679.165 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.282117 cubic_centimeter;

2022-09-23T19:01:19.165Z,1663959679.165 [SBIT](IMPORTANT): VerticalControl.massDefault=10.355450 millimeter;

2022-09-23T19:01:19.567Z,1663959679.567 [MissionManager](IMPORTANT): Started mission Startup
2022-09-23T19:01:19.568Z,1663959679.568 [Startup] Running Loop=1
2022-09-23T19:01:19.568Z,1663959679.568 [Startup](DEBUG): Aggregate::initialize Startup
2022-09-23T19:01:19.568Z,1663959679.568 [Startup:A.GoToSurface] Running Loop=1
2022-09-23T19:01:19.568Z,1663959679.568 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-09-23T19:01:19.569Z,1663959679.569 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-09-23T19:01:19.569Z,1663959679.569 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-09-23T19:01:19.569Z,1663959679.569 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-09-23T19:01:19.570Z,1663959679.570 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-09-23T19:01:19.570Z,1663959679.570 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-09-23T19:01:19.572Z,1663959679.572 [Startup:StartupSatComms] Running Loop=1
2022-09-23T19:01:19.572Z,1663959679.572 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-09-23T19:01:19.572Z,1663959679.572 [Startup:StartupSatComms:A] Running Loop=1
2022-09-23T19:01:19.973Z,1663959679.973 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-09-23T19:01:51.858Z,1663959711.858 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:02:05.184Z,1663959725.184 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:02:18.517Z,1663959738.517 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:02:19.745Z,1663959739.745 [Startup:StartupSatComms:A](INFO): Timed out from 2022-09-23T19:01:19.6Z
2022-09-23T19:02:19.745Z,1663959739.745 [Startup:StartupSatComms:A] Stopped
2022-09-23T19:02:19.745Z,1663959739.745 [Startup:StartupSatComms:B] Running Loop=1
2022-09-23T19:02:20.161Z,1663959740.161 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-09-23T19:02:23.830Z,1663959743.830 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-09-23T19:02:23.830Z,1663959743.830 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:02:23.845Z,1663959743.845 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:02:24.223Z,1663959744.223 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:02:24.223Z,1663959744.223 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-09-23T19:02:26.601Z,1663959746.601 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220923T003317/Courier0022.lzma
2022-09-23T19:02:27.604Z,1663959747.604 [DataOverHttps](INFO): Moved sent file to Logs/20220923T003317/Courier0022.lzma.bak
2022-09-23T19:02:27.604Z,1663959747.604 [DataOverHttps](INFO): SBD MOMSN=17197620
2022-09-23T19:02:32.253Z,1663959752.253 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:02:43.312Z,1663959763.312 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20220923T185913/Courier0000.lzma
2022-09-23T19:02:44.308Z,1663959764.308 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0000.lzma.bak
2022-09-23T19:02:44.308Z,1663959764.308 [DataOverHttps](INFO): SBD MOMSN=17197622
2022-09-23T19:02:45.585Z,1663959765.585 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:02:58.928Z,1663959778.928 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:03:01.273Z,1663959781.273 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20220923T003317/Express0023.lzma
2022-09-23T19:03:02.276Z,1663959782.276 [DataOverHttps](INFO): Moved sent file to Logs/20220923T003317/Express0023.lzma.bak
2022-09-23T19:03:02.276Z,1663959782.276 [DataOverHttps](INFO): SBD MOMSN=17197626
2022-09-23T19:03:12.248Z,1663959792.248 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:03:13.466Z,1663959793.466 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2022-09-23T19:03:19.811Z,1663959799.811 [DataOverHttps](INFO): Sending 1097 bytes from file Logs/20220923T185913/Express0001.lzma
2022-09-23T19:03:19.975Z,1663959799.975 [Startup:StartupSatComms:B](INFO): Timed out from 2022-09-23T19:02:19.7Z
2022-09-23T19:03:19.975Z,1663959799.975 [Startup:StartupSatComms:B] Stopped
2022-09-23T19:03:19.975Z,1663959799.975 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-09-23T19:03:19.976Z,1663959799.976 [Startup:StartupSatComms] Stopped
2022-09-23T19:03:19.976Z,1663959799.976 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-09-23T19:03:19.976Z,1663959799.976 [Startup](INFO): Completed Startup
2022-09-23T19:03:19.977Z,1663959799.977 [MissionManager](INFO): Startup is completed.
2022-09-23T19:03:19.977Z,1663959799.977 [MissionManager](INFO): Uninitializing Mission Startup
2022-09-23T19:03:19.977Z,1663959799.977 [Startup] Stopped
2022-09-23T19:03:19.977Z,1663959799.977 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-09-23T19:03:19.977Z,1663959799.977 [Startup:A.GoToSurface] Stopped
2022-09-23T19:03:19.977Z,1663959799.977 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-09-23T19:03:20.366Z,1663959800.366 [MissionManager](IMPORTANT): Started mission Default
2022-09-23T19:03:20.366Z,1663959800.366 [Default] Running Loop=1
2022-09-23T19:03:20.367Z,1663959800.367 [Default](DEBUG): Aggregate::initialize Default
2022-09-23T19:03:20.367Z,1663959800.367 [Default:B.GoToSurface] Running Loop=1
2022-09-23T19:03:20.367Z,1663959800.367 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-09-23T19:03:20.367Z,1663959800.367 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-09-23T19:03:20.367Z,1663959800.367 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-09-23T19:03:20.368Z,1663959800.368 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-09-23T19:03:20.368Z,1663959800.368 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-09-23T19:03:20.368Z,1663959800.368 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-09-23T19:03:20.368Z,1663959800.368 [Default:A.Wait] Running Loop=1
2022-09-23T19:03:20.369Z,1663959800.369 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-09-23T19:03:20.812Z,1663959800.812 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0001.lzma.bak
2022-09-23T19:03:20.812Z,1663959800.812 [DataOverHttps](INFO): SBD MOMSN=17197631
2022-09-23T19:03:25.176Z,1663959805.176 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:03:33.675Z,1663959813.675 [Default:A.Wait](INFO): Done Waiting.
2022-09-23T19:03:33.675Z,1663959813.675 [Default:A.Wait] Stopped
2022-09-23T19:03:33.675Z,1663959813.675 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T19:03:34.085Z,1663959814.085 [Default:CheckIn] Running Loop=1
2022-09-23T19:03:34.085Z,1663959814.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T19:03:34.085Z,1663959814.085 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T19:03:34.496Z,1663959814.496 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-09-23T19:03:38.104Z,1663959818.104 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:03:51.443Z,1663959831.443 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:04:04.770Z,1663959844.770 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:04:18.102Z,1663959858.102 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T19:05:06.581Z,1663959906.581 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T19:05:24.796Z,1663959924.796 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-09-23T19:05:24.796Z,1663959924.796 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:05:24.831Z,1663959924.831 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:05:25.216Z,1663959925.216 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:05:25.217Z,1663959925.217 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-09-23T19:06:51.626Z,1663960011.626 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2022-09-23T19:06:51.629Z,1663960011.629 [BPC1](INFO): Received data from all battery sticks.
2022-09-23T19:08:25.791Z,1663960105.791 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-09-23T19:08:25.791Z,1663960105.791 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:08:25.824Z,1663960105.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:08:26.201Z,1663960106.201 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:08:26.201Z,1663960106.201 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-09-23T19:08:34.272Z,1663960114.272 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-09-23T19:03:34.1Z
2022-09-23T19:08:34.272Z,1663960114.272 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T19:08:34.272Z,1663960114.272 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T19:08:34.666Z,1663960114.666 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-09-23T19:08:41.661Z,1663960121.661 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20220923T185913/Courier0004.lzma
2022-09-23T19:08:42.667Z,1663960122.667 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0004.lzma.bak
2022-09-23T19:08:42.667Z,1663960122.667 [DataOverHttps](INFO): SBD MOMSN=17197657
2022-09-23T19:08:59.425Z,1663960139.425 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20220923T185913/Express0005.lzma
2022-09-23T19:09:00.428Z,1663960140.428 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0005.lzma.bak
2022-09-23T19:09:00.428Z,1663960140.428 [DataOverHttps](INFO): SBD MOMSN=17197659
2022-09-23T19:09:02.951Z,1663960142.951 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T19:09:02.951Z,1663960142.951 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T19:09:02.952Z,1663960142.952 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T19:10:05.951Z,1663960205.951 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-09-23T19:10:05.951Z,1663960205.951 [NAL9602] Data Fault, FailCount= 1
2022-09-23T19:10:05.952Z,1663960205.952 [NAL9602](ERROR): Data Fault
2022-09-23T19:10:05.968Z,1663960205.968 [CBIT](ERROR): Data Fault in component: NAL9602
2022-09-23T19:10:06.360Z,1663960206.360 [NAL9602](INFO): Powering down
2022-09-23T19:10:07.179Z,1663960207.179 [CBIT](INFO): Clearing failed state for component NAL9602
2022-09-23T19:10:07.179Z,1663960207.179 [NAL9602] No Fault, FailCount= 1
2022-09-23T19:10:36.655Z,1663960236.655 [NAL9602](INFO): Powering up NAL9602
2022-09-23T19:10:47.563Z,1663960247.563 [NAL9602](INFO): NAL9602 initialized
2022-09-23T19:11:26.763Z,1663960286.763 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-09-23T19:11:26.763Z,1663960286.763 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:11:26.773Z,1663960286.773 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:11:27.184Z,1663960287.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:11:27.185Z,1663960287.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-09-23T19:14:03.532Z,1663960443.532 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T19:14:03.532Z,1663960443.532 [Default:CheckIn:C.Wait] Stopped
2022-09-23T19:14:03.532Z,1663960443.532 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T19:14:03.532Z,1663960443.532 [Default:CheckIn:D] Running Loop=1
2022-09-23T19:14:03.944Z,1663960443.944 [Default:CheckIn:D] Stopped
2022-09-23T19:14:03.944Z,1663960443.944 [Default:CheckIn:E] Running Loop=1
2022-09-23T19:14:04.340Z,1663960444.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.726286 min 
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn:E] Stopped
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn] Stopped
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn](INFO): Running loop #2
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn] Running Loop=2
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T19:14:04.341Z,1663960444.341 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T19:14:27.750Z,1663960467.750 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-09-23T19:14:27.750Z,1663960467.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:14:27.760Z,1663960467.760 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:14:28.184Z,1663960468.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:14:28.185Z,1663960468.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-09-23T19:17:28.754Z,1663960648.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-09-23T19:17:28.754Z,1663960648.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:17:28.797Z,1663960648.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:17:29.196Z,1663960649.196 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:17:29.196Z,1663960649.196 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-09-23T19:19:04.498Z,1663960744.498 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-09-23T19:14:04.3Z
2022-09-23T19:19:04.498Z,1663960744.498 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T19:19:04.498Z,1663960744.498 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T19:19:11.997Z,1663960751.997 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220923T185913/Courier0007.lzma
2022-09-23T19:19:13.008Z,1663960753.008 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0007.lzma.bak
2022-09-23T19:19:13.008Z,1663960753.008 [DataOverHttps](INFO): SBD MOMSN=17197674
2022-09-23T19:19:29.687Z,1663960769.687 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20220923T185913/Express0008.lzma
2022-09-23T19:19:30.688Z,1663960770.688 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0008.lzma.bak
2022-09-23T19:19:30.688Z,1663960770.688 [DataOverHttps](INFO): SBD MOMSN=17197676
2022-09-23T19:19:33.176Z,1663960773.176 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T19:19:33.176Z,1663960773.176 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T19:19:33.176Z,1663960773.176 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T19:20:29.754Z,1663960829.754 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-09-23T19:20:29.754Z,1663960829.754 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:20:29.764Z,1663960829.764 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:20:30.160Z,1663960830.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:20:30.161Z,1663960830.161 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-09-23T19:20:50.336Z,1663960850.336 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-09-23T19:20:50.336Z,1663960850.336 [NAL9602] Data Fault, FailCount= 2
2022-09-23T19:20:50.336Z,1663960850.336 [NAL9602](ERROR): Data Fault
2022-09-23T19:20:50.353Z,1663960850.353 [CBIT](ERROR): Data Fault in component: NAL9602
2022-09-23T19:20:50.735Z,1663960850.735 [NAL9602](INFO): Powering down
2022-09-23T19:20:51.557Z,1663960851.557 [CBIT](INFO): Clearing failed state for component NAL9602
2022-09-23T19:20:51.557Z,1663960851.557 [NAL9602] No Fault, FailCount= 2
2022-09-23T19:21:21.120Z,1663960881.120 [NAL9602](INFO): Powering up NAL9602
2022-09-23T19:21:31.951Z,1663960891.951 [NAL9602](INFO): NAL9602 initialized
2022-09-23T19:22:14.715Z,1663960934.715 [CommandExec](IMPORTANT): got command help
2022-09-23T19:23:30.752Z,1663961010.752 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-09-23T19:23:30.752Z,1663961010.752 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:23:30.763Z,1663961010.763 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:23:31.173Z,1663961011.173 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:23:31.173Z,1663961011.173 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-09-23T19:24:22.975Z,1663961062.975 [CommandExec](IMPORTANT): got command ibit
2022-09-23T19:24:23.280Z,1663961063.280 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-09-23T19:24:23.281Z,1663961063.281 [IBIT](IMPORTANT): Beginning control surface checks.
2022-09-23T19:24:23.284Z,1663961063.284 [CBIT](IMPORTANT): Beginning ground fault scan
2022-09-23T19:24:34.216Z,1663961074.216 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006481
CHAN A1 (24V): -0.007769
CHAN A2 (12V): -0.004636
CHAN A3 (5V): -0.002554
CHAN B0 (3.3V): -0.000446
CHAN B1 (3.15aV): -0.001155
CHAN B2 (3.15bV): -0.000802
CHAN B3 (GND): 0.000297
OPEN: 0.004593
Full Scale: +/- 1 mA
2022-09-23T19:25:29.918Z,1663961129.918 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2022-09-23T19:25:31.926Z,1663961131.926 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.431076
2022-09-23T19:25:44.579Z,1663961144.579 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004006
2022-09-23T19:26:14.440Z,1663961174.440 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-09-23T19:26:14.855Z,1663961174.855 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 13.558000
2022-09-23T19:26:14.856Z,1663961174.856 [IBIT](IMPORTANT): batteryCapacityThreshold: 30.000000 Ah
2022-09-23T19:26:14.856Z,1663961174.856 [IBIT](IMPORTANT): batteryVoltageThreshold: 12.000000 V
2022-09-23T19:26:15.263Z,1663961175.263 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-09-23T19:26:15.264Z,1663961175.264 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-09-23T19:26:15.264Z,1663961175.264 [IBIT](ERROR): Pressure failed. Onboard reading:14.860172 PSI
2022-09-23T19:26:15.264Z,1663961175.264 [IBIT](IMPORTANT): Humidity:50.805767 %
2022-09-23T19:26:15.670Z,1663961175.670 [IBIT](IMPORTANT): Vehicle Pitch:-0.556022 degrees
2022-09-23T19:26:15.671Z,1663961175.671 [IBIT](IMPORTANT): Vehicle  Roll:-19.718599 degrees
2022-09-23T19:26:15.671Z,1663961175.671 [IBIT](IMPORTANT): Vehicle  Heading:46.140263 degrees
2022-09-23T19:26:16.074Z,1663961176.074 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-09-23T19:26:16.074Z,1663961176.074 [IBIT](IMPORTANT): buoyancyNeutral: 324.282104 cc
2022-09-23T19:26:16.075Z,1663961176.075 [IBIT](IMPORTANT): massDefault: 1.035545 cm
2022-09-23T19:26:16.075Z,1663961176.075 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2022-09-23T19:26:16.075Z,1663961176.075 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2022-09-23T19:26:16.075Z,1663961176.075 [IBIT](IMPORTANT): IBIT FAILED
2022-09-23T19:26:16.460Z,1663961176.460 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T19:26:16.460Z,1663961176.460 [Default:CheckIn:C.Wait] Stopped
2022-09-23T19:26:16.460Z,1663961176.460 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T19:26:16.461Z,1663961176.461 [Default:CheckIn:D] Running Loop=1
2022-09-23T19:26:16.860Z,1663961176.860 [Default:CheckIn:D] Stopped
2022-09-23T19:26:16.860Z,1663961176.860 [Default:CheckIn:E] Running Loop=1
2022-09-23T19:26:17.251Z,1663961177.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.941553 min 
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn:E] Stopped
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn] Stopped
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn](INFO): Running loop #3
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn] Running Loop=3
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T19:26:17.252Z,1663961177.252 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T19:26:31.792Z,1663961191.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-09-23T19:26:31.792Z,1663961191.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:26:31.802Z,1663961191.802 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:26:32.219Z,1663961192.219 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:26:32.219Z,1663961192.219 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-09-23T19:29:32.827Z,1663961372.827 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-09-23T19:29:32.827Z,1663961372.827 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:29:32.837Z,1663961372.837 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:29:33.235Z,1663961373.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:29:33.236Z,1663961373.236 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-09-23T19:31:17.463Z,1663961477.463 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-09-23T19:26:17.3Z
2022-09-23T19:31:17.463Z,1663961477.463 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T19:31:17.463Z,1663961477.463 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T19:31:24.237Z,1663961484.237 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220923T185913/Courier0010.lzma
2022-09-23T19:31:25.240Z,1663961485.240 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0010.lzma.bak
2022-09-23T19:31:25.240Z,1663961485.240 [DataOverHttps](INFO): SBD MOMSN=17197680
2022-09-23T19:31:33.631Z,1663961493.631 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-09-23T19:31:33.632Z,1663961493.632 [NAL9602] Data Fault, FailCount= 3
2022-09-23T19:31:33.632Z,1663961493.632 [NAL9602](ERROR): Data Fault
2022-09-23T19:31:33.676Z,1663961493.676 [CBIT](ERROR): Data Fault in component: NAL9602
2022-09-23T19:31:34.035Z,1663961494.035 [NAL9602](INFO): Powering down
2022-09-23T19:31:34.860Z,1663961494.860 [CBIT](INFO): Clearing failed state for component NAL9602
2022-09-23T19:31:34.860Z,1663961494.860 [NAL9602] No Fault, FailCount= 3
2022-09-23T19:31:41.874Z,1663961501.874 [DataOverHttps](INFO): Sending 756 bytes from file Logs/20220923T185913/Express0011.lzma
2022-09-23T19:31:42.875Z,1663961502.875 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0011.lzma.bak
2022-09-23T19:31:42.876Z,1663961502.876 [DataOverHttps](INFO): SBD MOMSN=17197682
2022-09-23T19:31:45.380Z,1663961505.380 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T19:31:45.380Z,1663961505.380 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T19:31:45.381Z,1663961505.381 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T19:32:04.339Z,1663961524.339 [NAL9602](INFO): Powering up NAL9602
2022-09-23T19:32:15.240Z,1663961535.240 [NAL9602](INFO): NAL9602 initialized
2022-09-23T19:32:33.911Z,1663961553.911 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-09-23T19:32:33.911Z,1663961553.911 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:32:33.938Z,1663961553.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:32:34.345Z,1663961554.345 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:32:34.345Z,1663961554.345 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-09-23T19:35:34.923Z,1663961734.923 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2022-09-23T19:35:34.923Z,1663961734.923 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:35:34.934Z,1663961734.934 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:35:35.305Z,1663961735.305 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:35:35.305Z,1663961735.305 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2022-09-23T19:36:46.009Z,1663961806.009 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T19:36:46.009Z,1663961806.009 [Default:CheckIn:C.Wait] Stopped
2022-09-23T19:36:46.009Z,1663961806.009 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T19:36:46.009Z,1663961806.009 [Default:CheckIn:D] Running Loop=1
2022-09-23T19:36:46.402Z,1663961806.402 [Default:CheckIn:D] Stopped
2022-09-23T19:36:46.402Z,1663961806.402 [Default:CheckIn:E] Running Loop=1
2022-09-23T19:36:46.815Z,1663961806.815 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.433929 min 
2022-09-23T19:36:46.815Z,1663961806.815 [Default:CheckIn:E] Stopped
2022-09-23T19:36:46.815Z,1663961806.815 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T19:36:46.815Z,1663961806.815 [Default:CheckIn] Stopped
2022-09-23T19:36:46.816Z,1663961806.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T19:36:46.816Z,1663961806.816 [Default:CheckIn](INFO): Running loop #4
2022-09-23T19:36:46.816Z,1663961806.816 [Default:CheckIn] Running Loop=4
2022-09-23T19:36:46.816Z,1663961806.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T19:36:46.816Z,1663961806.816 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T19:38:35.903Z,1663961915.903 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2022-09-23T19:38:35.903Z,1663961915.903 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:38:35.922Z,1663961915.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:38:36.326Z,1663961916.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:38:36.326Z,1663961916.326 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2022-09-23T19:41:36.927Z,1663962096.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2022-09-23T19:41:36.927Z,1663962096.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:41:36.938Z,1663962096.938 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:41:37.347Z,1663962097.347 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:41:37.347Z,1663962097.347 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2022-09-23T19:41:47.017Z,1663962107.017 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-09-23T19:36:46.8Z
2022-09-23T19:41:47.017Z,1663962107.017 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T19:41:47.017Z,1663962107.017 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T19:41:54.537Z,1663962114.537 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220923T185913/Courier0013.lzma
2022-09-23T19:41:55.540Z,1663962115.540 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0013.lzma.bak
2022-09-23T19:41:55.540Z,1663962115.540 [DataOverHttps](INFO): SBD MOMSN=17197708
2022-09-23T19:42:12.441Z,1663962132.441 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20220923T185913/Express0014.lzma
2022-09-23T19:42:13.444Z,1663962133.444 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0014.lzma.bak
2022-09-23T19:42:13.444Z,1663962133.444 [DataOverHttps](INFO): SBD MOMSN=17197710
2022-09-23T19:42:15.712Z,1663962135.712 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T19:42:15.712Z,1663962135.712 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T19:42:15.712Z,1663962135.712 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T19:42:16.899Z,1663962136.899 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-09-23T19:42:16.900Z,1663962136.900 [NAL9602] Data Fault, FailCount= 4
2022-09-23T19:42:16.900Z,1663962136.900 [NAL9602](ERROR): Data Fault
2022-09-23T19:42:16.949Z,1663962136.949 [CBIT](ERROR): Data Fault in component: NAL9602
2022-09-23T19:42:17.307Z,1663962137.307 [NAL9602](INFO): Powering down
2022-09-23T19:42:18.153Z,1663962138.153 [CBIT](INFO): Clearing failed state for component NAL9602
2022-09-23T19:42:18.153Z,1663962138.153 [NAL9602] No Fault, FailCount= 4
2022-09-23T19:42:47.608Z,1663962167.608 [NAL9602](INFO): Powering up NAL9602
2022-09-23T19:42:58.515Z,1663962178.515 [NAL9602](INFO): NAL9602 initialized
2022-09-23T19:44:37.950Z,1663962277.950 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2022-09-23T19:44:37.950Z,1663962277.950 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:44:37.968Z,1663962277.968 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:44:38.380Z,1663962278.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:44:38.380Z,1663962278.380 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2022-09-23T19:47:16.447Z,1663962436.447 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T19:47:16.447Z,1663962436.447 [Default:CheckIn:C.Wait] Stopped
2022-09-23T19:47:16.447Z,1663962436.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T19:47:16.447Z,1663962436.447 [Default:CheckIn:D] Running Loop=1
2022-09-23T19:47:16.852Z,1663962436.852 [Default:CheckIn:D] Stopped
2022-09-23T19:47:16.852Z,1663962436.852 [Default:CheckIn:E] Running Loop=1
2022-09-23T19:47:17.259Z,1663962437.259 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.941431 min 
2022-09-23T19:47:17.259Z,1663962437.259 [Default:CheckIn:E] Stopped
2022-09-23T19:47:17.259Z,1663962437.259 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T19:47:17.259Z,1663962437.259 [Default:CheckIn] Stopped
2022-09-23T19:47:17.260Z,1663962437.260 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T19:47:17.260Z,1663962437.260 [Default:CheckIn](INFO): Running loop #5
2022-09-23T19:47:17.260Z,1663962437.260 [Default:CheckIn] Running Loop=5
2022-09-23T19:47:17.260Z,1663962437.260 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T19:47:17.260Z,1663962437.260 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T19:47:39.063Z,1663962459.063 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2022-09-23T19:47:39.063Z,1663962459.063 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:47:39.073Z,1663962459.073 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:47:39.492Z,1663962459.492 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:47:39.492Z,1663962459.492 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2022-09-23T19:50:40.055Z,1663962640.055 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2022-09-23T19:50:40.055Z,1663962640.055 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:50:40.066Z,1663962640.066 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:50:40.484Z,1663962640.484 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:50:40.484Z,1663962640.484 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2022-09-23T19:52:17.433Z,1663962737.433 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-09-23T19:47:17.3Z
2022-09-23T19:52:17.433Z,1663962737.433 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T19:52:17.433Z,1663962737.433 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T19:52:24.801Z,1663962744.801 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220923T185913/Courier0016.lzma
2022-09-23T19:52:25.804Z,1663962745.804 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0016.lzma.bak
2022-09-23T19:52:25.804Z,1663962745.804 [DataOverHttps](INFO): SBD MOMSN=17197714
2022-09-23T19:52:45.388Z,1663962765.388 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20220923T185913/Express0017.lzma
2022-09-23T19:52:46.388Z,1663962766.388 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0017.lzma.bak
2022-09-23T19:52:46.388Z,1663962766.388 [DataOverHttps](INFO): SBD MOMSN=17197716
2022-09-23T19:52:48.989Z,1663962768.989 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T19:52:48.994Z,1663962768.994 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T19:52:48.994Z,1663962768.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T19:53:02.284Z,1663962782.284 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-09-23T19:53:02.284Z,1663962782.284 [NAL9602] Data Fault, FailCount= 5
2022-09-23T19:53:02.284Z,1663962782.284 [NAL9602](ERROR): Data Fault
2022-09-23T19:53:02.378Z,1663962782.378 [CBIT](ERROR): Data Fault in component: NAL9602
2022-09-23T19:53:02.386Z,1663962782.386 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-09-23T19:53:02.695Z,1663962782.695 [NAL9602](INFO): Powering down
2022-09-23T19:53:02.732Z,1663962782.732 [CBIT](INFO): Critical error at 20220923T195302
2022-09-23T19:53:41.076Z,1663962821.076 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2022-09-23T19:53:41.077Z,1663962821.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:53:41.107Z,1663962821.107 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:53:41.533Z,1663962821.533 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:53:41.533Z,1663962821.533 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2022-09-23T19:54:04.722Z,1663962844.722 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259075
2022-09-23T19:54:14.967Z,1663962854.967 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003713
2022-09-23T19:55:02.310Z,1663962902.310 [CBIT](INFO): Clearing failed state for component NAL9602
2022-09-23T19:55:02.310Z,1663962902.310 [NAL9602] No Fault, FailCount= 5
2022-09-23T19:55:02.672Z,1663962902.672 [NAL9602](INFO): Powering up NAL9602
2022-09-23T19:55:13.587Z,1663962913.587 [NAL9602](INFO): NAL9602 initialized
2022-09-23T19:56:42.070Z,1663963002.070 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2022-09-23T19:56:42.070Z,1663963002.070 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-09-23T19:56:42.081Z,1663963002.081 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-09-23T19:56:42.505Z,1663963002.505 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-09-23T19:56:42.505Z,1663963002.505 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2022-09-23T19:57:33.780Z,1663963053.780 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195733.00,A,3648.17083,N,12147.27556,W,0.039,349.95,230922,,,A*70
2022-09-23T19:57:33.783Z,1663963053.783 [NAL9602](INFO): GPS fix at 20220923T195733: (36.802847, -121.787926)
2022-09-23T19:57:49.547Z,1663963069.547 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T19:57:49.547Z,1663963069.547 [Default:CheckIn:C.Wait] Stopped
2022-09-23T19:57:49.547Z,1663963069.547 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T19:57:49.548Z,1663963069.548 [Default:CheckIn:D] Running Loop=1
2022-09-23T19:57:49.956Z,1663963069.956 [Default:CheckIn:D] Stopped
2022-09-23T19:57:49.956Z,1663963069.956 [Default:CheckIn:E] Running Loop=1
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.493156 min 
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn:E] Stopped
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn] Stopped
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn](INFO): Running loop #6
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn] Running Loop=6
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T19:57:50.348Z,1663963070.348 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T19:57:52.365Z,1663963072.365 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195751.00,A,3648.17036,N,12147.28136,W,0.972,349.95,230922,,,A*71
2022-09-23T19:57:52.368Z,1663963072.368 [NAL9602](INFO): GPS fix at 20220923T195751: (36.802839, -121.788023)
2022-09-23T19:57:52.378Z,1663963072.378 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T19:57:52.378Z,1663963072.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T19:57:59.670Z,1663963079.670 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20220923T185913/Courier0019.lzma
2022-09-23T19:58:00.664Z,1663963080.664 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0019.lzma.bak
2022-09-23T19:58:00.664Z,1663963080.664 [DataOverHttps](INFO): SBD MOMSN=17197720
2022-09-23T19:58:18.935Z,1663963098.935 [DataOverHttps](INFO): Sending 1035 bytes from file Logs/20220923T185913/Express0020.lzma
2022-09-23T19:58:19.936Z,1663963099.936 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0020.lzma.bak
2022-09-23T19:58:19.936Z,1663963099.936 [DataOverHttps](INFO): SBD MOMSN=17197724
2022-09-23T19:58:22.273Z,1663963102.273 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T19:58:22.273Z,1663963102.273 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T19:58:22.273Z,1663963102.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T19:58:24.280Z,1663963104.280 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T20:01:00.743Z,1663963260.743 [CommandExec](IMPORTANT): got command ibit
2022-09-23T20:01:01.047Z,1663963261.047 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-09-23T20:01:01.048Z,1663963261.048 [IBIT](IMPORTANT): Beginning control surface checks.
2022-09-23T20:01:01.051Z,1663963261.051 [CBIT](IMPORTANT): Beginning ground fault scan
2022-09-23T20:01:02.652Z,1663963262.652 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200101.00,A,3648.15851,N,12147.28008,W,0.622,340.96,230922,,,A*7A
2022-09-23T20:01:02.654Z,1663963262.654 [NAL9602](INFO): GPS fix at 20220923T200101: (36.802642, -121.788001)
2022-09-23T20:01:11.955Z,1663963271.955 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006306
CHAN A1 (24V): -0.007475
CHAN A2 (12V): -0.004307
CHAN A3 (5V): -0.002736
CHAN B0 (3.3V): -0.001049
CHAN B1 (3.15aV): -0.001685
CHAN B2 (3.15bV): -0.001381
CHAN B3 (GND): -0.000962
OPEN: 0.004509
Full Scale: +/- 1 mA
2022-09-23T20:02:52.149Z,1663963372.149 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-09-23T20:02:52.547Z,1663963372.547 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 13.519000
2022-09-23T20:02:52.547Z,1663963372.547 [IBIT](IMPORTANT): batteryCapacityThreshold: 30.000000 Ah
2022-09-23T20:02:52.548Z,1663963372.548 [IBIT](IMPORTANT): batteryVoltageThreshold: 12.000000 V
2022-09-23T20:02:52.967Z,1663963372.967 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-09-23T20:02:52.967Z,1663963372.967 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-09-23T20:02:52.968Z,1663963372.968 [IBIT](ERROR): Pressure failed. Onboard reading:14.857491 PSI
2022-09-23T20:02:52.968Z,1663963372.968 [IBIT](IMPORTANT): Humidity:54.572086 %
2022-09-23T20:02:53.358Z,1663963373.358 [IBIT](IMPORTANT): Vehicle Pitch:-88.713661 degrees
2022-09-23T20:02:53.359Z,1663963373.359 [IBIT](IMPORTANT): Vehicle  Roll:-34.227249 degrees
2022-09-23T20:02:53.359Z,1663963373.359 [IBIT](IMPORTANT): Vehicle  Heading:106.810112 degrees
2022-09-23T20:02:53.762Z,1663963373.762 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-09-23T20:02:53.763Z,1663963373.763 [IBIT](IMPORTANT): buoyancyNeutral: 324.282104 cc
2022-09-23T20:02:53.763Z,1663963373.763 [IBIT](IMPORTANT): massDefault: 1.035545 cm
2022-09-23T20:02:53.763Z,1663963373.763 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2022-09-23T20:02:53.764Z,1663963373.764 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2022-09-23T20:02:53.764Z,1663963373.764 [IBIT](IMPORTANT): IBIT FAILED
2022-09-23T20:03:16.860Z,1663963396.860 [CBIT](CRITICAL): Environmental Failure. Press:14.877588 PSI. Humidity:54%. Temp:23 C. ABORTING MISSION
2022-09-23T20:03:17.246Z,1663963397.246 [CBIT](INFO): Critical error at 20220923T200316
2022-09-23T20:03:22.839Z,1663963402.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:03:22.839Z,1663963402.839 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:03:22.839Z,1663963402.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:03:22.839Z,1663963402.839 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:03:23.248Z,1663963403.248 [Default:CheckIn:D] Stopped
2022-09-23T20:03:23.248Z,1663963403.248 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:03:23.659Z,1663963403.659 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.048031 min 
2022-09-23T20:03:23.659Z,1663963403.659 [Default:CheckIn:E] Stopped
2022-09-23T20:03:23.659Z,1663963403.659 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:03:23.659Z,1663963403.659 [Default:CheckIn] Stopped
2022-09-23T20:03:23.660Z,1663963403.660 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:03:23.660Z,1663963403.660 [Default:CheckIn](INFO): Running loop #7
2022-09-23T20:03:23.660Z,1663963403.660 [Default:CheckIn] Running Loop=7
2022-09-23T20:03:23.660Z,1663963403.660 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:03:23.660Z,1663963403.660 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:06:05.238Z,1663963565.238 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T20:06:06.457Z,1663963566.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200605.00,A,3648.15945,N,12147.28585,W,0.292,340.96,230922,,,A*72
2022-09-23T20:06:06.459Z,1663963566.459 [NAL9602](INFO): GPS fix at 20220923T200605: (36.802658, -121.788098)
2022-09-23T20:06:06.469Z,1663963566.469 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:06:06.469Z,1663963566.469 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:06:13.337Z,1663963573.337 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20220923T185913/Courier0022.lzma
2022-09-23T20:06:14.340Z,1663963574.340 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0022.lzma.bak
2022-09-23T20:06:14.340Z,1663963574.340 [DataOverHttps](INFO): SBD MOMSN=17197728
2022-09-23T20:06:31.264Z,1663963591.264 [DataOverHttps](INFO): Sending 1520 bytes from file Logs/20220923T185913/Express0023.lzma
2022-09-23T20:06:32.264Z,1663963592.264 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0023.lzma.bak
2022-09-23T20:06:32.264Z,1663963592.264 [DataOverHttps](INFO): SBD MOMSN=17197732
2022-09-23T20:06:34.832Z,1663963594.832 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:06:34.832Z,1663963594.832 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:06:34.832Z,1663963594.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:06:38.423Z,1663963598.423 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T20:09:20.845Z,1663963760.845 [BPC1](ERROR): BPC1B: No match for serial number 0C29 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:09:34.163Z,1663963774.163 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:09:47.898Z,1663963787.898 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:10:01.230Z,1663963801.230 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:10:14.562Z,1663963814.562 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:10:27.894Z,1663963827.894 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:10:41.630Z,1663963841.630 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:10:47.324Z,1663963847.324 [CBIT](CRITICAL): Environmental Failure. Press:14.839252 PSI. Humidity:54%. Temp:23 C. ABORTING MISSION
2022-09-23T20:10:47.732Z,1663963847.732 [CBIT](INFO): Critical error at 20220923T201047
2022-09-23T20:10:55.367Z,1663963855.367 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:11:08.296Z,1663963868.296 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:11:22.036Z,1663963882.036 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:11:35.370Z,1663963895.370 [BPC1](ERROR): BPC1B: No match for serial number 0BEC in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2022-09-23T20:11:35.458Z,1663963895.458 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:11:35.458Z,1663963895.458 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:11:35.458Z,1663963895.458 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:11:35.458Z,1663963895.458 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:11:35.802Z,1663963895.802 [Default:CheckIn:D] Stopped
2022-09-23T20:11:35.802Z,1663963895.802 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:11:36.201Z,1663963896.201 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.257255 min 
2022-09-23T20:11:36.202Z,1663963896.202 [Default:CheckIn:E] Stopped
2022-09-23T20:11:36.206Z,1663963896.206 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:11:36.206Z,1663963896.206 [Default:CheckIn] Stopped
2022-09-23T20:11:36.206Z,1663963896.206 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:11:36.206Z,1663963896.206 [Default:CheckIn](INFO): Running loop #8
2022-09-23T20:11:36.206Z,1663963896.206 [Default:CheckIn] Running Loop=8
2022-09-23T20:11:36.206Z,1663963896.206 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:11:36.206Z,1663963896.206 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:11:38.205Z,1663963898.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201137.00,A,3648.16517,N,12147.28073,W,0.641,340.96,230922,,,A*7B
2022-09-23T20:11:38.208Z,1663963898.208 [NAL9602](INFO): GPS fix at 20220923T201137: (36.802753, -121.788012)
2022-09-23T20:11:38.218Z,1663963898.218 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:11:38.218Z,1663963898.218 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:11:47.493Z,1663963907.493 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20220923T185913/Courier0025.lzma
2022-09-23T20:11:48.496Z,1663963908.496 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0025.lzma.bak
2022-09-23T20:11:48.496Z,1663963908.496 [DataOverHttps](INFO): SBD MOMSN=17197757
2022-09-23T20:12:05.538Z,1663963925.538 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20220923T185913/Express0026.lzma
2022-09-23T20:12:06.540Z,1663963926.540 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0026.lzma.bak
2022-09-23T20:12:06.540Z,1663963926.540 [DataOverHttps](INFO): SBD MOMSN=17197761
2022-09-23T20:12:09.193Z,1663963929.193 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:12:09.193Z,1663963929.193 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:12:09.193Z,1663963929.193 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:14:19.250Z,1663964059.250 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5.
2022-09-23T20:14:19.253Z,1663964059.253 [BPC1](INFO): Received data from all battery sticks.
2022-09-23T20:15:21.062Z,1663964121.062 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2022-09-23T20:15:21.062Z,1663964121.062 [AHRS_M2] Data Fault, FailCount= 1
2022-09-23T20:15:21.062Z,1663964121.062 [AHRS_M2](ERROR): Data Fault
2022-09-23T20:15:21.081Z,1663964121.081 [CBIT](ERROR): Data Fault in component: AHRS_M2
2022-09-23T20:15:21.447Z,1663964121.447 [AHRS_M2](INFO): Powering down
2022-09-23T20:15:22.280Z,1663964122.280 [CBIT](INFO): Clearing failed state for component AHRS_M2
2022-09-23T20:15:22.281Z,1663964122.281 [AHRS_M2] No Fault, FailCount= 1
2022-09-23T20:15:22.659Z,1663964122.659 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-09-23T20:16:40.243Z,1663964200.243 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T20:17:09.744Z,1663964229.744 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:17:09.744Z,1663964229.744 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:17:09.744Z,1663964229.744 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:17:09.744Z,1663964229.744 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:17:10.156Z,1663964230.156 [Default:CheckIn:D] Stopped
2022-09-23T20:17:10.156Z,1663964230.156 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:17:10.559Z,1663964230.559 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.829826 min 
2022-09-23T20:17:10.559Z,1663964230.559 [Default:CheckIn:E] Stopped
2022-09-23T20:17:10.559Z,1663964230.559 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:17:10.559Z,1663964230.559 [Default:CheckIn] Stopped
2022-09-23T20:17:10.559Z,1663964230.559 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:17:10.560Z,1663964230.560 [Default:CheckIn](INFO): Running loop #9
2022-09-23T20:17:10.560Z,1663964230.560 [Default:CheckIn] Running Loop=9
2022-09-23T20:17:10.560Z,1663964230.560 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:17:10.560Z,1663964230.560 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:17:10.947Z,1663964230.947 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T20:17:12.565Z,1663964232.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201711.00,A,3648.16121,N,12147.28781,W,0.389,334.47,230922,,,A*7C
2022-09-23T20:17:12.567Z,1663964232.567 [NAL9602](INFO): GPS fix at 20220923T201711: (36.802687, -121.788130)
2022-09-23T20:17:12.597Z,1663964232.597 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:17:12.597Z,1663964232.597 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:17:32.102Z,1663964252.102 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-23T20:17:34.111Z,1663964254.111 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.242058
2022-09-23T20:17:43.328Z,1663964263.328 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-09-23T20:17:43.410Z,1663964263.410 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-09-23T20:18:05.234Z,1663964285.234 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-23T20:18:40.374Z,1663964320.374 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-23T20:19:15.514Z,1663964355.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-23T20:19:50.654Z,1663964390.654 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-23T20:20:25.802Z,1663964425.802 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-09-23T20:20:38.167Z,1663964438.167 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003547
2022-09-23T20:20:45.517Z,1663964445.517 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20220923T185913/Courier0028.lzma
2022-09-23T20:20:46.520Z,1663964446.520 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0028.lzma.bak
2022-09-23T20:20:46.520Z,1663964446.520 [DataOverHttps](INFO): SBD MOMSN=17197764
2022-09-23T20:21:03.542Z,1663964463.542 [DataOverHttps](INFO): Sending 288 bytes from file Logs/20220923T185913/Express0029.lzma
2022-09-23T20:21:06.086Z,1663964466.086 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0029.lzma.bak
2022-09-23T20:21:06.086Z,1663964466.086 [DataOverHttps](INFO): SBD MOMSN=17197767
2022-09-23T20:21:07.770Z,1663964467.770 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:21:07.770Z,1663964467.770 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:21:07.770Z,1663964467.770 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:22:14.414Z,1663964534.414 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T20:22:45.117Z,1663964565.117 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T20:23:28.415Z,1663964608.415 [CommandExec](IMPORTANT): got command ibit
2022-09-23T20:23:28.782Z,1663964608.782 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-09-23T20:23:28.782Z,1663964608.782 [IBIT](IMPORTANT): Beginning control surface checks.
2022-09-23T20:23:28.786Z,1663964608.786 [CBIT](IMPORTANT): Beginning ground fault scan
2022-09-23T20:23:30.368Z,1663964610.368 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202329.00,A,3648.15739,N,12147.27993,W,0.233,58.33,230922,,,A*44
2022-09-23T20:23:30.381Z,1663964610.381 [NAL9602](INFO): GPS fix at 20220923T202329: (36.802623, -121.787999)
2022-09-23T20:23:39.679Z,1663964619.679 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005982
CHAN A1 (24V): -0.007286
CHAN A2 (12V): -0.004698
CHAN A3 (5V): -0.002210
CHAN B0 (3.3V): -0.001257
CHAN B1 (3.15aV): -0.001240
CHAN B2 (3.15bV): -0.001579
CHAN B3 (GND): -0.000595
OPEN: 0.004482
Full Scale: +/- 1 mA
2022-09-23T20:24:01.079Z,1663964641.079 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-09-23T20:24:01.162Z,1663964641.162 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-09-23T20:25:19.876Z,1663964719.876 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2022-09-23T20:25:20.319Z,1663964720.319 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 13.479000
2022-09-23T20:25:20.319Z,1663964720.319 [IBIT](IMPORTANT): batteryCapacityThreshold: 30.000000 Ah
2022-09-23T20:25:20.319Z,1663964720.319 [IBIT](IMPORTANT): batteryVoltageThreshold: 12.000000 V
2022-09-23T20:25:20.707Z,1663964720.707 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-09-23T20:25:20.707Z,1663964720.707 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-09-23T20:25:20.707Z,1663964720.707 [IBIT](ERROR): Pressure failed. Onboard reading:14.839252 PSI
2022-09-23T20:25:20.708Z,1663964720.708 [IBIT](IMPORTANT): Humidity:54.608715 %
2022-09-23T20:25:21.104Z,1663964721.104 [IBIT](IMPORTANT): Vehicle Pitch:-88.539009 degrees
2022-09-23T20:25:21.104Z,1663964721.104 [IBIT](IMPORTANT): Vehicle  Roll:-35.877827 degrees
2022-09-23T20:25:21.105Z,1663964721.105 [IBIT](IMPORTANT): Vehicle  Heading:109.181923 degrees
2022-09-23T20:25:21.486Z,1663964721.486 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-09-23T20:25:21.486Z,1663964721.486 [IBIT](IMPORTANT): buoyancyNeutral: 324.282104 cc
2022-09-23T20:25:21.486Z,1663964721.486 [IBIT](IMPORTANT): massDefault: 1.035545 cm
2022-09-23T20:25:21.487Z,1663964721.487 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2022-09-23T20:25:21.487Z,1663964721.487 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2022-09-23T20:25:21.487Z,1663964721.487 [IBIT](IMPORTANT): IBIT FAILED
2022-09-23T20:26:08.362Z,1663964768.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:26:08.362Z,1663964768.362 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:26:08.362Z,1663964768.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:26:08.362Z,1663964768.362 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:26:08.752Z,1663964768.752 [Default:CheckIn:D] Stopped
2022-09-23T20:26:08.752Z,1663964768.752 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:26:09.147Z,1663964769.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.806429 min 
2022-09-23T20:26:09.147Z,1663964769.147 [Default:CheckIn:E] Stopped
2022-09-23T20:26:09.148Z,1663964769.148 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:26:09.148Z,1663964769.148 [Default:CheckIn] Stopped
2022-09-23T20:26:09.148Z,1663964769.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:26:09.148Z,1663964769.148 [Default:CheckIn](INFO): Running loop #10
2022-09-23T20:26:09.148Z,1663964769.148 [Default:CheckIn] Running Loop=10
2022-09-23T20:26:09.148Z,1663964769.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:26:09.148Z,1663964769.148 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:28:32.154Z,1663964912.154 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T20:28:37.009Z,1663964917.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202836.00,A,3648.16106,N,12147.28485,W,0.117,58.33,230922,,,A*48
2022-09-23T20:28:37.020Z,1663964917.020 [NAL9602](INFO): GPS fix at 20220923T202836: (36.802684, -121.788081)
2022-09-23T20:28:37.030Z,1663964917.030 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:28:37.030Z,1663964917.030 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:28:44.189Z,1663964924.189 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220923T185913/Courier0031.lzma
2022-09-23T20:28:45.192Z,1663964925.192 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0031.lzma.bak
2022-09-23T20:28:45.192Z,1663964925.192 [DataOverHttps](INFO): SBD MOMSN=17197772
2022-09-23T20:29:01.979Z,1663964941.979 [DataOverHttps](INFO): Sending 844 bytes from file Logs/20220923T185913/Express0032.lzma
2022-09-23T20:29:02.980Z,1663964942.980 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0032.lzma.bak
2022-09-23T20:29:02.980Z,1663964942.980 [DataOverHttps](INFO): SBD MOMSN=17197775
2022-09-23T20:29:05.321Z,1663964945.321 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:29:05.321Z,1663964945.321 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:29:05.321Z,1663964945.321 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:29:09.328Z,1663964949.328 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T20:34:05.863Z,1663965245.863 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:34:05.863Z,1663965245.863 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:34:05.863Z,1663965245.863 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:34:05.863Z,1663965245.863 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:34:06.269Z,1663965246.269 [Default:CheckIn:D] Stopped
2022-09-23T20:34:06.269Z,1663965246.269 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:34:06.681Z,1663965246.681 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.765031 min 
2022-09-23T20:34:06.681Z,1663965246.681 [Default:CheckIn:E] Stopped
2022-09-23T20:34:06.681Z,1663965246.681 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:34:06.681Z,1663965246.681 [Default:CheckIn] Stopped
2022-09-23T20:34:06.681Z,1663965246.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:34:06.681Z,1663965246.681 [Default:CheckIn](INFO): Running loop #11
2022-09-23T20:34:06.681Z,1663965246.681 [Default:CheckIn] Running Loop=11
2022-09-23T20:34:06.682Z,1663965246.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:34:06.682Z,1663965246.682 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:34:08.684Z,1663965248.684 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203407.00,A,3648.16589,N,12147.28448,W,0.467,0.00,230922,,,A*7A
2022-09-23T20:34:08.686Z,1663965248.686 [NAL9602](INFO): GPS fix at 20220923T203407: (36.802765, -121.788075)
2022-09-23T20:34:08.696Z,1663965248.696 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:34:08.696Z,1663965248.696 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:34:15.889Z,1663965255.889 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220923T185913/Courier0034.lzma
2022-09-23T20:34:16.892Z,1663965256.892 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0034.lzma.bak
2022-09-23T20:34:16.892Z,1663965256.892 [DataOverHttps](INFO): SBD MOMSN=17197801
2022-09-23T20:34:33.769Z,1663965273.769 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20220923T185913/Express0035.lzma
2022-09-23T20:34:34.772Z,1663965274.772 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0035.lzma.bak
2022-09-23T20:34:34.772Z,1663965274.772 [DataOverHttps](INFO): SBD MOMSN=17197804
2022-09-23T20:34:36.994Z,1663965276.994 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:34:36.994Z,1663965276.994 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:34:36.994Z,1663965276.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:34:39.387Z,1663965279.387 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-09-23T20:34:39.470Z,1663965279.470 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-09-23T20:39:11.304Z,1663965551.304 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T20:39:37.570Z,1663965577.570 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:39:37.570Z,1663965577.570 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:39:37.571Z,1663965577.571 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:39:37.571Z,1663965577.571 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:39:37.986Z,1663965577.986 [Default:CheckIn:D] Stopped
2022-09-23T20:39:37.986Z,1663965577.986 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:39:38.418Z,1663965578.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.293652 min 
2022-09-23T20:39:38.418Z,1663965578.418 [Default:CheckIn:E] Stopped
2022-09-23T20:39:38.419Z,1663965578.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:39:38.419Z,1663965578.419 [Default:CheckIn] Stopped
2022-09-23T20:39:38.419Z,1663965578.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:39:38.419Z,1663965578.419 [Default:CheckIn](INFO): Running loop #12
2022-09-23T20:39:38.419Z,1663965578.419 [Default:CheckIn] Running Loop=12
2022-09-23T20:39:38.419Z,1663965578.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:39:38.419Z,1663965578.419 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:39:40.397Z,1663965580.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203939.00,A,3648.16642,N,12147.28645,W,0.583,333.69,230922,,,A*76
2022-09-23T20:39:40.399Z,1663965580.399 [NAL9602](INFO): GPS fix at 20220923T203939: (36.802774, -121.788107)
2022-09-23T20:39:40.409Z,1663965580.409 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:39:40.409Z,1663965580.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:39:47.233Z,1663965587.233 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220923T185913/Courier0037.lzma
2022-09-23T20:39:48.236Z,1663965588.236 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0037.lzma.bak
2022-09-23T20:39:48.236Z,1663965588.236 [DataOverHttps](INFO): SBD MOMSN=17197807
2022-09-23T20:40:06.045Z,1663965606.045 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20220923T185913/Express0038.lzma
2022-09-23T20:40:07.048Z,1663965607.048 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0038.lzma.bak
2022-09-23T20:40:07.048Z,1663965607.048 [DataOverHttps](INFO): SBD MOMSN=17197810
2022-09-23T20:40:09.606Z,1663965609.606 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:40:09.606Z,1663965609.606 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:40:09.606Z,1663965609.606 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:40:12.371Z,1663965612.371 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T20:45:10.136Z,1663965910.136 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:45:10.136Z,1663965910.136 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:45:10.136Z,1663965910.136 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:45:10.136Z,1663965910.136 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:45:10.543Z,1663965910.543 [Default:CheckIn:D] Stopped
2022-09-23T20:45:10.543Z,1663965910.543 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.836279 min 
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn:E] Stopped
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn] Stopped
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn](INFO): Running loop #13
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn] Running Loop=13
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:45:10.935Z,1663965910.935 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:45:12.948Z,1663965912.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204512.00,A,3648.16258,N,12147.28483,W,0.350,333.69,230922,,,A*7B
2022-09-23T20:45:12.950Z,1663965912.950 [NAL9602](INFO): GPS fix at 20220923T204512: (36.802710, -121.788081)
2022-09-23T20:45:12.960Z,1663965912.960 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:45:12.960Z,1663965912.960 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:45:19.821Z,1663965919.821 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220923T185913/Courier0040.lzma
2022-09-23T20:45:20.824Z,1663965920.824 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0040.lzma.bak
2022-09-23T20:45:20.824Z,1663965920.824 [DataOverHttps](INFO): SBD MOMSN=17197814
2022-09-23T20:45:37.665Z,1663965937.665 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20220923T185913/Express0041.lzma
2022-09-23T20:45:38.668Z,1663965938.668 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0041.lzma.bak
2022-09-23T20:45:38.668Z,1663965938.668 [DataOverHttps](INFO): SBD MOMSN=17197817
2022-09-23T20:45:41.253Z,1663965941.253 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:45:41.253Z,1663965941.253 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:45:41.258Z,1663965941.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:45:43.659Z,1663965943.659 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-09-23T20:45:43.738Z,1663965943.738 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-09-23T20:50:15.143Z,1663966215.143 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T20:50:41.811Z,1663966241.811 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:50:41.811Z,1663966241.811 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:50:41.811Z,1663966241.811 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:50:41.811Z,1663966241.811 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:50:42.218Z,1663966242.218 [Default:CheckIn:D] Stopped
2022-09-23T20:50:42.218Z,1663966242.218 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:50:42.629Z,1663966242.629 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.364185 min 
2022-09-23T20:50:42.629Z,1663966242.629 [Default:CheckIn:E] Stopped
2022-09-23T20:50:42.629Z,1663966242.629 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:50:42.629Z,1663966242.629 [Default:CheckIn] Stopped
2022-09-23T20:50:42.630Z,1663966242.630 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:50:42.630Z,1663966242.630 [Default:CheckIn](INFO): Running loop #14
2022-09-23T20:50:42.630Z,1663966242.630 [Default:CheckIn] Running Loop=14
2022-09-23T20:50:42.630Z,1663966242.630 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:50:42.630Z,1663966242.630 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:50:44.637Z,1663966244.637 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205043.00,A,3648.16283,N,12147.28659,W,0.389,333.69,230922,,,A*7C
2022-09-23T20:50:44.639Z,1663966244.639 [NAL9602](INFO): GPS fix at 20220923T205043: (36.802714, -121.788110)
2022-09-23T20:50:44.671Z,1663966244.671 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:50:44.671Z,1663966244.671 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:50:52.017Z,1663966252.017 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220923T185913/Courier0043.lzma
2022-09-23T20:50:53.020Z,1663966253.020 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0043.lzma.bak
2022-09-23T20:50:53.020Z,1663966253.020 [DataOverHttps](INFO): SBD MOMSN=17197828
2022-09-23T20:51:11.337Z,1663966271.337 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20220923T185913/Express0044.lzma
2022-09-23T20:51:12.340Z,1663966272.340 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0044.lzma.bak
2022-09-23T20:51:12.340Z,1663966272.340 [DataOverHttps](INFO): SBD MOMSN=17197833
2022-09-23T20:51:15.782Z,1663966275.782 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:51:15.782Z,1663966275.782 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:51:15.782Z,1663966275.782 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:51:17.375Z,1663966277.375 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T20:56:16.327Z,1663966576.327 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T20:56:16.327Z,1663966576.327 [Default:CheckIn:C.Wait] Stopped
2022-09-23T20:56:16.327Z,1663966576.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T20:56:16.327Z,1663966576.327 [Default:CheckIn:D] Running Loop=1
2022-09-23T20:56:16.740Z,1663966576.740 [Default:CheckIn:D] Stopped
2022-09-23T20:56:16.740Z,1663966576.740 [Default:CheckIn:E] Running Loop=1
2022-09-23T20:56:17.153Z,1663966577.153 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.939559 min 
2022-09-23T20:56:17.153Z,1663966577.153 [Default:CheckIn:E] Stopped
2022-09-23T20:56:17.153Z,1663966577.153 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T20:56:17.158Z,1663966577.158 [Default:CheckIn] Stopped
2022-09-23T20:56:17.158Z,1663966577.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T20:56:17.158Z,1663966577.158 [Default:CheckIn](INFO): Running loop #15
2022-09-23T20:56:17.158Z,1663966577.158 [Default:CheckIn] Running Loop=15
2022-09-23T20:56:17.158Z,1663966577.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T20:56:17.158Z,1663966577.158 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T20:56:19.149Z,1663966579.149 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205618.00,A,3648.16595,N,12147.27887,W,0.739,186.02,230922,,,A*78
2022-09-23T20:56:19.151Z,1663966579.151 [NAL9602](INFO): GPS fix at 20220923T205618: (36.802766, -121.787981)
2022-09-23T20:56:19.201Z,1663966579.201 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T20:56:19.201Z,1663966579.201 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T20:56:20.558Z,1663966580.558 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220923T185913/Courier0046.lzma
2022-09-23T20:56:20.852Z,1663966580.852 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0046.lzma.bak
2022-09-23T20:56:20.852Z,1663966580.852 [DataOverHttps](INFO): SBD MOMSN=17197840
2022-09-23T20:56:37.757Z,1663966597.757 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20220923T185913/Express0047.lzma
2022-09-23T20:56:38.760Z,1663966598.760 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0047.lzma.bak
2022-09-23T20:56:38.760Z,1663966598.760 [DataOverHttps](INFO): SBD MOMSN=17197843
2022-09-23T20:56:41.097Z,1663966601.097 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T20:56:41.097Z,1663966601.097 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T20:56:41.097Z,1663966601.097 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T20:56:49.947Z,1663966609.947 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-09-23T20:56:50.026Z,1663966610.026 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-09-23T21:01:21.415Z,1663966881.415 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-09-23T21:01:41.675Z,1663966901.675 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T21:01:41.675Z,1663966901.675 [Default:CheckIn:C.Wait] Stopped
2022-09-23T21:01:41.675Z,1663966901.675 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T21:01:41.675Z,1663966901.675 [Default:CheckIn:D] Running Loop=1
2022-09-23T21:01:42.042Z,1663966902.042 [Default:CheckIn:D] Stopped
2022-09-23T21:01:42.042Z,1663966902.042 [Default:CheckIn:E] Running Loop=1
2022-09-23T21:01:42.459Z,1663966902.459 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.361190 min 
2022-09-23T21:01:42.459Z,1663966902.459 [Default:CheckIn:E] Stopped
2022-09-23T21:01:42.459Z,1663966902.459 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T21:01:42.460Z,1663966902.460 [Default:CheckIn] Stopped
2022-09-23T21:01:42.460Z,1663966902.460 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T21:01:42.460Z,1663966902.460 [Default:CheckIn](INFO): Running loop #16
2022-09-23T21:01:42.460Z,1663966902.460 [Default:CheckIn] Running Loop=16
2022-09-23T21:01:42.460Z,1663966902.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T21:01:42.460Z,1663966902.460 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T21:01:44.441Z,1663966904.441 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210143.00,A,3648.16778,N,12147.28326,W,0.330,179.74,230922,,,A*77
2022-09-23T21:01:44.444Z,1663966904.444 [NAL9602](INFO): GPS fix at 20220923T210143: (36.802796, -121.788054)
2022-09-23T21:01:44.465Z,1663966904.465 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T21:01:44.465Z,1663966904.465 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T21:01:54.505Z,1663966914.505 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220923T185913/Courier0049.lzma
2022-09-23T21:01:55.508Z,1663966915.508 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0049.lzma.bak
2022-09-23T21:01:55.508Z,1663966915.508 [DataOverHttps](INFO): SBD MOMSN=17197846
2022-09-23T21:02:12.414Z,1663966932.414 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20220923T185913/Express0050.lzma
2022-09-23T21:02:13.416Z,1663966933.416 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0050.lzma.bak
2022-09-23T21:02:13.416Z,1663966933.416 [DataOverHttps](INFO): SBD MOMSN=17197849
2022-09-23T21:02:15.946Z,1663966935.946 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T21:02:15.947Z,1663966935.947 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T21:02:15.947Z,1663966935.947 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T21:02:16.319Z,1663966936.319 [NAL9602](INFO): Not Powering down - fast GPS
2022-09-23T21:07:16.548Z,1663967236.548 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-09-23T21:07:16.548Z,1663967236.548 [Default:CheckIn:C.Wait] Stopped
2022-09-23T21:07:16.548Z,1663967236.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T21:07:16.548Z,1663967236.548 [Default:CheckIn:D] Running Loop=1
2022-09-23T21:07:16.947Z,1663967236.947 [Default:CheckIn:D] Stopped
2022-09-23T21:07:16.947Z,1663967236.947 [Default:CheckIn:E] Running Loop=1
2022-09-23T21:07:17.352Z,1663967237.352 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.943001 min 
2022-09-23T21:07:17.352Z,1663967237.352 [Default:CheckIn:E] Stopped
2022-09-23T21:07:17.352Z,1663967237.352 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-09-23T21:07:17.352Z,1663967237.352 [Default:CheckIn] Stopped
2022-09-23T21:07:17.352Z,1663967237.352 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T21:07:17.353Z,1663967237.353 [Default:CheckIn](INFO): Running loop #17
2022-09-23T21:07:17.353Z,1663967237.353 [Default:CheckIn] Running Loop=17
2022-09-23T21:07:17.353Z,1663967237.353 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-09-23T21:07:17.353Z,1663967237.353 [Default:CheckIn:Read_GPS] Running Loop=1
2022-09-23T21:07:19.364Z,1663967239.364 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210718.00,A,3648.16422,N,12147.28595,W,1.594,179.74,230922,,,A*74
2022-09-23T21:07:19.376Z,1663967239.376 [NAL9602](INFO): GPS fix at 20220923T210718: (36.802737, -121.788099)
2022-09-23T21:07:19.386Z,1663967239.386 [Default:CheckIn:Read_GPS] Stopped
2022-09-23T21:07:19.386Z,1663967239.386 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-09-23T21:07:26.933Z,1663967246.933 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220923T185913/Courier0052.lzma
2022-09-23T21:07:27.936Z,1663967247.936 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Courier0052.lzma.bak
2022-09-23T21:07:27.936Z,1663967247.936 [DataOverHttps](INFO): SBD MOMSN=17197853
2022-09-23T21:07:50.071Z,1663967270.071 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-09-23T21:07:50.154Z,1663967270.154 [NAL9602](ERROR): received: 
+CSQ:0

OK
2022-09-23T21:08:16.630Z,1663967296.630 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20220923T185913/Express0053.lzma
2022-09-23T21:08:17.632Z,1663967297.632 [DataOverHttps](INFO): Moved sent file to Logs/20220923T185913/Express0053.lzma.bak
2022-09-23T21:08:17.632Z,1663967297.632 [DataOverHttps](INFO): SBD MOMSN=17197857
2022-09-23T21:08:19.989Z,1663967299.989 [Default:CheckIn:Read_Iridium] Stopped
2022-09-23T21:08:19.989Z,1663967299.989 [Default:CheckIn:C.Wait] Running Loop=1
2022-09-23T21:08:19.989Z,1663967299.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-09-23T21:09:28.470Z,1663967368.470 [CommandExec](IMPORTANT): got command quit
2022-09-23T21:09:29.474Z,1663967369.474 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:29.474Z,1663967369.474 [CommandExec](INFO): Uninitializing the command executive.
2022-09-23T21:09:29.474Z,1663967369.474 [CommandExec](INFO): Uninitializing the command scheduler.
2022-09-23T21:09:29.474Z,1663967369.474 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:29.618Z,1663967369.618 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-09-23T21:09:29.618Z,1663967369.618 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-09-23T21:09:29.619Z,1663967369.619 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:29.619Z,1663967369.619 [NavChartDb](INFO): Join timeout helper Thread ID is 7431
2022-09-23T21:09:29.698Z,1663967369.698 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:29.698Z,1663967369.698 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:29.702Z,1663967369.702 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-09-23T21:09:29.702Z,1663967369.702 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:29.702Z,1663967369.702 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7432
2022-09-23T21:09:29.778Z,1663967369.778 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:29.778Z,1663967369.778 [WetLabsBB2FL](INFO): Powering down
2022-09-23T21:09:29.779Z,1663967369.779 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:29.782Z,1663967369.782 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-09-23T21:09:29.782Z,1663967369.782 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:29.782Z,1663967369.782 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7433
2022-09-23T21:09:30.498Z,1663967370.498 [CTD_Seabird](INFO): Powering down
2022-09-23T21:09:30.510Z,1663967370.510 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:30.510Z,1663967370.510 [CTD_Seabird](INFO): Powering down
2022-09-23T21:09:30.522Z,1663967370.522 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:30.530Z,1663967370.530 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2022-09-23T21:09:30.530Z,1663967370.530 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:30.530Z,1663967370.530 [DAT](INFO): Join timeout helper Thread ID is 7434
2022-09-23T21:09:30.658Z,1663967370.658 [DAT](INFO): Powering down
2022-09-23T21:09:30.730Z,1663967370.730 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:30.731Z,1663967370.731 [DAT](INFO): Powering down
2022-09-23T21:09:30.732Z,1663967370.732 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:30.746Z,1663967370.746 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-09-23T21:09:30.746Z,1663967370.746 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:30.746Z,1663967370.746 [Radio_Surface](INFO): Join timeout helper Thread ID is 7435
2022-09-23T21:09:30.942Z,1663967370.942 [Radio_Surface](INFO): Powering down
2022-09-23T21:09:30.943Z,1663967370.943 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:30.943Z,1663967370.943 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:30.947Z,1663967370.947 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-09-23T21:09:30.947Z,1663967370.947 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:30.947Z,1663967370.947 [Onboard](INFO): Join timeout helper Thread ID is 7436
2022-09-23T21:09:31.318Z,1663967371.318 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-09-23T21:09:35.330Z,1663967375.330 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:35.330Z,1663967375.330 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.340Z,1663967375.340 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-09-23T21:09:35.340Z,1663967375.340 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.341Z,1663967375.341 [DataOverHttps](INFO): Join timeout helper Thread ID is 7437
2022-09-23T21:09:35.349Z,1663967375.349 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:35.359Z,1663967375.359 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.367Z,1663967375.367 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2022-09-23T21:09:35.367Z,1663967375.367 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.367Z,1663967375.367 [BackseatComponent](INFO): Join timeout helper Thread ID is 7438
2022-09-23T21:09:35.546Z,1663967375.546 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:35.546Z,1663967375.546 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.548Z,1663967375.548 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-09-23T21:09:35.548Z,1663967375.548 [logger ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.548Z,1663967375.548 [logger](INFO): Join timeout helper Thread ID is 7439
2022-09-23T21:09:35.592Z,1663967375.592 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:35.593Z,1663967375.593 [logger ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.595Z,1663967375.595 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-09-23T21:09:35.595Z,1663967375.595 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.595Z,1663967375.595 [CommandLine](INFO): Join timeout helper Thread ID is 7440
2022-09-23T21:09:35.674Z,1663967375.674 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:35.674Z,1663967375.674 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.694Z,1663967375.694 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-09-23T21:09:35.695Z,1663967375.695 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.695Z,1663967375.695 [CommandExec](INFO): Join timeout helper Thread ID is 7441
2022-09-23T21:09:35.696Z,1663967375.696 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-09-23T21:09:35.696Z,1663967375.696 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:35.697Z,1663967375.697 [controlThread](INFO): Join timeout helper Thread ID is 7442
2022-09-23T21:09:35.898Z,1663967375.898 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-09-23T21:09:35.898Z,1663967375.898 [controlThread](DEBUG): Uninitializing ControlThread
2022-09-23T21:09:35.899Z,1663967375.899 [AHRS_M2](INFO): Powering down
2022-09-23T21:09:35.971Z,1663967375.971 [NAL9602](INFO): Powering down
2022-09-23T21:09:35.972Z,1663967375.972 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-09-23T21:09:35.973Z,1663967375.973 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-09-23T21:09:35.974Z,1663967375.974 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-09-23T21:09:35.974Z,1663967375.974 [MissionManager](INFO): Uninitializing Mission Default
2022-09-23T21:09:35.975Z,1663967375.975 [Default] Stopped
2022-09-23T21:09:35.975Z,1663967375.975 [Default](DEBUG): Aggregate::uninitialize Default
2022-09-23T21:09:35.975Z,1663967375.975 [Default:B.GoToSurface] Stopped
2022-09-23T21:09:35.975Z,1663967375.975 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-09-23T21:09:35.975Z,1663967375.975 [Default:CheckIn] Stopped
2022-09-23T21:09:35.975Z,1663967375.975 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-09-23T21:09:35.975Z,1663967375.975 [Default:CheckIn:C.Wait] Stopped
2022-09-23T21:09:35.975Z,1663967375.975 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-09-23T21:09:35.978Z,1663967375.978 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-09-23T21:09:35.978Z,1663967375.978 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-09-23T21:09:35.978Z,1663967375.978 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-09-23T21:09:35.979Z,1663967375.979 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-09-23T21:09:35.979Z,1663967375.979 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-09-23T21:09:35.979Z,1663967375.979 [BuoyancyServo](INFO): Powering down
2022-09-23T21:09:35.994Z,1663967375.994 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-09-23T21:09:35.994Z,1663967375.994 [ElevatorServo](INFO): Powering down
2022-09-23T21:09:35.995Z,1663967375.995 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-09-23T21:09:35.995Z,1663967375.995 [MassServo](INFO): Powering down
2022-09-23T21:09:35.996Z,1663967375.996 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-09-23T21:09:35.996Z,1663967375.996 [RudderServo](INFO): Powering down
2022-09-23T21:09:35.997Z,1663967375.997 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2022-09-23T21:09:35.997Z,1663967375.997 [ThrusterHE](INFO): Powering down
2022-09-23T21:09:35.998Z,1663967375.998 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-09-23T21:09:35.998Z,1663967375.998 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-09-23T21:09:35.998Z,1663967375.998 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-09-23T21:09:35.999Z,1663967375.999 [CBIT](DEBUG): Powering off loads.
2022-09-23T21:09:36.010Z,1663967376.010 [CBIT](DEBUG): Disabling WDT.
2022-09-23T21:09:36.022Z,1663967376.022 [CBIT](DEBUG): Opening all GF detection circuits.
2022-09-23T21:09:36.023Z,1663967376.023 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.076Z,1663967376.076 [DAT ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.081Z,1663967376.081 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.083Z,1663967376.083 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.090Z,1663967376.090 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.137Z,1663967376.137 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.175Z,1663967376.175 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.178Z,1663967376.178 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.214Z,1663967376.214 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-09-23T21:09:36.275Z,1663967376.275 [logger ThreadHandler](INFO): Thread cancelled.