2023-05-23T18:06:45.990Z,1684865205.990 [Supervisor](DEBUG): Initializing supervisor.
2023-05-23T18:06:45.994Z,1684865205.994 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-05-23T18:06:45.995Z,1684865205.995 [SyncHandler](INFO): Protected caller Thread ID is 841
2023-05-23T18:06:45.995Z,1684865205.995 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-05-23T18:06:45.996Z,1684865205.996 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-05-23T18:06:45.996Z,1684865205.996 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 842
2023-05-23T18:06:46.000Z,1684865206.000 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-05-23T18:06:46.018Z,1684865206.018 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-05-23T18:06:46.019Z,1684865206.019 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-05-23T18:06:46.019Z,1684865206.019 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 843
2023-05-23T18:06:46.023Z,1684865206.023 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-05-23T18:06:46.024Z,1684865206.024 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-05-23T18:06:46.025Z,1684865206.025 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 844
2023-05-23T18:06:46.027Z,1684865206.027 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-05-23T18:06:46.028Z,1684865206.028 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-05-23T18:06:46.028Z,1684865206.028 [logger ThreadHandler](INFO): Protected caller Thread ID is 845
2023-05-23T18:06:46.031Z,1684865206.031 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-05-23T18:06:46.032Z,1684865206.032 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-05-23T18:06:46.036Z,1684865206.036 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-05-23T18:06:46.133Z,1684865206.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-05-23T18:06:46.135Z,1684865206.135 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-05-23T18:06:46.830Z,1684865206.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-05-23T18:06:46.832Z,1684865206.832 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-05-23T18:06:47.061Z,1684865207.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-05-23T18:06:47.062Z,1684865207.062 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-05-23T18:06:47.143Z,1684865207.143 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-05-23T18:06:47.273Z,1684865207.273 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-05-23T18:06:47.274Z,1684865207.274 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-05-23T18:06:47.356Z,1684865207.356 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-05-23T18:06:47.464Z,1684865207.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-05-23T18:06:47.465Z,1684865207.465 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-05-23T18:06:47.862Z,1684865207.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-05-23T18:06:47.862Z,1684865207.862 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-05-23T18:06:48.349Z,1684865208.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-05-23T18:06:48.350Z,1684865208.351 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-05-23T18:06:48.575Z,1684865208.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-05-23T18:06:48.575Z,1684865208.575 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-05-23T18:06:49.151Z,1684865209.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-05-23T18:06:49.302Z,1684865209.302 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-05-23T18:06:50.104Z,1684865210.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-05-23T18:06:50.105Z,1684865210.105 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-05-23T18:06:50.390Z,1684865210.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-05-23T18:06:50.391Z,1684865210.391 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-05-23T18:06:50.605Z,1684865210.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-05-23T18:06:50.605Z,1684865210.605 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-05-23T18:06:50.978Z,1684865210.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-05-23T18:06:50.980Z,1684865210.980 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-05-23T18:06:51.327Z,1684865211.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-05-23T18:06:51.329Z,1684865211.329 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-05-23T18:06:51.331Z,1684865211.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-05-23T18:06:51.415Z,1684865211.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-05-23T18:06:51.572Z,1684865211.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-05-23T18:06:51.674Z,1684865211.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-05-23T18:06:51.760Z,1684865211.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-05-23T18:06:51.854Z,1684865211.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-05-23T18:06:52.036Z,1684865212.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-05-23T18:06:52.299Z,1684865212.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-05-23T18:06:52.299Z,1684865212.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-05-23T18:06:52.395Z,1684865212.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-05-23T18:06:52.495Z,1684865212.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-05-23T18:06:52.636Z,1684865212.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-05-23T18:06:52.738Z,1684865212.738 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-05-23T18:06:52.748Z,1684865212.748 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-05-23T18:06:52.871Z,1684865212.871 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-05-23T18:06:52.873Z,1684865212.873 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-05-23T18:06:52.939Z,1684865212.939 [VerticalControl](DEBUG): Construct VerticalControl.
2023-05-23T18:06:52.999Z,1684865212.999 [VerticalControl] Loaded
2023-05-23T18:06:52.999Z,1684865212.999 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-05-23T18:06:53.002Z,1684865213.002 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-05-23T18:06:53.043Z,1684865213.043 [HorizontalControl] Loaded
2023-05-23T18:06:53.043Z,1684865213.043 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-05-23T18:06:53.045Z,1684865213.045 [SpeedControl](DEBUG): Construct SpeedControl.
2023-05-23T18:06:53.049Z,1684865213.049 [SpeedControl] Loaded
2023-05-23T18:06:53.049Z,1684865213.049 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-05-23T18:06:53.051Z,1684865213.051 [LoopControl](DEBUG): Construct LoopControl.
2023-05-23T18:06:53.052Z,1684865213.052 [LoopControl] Loaded
2023-05-23T18:06:53.052Z,1684865213.052 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-05-23T18:06:53.053Z,1684865213.053 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-05-23T18:06:53.054Z,1684865213.054 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-05-23T18:06:53.122Z,1684865213.122 [DepthRateCalculator] Loaded
2023-05-23T18:06:53.123Z,1684865213.123 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-05-23T18:06:53.127Z,1684865213.127 [PitchRateCalculator] Loaded
2023-05-23T18:06:53.128Z,1684865213.128 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-05-23T18:06:53.137Z,1684865213.137 [SpeedCalculator] Loaded
2023-05-23T18:06:53.138Z,1684865213.138 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-05-23T18:06:53.142Z,1684865213.142 [YawRateCalculator] Loaded
2023-05-23T18:06:53.142Z,1684865213.142 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-05-23T18:06:53.160Z,1684865213.160 [ElevatorOffsetCalculator] Loaded
2023-05-23T18:06:53.160Z,1684865213.160 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-05-23T18:06:53.161Z,1684865213.161 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-05-23T18:06:53.161Z,1684865213.161 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-05-23T18:06:53.204Z,1684865213.204 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-05-23T18:06:53.205Z,1684865213.205 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-05-23T18:06:53.286Z,1684865213.286 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-05-23T18:06:53.287Z,1684865213.287 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-05-23T18:06:53.641Z,1684865213.641 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-05-23T18:06:53.641Z,1684865213.641 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-05-23T18:06:53.821Z,1684865213.821 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-05-23T18:06:53.821Z,1684865213.821 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-05-23T18:06:54.580Z,1684865214.580 [AHRS_M2] Loaded
2023-05-23T18:06:54.580Z,1684865214.580 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-05-23T18:06:54.963Z,1684865214.963 [BackseatComponent] Loaded
2023-05-23T18:06:54.963Z,1684865214.963 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-05-23T18:06:54.964Z,1684865214.964 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409824E0
2023-05-23T18:06:54.964Z,1684865214.964 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 927
2023-05-23T18:06:54.967Z,1684865214.967 [LcmUniversalReporter] Loaded
2023-05-23T18:06:54.967Z,1684865214.967 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-05-23T18:06:56.082Z,1684865216.082 [BPC1] Loaded
2023-05-23T18:06:56.082Z,1684865216.082 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-05-23T18:06:56.223Z,1684865216.223 [DataOverHttps] Loaded
2023-05-23T18:06:56.224Z,1684865216.224 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-05-23T18:06:56.225Z,1684865216.225 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409B24E0
2023-05-23T18:06:56.225Z,1684865216.225 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 928
2023-05-23T18:06:56.245Z,1684865216.245 [Depth_Keller] Loaded
2023-05-23T18:06:56.245Z,1684865216.245 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-05-23T18:06:56.307Z,1684865216.307 [NAL9602] Loaded
2023-05-23T18:06:56.307Z,1684865216.307 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-05-23T18:06:56.336Z,1684865216.336 [Onboard] Loaded
2023-05-23T18:06:56.337Z,1684865216.337 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-05-23T18:06:56.338Z,1684865216.338 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0
2023-05-23T18:06:56.338Z,1684865216.338 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 929
2023-05-23T18:06:56.350Z,1684865216.350 [Power24vConverter] Loaded
2023-05-23T18:06:56.350Z,1684865216.350 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-05-23T18:06:56.363Z,1684865216.363 [Radio_Surface] Loaded
2023-05-23T18:06:56.363Z,1684865216.363 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-05-23T18:06:56.364Z,1684865216.364 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A124E0
2023-05-23T18:06:56.364Z,1684865216.364 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 930
2023-05-23T18:06:56.409Z,1684865216.409 [RDI_Pathfinder] Loaded
2023-05-23T18:06:56.409Z,1684865216.409 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-05-23T18:06:56.500Z,1684865216.500 [DAT] Loaded
2023-05-23T18:06:56.500Z,1684865216.500 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-05-23T18:06:56.501Z,1684865216.501 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A424E0
2023-05-23T18:06:56.502Z,1684865216.502 [DAT ThreadHandler](INFO): Protected caller Thread ID is 931
2023-05-23T18:06:56.502Z,1684865216.502 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-05-23T18:06:56.503Z,1684865216.503 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-05-23T18:06:56.636Z,1684865216.636 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-05-23T18:06:56.636Z,1684865216.636 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-05-23T18:06:56.649Z,1684865216.649 [NavChart] Loaded
2023-05-23T18:06:56.650Z,1684865216.650 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-05-23T18:06:56.655Z,1684865216.655 [UniversalFixResidualReporter] Loaded
2023-05-23T18:06:56.655Z,1684865216.655 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-05-23T18:06:56.656Z,1684865216.656 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-05-23T18:06:56.658Z,1684865216.658 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-05-23T18:06:56.825Z,1684865216.825 [SBIT](DEBUG): Construct Startup Built In Test.
2023-05-23T18:06:56.835Z,1684865216.835 [SBIT] Loaded
2023-05-23T18:06:56.835Z,1684865216.835 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-05-23T18:06:56.838Z,1684865216.838 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-05-23T18:06:56.850Z,1684865216.850 [IBIT] Loaded
2023-05-23T18:06:56.850Z,1684865216.850 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-05-23T18:06:56.856Z,1684865216.856 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-05-23T18:06:56.959Z,1684865216.959 [CBIT] Loaded
2023-05-23T18:06:56.959Z,1684865216.959 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-05-23T18:06:56.960Z,1684865216.960 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-05-23T18:06:56.960Z,1684865216.960 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-05-23T18:06:57.119Z,1684865217.119 [BuoyancyServo] Loaded
2023-05-23T18:06:57.119Z,1684865217.119 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-05-23T18:06:57.139Z,1684865217.139 [ElevatorServo] Loaded
2023-05-23T18:06:57.139Z,1684865217.139 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-05-23T18:06:57.159Z,1684865217.159 [MassServo] Loaded
2023-05-23T18:06:57.159Z,1684865217.159 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-05-23T18:06:57.177Z,1684865217.177 [RudderServo] Loaded
2023-05-23T18:06:57.177Z,1684865217.177 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-05-23T18:06:57.194Z,1684865217.194 [ThrusterServo] Loaded
2023-05-23T18:06:57.194Z,1684865217.194 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-05-23T18:06:57.195Z,1684865217.195 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-05-23T18:06:57.195Z,1684865217.195 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-05-23T18:06:57.211Z,1684865217.211 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-05-23T18:06:57.212Z,1684865217.212 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-05-23T18:06:57.429Z,1684865217.429 [CTD_Seabird] Loaded
2023-05-23T18:06:57.430Z,1684865217.430 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-05-23T18:06:57.431Z,1684865217.431 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B8A4E0
2023-05-23T18:06:57.431Z,1684865217.431 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 932
2023-05-23T18:06:57.451Z,1684865217.451 [PAR_Licor] Loaded
2023-05-23T18:06:57.451Z,1684865217.451 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-05-23T18:06:57.482Z,1684865217.482 [WetLabsBB2FL] Loaded
2023-05-23T18:06:57.482Z,1684865217.482 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-05-23T18:06:57.483Z,1684865217.483 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BBA4E0
2023-05-23T18:06:57.484Z,1684865217.484 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 933
2023-05-23T18:06:57.503Z,1684865217.503 [WetLabsUBAT] Loaded
2023-05-23T18:06:57.503Z,1684865217.503 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-05-23T18:06:57.504Z,1684865217.504 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BEA4E0
2023-05-23T18:06:57.505Z,1684865217.505 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 934
2023-05-23T18:06:57.505Z,1684865217.505 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-05-23T18:06:57.511Z,1684865217.511 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-05-23T18:06:57.514Z,1684865217.514 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-05-23T18:06:57.525Z,1684865217.525 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-05-23T18:06:57.526Z,1684865217.526 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C1A4E0
2023-05-23T18:06:57.527Z,1684865217.527 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 935
2023-05-23T18:06:57.531Z,1684865217.531 [Supervisor](INFO): Main Thread ID is 832
2023-05-23T18:06:57.531Z,1684865217.531 [Supervisor](DEBUG): Running supervisor.
2023-05-23T18:06:57.532Z,1684865217.532 [CommandExec ThreadHandler](INFO): Handler Thread ID is 936
2023-05-23T18:06:57.532Z,1684865217.532 [CommandExec](INFO): Initializing the command executive.
2023-05-23T18:06:57.534Z,1684865217.534 [CommandLine ThreadHandler](INFO): Handler Thread ID is 937
2023-05-23T18:06:57.536Z,1684865217.536 [controlThread ThreadHandler](INFO): Handler Thread ID is 938
2023-05-23T18:06:57.536Z,1684865217.536 [controlThread](DEBUG): Initializing ControlThread
2023-05-23T18:06:57.537Z,1684865217.537 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-05-23T18:06:57.539Z,1684865217.539 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-05-23T18:06:57.540Z,1684865217.540 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-05-23T18:06:57.540Z,1684865217.540 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-05-23T18:06:57.540Z,1684865217.540 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-05-23T18:06:57.541Z,1684865217.541 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-05-23T18:06:57.541Z,1684865217.541 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-05-23T18:06:57.541Z,1684865217.541 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-05-23T18:06:57.542Z,1684865217.542 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-05-23T18:06:57.547Z,1684865217.547 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-05-23T18:06:57.548Z,1684865217.548 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-05-23T18:06:57.548Z,1684865217.548 [SBIT](INFO): Initialize SBIT Component.
2023-05-23T18:06:57.548Z,1684865217.548 [SBIT](IMPORTANT): git: 2023-05-23
2023-05-23T18:06:57.548Z,1684865217.548 [SBIT](INFO): git hash: b3953f130bbf661f8bdb6215fe799a82fab83260
2023-05-23T18:06:57.549Z,1684865217.549 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-05-23T18:06:57.550Z,1684865217.550 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-05-23T18:06:57.551Z,1684865217.551 [SBIT](INFO): Beginning SBIT in 51.000000 seconds.
2023-05-23T18:06:57.552Z,1684865217.552 [IBIT](INFO): Initialize IBIT Component.
2023-05-23T18:06:57.552Z,1684865217.552 [CBIT](DEBUG): Initialize CBIT Component.
2023-05-23T18:06:57.553Z,1684865217.553 [logger ThreadHandler](INFO): Handler Thread ID is 939
2023-05-23T18:06:57.566Z,1684865217.566 [CBIT](DEBUG): Initialized mux pins.
2023-05-23T18:06:57.566Z,1684865217.566 [CBIT](DEBUG): Initializing the watchdog timer.
2023-05-23T18:06:57.574Z,1684865217.574 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 940
2023-05-23T18:06:57.586Z,1684865217.586 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 941
2023-05-23T18:06:57.587Z,1684865217.587 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-05-23T18:06:57.590Z,1684865217.590 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-05-23T18:06:57.590Z,1684865217.590 [CBIT](DEBUG): Initializing heartbeat.
2023-05-23T18:06:57.598Z,1684865217.598 [Onboard ThreadHandler](INFO): Handler Thread ID is 942
2023-05-23T18:06:57.615Z,1684865217.615 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 943
2023-05-23T18:06:57.635Z,1684865217.635 [DAT ThreadHandler](INFO): Handler Thread ID is 944
2023-05-23T18:06:57.635Z,1684865217.635 [DAT](INFO): Powering up
2023-05-23T18:06:57.636Z,1684865217.636 [DAT](DEBUG): Initializing DAT.
2023-05-23T18:06:57.639Z,1684865217.639 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 946
2023-05-23T18:06:57.640Z,1684865217.640 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-05-23T18:06:57.643Z,1684865217.643 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 948
2023-05-23T18:06:57.646Z,1684865217.646 [WetLabsBB2FL](INFO): Powering up
2023-05-23T18:06:57.648Z,1684865217.648 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 950
2023-05-23T18:06:57.651Z,1684865217.651 [WetLabsUBAT](INFO): Powering up
2023-05-23T18:06:57.652Z,1684865217.652 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 952
2023-05-23T18:06:57.660Z,1684865217.660 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-05-23T18:06:57.660Z,1684865217.660 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-05-23T18:06:57.661Z,1684865217.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-05-23T18:06:57.661Z,1684865217.661 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-05-23T18:06:57.661Z,1684865217.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-05-23T18:06:57.661Z,1684865217.661 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-05-23T18:06:57.661Z,1684865217.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-05-23T18:06:57.661Z,1684865217.661 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-05-23T18:06:57.662Z,1684865217.662 [CBIT](DEBUG): Deactivating GF circuits.
2023-05-23T18:06:57.662Z,1684865217.662 [CBIT](DEBUG): Deactivating emergency mode.
2023-05-23T18:06:57.702Z,1684865217.702 [CBIT](DEBUG): Backplane powered.
2023-05-23T18:06:57.707Z,1684865217.707 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-05-23T18:06:57.748Z,1684865217.748 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-23T18:06:57.780Z,1684865217.780 [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-05-23T18:06:57.781Z,1684865217.781 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-05-23T18:06:57.848Z,1684865217.848 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-05-23T18:06:57.849Z,1684865217.849 [Default:A.Wait](DEBUG): Construct Wait.
2023-05-23T18:06:57.863Z,1684865217.863 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-05-23T18:06:57.896Z,1684865217.896 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-05-23T18:06:57.898Z,1684865217.898 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-05-23T18:06:57.920Z,1684865217.920 [Default:E.Execute](DEBUG): Construct Execute.
2023-05-23T18:06:57.924Z,1684865217.924 [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-05-23T18:06:57.938Z,1684865217.938 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,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,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-23T18:06:57.952Z,1684865217.952 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-05-23T18:06:58.022Z,1684865218.022 [Radio_Surface](INFO): Powering up
2023-05-23T18:06:58.111Z,1684865218.111 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1650.297119 decibar, 0.000000 m
2023-05-23T18:06:58.112Z,1684865218.112 [Power24vConverter](INFO): Powering up.
2023-05-23T18:06:58.112Z,1684865218.112 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-05-23T18:06:58.128Z,1684865218.128 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-05-23T18:06:58.160Z,1684865218.160 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-05-23T18:06:58.166Z,1684865218.166 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-05-23T18:06:58.167Z,1684865218.167 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-05-23T18:06:58.174Z,1684865218.174 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-05-23T18:06:58.175Z,1684865218.175 [MassServo](DEBUG): Initializing EZServoServo.
2023-05-23T18:06:58.182Z,1684865218.182 [MassServo](DEBUG): Initializing MassServo.
2023-05-23T18:06:58.183Z,1684865218.183 [RudderServo](DEBUG): Initializing EZServoServo.
2023-05-23T18:06:58.190Z,1684865218.190 [RudderServo](DEBUG): Initializing RudderServo.
2023-05-23T18:06:58.191Z,1684865218.191 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-05-23T18:06:58.198Z,1684865218.198 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-05-23T18:07:00.258Z,1684865220.258 [WetLabsBB2FL](INFO): Powering down
2023-05-23T18:07:04.686Z,1684865224.686 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-05-23T18:07:06.954Z,1684865226.954 [WetLabsUBAT](INFO): Powering down
2023-05-23T18:07:09.732Z,1684865229.732 [DAT](INFO): DAT read: 

2023-05-23T18:07:09.736Z,1684865229.736 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-05-23T18:07:11.495Z,1684865231.495 [DAT](INFO): DAT read: MF Frequency Band

2023-05-23T18:07:11.496Z,1684865231.496 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-05-23T18:07:11.497Z,1684865231.497 [DAT](INFO): DAT read: May 23 2023 18:07:06 

2023-05-23T18:07:12.503Z,1684865232.503 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-05-23T18:07:12.504Z,1684865232.504 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-05-23T18:07:12.505Z,1684865232.505 [DAT](INFO): commRate: 800
2023-05-23T18:07:14.570Z,1684865234.570 [DAT](INFO): entering command mode
2023-05-23T18:07:14.771Z,1684865234.771 [DAT](INFO): DAT read: 

2023-05-23T18:07:14.771Z,1684865234.771 [DAT](INFO): DAT read: user:1>

2023-05-23T18:07:14.772Z,1684865234.772 [DAT](INFO): setting verbose to 3
2023-05-23T18:07:15.026Z,1684865235.026 [DAT](INFO): DAT read: user:1>

2023-05-23T18:07:15.027Z,1684865235.027 [DAT](INFO): DAT read: Verbose         | 3           

2023-05-23T18:07:15.027Z,1684865235.027 [DAT](INFO): set verbose to 3
2023-05-23T18:07:15.028Z,1684865235.028 [DAT](INFO): setting DatVerbose to 27440
2023-05-23T18:07:15.275Z,1684865235.275 [DAT](INFO): DAT read: user:2>

2023-05-23T18:07:15.276Z,1684865235.276 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-05-23T18:07:15.276Z,1684865235.276 [DAT](INFO): set DatVerbose to 27440
2023-05-23T18:07:15.276Z,1684865235.276 [DAT](INFO): setting transmit power to 8
2023-05-23T18:07:15.527Z,1684865235.527 [DAT](INFO): DAT read: user:3>

2023-05-23T18:07:15.528Z,1684865235.528 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-05-23T18:07:15.528Z,1684865235.528 [DAT](INFO): set transmit power to 8
2023-05-23T18:07:15.528Z,1684865235.528 [DAT](INFO): setting local address to 10
2023-05-23T18:07:15.779Z,1684865235.779 [DAT](INFO): DAT read: user:4>

2023-05-23T18:07:15.779Z,1684865235.779 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-05-23T18:07:15.780Z,1684865235.780 [DAT](INFO): set local address to 10
2023-05-23T18:07:15.781Z,1684865235.781 [DAT](INFO): Setting time to: 18:7:15 And date to:5/23/2023
2023-05-23T18:07:16.031Z,1684865236.031 [DAT](INFO): DAT read: user:5>

2023-05-23T18:07:16.032Z,1684865236.032 [DAT](INFO): DAT read: Tue May 23, 2023  18:07:15  

2023-05-23T18:07:16.032Z,1684865236.032 [DAT](INFO): Local DAT time set to Tue May 23, 2023  18:07:15  

2023-05-23T18:07:26.675Z,1684865246.675 [NAL9602](INFO): Powering up NAL9602
2023-05-23T18:07:37.583Z,1684865257.583 [NAL9602](INFO): NAL9602 initialized
2023-05-23T18:07:49.318Z,1684865269.318 [SBIT](IMPORTANT): Beginning Startup BIT
2023-05-23T18:07:49.322Z,1684865269.322 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-23T18:08:00.670Z,1684865280.670 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010001
CHAN A1 (24V): 0.000507
CHAN A2 (12V): -0.007179
CHAN A3 (5V): -0.001516
CHAN B0 (3.3V): 0.000005
CHAN B1 (3.15aV): -0.000852
CHAN B2 (3.15bV): 0.000113
CHAN B3 (GND): 0.002097
OPEN: -0.003967
Full Scale: +/- 1 mA
2023-05-23T18:08:43.493Z,1684865323.493 [SBIT](IMPORTANT): SBIT PASSED
2023-05-23T18:08:43.493Z,1684865323.493 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-23T18:08:43.495Z,1684865323.495 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-23T18:08:43.495Z,1684865323.495 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-23T18:08:43.498Z,1684865323.498 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-23T18:08:43.498Z,1684865323.498 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-23T18:08:43.498Z,1684865323.498 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.582187 cubic_centimeter;

2023-05-23T18:08:43.498Z,1684865323.498 [SBIT](IMPORTANT): VerticalControl.massDefault=7.892016 millimeter;

2023-05-23T18:08:43.498Z,1684865323.498 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-23T18:08:43.889Z,1684865323.889 [MissionManager](IMPORTANT): Started mission Startup
2023-05-23T18:08:43.889Z,1684865323.889 [Startup] Running Loop=1
2023-05-23T18:08:43.889Z,1684865323.889 [Startup](DEBUG): Aggregate::initialize Startup
2023-05-23T18:08:43.889Z,1684865323.889 [Startup:A.GoToSurface] Running Loop=1
2023-05-23T18:08:43.889Z,1684865323.889 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-23T18:08:43.890Z,1684865323.890 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-23T18:08:43.891Z,1684865323.891 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-23T18:08:43.891Z,1684865323.891 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-23T18:08:43.891Z,1684865323.891 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-23T18:08:43.892Z,1684865323.892 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-23T18:08:43.893Z,1684865323.893 [Startup:StartupSatComms] Running Loop=1
2023-05-23T18:08:43.893Z,1684865323.893 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-05-23T18:08:43.893Z,1684865323.893 [Startup:StartupSatComms:A] Running Loop=1
2023-05-23T18:08:44.280Z,1684865324.280 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-05-23T18:09:14.185Z,1684865354.185 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-05-23T18:09:27.089Z,1684865367.089 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:09:34.636Z,1684865374.636 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004813
2023-05-23T18:09:38.552Z,1684865378.552 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-05-23T18:09:44.177Z,1684865384.177 [Startup:StartupSatComms:A](INFO): Timed out from 2023-05-23T18:08:43.9Z
2023-05-23T18:09:44.177Z,1684865384.177 [Startup:StartupSatComms:A] Stopped
2023-05-23T18:09:44.177Z,1684865384.177 [Startup:StartupSatComms:B] Running Loop=1
2023-05-23T18:09:44.600Z,1684865384.600 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-23T18:09:51.799Z,1684865391.799 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230523T180645/Courier0000.lzma
2023-05-23T18:09:52.801Z,1684865392.801 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0000.lzma.bak
2023-05-23T18:09:52.801Z,1684865392.801 [DataOverHttps](INFO): SBD MOMSN=18343884
2023-05-23T18:09:57.936Z,1684865397.936 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-05-23T18:09:57.936Z,1684865397.936 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:09:57.955Z,1684865397.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:09:58.422Z,1684865398.422 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:09:58.422Z,1684865398.422 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-05-23T18:10:02.395Z,1684865402.395 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-05-23T18:10:08.737Z,1684865408.737 [DataOverHttps](INFO): Sending 846 bytes from file Logs/20230523T180645/Express0001.lzma
2023-05-23T18:10:09.737Z,1684865409.737 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0001.lzma.bak
2023-05-23T18:10:09.737Z,1684865409.737 [DataOverHttps](INFO): SBD MOMSN=18343887
2023-05-23T18:10:10.837Z,1684865410.837 [Startup:StartupSatComms:B] Stopped
2023-05-23T18:10:10.837Z,1684865410.837 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-05-23T18:10:10.837Z,1684865410.837 [Startup:StartupSatComms] Stopped
2023-05-23T18:10:10.837Z,1684865410.837 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-05-23T18:10:10.838Z,1684865410.838 [Startup](INFO): Completed Startup
2023-05-23T18:10:10.838Z,1684865410.838 [MissionManager](INFO): Startup is completed.
2023-05-23T18:10:10.838Z,1684865410.838 [MissionManager](INFO): Uninitializing Mission Startup
2023-05-23T18:10:10.838Z,1684865410.838 [Startup] Stopped
2023-05-23T18:10:10.838Z,1684865410.838 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-05-23T18:10:10.838Z,1684865410.838 [Startup:A.GoToSurface] Stopped
2023-05-23T18:10:10.838Z,1684865410.838 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-23T18:10:11.241Z,1684865411.241 [MissionManager](IMPORTANT): Started mission Default
2023-05-23T18:10:11.241Z,1684865411.241 [Default] Running Loop=1
2023-05-23T18:10:11.241Z,1684865411.241 [Default](DEBUG): Aggregate::initialize Default
2023-05-23T18:10:11.241Z,1684865411.241 [Default:B.GoToSurface] Running Loop=1
2023-05-23T18:10:11.241Z,1684865411.241 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-23T18:10:11.241Z,1684865411.241 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-23T18:10:11.242Z,1684865411.242 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-23T18:10:11.242Z,1684865411.242 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-23T18:10:11.242Z,1684865411.242 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-23T18:10:11.243Z,1684865411.243 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-23T18:10:11.243Z,1684865411.243 [Default:A.Wait] Running Loop=1
2023-05-23T18:10:11.243Z,1684865411.243 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-23T18:10:24.593Z,1684865424.593 [Default:A.Wait](INFO): Done Waiting.
2023-05-23T18:10:24.594Z,1684865424.594 [Default:A.Wait] Stopped
2023-05-23T18:10:24.594Z,1684865424.594 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T18:10:24.982Z,1684865424.982 [Default:CheckIn] Running Loop=1
2023-05-23T18:10:24.982Z,1684865424.982 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T18:10:24.982Z,1684865424.982 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T18:10:25.378Z,1684865425.378 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-05-23T18:11:17.137Z,1684865477.137 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:11:22.736Z,1684865482.736 [CommandExec](IMPORTANT): got command burn on
2023-05-23T18:11:22.737Z,1684865482.737 [CommandExec](IMPORTANT): Activating dropweight wire
2023-05-23T18:11:33.148Z,1684865493.148 [CommandExec](IMPORTANT): got command burn off
2023-05-23T18:11:33.149Z,1684865493.149 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-05-23T18:12:00.821Z,1684865520.821 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-05-23T18:12:41.118Z,1684865561.118 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-23T18:12:51.681Z,1684865571.681 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:12:58.918Z,1684865578.918 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-05-23T18:12:58.918Z,1684865578.918 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:12:58.950Z,1684865578.950 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:12:59.315Z,1684865579.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:12:59.315Z,1684865579.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-05-23T18:13:32.626Z,1684865612.626 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2023-05-23T18:13:32.627Z,1684865612.627 [CommandExec](IMPORTANT): Onboard.Humidity 24.075205 %
2023-05-23T18:13:39.368Z,1684865619.368 [CommandExec](IMPORTANT): got command get Onboard.Pressure
2023-05-23T18:13:39.368Z,1684865619.368 [CommandExec](IMPORTANT): Onboard.Pressure 54285.500000 Pa
2023-05-23T18:13:58.739Z,1684865638.739 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:14:10.860Z,1684865650.860 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:14:21.380Z,1684865661.380 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:14:31.885Z,1684865671.885 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:14:42.792Z,1684865682.792 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:14:54.109Z,1684865694.109 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:15:05.012Z,1684865705.012 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2023-05-23T18:15:17.878Z,1684865717.878 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-23T18:15:17.881Z,1684865717.881 [BPC1](INFO): Received data from all battery sticks.
2023-05-23T18:15:25.167Z,1684865725.167 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-23T18:10:24.0Z
2023-05-23T18:15:25.167Z,1684865725.167 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T18:15:25.167Z,1684865725.167 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T18:15:25.580Z,1684865725.580 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-05-23T18:15:35.775Z,1684865735.775 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20230523T180645/Courier0004.lzma
2023-05-23T18:15:36.777Z,1684865736.777 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0004.lzma.bak
2023-05-23T18:15:36.777Z,1684865736.777 [DataOverHttps](INFO): SBD MOMSN=18343911
2023-05-23T18:15:52.616Z,1684865752.616 [DataOverHttps](INFO): Sending 335 bytes from file Logs/20230523T180645/Express0005.lzma
2023-05-23T18:15:53.617Z,1684865753.617 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0005.lzma.bak
2023-05-23T18:15:53.618Z,1684865753.618 [DataOverHttps](INFO): SBD MOMSN=18343913
2023-05-23T18:15:54.660Z,1684865754.660 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T18:15:54.660Z,1684865754.660 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T18:15:54.661Z,1684865754.661 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T18:15:59.921Z,1684865759.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-05-23T18:15:59.921Z,1684865759.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:15:59.944Z,1684865759.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:16:00.352Z,1684865760.352 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:16:00.352Z,1684865760.352 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-05-23T18:17:40.099Z,1684865860.099 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-23T18:17:40.099Z,1684865860.099 [NAL9602] Data Fault, FailCount= 1
2023-05-23T18:17:40.099Z,1684865860.099 [NAL9602](ERROR): Data Fault
2023-05-23T18:17:40.145Z,1684865860.145 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-23T18:17:40.505Z,1684865860.505 [NAL9602](INFO): Powering down
2023-05-23T18:17:41.335Z,1684865861.335 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-23T18:17:41.335Z,1684865861.335 [NAL9602] No Fault, FailCount= 1
2023-05-23T18:18:10.809Z,1684865890.809 [NAL9602](INFO): Powering up NAL9602
2023-05-23T18:18:21.716Z,1684865901.716 [NAL9602](INFO): NAL9602 initialized
2023-05-23T18:19:00.941Z,1684865940.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-05-23T18:19:00.941Z,1684865940.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:19:00.951Z,1684865940.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:19:01.361Z,1684865941.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:19:01.361Z,1684865941.361 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-05-23T18:20:46.065Z,1684866046.065 [DataOverHttps](IMPORTANT): SBD MTMSN=20230523T182045
2023-05-23T18:20:53.407Z,1684866053.407 [DataOverHttps](INFO): Received command: failc
2023-05-23T18:20:53.460Z,1684866053.460 [CommandExec](IMPORTANT): got command failComponent
2023-05-23T18:20:53.460Z,1684866053.460 [CommandExec](IMPORTANT): Failed components:
2023-05-23T18:20:53.460Z,1684866053.460 [CommandExec](IMPORTANT): No failed Components.
2023-05-23T18:20:55.262Z,1684866055.262 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T18:20:55.262Z,1684866055.262 [Default:CheckIn:C.Wait] Stopped
2023-05-23T18:20:55.262Z,1684866055.262 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T18:20:55.262Z,1684866055.262 [Default:CheckIn:D] Running Loop=1
2023-05-23T18:20:55.678Z,1684866055.678 [Default:CheckIn:D] Stopped
2023-05-23T18:20:55.678Z,1684866055.678 [Default:CheckIn:E] Running Loop=1
2023-05-23T18:20:56.086Z,1684866056.086 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.740618 min 
2023-05-23T18:20:56.086Z,1684866056.086 [Default:CheckIn:E] Stopped
2023-05-23T18:20:56.086Z,1684866056.086 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T18:20:56.086Z,1684866056.086 [Default:CheckIn] Stopped
2023-05-23T18:20:56.086Z,1684866056.086 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T18:20:56.087Z,1684866056.087 [Default:CheckIn](INFO): Running loop #2
2023-05-23T18:20:56.087Z,1684866056.087 [Default:CheckIn] Running Loop=2
2023-05-23T18:20:56.087Z,1684866056.087 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T18:20:56.087Z,1684866056.087 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T18:21:08.260Z,1684866068.260 [CommandExec](IMPORTANT): got command failComponent
2023-05-23T18:21:08.260Z,1684866068.260 [CommandExec](IMPORTANT): Failed components:
2023-05-23T18:21:08.260Z,1684866068.260 [CommandExec](IMPORTANT): No failed Components.
2023-05-23T18:22:01.948Z,1684866121.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-05-23T18:22:01.948Z,1684866121.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:22:01.967Z,1684866121.967 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:22:02.409Z,1684866122.409 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:22:02.410Z,1684866122.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-05-23T18:25:02.958Z,1684866302.958 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-05-23T18:25:02.959Z,1684866302.959 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:25:02.981Z,1684866302.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:25:03.405Z,1684866303.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:25:03.406Z,1684866303.406 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-05-23T18:25:56.302Z,1684866356.302 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-23T18:20:56.1Z
2023-05-23T18:25:56.302Z,1684866356.302 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T18:25:56.302Z,1684866356.302 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T18:26:03.215Z,1684866363.215 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230523T180645/Courier0007.lzma
2023-05-23T18:26:04.217Z,1684866364.217 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0007.lzma.bak
2023-05-23T18:26:04.218Z,1684866364.218 [DataOverHttps](IMPORTANT): SBD MOMSN=18343960, MTMSN=20230523T182603
2023-05-23T18:26:11.739Z,1684866371.739 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.xml
2023-05-23T18:26:11.772Z,1684866371.772 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.xml
2023-05-23T18:26:11.772Z,1684866371.772 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.xml
2023-05-23T18:26:11.840Z,1684866371.840 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2023-05-23T18:26:11.843Z,1684866371.843 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2023-05-23T18:26:11.846Z,1684866371.846 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2023-05-23T18:26:11.849Z,1684866371.849 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2023-05-23T18:26:11.852Z,1684866371.852 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2023-05-23T18:26:11.854Z,1684866371.854 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2023-05-23T18:26:11.858Z,1684866371.858 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2023-05-23T18:26:11.861Z,1684866371.861 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2023-05-23T18:26:11.864Z,1684866371.864 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2023-05-23T18:26:11.866Z,1684866371.866 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2023-05-23T18:26:11.870Z,1684866371.870 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2023-05-23T18:26:11.873Z,1684866371.873 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2023-05-23T18:26:11.876Z,1684866371.876 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2023-05-23T18:26:11.879Z,1684866371.879 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2023-05-23T18:26:11.896Z,1684866371.896 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml
2023-05-23T18:26:12.316Z,1684866372.316 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2023-05-23T18:26:12.319Z,1684866372.319 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2023-05-23T18:26:12.322Z,1684866372.322 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2023-05-23T18:26:12.324Z,1684866372.324 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2023-05-23T18:26:12.327Z,1684866372.327 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2023-05-23T18:26:12.330Z,1684866372.330 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2023-05-23T18:26:12.332Z,1684866372.332 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2023-05-23T18:26:12.335Z,1684866372.335 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2023-05-23T18:26:12.338Z,1684866372.338 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2023-05-23T18:26:12.341Z,1684866372.341 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2023-05-23T18:26:12.344Z,1684866372.344 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2023-05-23T18:26:12.351Z,1684866372.351 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2023-05-23T18:26:12.354Z,1684866372.354 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2023-05-23T18:26:12.373Z,1684866372.373 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2023-05-23T18:26:12.380Z,1684866372.380 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-05-23T18:26:12.390Z,1684866372.390 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2023-05-23T18:26:12.394Z,1684866372.394 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2023-05-23T18:26:12.417Z,1684866372.417 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2023-05-23T18:26:12.474Z,1684866372.474 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2023-05-23T18:26:12.482Z,1684866372.482 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-05-23T18:26:12.501Z,1684866372.501 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 1 bool
2023-05-23T18:26:12.509Z,1684866372.509 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2023-05-23T18:26:12.529Z,1684866372.529 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2023-05-23T18:26:12.544Z,1684866372.544 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2023-05-23T18:26:12.553Z,1684866372.553 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2023-05-23T18:26:12.561Z,1684866372.561 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-05-23T18:26:12.572Z,1684866372.572 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2023-05-23T18:26:12.580Z,1684866372.580 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2023-05-23T18:26:12.583Z,1684866372.583 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2023-05-23T18:26:12.586Z,1684866372.586 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2023-05-23T18:26:12.593Z,1684866372.593 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2023-05-23T18:26:12.600Z,1684866372.600 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2023-05-23T18:26:12.603Z,1684866372.603 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2023-05-23T18:26:12.610Z,1684866372.610 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2023-05-23T18:26:12.614Z,1684866372.614 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2023-05-23T18:26:12.629Z,1684866372.629 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2023-05-23T18:26:12.636Z,1684866372.636 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2023-05-23T18:26:12.643Z,1684866372.643 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2023-05-23T18:26:12.646Z,1684866372.646 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2023-05-23T18:26:12.653Z,1684866372.653 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2023-05-23T18:26:12.658Z,1684866372.658 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2023-05-23T18:26:12.669Z,1684866372.669 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2023-05-23T18:26:12.674Z,1684866372.674 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2023-05-23T18:26:12.686Z,1684866372.686 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2023-05-23T18:26:12.689Z,1684866372.689 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2023-05-23T18:26:12.700Z,1684866372.700 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2023-05-23T18:26:12.708Z,1684866372.708 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2023-05-23T18:26:12.711Z,1684866372.711 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2023-05-23T18:26:12.718Z,1684866372.718 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2023-05-23T18:26:12.721Z,1684866372.721 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2023-05-23T18:26:12.732Z,1684866372.732 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2023-05-23T18:26:12.756Z,1684866372.756 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2023-05-23T18:26:12.759Z,1684866372.759 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2023-05-23T18:26:12.771Z,1684866372.771 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2023-05-23T18:26:12.782Z,1684866372.782 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2023-05-23T18:26:12.793Z,1684866372.793 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2023-05-23T18:26:12.812Z,1684866372.812 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2023-05-23T18:26:12.815Z,1684866372.815 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2023-05-23T18:26:12.842Z,1684866372.842 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2023-05-23T18:26:12.858Z,1684866372.858 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2023-05-23T18:26:12.869Z,1684866372.869 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2023-05-23T18:26:12.885Z,1684866372.885 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2023-05-23T18:26:12.900Z,1684866372.900 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2023-05-23T18:26:12.903Z,1684866372.903 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2023-05-23T18:26:12.922Z,1684866372.922 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2023-05-23T18:26:12.941Z,1684866372.941 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2023-05-23T18:26:12.948Z,1684866372.948 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2023-05-23T18:26:12.960Z,1684866372.960 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2023-05-23T18:26:12.962Z,1684866372.962 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2023-05-23T18:26:12.993Z,1684866372.993 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2023-05-23T18:26:12.000Z,1684866373.000 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2023-05-23T18:26:13.098Z,1684866373.098 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-05-23T18:26:13.131Z,1684866373.131 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-05-23T18:26:13.189Z,1684866373.189 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-05-23T18:26:13.216Z,1684866373.216 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-05-23T18:26:13.268Z,1684866373.268 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-05-23T18:26:13.321Z,1684866373.321 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-05-23T18:26:13.365Z,1684866373.365 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-05-23T18:26:13.402Z,1684866373.402 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-05-23T18:26:13.484Z,1684866373.484 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-05-23T18:26:13.500Z,1684866373.500 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.xml
2023-05-23T18:26:13.743Z,1684866373.743 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2023-05-23T18:26:13.758Z,1684866373.758 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2023-05-23T18:26:13.762Z,1684866373.762 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2023-05-23T18:26:13.778Z,1684866373.778 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2023-05-23T18:26:13.782Z,1684866373.782 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2023-05-23T18:26:13.794Z,1684866373.794 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2023-05-23T18:26:13.809Z,1684866373.809 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2023-05-23T18:26:13.817Z,1684866373.817 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2023-05-23T18:26:13.824Z,1684866373.824 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2023-05-23T18:26:13.827Z,1684866373.827 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2023-05-23T18:26:13.835Z,1684866373.835 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2023-05-23T18:26:13.838Z,1684866373.838 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2023-05-23T18:26:13.843Z,1684866373.843 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2023-05-23T18:26:13.861Z,1684866373.861 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2023-05-23T18:26:13.893Z,1684866373.893 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2023-05-23T18:26:13.916Z,1684866373.916 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2023-05-23T18:26:13.923Z,1684866373.923 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2023-05-23T18:26:13.949Z,1684866373.949 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2023-05-23T18:26:13.957Z,1684866373.957 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2023-05-23T18:26:13.964Z,1684866373.964 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2023-05-23T18:26:13.974Z,1684866373.974 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2023-05-23T18:26:13.982Z,1684866373.982 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2023-05-23T18:26:13.989Z,1684866373.989 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2023-05-23T18:26:14.012Z,1684866374.012 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2023-05-23T18:26:14.018Z,1684866374.018 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2023-05-23T18:26:14.105Z,1684866374.105 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2023-05-23T18:26:14.107Z,1684866374.107 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2023-05-23T18:26:14.120Z,1684866374.120 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2023-05-23T18:26:14.122Z,1684866374.122 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2023-05-23T18:26:14.128Z,1684866374.128 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2023-05-23T18:26:14.130Z,1684866374.130 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2023-05-23T18:26:14.144Z,1684866374.144 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2023-05-23T18:26:14.145Z,1684866374.145 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2023-05-23T18:26:14.170Z,1684866374.170 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2023-05-23T18:26:14.182Z,1684866374.182 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2023-05-23T18:26:14.231Z,1684866374.231 [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-05-23T18:26:14.243Z,1684866374.243 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.xml
2023-05-23T18:26:14.502Z,1684866374.502 [Default] Stopped
2023-05-23T18:26:14.502Z,1684866374.502 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-23T18:26:14.502Z,1684866374.502 [Default:B.GoToSurface] Stopped
2023-05-23T18:26:14.502Z,1684866374.502 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-23T18:26:14.502Z,1684866374.502 [Default:CheckIn] Stopped
2023-05-23T18:26:14.502Z,1684866374.502 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T18:26:14.502Z,1684866374.502 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T18:26:14.502Z,1684866374.502 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2023-05-23T18:26:14.503Z,1684866374.503 [ballast_and_trim] Running Loop=1
2023-05-23T18:26:14.503Z,1684866374.503 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2023-05-23T18:26:14.503Z,1684866374.503 [ballast_and_trim:Science] Running Loop=1
2023-05-23T18:26:14.503Z,1684866374.503 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:A] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:B] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:C] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:D] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:E] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:F] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-05-23T18:26:14.508Z,1684866374.508 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-05-23T18:26:14.510Z,1684866374.510 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2023-05-23T18:26:14.511Z,1684866374.511 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2023-05-23T18:26:14.511Z,1684866374.511 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-05-23T18:26:14.511Z,1684866374.511 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-05-23T18:26:14.515Z,1684866374.515 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2023-05-23T18:26:14.516Z,1684866374.516 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2023-05-23T18:26:14.516Z,1684866374.516 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-05-23T18:26:14.516Z,1684866374.516 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-05-23T18:26:14.518Z,1684866374.518 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2023-05-23T18:26:14.518Z,1684866374.518 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2023-05-23T18:26:14.518Z,1684866374.518 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-05-23T18:26:14.518Z,1684866374.518 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-05-23T18:26:14.524Z,1684866374.524 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2023-05-23T18:26:14.525Z,1684866374.525 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2023-05-23T18:26:14.525Z,1684866374.525 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-05-23T18:26:14.525Z,1684866374.525 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-05-23T18:26:14.525Z,1684866374.525 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2023-05-23T18:26:14.525Z,1684866374.525 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2023-05-23T18:26:14.525Z,1684866374.525 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-05-23T18:26:14.526Z,1684866374.526 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-05-23T18:26:14.532Z,1684866374.532 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2023-05-23T18:26:14.532Z,1684866374.532 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2023-05-23T18:26:14.532Z,1684866374.532 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-05-23T18:26:14.532Z,1684866374.532 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-05-23T18:26:14.533Z,1684866374.533 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2023-05-23T18:26:14.533Z,1684866374.533 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2023-05-23T18:26:14.541Z,1684866374.541 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-05-23T18:26:14.541Z,1684866374.541 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-05-23T18:26:14.542Z,1684866374.542 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1
2023-05-23T18:26:14.542Z,1684866374.542 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger
2023-05-23T18:26:14.542Z,1684866374.542 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-05-23T18:26:14.542Z,1684866374.542 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-05-23T18:26:14.542Z,1684866374.542 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2023-05-23T18:26:14.543Z,1684866374.543 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2023-05-23T18:26:14.543Z,1684866374.543 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-05-23T18:26:14.543Z,1684866374.543 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-05-23T18:26:14.543Z,1684866374.543 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2023-05-23T18:26:14.543Z,1684866374.543 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2023-05-23T18:26:14.543Z,1684866374.543 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2023-05-23T18:26:14.547Z,1684866374.547 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-05-23T18:26:14.548Z,1684866374.548 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2023-05-23T18:26:14.548Z,1684866374.548 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2023-05-23T18:26:14.548Z,1684866374.548 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2023-05-23T18:26:14.548Z,1684866374.548 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-05-23T18:26:14.549Z,1684866374.549 [ballast_and_trim:Science] Running Loop=1
2023-05-23T18:26:14.549Z,1684866374.549 [ballast_and_trim:Science:ChlThreshTrigger] Stopped
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-05-23T18:26:14.550Z,1684866374.550 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-05-23T18:26:14.551Z,1684866374.551 [ballast_and_trim:Science:PeakDetectOil] Stopped
2023-05-23T18:26:14.555Z,1684866374.555 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2023-05-23T18:26:14.555Z,1684866374.555 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectChl] Stopped
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2023-05-23T18:26:14.556Z,1684866374.556 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-05-23T18:26:14.557Z,1684866374.557 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-05-23T18:26:14.557Z,1684866374.557 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-05-23T18:26:14.558Z,1684866374.558 [ballast_and_trim:Science:Read_Oil] Stopped
2023-05-23T18:26:14.559Z,1684866374.559 [ballast_and_trim:Science:F] Running Loop=1
2023-05-23T18:26:14.560Z,1684866374.560 [ballast_and_trim:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence
2023-05-23T18:26:14.560Z,1684866374.560 [ballast_and_trim:Science:E] Running Loop=1
2023-05-23T18:26:14.561Z,1684866374.561 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-05-23T18:26:14.561Z,1684866374.561 [ballast_and_trim:Science:D] Stopped
2023-05-23T18:26:14.561Z,1684866374.561 [ballast_and_trim:Science:C] Running Loop=1
2023-05-23T18:26:14.562Z,1684866374.562 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-05-23T18:26:14.562Z,1684866374.562 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-05-23T18:26:14.562Z,1684866374.562 [ballast_and_trim:Science:B] Running Loop=1
2023-05-23T18:26:14.563Z,1684866374.563 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-05-23T18:26:14.571Z,1684866374.571 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-05-23T18:26:14.572Z,1684866374.572 [ballast_and_trim:Science:A] Stopped
2023-05-23T18:26:14.889Z,1684866374.889 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2023-05-23T18:26:14.890Z,1684866374.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-05-23T18:26:14.890Z,1684866374.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-05-23T18:26:14.890Z,1684866374.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-05-23T18:26:14.890Z,1684866374.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-05-23T18:26:14.890Z,1684866374.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-05-23T18:26:14.890Z,1684866374.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-05-23T18:26:14.890Z,1684866374.890 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2023-05-23T18:26:14.896Z,1684866374.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2023-05-23T18:26:14.896Z,1684866374.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2023-05-23T18:26:14.896Z,1684866374.896 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2023-05-23T18:26:14.897Z,1684866374.897 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2023-05-23T18:26:14.898Z,1684866374.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2023-05-23T18:26:14.898Z,1684866374.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-05-23T18:26:14.898Z,1684866374.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2023-05-23T18:26:14.898Z,1684866374.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-05-23T18:26:14.898Z,1684866374.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-05-23T18:26:14.899Z,1684866374.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-05-23T18:26:14.899Z,1684866374.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-05-23T18:26:14.899Z,1684866374.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-05-23T18:26:14.921Z,1684866374.921 [WetLabsBB2FL](INFO): Powering up
2023-05-23T18:26:14.997Z,1684866374.997 [WetLabsUBAT](INFO): Powering up
2023-05-23T18:26:15.286Z,1684866375.286 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2023-05-23T18:26:15.286Z,1684866375.286 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2023-05-23T18:26:15.286Z,1684866375.286 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2023-05-23T18:26:15.286Z,1684866375.286 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2023-05-23T18:26:15.287Z,1684866375.287 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2023-05-23T18:26:15.287Z,1684866375.287 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-05-23T18:26:15.287Z,1684866375.287 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2023-05-23T18:26:15.287Z,1684866375.287 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2023-05-23T18:26:15.287Z,1684866375.287 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-05-23T18:26:15.287Z,1684866375.287 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2023-05-23T18:26:15.287Z,1684866375.287 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-05-23T18:26:15.695Z,1684866375.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2023-05-23T18:26:15.695Z,1684866375.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2023-05-23T18:26:15.695Z,1684866375.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T18:26:15.695Z,1684866375.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-05-23T18:26:15.719Z,1684866375.719 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2023-05-23T18:26:15.720Z,1684866375.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-05-23T18:26:15.720Z,1684866375.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2023-05-23T18:26:15.720Z,1684866375.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-05-23T18:26:15.720Z,1684866375.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-05-23T18:26:15.720Z,1684866375.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2023-05-23T18:26:15.720Z,1684866375.720 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2023-05-23T18:26:16.086Z,1684866376.086 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-05-23T18:26:16.087Z,1684866376.087 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2023-05-23T18:26:16.087Z,1684866376.087 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2023-05-23T18:26:16.087Z,1684866376.087 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2023-05-23T18:26:16.087Z,1684866376.087 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2023-05-23T18:26:18.073Z,1684866378.073 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-05-23T18:26:20.086Z,1684866380.086 [CTD_Seabird](ERROR): Salinity reading out of range: 1.947779 psu
2023-05-23T18:26:23.205Z,1684866383.205 [DataOverHttps](INFO): Sending 299 bytes from file Logs/20230523T180645/Express0008.lzma
2023-05-23T18:26:24.205Z,1684866384.205 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0008.lzma.bak
2023-05-23T18:26:24.205Z,1684866384.205 [DataOverHttps](INFO): SBD MOMSN=18343968
2023-05-23T18:26:25.185Z,1684866385.185 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s.
2023-05-23T18:26:36.368Z,1684866396.368 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-05-23T18:27:58.513Z,1684866478.513 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.711506
2023-05-23T18:28:03.980Z,1684866483.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-05-23T18:28:03.980Z,1684866483.980 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:28:04.002Z,1684866484.002 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:28:04.449Z,1684866484.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:28:04.449Z,1684866484.449 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-05-23T18:28:09.239Z,1684866489.239 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-05-23T18:28:22.553Z,1684866502.553 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-23T18:28:22.553Z,1684866502.553 [NAL9602] Data Fault, FailCount= 2
2023-05-23T18:28:22.553Z,1684866502.553 [NAL9602](ERROR): Data Fault
2023-05-23T18:28:22.668Z,1684866502.668 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-23T18:28:22.953Z,1684866502.953 [NAL9602](INFO): Powering down
2023-05-23T18:28:23.832Z,1684866503.832 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-23T18:28:23.832Z,1684866503.832 [NAL9602] No Fault, FailCount= 2
2023-05-23T18:28:30.656Z,1684866510.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-23T18:28:53.258Z,1684866533.258 [NAL9602](INFO): Powering up NAL9602
2023-05-23T18:28:55.436Z,1684866535.436 [Radio_Surface](INFO): Powering down
2023-05-23T18:29:04.165Z,1684866544.165 [NAL9602](INFO): NAL9602 initialized
2023-05-23T18:29:05.796Z,1684866545.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-23T18:29:06.804Z,1684866546.804 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-23T18:29:53.907Z,1684866593.907 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2023-05-23T18:29:53.908Z,1684866593.908 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-05-23T18:29:53.908Z,1684866593.908 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2023-05-23T18:29:53.908Z,1684866593.908 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-05-23T18:29:53.908Z,1684866593.908 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2023-05-23T18:29:53.908Z,1684866593.908 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-05-23T18:29:53.909Z,1684866593.909 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2023-05-23T18:29:53.910Z,1684866593.910 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2023-05-23T18:29:54.313Z,1684866594.313 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.050221 m 
2023-05-23T18:29:54.314Z,1684866594.314 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2023-05-23T18:29:54.314Z,1684866594.314 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2023-05-23T18:29:54.314Z,1684866594.314 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2023-05-23T18:29:54.314Z,1684866594.314 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-05-23T18:29:54.315Z,1684866594.315 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-05-23T18:31:04.984Z,1684866664.984 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-05-23T18:31:04.984Z,1684866664.984 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:31:05.053Z,1684866665.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:31:05.441Z,1684866665.441 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:31:05.441Z,1684866665.441 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-05-23T18:31:58.777Z,1684866718.777 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-05-23T18:33:14.695Z,1684866794.695 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2023-05-23T18:34:05.997Z,1684866845.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-05-23T18:34:05.997Z,1684866845.997 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:34:06.108Z,1684866846.108 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:34:06.489Z,1684866846.489 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:34:06.489Z,1684866846.489 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-05-23T18:35:34.906Z,1684866934.906 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-05-23T18:36:20.558Z,1684866980.558 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-05-23T18:37:07.041Z,1684867027.041 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-05-23T18:37:07.042Z,1684867027.042 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:37:07.076Z,1684867027.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:37:07.419Z,1684867027.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:37:07.419Z,1684867027.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-05-23T18:39:05.777Z,1684867145.777 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-23T18:39:05.777Z,1684867145.777 [NAL9602] Data Fault, FailCount= 3
2023-05-23T18:39:05.777Z,1684867145.777 [NAL9602](ERROR): Data Fault
2023-05-23T18:39:05.901Z,1684867145.901 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-23T18:39:06.189Z,1684867146.189 [NAL9602](INFO): Powering down
2023-05-23T18:39:07.050Z,1684867147.050 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-23T18:39:07.051Z,1684867147.051 [NAL9602] No Fault, FailCount= 3
2023-05-23T18:39:36.493Z,1684867176.493 [NAL9602](INFO): Powering up NAL9602
2023-05-23T18:39:47.401Z,1684867187.401 [NAL9602](INFO): NAL9602 initialized
2023-05-23T18:39:54.762Z,1684867194.762 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2023-05-23T18:39:54.762Z,1684867194.762 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2023-05-23T18:39:54.762Z,1684867194.762 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T18:39:54.762Z,1684867194.762 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Running Loop=1
2023-05-23T18:39:55.083Z,1684867195.083 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E](INFO): Running ballast and trim. Depth = 6.984570 m 
2023-05-23T18:39:55.083Z,1684867195.083 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E] Stopped
2023-05-23T18:39:55.083Z,1684867195.083 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Running Loop=1
2023-05-23T18:39:55.084Z,1684867195.084 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Initializing BallastAndTrim.
2023-05-23T18:40:08.037Z,1684867208.037 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-05-23T18:40:08.038Z,1684867208.038 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:40:08.117Z,1684867208.117 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:40:08.504Z,1684867208.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:40:08.504Z,1684867208.504 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-05-23T18:41:11.867Z,1684867271.867 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range.
2023-05-23T18:41:34.899Z,1684867294.899 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2023-05-23T18:42:05.948Z,1684867325.948 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-05-23T18:43:09.047Z,1684867389.047 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-05-23T18:43:09.047Z,1684867389.047 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:43:09.101Z,1684867389.101 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:43:09.492Z,1684867389.492 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:43:09.492Z,1684867389.492 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-05-23T18:45:15.140Z,1684867515.140 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-05-23T18:45:15.153Z,1684867515.153 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-05-23T18:46:10.106Z,1684867570.106 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-05-23T18:46:10.106Z,1684867570.106 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:46:10.158Z,1684867570.158 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:46:10.521Z,1684867570.521 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:46:10.521Z,1684867570.521 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-05-23T18:48:40.341Z,1684867720.341 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2023-05-23T18:49:11.069Z,1684867751.069 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-05-23T18:49:11.069Z,1684867751.069 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:49:11.088Z,1684867751.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:49:11.499Z,1684867751.499 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:49:11.499Z,1684867751.499 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-05-23T18:49:49.013Z,1684867789.013 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-05-23T18:49:49.013Z,1684867789.013 [NAL9602] Data Fault, FailCount= 4
2023-05-23T18:49:49.013Z,1684867789.013 [NAL9602](ERROR): Data Fault
2023-05-23T18:49:49.071Z,1684867789.071 [CBIT](ERROR): Data Fault in component: NAL9602
2023-05-23T18:49:49.413Z,1684867789.413 [NAL9602](INFO): Powering down
2023-05-23T18:49:50.313Z,1684867790.313 [CBIT](INFO): Clearing failed state for component NAL9602
2023-05-23T18:49:50.313Z,1684867790.313 [NAL9602] No Fault, FailCount= 4
2023-05-23T18:49:55.915Z,1684867795.915 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BallastAndTrim completed. Estimation time: 10.01 minutes.
2023-05-23T18:49:55.920Z,1684867795.920 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated buoyancyNeutral: 272.286016 +/- 13.027225 cc (conf. level 99.90%, sigma: 15.457898 cc).
2023-05-23T18:49:55.921Z,1684867795.921 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Estimated massDefault: 6.774373 +/- 0.237282 mm (conf. level 99.90%, sigma: 0.281555 mm).
2023-05-23T18:49:55.921Z,1684867795.921 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Changing persisted config values to: 272.286016 cc buoyancy neutral and 6.774373 mm mass default
2023-05-23T18:49:55.929Z,1684867795.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-05-23T18:49:55.929Z,1684867795.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-05-23T18:49:55.929Z,1684867795.929 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-05-23T18:49:55.930Z,1684867795.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-05-23T18:49:55.930Z,1684867795.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-05-23T18:49:55.930Z,1684867795.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.buoyancyNeutral=272.286016 cubic_centimeter;

2023-05-23T18:49:55.930Z,1684867795.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): VerticalControl.massDefault=6.774373 millimeter;

2023-05-23T18:49:55.930Z,1684867795.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-05-23T18:49:55.931Z,1684867795.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.] Stopped
2023-05-23T18:49:55.931Z,1684867795.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](INFO): Uninitializing BallastAndTrim.
2023-05-23T18:49:55.937Z,1684867795.937 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-05-23T18:49:55.937Z,1684867795.937 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2023-05-23T18:49:55.937Z,1684867795.937 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-05-23T18:49:55.937Z,1684867795.937 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2023-05-23T18:49:55.937Z,1684867795.937 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2023-05-23T18:49:55.937Z,1684867795.937 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2023-05-23T18:49:55.938Z,1684867795.938 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-05-23T18:49:55.938Z,1684867795.938 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2023-05-23T18:49:55.938Z,1684867795.938 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-05-23T18:49:55.938Z,1684867795.938 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2023-05-23T18:49:55.938Z,1684867795.938 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2023-05-23T18:49:55.938Z,1684867795.938 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2023-05-23T18:49:55.939Z,1684867795.939 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2023-05-23T18:49:55.939Z,1684867795.939 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2023-05-23T18:49:56.327Z,1684867796.327 [ballast_and_trim:RunBallastAndTrim:Depth1:B](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-05-23T18:49:56.328Z,1684867796.328 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2023-05-23T18:49:56.328Z,1684867796.328 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-05-23T18:49:56.328Z,1684867796.328 [ballast_and_trim:RunBallastAndTrim:Depth1](INFO): Completed ballast_and_trim:RunBallastAndTrim:Depth1
2023-05-23T18:49:56.328Z,1684867796.328 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2023-05-23T18:49:56.328Z,1684867796.328 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-05-23T18:49:56.328Z,1684867796.328 [ballast_and_trim:RunBallastAndTrim:Depth2] Running Loop=1
2023-05-23T18:49:56.329Z,1684867796.329 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth2
2023-05-23T18:49:56.329Z,1684867796.329 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Running Loop=1
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:RunBallastAndTrim:Depth2] Stopped
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:RunBallastAndTrim:Depth2](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth2
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:RunBallastAndTrim:Depth2:A] Stopped
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:RunBallastAndTrim](INFO): Completed ballast_and_trim:RunBallastAndTrim
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:RunBallastAndTrim] Stopped
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:Float_Up] Running Loop=1
2023-05-23T18:49:56.694Z,1684867796.694 [ballast_and_trim:Float_Up](DEBUG): Aggregate::initialize ballast_and_trim:Float_Up
2023-05-23T18:49:56.695Z,1684867796.695 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2023-05-23T18:49:56.695Z,1684867796.695 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-05-23T18:49:56.695Z,1684867796.695 [ballast_and_trim:Float_Up:B.Wait] Running Loop=1
2023-05-23T18:49:56.696Z,1684867796.696 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Initialize Wait Component.
2023-05-23T18:49:57.109Z,1684867797.109 [ballast_and_trim:Float_Up:A.Buoyancy] Running Loop=1
2023-05-23T18:50:19.721Z,1684867819.721 [NAL9602](INFO): Powering up NAL9602
2023-05-23T18:50:30.621Z,1684867830.621 [NAL9602](INFO): NAL9602 initialized
2023-05-23T18:51:14.184Z,1684867874.184 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-05-23T18:52:08.424Z,1684867928.424 [ballast_and_trim:Float_Up] Stopped
2023-05-23T18:52:08.425Z,1684867928.425 [ballast_and_trim:Float_Up](DEBUG): Aggregate::uninitialize ballast_and_trim:Float_Up
2023-05-23T18:52:08.425Z,1684867928.425 [ballast_and_trim:Float_Up:A.Buoyancy] Stopped
2023-05-23T18:52:08.425Z,1684867928.425 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-05-23T18:52:08.425Z,1684867928.425 [ballast_and_trim:Float_Up:B.Wait] Stopped
2023-05-23T18:52:08.425Z,1684867928.425 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T18:52:08.452Z,1684867928.452 [ballast_and_trim](INFO): Completed ballast_and_trim
2023-05-23T18:52:08.452Z,1684867928.452 [MissionManager](INFO): ballast_and_trim is completed.
2023-05-23T18:52:08.452Z,1684867928.452 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim] Stopped
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim:Science] Stopped
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim:Science:B] Stopped
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim:Science:C] Stopped
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim:Science:E] Stopped
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim:Science:F] Stopped
2023-05-23T18:52:08.453Z,1684867928.453 [ballast_and_trim:Science:OceanCurrent] Stopped
2023-05-23T18:52:08.454Z,1684867928.454 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2023-05-23T18:52:08.454Z,1684867928.454 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2023-05-23T18:52:08.576Z,1684867928.576 [WetLabsUBAT](INFO): Powering down
2023-05-23T18:52:08.580Z,1684867928.580 [WetLabsBB2FL](INFO): Powering down
2023-05-23T18:52:08.814Z,1684867928.814 [MissionManager](IMPORTANT): Started mission Default
2023-05-23T18:52:08.814Z,1684867928.814 [Default] Running Loop=1
2023-05-23T18:52:08.814Z,1684867928.814 [Default](DEBUG): Aggregate::initialize Default
2023-05-23T18:52:08.814Z,1684867928.814 [Default:B.GoToSurface] Running Loop=1
2023-05-23T18:52:08.815Z,1684867928.815 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-05-23T18:52:08.815Z,1684867928.815 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-05-23T18:52:08.815Z,1684867928.815 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-05-23T18:52:08.816Z,1684867928.816 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-05-23T18:52:08.816Z,1684867928.816 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-05-23T18:52:08.816Z,1684867928.816 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-05-23T18:52:08.817Z,1684867928.817 [Default:A.Wait] Running Loop=1
2023-05-23T18:52:08.817Z,1684867928.817 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-05-23T18:52:08.960Z,1684867928.960 [Radio_Surface](INFO): Powering up
2023-05-23T18:52:12.036Z,1684867932.036 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-05-23T18:52:12.037Z,1684867932.037 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:52:12.072Z,1684867932.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:52:12.504Z,1684867932.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:52:12.504Z,1684867932.504 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-05-23T18:52:14.560Z,1684867934.560 [DataOverHttps](INFO): Radio surface powered ON.
2023-05-23T18:52:22.166Z,1684867942.166 [Default:A.Wait](INFO): Done Waiting.
2023-05-23T18:52:22.167Z,1684867942.167 [Default:A.Wait] Stopped
2023-05-23T18:52:22.167Z,1684867942.167 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T18:52:22.580Z,1684867942.580 [Default:CheckIn] Running Loop=1
2023-05-23T18:52:22.580Z,1684867942.580 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T18:52:22.581Z,1684867942.581 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T18:52:44.688Z,1684867964.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-23T18:53:19.836Z,1684867999.836 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-23T18:53:54.992Z,1684868034.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-23T18:54:30.132Z,1684868070.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-23T18:55:05.276Z,1684868105.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-05-23T18:55:13.066Z,1684868113.066 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2023-05-23T18:55:13.066Z,1684868113.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-05-23T18:55:13.076Z,1684868113.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-05-23T18:55:13.446Z,1684868113.446 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-05-23T18:55:13.446Z,1684868113.446 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2023-05-23T18:55:37.979Z,1684868137.979 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005568
2023-05-23T18:57:22.717Z,1684868242.717 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-05-23T18:52:22.6Z
2023-05-23T18:57:22.718Z,1684868242.718 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T18:57:22.718Z,1684868242.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T18:57:30.683Z,1684868250.683 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230523T180645/Courier0010.lzma
2023-05-23T18:57:31.688Z,1684868251.688 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0010.lzma.bak
2023-05-23T18:57:31.688Z,1684868251.688 [DataOverHttps](INFO): SBD MOMSN=18344032
2023-05-23T18:57:47.863Z,1684868267.863 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20230523T180645/Courier0016.lzma
2023-05-23T18:57:48.865Z,1684868268.865 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0016.lzma.bak
2023-05-23T18:57:48.865Z,1684868268.865 [DataOverHttps](INFO): SBD MOMSN=18344034
2023-05-23T18:57:51.799Z,1684868271.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185750.00,A,3648.17528,N,12147.27306,W,0.816,87.32,230523,,,A*47
2023-05-23T18:57:51.802Z,1684868271.802 [NAL9602](INFO): GPS fix at 20230523T185750: (36.802921, -121.787884)
2023-05-23T18:58:07.135Z,1684868287.135 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230523T180645/Express0014.lzma
2023-05-23T18:58:08.137Z,1684868288.137 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0014.lzma.bak
2023-05-23T18:58:08.137Z,1684868288.137 [DataOverHttps](INFO): SBD MOMSN=18344036
2023-05-23T18:58:24.604Z,1684868304.604 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T18:58:30.238Z,1684868310.238 [DataOverHttps](INFO): Sending 1168 bytes from file Logs/20230523T180645/Express0017.lzma
2023-05-23T18:58:31.237Z,1684868311.237 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0017.lzma.bak
2023-05-23T18:58:31.237Z,1684868311.237 [DataOverHttps](INFO): SBD MOMSN=18344041
2023-05-23T18:58:32.291Z,1684868312.291 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T18:58:32.291Z,1684868312.291 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T18:58:32.291Z,1684868312.291 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:03:32.921Z,1684868612.921 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:03:32.922Z,1684868612.922 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:03:32.922Z,1684868612.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:03:32.923Z,1684868612.923 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:03:33.375Z,1684868613.375 [Default:CheckIn:D] Stopped
2023-05-23T19:03:33.375Z,1684868613.375 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:03:33.737Z,1684868613.737 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.409339 min 
2023-05-23T19:03:33.737Z,1684868613.737 [Default:CheckIn:E] Stopped
2023-05-23T19:03:33.738Z,1684868613.738 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:03:33.738Z,1684868613.738 [Default:CheckIn] Stopped
2023-05-23T19:03:33.738Z,1684868613.738 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:03:33.738Z,1684868613.738 [Default:CheckIn](INFO): Running loop #2
2023-05-23T19:03:33.738Z,1684868613.738 [Default:CheckIn] Running Loop=2
2023-05-23T19:03:33.738Z,1684868613.738 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:03:33.738Z,1684868613.738 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:03:35.746Z,1684868615.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190334.00,A,3648.17198,N,12147.28931,W,1.089,0.00,230523,,,A*7A
2023-05-23T19:03:35.758Z,1684868615.758 [NAL9602](INFO): GPS fix at 20230523T190334: (36.802866, -121.788155)
2023-05-23T19:03:35.769Z,1684868615.769 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:03:35.769Z,1684868615.769 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:03:43.192Z,1684868623.192 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20230523T180645/Courier0019.lzma
2023-05-23T19:03:44.194Z,1684868624.194 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0019.lzma.bak
2023-05-23T19:03:44.194Z,1684868624.194 [DataOverHttps](INFO): SBD MOMSN=18344063
2023-05-23T19:04:00.492Z,1684868640.492 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20230523T180645/Express0020.lzma
2023-05-23T19:04:01.493Z,1684868641.493 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0020.lzma.bak
2023-05-23T19:04:01.493Z,1684868641.493 [DataOverHttps](INFO): SBD MOMSN=18344067
2023-05-23T19:04:02.006Z,1684868642.006 [NAL9602](INFO): SBD MO Status=2, MOMSN=25494, MT Status=2, MTMSN=0
2023-05-23T19:04:02.006Z,1684868642.006 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:04:02.833Z,1684868642.833 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:04:02.833Z,1684868642.833 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:04:02.833Z,1684868642.833 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:05:15.542Z,1684868715.542 [NAL9602](INFO): SBD MO Status=2, MOMSN=25494, MT Status=2, MTMSN=0
2023-05-23T19:05:15.542Z,1684868715.542 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:08:38.421Z,1684868918.421 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-05-23T19:09:03.526Z,1684868943.526 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:09:03.526Z,1684868943.526 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:09:03.526Z,1684868943.526 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:09:03.526Z,1684868943.526 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:09:03.907Z,1684868943.907 [Default:CheckIn:D] Stopped
2023-05-23T19:09:03.907Z,1684868943.907 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:09:04.303Z,1684868944.303 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.918202 min 
2023-05-23T19:09:04.303Z,1684868944.303 [Default:CheckIn:E] Stopped
2023-05-23T19:09:04.303Z,1684868944.303 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:09:04.303Z,1684868944.303 [Default:CheckIn] Stopped
2023-05-23T19:09:04.304Z,1684868944.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:09:04.304Z,1684868944.304 [Default:CheckIn](INFO): Running loop #3
2023-05-23T19:09:04.304Z,1684868944.304 [Default:CheckIn] Running Loop=3
2023-05-23T19:09:04.304Z,1684868944.304 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:09:04.304Z,1684868944.304 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:09:06.303Z,1684868946.303 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190904.00,A,3648.16998,N,12147.26798,W,0.836,0.00,230523,,,A*74
2023-05-23T19:09:06.306Z,1684868946.306 [NAL9602](INFO): GPS fix at 20230523T190904: (36.802833, -121.787800)
2023-05-23T19:09:06.337Z,1684868946.337 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:09:06.337Z,1684868946.337 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:09:13.735Z,1684868953.735 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230523T180645/Courier0022.lzma
2023-05-23T19:09:14.737Z,1684868954.737 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0022.lzma.bak
2023-05-23T19:09:14.737Z,1684868954.737 [DataOverHttps](INFO): SBD MOMSN=18344072
2023-05-23T19:09:30.859Z,1684868970.859 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20230523T180645/Express0023.lzma
2023-05-23T19:09:31.861Z,1684868971.861 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0023.lzma.bak
2023-05-23T19:09:31.861Z,1684868971.861 [DataOverHttps](INFO): SBD MOMSN=18344075
2023-05-23T19:09:32.973Z,1684868972.973 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:09:32.973Z,1684868972.973 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:09:32.973Z,1684868972.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:09:38.620Z,1684868978.620 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:14:33.474Z,1684869273.474 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:14:33.474Z,1684869273.474 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:14:33.474Z,1684869273.474 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:14:33.474Z,1684869273.474 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:14:33.607Z,1684869273.607 [Default:CheckIn:D] Stopped
2023-05-23T19:14:33.607Z,1684869273.607 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:14:34.008Z,1684869274.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.413204 min 
2023-05-23T19:14:34.008Z,1684869274.008 [Default:CheckIn:E] Stopped
2023-05-23T19:14:34.008Z,1684869274.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:14:34.008Z,1684869274.008 [Default:CheckIn] Stopped
2023-05-23T19:14:34.008Z,1684869274.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:14:34.008Z,1684869274.008 [Default:CheckIn](INFO): Running loop #4
2023-05-23T19:14:34.008Z,1684869274.008 [Default:CheckIn] Running Loop=4
2023-05-23T19:14:34.009Z,1684869274.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:14:34.009Z,1684869274.009 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:14:36.018Z,1684869276.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191435.00,A,3648.14531,N,12147.22700,W,0.097,52.11,230523,,,D*43
2023-05-23T19:14:36.021Z,1684869276.021 [NAL9602](INFO): GPS fix at 20230523T191435: (36.802422, -121.787117)
2023-05-23T19:14:36.033Z,1684869276.033 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:14:36.033Z,1684869276.033 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:14:44.007Z,1684869284.007 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20230523T180645/Courier0025.lzma
2023-05-23T19:14:45.009Z,1684869285.009 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0025.lzma.bak
2023-05-23T19:14:45.009Z,1684869285.009 [DataOverHttps](INFO): SBD MOMSN=18344082
2023-05-23T19:15:01.384Z,1684869301.384 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230523T180645/Express0026.lzma
2023-05-23T19:15:02.385Z,1684869302.385 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0026.lzma.bak
2023-05-23T19:15:02.385Z,1684869302.385 [DataOverHttps](INFO): SBD MOMSN=18344085
2023-05-23T19:15:03.536Z,1684869303.536 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:15:03.536Z,1684869303.536 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:15:03.536Z,1684869303.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:15:06.717Z,1684869306.717 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-05-23T19:15:06.799Z,1684869306.799 [NAL9602](ERROR): received: 
+CSQ:0

OK494, 2, 0, 0, 0

OK
2023-05-23T19:15:58.035Z,1684869358.035 [NAL9602](INFO): SBD MO Status=0, MOMSN=25494, MT Status=0, MTMSN=0
2023-05-23T19:15:58.035Z,1684869358.035 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:16:28.764Z,1684869388.764 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:18:39.283Z,1684869519.283 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-05-23T19:20:04.111Z,1684869604.111 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:20:04.111Z,1684869604.111 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:20:04.111Z,1684869604.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:20:04.111Z,1684869604.111 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:20:04.512Z,1684869604.512 [Default:CheckIn:D] Stopped
2023-05-23T19:20:04.512Z,1684869604.512 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:20:04.926Z,1684869604.926 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.928284 min 
2023-05-23T19:20:04.926Z,1684869604.926 [Default:CheckIn:E] Stopped
2023-05-23T19:20:04.927Z,1684869604.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:20:04.927Z,1684869604.927 [Default:CheckIn] Stopped
2023-05-23T19:20:04.927Z,1684869604.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:20:04.927Z,1684869604.927 [Default:CheckIn](INFO): Running loop #5
2023-05-23T19:20:04.927Z,1684869604.927 [Default:CheckIn] Running Loop=5
2023-05-23T19:20:04.927Z,1684869604.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:20:04.928Z,1684869604.928 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:20:06.926Z,1684869606.926 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192006.00,A,3648.14998,N,12147.22170,W,0.039,74.75,230523,,,D*48
2023-05-23T19:20:06.929Z,1684869606.929 [NAL9602](INFO): GPS fix at 20230523T192006: (36.802500, -121.787028)
2023-05-23T19:20:06.939Z,1684869606.939 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:20:06.940Z,1684869606.940 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:20:16.019Z,1684869616.019 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230523T180645/Courier0028.lzma
2023-05-23T19:20:17.022Z,1684869617.022 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0028.lzma.bak
2023-05-23T19:20:17.022Z,1684869617.022 [DataOverHttps](INFO): SBD MOMSN=18344095
2023-05-23T19:20:29.294Z,1684869629.294 [NAL9602](INFO): SBD MO Status=2, MOMSN=25495, MT Status=2, MTMSN=0
2023-05-23T19:20:29.294Z,1684869629.294 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:20:35.735Z,1684869635.735 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230523T180645/Express0029.lzma
2023-05-23T19:20:36.737Z,1684869636.737 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0029.lzma.bak
2023-05-23T19:20:36.737Z,1684869636.737 [DataOverHttps](INFO): SBD MOMSN=18344103
2023-05-23T19:20:37.785Z,1684869637.785 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:20:37.785Z,1684869637.785 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:20:37.785Z,1684869637.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:20:39.458Z,1684869639.458 [NAL9602](INFO): SBD MO Status=0, MOMSN=25495, MT Status=0, MTMSN=0
2023-05-23T19:20:39.458Z,1684869639.458 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:21:10.091Z,1684869670.091 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:22:46.654Z,1684869766.654 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-05-23T19:22:46.657Z,1684869766.657 [BPC1](INFO): Received data from all battery sticks.
2023-05-23T19:25:38.453Z,1684869938.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:25:38.453Z,1684869938.453 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:25:38.453Z,1684869938.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:25:38.454Z,1684869938.454 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:25:38.829Z,1684869938.829 [Default:CheckIn:D] Stopped
2023-05-23T19:25:38.829Z,1684869938.829 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:25:39.290Z,1684869939.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.500246 min 
2023-05-23T19:25:39.290Z,1684869939.290 [Default:CheckIn:E] Stopped
2023-05-23T19:25:39.291Z,1684869939.291 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:25:39.291Z,1684869939.291 [Default:CheckIn] Stopped
2023-05-23T19:25:39.291Z,1684869939.291 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:25:39.291Z,1684869939.291 [Default:CheckIn](INFO): Running loop #6
2023-05-23T19:25:39.291Z,1684869939.291 [Default:CheckIn] Running Loop=6
2023-05-23T19:25:39.291Z,1684869939.291 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:25:39.291Z,1684869939.291 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:25:41.275Z,1684869941.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192540.00,A,3648.15014,N,12147.22140,W,0.117,74.75,230523,,,D*4D
2023-05-23T19:25:41.277Z,1684869941.277 [NAL9602](INFO): GPS fix at 20230523T192540: (36.802502, -121.787023)
2023-05-23T19:25:41.332Z,1684869941.332 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:25:41.332Z,1684869941.332 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:25:48.863Z,1684869948.863 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20230523T180645/Courier0031.lzma
2023-05-23T19:25:49.865Z,1684869949.865 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0031.lzma.bak
2023-05-23T19:25:49.865Z,1684869949.865 [DataOverHttps](INFO): SBD MOMSN=18344185
2023-05-23T19:26:06.179Z,1684869966.179 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230523T180645/Express0032.lzma
2023-05-23T19:26:06.702Z,1684869966.702 [NAL9602](INFO): SBD MO Status=2, MOMSN=25496, MT Status=2, MTMSN=0
2023-05-23T19:26:06.702Z,1684869966.702 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:26:07.181Z,1684869967.181 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0032.lzma.bak
2023-05-23T19:26:07.182Z,1684869967.182 [DataOverHttps](INFO): SBD MOMSN=18344189
2023-05-23T19:26:08.331Z,1684869968.331 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:26:08.331Z,1684869968.331 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:26:08.331Z,1684869968.331 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:26:41.037Z,1684870001.037 [NAL9602](INFO): SBD MO Status=2, MOMSN=25496, MT Status=2, MTMSN=0
2023-05-23T19:26:41.037Z,1684870001.037 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:27:08.510Z,1684870028.510 [NAL9602](INFO): SBD MO Status=2, MOMSN=25496, MT Status=2, MTMSN=0
2023-05-23T19:27:08.510Z,1684870028.510 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:27:48.510Z,1684870068.510 [NAL9602](INFO): SBD MO Status=2, MOMSN=25496, MT Status=2, MTMSN=0
2023-05-23T19:27:48.510Z,1684870068.510 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:28:03.169Z,1684870083.169 [DataOverHttps](IMPORTANT): SBD MTMSN=20230523T192802
2023-05-23T19:28:07.494Z,1684870087.494 [NAL9602](INFO): SBD MO Status=0, MOMSN=25496, MT Status=0, MTMSN=0
2023-05-23T19:28:07.494Z,1684870087.494 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:28:10.731Z,1684870090.731 [DataOverHttps](INFO): Received command: ibit
2023-05-23T19:28:10.772Z,1684870090.772 [CommandExec](IMPORTANT): got command ibit
2023-05-23T19:28:10.776Z,1684870090.776 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-05-23T19:28:10.776Z,1684870090.776 [IBIT](IMPORTANT): Beginning control surface checks.
2023-05-23T19:28:10.780Z,1684870090.780 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-23T19:28:11.733Z,1684870091.733 [DataOverHttps](IMPORTANT): SBD MTMSN=20230523T192810
2023-05-23T19:28:12.346Z,1684870092.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192811.00,A,3648.15034,N,12147.22136,W,0.019,74.75,230523,,,A*4D
2023-05-23T19:28:12.348Z,1684870092.348 [NAL9602](INFO): GPS fix at 20230523T192811: (36.802506, -121.787023)
2023-05-23T19:28:15.178Z,1684870095.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192814.00,A,3648.15032,N,12147.22144,W,0.058,74.75,230523,,,A*4E
2023-05-23T19:28:15.181Z,1684870095.181 [NAL9602](INFO): GPS fix at 20230523T192814: (36.802505, -121.787024)
2023-05-23T19:28:18.002Z,1684870098.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192817.00,A,3648.15029,N,12147.22142,W,0.019,74.75,230523,,,A*44
2023-05-23T19:28:18.005Z,1684870098.005 [NAL9602](INFO): GPS fix at 20230523T192817: (36.802505, -121.787024)
2023-05-23T19:28:19.796Z,1684870099.796 [DataOverHttps](INFO): Received command: gfscan
2023-05-23T19:28:19.852Z,1684870099.852 [CommandExec](IMPORTANT): got command gfscan
2023-05-23T19:28:20.793Z,1684870100.793 [DataOverHttps](IMPORTANT): SBD MTMSN=20230523T192819
2023-05-23T19:28:20.836Z,1684870100.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192820.00,A,3648.15029,N,12147.22136,W,0.058,74.75,230523,,,A*46
2023-05-23T19:28:20.838Z,1684870100.838 [NAL9602](INFO): GPS fix at 20230523T192820: (36.802505, -121.787023)
2023-05-23T19:28:21.673Z,1684870101.673 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003339
CHAN A1 (24V): 0.010430
CHAN A2 (12V): -0.002762
CHAN A3 (5V): -0.000536
CHAN B0 (3.3V): 0.001190
CHAN B1 (3.15aV): 0.001697
CHAN B2 (3.15bV): 0.001506
CHAN B3 (GND): 0.002410
OPEN: 0.005830
Full Scale: +/- 1 mA
2023-05-23T19:28:24.091Z,1684870104.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192823.00,A,3648.15028,N,12147.22130,W,0.019,74.75,230523,,,A*47
2023-05-23T19:28:24.093Z,1684870104.093 [NAL9602](INFO): GPS fix at 20230523T192823: (36.802505, -121.787022)
2023-05-23T19:28:26.943Z,1684870106.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192826.00,A,3648.15032,N,12147.22128,W,0.039,74.75,230523,,,A*42
2023-05-23T19:28:26.945Z,1684870106.945 [NAL9602](INFO): GPS fix at 20230523T192826: (36.802505, -121.787021)
2023-05-23T19:28:29.799Z,1684870109.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192829.00,A,3648.15035,N,12147.22130,W,0.019,74.75,230523,,,A*41
2023-05-23T19:28:29.801Z,1684870109.801 [NAL9602](INFO): GPS fix at 20230523T192829: (36.802506, -121.787022)
2023-05-23T19:28:33.015Z,1684870113.015 [DataOverHttps](INFO): Received command: failc
2023-05-23T19:28:33.020Z,1684870113.020 [CommandExec](IMPORTANT): got command failComponent
2023-05-23T19:28:33.020Z,1684870113.020 [CommandExec](IMPORTANT): Failed components:
2023-05-23T19:28:33.021Z,1684870113.021 [CommandExec](IMPORTANT): No failed Components.
2023-05-23T19:28:33.035Z,1684870113.035 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192832.00,A,3648.15031,N,12147.22129,W,0.019,74.75,230523,,,A*47
2023-05-23T19:28:33.045Z,1684870113.045 [NAL9602](INFO): GPS fix at 20230523T192832: (36.802505, -121.787021)
2023-05-23T19:28:35.866Z,1684870115.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192835.00,A,3648.15034,N,12147.22125,W,0.039,74.75,230523,,,A*4B
2023-05-23T19:28:35.869Z,1684870115.869 [NAL9602](INFO): GPS fix at 20230523T192835: (36.802506, -121.787021)
2023-05-23T19:28:39.095Z,1684870119.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192838.00,A,3648.15035,N,12147.22133,W,0.019,74.75,230523,,,A*42
2023-05-23T19:28:39.097Z,1684870119.097 [NAL9602](INFO): GPS fix at 20230523T192838: (36.802506, -121.787022)
2023-05-23T19:28:41.198Z,1684870121.198 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.485359
2023-05-23T19:28:41.198Z,1684870121.198 [IBIT](FAULT): Control surface position failure.
2023-05-23T19:28:41.931Z,1684870121.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192841.00,A,3648.15037,N,12147.22134,W,0.078,74.75,230523,,,A*4E
2023-05-23T19:28:41.933Z,1684870121.933 [NAL9602](INFO): GPS fix at 20230523T192841: (36.802506, -121.787022)
2023-05-23T19:28:45.158Z,1684870125.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192844.00,A,3648.15039,N,12147.22133,W,0.000,74.75,230523,,,A*4D
2023-05-23T19:28:45.160Z,1684870125.160 [NAL9602](INFO): GPS fix at 20230523T192844: (36.802506, -121.787022)
2023-05-23T19:28:47.986Z,1684870127.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192847.00,A,3648.15041,N,12147.22134,W,0.019,74.75,230523,,,A*4E
2023-05-23T19:28:47.988Z,1684870127.988 [NAL9602](INFO): GPS fix at 20230523T192847: (36.802507, -121.787022)
2023-05-23T19:28:50.810Z,1684870130.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192850.00,A,3648.15044,N,12147.22141,W,0.078,74.75,230523,,,A*48
2023-05-23T19:28:50.813Z,1684870130.813 [NAL9602](INFO): GPS fix at 20230523T192850: (36.802507, -121.787024)
2023-05-23T19:28:54.041Z,1684870134.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192853.00,A,3648.15040,N,12147.22143,W,0.117,74.75,230523,,,D*40
2023-05-23T19:28:54.043Z,1684870134.043 [NAL9602](INFO): GPS fix at 20230523T192853: (36.802507, -121.787024)
2023-05-23T19:28:56.871Z,1684870136.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192856.00,A,3648.15034,N,12147.22143,W,0.058,74.75,230523,,,D*4C
2023-05-23T19:28:56.873Z,1684870136.873 [NAL9602](INFO): GPS fix at 20230523T192856: (36.802506, -121.787024)
2023-05-23T19:28:56.891Z,1684870136.891 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802505 Longitude: -121.787025
2023-05-23T19:28:57.420Z,1684870137.420 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-05-23T19:28:57.420Z,1684870137.420 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-05-23T19:28:57.420Z,1684870137.420 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-05-23T19:28:57.716Z,1684870137.716 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-05-23T19:28:57.717Z,1684870137.717 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-05-23T19:28:57.717Z,1684870137.717 [IBIT](IMPORTANT): Pressure:7.836092 PSI
2023-05-23T19:28:57.717Z,1684870137.717 [IBIT](IMPORTANT): Humidity:24.551336 %
2023-05-23T19:28:58.136Z,1684870138.136 [IBIT](IMPORTANT): Vehicle Pitch:1.428112 degrees
2023-05-23T19:28:58.137Z,1684870138.137 [IBIT](IMPORTANT): Vehicle  Roll:-12.557683 degrees
2023-05-23T19:28:58.137Z,1684870138.137 [IBIT](IMPORTANT): Vehicle  Heading:202.719360 degrees
2023-05-23T19:28:58.518Z,1684870138.518 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-05-23T19:28:58.518Z,1684870138.518 [IBIT](IMPORTANT): buoyancyNeutral: 279.582184 cc
2023-05-23T19:28:58.518Z,1684870138.518 [IBIT](IMPORTANT): massDefault: 0.789202 cm
2023-05-23T19:28:58.518Z,1684870138.518 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-05-23T19:28:58.519Z,1684870138.519 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-05-23T19:28:58.519Z,1684870138.519 [IBIT](IMPORTANT): IBIT FAILED
2023-05-23T19:29:29.608Z,1684870169.608 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:31:09.027Z,1684870269.027 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:31:09.027Z,1684870269.027 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:31:09.027Z,1684870269.027 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:31:09.027Z,1684870269.027 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:31:09.451Z,1684870269.451 [Default:CheckIn:D] Stopped
2023-05-23T19:31:09.451Z,1684870269.451 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:31:09.825Z,1684870269.825 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.010608 min 
2023-05-23T19:31:09.825Z,1684870269.825 [Default:CheckIn:E] Stopped
2023-05-23T19:31:09.825Z,1684870269.825 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:31:09.825Z,1684870269.825 [Default:CheckIn] Stopped
2023-05-23T19:31:09.825Z,1684870269.825 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:31:09.826Z,1684870269.826 [Default:CheckIn](INFO): Running loop #7
2023-05-23T19:31:09.826Z,1684870269.826 [Default:CheckIn] Running Loop=7
2023-05-23T19:31:09.826Z,1684870269.826 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:31:09.826Z,1684870269.826 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:31:11.842Z,1684870271.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193111.00,A,3648.15024,N,12147.22019,W,0.058,74.75,230523,,,D*48
2023-05-23T19:31:11.844Z,1684870271.844 [NAL9602](INFO): GPS fix at 20230523T193111: (36.802504, -121.787003)
2023-05-23T19:31:11.855Z,1684870271.855 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:31:11.856Z,1684870271.856 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:31:19.439Z,1684870279.439 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230523T180645/Courier0034.lzma
2023-05-23T19:31:20.441Z,1684870280.441 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0034.lzma.bak
2023-05-23T19:31:20.441Z,1684870280.441 [DataOverHttps](INFO): SBD MOMSN=18344216
2023-05-23T19:31:24.362Z,1684870284.362 [NAL9602](INFO): SBD MO Status=0, MOMSN=25497, MT Status=0, MTMSN=0
2023-05-23T19:31:24.363Z,1684870284.363 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:31:36.763Z,1684870296.763 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230523T180645/Courier0037.lzma
2023-05-23T19:31:37.765Z,1684870297.765 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0037.lzma.bak
2023-05-23T19:31:37.766Z,1684870297.766 [DataOverHttps](INFO): SBD MOMSN=18344229
2023-05-23T19:31:54.010Z,1684870314.010 [DataOverHttps](INFO): Sending 910 bytes from file Logs/20230523T180645/Express0035.lzma
2023-05-23T19:31:55.009Z,1684870315.009 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0035.lzma.bak
2023-05-23T19:31:55.010Z,1684870315.010 [DataOverHttps](INFO): SBD MOMSN=18344231
2023-05-23T19:31:55.064Z,1684870315.064 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:32:11.115Z,1684870331.115 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20230523T180645/Express0038.lzma
2023-05-23T19:32:12.118Z,1684870332.118 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0038.lzma.bak
2023-05-23T19:32:12.118Z,1684870332.118 [DataOverHttps](INFO): SBD MOMSN=18344284
2023-05-23T19:32:13.469Z,1684870333.469 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:32:13.469Z,1684870333.469 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:32:13.469Z,1684870333.469 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:32:56.163Z,1684870376.163 [DataOverHttps](IMPORTANT): SBD MTMSN=20230523T193255
2023-05-23T19:33:03.731Z,1684870383.731 [DataOverHttps](INFO): Received command: ibit
2023-05-23T19:33:03.828Z,1684870383.828 [CommandExec](IMPORTANT): got command ibit
2023-05-23T19:33:04.221Z,1684870384.221 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-05-23T19:33:04.221Z,1684870384.221 [IBIT](IMPORTANT): Beginning control surface checks.
2023-05-23T19:33:04.225Z,1684870384.225 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-23T19:33:05.822Z,1684870385.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193305.00,A,3648.14972,N,12147.22089,W,0.019,74.75,230523,,,D*48
2023-05-23T19:33:05.825Z,1684870385.825 [NAL9602](INFO): GPS fix at 20230523T193305: (36.802495, -121.787015)
2023-05-23T19:33:15.143Z,1684870395.143 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003974
CHAN A1 (24V): 0.010547
CHAN A2 (12V): -0.002168
CHAN A3 (5V): 0.000735
CHAN B0 (3.3V): 0.001918
CHAN B1 (3.15aV): 0.001935
CHAN B2 (3.15bV): 0.001688
CHAN B3 (GND): 0.002242
OPEN: 0.006329
Full Scale: +/- 1 mA
2023-05-23T19:33:35.014Z,1684870415.014 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.692104
2023-05-23T19:33:35.014Z,1684870415.014 [IBIT](FAULT): Control surface position failure.
2023-05-23T19:33:40.561Z,1684870420.561 [NAL9602](INFO): SBD MO Status=2, MOMSN=25498, MT Status=2, MTMSN=0
2023-05-23T19:33:40.561Z,1684870420.561 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:34:27.082Z,1684870467.082 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802494 Longitude: -121.787018
2023-05-23T19:34:27.498Z,1684870467.498 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-05-23T19:34:27.499Z,1684870467.499 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-05-23T19:34:27.499Z,1684870467.499 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-05-23T19:34:27.861Z,1684870467.861 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-05-23T19:34:27.861Z,1684870467.861 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-05-23T19:34:27.862Z,1684870467.862 [IBIT](IMPORTANT): Pressure:7.743355 PSI
2023-05-23T19:34:27.862Z,1684870467.862 [IBIT](IMPORTANT): Humidity:24.496399 %
2023-05-23T19:34:28.290Z,1684870468.290 [IBIT](IMPORTANT): Vehicle Pitch:1.478125 degrees
2023-05-23T19:34:28.290Z,1684870468.290 [IBIT](IMPORTANT): Vehicle  Roll:-17.812351 degrees
2023-05-23T19:34:28.290Z,1684870468.290 [IBIT](IMPORTANT): Vehicle  Heading:202.267624 degrees
2023-05-23T19:34:28.661Z,1684870468.661 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-05-23T19:34:28.662Z,1684870468.662 [IBIT](IMPORTANT): buoyancyNeutral: 279.582184 cc
2023-05-23T19:34:28.662Z,1684870468.662 [IBIT](IMPORTANT): massDefault: 0.789202 cm
2023-05-23T19:34:28.662Z,1684870468.662 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-05-23T19:34:28.663Z,1684870468.663 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-05-23T19:34:28.663Z,1684870468.663 [IBIT](IMPORTANT): IBIT FAILED
2023-05-23T19:34:41.983Z,1684870481.983 [NAL9602](INFO): SBD MO Status=0, MOMSN=25498, MT Status=0, MTMSN=0
2023-05-23T19:34:41.984Z,1684870481.984 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:35:12.686Z,1684870512.686 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:37:13.896Z,1684870633.896 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:37:13.896Z,1684870633.896 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:37:13.896Z,1684870633.896 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:37:13.897Z,1684870633.897 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:37:14.299Z,1684870634.299 [Default:CheckIn:D] Stopped
2023-05-23T19:37:14.299Z,1684870634.299 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:37:14.706Z,1684870634.706 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.091402 min 
2023-05-23T19:37:14.706Z,1684870634.706 [Default:CheckIn:E] Stopped
2023-05-23T19:37:14.706Z,1684870634.706 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:37:14.706Z,1684870634.706 [Default:CheckIn] Stopped
2023-05-23T19:37:14.707Z,1684870634.707 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:37:14.707Z,1684870634.707 [Default:CheckIn](INFO): Running loop #8
2023-05-23T19:37:14.707Z,1684870634.707 [Default:CheckIn] Running Loop=8
2023-05-23T19:37:14.707Z,1684870634.707 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:37:14.707Z,1684870634.707 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:37:16.705Z,1684870636.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193715.00,A,3648.14954,N,12147.22142,W,0.039,74.75,230523,,,D*4D
2023-05-23T19:37:16.707Z,1684870636.707 [NAL9602](INFO): GPS fix at 20230523T193715: (36.802492, -121.787024)
2023-05-23T19:37:16.718Z,1684870636.718 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:37:16.718Z,1684870636.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:37:24.311Z,1684870644.311 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230523T180645/Courier0040.lzma
2023-05-23T19:37:25.313Z,1684870645.313 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0040.lzma.bak
2023-05-23T19:37:25.313Z,1684870645.313 [DataOverHttps](INFO): SBD MOMSN=18344390
2023-05-23T19:37:30.446Z,1684870650.446 [NAL9602](INFO): SBD MO Status=0, MOMSN=25499, MT Status=0, MTMSN=0
2023-05-23T19:37:30.446Z,1684870650.446 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:37:41.657Z,1684870661.657 [DataOverHttps](INFO): Sending 833 bytes from file Logs/20230523T180645/Express0041.lzma
2023-05-23T19:37:42.657Z,1684870662.657 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0041.lzma.bak
2023-05-23T19:37:42.658Z,1684870662.658 [DataOverHttps](INFO): SBD MOMSN=18344393
2023-05-23T19:37:58.931Z,1684870678.931 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20230523T180645/Express0044.lzma
2023-05-23T19:37:59.933Z,1684870679.933 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0044.lzma.bak
2023-05-23T19:37:59.933Z,1684870679.933 [DataOverHttps](INFO): SBD MOMSN=18344423
2023-05-23T19:38:01.149Z,1684870681.149 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:38:01.162Z,1684870681.162 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:38:01.162Z,1684870681.162 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:38:01.162Z,1684870681.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:43:01.795Z,1684870981.795 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:43:01.795Z,1684870981.795 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:43:01.795Z,1684870981.795 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:43:01.795Z,1684870981.795 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:43:02.208Z,1684870982.208 [Default:CheckIn:D] Stopped
2023-05-23T19:43:02.209Z,1684870982.209 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:43:02.615Z,1684870982.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.889901 min 
2023-05-23T19:43:02.615Z,1684870982.615 [Default:CheckIn:E] Stopped
2023-05-23T19:43:02.615Z,1684870982.615 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:43:02.615Z,1684870982.615 [Default:CheckIn] Stopped
2023-05-23T19:43:02.615Z,1684870982.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:43:02.615Z,1684870982.615 [Default:CheckIn](INFO): Running loop #9
2023-05-23T19:43:02.615Z,1684870982.615 [Default:CheckIn] Running Loop=9
2023-05-23T19:43:02.616Z,1684870982.616 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:43:02.616Z,1684870982.616 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:43:04.614Z,1684870984.614 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194303.00,A,3648.15065,N,12147.22056,W,0.058,74.75,230523,,,D*40
2023-05-23T19:43:04.616Z,1684870984.616 [NAL9602](INFO): GPS fix at 20230523T194303: (36.802511, -121.787009)
2023-05-23T19:43:04.627Z,1684870984.627 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:43:04.627Z,1684870984.627 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:43:11.975Z,1684870991.975 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230523T180645/Courier0046.lzma
2023-05-23T19:43:12.977Z,1684870992.977 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0046.lzma.bak
2023-05-23T19:43:12.977Z,1684870992.977 [DataOverHttps](INFO): SBD MOMSN=18344442
2023-05-23T19:43:29.527Z,1684871009.527 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230523T180645/Express0047.lzma
2023-05-23T19:43:30.465Z,1684871010.465 [NAL9602](INFO): SBD MO Status=0, MOMSN=25500, MT Status=0, MTMSN=0
2023-05-23T19:43:30.465Z,1684871010.465 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:43:30.529Z,1684871010.529 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0047.lzma.bak
2023-05-23T19:43:30.529Z,1684871010.529 [DataOverHttps](INFO): SBD MOMSN=18344445
2023-05-23T19:43:31.690Z,1684871011.690 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:43:31.690Z,1684871011.690 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:43:31.690Z,1684871011.690 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:44:01.168Z,1684871041.168 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:44:37.928Z,1684871077.928 [CommandExec](IMPORTANT): got command ibit
2023-05-23T19:44:38.016Z,1684871078.016 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-05-23T19:44:38.016Z,1684871078.016 [IBIT](IMPORTANT): Beginning control surface checks.
2023-05-23T19:44:38.019Z,1684871078.019 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-23T19:44:39.605Z,1684871079.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194438.00,A,3648.14981,N,12147.22037,W,0.058,74.75,230523,,,D*4A
2023-05-23T19:44:39.608Z,1684871079.608 [NAL9602](INFO): GPS fix at 20230523T194438: (36.802497, -121.787006)
2023-05-23T19:44:49.026Z,1684871089.026 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003283
CHAN A1 (24V): 0.010577
CHAN A2 (12V): -0.001971
CHAN A3 (5V): 0.000144
CHAN B0 (3.3V): 0.001420
CHAN B1 (3.15aV): 0.001606
CHAN B2 (3.15bV): 0.001776
CHAN B3 (GND): 0.002139
OPEN: 0.005857
Full Scale: +/- 1 mA
2023-05-23T19:45:02.326Z,1684871102.326 [NAL9602](INFO): SBD MO Status=2, MOMSN=25501, MT Status=2, MTMSN=0
2023-05-23T19:45:02.326Z,1684871102.326 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-05-23T19:45:08.458Z,1684871108.458 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.588732
2023-05-23T19:45:08.458Z,1684871108.458 [IBIT](FAULT): Control surface position failure.
2023-05-23T19:45:23.758Z,1684871123.758 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802498 Longitude: -121.787003
2023-05-23T19:45:24.181Z,1684871124.181 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-05-23T19:45:24.181Z,1684871124.181 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-05-23T19:45:24.181Z,1684871124.181 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-05-23T19:45:24.564Z,1684871124.564 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-05-23T19:45:24.564Z,1684871124.564 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-05-23T19:45:24.565Z,1684871124.565 [IBIT](IMPORTANT): Pressure:7.818320 PSI
2023-05-23T19:45:24.565Z,1684871124.565 [IBIT](IMPORTANT): Humidity:24.221706 %
2023-05-23T19:45:24.954Z,1684871124.954 [NAL9602](INFO): SBD MO Status=0, MOMSN=25501, MT Status=0, MTMSN=0
2023-05-23T19:45:24.954Z,1684871124.954 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:45:24.996Z,1684871124.996 [IBIT](IMPORTANT): Vehicle Pitch:1.560913 degrees
2023-05-23T19:45:24.996Z,1684871124.996 [IBIT](IMPORTANT): Vehicle  Roll:-17.731050 degrees
2023-05-23T19:45:24.997Z,1684871124.997 [IBIT](IMPORTANT): Vehicle  Heading:202.560669 degrees
2023-05-23T19:45:25.412Z,1684871125.412 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-05-23T19:45:25.413Z,1684871125.413 [IBIT](IMPORTANT): buoyancyNeutral: 279.582184 cc
2023-05-23T19:45:25.413Z,1684871125.413 [IBIT](IMPORTANT): massDefault: 0.789202 cm
2023-05-23T19:45:25.413Z,1684871125.413 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-05-23T19:45:25.414Z,1684871125.414 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-05-23T19:45:25.414Z,1684871125.414 [IBIT](IMPORTANT): IBIT FAILED
2023-05-23T19:45:55.661Z,1684871155.661 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:46:38.796Z,1684871198.796 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2023-05-23T19:46:38.800Z,1684871198.800 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2023-05-23T19:46:38.924Z,1684871198.924 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-23T19:46:50.653Z,1684871210.653 [CommandExec](IMPORTANT): got command get platform_rudder_angle degree
2023-05-23T19:46:50.654Z,1684871210.654 [CommandExec](IMPORTANT): platform_rudder_angle -14.868057 arcdeg
2023-05-23T19:47:03.544Z,1684871223.544 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2023-05-23T19:47:05.228Z,1684871225.228 [CommandExec](IMPORTANT): got command maintain clear
2023-05-23T19:47:05.282Z,1684871225.282 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,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,LcmUniversalReporter,Reporter,LogSplitter,
2023-05-23T19:47:10.356Z,1684871230.356 [CommandExec](IMPORTANT): got command ibit
2023-05-23T19:47:10.447Z,1684871230.447 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-05-23T19:47:10.447Z,1684871230.447 [IBIT](IMPORTANT): Beginning control surface checks.
2023-05-23T19:47:10.454Z,1684871230.454 [CBIT](IMPORTANT): Beginning ground fault scan
2023-05-23T19:47:12.018Z,1684871232.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194711.00,A,3648.14930,N,12147.22030,W,0.039,74.75,230523,,,D*48
2023-05-23T19:47:12.021Z,1684871232.021 [NAL9602](INFO): GPS fix at 20230523T194711: (36.802488, -121.787005)
2023-05-23T19:47:21.375Z,1684871241.375 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004275
CHAN A1 (24V): 0.011468
CHAN A2 (12V): -0.001954
CHAN A3 (5V): 0.000112
CHAN B0 (3.3V): 0.002168
CHAN B1 (3.15aV): 0.001397
CHAN B2 (3.15bV): 0.002174
CHAN B3 (GND): 0.002448
OPEN: 0.006066
Full Scale: +/- 1 mA
2023-05-23T19:47:42.318Z,1684871262.318 [NAL9602](INFO): SBD MO Status=0, MOMSN=25502, MT Status=0, MTMSN=0
2023-05-23T19:47:42.318Z,1684871262.318 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:47:43.530Z,1684871263.530 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194742.00,A,3648.14903,N,12147.22061,W,0.058,74.75,230523,,,D*4D
2023-05-23T19:47:43.532Z,1684871263.532 [NAL9602](INFO): GPS fix at 20230523T194742: (36.802484, -121.787010)
2023-05-23T19:47:45.951Z,1684871265.951 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194745.00,A,3648.14906,N,12147.22062,W,0.000,74.75,230523,,,D*41
2023-05-23T19:47:45.953Z,1684871265.953 [NAL9602](INFO): GPS fix at 20230523T194745: (36.802484, -121.787010)
2023-05-23T19:47:49.187Z,1684871269.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194748.00,A,3648.14918,N,12147.22074,W,0.019,74.75,230523,,,D*4C
2023-05-23T19:47:49.201Z,1684871269.201 [NAL9602](INFO): GPS fix at 20230523T194748: (36.802486, -121.787012)
2023-05-23T19:47:52.024Z,1684871272.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194751.00,A,3648.14946,N,12147.22068,W,0.058,74.75,230523,,,D*47
2023-05-23T19:47:52.026Z,1684871272.026 [NAL9602](INFO): GPS fix at 20230523T194751: (36.802491, -121.787011)
2023-05-23T19:47:54.837Z,1684871274.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194754.00,A,3648.14993,N,12147.22052,W,0.058,74.75,230523,,,D*43
2023-05-23T19:47:54.839Z,1684871274.839 [NAL9602](INFO): GPS fix at 20230523T194754: (36.802499, -121.787009)
2023-05-23T19:47:56.886Z,1684871276.886 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802498 Longitude: -121.787010
2023-05-23T19:47:57.337Z,1684871277.337 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-05-23T19:47:57.338Z,1684871277.338 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-05-23T19:47:57.338Z,1684871277.338 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-05-23T19:47:57.685Z,1684871277.685 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-05-23T19:47:57.686Z,1684871277.686 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-05-23T19:47:57.686Z,1684871277.686 [IBIT](IMPORTANT): Pressure:7.821424 PSI
2023-05-23T19:47:57.686Z,1684871277.686 [IBIT](IMPORTANT): Humidity:24.130142 %
2023-05-23T19:47:58.090Z,1684871278.090 [IBIT](IMPORTANT): Vehicle Pitch:1.415075 degrees
2023-05-23T19:47:58.090Z,1684871278.090 [IBIT](IMPORTANT): Vehicle  Roll:-17.403509 degrees
2023-05-23T19:47:58.091Z,1684871278.091 [IBIT](IMPORTANT): Vehicle  Heading:202.557129 degrees
2023-05-23T19:47:58.505Z,1684871278.505 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-05-23T19:47:58.505Z,1684871278.505 [IBIT](IMPORTANT): buoyancyNeutral: 279.582184 cc
2023-05-23T19:47:58.506Z,1684871278.506 [IBIT](IMPORTANT): massDefault: 0.789202 cm
2023-05-23T19:47:58.506Z,1684871278.506 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-05-23T19:47:58.506Z,1684871278.506 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2023-05-23T19:47:58.506Z,1684871278.506 [IBIT](IMPORTANT): IBIT PASSED
2023-05-23T19:48:27.568Z,1684871307.568 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:48:32.428Z,1684871312.428 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-05-23T19:48:32.428Z,1684871312.428 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:48:32.428Z,1684871312.428 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:48:32.428Z,1684871312.428 [Default:CheckIn:D] Running Loop=1
2023-05-23T19:48:32.834Z,1684871312.834 [Default:CheckIn:D] Stopped
2023-05-23T19:48:32.834Z,1684871312.834 [Default:CheckIn:E] Running Loop=1
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.400326 min 
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn:E] Stopped
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn] Stopped
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn](INFO): Running loop #10
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn] Running Loop=10
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-05-23T19:48:33.321Z,1684871313.321 [Default:CheckIn:Read_GPS] Running Loop=1
2023-05-23T19:48:35.274Z,1684871315.274 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194834.00,A,3648.15021,N,12147.21992,W,0.019,74.75,230523,,,D*48
2023-05-23T19:48:35.280Z,1684871315.280 [NAL9602](INFO): GPS fix at 20230523T194834: (36.802504, -121.786999)
2023-05-23T19:48:35.335Z,1684871315.335 [Default:CheckIn:Read_GPS] Stopped
2023-05-23T19:48:35.335Z,1684871315.335 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-05-23T19:48:42.419Z,1684871322.419 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230523T180645/Courier0049.lzma
2023-05-23T19:48:43.421Z,1684871323.421 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Courier0049.lzma.bak
2023-05-23T19:48:43.421Z,1684871323.421 [DataOverHttps](INFO): SBD MOMSN=18344453
2023-05-23T19:48:45.346Z,1684871325.346 [NAL9602](INFO): SBD MO Status=0, MOMSN=25503, MT Status=0, MTMSN=0
2023-05-23T19:48:45.346Z,1684871325.346 [NAL9602](INFO): No messages in MT queue
2023-05-23T19:48:59.840Z,1684871339.840 [DataOverHttps](INFO): Sending 1052 bytes from file Logs/20230523T180645/Express0050.lzma
2023-05-23T19:49:00.839Z,1684871340.839 [DataOverHttps](INFO): Moved sent file to Logs/20230523T180645/Express0050.lzma.bak
2023-05-23T19:49:00.839Z,1684871340.839 [DataOverHttps](INFO): SBD MOMSN=18344456
2023-05-23T19:49:01.943Z,1684871341.943 [Default:CheckIn:Read_Iridium] Stopped
2023-05-23T19:49:01.943Z,1684871341.943 [Default:CheckIn:C.Wait] Running Loop=1
2023-05-23T19:49:01.943Z,1684871341.943 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-05-23T19:49:16.069Z,1684871356.069 [NAL9602](INFO): Not Powering down - fast GPS
2023-05-23T19:52:06.513Z,1684871526.513 [DataOverHttps](IMPORTANT): SBD MTMSN=20230523T195205
2023-05-23T19:52:17.274Z,1684871537.274 [DataOverHttps](INFO): Received command: restart app
2023-05-23T19:52:17.297Z,1684871537.297 [CommandExec](IMPORTANT): got command restart application
2023-05-23T19:52:18.300Z,1684871538.300 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-05-23T19:52:18.300Z,1684871538.300 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:18.300Z,1684871538.300 [CommandExec](INFO): Uninitializing the command executive.
2023-05-23T19:52:18.300Z,1684871538.300 [CommandExec](INFO): Uninitializing the command scheduler.
2023-05-23T19:52:18.300Z,1684871538.300 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.344Z,1684871538.344 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-05-23T19:52:18.344Z,1684871538.344 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-05-23T19:52:18.344Z,1684871538.344 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.345Z,1684871538.345 [NavChartDb](INFO): Join timeout helper Thread ID is 1430
2023-05-23T19:52:18.548Z,1684871538.548 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:18.548Z,1684871538.548 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.563Z,1684871538.563 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-05-23T19:52:18.564Z,1684871538.564 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.564Z,1684871538.564 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1431
2023-05-23T19:52:18.572Z,1684871538.572 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:18.572Z,1684871538.572 [WetLabsUBAT](INFO): Powering down
2023-05-23T19:52:18.573Z,1684871538.573 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.573Z,1684871538.573 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-05-23T19:52:18.573Z,1684871538.573 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.574Z,1684871538.574 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1432
2023-05-23T19:52:18.680Z,1684871538.680 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:18.680Z,1684871538.680 [WetLabsBB2FL](INFO): Powering down
2023-05-23T19:52:18.681Z,1684871538.681 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.700Z,1684871538.700 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-05-23T19:52:18.700Z,1684871538.700 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.700Z,1684871538.700 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1433
2023-05-23T19:52:18.860Z,1684871538.860 [CTD_Seabird](INFO): Powering down
2023-05-23T19:52:18.872Z,1684871538.872 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:18.872Z,1684871538.872 [CTD_Seabird](INFO): Powering down
2023-05-23T19:52:18.884Z,1684871538.884 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.884Z,1684871538.884 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-05-23T19:52:18.884Z,1684871538.884 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.885Z,1684871538.885 [DAT](INFO): Join timeout helper Thread ID is 1434
2023-05-23T19:52:18.912Z,1684871538.912 [DAT](INFO): Powering down
2023-05-23T19:52:18.980Z,1684871538.980 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:18.980Z,1684871538.980 [DAT](INFO): Powering down
2023-05-23T19:52:18.981Z,1684871538.981 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.996Z,1684871538.996 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-05-23T19:52:18.997Z,1684871538.997 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:18.997Z,1684871538.997 [Radio_Surface](INFO): Join timeout helper Thread ID is 1435
2023-05-23T19:52:19.360Z,1684871539.360 [Radio_Surface](INFO): Powering down
2023-05-23T19:52:19.361Z,1684871539.361 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:19.361Z,1684871539.361 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:19.376Z,1684871539.376 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-05-23T19:52:19.376Z,1684871539.376 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:19.377Z,1684871539.377 [Onboard](INFO): Join timeout helper Thread ID is 1436
2023-05-23T19:52:20.276Z,1684871540.276 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-05-23T19:52:23.479Z,1684871543.479 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:23.480Z,1684871543.480 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:23.484Z,1684871543.484 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-05-23T19:52:23.484Z,1684871543.484 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:23.485Z,1684871543.485 [DataOverHttps](INFO): Join timeout helper Thread ID is 1437
2023-05-23T19:52:24.292Z,1684871544.292 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:24.292Z,1684871544.292 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.312Z,1684871544.312 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-05-23T19:52:24.312Z,1684871544.312 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.313Z,1684871544.313 [BackseatComponent](INFO): Join timeout helper Thread ID is 1438
2023-05-23T19:52:24.496Z,1684871544.496 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:24.496Z,1684871544.496 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.516Z,1684871544.516 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-05-23T19:52:24.516Z,1684871544.516 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.517Z,1684871544.517 [logger](INFO): Join timeout helper Thread ID is 1439
2023-05-23T19:52:24.532Z,1684871544.532 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:24.532Z,1684871544.532 [logger ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.536Z,1684871544.536 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-05-23T19:52:24.536Z,1684871544.536 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.537Z,1684871544.537 [CommandLine](INFO): Join timeout helper Thread ID is 1440
2023-05-23T19:52:24.556Z,1684871544.556 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:24.556Z,1684871544.556 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.576Z,1684871544.576 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-05-23T19:52:24.576Z,1684871544.576 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.577Z,1684871544.577 [CommandExec](INFO): Join timeout helper Thread ID is 1441
2023-05-23T19:52:24.578Z,1684871544.578 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-05-23T19:52:24.578Z,1684871544.578 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:24.578Z,1684871544.578 [controlThread](INFO): Join timeout helper Thread ID is 1442
2023-05-23T19:52:24.844Z,1684871544.844 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-05-23T19:52:24.844Z,1684871544.844 [controlThread](DEBUG): Uninitializing ControlThread
2023-05-23T19:52:24.844Z,1684871544.844 [AHRS_M2](INFO): Powering down
2023-05-23T19:52:24.916Z,1684871544.916 [NAL9602](INFO): Powering down
2023-05-23T19:52:24.988Z,1684871544.988 [RDI_Pathfinder](INFO): Powering down
2023-05-23T19:52:24.989Z,1684871544.989 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-05-23T19:52:24.990Z,1684871544.990 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-05-23T19:52:24.990Z,1684871544.990 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-05-23T19:52:24.991Z,1684871544.991 [MissionManager](INFO): Uninitializing Mission Default
2023-05-23T19:52:24.991Z,1684871544.991 [Default] Stopped
2023-05-23T19:52:24.991Z,1684871544.991 [Default](DEBUG): Aggregate::uninitialize Default
2023-05-23T19:52:24.991Z,1684871544.991 [Default:B.GoToSurface] Stopped
2023-05-23T19:52:24.991Z,1684871544.991 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-05-23T19:52:24.991Z,1684871544.991 [Default:CheckIn] Stopped
2023-05-23T19:52:24.991Z,1684871544.991 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-05-23T19:52:24.991Z,1684871544.991 [Default:CheckIn:C.Wait] Stopped
2023-05-23T19:52:24.991Z,1684871544.991 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-05-23T19:52:24.994Z,1684871544.994 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-05-23T19:52:24.994Z,1684871544.994 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-05-23T19:52:24.995Z,1684871544.995 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-05-23T19:52:24.995Z,1684871544.995 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-05-23T19:52:24.995Z,1684871544.995 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-05-23T19:52:24.996Z,1684871544.996 [BuoyancyServo](INFO): Powering down
2023-05-23T19:52:25.008Z,1684871545.008 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-05-23T19:52:25.008Z,1684871545.008 [ElevatorServo](INFO): Powering down
2023-05-23T19:52:25.009Z,1684871545.009 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-05-23T19:52:25.009Z,1684871545.009 [MassServo](INFO): Powering down
2023-05-23T19:52:25.009Z,1684871545.009 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-05-23T19:52:25.010Z,1684871545.010 [RudderServo](INFO): Powering down
2023-05-23T19:52:25.010Z,1684871545.010 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-05-23T19:52:25.010Z,1684871545.010 [ThrusterServo](INFO): Powering down
2023-05-23T19:52:25.011Z,1684871545.011 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-05-23T19:52:25.012Z,1684871545.012 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-05-23T19:52:25.012Z,1684871545.012 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-05-23T19:52:25.012Z,1684871545.012 [CBIT](DEBUG): Powering off loads.
2023-05-23T19:52:25.023Z,1684871545.023 [CBIT](DEBUG): Disabling WDT.
2023-05-23T19:52:25.035Z,1684871545.035 [CBIT](DEBUG): Opening all GF detection circuits.
2023-05-23T19:52:25.036Z,1684871545.036 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.098Z,1684871545.098 [DAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.105Z,1684871545.105 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.107Z,1684871545.107 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.112Z,1684871545.112 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.155Z,1684871545.155 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.213Z,1684871545.213 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.215Z,1684871545.215 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.218Z,1684871545.218 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.225Z,1684871545.225 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-05-23T19:52:25.300Z,1684871545.300 [logger ThreadHandler](INFO): Thread cancelled.