2023-07-07T20:05:22.549Z,1688760322.549 [Supervisor](DEBUG): Initializing supervisor.
2023-07-07T20:05:22.553Z,1688760322.553 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-07-07T20:05:22.554Z,1688760322.554 [SyncHandler](INFO): Protected caller Thread ID is 2119
2023-07-07T20:05:22.554Z,1688760322.554 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-07-07T20:05:22.555Z,1688760322.555 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-07-07T20:05:22.555Z,1688760322.555 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2120
2023-07-07T20:05:22.559Z,1688760322.559 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-07-07T20:05:22.576Z,1688760322.576 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-07-07T20:05:22.577Z,1688760322.577 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-07-07T20:05:22.577Z,1688760322.577 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2121
2023-07-07T20:05:22.582Z,1688760322.582 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-07-07T20:05:22.583Z,1688760322.583 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-07-07T20:05:22.583Z,1688760322.583 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2122
2023-07-07T20:05:22.585Z,1688760322.585 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-07-07T20:05:22.586Z,1688760322.586 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-07-07T20:05:22.586Z,1688760322.586 [logger ThreadHandler](INFO): Protected caller Thread ID is 2123
2023-07-07T20:05:22.590Z,1688760322.590 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-07-07T20:05:22.590Z,1688760322.590 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-07-07T20:05:22.592Z,1688760322.592 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-07-07T20:05:22.847Z,1688760322.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-07-07T20:05:22.848Z,1688760322.848 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-07-07T20:05:22.928Z,1688760322.928 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-07-07T20:05:23.374Z,1688760323.374 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-07-07T20:05:23.375Z,1688760323.375 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-07-07T20:05:23.680Z,1688760323.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-07-07T20:05:23.681Z,1688760323.681 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-07-07T20:05:23.776Z,1688760323.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-07-07T20:05:23.777Z,1688760323.777 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-07-07T20:05:24.147Z,1688760324.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-07-07T20:05:24.147Z,1688760324.147 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-07-07T20:05:24.348Z,1688760324.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-07-07T20:05:24.349Z,1688760324.349 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-07-07T20:05:24.835Z,1688760324.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-07-07T20:05:24.836Z,1688760324.836 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-07-07T20:05:24.968Z,1688760324.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-07-07T20:05:24.969Z,1688760324.969 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-07-07T20:05:25.049Z,1688760325.049 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-07-07T20:05:25.760Z,1688760325.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-07-07T20:05:25.761Z,1688760325.761 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-07-07T20:05:26.116Z,1688760326.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-07-07T20:05:26.117Z,1688760326.117 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-07-07T20:05:26.300Z,1688760326.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-07-07T20:05:26.301Z,1688760326.301 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-07-07T20:05:26.410Z,1688760326.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-07-07T20:05:26.410Z,1688760326.410 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-07-07T20:05:26.624Z,1688760326.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-07-07T20:05:26.625Z,1688760326.625 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-07-07T20:05:26.852Z,1688760326.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-07-07T20:05:26.855Z,1688760326.855 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-07-07T20:05:26.856Z,1688760326.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-07-07T20:05:26.946Z,1688760326.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-07-07T20:05:27.031Z,1688760327.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-07-07T20:05:27.131Z,1688760327.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-07-07T20:05:27.215Z,1688760327.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-07-07T20:05:27.334Z,1688760327.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-07-07T20:05:27.633Z,1688760327.633 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-07-07T20:05:28.182Z,1688760328.182 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-07-07T20:05:28.462Z,1688760328.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-07-07T20:05:28.651Z,1688760328.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-07-07T20:05:28.787Z,1688760328.787 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-07-07T20:05:28.893Z,1688760328.893 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-07-07T20:05:29.150Z,1688760329.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-07-07T20:05:29.151Z,1688760329.151 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-07-07T20:05:29.160Z,1688760329.160 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-07-07T20:05:29.209Z,1688760329.209 [VerticalControl](DEBUG): Construct VerticalControl.
2023-07-07T20:05:29.266Z,1688760329.266 [VerticalControl] Loaded
2023-07-07T20:05:29.267Z,1688760329.267 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-07-07T20:05:29.269Z,1688760329.269 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-07-07T20:05:29.309Z,1688760329.309 [HorizontalControl] Loaded
2023-07-07T20:05:29.310Z,1688760329.310 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-07-07T20:05:29.312Z,1688760329.312 [SpeedControl](DEBUG): Construct SpeedControl.
2023-07-07T20:05:29.315Z,1688760329.315 [SpeedControl] Loaded
2023-07-07T20:05:29.315Z,1688760329.315 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-07-07T20:05:29.318Z,1688760329.318 [LoopControl](DEBUG): Construct LoopControl.
2023-07-07T20:05:29.318Z,1688760329.318 [LoopControl] Loaded
2023-07-07T20:05:29.318Z,1688760329.318 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-07-07T20:05:29.319Z,1688760329.319 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-07-07T20:05:29.319Z,1688760329.319 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-07-07T20:05:29.329Z,1688760329.329 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-07-07T20:05:29.330Z,1688760329.330 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-07-07T20:05:29.469Z,1688760329.469 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-07-07T20:05:29.470Z,1688760329.470 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-07-07T20:05:29.594Z,1688760329.594 [BuoyancyServo] Loaded
2023-07-07T20:05:29.594Z,1688760329.594 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-07-07T20:05:29.613Z,1688760329.613 [ElevatorServo] Loaded
2023-07-07T20:05:29.614Z,1688760329.614 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-07-07T20:05:29.632Z,1688760329.632 [MassServo] Loaded
2023-07-07T20:05:29.632Z,1688760329.632 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-07-07T20:05:29.650Z,1688760329.650 [RudderServo] Loaded
2023-07-07T20:05:29.650Z,1688760329.650 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-07-07T20:05:29.667Z,1688760329.667 [ThrusterServo] Loaded
2023-07-07T20:05:29.667Z,1688760329.667 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-07-07T20:05:29.668Z,1688760329.668 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-07-07T20:05:29.668Z,1688760329.668 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-07-07T20:05:29.776Z,1688760329.776 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-07-07T20:05:29.776Z,1688760329.776 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-07-07T20:05:29.790Z,1688760329.790 [NavChart] Loaded
2023-07-07T20:05:29.790Z,1688760329.790 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-07-07T20:05:29.795Z,1688760329.795 [UniversalFixResidualReporter] Loaded
2023-07-07T20:05:29.795Z,1688760329.795 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-07-07T20:05:29.796Z,1688760329.796 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-07-07T20:05:29.797Z,1688760329.797 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-07-07T20:05:29.849Z,1688760329.849 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-07-07T20:05:29.850Z,1688760329.850 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-07-07T20:05:30.253Z,1688760330.253 [AHRS_M2] Loaded
2023-07-07T20:05:30.253Z,1688760330.253 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-07-07T20:05:31.051Z,1688760331.051 [BPC1] Loaded
2023-07-07T20:05:31.052Z,1688760331.052 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-07-07T20:05:31.123Z,1688760331.123 [DataOverHttps] Loaded
2023-07-07T20:05:31.124Z,1688760331.124 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-07-07T20:05:31.125Z,1688760331.125 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2023-07-07T20:05:31.125Z,1688760331.125 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2209
2023-07-07T20:05:31.145Z,1688760331.145 [Depth_Keller] Loaded
2023-07-07T20:05:31.146Z,1688760331.146 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-07-07T20:05:31.150Z,1688760331.150 [DropWeight] Loaded
2023-07-07T20:05:31.151Z,1688760331.151 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-07-07T20:05:31.212Z,1688760331.212 [NAL9602] Loaded
2023-07-07T20:05:31.212Z,1688760331.212 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-07-07T20:05:31.241Z,1688760331.241 [Onboard] Loaded
2023-07-07T20:05:31.241Z,1688760331.241 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-07-07T20:05:31.242Z,1688760331.242 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2023-07-07T20:05:31.243Z,1688760331.243 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2210
2023-07-07T20:05:31.256Z,1688760331.256 [Power24vConverter] Loaded
2023-07-07T20:05:31.256Z,1688760331.256 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-07-07T20:05:31.269Z,1688760331.269 [Radio_Surface] Loaded
2023-07-07T20:05:31.269Z,1688760331.269 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-07-07T20:05:31.270Z,1688760331.270 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2023-07-07T20:05:31.271Z,1688760331.271 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2211
2023-07-07T20:05:31.272Z,1688760331.272 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-07-07T20:05:31.272Z,1688760331.272 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-07-07T20:05:31.322Z,1688760331.322 [DepthRateCalculator] Loaded
2023-07-07T20:05:31.322Z,1688760331.322 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-07-07T20:05:31.327Z,1688760331.327 [PitchRateCalculator] Loaded
2023-07-07T20:05:31.327Z,1688760331.327 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-07-07T20:05:31.336Z,1688760331.336 [SpeedCalculator] Loaded
2023-07-07T20:05:31.336Z,1688760331.336 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-07-07T20:05:31.340Z,1688760331.340 [YawRateCalculator] Loaded
2023-07-07T20:05:31.340Z,1688760331.340 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-07-07T20:05:31.359Z,1688760331.359 [ElevatorOffsetCalculator] Loaded
2023-07-07T20:05:31.359Z,1688760331.359 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-07-07T20:05:31.360Z,1688760331.360 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-07-07T20:05:31.360Z,1688760331.360 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-07-07T20:05:31.482Z,1688760331.482 [CANONSampler] Loaded
2023-07-07T20:05:31.483Z,1688760331.483 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-07-07T20:05:31.541Z,1688760331.541 [CTD_Seabird] Loaded
2023-07-07T20:05:31.541Z,1688760331.541 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-07-07T20:05:31.542Z,1688760331.542 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0
2023-07-07T20:05:31.543Z,1688760331.543 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2212
2023-07-07T20:05:31.562Z,1688760331.562 [PAR_Licor] Loaded
2023-07-07T20:05:31.563Z,1688760331.563 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-07-07T20:05:31.593Z,1688760331.593 [WetLabsBB2FL] Loaded
2023-07-07T20:05:31.593Z,1688760331.593 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-07-07T20:05:31.594Z,1688760331.594 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0
2023-07-07T20:05:31.594Z,1688760331.594 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2213
2023-07-07T20:05:31.595Z,1688760331.595 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-07-07T20:05:31.596Z,1688760331.596 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-07-07T20:05:31.621Z,1688760331.621 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-07-07T20:05:31.621Z,1688760331.621 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-07-07T20:05:31.911Z,1688760331.911 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-07-07T20:05:31.912Z,1688760331.912 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-07-07T20:05:32.044Z,1688760332.044 [SBIT](DEBUG): Construct Startup Built In Test.
2023-07-07T20:05:32.053Z,1688760332.053 [SBIT] Loaded
2023-07-07T20:05:32.053Z,1688760332.053 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-07-07T20:05:32.056Z,1688760332.056 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-07-07T20:05:32.068Z,1688760332.068 [IBIT] Loaded
2023-07-07T20:05:32.069Z,1688760332.069 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-07-07T20:05:32.074Z,1688760332.074 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-07-07T20:05:32.176Z,1688760332.176 [CBIT] Loaded
2023-07-07T20:05:32.177Z,1688760332.177 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-07-07T20:05:32.177Z,1688760332.177 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-07-07T20:05:32.178Z,1688760332.178 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-07-07T20:05:32.271Z,1688760332.271 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-07-07T20:05:32.277Z,1688760332.277 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-07-07T20:05:32.280Z,1688760332.280 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-07-07T20:05:32.290Z,1688760332.290 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-07-07T20:05:32.292Z,1688760332.292 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B814E0
2023-07-07T20:05:32.292Z,1688760332.292 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2214
2023-07-07T20:05:32.297Z,1688760332.297 [Supervisor](INFO): Main Thread ID is 2118
2023-07-07T20:05:32.297Z,1688760332.297 [Supervisor](DEBUG): Running supervisor.
2023-07-07T20:05:32.297Z,1688760332.297 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2215
2023-07-07T20:05:32.298Z,1688760332.298 [CommandExec](INFO): Initializing the command executive.
2023-07-07T20:05:32.300Z,1688760332.300 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2216
2023-07-07T20:05:32.302Z,1688760332.302 [controlThread ThreadHandler](INFO): Handler Thread ID is 2217
2023-07-07T20:05:32.302Z,1688760332.302 [controlThread](DEBUG): Initializing ControlThread
2023-07-07T20:05:32.303Z,1688760332.303 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-07-07T20:05:32.305Z,1688760332.305 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-07-07T20:05:32.306Z,1688760332.306 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-07-07T20:05:32.306Z,1688760332.306 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-07-07T20:05:32.308Z,1688760332.308 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-07-07T20:05:32.308Z,1688760332.308 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-07-07T20:05:32.313Z,1688760332.313 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-07-07T20:05:32.313Z,1688760332.313 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-07-07T20:05:32.313Z,1688760332.313 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-07-07T20:05:32.314Z,1688760332.314 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-07-07T20:05:32.314Z,1688760332.314 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-07-07T20:05:32.319Z,1688760332.319 [SBIT](INFO): Initialize SBIT Component.
2023-07-07T20:05:32.320Z,1688760332.320 [SBIT](IMPORTANT): git: 2023-03-30-8-g9f4e94ede
2023-07-07T20:05:32.320Z,1688760332.320 [SBIT](INFO): git hash: 9f4e94ede96dd26b46ac6117d56fbd84feccae72
2023-07-07T20:05:32.320Z,1688760332.320 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-07-07T20:05:32.321Z,1688760332.321 [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
2023-07-07T20:05:32.323Z,1688760332.323 [SBIT](INFO): Beginning SBIT in 67.000000 seconds.
2023-07-07T20:05:32.323Z,1688760332.323 [IBIT](INFO): Initialize IBIT Component.
2023-07-07T20:05:32.324Z,1688760332.324 [CBIT](DEBUG): Initialize CBIT Component.
2023-07-07T20:05:32.325Z,1688760332.325 [logger ThreadHandler](INFO): Handler Thread ID is 2218
2023-07-07T20:05:32.335Z,1688760332.335 [CBIT](DEBUG): Initialized mux pins.
2023-07-07T20:05:32.335Z,1688760332.335 [CBIT](DEBUG): Initializing the watchdog timer.
2023-07-07T20:05:32.343Z,1688760332.343 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2219
2023-07-07T20:05:32.345Z,1688760332.345 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-07-07T20:05:32.355Z,1688760332.355 [Onboard ThreadHandler](INFO): Handler Thread ID is 2220
2023-07-07T20:05:32.360Z,1688760332.360 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-07-07T20:05:32.360Z,1688760332.360 [CBIT](DEBUG): Initializing heartbeat.
2023-07-07T20:05:32.378Z,1688760332.378 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2221
2023-07-07T20:05:32.395Z,1688760332.395 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2222
2023-07-07T20:05:32.396Z,1688760332.396 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-07-07T20:05:32.401Z,1688760332.401 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2224
2023-07-07T20:05:32.403Z,1688760332.403 [WetLabsBB2FL](INFO): Powering up
2023-07-07T20:05:32.405Z,1688760332.405 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2226
2023-07-07T20:05:32.408Z,1688760332.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-07-07T20:05:32.408Z,1688760332.408 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-07-07T20:05:32.408Z,1688760332.408 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-07-07T20:05:32.409Z,1688760332.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-07-07T20:05:32.409Z,1688760332.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-07-07T20:05:32.409Z,1688760332.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-07-07T20:05:32.409Z,1688760332.409 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-07-07T20:05:32.409Z,1688760332.409 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-07-07T20:05:32.431Z,1688760332.431 [CBIT](DEBUG): Deactivating GF circuits.
2023-07-07T20:05:32.431Z,1688760332.431 [CBIT](DEBUG): Deactivating emergency mode.
2023-07-07T20:05:32.471Z,1688760332.471 [CBIT](DEBUG): Backplane powered.
2023-07-07T20:05:32.472Z,1688760332.472 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-07-07T20:05:32.484Z,1688760332.484 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-07-07T20:05:32.496Z,1688760332.496 [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>


2023-07-07T20:05:32.497Z,1688760332.497 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-07-07T20:05:32.697Z,1688760332.697 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-07-07T20:05:32.698Z,1688760332.698 [Default:A.Wait](DEBUG): Construct Wait.
2023-07-07T20:05:32.713Z,1688760332.713 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-07-07T20:05:32.740Z,1688760332.740 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-07-07T20:05:32.771Z,1688760332.771 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-07-07T20:05:32.787Z,1688760332.787 [Radio_Surface](INFO): Powering up
2023-07-07T20:05:32.790Z,1688760332.790 [Default:E.Execute](DEBUG): Construct Execute.
2023-07-07T20:05:32.806Z,1688760332.806 [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>


2023-07-07T20:05:32.811Z,1688760332.811 [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,
2023-07-07T20:05:32.845Z,1688760332.845 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-07-07T20:05:32.927Z,1688760332.927 [Power24vConverter](INFO): Powering up.
2023-07-07T20:05:32.928Z,1688760332.928 [CANONSampler](INFO): Powering down
2023-07-07T20:05:32.973Z,1688760332.973 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-07-07T20:05:32.979Z,1688760332.979 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-07-07T20:05:32.981Z,1688760332.981 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-07-07T20:05:32.987Z,1688760332.987 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-07-07T20:05:32.988Z,1688760332.988 [MassServo](DEBUG): Initializing EZServoServo.
2023-07-07T20:05:32.995Z,1688760332.995 [MassServo](DEBUG): Initializing MassServo.
2023-07-07T20:05:32.996Z,1688760332.996 [RudderServo](DEBUG): Initializing EZServoServo.
2023-07-07T20:05:33.003Z,1688760333.003 [RudderServo](DEBUG): Initializing RudderServo.
2023-07-07T20:05:33.004Z,1688760333.004 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-07-07T20:05:33.011Z,1688760333.011 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-07-07T20:05:35.015Z,1688760335.015 [WetLabsBB2FL](INFO): Powering down
2023-07-07T20:05:45.757Z,1688760345.757 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004696
2023-07-07T20:06:01.509Z,1688760361.509 [NAL9602](INFO): Powering up NAL9602
2023-07-07T20:06:12.417Z,1688760372.417 [NAL9602](INFO): NAL9602 initialized
2023-07-07T20:06:39.926Z,1688760399.926 [SBIT](IMPORTANT): Beginning Startup BIT
2023-07-07T20:06:39.931Z,1688760399.931 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-07T20:06:50.871Z,1688760410.871 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-07-07T20:06:51.230Z,1688760411.230 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-07T20:07:02.224Z,1688760422.224 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-07-07T20:07:02.641Z,1688760422.641 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-07T20:07:13.532Z,1688760433.532 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-07-07T20:07:13.532Z,1688760433.532 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-07-07T20:07:34.062Z,1688760454.062 [SBIT](IMPORTANT): SBIT PASSED
2023-07-07T20:07:34.063Z,1688760454.063 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-07-07T20:07:34.064Z,1688760454.064 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-07-07T20:07:34.064Z,1688760454.064 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2023-07-07T20:07:34.064Z,1688760454.064 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-07-07T20:07:34.064Z,1688760454.064 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-07-07T20:07:34.064Z,1688760454.064 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-07-07T20:07:34.064Z,1688760454.064 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2023-07-07T20:07:34.064Z,1688760454.064 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2023-07-07T20:07:34.065Z,1688760454.065 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234.842285 cubic_centimeter;

2023-07-07T20:07:34.065Z,1688760454.065 [SBIT](IMPORTANT): VerticalControl.massDefault=-11.931131 millimeter;

2023-07-07T20:07:34.461Z,1688760454.461 [MissionManager](IMPORTANT): Started mission Startup
2023-07-07T20:07:34.462Z,1688760454.462 [Startup] Running Loop=1
2023-07-07T20:07:34.462Z,1688760454.462 [Startup](DEBUG): Aggregate::initialize Startup
2023-07-07T20:07:34.462Z,1688760454.462 [Startup:A.GoToSurface] Running Loop=1
2023-07-07T20:07:34.462Z,1688760454.462 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-07-07T20:07:34.462Z,1688760454.462 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-07-07T20:07:34.463Z,1688760454.463 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-07-07T20:07:34.463Z,1688760454.463 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-07-07T20:07:34.464Z,1688760454.464 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-07-07T20:07:34.464Z,1688760454.464 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-07-07T20:07:34.465Z,1688760454.465 [Startup:StartupSatComms] Running Loop=1
2023-07-07T20:07:34.466Z,1688760454.466 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-07-07T20:07:34.466Z,1688760454.466 [Startup:StartupSatComms:A] Running Loop=1
2023-07-07T20:07:34.870Z,1688760454.870 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-07-07T20:08:32.672Z,1688760512.672 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-07-07T20:08:32.673Z,1688760512.673 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:08:32.683Z,1688760512.683 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:08:33.038Z,1688760513.038 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:08:33.038Z,1688760513.038 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-07-07T20:08:34.637Z,1688760514.637 [Startup:StartupSatComms:A](INFO): Timed out from 2023-07-07T20:07:34.5Z
2023-07-07T20:08:34.637Z,1688760514.637 [Startup:StartupSatComms:A] Stopped
2023-07-07T20:08:34.637Z,1688760514.637 [Startup:StartupSatComms:B] Running Loop=1
2023-07-07T20:08:35.069Z,1688760515.069 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-07-07T20:08:41.367Z,1688760521.367 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230706T232535/Courier0004.lzma
2023-07-07T20:08:42.369Z,1688760522.369 [DataOverHttps](INFO): Moved sent file to Logs/20230706T232535/Courier0004.lzma.bak
2023-07-07T20:08:42.369Z,1688760522.369 [DataOverHttps](INFO): SBD MOMSN=18455022
2023-07-07T20:08:57.771Z,1688760537.771 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230707T200522/Courier0000.lzma
2023-07-07T20:08:58.773Z,1688760538.773 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0000.lzma.bak
2023-07-07T20:08:58.773Z,1688760538.773 [DataOverHttps](INFO): SBD MOMSN=18455024
2023-07-07T20:09:14.252Z,1688760554.252 [DataOverHttps](INFO): Sending 751 bytes from file Logs/20230706T232535/Express0001.lzma
2023-07-07T20:09:15.253Z,1688760555.253 [DataOverHttps](INFO): Moved sent file to Logs/20230706T232535/Express0001.lzma.bak
2023-07-07T20:09:15.253Z,1688760555.253 [DataOverHttps](INFO): SBD MOMSN=18455027
2023-07-07T20:09:30.884Z,1688760570.884 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230706T232535/Express0005.lzma
2023-07-07T20:09:31.885Z,1688760571.885 [DataOverHttps](INFO): Moved sent file to Logs/20230706T232535/Express0005.lzma.bak
2023-07-07T20:09:31.885Z,1688760571.885 [DataOverHttps](INFO): SBD MOMSN=18455049
2023-07-07T20:09:34.833Z,1688760574.833 [Startup:StartupSatComms:B](INFO): Timed out from 2023-07-07T20:08:34.6Z
2023-07-07T20:09:34.833Z,1688760574.833 [Startup:StartupSatComms:B] Stopped
2023-07-07T20:09:34.833Z,1688760574.833 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-07-07T20:09:34.833Z,1688760574.833 [Startup:StartupSatComms] Stopped
2023-07-07T20:09:34.833Z,1688760574.833 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-07-07T20:09:34.834Z,1688760574.834 [Startup](INFO): Completed Startup
2023-07-07T20:09:34.834Z,1688760574.834 [MissionManager](INFO): Startup is completed.
2023-07-07T20:09:34.834Z,1688760574.834 [MissionManager](INFO): Uninitializing Mission Startup
2023-07-07T20:09:34.834Z,1688760574.834 [Startup] Stopped
2023-07-07T20:09:34.835Z,1688760574.835 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-07-07T20:09:34.835Z,1688760574.835 [Startup:A.GoToSurface] Stopped
2023-07-07T20:09:34.835Z,1688760574.835 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-07-07T20:09:35.260Z,1688760575.260 [MissionManager](IMPORTANT): Started mission Default
2023-07-07T20:09:35.260Z,1688760575.260 [Default] Running Loop=1
2023-07-07T20:09:35.261Z,1688760575.261 [Default](DEBUG): Aggregate::initialize Default
2023-07-07T20:09:35.261Z,1688760575.261 [Default:B.GoToSurface] Running Loop=1
2023-07-07T20:09:35.261Z,1688760575.261 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-07-07T20:09:35.261Z,1688760575.261 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-07-07T20:09:35.261Z,1688760575.261 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-07-07T20:09:35.262Z,1688760575.262 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-07-07T20:09:35.262Z,1688760575.262 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-07-07T20:09:35.262Z,1688760575.262 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-07-07T20:09:35.262Z,1688760575.262 [Default:A.Wait] Running Loop=1
2023-07-07T20:09:35.263Z,1688760575.263 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-07-07T20:09:41.152Z,1688760581.152 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2023-07-07T20:09:41.154Z,1688760581.154 [CommandExec](IMPORTANT): Onboard.Pressure 69464.242188 Pa
2023-07-07T20:09:47.788Z,1688760587.788 [DataOverHttps](INFO): Sending 752 bytes from file Logs/20230707T200522/Express0001.lzma
2023-07-07T20:09:48.565Z,1688760588.565 [Default:A.Wait](INFO): Done Waiting.
2023-07-07T20:09:48.565Z,1688760588.565 [Default:A.Wait] Stopped
2023-07-07T20:09:48.565Z,1688760588.565 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T20:09:48.789Z,1688760588.789 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0001.lzma.bak
2023-07-07T20:09:48.789Z,1688760588.789 [DataOverHttps](INFO): SBD MOMSN=18455054
2023-07-07T20:09:48.986Z,1688760588.986 [Default:CheckIn] Running Loop=1
2023-07-07T20:09:48.986Z,1688760588.986 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T20:09:48.986Z,1688760588.986 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T20:09:49.378Z,1688760589.378 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-07-07T20:11:15.849Z,1688760675.849 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-07-07T20:11:33.647Z,1688760693.647 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-07-07T20:11:33.647Z,1688760693.647 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:11:33.674Z,1688760693.674 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:11:34.066Z,1688760694.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:11:34.066Z,1688760694.066 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-07-07T20:12:44.724Z,1688760764.724 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T20:12:44.747Z,1688760764.747 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-07-07T20:12:44.751Z,1688760764.751 [BPC1](INFO): Received data from all battery sticks.
2023-07-07T20:14:34.628Z,1688760874.628 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-07-07T20:14:34.628Z,1688760874.628 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:14:34.655Z,1688760874.655 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:14:35.059Z,1688760875.059 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:14:35.059Z,1688760875.059 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-07-07T20:14:49.194Z,1688760889.194 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-07-07T20:09:48.0Z
2023-07-07T20:14:49.195Z,1688760889.195 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T20:14:49.195Z,1688760889.195 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T20:14:49.594Z,1688760889.594 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-07-07T20:14:50.733Z,1688760890.733 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230707T200522/Courier0004.lzma
2023-07-07T20:14:51.689Z,1688760891.689 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0004.lzma.bak
2023-07-07T20:14:51.689Z,1688760891.689 [DataOverHttps](INFO): SBD MOMSN=18455080
2023-07-07T20:15:07.083Z,1688760907.083 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20230707T200522/Express0005.lzma
2023-07-07T20:15:08.085Z,1688760908.085 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0005.lzma.bak
2023-07-07T20:15:08.085Z,1688760908.085 [DataOverHttps](INFO): SBD MOMSN=18455082
2023-07-07T20:15:09.409Z,1688760909.409 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T20:15:09.409Z,1688760909.409 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T20:15:09.409Z,1688760909.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T20:15:34.033Z,1688760934.033 [Power24vConverter](INFO): Powering down.
2023-07-07T20:16:14.025Z,1688760974.025 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-07-07T20:16:14.025Z,1688760974.025 [NAL9602] Data Fault, FailCount= 1
2023-07-07T20:16:14.025Z,1688760974.025 [NAL9602](ERROR): Data Fault
2023-07-07T20:16:14.079Z,1688760974.079 [CBIT](ERROR): Data Fault in component: NAL9602
2023-07-07T20:16:14.428Z,1688760974.428 [NAL9602](INFO): Powering down
2023-07-07T20:16:15.259Z,1688760975.259 [CBIT](INFO): Clearing failed state for component NAL9602
2023-07-07T20:16:15.259Z,1688760975.259 [NAL9602] No Fault, FailCount= 1
2023-07-07T20:16:44.728Z,1688761004.728 [NAL9602](INFO): Powering up NAL9602
2023-07-07T20:16:55.641Z,1688761015.641 [NAL9602](INFO): NAL9602 initialized
2023-07-07T20:17:09.979Z,1688761029.979 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:17:35.656Z,1688761055.656 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-07-07T20:17:35.656Z,1688761055.657 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:17:35.667Z,1688761055.667 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:17:36.082Z,1688761056.082 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:17:36.082Z,1688761056.082 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-07-07T20:17:41.115Z,1688761061.115 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:18:12.239Z,1688761092.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:19:14.043Z,1688761154.043 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:19:45.168Z,1688761185.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:20:10.025Z,1688761210.025 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T20:20:10.025Z,1688761210.025 [Default:CheckIn:C.Wait] Stopped
2023-07-07T20:20:10.025Z,1688761210.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T20:20:10.025Z,1688761210.025 [Default:CheckIn:D] Running Loop=1
2023-07-07T20:20:10.413Z,1688761210.413 [Default:CheckIn:D] Stopped
2023-07-07T20:20:10.413Z,1688761210.413 [Default:CheckIn:E] Running Loop=1
2023-07-07T20:20:10.804Z,1688761210.804 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.585882 min 
2023-07-07T20:20:10.804Z,1688761210.804 [Default:CheckIn:E] Stopped
2023-07-07T20:20:10.804Z,1688761210.804 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T20:20:10.805Z,1688761210.805 [Default:CheckIn] Stopped
2023-07-07T20:20:10.805Z,1688761210.805 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T20:20:10.805Z,1688761210.805 [Default:CheckIn](INFO): Running loop #2
2023-07-07T20:20:10.805Z,1688761210.805 [Default:CheckIn] Running Loop=2
2023-07-07T20:20:10.805Z,1688761210.805 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T20:20:10.805Z,1688761210.805 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T20:20:16.291Z,1688761216.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:20:36.680Z,1688761236.680 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-07-07T20:20:36.680Z,1688761236.680 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:20:36.691Z,1688761236.691 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:20:37.097Z,1688761237.097 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:20:37.097Z,1688761237.097 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-07-07T20:20:47.415Z,1688761247.415 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:21:18.539Z,1688761278.539 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:21:22.556Z,1688761282.556 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743271
2023-07-07T20:22:19.796Z,1688761339.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:22:50.920Z,1688761370.920 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:23:22.051Z,1688761402.051 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:23:37.675Z,1688761417.675 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-07-07T20:23:37.675Z,1688761417.675 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:23:37.686Z,1688761417.686 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:23:38.097Z,1688761418.097 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:23:38.097Z,1688761418.097 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-07-07T20:23:53.187Z,1688761433.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:24:24.339Z,1688761464.339 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:25:09.555Z,1688761509.555 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002940
2023-07-07T20:25:10.999Z,1688761510.999 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-07-07T20:20:10.8Z
2023-07-07T20:25:10.999Z,1688761510.999 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T20:25:10.999Z,1688761510.999 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T20:25:17.697Z,1688761517.697 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230707T200522/Courier0007.lzma
2023-07-07T20:25:19.702Z,1688761519.702 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0007.lzma.bak
2023-07-07T20:25:19.702Z,1688761519.702 [DataOverHttps](INFO): SBD MOMSN=18455099
2023-07-07T20:25:38.975Z,1688761538.975 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230707T200522/Express0008.lzma
2023-07-07T20:25:40.981Z,1688761540.981 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0008.lzma.bak
2023-07-07T20:25:40.981Z,1688761540.981 [DataOverHttps](INFO): SBD MOMSN=18455101
2023-07-07T20:25:42.093Z,1688761542.093 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T20:25:42.093Z,1688761542.093 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T20:25:42.093Z,1688761542.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T20:26:38.669Z,1688761598.669 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-07-07T20:26:38.669Z,1688761598.669 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:26:38.680Z,1688761598.680 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:26:39.094Z,1688761599.094 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:26:39.094Z,1688761599.094 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-07-07T20:26:56.821Z,1688761616.821 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-07-07T20:26:56.821Z,1688761616.821 [NAL9602] Data Fault, FailCount= 2
2023-07-07T20:26:56.821Z,1688761616.821 [NAL9602](ERROR): Data Fault
2023-07-07T20:26:56.837Z,1688761616.837 [CBIT](ERROR): Data Fault in component: NAL9602
2023-07-07T20:26:57.223Z,1688761617.223 [NAL9602](INFO): Powering down
2023-07-07T20:26:58.084Z,1688761618.084 [CBIT](INFO): Clearing failed state for component NAL9602
2023-07-07T20:26:58.084Z,1688761618.084 [NAL9602] No Fault, FailCount= 2
2023-07-07T20:27:27.528Z,1688761647.528 [NAL9602](INFO): Powering up NAL9602
2023-07-07T20:27:38.436Z,1688761658.436 [NAL9602](INFO): NAL9602 initialized
2023-07-07T20:27:43.387Z,1688761663.387 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:28:14.519Z,1688761694.519 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:28:45.647Z,1688761725.647 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:29:16.779Z,1688761756.779 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:29:39.646Z,1688761779.646 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-07-07T20:29:39.646Z,1688761779.646 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:29:39.656Z,1688761779.656 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:29:40.068Z,1688761780.068 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:29:40.068Z,1688761780.068 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-07-07T20:30:42.729Z,1688761842.729 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T20:30:42.729Z,1688761842.729 [Default:CheckIn:C.Wait] Stopped
2023-07-07T20:30:42.729Z,1688761842.729 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T20:30:42.730Z,1688761842.730 [Default:CheckIn:D] Running Loop=1
2023-07-07T20:30:43.133Z,1688761843.133 [Default:CheckIn:D] Stopped
2023-07-07T20:30:43.133Z,1688761843.133 [Default:CheckIn:E] Running Loop=1
2023-07-07T20:30:43.550Z,1688761843.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.131203 min 
2023-07-07T20:30:43.550Z,1688761843.550 [Default:CheckIn:E] Stopped
2023-07-07T20:30:43.550Z,1688761843.550 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T20:30:43.550Z,1688761843.550 [Default:CheckIn] Stopped
2023-07-07T20:30:43.550Z,1688761843.550 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T20:30:43.551Z,1688761843.551 [Default:CheckIn](INFO): Running loop #3
2023-07-07T20:30:43.551Z,1688761843.551 [Default:CheckIn] Running Loop=3
2023-07-07T20:30:43.551Z,1688761843.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T20:30:43.551Z,1688761843.551 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T20:31:27.528Z,1688761887.528 [DataOverHttps](IMPORTANT): SBD MTMSN=20230707T203126
2023-07-07T20:31:34.607Z,1688761894.607 [DataOverHttps](INFO): Received command: get onboard.humidity
2023-07-07T20:31:35.173Z,1688761895.173 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2023-07-07T20:31:35.174Z,1688761895.174 [CommandExec](IMPORTANT): Onboard.Humidity 3.516054 %
2023-07-07T20:32:40.803Z,1688761960.803 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-07-07T20:32:40.803Z,1688761960.803 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-07T20:32:40.814Z,1688761960.814 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-07T20:32:41.224Z,1688761961.224 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-07T20:32:41.224Z,1688761961.224 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-07-07T20:33:11.067Z,1688761991.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203309.00,A,3648.17716,N,12147.28324,W,0.544,320.67,070723,,,A*72
2023-07-07T20:33:11.112Z,1688761991.112 [NAL9602](INFO): GPS fix at 20230707T203309: (36.802953, -121.788054)
2023-07-07T20:33:11.123Z,1688761991.123 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T20:33:11.123Z,1688761991.123 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T20:33:20.295Z,1688762000.295 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230707T200522/Courier0010.lzma
2023-07-07T20:33:21.297Z,1688762001.297 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0010.lzma.bak
2023-07-07T20:33:21.297Z,1688762001.297 [DataOverHttps](INFO): SBD MOMSN=18455115
2023-07-07T20:33:37.867Z,1688762017.867 [DataOverHttps](INFO): Sending 385 bytes from file Logs/20230707T200522/Express0011.lzma
2023-07-07T20:33:38.869Z,1688762018.869 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0011.lzma.bak
2023-07-07T20:33:38.869Z,1688762018.869 [DataOverHttps](INFO): SBD MOMSN=18455118
2023-07-07T20:33:43.386Z,1688762023.386 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-07T20:34:03.502Z,1688762043.502 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230707T200522/Express0014.lzma
2023-07-07T20:34:04.505Z,1688762044.505 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0014.lzma.bak
2023-07-07T20:34:04.505Z,1688762044.505 [DataOverHttps](INFO): SBD MOMSN=18455125
2023-07-07T20:34:05.624Z,1688762045.624 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T20:34:05.624Z,1688762045.624 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T20:34:05.625Z,1688762045.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T20:38:16.373Z,1688762296.373 [DataOverHttps](IMPORTANT): SBD MTMSN=20230707T203815
2023-07-07T20:38:26.347Z,1688762306.347 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.xml
2023-07-07T20:38:26.408Z,1688762306.408 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2023-07-07T20:38:26.408Z,1688762306.408 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.xml
2023-07-07T20:38:26.498Z,1688762306.498 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2023-07-07T20:38:26.509Z,1688762306.509 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2023-07-07T20:38:26.512Z,1688762306.512 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2023-07-07T20:38:26.515Z,1688762306.515 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2023-07-07T20:38:26.518Z,1688762306.518 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2023-07-07T20:38:26.531Z,1688762306.531 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2023-07-07T20:38:26.534Z,1688762306.534 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2023-07-07T20:38:26.537Z,1688762306.537 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2023-07-07T20:38:26.588Z,1688762306.588 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2023-07-07T20:38:26.591Z,1688762306.591 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2023-07-07T20:38:26.594Z,1688762306.594 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2023-07-07T20:38:26.609Z,1688762306.609 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2023-07-07T20:38:26.612Z,1688762306.612 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2023-07-07T20:38:26.631Z,1688762306.631 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2023-07-07T20:38:26.632Z,1688762306.632 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2023-07-07T20:38:27.286Z,1688762307.286 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2023-07-07T20:38:27.289Z,1688762307.289 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2023-07-07T20:38:27.300Z,1688762307.300 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2023-07-07T20:38:27.302Z,1688762307.302 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2023-07-07T20:38:27.305Z,1688762307.305 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2023-07-07T20:38:27.352Z,1688762307.352 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2023-07-07T20:38:27.355Z,1688762307.355 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2023-07-07T20:38:27.362Z,1688762307.362 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2023-07-07T20:38:27.384Z,1688762307.384 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2023-07-07T20:38:27.399Z,1688762307.399 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2023-07-07T20:38:27.402Z,1688762307.402 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2023-07-07T20:38:27.405Z,1688762307.405 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2023-07-07T20:38:27.424Z,1688762307.424 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2023-07-07T20:38:27.426Z,1688762307.426 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2023-07-07T20:38:27.446Z,1688762307.446 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-07-07T20:38:27.450Z,1688762307.450 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2023-07-07T20:38:27.454Z,1688762307.454 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2023-07-07T20:38:27.506Z,1688762307.506 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2023-07-07T20:38:27.526Z,1688762307.526 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2023-07-07T20:38:27.542Z,1688762307.542 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-07-07T20:38:27.565Z,1688762307.565 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2023-07-07T20:38:27.573Z,1688762307.573 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2023-07-07T20:38:27.590Z,1688762307.590 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2023-07-07T20:38:27.618Z,1688762307.618 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2023-07-07T20:38:27.621Z,1688762307.621 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2023-07-07T20:38:27.637Z,1688762307.637 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-07-07T20:38:27.644Z,1688762307.644 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = 0.000000 ug/l
2023-07-07T20:38:27.659Z,1688762307.659 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = 0.000000 m
2023-07-07T20:38:27.662Z,1688762307.662 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = 0.000000 degC
2023-07-07T20:38:27.669Z,1688762307.669 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2023-07-07T20:38:27.689Z,1688762307.689 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2023-07-07T20:38:27.692Z,1688762307.692 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = 0.000000 umol/l
2023-07-07T20:38:27.707Z,1688762307.707 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = 0.000000 m
2023-07-07T20:38:27.710Z,1688762307.710 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2023-07-07T20:38:27.713Z,1688762307.713 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2023-07-07T20:38:27.736Z,1688762307.736 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = 0.000000 kg/m3
2023-07-07T20:38:27.743Z,1688762307.743 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = 0.000000 m
2023-07-07T20:38:27.746Z,1688762307.746 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2023-07-07T20:38:27.765Z,1688762307.765 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2023-07-07T20:38:27.781Z,1688762307.781 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = 0.000000 ppb
2023-07-07T20:38:27.786Z,1688762307.786 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = 0.000000 m
2023-07-07T20:38:27.858Z,1688762307.858 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2023-07-07T20:38:27.860Z,1688762307.860 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2023-07-07T20:38:27.904Z,1688762307.904 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2023-07-07T20:38:27.906Z,1688762307.906 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2023-07-07T20:38:27.910Z,1688762307.910 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = 0.000000 psu
2023-07-07T20:38:27.933Z,1688762307.933 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = 0.000000 m
2023-07-07T20:38:27.936Z,1688762307.936 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = 0.000000 degC
2023-07-07T20:38:27.938Z,1688762307.938 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2023-07-07T20:38:27.971Z,1688762307.971 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2023-07-07T20:38:27.974Z,1688762307.974 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = 0.000000 ug/l
2023-07-07T20:38:28.009Z,1688762308.009 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = 0.000000 m
2023-07-07T20:38:28.012Z,1688762308.012 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2023-07-07T20:38:28.031Z,1688762308.031 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2023-07-07T20:38:28.035Z,1688762308.035 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2023-07-07T20:38:28.038Z,1688762308.038 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = 0.000000 psu
2023-07-07T20:38:28.050Z,1688762308.050 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = 0.000000 m
2023-07-07T20:38:28.053Z,1688762308.053 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2023-07-07T20:38:28.080Z,1688762308.080 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2023-07-07T20:38:28.083Z,1688762308.083 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2023-07-07T20:38:28.086Z,1688762308.086 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = 0.000000 psu
2023-07-07T20:38:28.113Z,1688762308.113 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = 0.000000 m
2023-07-07T20:38:28.116Z,1688762308.116 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2023-07-07T20:38:28.137Z,1688762308.137 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2023-07-07T20:38:28.140Z,1688762308.140 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2023-07-07T20:38:28.268Z,1688762308.268 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-07-07T20:38:28.314Z,1688762308.314 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-07-07T20:38:28.391Z,1688762308.391 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-07-07T20:38:28.417Z,1688762308.417 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-07-07T20:38:28.440Z,1688762308.440 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-07-07T20:38:28.481Z,1688762308.481 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-07-07T20:38:28.516Z,1688762308.516 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-07-07T20:38:28.569Z,1688762308.569 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-07-07T20:38:28.607Z,1688762308.607 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.xml
2023-07-07T20:38:28.931Z,1688762308.931 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2023-07-07T20:38:28.934Z,1688762308.934 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2023-07-07T20:38:28.950Z,1688762308.950 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2023-07-07T20:38:28.953Z,1688762308.953 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2023-07-07T20:38:28.984Z,1688762308.984 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2023-07-07T20:38:28.987Z,1688762308.987 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2023-07-07T20:38:28.990Z,1688762308.990 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2023-07-07T20:38:29.009Z,1688762309.009 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2023-07-07T20:38:29.012Z,1688762309.012 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2023-07-07T20:38:29.032Z,1688762309.032 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2023-07-07T20:38:29.035Z,1688762309.035 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2023-07-07T20:38:29.038Z,1688762309.038 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2023-07-07T20:38:29.096Z,1688762309.096 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2023-07-07T20:38:29.131Z,1688762309.131 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2023-07-07T20:38:29.174Z,1688762309.174 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2023-07-07T20:38:29.209Z,1688762309.209 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2023-07-07T20:38:29.213Z,1688762309.213 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2023-07-07T20:38:29.242Z,1688762309.242 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2023-07-07T20:38:29.264Z,1688762309.264 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2023-07-07T20:38:29.267Z,1688762309.267 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2023-07-07T20:38:29.285Z,1688762309.285 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2023-07-07T20:38:29.288Z,1688762309.288 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2023-07-07T20:38:29.311Z,1688762309.311 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2023-07-07T20:38:29.320Z,1688762309.320 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2023-07-07T20:38:29.326Z,1688762309.326 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2023-07-07T20:38:29.366Z,1688762309.366 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2023-07-07T20:38:29.394Z,1688762309.395 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2023-07-07T20:38:29.396Z,1688762309.396 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2023-07-07T20:38:29.398Z,1688762309.398 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2023-07-07T20:38:29.410Z,1688762309.410 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2023-07-07T20:38:29.412Z,1688762309.412 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2023-07-07T20:38:29.413Z,1688762309.413 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2023-07-07T20:38:29.415Z,1688762309.415 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2023-07-07T20:38:29.458Z,1688762309.458 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2023-07-07T20:38:29.462Z,1688762309.462 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2023-07-07T20:38:29.627Z,1688762309.627 [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>


2023-07-07T20:38:29.660Z,1688762309.660 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2023-07-07T20:38:29.935Z,1688762309.935 [Default] Stopped
2023-07-07T20:38:29.935Z,1688762309.935 [Default](DEBUG): Aggregate::uninitialize Default
2023-07-07T20:38:29.935Z,1688762309.935 [Default:B.GoToSurface] Stopped
2023-07-07T20:38:29.935Z,1688762309.935 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-07-07T20:38:29.936Z,1688762309.936 [Default:CheckIn] Stopped
2023-07-07T20:38:29.936Z,1688762309.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T20:38:29.936Z,1688762309.936 [Default:CheckIn:C.Wait] Stopped
2023-07-07T20:38:29.936Z,1688762309.936 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T20:38:29.936Z,1688762309.936 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2023-07-07T20:38:29.936Z,1688762309.936 [ballast_and_trim] Running Loop=1
2023-07-07T20:38:29.936Z,1688762309.936 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2023-07-07T20:38:29.936Z,1688762309.936 [ballast_and_trim:Science] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:A] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:B] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:C] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:D] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:E] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:F] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-07-07T20:38:29.937Z,1688762309.937 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-07-07T20:38:29.940Z,1688762309.940 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2023-07-07T20:38:29.941Z,1688762309.941 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2023-07-07T20:38:29.941Z,1688762309.941 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-07-07T20:38:29.941Z,1688762309.941 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-07-07T20:38:29.941Z,1688762309.941 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2023-07-07T20:38:29.941Z,1688762309.941 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2023-07-07T20:38:29.942Z,1688762309.942 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-07-07T20:38:29.942Z,1688762309.942 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-07-07T20:38:29.952Z,1688762309.952 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2023-07-07T20:38:29.952Z,1688762309.952 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2023-07-07T20:38:29.952Z,1688762309.952 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-07-07T20:38:29.952Z,1688762309.952 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-07-07T20:38:29.954Z,1688762309.954 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2023-07-07T20:38:29.954Z,1688762309.954 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2023-07-07T20:38:29.954Z,1688762309.954 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-07-07T20:38:29.954Z,1688762309.954 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-07-07T20:38:29.955Z,1688762309.955 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2023-07-07T20:38:29.959Z,1688762309.959 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2023-07-07T20:38:29.959Z,1688762309.959 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-07-07T20:38:29.959Z,1688762309.959 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-07-07T20:38:29.961Z,1688762309.961 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2023-07-07T20:38:29.961Z,1688762309.961 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2023-07-07T20:38:29.961Z,1688762309.961 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-07-07T20:38:29.961Z,1688762309.961 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-07-07T20:38:29.972Z,1688762309.972 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2023-07-07T20:38:29.972Z,1688762309.972 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2023-07-07T20:38:29.972Z,1688762309.972 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-07-07T20:38:29.972Z,1688762309.972 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-07-07T20:38:29.972Z,1688762309.972 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2023-07-07T20:38:29.987Z,1688762309.987 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2023-07-07T20:38:29.987Z,1688762309.987 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-07-07T20:38:29.987Z,1688762309.987 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-07-07T20:38:29.988Z,1688762309.988 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2023-07-07T20:38:29.988Z,1688762309.988 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2023-07-07T20:38:29.988Z,1688762309.988 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2023-07-07T20:38:29.988Z,1688762309.988 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-07-07T20:38:29.988Z,1688762309.988 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2023-07-07T20:38:29.989Z,1688762309.989 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2023-07-07T20:38:29.989Z,1688762309.989 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2023-07-07T20:38:29.989Z,1688762309.989 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-07-07T20:38:29.989Z,1688762309.989 [ballast_and_trim:Science] Running Loop=1
2023-07-07T20:38:29.990Z,1688762309.990 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2023-07-07T20:38:29.990Z,1688762309.990 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2023-07-07T20:38:29.990Z,1688762309.990 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-07-07T20:38:29.991Z,1688762309.991 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-07-07T20:38:29.991Z,1688762309.991 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2023-07-07T20:38:29.999Z,1688762309.999 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2023-07-07T20:38:29.999Z,1688762309.999 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-07-07T20:38:29.999Z,1688762309.999 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-07-07T20:38:29.999Z,1688762309.999 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2023-07-07T20:38:29.999Z,1688762309.999 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2023-07-07T20:38:29.999Z,1688762309.999 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-07-07T20:38:29.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-07-07T20:38:29.000Z,1688762310.000 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2023-07-07T20:38:29.000Z,1688762310.000 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2023-07-07T20:38:29.000Z,1688762310.000 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-07-07T20:38:29.000Z,1688762310.000 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-07-07T20:38:29.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectOil] Stopped
2023-07-07T20:38:30.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2023-07-07T20:38:30.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-07-07T20:38:30.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-07-07T20:38:30.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2023-07-07T20:38:30.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2023-07-07T20:38:30.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-07-07T20:38:30.000Z,1688762310.000 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:PeakDetectChl] Stopped
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-07-07T20:38:30.001Z,1688762310.001 [ballast_and_trim:Science:Read_Oil] Stopped
2023-07-07T20:38:30.002Z,1688762310.002 [ballast_and_trim:Science:F] Stopped
2023-07-07T20:38:30.002Z,1688762310.002 [ballast_and_trim:Science:E] Running Loop=1
2023-07-07T20:38:30.002Z,1688762310.002 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-07-07T20:38:30.002Z,1688762310.002 [ballast_and_trim:Science:D] Stopped
2023-07-07T20:38:30.002Z,1688762310.002 [ballast_and_trim:Science:C] Running Loop=1
2023-07-07T20:38:30.015Z,1688762310.015 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-07-07T20:38:30.016Z,1688762310.016 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-07-07T20:38:30.016Z,1688762310.016 [ballast_and_trim:Science:B] Running Loop=1
2023-07-07T20:38:30.017Z,1688762310.017 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-07-07T20:38:30.017Z,1688762310.017 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-07-07T20:38:30.018Z,1688762310.018 [ballast_and_trim:Science:A] Stopped
2023-07-07T20:38:30.336Z,1688762310.336 [WetLabsBB2FL](INFO): Powering up
2023-07-07T20:38:30.339Z,1688762310.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2023-07-07T20:38:30.339Z,1688762310.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-07-07T20:38:30.339Z,1688762310.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-07-07T20:38:30.339Z,1688762310.339 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-07-07T20:38:30.340Z,1688762310.340 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-07-07T20:38:30.340Z,1688762310.340 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-07-07T20:38:30.340Z,1688762310.340 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-07-07T20:38:30.340Z,1688762310.340 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2023-07-07T20:38:30.342Z,1688762310.342 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2023-07-07T20:38:30.343Z,1688762310.343 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2023-07-07T20:38:30.343Z,1688762310.343 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-07-07T20:38:30.351Z,1688762310.351 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2023-07-07T20:38:30.351Z,1688762310.351 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2023-07-07T20:38:30.351Z,1688762310.351 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2023-07-07T20:38:30.352Z,1688762310.352 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-07-07T20:38:30.352Z,1688762310.352 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2023-07-07T20:38:30.352Z,1688762310.352 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-07-07T20:38:30.352Z,1688762310.352 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-07-07T20:38:30.352Z,1688762310.352 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-07-07T20:38:30.352Z,1688762310.352 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-07-07T20:38:30.353Z,1688762310.353 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2023-07-07T20:38:30.743Z,1688762310.743 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-07-07T20:38:30.744Z,1688762310.744 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2023-07-07T20:38:30.744Z,1688762310.744 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-07-07T20:38:31.148Z,1688762311.148 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-07-07T20:38:31.149Z,1688762311.149 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2023-07-07T20:38:31.150Z,1688762311.150 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2023-07-07T20:38:31.534Z,1688762311.534 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-07-07T20:38:31.535Z,1688762311.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2023-07-07T20:38:31.535Z,1688762311.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2023-07-07T20:38:31.535Z,1688762311.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2023-07-07T20:38:31.535Z,1688762311.535 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2023-07-07T20:38:33.804Z,1688762313.804 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-07-07T20:40:18.688Z,1688762418.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:40:49.811Z,1688762449.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:41:16.175Z,1688762476.175 [Radio_Surface](INFO): Powering down
2023-07-07T20:41:20.943Z,1688762480.943 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T20:41:21.947Z,1688762481.947 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-07-07T20:42:21.895Z,1688762541.895 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2023-07-07T20:42:21.895Z,1688762541.895 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-07-07T20:42:21.895Z,1688762541.895 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2023-07-07T20:42:21.896Z,1688762541.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-07-07T20:42:21.896Z,1688762541.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2023-07-07T20:42:21.896Z,1688762541.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2023-07-07T20:42:21.896Z,1688762541.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-07-07T20:42:21.896Z,1688762541.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2023-07-07T20:42:21.896Z,1688762541.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-07-07T20:42:21.896Z,1688762541.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2023-07-07T20:42:21.897Z,1688762541.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2023-07-07T20:42:21.897Z,1688762541.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-07-07T20:42:21.897Z,1688762541.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-07-07T20:42:21.897Z,1688762541.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2023-07-07T20:42:21.897Z,1688762541.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-07-07T20:42:21.897Z,1688762541.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2023-07-07T20:42:21.897Z,1688762541.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2023-07-07T20:42:22.312Z,1688762542.312 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.054586 m 
2023-07-07T20:42:22.312Z,1688762542.312 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2023-07-07T20:42:22.312Z,1688762542.312 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2023-07-07T20:42:22.312Z,1688762542.312 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2023-07-07T20:42:22.313Z,1688762542.313 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-07-07T20:42:22.313Z,1688762542.313 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-07-07T20:42:26.231Z,1688762546.231 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.771179
2023-07-07T20:52:23.073Z,1688763143.073 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2023-07-07T20:52:23.073Z,1688763143.073 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2023-07-07T20:52:23.073Z,1688763143.073 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T20:52:23.074Z,1688763143.074 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2023-07-07T20:52:23.471Z,1688763143.471 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 6.584493 m 
2023-07-07T20:52:23.471Z,1688763143.471 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2023-07-07T20:52:23.471Z,1688763143.471 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2023-07-07T20:52:23.471Z,1688763143.471 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2023-07-07T21:17:34.081Z,1688764654.081 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 25.17 minutes.
2023-07-07T21:17:34.082Z,1688764654.082 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 212.769452 +/- 14.997803 cc (conf. level 99.90%, sigma: 28.198081 cc).
2023-07-07T21:17:34.083Z,1688764654.083 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: -9.553429 +/- 0.196982 mm (conf. level 99.90%, sigma: 0.370354 mm).
2023-07-07T21:17:34.084Z,1688764654.084 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 212.769452 cc buoyancy neutral and -9.553429 mm mass default
2023-07-07T21:17:34.089Z,1688764654.089 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-07-07T21:17:34.090Z,1688764654.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-07-07T21:17:34.090Z,1688764654.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2023-07-07T21:17:34.090Z,1688764654.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-07-07T21:17:34.090Z,1688764654.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-07-07T21:17:34.090Z,1688764654.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-07-07T21:17:34.090Z,1688764654.090 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): RudderServo.offsetAngle=0 degree;

2023-07-07T21:17:34.091Z,1688764654.091 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2023-07-07T21:17:34.091Z,1688764654.091 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=212.769452 cubic_centimeter;

2023-07-07T21:17:34.107Z,1688764654.107 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=-9.553429 millimeter;

2023-07-07T21:17:34.108Z,1688764654.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2023-07-07T21:17:34.108Z,1688764654.108 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2023-07-07T21:17:34.109Z,1688764654.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-07-07T21:17:34.109Z,1688764654.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2023-07-07T21:17:34.109Z,1688764654.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-07-07T21:17:34.109Z,1688764654.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2023-07-07T21:17:34.109Z,1688764654.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2023-07-07T21:17:34.109Z,1688764654.109 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2023-07-07T21:17:34.110Z,1688764654.110 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-07-07T21:17:34.110Z,1688764654.110 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2023-07-07T21:17:34.110Z,1688764654.110 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-07-07T21:17:34.111Z,1688764654.111 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2023-07-07T21:17:34.111Z,1688764654.111 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2023-07-07T21:17:34.111Z,1688764654.111 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2023-07-07T21:17:34.111Z,1688764654.111 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2023-07-07T21:17:34.111Z,1688764654.111 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2023-07-07T21:17:34.486Z,1688764654.486 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-07-07T21:17:34.486Z,1688764654.486 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2023-07-07T21:17:34.486Z,1688764654.486 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-07-07T21:17:34.486Z,1688764654.486 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2023-07-07T21:17:34.486Z,1688764654.486 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2023-07-07T21:17:34.487Z,1688764654.487 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-07-07T21:17:34.487Z,1688764654.487 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2023-07-07T21:17:34.487Z,1688764654.487 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2023-07-07T21:17:34.487Z,1688764654.487 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2023-07-07T21:17:34.908Z,1688764654.908 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2023-07-07T21:17:34.908Z,1688764654.908 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2023-07-07T21:17:34.908Z,1688764654.908 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2023-07-07T21:17:34.908Z,1688764654.908 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:RunBallastAndTrim] Stopped
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:Float_Up] Running Loop=1
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2023-07-07T21:17:34.909Z,1688764654.909 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:17:35.292Z,1688764655.292 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2023-07-07T21:17:39.314Z,1688764659.314 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:17:54.668Z,1688764674.668 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:18:10.833Z,1688764690.833 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:18:26.185Z,1688764706.185 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:18:41.538Z,1688764721.538 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:18:57.693Z,1688764737.693 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:19:13.449Z,1688764753.449 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:19:28.397Z,1688764768.397 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:19:37.704Z,1688764777.704 [ballast_and_trim:Float_Up] Stopped
2023-07-07T21:19:37.704Z,1688764777.704 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2023-07-07T21:19:37.704Z,1688764777.704 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2023-07-07T21:19:37.704Z,1688764777.704 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-07-07T21:19:37.704Z,1688764777.704 [ballast_and_trim:Float_Up:B.Wait] Stopped
2023-07-07T21:19:37.704Z,1688764777.704 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T21:19:37.705Z,1688764777.705 [ballast_and_trim](INFO): Completed ballast_and_trim
2023-07-07T21:19:37.706Z,1688764777.706 [MissionManager](INFO): ballast_and_trim is completed.
2023-07-07T21:19:37.706Z,1688764777.706 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim] Stopped
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim:Science] Stopped
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim:Science:B] Stopped
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim:Science:C] Stopped
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim:Science:E] Stopped
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim:Science:OceanCurrent] Stopped
2023-07-07T21:19:37.706Z,1688764777.706 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2023-07-07T21:19:37.707Z,1688764777.707 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2023-07-07T21:19:38.116Z,1688764778.116 [MissionManager](IMPORTANT): Started mission Default
2023-07-07T21:19:38.117Z,1688764778.117 [Default] Running Loop=1
2023-07-07T21:19:38.117Z,1688764778.117 [Default](DEBUG): Aggregate::initialize Default
2023-07-07T21:19:38.117Z,1688764778.117 [Default:B.GoToSurface] Running Loop=1
2023-07-07T21:19:38.117Z,1688764778.117 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-07-07T21:19:38.117Z,1688764778.117 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-07-07T21:19:38.118Z,1688764778.118 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-07-07T21:19:38.118Z,1688764778.118 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-07-07T21:19:38.118Z,1688764778.118 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-07-07T21:19:38.118Z,1688764778.118 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-07-07T21:19:38.119Z,1688764778.119 [Default:A.Wait] Running Loop=1
2023-07-07T21:19:38.119Z,1688764778.119 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:19:38.195Z,1688764778.195 [WetLabsBB2FL](INFO): Powering down
2023-07-07T21:19:38.267Z,1688764778.267 [Radio_Surface](INFO): Powering up
2023-07-07T21:19:43.739Z,1688764783.739 [DataOverHttps](INFO): Radio surface powered ON.
2023-07-07T21:19:43.751Z,1688764783.751 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-07T21:19:51.469Z,1688764791.469 [Default:A.Wait](INFO): Done Waiting.
2023-07-07T21:19:51.469Z,1688764791.469 [Default:A.Wait] Stopped
2023-07-07T21:19:51.469Z,1688764791.469 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T21:19:51.846Z,1688764791.846 [Default:CheckIn] Running Loop=1
2023-07-07T21:19:51.846Z,1688764791.846 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T21:19:51.846Z,1688764791.846 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T21:19:58.294Z,1688764798.294 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-07-07T21:19:58.300Z,1688764798.300 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5.
2023-07-07T21:19:58.302Z,1688764798.302 [BPC1](INFO): Received data from all battery sticks.
2023-07-07T21:20:13.860Z,1688764813.860 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T21:20:44.983Z,1688764844.983 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T21:21:16.107Z,1688764876.107 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T21:21:47.243Z,1688764907.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T21:22:18.367Z,1688764938.367 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T21:22:49.499Z,1688764969.499 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T21:23:18.099Z,1688764998.099 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003122
2023-07-07T21:24:52.071Z,1688765092.071 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-07-07T21:19:51.8Z
2023-07-07T21:24:52.071Z,1688765092.071 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T21:24:52.072Z,1688765092.072 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T21:24:56.552Z,1688765096.552 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-07-07T21:24:59.934Z,1688765099.934 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20230707T200522/Courier0016.lzma
2023-07-07T21:25:00.939Z,1688765100.939 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0016.lzma.bak
2023-07-07T21:25:00.939Z,1688765100.939 [DataOverHttps](INFO): SBD MOMSN=18455176
2023-07-07T21:25:16.617Z,1688765116.617 [DataOverHttps](INFO): Sending 1089 bytes from file Logs/20230707T200522/Express0017.lzma
2023-07-07T21:25:17.617Z,1688765117.617 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0017.lzma.bak
2023-07-07T21:25:17.617Z,1688765117.617 [DataOverHttps](INFO): SBD MOMSN=18455178
2023-07-07T21:25:27.260Z,1688765127.260 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-07T21:25:33.438Z,1688765133.438 [DataOverHttps](INFO): Sending 41 bytes from file Logs/20230707T200522/Express0018.lzma
2023-07-07T21:25:34.441Z,1688765134.441 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0018.lzma.bak
2023-07-07T21:25:34.441Z,1688765134.441 [DataOverHttps](INFO): SBD MOMSN=18455199
2023-07-07T21:25:35.758Z,1688765135.758 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T21:25:35.759Z,1688765135.759 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T21:25:35.759Z,1688765135.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:30:36.338Z,1688765436.338 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T21:30:36.338Z,1688765436.338 [Default:CheckIn:C.Wait] Stopped
2023-07-07T21:30:36.338Z,1688765436.338 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T21:30:36.339Z,1688765436.339 [Default:CheckIn:D] Running Loop=1
2023-07-07T21:30:36.750Z,1688765436.750 [Default:CheckIn:D] Stopped
2023-07-07T21:30:36.750Z,1688765436.750 [Default:CheckIn:E] Running Loop=1
2023-07-07T21:30:37.157Z,1688765437.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.977217 min 
2023-07-07T21:30:37.157Z,1688765437.157 [Default:CheckIn:E] Stopped
2023-07-07T21:30:37.157Z,1688765437.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T21:30:37.157Z,1688765437.157 [Default:CheckIn] Stopped
2023-07-07T21:30:37.157Z,1688765437.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T21:30:37.158Z,1688765437.158 [Default:CheckIn](INFO): Running loop #2
2023-07-07T21:30:37.158Z,1688765437.158 [Default:CheckIn] Running Loop=2
2023-07-07T21:30:37.158Z,1688765437.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T21:30:37.158Z,1688765437.158 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T21:30:38.752Z,1688765438.752 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-07-07T21:30:38.752Z,1688765438.752 [NAL9602] Data Fault, FailCount= 3
2023-07-07T21:30:38.752Z,1688765438.752 [NAL9602](ERROR): Data Fault
2023-07-07T21:30:38.793Z,1688765438.793 [CBIT](ERROR): Data Fault in component: NAL9602
2023-07-07T21:30:39.160Z,1688765439.160 [NAL9602](INFO): Powering down
2023-07-07T21:30:39.986Z,1688765439.986 [CBIT](INFO): Clearing failed state for component NAL9602
2023-07-07T21:30:39.987Z,1688765439.987 [NAL9602] No Fault, FailCount= 3
2023-07-07T21:31:09.466Z,1688765469.466 [NAL9602](INFO): Powering up NAL9602
2023-07-07T21:31:20.372Z,1688765480.372 [NAL9602](INFO): NAL9602 initialized
2023-07-07T21:35:37.400Z,1688765737.400 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-07-07T21:30:37.2Z
2023-07-07T21:35:37.400Z,1688765737.400 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T21:35:37.400Z,1688765737.400 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T21:35:44.966Z,1688765744.966 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230707T200522/Courier0021.lzma
2023-07-07T21:35:45.969Z,1688765745.969 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0021.lzma.bak
2023-07-07T21:35:45.969Z,1688765745.969 [DataOverHttps](INFO): SBD MOMSN=18455209
2023-07-07T21:36:01.763Z,1688765761.763 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230707T200522/Express0023.lzma
2023-07-07T21:36:02.765Z,1688765762.765 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0023.lzma.bak
2023-07-07T21:36:02.765Z,1688765762.765 [DataOverHttps](INFO): SBD MOMSN=18455211
2023-07-07T21:36:04.079Z,1688765764.079 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T21:36:04.079Z,1688765764.079 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T21:36:04.079Z,1688765764.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:36:23.845Z,1688765783.845 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-07-07T21:36:54.557Z,1688765814.557 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-07T21:41:04.653Z,1688766064.653 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T21:41:04.653Z,1688766064.653 [Default:CheckIn:C.Wait] Stopped
2023-07-07T21:41:04.654Z,1688766064.654 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T21:41:04.654Z,1688766064.654 [Default:CheckIn:D] Running Loop=1
2023-07-07T21:41:05.053Z,1688766065.053 [Default:CheckIn:D] Stopped
2023-07-07T21:41:05.053Z,1688766065.053 [Default:CheckIn:E] Running Loop=1
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.448930 min 
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn:E] Stopped
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn] Stopped
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn](INFO): Running loop #3
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn] Running Loop=3
2023-07-07T21:41:05.468Z,1688766065.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T21:41:05.469Z,1688766065.469 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T21:41:07.470Z,1688766067.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214106.00,A,3648.16403,N,12147.28455,W,0.136,245.11,070723,,,A*7C
2023-07-07T21:41:07.472Z,1688766067.472 [NAL9602](INFO): GPS fix at 20230707T214106: (36.802734, -121.788076)
2023-07-07T21:41:07.505Z,1688766067.505 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T21:41:07.505Z,1688766067.505 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T21:41:14.779Z,1688766074.779 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230707T200522/Courier0025.lzma
2023-07-07T21:41:15.781Z,1688766075.781 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0025.lzma.bak
2023-07-07T21:41:15.781Z,1688766075.781 [DataOverHttps](INFO): SBD MOMSN=18455216
2023-07-07T21:41:31.535Z,1688766091.535 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20230707T200522/Express0026.lzma
2023-07-07T21:41:32.545Z,1688766092.545 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0026.lzma.bak
2023-07-07T21:41:32.545Z,1688766092.545 [DataOverHttps](INFO): SBD MOMSN=18455219
2023-07-07T21:41:33.756Z,1688766093.756 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T21:41:33.756Z,1688766093.756 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T21:41:33.756Z,1688766093.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:41:38.178Z,1688766098.178 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-07-07T21:41:38.251Z,1688766098.251 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-07-07T21:44:34.732Z,1688766274.732 [NAL9602](INFO): SBD MO Status=2, MOMSN=43899, MT Status=2, MTMSN=0
2023-07-07T21:44:34.732Z,1688766274.732 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-07T21:45:13.551Z,1688766313.551 [NAL9602](INFO): SBD MO Status=2, MOMSN=43899, MT Status=2, MTMSN=0
2023-07-07T21:45:13.551Z,1688766313.551 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-07T21:45:59.577Z,1688766359.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=43899, MT Status=2, MTMSN=0
2023-07-07T21:45:59.577Z,1688766359.577 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-07T21:46:09.276Z,1688766369.276 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-07-07T21:46:34.331Z,1688766394.331 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T21:46:34.331Z,1688766394.331 [Default:CheckIn:C.Wait] Stopped
2023-07-07T21:46:34.331Z,1688766394.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T21:46:34.331Z,1688766394.331 [Default:CheckIn:D] Running Loop=1
2023-07-07T21:46:34.742Z,1688766394.742 [Default:CheckIn:D] Stopped
2023-07-07T21:46:34.742Z,1688766394.742 [Default:CheckIn:E] Running Loop=1
2023-07-07T21:46:35.140Z,1688766395.140 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.943746 min 
2023-07-07T21:46:35.140Z,1688766395.140 [Default:CheckIn:E] Stopped
2023-07-07T21:46:35.140Z,1688766395.140 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T21:46:35.140Z,1688766395.140 [Default:CheckIn] Stopped
2023-07-07T21:46:35.140Z,1688766395.140 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T21:46:35.140Z,1688766395.140 [Default:CheckIn](INFO): Running loop #4
2023-07-07T21:46:35.140Z,1688766395.140 [Default:CheckIn] Running Loop=4
2023-07-07T21:46:35.141Z,1688766395.141 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T21:46:35.141Z,1688766395.141 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T21:46:37.154Z,1688766397.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214636.00,A,3648.16529,N,12147.27771,W,0.136,245.11,070723,,,A*7B
2023-07-07T21:46:37.165Z,1688766397.165 [NAL9602](INFO): GPS fix at 20230707T214636: (36.802755, -121.787962)
2023-07-07T21:46:37.175Z,1688766397.175 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T21:46:37.175Z,1688766397.175 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T21:46:44.766Z,1688766404.766 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230707T200522/Courier0028.lzma
2023-07-07T21:46:45.769Z,1688766405.769 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0028.lzma.bak
2023-07-07T21:46:45.769Z,1688766405.769 [DataOverHttps](INFO): SBD MOMSN=18455232
2023-07-07T21:47:04.078Z,1688766424.078 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20230707T200522/Express0029.lzma
2023-07-07T21:47:05.077Z,1688766425.077 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0029.lzma.bak
2023-07-07T21:47:05.077Z,1688766425.077 [DataOverHttps](INFO): SBD MOMSN=18455235
2023-07-07T21:47:06.294Z,1688766426.294 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T21:47:06.295Z,1688766426.295 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T21:47:06.295Z,1688766426.295 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:47:09.508Z,1688766429.508 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-07T21:52:06.866Z,1688766726.866 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T21:52:06.866Z,1688766726.866 [Default:CheckIn:C.Wait] Stopped
2023-07-07T21:52:06.866Z,1688766726.866 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T21:52:06.866Z,1688766726.866 [Default:CheckIn:D] Running Loop=1
2023-07-07T21:52:07.271Z,1688766727.271 [Default:CheckIn:D] Stopped
2023-07-07T21:52:07.271Z,1688766727.271 [Default:CheckIn:E] Running Loop=1
2023-07-07T21:52:07.694Z,1688766727.694 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.485885 min 
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn:E] Stopped
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn] Stopped
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn](INFO): Running loop #5
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn] Running Loop=5
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T21:52:07.695Z,1688766727.695 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T21:52:09.682Z,1688766729.682 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215208.00,A,3648.16529,N,12147.27837,W,0.700,0.00,070723,,,A*7E
2023-07-07T21:52:09.685Z,1688766729.685 [NAL9602](INFO): GPS fix at 20230707T215208: (36.802755, -121.787973)
2023-07-07T21:52:09.698Z,1688766729.698 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T21:52:09.699Z,1688766729.699 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T21:52:17.434Z,1688766737.434 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230707T200522/Courier0031.lzma
2023-07-07T21:52:18.437Z,1688766738.437 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0031.lzma.bak
2023-07-07T21:52:18.437Z,1688766738.437 [DataOverHttps](INFO): SBD MOMSN=18455239
2023-07-07T21:52:36.778Z,1688766756.778 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230707T200522/Express0032.lzma
2023-07-07T21:52:37.781Z,1688766757.781 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0032.lzma.bak
2023-07-07T21:52:37.781Z,1688766757.781 [DataOverHttps](INFO): SBD MOMSN=18455242
2023-07-07T21:52:39.244Z,1688766759.244 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T21:52:39.244Z,1688766759.244 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T21:52:39.244Z,1688766759.244 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:52:40.439Z,1688766760.439 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-07-07T21:52:40.515Z,1688766760.515 [NAL9602](ERROR): received: 
+CSQ:0

OK899, 2, 0, 0, 0

OK
2023-07-07T21:57:12.380Z,1688767032.380 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-07-07T21:57:39.857Z,1688767059.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T21:57:39.857Z,1688767059.857 [Default:CheckIn:C.Wait] Stopped
2023-07-07T21:57:39.857Z,1688767059.857 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T21:57:39.858Z,1688767059.858 [Default:CheckIn:D] Running Loop=1
2023-07-07T21:57:40.270Z,1688767060.270 [Default:CheckIn:D] Stopped
2023-07-07T21:57:40.271Z,1688767060.271 [Default:CheckIn:E] Running Loop=1
2023-07-07T21:57:40.671Z,1688767060.671 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.035893 min 
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn:E] Stopped
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn] Stopped
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn](INFO): Running loop #6
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn] Running Loop=6
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T21:57:40.672Z,1688767060.672 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T21:57:42.678Z,1688767062.678 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215741.00,A,3648.16645,N,12147.27809,W,0.505,0.00,070723,,,A*75
2023-07-07T21:57:42.681Z,1688767062.681 [NAL9602](INFO): GPS fix at 20230707T215741: (36.802774, -121.787968)
2023-07-07T21:57:42.691Z,1688767062.691 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T21:57:42.691Z,1688767062.691 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T21:57:50.470Z,1688767070.470 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230707T200522/Courier0034.lzma
2023-07-07T21:57:51.473Z,1688767071.473 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0034.lzma.bak
2023-07-07T21:57:51.473Z,1688767071.473 [DataOverHttps](INFO): SBD MOMSN=18455251
2023-07-07T21:58:07.195Z,1688767087.195 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230707T200522/Express0035.lzma
2023-07-07T21:58:08.197Z,1688767088.197 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0035.lzma.bak
2023-07-07T21:58:08.198Z,1688767088.198 [DataOverHttps](INFO): SBD MOMSN=18455254
2023-07-07T21:58:09.355Z,1688767089.355 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T21:58:09.355Z,1688767089.355 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T21:58:09.355Z,1688767089.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T21:58:15.396Z,1688767095.396 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-07T22:01:12.555Z,1688767272.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:01:43.679Z,1688767303.679 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:02:14.819Z,1688767334.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:02:45.943Z,1688767365.943 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:02:54.980Z,1688767374.980 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.191650
2023-07-07T22:03:09.937Z,1688767389.937 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-07T22:03:09.937Z,1688767389.937 [Default:CheckIn:C.Wait] Stopped
2023-07-07T22:03:09.937Z,1688767389.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T22:03:09.937Z,1688767389.937 [Default:CheckIn:D] Running Loop=1
2023-07-07T22:03:10.338Z,1688767390.338 [Default:CheckIn:D] Stopped
2023-07-07T22:03:10.338Z,1688767390.338 [Default:CheckIn:E] Running Loop=1
2023-07-07T22:03:10.742Z,1688767390.742 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.537012 min 
2023-07-07T22:03:10.742Z,1688767390.742 [Default:CheckIn:E] Stopped
2023-07-07T22:03:10.742Z,1688767390.742 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-07T22:03:10.742Z,1688767390.742 [Default:CheckIn] Stopped
2023-07-07T22:03:10.742Z,1688767390.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T22:03:10.743Z,1688767390.743 [Default:CheckIn](INFO): Running loop #7
2023-07-07T22:03:10.743Z,1688767390.743 [Default:CheckIn] Running Loop=7
2023-07-07T22:03:10.743Z,1688767390.743 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-07T22:03:10.743Z,1688767390.743 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-07T22:03:47.188Z,1688767427.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:04:18.311Z,1688767458.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:04:49.443Z,1688767489.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:05:20.567Z,1688767520.567 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:05:51.691Z,1688767551.691 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:06:22.816Z,1688767582.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:06:53.940Z,1688767613.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:07:25.087Z,1688767645.087 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:07:56.211Z,1688767676.211 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:08:10.940Z,1688767690.940 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-07-07T22:03:10.7Z
2023-07-07T22:08:10.940Z,1688767690.940 [Default:CheckIn:Read_GPS] Stopped
2023-07-07T22:08:10.941Z,1688767690.941 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-07T22:08:15.345Z,1688767695.345 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-07-07T22:08:27.335Z,1688767707.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:08:58.459Z,1688767738.459 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:09:29.603Z,1688767769.603 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:10:00.727Z,1688767800.727 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-07T22:10:29.420Z,1688767829.420 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003569
2023-07-07T22:10:36.711Z,1688767836.711 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230707T200522/Courier0037.lzma
2023-07-07T22:10:37.713Z,1688767837.713 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Courier0037.lzma.bak
2023-07-07T22:10:37.713Z,1688767837.713 [DataOverHttps](INFO): SBD MOMSN=18455265
2023-07-07T22:11:01.442Z,1688767861.442 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230707T200522/Express0038.lzma
2023-07-07T22:11:01.844Z,1688767861.844 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-07T22:11:02.445Z,1688767862.445 [DataOverHttps](INFO): Moved sent file to Logs/20230707T200522/Express0038.lzma.bak
2023-07-07T22:11:02.445Z,1688767862.445 [DataOverHttps](INFO): SBD MOMSN=18455267
2023-07-07T22:11:03.873Z,1688767863.873 [Default:CheckIn:Read_Iridium] Stopped
2023-07-07T22:11:03.874Z,1688767863.874 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-07T22:11:03.874Z,1688767863.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-07T22:11:30.436Z,1688767890.436 [CommandExec](IMPORTANT): got command restart application
2023-07-07T22:11:31.511Z,1688767891.511 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-07-07T22:11:31.511Z,1688767891.511 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:31.511Z,1688767891.511 [CommandExec](INFO): Uninitializing the command executive.
2023-07-07T22:11:31.511Z,1688767891.511 [CommandExec](INFO): Uninitializing the command scheduler.
2023-07-07T22:11:31.512Z,1688767891.512 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:31.543Z,1688767891.543 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-07-07T22:11:31.543Z,1688767891.543 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-07-07T22:11:31.543Z,1688767891.543 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:31.544Z,1688767891.544 [NavChartDb](INFO): Join timeout helper Thread ID is 2828
2023-07-07T22:11:31.835Z,1688767891.835 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:31.835Z,1688767891.835 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:31.847Z,1688767891.847 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-07-07T22:11:31.847Z,1688767891.847 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:31.848Z,1688767891.848 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2829
2023-07-07T22:11:31.871Z,1688767891.871 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:31.871Z,1688767891.871 [WetLabsBB2FL](INFO): Powering down
2023-07-07T22:11:31.872Z,1688767891.872 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:31.887Z,1688767891.887 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-07-07T22:11:31.887Z,1688767891.887 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:31.888Z,1688767891.888 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2830
2023-07-07T22:11:32.371Z,1688767892.371 [CTD_Seabird](INFO): Powering down
2023-07-07T22:11:32.383Z,1688767892.383 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:32.383Z,1688767892.383 [CTD_Seabird](INFO): Powering down
2023-07-07T22:11:32.395Z,1688767892.395 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:32.395Z,1688767892.395 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-07-07T22:11:32.395Z,1688767892.395 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:32.396Z,1688767892.396 [Radio_Surface](INFO): Join timeout helper Thread ID is 2831
2023-07-07T22:11:32.771Z,1688767892.771 [Radio_Surface](INFO): Powering down
2023-07-07T22:11:32.772Z,1688767892.772 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:32.772Z,1688767892.772 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:32.787Z,1688767892.787 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-07-07T22:11:32.787Z,1688767892.787 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:32.787Z,1688767892.787 [Onboard](INFO): Join timeout helper Thread ID is 2832
2023-07-07T22:11:32.895Z,1688767892.895 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-07-07T22:11:33.755Z,1688767893.755 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:33.755Z,1688767893.755 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.764Z,1688767893.764 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-07-07T22:11:33.764Z,1688767893.764 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.765Z,1688767893.765 [DataOverHttps](INFO): Join timeout helper Thread ID is 2833
2023-07-07T22:11:33.899Z,1688767893.899 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:33.899Z,1688767893.899 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.908Z,1688767893.908 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-07-07T22:11:33.908Z,1688767893.908 [logger ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.908Z,1688767893.908 [logger](INFO): Join timeout helper Thread ID is 2834
2023-07-07T22:11:33.911Z,1688767893.911 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:33.911Z,1688767893.911 [logger ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.928Z,1688767893.928 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-07-07T22:11:33.928Z,1688767893.928 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.928Z,1688767893.928 [CommandLine](INFO): Join timeout helper Thread ID is 2835
2023-07-07T22:11:33.983Z,1688767893.983 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:33.983Z,1688767893.983 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.988Z,1688767893.988 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-07-07T22:11:33.988Z,1688767893.988 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.988Z,1688767893.988 [CommandExec](INFO): Join timeout helper Thread ID is 2836
2023-07-07T22:11:33.989Z,1688767893.989 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-07-07T22:11:33.989Z,1688767893.989 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:33.990Z,1688767893.990 [controlThread](INFO): Join timeout helper Thread ID is 2837
2023-07-07T22:11:34.147Z,1688767894.147 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-07-07T22:11:34.147Z,1688767894.147 [controlThread](DEBUG): Uninitializing ControlThread
2023-07-07T22:11:34.148Z,1688767894.148 [AHRS_M2](INFO): Powering down
2023-07-07T22:11:34.220Z,1688767894.220 [NAL9602](INFO): Powering down
2023-07-07T22:11:34.222Z,1688767894.222 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-07-07T22:11:34.223Z,1688767894.223 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-07-07T22:11:34.224Z,1688767894.224 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-07-07T22:11:34.224Z,1688767894.224 [MissionManager](INFO): Uninitializing Mission Default
2023-07-07T22:11:34.224Z,1688767894.224 [Default] Stopped
2023-07-07T22:11:34.225Z,1688767894.225 [Default](DEBUG): Aggregate::uninitialize Default
2023-07-07T22:11:34.225Z,1688767894.225 [Default:B.GoToSurface] Stopped
2023-07-07T22:11:34.225Z,1688767894.225 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-07-07T22:11:34.225Z,1688767894.225 [Default:CheckIn] Stopped
2023-07-07T22:11:34.225Z,1688767894.225 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-07T22:11:34.225Z,1688767894.225 [Default:CheckIn:C.Wait] Stopped
2023-07-07T22:11:34.225Z,1688767894.225 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-07T22:11:34.228Z,1688767894.228 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-07-07T22:11:34.228Z,1688767894.228 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-07-07T22:11:34.228Z,1688767894.228 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-07-07T22:11:34.229Z,1688767894.229 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-07-07T22:11:34.229Z,1688767894.229 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-07-07T22:11:34.229Z,1688767894.229 [BuoyancyServo](INFO): Powering down
2023-07-07T22:11:34.243Z,1688767894.243 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-07-07T22:11:34.243Z,1688767894.243 [ElevatorServo](INFO): Powering down
2023-07-07T22:11:34.244Z,1688767894.244 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-07-07T22:11:34.244Z,1688767894.244 [MassServo](INFO): Powering down
2023-07-07T22:11:34.245Z,1688767894.245 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-07-07T22:11:34.245Z,1688767894.245 [RudderServo](INFO): Powering down
2023-07-07T22:11:34.246Z,1688767894.246 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-07-07T22:11:34.246Z,1688767894.246 [ThrusterServo](INFO): Powering down
2023-07-07T22:11:34.247Z,1688767894.247 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-07-07T22:11:34.247Z,1688767894.247 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-07-07T22:11:34.248Z,1688767894.248 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-07-07T22:11:34.248Z,1688767894.248 [CBIT](DEBUG): Powering off loads.
2023-07-07T22:11:34.259Z,1688767894.259 [CBIT](DEBUG): Disabling WDT.
2023-07-07T22:11:34.271Z,1688767894.271 [CBIT](DEBUG): Opening all GF detection circuits.
2023-07-07T22:11:34.272Z,1688767894.272 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:34.325Z,1688767894.325 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:34.327Z,1688767894.327 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:34.334Z,1688767894.334 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:34.406Z,1688767894.406 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:34.409Z,1688767894.409 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:34.449Z,1688767894.449 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-07-07T22:11:34.521Z,1688767894.521 [logger ThreadHandler](INFO): Thread cancelled.