2022-11-07T21:36:58.492Z,1667857018.492 [Supervisor](DEBUG): Initializing supervisor.
2022-11-07T21:36:58.496Z,1667857018.496 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-11-07T21:36:58.497Z,1667857018.497 [SyncHandler](INFO): Protected caller Thread ID is 3404
2022-11-07T21:36:58.497Z,1667857018.497 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-11-07T21:36:58.498Z,1667857018.498 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-11-07T21:36:58.498Z,1667857018.498 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3405
2022-11-07T21:36:58.502Z,1667857018.502 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-11-07T21:36:58.520Z,1667857018.520 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-11-07T21:36:58.521Z,1667857018.521 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-11-07T21:36:58.521Z,1667857018.521 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3406
2022-11-07T21:36:58.526Z,1667857018.526 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-11-07T21:36:58.528Z,1667857018.528 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-11-07T21:36:58.528Z,1667857018.528 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3407
2022-11-07T21:36:58.530Z,1667857018.530 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-11-07T21:36:58.531Z,1667857018.531 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-11-07T21:36:58.531Z,1667857018.531 [logger ThreadHandler](INFO): Protected caller Thread ID is 3408
2022-11-07T21:36:58.535Z,1667857018.535 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-11-07T21:36:58.535Z,1667857018.535 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-11-07T21:36:58.539Z,1667857018.539 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-11-07T21:36:58.865Z,1667857018.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-11-07T21:36:58.866Z,1667857018.866 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-11-07T21:36:58.948Z,1667857018.948 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-11-07T21:36:59.427Z,1667857019.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-11-07T21:36:59.427Z,1667857019.427 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-11-07T21:36:59.760Z,1667857019.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-11-07T21:36:59.761Z,1667857019.761 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-11-07T21:36:59.858Z,1667857019.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-11-07T21:36:59.859Z,1667857019.859 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-11-07T21:37:00.234Z,1667857020.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-11-07T21:37:00.235Z,1667857020.235 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-11-07T21:37:00.456Z,1667857020.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-11-07T21:37:00.457Z,1667857020.457 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-11-07T21:37:00.928Z,1667857020.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-11-07T21:37:00.929Z,1667857020.929 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-11-07T21:37:01.064Z,1667857021.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-11-07T21:37:01.065Z,1667857021.065 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-11-07T21:37:01.144Z,1667857021.144 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-11-07T21:37:01.867Z,1667857021.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-11-07T21:37:01.868Z,1667857021.868 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-11-07T21:37:02.878Z,1667857022.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-11-07T21:37:02.879Z,1667857022.879 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-11-07T21:37:03.073Z,1667857023.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-11-07T21:37:03.073Z,1667857023.073 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-11-07T21:37:03.183Z,1667857023.183 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-11-07T21:37:03.184Z,1667857023.184 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-11-07T21:37:03.408Z,1667857023.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-11-07T21:37:03.409Z,1667857023.409 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-11-07T21:37:03.653Z,1667857023.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-11-07T21:37:03.654Z,1667857023.654 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-11-07T21:37:03.657Z,1667857023.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-11-07T21:37:03.753Z,1667857023.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-11-07T21:37:03.839Z,1667857023.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-11-07T21:37:03.942Z,1667857023.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-11-07T21:37:04.026Z,1667857024.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-11-07T21:37:04.123Z,1667857024.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-11-07T21:37:04.227Z,1667857024.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-11-07T21:37:04.417Z,1667857024.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-11-07T21:37:04.493Z,1667857024.493 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-11-07T21:37:04.642Z,1667857024.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-11-07T21:37:04.781Z,1667857024.781 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-11-07T21:37:04.897Z,1667857024.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-11-07T21:37:05.157Z,1667857025.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-11-07T21:37:05.158Z,1667857025.158 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2022-11-07T21:37:05.158Z,1667857025.158 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-11-07T21:37:05.177Z,1667857025.177 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-11-07T21:37:05.246Z,1667857025.246 [VerticalControl](DEBUG): Construct VerticalControl.
2022-11-07T21:37:05.305Z,1667857025.305 [VerticalControl] Loaded
2022-11-07T21:37:05.305Z,1667857025.305 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-11-07T21:37:05.308Z,1667857025.308 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-11-07T21:37:05.348Z,1667857025.348 [HorizontalControl] Loaded
2022-11-07T21:37:05.348Z,1667857025.348 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-11-07T21:37:05.350Z,1667857025.350 [SpeedControl](DEBUG): Construct SpeedControl.
2022-11-07T21:37:05.353Z,1667857025.353 [SpeedControl] Loaded
2022-11-07T21:37:05.354Z,1667857025.354 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-11-07T21:37:05.356Z,1667857025.356 [LoopControl](DEBUG): Construct LoopControl.
2022-11-07T21:37:05.357Z,1667857025.357 [LoopControl] Loaded
2022-11-07T21:37:05.357Z,1667857025.357 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-11-07T21:37:05.357Z,1667857025.357 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-11-07T21:37:05.358Z,1667857025.358 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-11-07T21:37:05.375Z,1667857025.375 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-11-07T21:37:05.375Z,1667857025.375 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-11-07T21:37:05.561Z,1667857025.561 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-11-07T21:37:05.561Z,1667857025.561 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-11-07T21:37:05.712Z,1667857025.712 [BuoyancyServo] Loaded
2022-11-07T21:37:05.712Z,1667857025.712 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-11-07T21:37:05.731Z,1667857025.731 [ElevatorServo] Loaded
2022-11-07T21:37:05.732Z,1667857025.732 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-11-07T21:37:05.751Z,1667857025.751 [MassServo] Loaded
2022-11-07T21:37:05.751Z,1667857025.751 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-11-07T21:37:05.769Z,1667857025.769 [RudderServo] Loaded
2022-11-07T21:37:05.769Z,1667857025.769 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-11-07T21:37:05.786Z,1667857025.786 [ThrusterServo] Loaded
2022-11-07T21:37:05.786Z,1667857025.786 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-11-07T21:37:05.787Z,1667857025.787 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-11-07T21:37:05.787Z,1667857025.787 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-11-07T21:37:05.926Z,1667857025.926 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-11-07T21:37:05.926Z,1667857025.926 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-11-07T21:37:05.939Z,1667857025.939 [NavChart] Loaded
2022-11-07T21:37:05.940Z,1667857025.940 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-11-07T21:37:05.945Z,1667857025.945 [UniversalFixResidualReporter] Loaded
2022-11-07T21:37:05.946Z,1667857025.946 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-11-07T21:37:05.946Z,1667857025.946 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-11-07T21:37:05.947Z,1667857025.947 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-11-07T21:37:06.030Z,1667857026.030 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-11-07T21:37:06.030Z,1667857026.030 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-11-07T21:37:06.782Z,1667857026.782 [AHRS_M2] Loaded
2022-11-07T21:37:06.783Z,1667857026.783 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-11-07T21:37:07.571Z,1667857027.571 [BPC1] Loaded
2022-11-07T21:37:07.572Z,1667857027.572 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-11-07T21:37:07.666Z,1667857027.666 [DataOverHttps] Loaded
2022-11-07T21:37:07.667Z,1667857027.667 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-11-07T21:37:07.668Z,1667857027.668 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F04E0
2022-11-07T21:37:07.668Z,1667857027.668 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3494
2022-11-07T21:37:07.689Z,1667857027.689 [Depth_Keller] Loaded
2022-11-07T21:37:07.689Z,1667857027.689 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-11-07T21:37:07.694Z,1667857027.694 [DropWeight] Loaded
2022-11-07T21:37:07.694Z,1667857027.694 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-11-07T21:37:07.757Z,1667857027.757 [NAL9602] Loaded
2022-11-07T21:37:07.757Z,1667857027.757 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-11-07T21:37:07.787Z,1667857027.787 [Onboard] Loaded
2022-11-07T21:37:07.787Z,1667857027.787 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-11-07T21:37:07.788Z,1667857027.788 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409204E0
2022-11-07T21:37:07.788Z,1667857027.788 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3495
2022-11-07T21:37:07.801Z,1667857027.801 [Power24vConverter] Loaded
2022-11-07T21:37:07.802Z,1667857027.802 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-11-07T21:37:07.815Z,1667857027.815 [Radio_Surface] Loaded
2022-11-07T21:37:07.815Z,1667857027.815 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-11-07T21:37:07.816Z,1667857027.816 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409504E0
2022-11-07T21:37:07.816Z,1667857027.816 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3496
2022-11-07T21:37:07.817Z,1667857027.817 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-11-07T21:37:07.818Z,1667857027.818 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-11-07T21:37:08.196Z,1667857028.196 [DepthRateCalculator] Loaded
2022-11-07T21:37:08.196Z,1667857028.196 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-11-07T21:37:08.201Z,1667857028.201 [PitchRateCalculator] Loaded
2022-11-07T21:37:08.201Z,1667857028.201 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-11-07T21:37:08.253Z,1667857028.253 [SpeedCalculator] Loaded
2022-11-07T21:37:08.254Z,1667857028.254 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-11-07T21:37:08.266Z,1667857028.266 [YawRateCalculator] Loaded
2022-11-07T21:37:08.266Z,1667857028.266 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-11-07T21:37:08.352Z,1667857028.352 [ElevatorOffsetCalculator] Loaded
2022-11-07T21:37:08.353Z,1667857028.353 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-11-07T21:37:08.353Z,1667857028.353 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-11-07T21:37:08.354Z,1667857028.354 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-11-07T21:37:09.081Z,1667857029.081 [CANONSampler] Loaded
2022-11-07T21:37:09.081Z,1667857029.081 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-11-07T21:37:09.139Z,1667857029.139 [CTD_Seabird] Loaded
2022-11-07T21:37:09.139Z,1667857029.139 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-11-07T21:37:09.140Z,1667857029.140 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A094E0
2022-11-07T21:37:09.141Z,1667857029.141 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3497
2022-11-07T21:37:09.162Z,1667857029.162 [PAR_Licor] Loaded
2022-11-07T21:37:09.162Z,1667857029.162 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-11-07T21:37:09.192Z,1667857029.192 [WetLabsBB2FL] Loaded
2022-11-07T21:37:09.193Z,1667857029.193 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-11-07T21:37:09.194Z,1667857029.194 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A394E0
2022-11-07T21:37:09.194Z,1667857029.194 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3498
2022-11-07T21:37:09.195Z,1667857029.195 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-11-07T21:37:09.195Z,1667857029.195 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-11-07T21:37:09.239Z,1667857029.239 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-11-07T21:37:09.239Z,1667857029.239 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-11-07T21:37:09.597Z,1667857029.597 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-11-07T21:37:09.598Z,1667857029.598 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-11-07T21:37:09.773Z,1667857029.773 [SBIT](DEBUG): Construct Startup Built In Test.
2022-11-07T21:37:09.781Z,1667857029.781 [SBIT] Loaded
2022-11-07T21:37:09.782Z,1667857029.782 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-11-07T21:37:09.784Z,1667857029.784 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-11-07T21:37:09.797Z,1667857029.797 [IBIT] Loaded
2022-11-07T21:37:09.797Z,1667857029.797 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-11-07T21:37:09.803Z,1667857029.803 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-11-07T21:37:09.906Z,1667857029.906 [CBIT] Loaded
2022-11-07T21:37:09.906Z,1667857029.906 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-11-07T21:37:09.906Z,1667857029.906 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-11-07T21:37:09.907Z,1667857029.907 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-11-07T21:37:10.024Z,1667857030.024 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-11-07T21:37:10.030Z,1667857030.030 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-11-07T21:37:10.033Z,1667857030.033 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-11-07T21:37:10.044Z,1667857030.044 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-11-07T21:37:10.046Z,1667857030.046 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B6E4E0
2022-11-07T21:37:10.046Z,1667857030.046 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3499
2022-11-07T21:37:10.050Z,1667857030.050 [Supervisor](INFO): Main Thread ID is 3403
2022-11-07T21:37:10.051Z,1667857030.051 [Supervisor](DEBUG): Running supervisor.
2022-11-07T21:37:10.051Z,1667857030.051 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3500
2022-11-07T21:37:10.052Z,1667857030.052 [CommandExec](INFO): Initializing the command executive.
2022-11-07T21:37:10.053Z,1667857030.053 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3501
2022-11-07T21:37:10.056Z,1667857030.056 [controlThread ThreadHandler](INFO): Handler Thread ID is 3502
2022-11-07T21:37:10.056Z,1667857030.056 [controlThread](DEBUG): Initializing ControlThread
2022-11-07T21:37:10.057Z,1667857030.057 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-11-07T21:37:10.059Z,1667857030.059 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-11-07T21:37:10.059Z,1667857030.059 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-11-07T21:37:10.060Z,1667857030.060 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-11-07T21:37:10.062Z,1667857030.062 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-11-07T21:37:10.062Z,1667857030.062 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-11-07T21:37:10.066Z,1667857030.066 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-11-07T21:37:10.066Z,1667857030.066 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-11-07T21:37:10.067Z,1667857030.067 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-11-07T21:37:10.067Z,1667857030.067 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-11-07T21:37:10.067Z,1667857030.067 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-11-07T21:37:10.072Z,1667857030.072 [SBIT](INFO): Initialize SBIT Component.
2022-11-07T21:37:10.073Z,1667857030.073 [SBIT](IMPORTANT): git: 2022-10-07
2022-11-07T21:37:10.073Z,1667857030.073 [SBIT](INFO): git hash: 59ae116cc22d398180fea642041930be307d5d31
2022-11-07T21:37:10.073Z,1667857030.073 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-11-07T21:37:10.074Z,1667857030.074 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2022-11-07T21:37:10.075Z,1667857030.075 [SBIT](INFO): Beginning SBIT in 77.000000 seconds.
2022-11-07T21:37:10.076Z,1667857030.076 [IBIT](INFO): Initialize IBIT Component.
2022-11-07T21:37:10.077Z,1667857030.077 [CBIT](DEBUG): Initialize CBIT Component.
2022-11-07T21:37:10.078Z,1667857030.078 [logger ThreadHandler](INFO): Handler Thread ID is 3503
2022-11-07T21:37:10.088Z,1667857030.088 [CBIT](DEBUG): Initialized mux pins.
2022-11-07T21:37:10.088Z,1667857030.088 [CBIT](DEBUG): Initializing the watchdog timer.
2022-11-07T21:37:10.096Z,1667857030.096 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3504
2022-11-07T21:37:10.097Z,1667857030.097 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-11-07T21:37:10.108Z,1667857030.108 [Onboard ThreadHandler](INFO): Handler Thread ID is 3505
2022-11-07T21:37:10.112Z,1667857030.112 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-11-07T21:37:10.113Z,1667857030.113 [CBIT](DEBUG): Initializing heartbeat.
2022-11-07T21:37:10.129Z,1667857030.129 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3506
2022-11-07T21:37:10.145Z,1667857030.145 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3507
2022-11-07T21:37:10.146Z,1667857030.146 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-11-07T21:37:10.149Z,1667857030.149 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3509
2022-11-07T21:37:10.152Z,1667857030.152 [WetLabsBB2FL](INFO): Powering up
2022-11-07T21:37:10.154Z,1667857030.154 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3511
2022-11-07T21:37:10.161Z,1667857030.161 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-11-07T21:37:10.162Z,1667857030.162 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-11-07T21:37:10.162Z,1667857030.162 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-11-07T21:37:10.162Z,1667857030.162 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-11-07T21:37:10.162Z,1667857030.162 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-11-07T21:37:10.162Z,1667857030.162 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-11-07T21:37:10.162Z,1667857030.162 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-11-07T21:37:10.162Z,1667857030.162 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-11-07T21:37:10.184Z,1667857030.184 [CBIT](DEBUG): Deactivating GF circuits.
2022-11-07T21:37:10.184Z,1667857030.184 [CBIT](DEBUG): Deactivating emergency mode.
2022-11-07T21:37:10.220Z,1667857030.220 [CBIT](DEBUG): Backplane powered.
2022-11-07T21:37:10.221Z,1667857030.221 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-11-07T21:37:10.248Z,1667857030.248 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-11-07T21:37:10.275Z,1667857030.275 [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-11-07T21:37:10.292Z,1667857030.292 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-11-07T21:37:10.389Z,1667857030.389 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-11-07T21:37:10.390Z,1667857030.390 [Default:A.Wait](DEBUG): Construct Wait.
2022-11-07T21:37:10.401Z,1667857030.401 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-11-07T21:37:10.439Z,1667857030.439 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-11-07T21:37:10.442Z,1667857030.442 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-11-07T21:37:10.481Z,1667857030.481 [Default:E.Execute](DEBUG): Construct Execute.
2022-11-07T21:37:10.484Z,1667857030.484 [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-11-07T21:37:10.504Z,1667857030.504 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-11-07T21:37:10.510Z,1667857030.510 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-11-07T21:37:10.536Z,1667857030.536 [Radio_Surface](INFO): Powering up
2022-11-07T21:37:10.604Z,1667857030.604 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2022-11-07T21:37:10.606Z,1667857030.606 [Power24vConverter](INFO): Powering up.
2022-11-07T21:37:10.606Z,1667857030.606 [CANONSampler](INFO): Powering down
2022-11-07T21:37:10.622Z,1667857030.622 [DepthRateCalculator](ERROR): Depth measurement is not active
2022-11-07T21:37:10.709Z,1667857030.709 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-11-07T21:37:10.716Z,1667857030.716 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-11-07T21:37:10.717Z,1667857030.717 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-11-07T21:37:10.725Z,1667857030.725 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-11-07T21:37:10.726Z,1667857030.726 [MassServo](DEBUG): Initializing EZServoServo.
2022-11-07T21:37:10.733Z,1667857030.733 [MassServo](DEBUG): Initializing MassServo.
2022-11-07T21:37:10.734Z,1667857030.734 [RudderServo](DEBUG): Initializing EZServoServo.
2022-11-07T21:37:10.740Z,1667857030.740 [RudderServo](DEBUG): Initializing RudderServo.
2022-11-07T21:37:10.742Z,1667857030.742 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-11-07T21:37:10.748Z,1667857030.748 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-11-07T21:37:11.684Z,1667857031.684 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-11-07T21:37:11.684Z,1667857031.684 [RudderServo](FAULT): Rudder failed to initialize
2022-11-07T21:37:11.684Z,1667857031.684 [RudderServo] Communications Fault, FailCount= 1
2022-11-07T21:37:11.684Z,1667857031.684 [RudderServo](ERROR): Communications Fault
2022-11-07T21:37:11.784Z,1667857031.784 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-11-07T21:37:11.997Z,1667857031.997 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-11-07T21:37:11.997Z,1667857031.997 [RudderServo](INFO): Powering down
2022-11-07T21:37:12.260Z,1667857032.260 [WetLabsBB2FL](INFO): Powering down
2022-11-07T21:37:12.635Z,1667857032.635 [RudderServo](DEBUG): Initializing EZServoServo.
2022-11-07T21:37:12.753Z,1667857032.753 [RudderServo](DEBUG): Initializing RudderServo.
2022-11-07T21:37:12.758Z,1667857032.758 [CBIT](INFO): Clearing failed state for component RudderServo
2022-11-07T21:37:12.758Z,1667857032.758 [RudderServo] No Fault, FailCount= 1
2022-11-07T21:37:17.176Z,1667857037.177 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2022-11-07T21:37:23.866Z,1667857043.866 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004743
2022-11-07T21:37:38.058Z,1667857058.058 [NAL9602](INFO): Powering up NAL9602
2022-11-07T21:37:48.974Z,1667857068.974 [NAL9602](INFO): NAL9602 initialized
2022-11-07T21:38:27.799Z,1667857107.799 [SBIT](IMPORTANT): Beginning Startup BIT
2022-11-07T21:38:27.803Z,1667857107.803 [CBIT](IMPORTANT): Beginning ground fault scan
2022-11-07T21:38:38.745Z,1667857118.745 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-11-07T21:38:39.145Z,1667857119.145 [CBIT](IMPORTANT): Beginning ground fault scan
2022-11-07T21:38:50.037Z,1667857130.037 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-11-07T21:38:50.434Z,1667857130.434 [CBIT](IMPORTANT): Beginning ground fault scan
2022-11-07T21:39:01.302Z,1667857141.302 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-11-07T21:39:01.303Z,1667857141.303 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2022-11-07T21:39:21.903Z,1667857161.903 [SBIT](IMPORTANT): SBIT PASSED
2022-11-07T21:39:21.903Z,1667857161.903 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-11-07T21:39:21.904Z,1667857161.904 [SBIT](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2022-11-07T21:39:21.904Z,1667857161.904 [SBIT](IMPORTANT): AHRS_M2.readAngularVelocities=0 bool;

2022-11-07T21:39:21.904Z,1667857161.904 [SBIT](IMPORTANT): AHRS_M2.readMagnetics=0 bool;

2022-11-07T21:39:21.904Z,1667857161.904 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-11-07T21:39:21.904Z,1667857161.904 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): CANONSampler.sampleTimeout=10 minute;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2022-11-07T21:39:21.905Z,1667857161.905 [SBIT](IMPORTANT): Express linearApproximation ThrusterServo.component_avgCurrent 10.000000 milliampere;

2022-11-07T21:39:21.906Z,1667857161.906 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=311.602055 cubic_centimeter;

2022-11-07T21:39:21.906Z,1667857161.906 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.422383 millimeter;

2022-11-07T21:39:22.333Z,1667857162.333 [MissionManager](IMPORTANT): Started mission Startup
2022-11-07T21:39:22.334Z,1667857162.334 [Startup] Running Loop=1
2022-11-07T21:39:22.334Z,1667857162.334 [Startup](DEBUG): Aggregate::initialize Startup
2022-11-07T21:39:22.334Z,1667857162.334 [Startup:A.GoToSurface] Running Loop=1
2022-11-07T21:39:22.334Z,1667857162.334 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-07T21:39:22.335Z,1667857162.335 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-07T21:39:22.335Z,1667857162.335 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-07T21:39:22.335Z,1667857162.335 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-07T21:39:22.336Z,1667857162.336 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-07T21:39:22.336Z,1667857162.336 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-07T21:39:22.338Z,1667857162.338 [Startup:StartupSatComms] Running Loop=1
2022-11-07T21:39:22.338Z,1667857162.338 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-11-07T21:39:22.338Z,1667857162.338 [Startup:StartupSatComms:A] Running Loop=1
2022-11-07T21:39:22.731Z,1667857162.731 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-11-07T21:40:10.381Z,1667857210.381 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-11-07T21:40:10.381Z,1667857210.381 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T21:40:10.400Z,1667857210.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T21:40:10.802Z,1667857210.802 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T21:40:10.802Z,1667857210.802 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-11-07T21:40:22.514Z,1667857222.514 [Startup:StartupSatComms:A](INFO): Timed out from 2022-11-07T21:39:22.3Z
2022-11-07T21:40:22.515Z,1667857222.515 [Startup:StartupSatComms:A] Stopped
2022-11-07T21:40:22.515Z,1667857222.515 [Startup:StartupSatComms:B] Running Loop=1
2022-11-07T21:40:22.920Z,1667857222.920 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-11-07T21:40:29.638Z,1667857229.638 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20221018T193521/Courier0375.lzma
2022-11-07T21:40:30.638Z,1667857230.638 [DataOverHttps](INFO): Moved sent file to Logs/20221018T193521/Courier0375.lzma.bak
2022-11-07T21:40:30.638Z,1667857230.638 [DataOverHttps](INFO): SBD MOMSN=17270696
2022-11-07T21:40:46.407Z,1667857246.407 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20221019T135652/Courier0000.lzma
2022-11-07T21:40:47.406Z,1667857247.406 [DataOverHttps](INFO): Moved sent file to Logs/20221019T135652/Courier0000.lzma.bak
2022-11-07T21:40:47.406Z,1667857247.406 [DataOverHttps](INFO): SBD MOMSN=17270699
2022-11-07T21:41:02.980Z,1667857262.980 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20221107T213658/Courier0000.lzma
2022-11-07T21:41:03.982Z,1667857263.982 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0000.lzma.bak
2022-11-07T21:41:03.982Z,1667857263.982 [DataOverHttps](INFO): SBD MOMSN=17270704
2022-11-07T21:41:19.270Z,1667857279.270 [DataOverHttps](INFO): Sending 461 bytes from file Logs/20221018T193521/Express0376.lzma
2022-11-07T21:41:20.270Z,1667857280.270 [DataOverHttps](INFO): Moved sent file to Logs/20221018T193521/Express0376.lzma.bak
2022-11-07T21:41:20.270Z,1667857280.270 [DataOverHttps](INFO): SBD MOMSN=17270707
2022-11-07T21:41:22.736Z,1667857282.736 [Startup:StartupSatComms:B](INFO): Timed out from 2022-11-07T21:40:22.5Z
2022-11-07T21:41:22.737Z,1667857282.737 [Startup:StartupSatComms:B] Stopped
2022-11-07T21:41:22.737Z,1667857282.737 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-11-07T21:41:22.737Z,1667857282.737 [Startup:StartupSatComms] Stopped
2022-11-07T21:41:22.737Z,1667857282.737 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-11-07T21:41:22.738Z,1667857282.738 [Startup](INFO): Completed Startup
2022-11-07T21:41:22.738Z,1667857282.738 [MissionManager](INFO): Startup is completed.
2022-11-07T21:41:22.738Z,1667857282.738 [MissionManager](INFO): Uninitializing Mission Startup
2022-11-07T21:41:22.738Z,1667857282.738 [Startup] Stopped
2022-11-07T21:41:22.738Z,1667857282.738 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-11-07T21:41:22.738Z,1667857282.738 [Startup:A.GoToSurface] Stopped
2022-11-07T21:41:22.738Z,1667857282.738 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-07T21:41:23.145Z,1667857283.145 [MissionManager](IMPORTANT): Started mission Default
2022-11-07T21:41:23.145Z,1667857283.145 [Default] Running Loop=1
2022-11-07T21:41:23.145Z,1667857283.145 [Default](DEBUG): Aggregate::initialize Default
2022-11-07T21:41:23.145Z,1667857283.145 [Default:B.GoToSurface] Running Loop=1
2022-11-07T21:41:23.146Z,1667857283.146 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-07T21:41:23.146Z,1667857283.146 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-07T21:41:23.146Z,1667857283.146 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-07T21:41:23.146Z,1667857283.146 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-07T21:41:23.147Z,1667857283.147 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-07T21:41:23.147Z,1667857283.147 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-07T21:41:23.147Z,1667857283.147 [Default:A.Wait] Running Loop=1
2022-11-07T21:41:23.147Z,1667857283.147 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-07T21:41:35.797Z,1667857295.797 [DataOverHttps](INFO): Sending 600 bytes from file Logs/20221019T135652/Express0001.lzma
2022-11-07T21:41:36.475Z,1667857296.475 [Default:A.Wait](INFO): Done Waiting.
2022-11-07T21:41:36.475Z,1667857296.475 [Default:A.Wait] Stopped
2022-11-07T21:41:36.475Z,1667857296.475 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T21:41:36.798Z,1667857296.798 [DataOverHttps](INFO): Moved sent file to Logs/20221019T135652/Express0001.lzma.bak
2022-11-07T21:41:36.798Z,1667857296.798 [DataOverHttps](INFO): SBD MOMSN=17270717
2022-11-07T21:41:36.883Z,1667857296.883 [Default:CheckIn] Running Loop=1
2022-11-07T21:41:36.883Z,1667857296.883 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T21:41:36.883Z,1667857296.883 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T21:41:37.275Z,1667857297.275 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-11-07T21:42:07.195Z,1667857327.195 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:42:22.542Z,1667857342.542 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:42:37.513Z,1667857357.513 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:42:52.511Z,1667857372.511 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-07T21:42:52.864Z,1667857372.864 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:43:08.620Z,1667857388.620 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:43:11.464Z,1667857391.464 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-11-07T21:43:11.464Z,1667857391.464 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T21:43:11.475Z,1667857391.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T21:43:11.895Z,1667857391.895 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T21:43:11.895Z,1667857391.895 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-11-07T21:43:23.568Z,1667857403.568 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:43:38.921Z,1667857418.921 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:43:54.272Z,1667857434.272 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:44:09.220Z,1667857449.220 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T21:44:24.169Z,1667857464.169 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2022-11-07T21:44:37.508Z,1667857477.508 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2022-11-07T21:44:37.511Z,1667857477.511 [BPC1](INFO): Received data from all battery sticks.
2022-11-07T21:46:12.452Z,1667857572.452 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-11-07T21:46:12.452Z,1667857572.452 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T21:46:12.463Z,1667857572.463 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T21:46:12.899Z,1667857572.899 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T21:46:12.899Z,1667857572.899 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-11-07T21:46:37.138Z,1667857597.138 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T21:41:36.9Z
2022-11-07T21:46:37.138Z,1667857597.138 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T21:46:37.138Z,1667857597.138 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T21:46:37.522Z,1667857597.522 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-11-07T21:46:38.165Z,1667857598.165 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20221107T213658/Courier0004.lzma
2022-11-07T21:46:39.113Z,1667857599.113 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0004.lzma.bak
2022-11-07T21:46:39.114Z,1667857599.114 [DataOverHttps](INFO): SBD MOMSN=17270733
2022-11-07T21:46:54.498Z,1667857614.498 [DataOverHttps](INFO): Sending 986 bytes from file Logs/20221107T213658/Express0001.lzma
2022-11-07T21:46:55.498Z,1667857615.498 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0001.lzma.bak
2022-11-07T21:46:55.498Z,1667857615.498 [DataOverHttps](INFO): SBD MOMSN=17270735
2022-11-07T21:47:10.923Z,1667857630.923 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20221107T213658/Express0005.lzma
2022-11-07T21:47:11.471Z,1667857631.471 [Power24vConverter](INFO): Powering down.
2022-11-07T21:47:11.926Z,1667857631.926 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0005.lzma.bak
2022-11-07T21:47:11.926Z,1667857631.926 [DataOverHttps](INFO): SBD MOMSN=17270764
2022-11-07T21:47:13.126Z,1667857633.126 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T21:47:13.126Z,1667857633.126 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T21:47:13.127Z,1667857633.127 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T21:47:49.853Z,1667857669.853 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-11-07T21:47:49.853Z,1667857669.853 [NAL9602] Data Fault, FailCount= 1
2022-11-07T21:47:49.853Z,1667857669.853 [NAL9602](ERROR): Data Fault
2022-11-07T21:47:49.870Z,1667857669.870 [CBIT](ERROR): Data Fault in component: NAL9602
2022-11-07T21:47:50.253Z,1667857670.253 [NAL9602](INFO): Powering down
2022-11-07T21:47:51.079Z,1667857671.079 [CBIT](INFO): Clearing failed state for component NAL9602
2022-11-07T21:47:51.079Z,1667857671.079 [NAL9602] No Fault, FailCount= 1
2022-11-07T21:48:20.553Z,1667857700.553 [NAL9602](INFO): Powering up NAL9602
2022-11-07T21:48:31.465Z,1667857711.465 [NAL9602](INFO): NAL9602 initialized
2022-11-07T21:49:13.484Z,1667857753.484 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-11-07T21:49:13.484Z,1667857753.484 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T21:49:13.495Z,1667857753.495 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T21:49:13.897Z,1667857753.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T21:49:13.897Z,1667857753.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-11-07T21:52:13.661Z,1667857933.661 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T21:52:13.661Z,1667857933.661 [Default:CheckIn:C.Wait] Stopped
2022-11-07T21:52:13.662Z,1667857933.662 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T21:52:13.662Z,1667857933.662 [Default:CheckIn:D] Running Loop=1
2022-11-07T21:52:14.086Z,1667857934.086 [Default:CheckIn:D] Stopped
2022-11-07T21:52:14.086Z,1667857934.086 [Default:CheckIn:E] Running Loop=1
2022-11-07T21:52:14.497Z,1667857934.497 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-11-07T21:52:14.497Z,1667857934.497 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.849010 min 
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn:E] Stopped
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn] Stopped
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn](INFO): Running loop #2
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn] Running Loop=2
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T21:52:14.499Z,1667857934.499 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T21:52:14.509Z,1667857934.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T21:52:14.907Z,1667857934.907 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T21:52:14.907Z,1667857934.907 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-11-07T21:55:15.476Z,1667858115.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-11-07T21:55:15.476Z,1667858115.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T21:55:15.487Z,1667858115.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T21:55:15.900Z,1667858115.900 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T21:55:15.901Z,1667858115.901 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-11-07T21:57:14.649Z,1667858234.649 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T21:52:14.5Z
2022-11-07T21:57:14.649Z,1667858234.649 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T21:57:14.649Z,1667858234.649 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T21:57:21.651Z,1667858241.651 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20221107T213658/Courier0007.lzma
2022-11-07T21:57:22.654Z,1667858242.654 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0007.lzma.bak
2022-11-07T21:57:22.654Z,1667858242.654 [DataOverHttps](INFO): SBD MOMSN=17270782
2022-11-07T21:57:38.171Z,1667858258.171 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20221107T213658/Express0008.lzma
2022-11-07T21:57:39.174Z,1667858259.174 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0008.lzma.bak
2022-11-07T21:57:39.174Z,1667858259.174 [DataOverHttps](INFO): SBD MOMSN=17270784
2022-11-07T21:57:40.530Z,1667858260.530 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T21:57:40.530Z,1667858260.530 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T21:57:40.530Z,1667858260.530 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T21:58:16.456Z,1667858296.456 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2022-11-07T21:58:16.456Z,1667858296.456 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T21:58:16.466Z,1667858296.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T21:58:16.869Z,1667858296.869 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T21:58:16.869Z,1667858296.869 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2022-11-07T21:58:33.421Z,1667858313.421 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-11-07T21:58:33.421Z,1667858313.421 [NAL9602] Data Fault, FailCount= 2
2022-11-07T21:58:33.421Z,1667858313.421 [NAL9602](ERROR): Data Fault
2022-11-07T21:58:33.438Z,1667858313.438 [CBIT](ERROR): Data Fault in component: NAL9602
2022-11-07T21:58:33.825Z,1667858313.825 [NAL9602](INFO): Powering down
2022-11-07T21:58:34.657Z,1667858314.657 [CBIT](INFO): Clearing failed state for component NAL9602
2022-11-07T21:58:34.657Z,1667858314.657 [NAL9602] No Fault, FailCount= 2
2022-11-07T21:59:04.125Z,1667858344.125 [NAL9602](INFO): Powering up NAL9602
2022-11-07T21:59:15.029Z,1667858355.029 [NAL9602](INFO): NAL9602 initialized
2022-11-07T22:01:17.464Z,1667858477.464 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2022-11-07T22:01:17.464Z,1667858477.464 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T22:01:17.486Z,1667858477.486 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T22:01:17.879Z,1667858477.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T22:01:17.879Z,1667858477.879 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2022-11-07T22:02:41.095Z,1667858561.095 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T22:02:41.095Z,1667858561.095 [Default:CheckIn:C.Wait] Stopped
2022-11-07T22:02:41.095Z,1667858561.095 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T22:02:41.095Z,1667858561.095 [Default:CheckIn:D] Running Loop=1
2022-11-07T22:02:41.484Z,1667858561.484 [Default:CheckIn:D] Stopped
2022-11-07T22:02:41.485Z,1667858561.485 [Default:CheckIn:E] Running Loop=1
2022-11-07T22:02:41.893Z,1667858561.893 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.305652 min 
2022-11-07T22:02:41.893Z,1667858561.893 [Default:CheckIn:E] Stopped
2022-11-07T22:02:41.893Z,1667858561.893 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T22:02:41.893Z,1667858561.893 [Default:CheckIn] Stopped
2022-11-07T22:02:41.893Z,1667858561.893 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T22:02:41.894Z,1667858561.894 [Default:CheckIn](INFO): Running loop #3
2022-11-07T22:02:41.894Z,1667858561.894 [Default:CheckIn] Running Loop=3
2022-11-07T22:02:41.894Z,1667858561.894 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T22:02:41.894Z,1667858561.894 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T22:04:18.457Z,1667858658.457 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2022-11-07T22:04:18.457Z,1667858658.457 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T22:04:18.481Z,1667858658.481 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T22:04:18.883Z,1667858658.883 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T22:04:18.883Z,1667858658.883 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2022-11-07T22:05:01.236Z,1667858701.236 [CommandExec](IMPORTANT): got command failComponent
2022-11-07T22:05:01.237Z,1667858701.237 [CommandExec](IMPORTANT): Failed components:
2022-11-07T22:05:01.237Z,1667858701.237 [CommandExec](IMPORTANT): No failed Components.
2022-11-07T22:05:08.396Z,1667858708.396 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2022-11-07T22:05:08.397Z,1667858708.397 [CommandExec](IMPORTANT): Onboard.Pressure 68516.750000 Pa
2022-11-07T22:05:14.803Z,1667858714.803 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-11-07T22:05:14.804Z,1667858714.804 [CommandExec](IMPORTANT): Onboard.Humidity 0.000000 %
2022-11-07T22:05:26.377Z,1667858726.377 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-11-07T22:05:26.377Z,1667858726.377 [CommandExec](IMPORTANT): Onboard.Humidity 0.000000 %
2022-11-07T22:05:35.817Z,1667858735.817 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2022-11-07T22:05:35.818Z,1667858735.818 [CommandExec](IMPORTANT): Onboard.Humidity 0.000000 %
2022-11-07T22:06:28.056Z,1667858788.056 [CommandExec](IMPORTANT): got command failComponent
2022-11-07T22:06:28.056Z,1667858788.056 [CommandExec](IMPORTANT): Failed components:
2022-11-07T22:06:28.057Z,1667858788.057 [CommandExec](IMPORTANT): No failed Components.
2022-11-07T22:07:14.792Z,1667858834.792 [CommandExec](IMPORTANT): got command failComponent
2022-11-07T22:07:14.793Z,1667858834.793 [CommandExec](IMPORTANT): Failed components:
2022-11-07T22:07:14.793Z,1667858834.793 [CommandExec](IMPORTANT): No failed Components.
2022-11-07T22:07:17.813Z,1667858837.813 [CommandExec](IMPORTANT): got command gfscan
2022-11-07T22:07:17.876Z,1667858837.876 [CBIT](IMPORTANT): Beginning ground fault scan
2022-11-07T22:07:19.441Z,1667858839.441 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2022-11-07T22:07:19.441Z,1667858839.441 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T22:07:19.476Z,1667858839.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T22:07:19.865Z,1667858839.865 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T22:07:19.865Z,1667858839.865 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2022-11-07T22:07:20.691Z,1667858840.691 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2022-11-07T22:07:28.750Z,1667858848.750 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002798
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002286
CHAN A3 (5V): -0.001438
CHAN B0 (3.3V): -0.000090
CHAN B1 (3.15aV): -0.000235
CHAN B2 (3.15bV): -0.000148
CHAN B3 (GND): -0.000048
OPEN: -0.000704
Full Scale: +/- 1 mA
2022-11-07T22:07:42.065Z,1667858862.065 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T22:02:41.9Z
2022-11-07T22:07:42.066Z,1667858862.066 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T22:07:42.066Z,1667858862.066 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T22:07:49.539Z,1667858869.539 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20221107T213658/Courier0010.lzma
2022-11-07T22:07:50.542Z,1667858870.542 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0010.lzma.bak
2022-11-07T22:07:50.542Z,1667858870.542 [DataOverHttps](INFO): SBD MOMSN=17270801
2022-11-07T22:08:05.957Z,1667858885.957 [DataOverHttps](INFO): Sending 516 bytes from file Logs/20221107T213658/Express0011.lzma
2022-11-07T22:08:06.958Z,1667858886.958 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0011.lzma.bak
2022-11-07T22:08:06.958Z,1667858886.958 [DataOverHttps](INFO): SBD MOMSN=17270803
2022-11-07T22:08:08.334Z,1667858888.334 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T22:08:08.334Z,1667858888.334 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T22:08:08.334Z,1667858888.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T22:09:16.190Z,1667858956.190 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-11-07T22:09:16.190Z,1667858956.190 [NAL9602] Data Fault, FailCount= 3
2022-11-07T22:09:16.190Z,1667858956.190 [NAL9602](ERROR): Data Fault
2022-11-07T22:09:16.207Z,1667858956.207 [CBIT](ERROR): Data Fault in component: NAL9602
2022-11-07T22:09:16.589Z,1667858956.589 [NAL9602](INFO): Powering down
2022-11-07T22:09:17.413Z,1667858957.413 [CBIT](INFO): Clearing failed state for component NAL9602
2022-11-07T22:09:17.414Z,1667858957.414 [NAL9602] No Fault, FailCount= 3
2022-11-07T22:09:46.882Z,1667858986.882 [NAL9602](INFO): Powering up NAL9602
2022-11-07T22:09:57.797Z,1667858997.797 [NAL9602](INFO): NAL9602 initialized
2022-11-07T22:10:20.461Z,1667859020.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2022-11-07T22:10:20.461Z,1667859020.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-11-07T22:10:20.510Z,1667859020.510 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-11-07T22:10:20.868Z,1667859020.868 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-11-07T22:10:20.868Z,1667859020.868 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2022-11-07T22:11:52.964Z,1667859112.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221152.00,A,3648.16422,N,12147.27839,W,0.661,198.13,071122,,,A*73
2022-11-07T22:11:52.982Z,1667859112.982 [NAL9602](INFO): GPS fix at 20221107T221152: (36.802737, -121.787973)
2022-11-07T22:12:25.294Z,1667859145.294 [NAL9602](INFO): Not Powering down - fast GPS
2022-11-07T22:13:08.950Z,1667859188.950 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T22:13:08.950Z,1667859188.950 [Default:CheckIn:C.Wait] Stopped
2022-11-07T22:13:08.950Z,1667859188.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T22:13:08.951Z,1667859188.951 [Default:CheckIn:D] Running Loop=1
2022-11-07T22:13:09.353Z,1667859189.353 [Default:CheckIn:D] Stopped
2022-11-07T22:13:09.353Z,1667859189.353 [Default:CheckIn:E] Running Loop=1
2022-11-07T22:13:09.790Z,1667859189.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.770131 min 
2022-11-07T22:13:09.790Z,1667859189.790 [Default:CheckIn:E] Stopped
2022-11-07T22:13:09.790Z,1667859189.790 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T22:13:09.790Z,1667859189.790 [Default:CheckIn] Stopped
2022-11-07T22:13:09.790Z,1667859189.790 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T22:13:09.790Z,1667859189.790 [Default:CheckIn](INFO): Running loop #4
2022-11-07T22:13:09.791Z,1667859189.791 [Default:CheckIn] Running Loop=4
2022-11-07T22:13:09.791Z,1667859189.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T22:13:09.791Z,1667859189.791 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T22:13:11.761Z,1667859191.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221311.00,A,3648.16311,N,12147.27969,W,0.136,198.13,071122,,,A*70
2022-11-07T22:13:11.764Z,1667859191.764 [NAL9602](INFO): GPS fix at 20221107T221311: (36.802718, -121.787995)
2022-11-07T22:13:11.774Z,1667859191.774 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T22:13:11.774Z,1667859191.774 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T22:13:17.583Z,1667859197.583 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20221107T213658/Courier0013.lzma
2022-11-07T22:13:18.586Z,1667859198.586 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0013.lzma.bak
2022-11-07T22:13:18.586Z,1667859198.586 [DataOverHttps](INFO): SBD MOMSN=17270833
2022-11-07T22:13:33.179Z,1667859213.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=37433, MT Status=2, MTMSN=0
2022-11-07T22:13:33.179Z,1667859213.179 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-11-07T22:13:34.340Z,1667859214.340 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20221107T213658/Express0014.lzma
2022-11-07T22:13:35.342Z,1667859215.342 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0014.lzma.bak
2022-11-07T22:13:35.342Z,1667859215.342 [DataOverHttps](INFO): SBD MOMSN=17270836
2022-11-07T22:13:36.832Z,1667859216.832 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T22:13:36.833Z,1667859216.833 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T22:13:36.833Z,1667859216.833 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T22:13:50.143Z,1667859230.143 [NAL9602](INFO): SBD MO Status=2, MOMSN=37433, MT Status=2, MTMSN=0
2022-11-07T22:13:50.143Z,1667859230.143 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-11-07T22:14:07.514Z,1667859247.514 [NAL9602](INFO): SBD MO Status=2, MOMSN=37433, MT Status=2, MTMSN=0
2022-11-07T22:14:07.514Z,1667859247.514 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-11-07T22:14:35.017Z,1667859275.017 [NAL9602](INFO): SBD MO Status=2, MOMSN=37433, MT Status=2, MTMSN=0
2022-11-07T22:14:35.017Z,1667859275.017 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-11-07T22:16:21.266Z,1667859381.266 [NAL9602](INFO): SBD MO Status=2, MOMSN=37433, MT Status=2, MTMSN=0
2022-11-07T22:16:21.266Z,1667859381.266 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-11-07T22:18:14.401Z,1667859494.401 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-07T22:18:37.462Z,1667859517.462 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T22:18:37.462Z,1667859517.462 [Default:CheckIn:C.Wait] Stopped
2022-11-07T22:18:37.462Z,1667859517.462 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T22:18:37.463Z,1667859517.463 [Default:CheckIn:D] Running Loop=1
2022-11-07T22:18:37.863Z,1667859517.863 [Default:CheckIn:D] Stopped
2022-11-07T22:18:37.863Z,1667859517.863 [Default:CheckIn:E] Running Loop=1
2022-11-07T22:18:38.275Z,1667859518.275 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.245288 min 
2022-11-07T22:18:38.275Z,1667859518.275 [Default:CheckIn:E] Stopped
2022-11-07T22:18:38.275Z,1667859518.275 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T22:18:38.275Z,1667859518.275 [Default:CheckIn] Stopped
2022-11-07T22:18:38.276Z,1667859518.276 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T22:18:38.276Z,1667859518.276 [Default:CheckIn](INFO): Running loop #5
2022-11-07T22:18:38.276Z,1667859518.276 [Default:CheckIn] Running Loop=5
2022-11-07T22:18:38.276Z,1667859518.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T22:18:38.276Z,1667859518.276 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T22:18:40.279Z,1667859520.279 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221839.00,A,3648.16616,N,12147.28319,W,0.641,256.01,071122,,,A*74
2022-11-07T22:18:40.281Z,1667859520.281 [NAL9602](INFO): GPS fix at 20221107T221839: (36.802769, -121.788053)
2022-11-07T22:18:40.308Z,1667859520.308 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T22:18:40.308Z,1667859520.308 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T22:18:47.883Z,1667859527.883 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20221107T213658/Courier0016.lzma
2022-11-07T22:18:48.886Z,1667859528.886 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0016.lzma.bak
2022-11-07T22:18:48.886Z,1667859528.886 [DataOverHttps](INFO): SBD MOMSN=17270847
2022-11-07T22:19:04.991Z,1667859544.991 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20221107T213658/Express0017.lzma
2022-11-07T22:19:05.994Z,1667859545.994 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0017.lzma.bak
2022-11-07T22:19:05.994Z,1667859545.994 [DataOverHttps](INFO): SBD MOMSN=17270850
2022-11-07T22:19:07.362Z,1667859547.362 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T22:19:07.362Z,1667859547.362 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T22:19:07.362Z,1667859547.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T22:19:12.613Z,1667859552.613 [NAL9602](INFO): Not Powering down - fast GPS
2022-11-07T22:23:48.313Z,1667859828.313 [DataOverHttps](IMPORTANT): SBD MTMSN=20221107T222347
2022-11-07T22:23:55.729Z,1667859835.729 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.xml
2022-11-07T22:23:55.831Z,1667859835.831 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-11-07T22:23:55.833Z,1667859835.833 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-11-07T22:23:55.936Z,1667859835.936 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2022-11-07T22:23:55.938Z,1667859835.938 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2022-11-07T22:23:55.941Z,1667859835.941 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2022-11-07T22:23:55.943Z,1667859835.943 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2022-11-07T22:23:55.946Z,1667859835.946 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2022-11-07T22:23:55.949Z,1667859835.949 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2022-11-07T22:23:55.952Z,1667859835.952 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2022-11-07T22:23:55.954Z,1667859835.954 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2022-11-07T22:23:55.957Z,1667859835.957 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2022-11-07T22:23:55.960Z,1667859835.960 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2022-11-07T22:23:55.963Z,1667859835.963 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2022-11-07T22:23:55.966Z,1667859835.966 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2022-11-07T22:23:55.968Z,1667859835.968 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2022-11-07T22:23:55.971Z,1667859835.971 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2022-11-07T22:23:55.971Z,1667859835.971 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2022-11-07T22:23:56.477Z,1667859836.477 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2022-11-07T22:23:56.480Z,1667859836.480 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2022-11-07T22:23:56.483Z,1667859836.483 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2022-11-07T22:23:56.485Z,1667859836.485 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2022-11-07T22:23:56.488Z,1667859836.488 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2022-11-07T22:23:56.490Z,1667859836.490 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2022-11-07T22:23:56.493Z,1667859836.493 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2022-11-07T22:23:56.495Z,1667859836.495 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2022-11-07T22:23:56.498Z,1667859836.498 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2022-11-07T22:23:56.501Z,1667859836.501 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2022-11-07T22:23:56.503Z,1667859836.503 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2022-11-07T22:23:56.506Z,1667859836.506 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2022-11-07T22:23:56.509Z,1667859836.509 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2022-11-07T22:23:56.511Z,1667859836.511 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2022-11-07T22:23:56.514Z,1667859836.514 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2022-11-07T22:23:56.517Z,1667859836.517 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2022-11-07T22:23:56.520Z,1667859836.520 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2022-11-07T22:23:56.523Z,1667859836.523 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2022-11-07T22:23:56.564Z,1667859836.564 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2022-11-07T22:23:56.567Z,1667859836.567 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2022-11-07T22:23:56.570Z,1667859836.570 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2022-11-07T22:23:56.585Z,1667859836.585 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2022-11-07T22:23:56.588Z,1667859836.588 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2022-11-07T22:23:56.591Z,1667859836.591 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2022-11-07T22:23:56.593Z,1667859836.593 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2022-11-07T22:23:56.605Z,1667859836.605 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2022-11-07T22:23:56.641Z,1667859836.641 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2022-11-07T22:23:56.656Z,1667859836.656 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2022-11-07T22:23:56.662Z,1667859836.662 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2022-11-07T22:23:56.678Z,1667859836.678 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2022-11-07T22:23:56.683Z,1667859836.683 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2022-11-07T22:23:56.695Z,1667859836.695 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2022-11-07T22:23:56.698Z,1667859836.698 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2022-11-07T22:23:56.703Z,1667859836.703 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2022-11-07T22:23:56.714Z,1667859836.714 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2022-11-07T22:23:56.733Z,1667859836.733 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2022-11-07T22:23:56.748Z,1667859836.748 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2022-11-07T22:23:56.751Z,1667859836.751 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2022-11-07T22:23:56.759Z,1667859836.759 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2022-11-07T22:23:56.766Z,1667859836.766 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2022-11-07T22:23:56.773Z,1667859836.773 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2022-11-07T22:23:56.781Z,1667859836.781 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2022-11-07T22:23:56.788Z,1667859836.788 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2022-11-07T22:23:56.791Z,1667859836.791 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2022-11-07T22:23:56.803Z,1667859836.803 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2022-11-07T22:23:56.813Z,1667859836.813 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2022-11-07T22:23:56.816Z,1667859836.816 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2022-11-07T22:23:56.819Z,1667859836.819 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2022-11-07T22:23:56.838Z,1667859836.838 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2022-11-07T22:23:56.841Z,1667859836.841 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2022-11-07T22:23:56.857Z,1667859836.857 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2022-11-07T22:23:56.860Z,1667859836.860 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2022-11-07T22:23:56.863Z,1667859836.863 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2022-11-07T22:23:56.866Z,1667859836.866 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2022-11-07T22:23:56.892Z,1667859836.892 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2022-11-07T22:23:56.895Z,1667859836.895 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2022-11-07T22:23:56.898Z,1667859836.898 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2022-11-07T22:23:56.913Z,1667859836.913 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2022-11-07T22:23:56.916Z,1667859836.916 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2022-11-07T22:23:56.919Z,1667859836.919 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2022-11-07T22:23:56.935Z,1667859836.935 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2022-11-07T22:23:56.938Z,1667859836.938 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2022-11-07T22:23:56.941Z,1667859836.941 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2022-11-07T22:23:56.964Z,1667859836.964 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2022-11-07T22:23:56.968Z,1667859836.968 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2022-11-07T22:23:57.094Z,1667859837.094 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-11-07T22:23:57.114Z,1667859837.114 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-11-07T22:23:57.147Z,1667859837.147 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-11-07T22:23:57.157Z,1667859837.157 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-11-07T22:23:57.199Z,1667859837.199 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-11-07T22:23:57.220Z,1667859837.220 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-11-07T22:23:57.256Z,1667859837.256 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2022-11-07T22:23:57.280Z,1667859837.280 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2022-11-07T22:23:57.299Z,1667859837.299 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.xml
2022-11-07T22:23:57.559Z,1667859837.559 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2022-11-07T22:23:57.561Z,1667859837.561 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2022-11-07T22:23:57.581Z,1667859837.581 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2022-11-07T22:23:57.584Z,1667859837.584 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2022-11-07T22:23:57.587Z,1667859837.587 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2022-11-07T22:23:57.601Z,1667859837.601 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2022-11-07T22:23:57.604Z,1667859837.604 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2022-11-07T22:23:57.607Z,1667859837.607 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2022-11-07T22:23:57.622Z,1667859837.622 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2022-11-07T22:23:57.625Z,1667859837.625 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2022-11-07T22:23:57.628Z,1667859837.628 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2022-11-07T22:23:57.631Z,1667859837.631 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2022-11-07T22:23:57.655Z,1667859837.655 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2022-11-07T22:23:57.658Z,1667859837.658 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2022-11-07T22:23:57.702Z,1667859837.702 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2022-11-07T22:23:57.719Z,1667859837.719 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2022-11-07T22:23:57.723Z,1667859837.723 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2022-11-07T22:23:57.753Z,1667859837.753 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2022-11-07T22:23:57.756Z,1667859837.756 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2022-11-07T22:23:57.759Z,1667859837.759 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2022-11-07T22:23:57.777Z,1667859837.777 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2022-11-07T22:23:57.801Z,1667859837.801 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2022-11-07T22:23:57.804Z,1667859837.804 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2022-11-07T22:23:57.850Z,1667859837.850 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2022-11-07T22:23:57.884Z,1667859837.884 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2022-11-07T22:23:57.928Z,1667859837.928 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2022-11-07T22:23:57.930Z,1667859837.930 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2022-11-07T22:23:57.936Z,1667859837.936 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2022-11-07T22:23:57.938Z,1667859837.938 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2022-11-07T22:23:57.944Z,1667859837.944 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2022-11-07T22:23:57.946Z,1667859837.946 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2022-11-07T22:23:57.952Z,1667859837.952 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2022-11-07T22:23:57.954Z,1667859837.954 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2022-11-07T22:23:57.973Z,1667859837.973 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2022-11-07T22:23:57.985Z,1667859837.985 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2022-11-07T22:23:58.041Z,1667859838.041 [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="ballast_and_trim">

    <DefineArg Name="MissionTimeout"><Description>
        Maximum duration of mission
    </Description><Units:minute/><Value>120</Value></DefineArg>

    <DefineArg Name="SkipComms"><Description>
        Skip communications at start of mission.
    </Description><False/></DefineArg>

    <DefineArg Name="Depth1"><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="Depth2"><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed durnig the
        mission.
    </Description><Units:meter/><Value>0.1</Value></DefineArg>

    <DefineArg Name="SurfaceThreshold"><Description>
        Surface threshold depth.
    </Description><Units:meter/><Value>2.5</Value></DefineArg>

    <DefineArg Name="MaxWaitOnSurface"><Description>
        Maximum wait time for the vehicle to go under the surface (i.e., reach
        the surface threshold depth).
    </Description><Units:minute/><Value>20</Value></DefineArg>

    <DefineArg Name="SettleTime"><Description>
        How long to wait after reaching target depth before starting ballast and
        trim.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="EstimationTimeout"><Description>
        Timeout for ballast and trim estimation.
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MinEstimationTime"><Description>
        Minimum time to run ballast and trim estimation before determining
        convergence (must be shorter than timeout).
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="MassEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:millimeter/><Value>0.25</Value></DefineArg>

    <DefineArg Name="BuoyEstimationErrorBound"><Description>
        Desired range of the estimated sample mean from the true mean statistic
        (smaller values take longer to converge).
    </Description><Units:cubic_centimeter/><Value>15</Value></DefineArg>

    <DefineArg Name="EstimationConfidence"><Description>
        Confidence level that the estimator has reached the desired error range
        (larger values take longer to converge).
    </Description><Units:percent/><Value>99.9</Value></DefineArg>

    <DefineArg Name="WaitToFloat"><Units:minute/><Value>5</Value></DefineArg>

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml"/>

    <Aggregate Id="RunBallastAndTrim">

        <Sequence/>

        <Insert Filename="Insert/BallastAndTrim.xml">
            <RedefineArg Name="InsertDepthDeadband"><Arg Name="DepthDeadband"/></RedefineArg>
            <RedefineArg Name="InsertApproachDepthTimeout"><Arg Name="MaxWaitOnSurface"/></RedefineArg>
            <RedefineArg Name="InsertSettleTime"><Arg Name="SettleTime"/></RedefineArg>
            <RedefineArg Name="EstTimeout"><Arg Name="EstimationTimeout"/></RedefineArg>
            <RedefineArg Name="MinEstTime"><Arg Name="MinEstimationTime"/></RedefineArg>
            <RedefineArg Name="MassEstErrorBound"><Arg Name="MassEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="BuoyEstErrorBound"><Arg Name="BuoyEstimationErrorBound"/></RedefineArg>
            <RedefineArg Name="EstConfidence"><Arg Name="EstimationConfidence"/></RedefineArg>
        </Insert>

        <Aggregate Id="Depth1">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth1"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

        <Aggregate Id="Depth2">

            <Sequence/>

            <Break>
                <IsNaN><Arg Name="Depth2"/>
                </IsNaN>
            </Break>

            <Assign><Sequence/><Arg Name="BallastAndTrim:InsertDepth"/><Arg Name="Depth1"/></Assign>

            <Call RefId="BallastAndTrim"/>

        </Aggregate>

    </Aggregate>

    <Aggregate Id="Float_Up">

        <Description>
            Float to surface instead of driving.
        </Description>

        <Sequence/>

        <Break>
            <Universal:depth/>
            <Lt><Control:VerticalControl.surfaceThreshold/></Lt>
        </Break>

        <Guidance:Buoyancy>
            <Parallel/>
            <Setting><Guidance:Buoyancy.position/><Control:VerticalControl.buoyancyDefault/></Setting>
        </Guidance:Buoyancy>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="WaitToFloat"/></Setting>
        </Guidance:Wait>

    </Aggregate>

</Mission>


2022-11-07T22:23:58.058Z,1667859838.058 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2022-11-07T22:23:58.265Z,1667859838.265 [Default] Stopped
2022-11-07T22:23:58.265Z,1667859838.265 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-07T22:23:58.265Z,1667859838.265 [Default:B.GoToSurface] Stopped
2022-11-07T22:23:58.265Z,1667859838.265 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-07T22:23:58.265Z,1667859838.265 [Default:CheckIn] Stopped
2022-11-07T22:23:58.265Z,1667859838.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T22:23:58.265Z,1667859838.265 [Default:CheckIn:C.Wait] Stopped
2022-11-07T22:23:58.265Z,1667859838.265 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T22:23:58.265Z,1667859838.265 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science:A] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science:B] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science:C] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science:D] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science:E] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science:F] Running Loop=1
2022-11-07T22:23:58.266Z,1667859838.266 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2022-11-07T22:23:58.267Z,1667859838.267 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2022-11-07T22:23:58.267Z,1667859838.267 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2022-11-07T22:23:58.267Z,1667859838.267 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2022-11-07T22:23:58.267Z,1667859838.267 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-11-07T22:23:58.269Z,1667859838.269 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2022-11-07T22:23:58.269Z,1667859838.269 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2022-11-07T22:23:58.269Z,1667859838.269 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-11-07T22:23:58.269Z,1667859838.269 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-11-07T22:23:58.270Z,1667859838.270 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2022-11-07T22:23:58.270Z,1667859838.270 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2022-11-07T22:23:58.270Z,1667859838.270 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2022-11-07T22:23:58.270Z,1667859838.270 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-11-07T22:23:58.272Z,1667859838.272 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2022-11-07T22:23:58.272Z,1667859838.272 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2022-11-07T22:23:58.272Z,1667859838.272 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2022-11-07T22:23:58.272Z,1667859838.272 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-11-07T22:23:58.288Z,1667859838.288 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2022-11-07T22:23:58.288Z,1667859838.288 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2022-11-07T22:23:58.288Z,1667859838.288 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-11-07T22:23:58.289Z,1667859838.289 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-11-07T22:23:58.289Z,1667859838.289 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2022-11-07T22:23:58.289Z,1667859838.289 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2022-11-07T22:23:58.289Z,1667859838.289 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2022-11-07T22:23:58.289Z,1667859838.289 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-11-07T22:23:58.291Z,1667859838.291 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2022-11-07T22:23:58.291Z,1667859838.291 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2022-11-07T22:23:58.291Z,1667859838.291 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2022-11-07T22:23:58.291Z,1667859838.291 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2022-11-07T22:23:58.293Z,1667859838.293 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2022-11-07T22:23:58.293Z,1667859838.293 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2022-11-07T22:23:58.293Z,1667859838.293 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2022-11-07T22:23:58.293Z,1667859838.293 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2022-11-07T22:23:58.294Z,1667859838.294 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2022-11-07T22:23:58.294Z,1667859838.294 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2022-11-07T22:23:58.294Z,1667859838.294 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-11-07T22:23:58.294Z,1667859838.294 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2022-11-07T22:23:58.294Z,1667859838.294 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2022-11-07T22:23:58.294Z,1667859838.294 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2022-11-07T22:23:58.295Z,1667859838.295 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2022-11-07T22:23:58.295Z,1667859838.295 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-11-07T22:23:58.295Z,1667859838.295 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2022-11-07T22:23:58.296Z,1667859838.296 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2022-11-07T22:23:58.296Z,1667859838.296 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2022-11-07T22:23:58.296Z,1667859838.296 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-11-07T22:23:58.296Z,1667859838.296 [ballast_and_trim:Science] Running Loop=1
2022-11-07T22:23:58.297Z,1667859838.297 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2022-11-07T22:23:58.297Z,1667859838.297 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2022-11-07T22:23:58.298Z,1667859838.298 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectOil] Stopped
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2022-11-07T22:23:58.299Z,1667859838.299 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:PeakDetectChl] Stopped
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:Read_Oil] Stopped
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:F] Stopped
2022-11-07T22:23:58.317Z,1667859838.317 [ballast_and_trim:Science:E] Running Loop=1
2022-11-07T22:23:58.318Z,1667859838.318 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2022-11-07T22:23:58.318Z,1667859838.318 [ballast_and_trim:Science:D] Stopped
2022-11-07T22:23:58.318Z,1667859838.318 [ballast_and_trim:Science:C] Running Loop=1
2022-11-07T22:23:58.319Z,1667859838.319 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2022-11-07T22:23:58.320Z,1667859838.320 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2022-11-07T22:23:58.320Z,1667859838.320 [ballast_and_trim:Science:B] Running Loop=1
2022-11-07T22:23:58.320Z,1667859838.320 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2022-11-07T22:23:58.321Z,1667859838.321 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2022-11-07T22:23:58.321Z,1667859838.321 [ballast_and_trim:Science:A] Stopped
2022-11-07T22:23:58.448Z,1667859838.448 [WetLabsBB2FL](INFO): Powering up
2022-11-07T22:23:58.682Z,1667859838.682 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2022-11-07T22:23:58.682Z,1667859838.682 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-11-07T22:23:58.682Z,1667859838.682 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-11-07T22:23:58.682Z,1667859838.682 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-11-07T22:23:58.682Z,1667859838.682 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-11-07T22:23:58.683Z,1667859838.683 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-11-07T22:23:58.683Z,1667859838.683 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-11-07T22:23:58.683Z,1667859838.683 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2022-11-07T22:23:58.685Z,1667859838.685 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2022-11-07T22:23:58.686Z,1667859838.686 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2022-11-07T22:23:58.687Z,1667859838.687 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2022-11-07T22:23:58.687Z,1667859838.687 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2022-11-07T22:23:58.687Z,1667859838.687 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2022-11-07T22:23:58.687Z,1667859838.687 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2022-11-07T22:23:59.060Z,1667859839.060 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2022-11-07T22:23:59.060Z,1667859839.060 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2022-11-07T22:23:59.060Z,1667859839.060 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2022-11-07T22:23:59.060Z,1667859839.060 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2022-11-07T22:23:59.061Z,1667859839.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2022-11-07T22:23:59.061Z,1667859839.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2022-11-07T22:23:59.061Z,1667859839.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2022-11-07T22:23:59.061Z,1667859839.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2022-11-07T22:23:59.061Z,1667859839.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-11-07T22:23:59.061Z,1667859839.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2022-11-07T22:23:59.061Z,1667859839.061 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2022-11-07T22:23:59.468Z,1667859839.468 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-11-07T22:23:59.469Z,1667859839.469 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2022-11-07T22:23:59.470Z,1667859839.470 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2022-11-07T22:23:59.876Z,1667859839.876 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2022-11-07T22:23:59.877Z,1667859839.877 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2022-11-07T22:23:59.877Z,1667859839.877 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2022-11-07T22:23:59.877Z,1667859839.877 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2022-11-07T22:23:59.878Z,1667859839.878 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2022-11-07T22:24:02.109Z,1667859842.109 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2022-11-07T22:24:03.114Z,1667859843.114 [CTD_Seabird](ERROR): Salinity reading out of range: 16.273333 psu
2022-11-07T22:25:51.200Z,1667859951.200 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T22:26:22.324Z,1667859982.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T22:26:24.400Z,1667859984.400 [Radio_Surface](INFO): Powering down
2022-11-07T22:26:53.448Z,1667860013.448 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T22:26:54.452Z,1667860014.452 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-11-07T22:27:34.111Z,1667860054.111 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2022-11-07T22:27:34.116Z,1667860054.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-11-07T22:27:34.116Z,1667860054.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2022-11-07T22:27:34.116Z,1667860054.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2022-11-07T22:27:34.116Z,1667860054.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2022-11-07T22:27:34.116Z,1667860054.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-11-07T22:27:34.117Z,1667860054.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2022-11-07T22:27:34.118Z,1667860054.118 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2022-11-07T22:27:34.503Z,1667860054.503 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.058052 m 
2022-11-07T22:27:34.503Z,1667860054.503 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2022-11-07T22:27:34.503Z,1667860054.503 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2022-11-07T22:27:34.503Z,1667860054.503 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2022-11-07T22:27:34.503Z,1667860054.503 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2022-11-07T22:27:34.504Z,1667860054.504 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2022-11-07T22:27:58.728Z,1667860078.728 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735077
2022-11-07T22:37:35.260Z,1667860655.260 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2022-11-07T22:37:35.260Z,1667860655.260 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2022-11-07T22:37:35.261Z,1667860655.261 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T22:37:35.261Z,1667860655.261 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2022-11-07T22:37:35.664Z,1667860655.664 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 6.602690 m 
2022-11-07T22:37:35.664Z,1667860655.664 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2022-11-07T22:37:35.664Z,1667860655.664 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2022-11-07T22:37:35.664Z,1667860655.664 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2022-11-07T22:49:32.445Z,1667861372.445 [CTD_Seabird](ERROR): Failed to parse device response: .03
2022-11-07T22:49:36.005Z,1667861376.005 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:49:42.866Z,1667861382.866 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:49:57.814Z,1667861397.814 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:50:13.166Z,1667861413.166 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:50:28.518Z,1667861428.518 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:50:43.466Z,1667861443.466 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:50:59.226Z,1667861459.226 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:51:14.578Z,1667861474.578 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:51:29.930Z,1667861489.930 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:51:45.290Z,1667861505.290 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2022-11-07T22:52:03.883Z,1667861523.883 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6.
2022-11-07T22:52:03.886Z,1667861523.886 [BPC1](INFO): Received data from all battery sticks.
2022-11-07T23:02:32.955Z,1667862152.955 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 24.95 minutes.
2022-11-07T23:02:32.961Z,1667862152.961 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 237.497902 +/- 14.997124 cc (conf. level 99.90%, sigma: 28.024785 cc).
2022-11-07T23:02:32.961Z,1667862152.961 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: -10.509636 +/- 0.174617 mm (conf. level 99.90%, sigma: 0.326303 mm).
2022-11-07T23:02:32.962Z,1667862152.962 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 237.497902 cc buoyancy neutral and -10.509636 mm mass default
2022-11-07T23:02:32.969Z,1667862152.969 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AHRS_M2.readAccelerations=0 bool;

2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AHRS_M2.readAngularVelocities=0 bool;

2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AHRS_M2.readMagnetics=0 bool;

2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batteryMissingStickThreshold=10 count;

2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CANONSampler.sampleTimeout=10 minute;

2022-11-07T23:02:32.970Z,1667862152.970 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2022-11-07T23:02:32.971Z,1667862152.971 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-11-07T23:02:32.971Z,1667862152.971 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2022-11-07T23:02:32.971Z,1667862152.971 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-11-07T23:02:32.971Z,1667862152.971 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express linearApproximation NAL9602.sigQuality 1.000000 count;

2022-11-07T23:02:32.971Z,1667862152.971 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express linearApproximation ThrusterServo.component_avgCurrent 10.000000 milliampere;

2022-11-07T23:02:32.971Z,1667862152.971 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=237.497902 cubic_centimeter;

2022-11-07T23:02:32.971Z,1667862152.971 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=-10.509636 millimeter;

2022-11-07T23:02:32.972Z,1667862152.972 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2022-11-07T23:02:32.976Z,1667862152.976 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2022-11-07T23:02:32.977Z,1667862152.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-11-07T23:02:32.977Z,1667862152.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2022-11-07T23:02:32.977Z,1667862152.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2022-11-07T23:02:32.977Z,1667862152.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2022-11-07T23:02:32.977Z,1667862152.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2022-11-07T23:02:32.977Z,1667862152.977 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2022-11-07T23:02:32.978Z,1667862152.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-11-07T23:02:32.978Z,1667862152.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2022-11-07T23:02:32.978Z,1667862152.978 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2022-11-07T23:02:32.979Z,1667862152.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2022-11-07T23:02:32.979Z,1667862152.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2022-11-07T23:02:32.979Z,1667862152.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2022-11-07T23:02:32.979Z,1667862152.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2022-11-07T23:02:32.979Z,1667862152.979 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2022-11-07T23:02:33.347Z,1667862153.347 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-11-07T23:02:33.347Z,1667862153.347 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2022-11-07T23:02:33.347Z,1667862153.347 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2022-11-07T23:02:33.347Z,1667862153.347 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2022-11-07T23:02:33.347Z,1667862153.347 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2022-11-07T23:02:33.347Z,1667862153.347 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2022-11-07T23:02:33.348Z,1667862153.348 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2022-11-07T23:02:33.348Z,1667862153.348 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2022-11-07T23:02:33.348Z,1667862153.348 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2022-11-07T23:02:33.763Z,1667862153.763 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:RunBallastAndTrim] Stopped
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:Float_Up] Running Loop=1
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2022-11-07T23:02:33.764Z,1667862153.764 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2022-11-07T23:02:33.765Z,1667862153.765 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2022-11-07T23:02:33.765Z,1667862153.765 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2022-11-07T23:02:34.151Z,1667862154.151 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2022-11-07T23:04:26.463Z,1667862266.463 [ballast_and_trim:Float_Up] Stopped
2022-11-07T23:04:26.463Z,1667862266.463 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2022-11-07T23:04:26.463Z,1667862266.463 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2022-11-07T23:04:26.463Z,1667862266.463 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2022-11-07T23:04:26.463Z,1667862266.463 [ballast_and_trim:Float_Up:B.Wait] Stopped
2022-11-07T23:04:26.463Z,1667862266.463 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T23:04:26.465Z,1667862266.465 [ballast_and_trim](INFO): Completed ballast_and_trim
2022-11-07T23:04:26.466Z,1667862266.466 [MissionManager](INFO): ballast_and_trim is completed.
2022-11-07T23:04:26.466Z,1667862266.466 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim] Stopped
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science] Stopped
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science:B] Stopped
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science:C] Stopped
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science:E] Stopped
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science:OceanCurrent] Stopped
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2022-11-07T23:04:26.466Z,1667862266.466 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2022-11-07T23:04:26.863Z,1667862266.863 [MissionManager](IMPORTANT): Started mission Default
2022-11-07T23:04:26.863Z,1667862266.863 [Default] Running Loop=1
2022-11-07T23:04:26.863Z,1667862266.863 [Default](DEBUG): Aggregate::initialize Default
2022-11-07T23:04:26.863Z,1667862266.863 [Default:B.GoToSurface] Running Loop=1
2022-11-07T23:04:26.863Z,1667862266.863 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-11-07T23:04:26.864Z,1667862266.864 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-11-07T23:04:26.864Z,1667862266.864 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-11-07T23:04:26.864Z,1667862266.864 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-11-07T23:04:26.865Z,1667862266.865 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-11-07T23:04:26.865Z,1667862266.865 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-11-07T23:04:26.865Z,1667862266.865 [Default:A.Wait] Running Loop=1
2022-11-07T23:04:26.865Z,1667862266.865 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-11-07T23:04:26.912Z,1667862266.912 [WetLabsBB2FL](INFO): Powering down
2022-11-07T23:04:27.036Z,1667862267.036 [Radio_Surface](INFO): Powering up
2022-11-07T23:04:33.005Z,1667862273.005 [DataOverHttps](INFO): Radio surface powered ON.
2022-11-07T23:04:40.194Z,1667862280.194 [Default:A.Wait](INFO): Done Waiting.
2022-11-07T23:04:40.194Z,1667862280.194 [Default:A.Wait] Stopped
2022-11-07T23:04:40.194Z,1667862280.194 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T23:04:40.603Z,1667862280.603 [Default:CheckIn] Running Loop=1
2022-11-07T23:04:40.603Z,1667862280.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T23:04:40.603Z,1667862280.603 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T23:05:03.124Z,1667862303.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T23:05:13.313Z,1667862313.313 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-11-07T23:05:13.396Z,1667862313.396 [NAL9602](ERROR): received: 
+CSQ:0

OK433, 2, 0, 0, 0

OK
2022-11-07T23:05:34.264Z,1667862334.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T23:06:05.404Z,1667862365.404 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T23:06:36.528Z,1667862396.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T23:07:07.661Z,1667862427.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T23:07:38.784Z,1667862458.784 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2022-11-07T23:08:08.188Z,1667862488.188 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003031
2022-11-07T23:09:40.780Z,1667862580.780 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T23:04:40.6Z
2022-11-07T23:09:40.780Z,1667862580.780 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T23:09:40.780Z,1667862580.780 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T23:09:45.217Z,1667862585.217 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-07T23:09:48.815Z,1667862588.815 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20221107T213658/Courier0019.lzma
2022-11-07T23:09:49.818Z,1667862589.818 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0019.lzma.bak
2022-11-07T23:09:49.818Z,1667862589.818 [DataOverHttps](INFO): SBD MOMSN=17271036
2022-11-07T23:10:06.101Z,1667862606.101 [DataOverHttps](INFO): Sending 1310 bytes from file Logs/20221107T213658/Express0020.lzma
2022-11-07T23:10:07.123Z,1667862607.123 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0020.lzma.bak
2022-11-07T23:10:07.123Z,1667862607.123 [DataOverHttps](INFO): SBD MOMSN=17271038
2022-11-07T23:10:15.915Z,1667862615.915 [NAL9602](INFO): Not Powering down - fast GPS
2022-11-07T23:10:26.559Z,1667862626.559 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20221107T213658/Express0023.lzma
2022-11-07T23:10:27.562Z,1667862627.562 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0023.lzma.bak
2022-11-07T23:10:27.562Z,1667862627.562 [DataOverHttps](INFO): SBD MOMSN=17271074
2022-11-07T23:10:28.926Z,1667862628.926 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T23:10:28.926Z,1667862628.926 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T23:10:28.926Z,1667862628.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T23:15:29.581Z,1667862929.581 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T23:15:29.581Z,1667862929.581 [Default:CheckIn:C.Wait] Stopped
2022-11-07T23:15:29.581Z,1667862929.581 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T23:15:29.582Z,1667862929.582 [Default:CheckIn:D] Running Loop=1
2022-11-07T23:15:29.986Z,1667862929.986 [Default:CheckIn:D] Stopped
2022-11-07T23:15:29.986Z,1667862929.986 [Default:CheckIn:E] Running Loop=1
2022-11-07T23:15:30.401Z,1667862930.401 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.052046 min 
2022-11-07T23:15:30.401Z,1667862930.401 [Default:CheckIn:E] Stopped
2022-11-07T23:15:30.402Z,1667862930.402 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T23:15:30.402Z,1667862930.402 [Default:CheckIn] Stopped
2022-11-07T23:15:30.402Z,1667862930.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T23:15:30.402Z,1667862930.402 [Default:CheckIn](INFO): Running loop #2
2022-11-07T23:15:30.402Z,1667862930.402 [Default:CheckIn] Running Loop=2
2022-11-07T23:15:30.402Z,1667862930.402 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T23:15:30.402Z,1667862930.402 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T23:15:31.998Z,1667862931.998 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-11-07T23:15:31.998Z,1667862931.998 [NAL9602] Data Fault, FailCount= 4
2022-11-07T23:15:31.998Z,1667862931.998 [NAL9602](ERROR): Data Fault
2022-11-07T23:15:32.018Z,1667862932.018 [CBIT](ERROR): Data Fault in component: NAL9602
2022-11-07T23:15:32.401Z,1667862932.401 [NAL9602](INFO): Powering down
2022-11-07T23:15:33.227Z,1667862933.227 [CBIT](INFO): Clearing failed state for component NAL9602
2022-11-07T23:15:33.227Z,1667862933.227 [NAL9602] No Fault, FailCount= 4
2022-11-07T23:16:02.701Z,1667862962.701 [NAL9602](INFO): Powering up NAL9602
2022-11-07T23:16:13.608Z,1667862973.608 [NAL9602](INFO): NAL9602 initialized
2022-11-07T23:20:30.641Z,1667863230.641 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T23:15:30.4Z
2022-11-07T23:20:30.641Z,1667863230.641 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T23:20:30.641Z,1667863230.641 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T23:20:40.683Z,1667863240.683 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221107T213658/Courier0025.lzma
2022-11-07T23:20:41.686Z,1667863241.686 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0025.lzma.bak
2022-11-07T23:20:41.686Z,1667863241.686 [DataOverHttps](INFO): SBD MOMSN=17271314
2022-11-07T23:20:57.504Z,1667863257.504 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20221107T213658/Express0026.lzma
2022-11-07T23:20:58.502Z,1667863258.502 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0026.lzma.bak
2022-11-07T23:20:58.502Z,1667863258.502 [DataOverHttps](INFO): SBD MOMSN=17271316
2022-11-07T23:20:59.755Z,1667863259.755 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T23:20:59.755Z,1667863259.755 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T23:20:59.755Z,1667863259.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T23:21:17.117Z,1667863277.117 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-07T23:21:47.820Z,1667863307.820 [NAL9602](INFO): Not Powering down - fast GPS
2022-11-07T23:26:00.420Z,1667863560.420 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T23:26:00.420Z,1667863560.420 [Default:CheckIn:C.Wait] Stopped
2022-11-07T23:26:00.421Z,1667863560.421 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T23:26:00.421Z,1667863560.421 [Default:CheckIn:D] Running Loop=1
2022-11-07T23:26:00.816Z,1667863560.816 [Default:CheckIn:D] Stopped
2022-11-07T23:26:00.817Z,1667863560.817 [Default:CheckIn:E] Running Loop=1
2022-11-07T23:26:01.213Z,1667863561.213 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.565887 min 
2022-11-07T23:26:01.213Z,1667863561.213 [Default:CheckIn:E] Stopped
2022-11-07T23:26:01.213Z,1667863561.213 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T23:26:01.213Z,1667863561.213 [Default:CheckIn] Stopped
2022-11-07T23:26:01.214Z,1667863561.214 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T23:26:01.214Z,1667863561.214 [Default:CheckIn](INFO): Running loop #3
2022-11-07T23:26:01.214Z,1667863561.214 [Default:CheckIn] Running Loop=3
2022-11-07T23:26:01.214Z,1667863561.214 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T23:26:01.214Z,1667863561.214 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T23:26:33.961Z,1667863593.961 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-11-07T23:26:34.044Z,1667863594.044 [NAL9602](ERROR): received: 
+CSQ:0

OK433, 2, 0, 0, 0

OK
2022-11-07T23:31:01.438Z,1667863861.438 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T23:26:01.2Z
2022-11-07T23:31:01.438Z,1667863861.438 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T23:31:01.438Z,1667863861.438 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T23:31:05.241Z,1667863865.241 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-07T23:31:08.359Z,1667863868.359 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221107T213658/Courier0028.lzma
2022-11-07T23:31:09.362Z,1667863869.362 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0028.lzma.bak
2022-11-07T23:31:09.362Z,1667863869.362 [DataOverHttps](INFO): SBD MOMSN=17271490
2022-11-07T23:31:25.544Z,1667863885.544 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20221107T213658/Express0029.lzma
2022-11-07T23:31:26.542Z,1667863886.542 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0029.lzma.bak
2022-11-07T23:31:26.542Z,1667863886.542 [DataOverHttps](INFO): SBD MOMSN=17271492
2022-11-07T23:31:27.887Z,1667863887.887 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T23:31:27.887Z,1667863887.887 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T23:31:27.888Z,1667863887.888 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T23:31:35.949Z,1667863895.949 [NAL9602](INFO): Not Powering down - fast GPS
2022-11-07T23:36:28.454Z,1667864188.454 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T23:36:28.454Z,1667864188.454 [Default:CheckIn:C.Wait] Stopped
2022-11-07T23:36:28.454Z,1667864188.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T23:36:28.455Z,1667864188.455 [Default:CheckIn:D] Running Loop=1
2022-11-07T23:36:28.869Z,1667864188.869 [Default:CheckIn:D] Stopped
2022-11-07T23:36:28.869Z,1667864188.869 [Default:CheckIn:E] Running Loop=1
2022-11-07T23:36:29.261Z,1667864189.261 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.033423 min 
2022-11-07T23:36:29.261Z,1667864189.261 [Default:CheckIn:E] Stopped
2022-11-07T23:36:29.262Z,1667864189.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T23:36:29.262Z,1667864189.262 [Default:CheckIn] Stopped
2022-11-07T23:36:29.262Z,1667864189.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T23:36:29.262Z,1667864189.262 [Default:CheckIn](INFO): Running loop #4
2022-11-07T23:36:29.262Z,1667864189.262 [Default:CheckIn] Running Loop=4
2022-11-07T23:36:29.262Z,1667864189.262 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T23:36:29.262Z,1667864189.262 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T23:36:30.870Z,1667864190.870 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-11-07T23:36:30.870Z,1667864190.870 [NAL9602] Data Fault, FailCount= 5
2022-11-07T23:36:30.870Z,1667864190.870 [NAL9602](ERROR): Data Fault
2022-11-07T23:36:30.915Z,1667864190.915 [CBIT](ERROR): Data Fault in component: NAL9602
2022-11-07T23:36:30.915Z,1667864190.915 [CBIT](CRITICAL): Data Fault in component: NAL9602
2022-11-07T23:36:31.273Z,1667864191.273 [NAL9602](INFO): Powering down
2022-11-07T23:36:31.328Z,1667864191.328 [CommandExec](FAULT): Scheduling is paused
2022-11-07T23:36:31.329Z,1667864191.329 [CBIT](INFO): Critical error at 20221107T233630
2022-11-07T23:36:31.329Z,1667864191.329 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2022-11-07T23:37:16.532Z,1667864236.532 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2022-11-07T23:37:16.532Z,1667864236.532 [DropWeight] Hardware Fault, FailCount= 1
2022-11-07T23:37:16.532Z,1667864236.532 [DropWeight](ERROR): Hardware Fault
2022-11-07T23:37:16.555Z,1667864236.555 [CBIT](INFO): Critical error at 20221107T233716
2022-11-07T23:37:16.558Z,1667864236.558 [CBIT](ERROR): Hardware Fault in component: DropWeight
2022-11-07T23:37:16.558Z,1667864236.558 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2022-11-07T23:37:16.954Z,1667864236.954 [CBIT](INFO): Critical error at 20221107T233716
2022-11-07T23:38:30.884Z,1667864310.884 [CBIT](INFO): Clearing failed state for component NAL9602
2022-11-07T23:38:30.884Z,1667864310.884 [NAL9602] No Fault, FailCount= 5
2022-11-07T23:38:31.269Z,1667864311.269 [NAL9602](INFO): Powering up NAL9602
2022-11-07T23:38:42.177Z,1667864322.177 [NAL9602](INFO): NAL9602 initialized
2022-11-07T23:41:29.446Z,1667864489.446 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T23:36:29.3Z
2022-11-07T23:41:29.447Z,1667864489.447 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T23:41:29.447Z,1667864489.447 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T23:41:37.163Z,1667864497.163 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20221107T213658/Courier0031.lzma
2022-11-07T23:41:38.166Z,1667864498.166 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0031.lzma.bak
2022-11-07T23:41:38.166Z,1667864498.166 [DataOverHttps](INFO): SBD MOMSN=17271719
2022-11-07T23:41:54.004Z,1667864514.004 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20221107T213658/Express0032.lzma
2022-11-07T23:41:55.006Z,1667864515.006 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0032.lzma.bak
2022-11-07T23:41:55.006Z,1667864515.006 [DataOverHttps](INFO): SBD MOMSN=17271724
2022-11-07T23:41:56.573Z,1667864516.573 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T23:41:56.573Z,1667864516.573 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T23:41:56.574Z,1667864516.574 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T23:43:45.592Z,1667864625.592 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-07T23:44:16.297Z,1667864656.297 [NAL9602](INFO): Not Powering down - fast GPS
2022-11-07T23:46:57.105Z,1667864817.105 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-11-07T23:46:57.105Z,1667864817.105 [Default:CheckIn:C.Wait] Stopped
2022-11-07T23:46:57.105Z,1667864817.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T23:46:57.105Z,1667864817.105 [Default:CheckIn:D] Running Loop=1
2022-11-07T23:46:57.516Z,1667864817.516 [Default:CheckIn:D] Stopped
2022-11-07T23:46:57.516Z,1667864817.516 [Default:CheckIn:E] Running Loop=1
2022-11-07T23:46:57.918Z,1667864817.918 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.510872 min 
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn:E] Stopped
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn] Stopped
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn](INFO): Running loop #5
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn] Running Loop=5
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-11-07T23:46:57.919Z,1667864817.919 [Default:CheckIn:Read_GPS] Running Loop=1
2022-11-07T23:47:30.633Z,1667864850.633 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2022-11-07T23:47:30.708Z,1667864850.708 [NAL9602](ERROR): received: 
+CSQ:0

OK433, 2, 0, 0, 0

OK
2022-11-07T23:51:58.116Z,1667865118.116 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-11-07T23:46:57.9Z
2022-11-07T23:51:58.116Z,1667865118.116 [Default:CheckIn:Read_GPS] Stopped
2022-11-07T23:51:58.116Z,1667865118.116 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-11-07T23:52:01.734Z,1667865121.734 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-11-07T23:52:05.331Z,1667865125.331 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221107T213658/Courier0034.lzma
2022-11-07T23:52:06.334Z,1667865126.334 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Courier0034.lzma.bak
2022-11-07T23:52:06.334Z,1667865126.334 [DataOverHttps](INFO): SBD MOMSN=17271761
2022-11-07T23:52:16.780Z,1667865136.780 [CBIT](INFO): Clearing failed state for component DropWeight
2022-11-07T23:52:16.780Z,1667865136.780 [DropWeight] No Fault, FailCount= 1
2022-11-07T23:52:24.856Z,1667865144.856 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20221107T213658/Express0035.lzma
2022-11-07T23:52:25.858Z,1667865145.858 [DataOverHttps](INFO): Moved sent file to Logs/20221107T213658/Express0035.lzma.bak
2022-11-07T23:52:25.858Z,1667865145.858 [DataOverHttps](INFO): SBD MOMSN=17271763
2022-11-07T23:52:27.292Z,1667865147.292 [Default:CheckIn:Read_Iridium] Stopped
2022-11-07T23:52:27.292Z,1667865147.292 [Default:CheckIn:C.Wait] Running Loop=1
2022-11-07T23:52:27.292Z,1667865147.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-11-07T23:52:32.505Z,1667865152.505 [NAL9602](INFO): Not Powering down - fast GPS
2022-11-07T23:56:38.008Z,1667865398.008 [CommandExec](IMPORTANT): got command quit
2022-11-07T23:56:39.012Z,1667865399.012 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:39.012Z,1667865399.012 [CommandExec](INFO): Uninitializing the command executive.
2022-11-07T23:56:39.012Z,1667865399.012 [CommandExec](INFO): Uninitializing the command scheduler.
2022-11-07T23:56:39.012Z,1667865399.012 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.084Z,1667865399.084 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2022-11-07T23:56:39.084Z,1667865399.084 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2022-11-07T23:56:39.084Z,1667865399.084 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.085Z,1667865399.085 [NavChartDb](INFO): Join timeout helper Thread ID is 4128
2022-11-07T23:56:39.088Z,1667865399.088 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:39.088Z,1667865399.088 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.104Z,1667865399.104 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2022-11-07T23:56:39.104Z,1667865399.104 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.104Z,1667865399.104 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4129
2022-11-07T23:56:39.500Z,1667865399.500 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:39.500Z,1667865399.500 [WetLabsBB2FL](INFO): Powering down
2022-11-07T23:56:39.501Z,1667865399.501 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.521Z,1667865399.521 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2022-11-07T23:56:39.522Z,1667865399.522 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.522Z,1667865399.522 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4130
2022-11-07T23:56:39.860Z,1667865399.860 [CTD_Seabird](INFO): Powering down
2022-11-07T23:56:39.872Z,1667865399.872 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:39.872Z,1667865399.872 [CTD_Seabird](INFO): Powering down
2022-11-07T23:56:39.896Z,1667865399.896 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.900Z,1667865399.900 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2022-11-07T23:56:39.900Z,1667865399.900 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:39.900Z,1667865399.900 [Radio_Surface](INFO): Join timeout helper Thread ID is 4131
2022-11-07T23:56:40.156Z,1667865400.156 [Radio_Surface](INFO): Powering down
2022-11-07T23:56:40.157Z,1667865400.157 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:40.157Z,1667865400.157 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:40.160Z,1667865400.160 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2022-11-07T23:56:40.160Z,1667865400.160 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:40.160Z,1667865400.160 [Onboard](INFO): Join timeout helper Thread ID is 4132
2022-11-07T23:56:40.372Z,1667865400.372 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2022-11-07T23:56:42.284Z,1667865402.284 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:42.284Z,1667865402.284 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.309Z,1667865402.309 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2022-11-07T23:56:42.309Z,1667865402.309 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.309Z,1667865402.309 [DataOverHttps](INFO): Join timeout helper Thread ID is 4133
2022-11-07T23:56:42.380Z,1667865402.380 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:42.380Z,1667865402.380 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.385Z,1667865402.385 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2022-11-07T23:56:42.385Z,1667865402.385 [logger ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.385Z,1667865402.385 [logger](INFO): Join timeout helper Thread ID is 4134
2022-11-07T23:56:42.396Z,1667865402.396 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:42.396Z,1667865402.396 [logger ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.405Z,1667865402.405 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2022-11-07T23:56:42.405Z,1667865402.405 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.405Z,1667865402.405 [CommandLine](INFO): Join timeout helper Thread ID is 4135
2022-11-07T23:56:42.436Z,1667865402.436 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:42.436Z,1667865402.436 [CommandLine ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.444Z,1667865402.444 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2022-11-07T23:56:42.445Z,1667865402.445 [CommandExec ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.445Z,1667865402.445 [CommandExec](INFO): Join timeout helper Thread ID is 4136
2022-11-07T23:56:42.446Z,1667865402.446 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2022-11-07T23:56:42.446Z,1667865402.446 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.447Z,1667865402.447 [controlThread](INFO): Join timeout helper Thread ID is 4137
2022-11-07T23:56:42.628Z,1667865402.628 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2022-11-07T23:56:42.628Z,1667865402.628 [controlThread](DEBUG): Uninitializing ControlThread
2022-11-07T23:56:42.629Z,1667865402.629 [AHRS_M2](INFO): Powering down
2022-11-07T23:56:42.701Z,1667865402.701 [NAL9602](INFO): Powering down
2022-11-07T23:56:42.703Z,1667865402.703 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2022-11-07T23:56:42.705Z,1667865402.705 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2022-11-07T23:56:42.705Z,1667865402.705 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2022-11-07T23:56:42.706Z,1667865402.706 [MissionManager](INFO): Uninitializing Mission Default
2022-11-07T23:56:42.706Z,1667865402.706 [Default] Stopped
2022-11-07T23:56:42.706Z,1667865402.706 [Default](DEBUG): Aggregate::uninitialize Default
2022-11-07T23:56:42.706Z,1667865402.706 [Default:B.GoToSurface] Stopped
2022-11-07T23:56:42.706Z,1667865402.706 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-11-07T23:56:42.706Z,1667865402.706 [Default:CheckIn] Stopped
2022-11-07T23:56:42.706Z,1667865402.706 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-11-07T23:56:42.706Z,1667865402.706 [Default:CheckIn:C.Wait] Stopped
2022-11-07T23:56:42.706Z,1667865402.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-11-07T23:56:42.709Z,1667865402.709 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2022-11-07T23:56:42.709Z,1667865402.709 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2022-11-07T23:56:42.709Z,1667865402.709 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2022-11-07T23:56:42.710Z,1667865402.710 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2022-11-07T23:56:42.710Z,1667865402.710 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2022-11-07T23:56:42.710Z,1667865402.710 [BuoyancyServo](INFO): Powering down
2022-11-07T23:56:42.724Z,1667865402.724 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2022-11-07T23:56:42.724Z,1667865402.724 [ElevatorServo](INFO): Powering down
2022-11-07T23:56:42.725Z,1667865402.725 [MassServo](DEBUG): Uninitialize Mass Servo.
2022-11-07T23:56:42.725Z,1667865402.725 [MassServo](INFO): Powering down
2022-11-07T23:56:42.726Z,1667865402.726 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-11-07T23:56:42.726Z,1667865402.726 [RudderServo](INFO): Powering down
2022-11-07T23:56:42.727Z,1667865402.727 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2022-11-07T23:56:42.727Z,1667865402.727 [ThrusterServo](INFO): Powering down
2022-11-07T23:56:42.728Z,1667865402.728 [SBIT](DEBUG): Uninitialize SBIT Component.
2022-11-07T23:56:42.728Z,1667865402.728 [IBIT](DEBUG): Uninitialize IBIT Component.
2022-11-07T23:56:42.728Z,1667865402.728 [CBIT](DEBUG): Uninitialize CBIT Component.
2022-11-07T23:56:42.729Z,1667865402.729 [CBIT](DEBUG): Powering off loads.
2022-11-07T23:56:42.740Z,1667865402.740 [CBIT](DEBUG): Disabling WDT.
2022-11-07T23:56:42.752Z,1667865402.752 [CBIT](DEBUG): Opening all GF detection circuits.
2022-11-07T23:56:42.753Z,1667865402.753 [controlThread ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.801Z,1667865402.801 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.804Z,1667865402.804 [Onboard ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.810Z,1667865402.810 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.881Z,1667865402.881 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.884Z,1667865402.884 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.924Z,1667865402.924 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2022-11-07T23:56:42.994Z,1667865402.994 [logger ThreadHandler](INFO): Thread cancelled.