2023-11-14T23:24:09.526Z,1700004249.526 [Supervisor](DEBUG): Initializing supervisor.
2023-11-14T23:24:09.534Z,1700004249.534 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-11-14T23:24:09.534Z,1700004249.534 [SyncHandler](INFO): Protected caller Thread ID is 1207
2023-11-14T23:24:09.535Z,1700004249.535 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-11-14T23:24:09.536Z,1700004249.536 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-11-14T23:24:09.536Z,1700004249.536 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1208
2023-11-14T23:24:09.540Z,1700004249.540 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-11-14T23:24:09.557Z,1700004249.557 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-11-14T23:24:09.558Z,1700004249.558 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-11-14T23:24:09.558Z,1700004249.558 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1209
2023-11-14T23:24:09.563Z,1700004249.563 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-11-14T23:24:09.564Z,1700004249.564 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-11-14T23:24:09.564Z,1700004249.564 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1210
2023-11-14T23:24:09.566Z,1700004249.566 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-11-14T23:24:09.567Z,1700004249.567 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-11-14T23:24:09.567Z,1700004249.567 [logger ThreadHandler](INFO): Protected caller Thread ID is 1211
2023-11-14T23:24:09.571Z,1700004249.571 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-11-14T23:24:09.571Z,1700004249.571 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-11-14T23:24:09.573Z,1700004249.573 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-11-14T23:24:09.668Z,1700004249.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-11-14T23:24:09.668Z,1700004249.668 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-11-14T23:24:10.341Z,1700004250.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-11-14T23:24:10.341Z,1700004250.341 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-11-14T23:24:10.552Z,1700004250.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-11-14T23:24:10.553Z,1700004250.553 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-11-14T23:24:10.632Z,1700004250.632 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-11-14T23:24:10.761Z,1700004250.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-11-14T23:24:10.762Z,1700004250.762 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-11-14T23:24:10.843Z,1700004250.843 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-11-14T23:24:10.949Z,1700004250.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-11-14T23:24:10.950Z,1700004250.950 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-11-14T23:24:11.319Z,1700004251.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-11-14T23:24:11.320Z,1700004251.320 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-11-14T23:24:11.785Z,1700004251.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-11-14T23:24:11.786Z,1700004251.786 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-11-14T23:24:11.995Z,1700004251.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-11-14T23:24:11.996Z,1700004251.996 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-11-14T23:24:12.176Z,1700004252.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-11-14T23:24:12.177Z,1700004252.177 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-11-14T23:24:12.671Z,1700004252.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-11-14T23:24:12.672Z,1700004252.672 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-11-14T23:24:12.947Z,1700004252.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-11-14T23:24:12.948Z,1700004252.948 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-11-14T23:24:13.164Z,1700004253.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-11-14T23:24:13.164Z,1700004253.164 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-11-14T23:24:13.523Z,1700004253.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-11-14T23:24:13.524Z,1700004253.524 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-11-14T23:24:13.845Z,1700004253.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-11-14T23:24:13.847Z,1700004253.847 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-11-14T23:24:13.848Z,1700004253.848 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-11-14T23:24:13.932Z,1700004253.932 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-11-14T23:24:14.094Z,1700004254.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-11-14T23:24:14.197Z,1700004254.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-11-14T23:24:14.283Z,1700004254.283 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-11-14T23:24:14.379Z,1700004254.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-11-14T23:24:14.540Z,1700004254.540 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-11-14T23:24:15.119Z,1700004255.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-11-14T23:24:15.119Z,1700004255.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-11-14T23:24:15.277Z,1700004255.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-11-14T23:24:15.461Z,1700004255.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-11-14T23:24:15.598Z,1700004255.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-11-14T23:24:15.698Z,1700004255.698 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-11-14T23:24:15.699Z,1700004255.699 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-11-14T23:24:15.710Z,1700004255.710 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-11-14T23:24:15.809Z,1700004255.809 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-11-14T23:24:15.810Z,1700004255.810 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-11-14T23:24:15.861Z,1700004255.861 [VerticalControl](DEBUG): Construct VerticalControl.
2023-11-14T23:24:15.920Z,1700004255.920 [VerticalControl] Loaded
2023-11-14T23:24:15.920Z,1700004255.920 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-11-14T23:24:15.923Z,1700004255.923 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-11-14T23:24:15.963Z,1700004255.963 [HorizontalControl] Loaded
2023-11-14T23:24:15.963Z,1700004255.963 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-11-14T23:24:15.965Z,1700004255.965 [SpeedControl](DEBUG): Construct SpeedControl.
2023-11-14T23:24:15.968Z,1700004255.968 [SpeedControl] Loaded
2023-11-14T23:24:15.969Z,1700004255.969 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-11-14T23:24:15.971Z,1700004255.971 [LoopControl](DEBUG): Construct LoopControl.
2023-11-14T23:24:15.972Z,1700004255.972 [LoopControl] Loaded
2023-11-14T23:24:15.972Z,1700004255.972 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-11-14T23:24:15.972Z,1700004255.972 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-11-14T23:24:15.973Z,1700004255.973 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-11-14T23:24:16.022Z,1700004256.022 [DepthRateCalculator] Loaded
2023-11-14T23:24:16.023Z,1700004256.023 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-11-14T23:24:16.028Z,1700004256.028 [PitchRateCalculator] Loaded
2023-11-14T23:24:16.028Z,1700004256.028 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-11-14T23:24:16.038Z,1700004256.038 [SpeedCalculator] Loaded
2023-11-14T23:24:16.038Z,1700004256.038 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-11-14T23:24:16.042Z,1700004256.042 [YawRateCalculator] Loaded
2023-11-14T23:24:16.042Z,1700004256.042 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-11-14T23:24:16.060Z,1700004256.060 [ElevatorOffsetCalculator] Loaded
2023-11-14T23:24:16.061Z,1700004256.061 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-11-14T23:24:16.061Z,1700004256.061 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-11-14T23:24:16.062Z,1700004256.062 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-11-14T23:24:16.088Z,1700004256.088 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-11-14T23:24:16.089Z,1700004256.089 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-11-14T23:24:16.145Z,1700004256.145 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-11-14T23:24:16.145Z,1700004256.145 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-11-14T23:24:16.448Z,1700004256.448 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-11-14T23:24:16.449Z,1700004256.449 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-11-14T23:24:16.595Z,1700004256.595 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-11-14T23:24:16.595Z,1700004256.595 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-11-14T23:24:17.041Z,1700004257.041 [AHRS_M2] Loaded
2023-11-14T23:24:17.041Z,1700004257.041 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-11-14T23:24:17.076Z,1700004257.076 [BackseatComponent] Loaded
2023-11-14T23:24:17.076Z,1700004257.076 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-11-14T23:24:17.078Z,1700004257.078 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2023-11-14T23:24:17.078Z,1700004257.078 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1293
2023-11-14T23:24:17.081Z,1700004257.081 [LcmUniversalReporter] Loaded
2023-11-14T23:24:17.081Z,1700004257.081 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-11-14T23:24:17.895Z,1700004257.895 [BPC1] Loaded
2023-11-14T23:24:17.895Z,1700004257.895 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-11-14T23:24:17.965Z,1700004257.965 [DataOverHttps] Loaded
2023-11-14T23:24:17.965Z,1700004257.965 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-11-14T23:24:17.966Z,1700004257.966 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2023-11-14T23:24:17.966Z,1700004257.966 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1294
2023-11-14T23:24:17.987Z,1700004257.987 [Depth_Keller] Loaded
2023-11-14T23:24:17.987Z,1700004257.987 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-11-14T23:24:17.992Z,1700004257.992 [DropWeight] Loaded
2023-11-14T23:24:17.992Z,1700004257.992 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-11-14T23:24:18.055Z,1700004258.055 [NAL9602] Loaded
2023-11-14T23:24:18.055Z,1700004258.055 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-11-14T23:24:18.085Z,1700004258.085 [Onboard] Loaded
2023-11-14T23:24:18.085Z,1700004258.085 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-11-14T23:24:18.086Z,1700004258.086 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0
2023-11-14T23:24:18.086Z,1700004258.086 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1295
2023-11-14T23:24:18.092Z,1700004258.092 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2023-11-14T23:24:18.104Z,1700004258.104 [PowerOnly] Loaded
2023-11-14T23:24:18.104Z,1700004258.104 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-11-14T23:24:18.115Z,1700004258.115 [Power24vConverter] Loaded
2023-11-14T23:24:18.116Z,1700004258.116 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-11-14T23:24:18.129Z,1700004258.129 [Radio_Surface] Loaded
2023-11-14T23:24:18.129Z,1700004258.129 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-11-14T23:24:18.130Z,1700004258.130 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0
2023-11-14T23:24:18.130Z,1700004258.130 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1296
2023-11-14T23:24:18.175Z,1700004258.175 [RDI_Pathfinder] Loaded
2023-11-14T23:24:18.175Z,1700004258.175 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-11-14T23:24:18.267Z,1700004258.267 [DAT] Loaded
2023-11-14T23:24:18.268Z,1700004258.268 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-11-14T23:24:18.269Z,1700004258.269 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0
2023-11-14T23:24:18.269Z,1700004258.269 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1297
2023-11-14T23:24:18.269Z,1700004258.269 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-11-14T23:24:18.270Z,1700004258.270 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-11-14T23:24:18.379Z,1700004258.379 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-11-14T23:24:18.379Z,1700004258.379 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-11-14T23:24:18.392Z,1700004258.392 [NavChart] Loaded
2023-11-14T23:24:18.393Z,1700004258.393 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-11-14T23:24:18.398Z,1700004258.398 [UniversalFixResidualReporter] Loaded
2023-11-14T23:24:18.398Z,1700004258.398 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-11-14T23:24:18.399Z,1700004258.399 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-11-14T23:24:18.399Z,1700004258.399 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-11-14T23:24:18.539Z,1700004258.539 [SBIT](DEBUG): Construct Startup Built In Test.
2023-11-14T23:24:18.548Z,1700004258.548 [SBIT] Loaded
2023-11-14T23:24:18.548Z,1700004258.548 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-11-14T23:24:18.551Z,1700004258.551 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-11-14T23:24:18.563Z,1700004258.563 [IBIT] Loaded
2023-11-14T23:24:18.564Z,1700004258.564 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-11-14T23:24:18.569Z,1700004258.569 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-11-14T23:24:18.672Z,1700004258.672 [CBIT] Loaded
2023-11-14T23:24:18.672Z,1700004258.672 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-11-14T23:24:18.672Z,1700004258.672 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-11-14T23:24:18.673Z,1700004258.673 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-11-14T23:24:18.805Z,1700004258.805 [BuoyancyServo] Loaded
2023-11-14T23:24:18.805Z,1700004258.805 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-11-14T23:24:18.826Z,1700004258.826 [ElevatorServo] Loaded
2023-11-14T23:24:18.826Z,1700004258.826 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-11-14T23:24:18.846Z,1700004258.846 [MassServo] Loaded
2023-11-14T23:24:18.846Z,1700004258.846 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-11-14T23:24:18.865Z,1700004258.865 [RudderServo] Loaded
2023-11-14T23:24:18.865Z,1700004258.865 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-11-14T23:24:18.882Z,1700004258.882 [ThrusterServo] Loaded
2023-11-14T23:24:18.882Z,1700004258.882 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-11-14T23:24:18.883Z,1700004258.883 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-11-14T23:24:18.883Z,1700004258.883 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-11-14T23:24:18.894Z,1700004258.894 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-11-14T23:24:18.894Z,1700004258.894 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-11-14T23:24:19.066Z,1700004259.066 [CTD_Seabird] Loaded
2023-11-14T23:24:19.066Z,1700004259.066 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-11-14T23:24:19.067Z,1700004259.067 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B944E0
2023-11-14T23:24:19.068Z,1700004259.068 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1298
2023-11-14T23:24:19.088Z,1700004259.088 [PAR_Licor] Loaded
2023-11-14T23:24:19.088Z,1700004259.088 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-11-14T23:24:19.128Z,1700004259.128 [WetLabsBB2FL] Loaded
2023-11-14T23:24:19.129Z,1700004259.129 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-11-14T23:24:19.130Z,1700004259.130 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0
2023-11-14T23:24:19.130Z,1700004259.130 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1299
2023-11-14T23:24:19.149Z,1700004259.149 [WetLabsUBAT] Loaded
2023-11-14T23:24:19.149Z,1700004259.149 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-11-14T23:24:19.150Z,1700004259.150 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BF44E0
2023-11-14T23:24:19.151Z,1700004259.151 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1300
2023-11-14T23:24:19.151Z,1700004259.151 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-11-14T23:24:19.158Z,1700004259.158 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-11-14T23:24:19.160Z,1700004259.160 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-11-14T23:24:19.172Z,1700004259.172 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-11-14T23:24:19.173Z,1700004259.173 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C244E0
2023-11-14T23:24:19.173Z,1700004259.173 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1301
2023-11-14T23:24:19.177Z,1700004259.177 [Supervisor](INFO): Main Thread ID is 1055
2023-11-14T23:24:19.178Z,1700004259.178 [Supervisor](DEBUG): Running supervisor.
2023-11-14T23:24:19.178Z,1700004259.178 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1302
2023-11-14T23:24:19.179Z,1700004259.179 [CommandExec](INFO): Initializing the command executive.
2023-11-14T23:24:19.180Z,1700004259.180 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1303
2023-11-14T23:24:19.182Z,1700004259.182 [controlThread ThreadHandler](INFO): Handler Thread ID is 1304
2023-11-14T23:24:19.183Z,1700004259.183 [controlThread](DEBUG): Initializing ControlThread
2023-11-14T23:24:19.184Z,1700004259.184 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-11-14T23:24:19.186Z,1700004259.186 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-11-14T23:24:19.187Z,1700004259.187 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-11-14T23:24:19.187Z,1700004259.187 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-11-14T23:24:19.188Z,1700004259.188 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-11-14T23:24:19.188Z,1700004259.188 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-11-14T23:24:19.188Z,1700004259.188 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-11-14T23:24:19.189Z,1700004259.189 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-11-14T23:24:19.189Z,1700004259.189 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-11-14T23:24:19.194Z,1700004259.194 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-11-14T23:24:19.195Z,1700004259.195 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-11-14T23:24:19.195Z,1700004259.195 [SBIT](INFO): Initialize SBIT Component.
2023-11-14T23:24:19.196Z,1700004259.196 [SBIT](IMPORTANT): git: 2023-10-12
2023-11-14T23:24:19.196Z,1700004259.196 [SBIT](INFO): git hash: f38038725fad7dfee583a1d6cb07fccd712c5d54
2023-11-14T23:24:19.196Z,1700004259.196 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-11-14T23:24:19.197Z,1700004259.197 [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-11-14T23:24:19.198Z,1700004259.198 [SBIT](INFO): Beginning SBIT in 35.000000 seconds.
2023-11-14T23:24:19.199Z,1700004259.199 [IBIT](INFO): Initialize IBIT Component.
2023-11-14T23:24:19.200Z,1700004259.200 [CBIT](DEBUG): Initialize CBIT Component.
2023-11-14T23:24:19.201Z,1700004259.201 [logger ThreadHandler](INFO): Handler Thread ID is 1305
2023-11-14T23:24:19.212Z,1700004259.212 [CBIT](DEBUG): Initialized mux pins.
2023-11-14T23:24:19.212Z,1700004259.212 [CBIT](DEBUG): Initializing the watchdog timer.
2023-11-14T23:24:19.220Z,1700004259.220 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1306
2023-11-14T23:24:19.232Z,1700004259.232 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1307
2023-11-14T23:24:19.233Z,1700004259.233 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-11-14T23:24:19.236Z,1700004259.236 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-11-14T23:24:19.236Z,1700004259.236 [CBIT](DEBUG): Initializing heartbeat.
2023-11-14T23:24:19.244Z,1700004259.244 [Onboard ThreadHandler](INFO): Handler Thread ID is 1308
2023-11-14T23:24:19.262Z,1700004259.262 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1309
2023-11-14T23:24:19.292Z,1700004259.292 [DAT ThreadHandler](INFO): Handler Thread ID is 1310
2023-11-14T23:24:19.293Z,1700004259.293 [DAT](INFO): Powering up
2023-11-14T23:24:19.293Z,1700004259.293 [DAT](DEBUG): Initializing DAT.
2023-11-14T23:24:19.300Z,1700004259.300 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1312
2023-11-14T23:24:19.301Z,1700004259.301 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-11-14T23:24:19.305Z,1700004259.305 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1314
2023-11-14T23:24:19.308Z,1700004259.308 [CBIT](DEBUG): Deactivating GF circuits.
2023-11-14T23:24:19.308Z,1700004259.308 [CBIT](DEBUG): Deactivating emergency mode.
2023-11-14T23:24:19.309Z,1700004259.309 [WetLabsBB2FL](INFO): Powering up
2023-11-14T23:24:19.310Z,1700004259.310 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1316
2023-11-14T23:24:19.312Z,1700004259.312 [WetLabsUBAT](INFO): Powering up
2023-11-14T23:24:19.313Z,1700004259.313 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1318
2023-11-14T23:24:19.317Z,1700004259.317 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-11-14T23:24:19.317Z,1700004259.317 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-11-14T23:24:19.317Z,1700004259.317 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-11-14T23:24:19.317Z,1700004259.317 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-11-14T23:24:19.318Z,1700004259.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-11-14T23:24:19.318Z,1700004259.318 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-11-14T23:24:19.318Z,1700004259.318 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-11-14T23:24:19.318Z,1700004259.318 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-11-14T23:24:19.348Z,1700004259.348 [CBIT](DEBUG): Backplane powered.
2023-11-14T23:24:19.353Z,1700004259.353 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-11-14T23:24:19.369Z,1700004259.369 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-14T23:24:19.405Z,1700004259.405 [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-11-14T23:24:19.406Z,1700004259.406 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-11-14T23:24:19.489Z,1700004259.489 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-11-14T23:24:19.491Z,1700004259.491 [Default:A.Wait](DEBUG): Construct Wait.
2023-11-14T23:24:19.496Z,1700004259.496 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-11-14T23:24:19.524Z,1700004259.524 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-11-14T23:24:19.527Z,1700004259.527 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-11-14T23:24:19.557Z,1700004259.557 [Default:E.Execute](DEBUG): Construct Execute.
2023-11-14T23:24:19.576Z,1700004259.576 [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-11-14T23:24:19.581Z,1700004259.581 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,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-11-14T23:24:19.589Z,1700004259.589 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-11-14T23:24:19.668Z,1700004259.668 [Radio_Surface](INFO): Powering up
2023-11-14T23:24:19.705Z,1700004259.705 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-11-14T23:24:19.707Z,1700004259.707 [PowerOnly](INFO): Powering up loadControl
2023-11-14T23:24:19.725Z,1700004259.725 [Power24vConverter](INFO): Powering up.
2023-11-14T23:24:19.726Z,1700004259.726 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-11-14T23:24:19.766Z,1700004259.766 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-11-14T23:24:19.836Z,1700004259.836 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-11-14T23:24:19.848Z,1700004259.848 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-11-14T23:24:19.850Z,1700004259.850 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-11-14T23:24:19.930Z,1700004259.930 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-11-14T23:24:19.931Z,1700004259.931 [MassServo](DEBUG): Initializing EZServoServo.
2023-11-14T23:24:19.944Z,1700004259.944 [MassServo](DEBUG): Initializing MassServo.
2023-11-14T23:24:19.945Z,1700004259.945 [RudderServo](DEBUG): Initializing EZServoServo.
2023-11-14T23:24:19.952Z,1700004259.952 [RudderServo](DEBUG): Initializing RudderServo.
2023-11-14T23:24:19.953Z,1700004259.953 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-11-14T23:24:20.037Z,1700004260.037 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-11-14T23:24:21.920Z,1700004261.920 [WetLabsBB2FL](INFO): Powering down
2023-11-14T23:24:26.332Z,1700004266.332 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-11-14T23:24:28.584Z,1700004268.584 [WetLabsUBAT](INFO): Powering down
2023-11-14T23:24:31.640Z,1700004271.640 [DAT](INFO): DAT read: 

2023-11-14T23:24:31.642Z,1700004271.642 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-11-14T23:24:33.155Z,1700004273.155 [DAT](INFO): DAT read: MF Frequency Band

2023-11-14T23:24:33.157Z,1700004273.157 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-11-14T23:24:33.157Z,1700004273.157 [DAT](INFO): DAT read: Nov 14 2023 23:24:27 

2023-11-14T23:24:34.413Z,1700004274.413 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-11-14T23:24:34.414Z,1700004274.414 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-11-14T23:24:34.415Z,1700004274.415 [DAT](INFO): commRate: 800
2023-11-14T23:24:36.480Z,1700004276.480 [DAT](INFO): entering command mode
2023-11-14T23:24:36.680Z,1700004276.680 [DAT](INFO): DAT read: 

2023-11-14T23:24:36.681Z,1700004276.681 [DAT](INFO): DAT read: user:1>

2023-11-14T23:24:36.681Z,1700004276.681 [DAT](INFO): setting verbose to 3
2023-11-14T23:24:36.932Z,1700004276.932 [DAT](INFO): DAT read: user:1>

2023-11-14T23:24:36.934Z,1700004276.934 [DAT](INFO): DAT read: Verbose         | 3           

2023-11-14T23:24:36.934Z,1700004276.934 [DAT](INFO): set verbose to 3
2023-11-14T23:24:36.934Z,1700004276.934 [DAT](INFO): setting DatVerbose to 27440
2023-11-14T23:24:37.187Z,1700004277.187 [DAT](INFO): DAT read: user:2>

2023-11-14T23:24:37.189Z,1700004277.189 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-11-14T23:24:37.190Z,1700004277.190 [DAT](INFO): set DatVerbose to 27440
2023-11-14T23:24:37.190Z,1700004277.190 [DAT](INFO): setting transmit power to 8
2023-11-14T23:24:37.436Z,1700004277.436 [DAT](INFO): DAT read: user:3>

2023-11-14T23:24:37.438Z,1700004277.438 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-11-14T23:24:37.438Z,1700004277.438 [DAT](INFO): set transmit power to 8
2023-11-14T23:24:37.438Z,1700004277.438 [DAT](INFO): setting local address to 10
2023-11-14T23:24:37.689Z,1700004277.689 [DAT](INFO): DAT read: user:4>

2023-11-14T23:24:37.690Z,1700004277.690 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-11-14T23:24:37.690Z,1700004277.690 [DAT](INFO): set local address to 10
2023-11-14T23:24:37.691Z,1700004277.691 [DAT](INFO): Setting time to: 23:24:37 And date to:11/14/2023
2023-11-14T23:24:37.941Z,1700004277.941 [DAT](INFO): DAT read: user:5>

2023-11-14T23:24:37.942Z,1700004277.942 [DAT](INFO): DAT read: Tue Nov 14, 2023  23:24:37  

2023-11-14T23:24:37.942Z,1700004277.942 [DAT](INFO): Local DAT time set to Tue Nov 14, 2023  23:24:37  

2023-11-14T23:24:48.365Z,1700004288.365 [NAL9602](INFO): Powering up NAL9602
2023-11-14T23:24:54.863Z,1700004294.863 [SBIT](IMPORTANT): Beginning Startup BIT
2023-11-14T23:24:54.904Z,1700004294.904 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-14T23:24:59.310Z,1700004299.310 [NAL9602](INFO): NAL9602 initialized
2023-11-14T23:25:05.834Z,1700004305.834 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000367
CHAN A1 (24V): 0.016222
CHAN A2 (12V): -0.001015
CHAN A3 (5V): -0.000438
CHAN B0 (3.3V): 0.000001
CHAN B1 (3.15aV): -0.000119
CHAN B2 (3.15bV): 0.000584
CHAN B3 (GND): 0.000621
OPEN: 0.005518
Full Scale: +/- 1 mA
2023-11-14T23:25:48.983Z,1700004348.983 [SBIT](IMPORTANT): SBIT PASSED
2023-11-14T23:25:48.983Z,1700004348.983 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-11-14T23:25:48.984Z,1700004348.984 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): Express linearApproximation downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=227.996383 cubic_centimeter;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): VerticalControl.kdPitchMass=0 second;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): VerticalControl.kiPitchMass=0.0015 reciprocal_second;

2023-11-14T23:25:48.985Z,1700004348.985 [SBIT](IMPORTANT): VerticalControl.kpPitchMass=0.01 none;

2023-11-14T23:25:48.986Z,1700004348.986 [SBIT](IMPORTANT): VerticalControl.massDeadband=0.1 millimeter;

2023-11-14T23:25:48.986Z,1700004348.986 [SBIT](IMPORTANT): VerticalControl.massDefault=3.881765 millimeter;

2023-11-14T23:25:49.413Z,1700004349.413 [MissionManager](IMPORTANT): Started mission Startup
2023-11-14T23:25:49.413Z,1700004349.413 [Startup] Running Loop=1
2023-11-14T23:25:49.413Z,1700004349.413 [Startup](DEBUG): Aggregate::initialize Startup
2023-11-14T23:25:49.413Z,1700004349.413 [Startup:A.GoToSurface] Running Loop=1
2023-11-14T23:25:49.414Z,1700004349.414 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-14T23:25:49.415Z,1700004349.415 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-14T23:25:49.415Z,1700004349.415 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-14T23:25:49.415Z,1700004349.415 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-14T23:25:49.416Z,1700004349.416 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-14T23:25:49.416Z,1700004349.416 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-14T23:25:49.418Z,1700004349.418 [Startup:StartupSatComms] Running Loop=1
2023-11-14T23:25:49.418Z,1700004349.418 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-11-14T23:25:49.418Z,1700004349.418 [Startup:StartupSatComms:A] Running Loop=1
2023-11-14T23:25:49.810Z,1700004349.810 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-11-14T23:26:47.318Z,1700004407.318 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004823
2023-11-14T23:26:49.595Z,1700004409.595 [Startup:StartupSatComms:A](INFO): Timed out from 2023-11-14T23:25:49.4Z
2023-11-14T23:26:49.595Z,1700004409.595 [Startup:StartupSatComms:A] Stopped
2023-11-14T23:26:49.595Z,1700004409.595 [Startup:StartupSatComms:B] Running Loop=1
2023-11-14T23:26:49.985Z,1700004409.985 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-14T23:26:57.215Z,1700004417.215 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231114T231010/Courier0007.lzma
2023-11-14T23:26:58.214Z,1700004418.214 [DataOverHttps](INFO): Moved sent file to Logs/20231114T231010/Courier0007.lzma.bak
2023-11-14T23:26:58.215Z,1700004418.215 [DataOverHttps](INFO): SBD MOMSN=19066227
2023-11-14T23:27:14.119Z,1700004434.119 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20231114T232409/Courier0000.lzma
2023-11-14T23:27:15.121Z,1700004435.121 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Courier0000.lzma.bak
2023-11-14T23:27:15.122Z,1700004435.122 [DataOverHttps](INFO): SBD MOMSN=19066229
2023-11-14T23:27:19.510Z,1700004439.510 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-11-14T23:27:19.510Z,1700004439.510 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:27:19.555Z,1700004439.555 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:27:19.915Z,1700004439.915 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:27:19.915Z,1700004439.915 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-11-14T23:27:31.328Z,1700004451.328 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20231114T231010/Express0008.lzma
2023-11-14T23:27:32.330Z,1700004452.330 [DataOverHttps](INFO): Moved sent file to Logs/20231114T231010/Express0008.lzma.bak
2023-11-14T23:27:32.330Z,1700004452.330 [DataOverHttps](INFO): SBD MOMSN=19066232
2023-11-14T23:27:48.093Z,1700004468.093 [DataOverHttps](INFO): Sending 1018 bytes from file Logs/20231114T232409/Express0001.lzma
2023-11-14T23:27:49.094Z,1700004469.094 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Express0001.lzma.bak
2023-11-14T23:27:49.094Z,1700004469.094 [DataOverHttps](INFO): SBD MOMSN=19066240
2023-11-14T23:27:49.779Z,1700004469.779 [Startup:StartupSatComms:B](INFO): Timed out from 2023-11-14T23:26:49.6Z
2023-11-14T23:27:49.779Z,1700004469.779 [Startup:StartupSatComms:B] Stopped
2023-11-14T23:27:49.779Z,1700004469.779 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-11-14T23:27:49.779Z,1700004469.779 [Startup:StartupSatComms] Stopped
2023-11-14T23:27:49.779Z,1700004469.779 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-11-14T23:27:49.780Z,1700004469.780 [Startup](INFO): Completed Startup
2023-11-14T23:27:49.780Z,1700004469.780 [MissionManager](INFO): Startup is completed.
2023-11-14T23:27:49.780Z,1700004469.780 [MissionManager](INFO): Uninitializing Mission Startup
2023-11-14T23:27:49.780Z,1700004469.780 [Startup] Stopped
2023-11-14T23:27:49.781Z,1700004469.781 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-11-14T23:27:49.781Z,1700004469.781 [Startup:A.GoToSurface] Stopped
2023-11-14T23:27:49.781Z,1700004469.781 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-14T23:27:50.191Z,1700004470.191 [MissionManager](IMPORTANT): Started mission Default
2023-11-14T23:27:50.191Z,1700004470.191 [Default] Running Loop=1
2023-11-14T23:27:50.191Z,1700004470.191 [Default](DEBUG): Aggregate::initialize Default
2023-11-14T23:27:50.191Z,1700004470.191 [Default:B.GoToSurface] Running Loop=1
2023-11-14T23:27:50.191Z,1700004470.191 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-14T23:27:50.192Z,1700004470.192 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-14T23:27:50.192Z,1700004470.192 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-14T23:27:50.192Z,1700004470.192 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-14T23:27:50.193Z,1700004470.193 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-14T23:27:50.193Z,1700004470.193 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-14T23:27:50.193Z,1700004470.193 [Default:A.Wait] Running Loop=1
2023-11-14T23:27:50.193Z,1700004470.193 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-11-14T23:28:03.535Z,1700004483.535 [Default:A.Wait](INFO): Done Waiting.
2023-11-14T23:28:03.535Z,1700004483.535 [Default:A.Wait] Stopped
2023-11-14T23:28:03.535Z,1700004483.535 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-11-14T23:28:03.911Z,1700004483.911 [Default:CheckIn] Running Loop=1
2023-11-14T23:28:03.911Z,1700004483.911 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-14T23:28:03.911Z,1700004483.911 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-14T23:28:04.319Z,1700004484.319 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-11-14T23:29:13.791Z,1700004553.791 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:29:20.254Z,1700004560.254 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:29:32.777Z,1700004572.777 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:29:45.705Z,1700004585.705 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:29:57.827Z,1700004597.827 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:30:02.682Z,1700004602.682 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-11-14T23:30:10.349Z,1700004610.349 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:30:20.466Z,1700004620.466 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-11-14T23:30:20.466Z,1700004620.466 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:30:20.513Z,1700004620.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:30:20.984Z,1700004620.984 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:30:20.984Z,1700004620.984 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-11-14T23:30:23.282Z,1700004623.282 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:30:35.888Z,1700004635.888 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:30:48.832Z,1700004648.832 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:31:02.160Z,1700004662.160 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:31:14.684Z,1700004674.684 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:31:28.114Z,1700004688.114 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-11-14T23:31:47.404Z,1700004707.404 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2023-11-14T23:31:47.407Z,1700004707.407 [BPC1](INFO): Received data from all battery sticks.
2023-11-14T23:33:04.183Z,1700004784.183 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-14T23:28:03.9Z
2023-11-14T23:33:04.183Z,1700004784.183 [Default:CheckIn:Read_GPS] Stopped
2023-11-14T23:33:04.183Z,1700004784.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-14T23:33:04.603Z,1700004784.603 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-11-14T23:33:11.384Z,1700004791.384 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20231114T232409/Courier0004.lzma
2023-11-14T23:33:12.385Z,1700004792.385 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Courier0004.lzma.bak
2023-11-14T23:33:12.386Z,1700004792.386 [DataOverHttps](INFO): SBD MOMSN=19066263
2023-11-14T23:33:21.566Z,1700004801.566 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-11-14T23:33:21.566Z,1700004801.566 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:33:21.577Z,1700004801.577 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:33:21.971Z,1700004801.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:33:21.971Z,1700004801.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-11-14T23:33:28.380Z,1700004808.380 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20231114T232409/Express0005.lzma
2023-11-14T23:33:29.382Z,1700004809.382 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Express0005.lzma.bak
2023-11-14T23:33:29.382Z,1700004809.382 [DataOverHttps](INFO): SBD MOMSN=19066265
2023-11-14T23:33:30.862Z,1700004810.862 [Default:CheckIn:Read_Iridium] Stopped
2023-11-14T23:33:30.862Z,1700004810.862 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-14T23:33:30.862Z,1700004810.862 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-14T23:35:01.738Z,1700004901.738 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-14T23:35:01.738Z,1700004901.738 [NAL9602] Data Fault, FailCount= 1
2023-11-14T23:35:01.738Z,1700004901.738 [NAL9602](ERROR): Data Fault
2023-11-14T23:35:01.780Z,1700004901.780 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-14T23:35:02.137Z,1700004902.137 [NAL9602](INFO): Powering down
2023-11-14T23:35:02.977Z,1700004902.977 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-14T23:35:02.977Z,1700004902.977 [NAL9602] No Fault, FailCount= 1
2023-11-14T23:35:32.441Z,1700004932.441 [NAL9602](INFO): Powering up NAL9602
2023-11-14T23:35:33.654Z,1700004933.654 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2023-11-14T23:35:33.654Z,1700004933.654 [RDI_Pathfinder] Communications Fault, FailCount= 1
2023-11-14T23:35:33.655Z,1700004933.655 [RDI_Pathfinder](ERROR): Communications Fault
2023-11-14T23:35:33.672Z,1700004933.672 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2023-11-14T23:35:34.128Z,1700004934.128 [RDI_Pathfinder](INFO): Powering down
2023-11-14T23:35:34.883Z,1700004934.883 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2023-11-14T23:35:34.900Z,1700004934.900 [RDI_Pathfinder] No Fault, FailCount= 1
2023-11-14T23:35:35.263Z,1700004935.263 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-11-14T23:35:43.348Z,1700004943.348 [NAL9602](INFO): NAL9602 initialized
2023-11-14T23:36:22.582Z,1700004982.582 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-11-14T23:36:22.582Z,1700004982.582 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:36:22.593Z,1700004982.593 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:36:23.042Z,1700004983.042 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:36:23.042Z,1700004983.042 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-11-14T23:38:31.486Z,1700005111.486 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-14T23:38:31.486Z,1700005111.486 [Default:CheckIn:C.Wait] Stopped
2023-11-14T23:38:31.486Z,1700005111.486 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-14T23:38:31.486Z,1700005111.486 [Default:CheckIn:D] Running Loop=1
2023-11-14T23:38:31.893Z,1700005111.893 [Default:CheckIn:D] Stopped
2023-11-14T23:38:31.893Z,1700005111.893 [Default:CheckIn:E] Running Loop=1
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.695040 min 
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn:E] Stopped
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn] Stopped
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn](INFO): Running loop #2
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn] Running Loop=2
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-14T23:38:32.303Z,1700005112.303 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-14T23:39:23.601Z,1700005163.601 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-11-14T23:39:23.601Z,1700005163.601 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:39:23.628Z,1700005163.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:39:24.016Z,1700005164.016 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:39:24.016Z,1700005164.016 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-11-14T23:42:24.647Z,1700005344.647 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-11-14T23:42:24.647Z,1700005344.647 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:42:24.690Z,1700005344.690 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:42:25.078Z,1700005345.078 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:42:25.078Z,1700005345.078 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-11-14T23:43:32.550Z,1700005412.550 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-14T23:38:32.3Z
2023-11-14T23:43:32.551Z,1700005412.551 [Default:CheckIn:Read_GPS] Stopped
2023-11-14T23:43:32.551Z,1700005412.551 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-14T23:43:40.224Z,1700005420.224 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231114T232409/Courier0007.lzma
2023-11-14T23:43:41.225Z,1700005421.225 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Courier0007.lzma.bak
2023-11-14T23:43:41.226Z,1700005421.226 [DataOverHttps](INFO): SBD MOMSN=19066269
2023-11-14T23:43:57.160Z,1700005437.160 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20231114T232409/Express0008.lzma
2023-11-14T23:43:58.162Z,1700005438.162 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Express0008.lzma.bak
2023-11-14T23:43:58.162Z,1700005438.162 [DataOverHttps](INFO): SBD MOMSN=19066271
2023-11-14T23:43:59.602Z,1700005439.602 [Default:CheckIn:Read_Iridium] Stopped
2023-11-14T23:43:59.602Z,1700005439.602 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-14T23:43:59.602Z,1700005439.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-14T23:45:25.685Z,1700005525.685 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-11-14T23:45:25.685Z,1700005525.685 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:45:25.696Z,1700005525.696 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:45:26.100Z,1700005526.100 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:45:26.100Z,1700005526.100 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-11-14T23:45:46.257Z,1700005546.257 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-14T23:45:46.257Z,1700005546.257 [NAL9602] Data Fault, FailCount= 2
2023-11-14T23:45:46.257Z,1700005546.257 [NAL9602](ERROR): Data Fault
2023-11-14T23:45:46.317Z,1700005546.317 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-14T23:45:46.653Z,1700005546.653 [NAL9602](INFO): Powering down
2023-11-14T23:45:47.523Z,1700005547.523 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-14T23:45:47.523Z,1700005547.523 [NAL9602] No Fault, FailCount= 2
2023-11-14T23:46:16.960Z,1700005576.960 [NAL9602](INFO): Powering up NAL9602
2023-11-14T23:46:27.889Z,1700005587.889 [NAL9602](INFO): NAL9602 initialized
2023-11-14T23:47:40.313Z,1700005660.313 [DataOverHttps](IMPORTANT): SBD MTMSN=20231114T234739
2023-11-14T23:47:47.866Z,1700005667.866 [DataOverHttps](INFO): Received command: gfscan
2023-11-14T23:47:47.925Z,1700005667.925 [CommandExec](IMPORTANT): got command gfscan
2023-11-14T23:47:47.942Z,1700005667.942 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-14T23:47:58.862Z,1700005678.862 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002498
CHAN A1 (24V): 0.020492
CHAN A2 (12V): 0.002364
CHAN A3 (5V): 0.001222
CHAN B0 (3.3V): 0.001788
CHAN B1 (3.15aV): 0.001035
CHAN B2 (3.15bV): 0.001175
CHAN B3 (GND): 0.001389
OPEN: 0.006132
Full Scale: +/- 1 mA
2023-11-14T23:48:26.782Z,1700005706.782 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-11-14T23:48:26.782Z,1700005706.782 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:48:26.800Z,1700005706.800 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:48:27.163Z,1700005707.163 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:48:27.163Z,1700005707.163 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-11-14T23:48:27.473Z,1700005707.473 [DataOverHttps](IMPORTANT): SBD MTMSN=20231114T234826
2023-11-14T23:48:35.124Z,1700005715.124 [DataOverHttps](INFO): Received command: run Maintenance/tank_ballast_and_trim.tl
2023-11-14T23:48:35.227Z,1700005715.227 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-11-14T23:48:35.227Z,1700005715.227 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-11-14T23:48:35.227Z,1700005715.227 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx
2023-11-14T23:48:35.347Z,1700005715.347 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2023-11-14T23:48:35.350Z,1700005715.350 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2023-11-14T23:48:35.353Z,1700005715.353 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2023-11-14T23:48:35.356Z,1700005715.356 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2023-11-14T23:48:35.359Z,1700005715.359 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2023-11-14T23:48:35.362Z,1700005715.362 [MissionManager](INFO): DefineArg ballast_and_trim.TargetDepthTimeout = 20.000000 min
2023-11-14T23:48:35.365Z,1700005715.365 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2023-11-14T23:48:35.368Z,1700005715.368 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2023-11-14T23:48:35.371Z,1700005715.371 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2023-11-14T23:48:35.374Z,1700005715.374 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2023-11-14T23:48:35.377Z,1700005715.377 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2023-11-14T23:48:35.379Z,1700005715.379 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2023-11-14T23:48:35.382Z,1700005715.382 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2023-11-14T23:48:35.383Z,1700005715.383 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-11-14T23:48:35.383Z,1700005715.383 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-11-14T23:48:35.899Z,1700005715.899 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2023-11-14T23:48:35.902Z,1700005715.902 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2023-11-14T23:48:35.904Z,1700005715.904 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2023-11-14T23:48:35.907Z,1700005715.907 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2023-11-14T23:48:35.910Z,1700005715.910 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2023-11-14T23:48:35.913Z,1700005715.913 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2023-11-14T23:48:35.915Z,1700005715.915 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2023-11-14T23:48:35.918Z,1700005715.918 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2023-11-14T23:48:35.921Z,1700005715.921 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2023-11-14T23:48:35.924Z,1700005715.924 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2023-11-14T23:48:35.927Z,1700005715.927 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2023-11-14T23:48:35.930Z,1700005715.930 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2023-11-14T23:48:35.956Z,1700005715.956 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2023-11-14T23:48:35.959Z,1700005715.959 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2023-11-14T23:48:35.977Z,1700005715.977 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-11-14T23:48:35.980Z,1700005715.980 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2023-11-14T23:48:35.992Z,1700005715.992 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2023-11-14T23:48:35.995Z,1700005715.995 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2023-11-14T23:48:36.007Z,1700005716.007 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2023-11-14T23:48:36.060Z,1700005716.060 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-11-14T23:48:36.063Z,1700005716.063 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 1 bool
2023-11-14T23:48:36.075Z,1700005716.075 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2023-11-14T23:48:36.096Z,1700005716.096 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2023-11-14T23:48:36.099Z,1700005716.099 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2023-11-14T23:48:36.106Z,1700005716.106 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2023-11-14T23:48:36.121Z,1700005716.121 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-11-14T23:48:36.130Z,1700005716.130 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2023-11-14T23:48:36.141Z,1700005716.141 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2023-11-14T23:48:36.148Z,1700005716.148 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2023-11-14T23:48:36.151Z,1700005716.151 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2023-11-14T23:48:36.159Z,1700005716.159 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2023-11-14T23:48:36.162Z,1700005716.162 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2023-11-14T23:48:36.165Z,1700005716.165 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2023-11-14T23:48:36.187Z,1700005716.187 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2023-11-14T23:48:36.190Z,1700005716.190 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2023-11-14T23:48:36.193Z,1700005716.193 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2023-11-14T23:48:36.206Z,1700005716.206 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2023-11-14T23:48:36.209Z,1700005716.209 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2023-11-14T23:48:36.212Z,1700005716.212 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2023-11-14T23:48:36.232Z,1700005716.232 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2023-11-14T23:48:36.235Z,1700005716.235 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2023-11-14T23:48:36.238Z,1700005716.238 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2023-11-14T23:48:36.241Z,1700005716.241 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2023-11-14T23:48:36.259Z,1700005716.259 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2023-11-14T23:48:36.262Z,1700005716.262 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2023-11-14T23:48:36.265Z,1700005716.265 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2023-11-14T23:48:36.291Z,1700005716.291 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2023-11-14T23:48:36.294Z,1700005716.294 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2023-11-14T23:48:36.297Z,1700005716.297 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2023-11-14T23:48:36.312Z,1700005716.312 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2023-11-14T23:48:36.315Z,1700005716.315 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2023-11-14T23:48:36.318Z,1700005716.318 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2023-11-14T23:48:36.333Z,1700005716.333 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2023-11-14T23:48:36.336Z,1700005716.336 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2023-11-14T23:48:36.339Z,1700005716.339 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2023-11-14T23:48:36.342Z,1700005716.342 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2023-11-14T23:48:36.361Z,1700005716.361 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2023-11-14T23:48:36.364Z,1700005716.364 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2023-11-14T23:48:36.367Z,1700005716.367 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2023-11-14T23:48:36.374Z,1700005716.374 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2023-11-14T23:48:36.382Z,1700005716.382 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2023-11-14T23:48:36.385Z,1700005716.385 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2023-11-14T23:48:36.397Z,1700005716.397 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2023-11-14T23:48:36.401Z,1700005716.401 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2023-11-14T23:48:36.412Z,1700005716.412 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2023-11-14T23:48:36.415Z,1700005716.415 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2023-11-14T23:48:36.454Z,1700005716.454 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2023-11-14T23:48:36.462Z,1700005716.462 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2023-11-14T23:48:36.481Z,1700005716.481 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2023-11-14T23:48:36.488Z,1700005716.488 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2023-11-14T23:48:36.491Z,1700005716.491 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2023-11-14T23:48:36.586Z,1700005716.586 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-14T23:48:36.620Z,1700005716.620 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-11-14T23:48:36.645Z,1700005716.645 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-14T23:48:36.676Z,1700005716.676 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-14T23:48:36.701Z,1700005716.701 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-11-14T23:48:36.777Z,1700005716.777 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-14T23:48:36.805Z,1700005716.805 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-11-14T23:48:36.861Z,1700005716.861 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-11-14T23:48:36.904Z,1700005716.904 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-11-14T23:48:36.915Z,1700005716.915 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-11-14T23:48:36.915Z,1700005716.915 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-11-14T23:48:36.947Z,1700005716.947 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool
2023-11-14T23:48:36.958Z,1700005716.958 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-11-14T23:48:36.972Z,1700005716.972 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl
2023-11-14T23:48:36.972Z,1700005716.972 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx
2023-11-14T23:48:37.157Z,1700005717.157 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2023-11-14T23:48:37.164Z,1700005717.164 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2023-11-14T23:48:37.172Z,1700005717.172 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2023-11-14T23:48:37.175Z,1700005717.175 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2023-11-14T23:48:37.182Z,1700005717.182 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2023-11-14T23:48:37.186Z,1700005717.186 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2023-11-14T23:48:37.197Z,1700005717.197 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2023-11-14T23:48:37.201Z,1700005717.201 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2023-11-14T23:48:37.220Z,1700005717.220 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2023-11-14T23:48:37.223Z,1700005717.223 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2023-11-14T23:48:37.262Z,1700005717.262 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2023-11-14T23:48:37.270Z,1700005717.270 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2023-11-14T23:48:37.289Z,1700005717.289 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2023-11-14T23:48:37.292Z,1700005717.292 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2023-11-14T23:48:37.332Z,1700005717.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2023-11-14T23:48:37.350Z,1700005717.350 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2023-11-14T23:48:37.358Z,1700005717.358 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2023-11-14T23:48:37.385Z,1700005717.385 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2023-11-14T23:48:37.393Z,1700005717.393 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2023-11-14T23:48:37.401Z,1700005717.401 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2023-11-14T23:48:37.423Z,1700005717.423 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2023-11-14T23:48:37.431Z,1700005717.431 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2023-11-14T23:48:37.438Z,1700005717.438 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2023-11-14T23:48:37.464Z,1700005717.464 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2023-11-14T23:48:37.465Z,1700005717.465 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute.
2023-11-14T23:48:37.480Z,1700005717.480 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2023-11-14T23:48:37.507Z,1700005717.507 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2023-11-14T23:48:37.513Z,1700005717.513 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.TargetDepthTimeout
2023-11-14T23:48:37.515Z,1700005717.515 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2023-11-14T23:48:37.521Z,1700005717.521 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2023-11-14T23:48:37.522Z,1700005717.522 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2023-11-14T23:48:37.525Z,1700005717.525 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2023-11-14T23:48:37.526Z,1700005717.526 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2023-11-14T23:48:37.536Z,1700005717.536 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2023-11-14T23:48:37.573Z,1700005717.573 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2023-11-14T23:48:37.577Z,1700005717.577 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2023-11-14T23:48:37.667Z,1700005717.667 [MissionManager](DEBUG): 
mission ballast_and_trim {
  arguments {
    MissionTimeout = 120 minute
      """
      Maximum duration of mission.
      """

    Depth1 = 7 meter
      """
      Target depth for ballast and trim.
      """

    Depth2 = NaN meter
      """
      Target depth to run a second ballast and trim, skipped if NaN.
      """

    DepthDeadband = 0.1 meter
      """
      How much vertical drift from the specified depth is allowed during the
      mission.
      """

    SurfaceThreshold = 2.5 meter
      """
      Surface threshold depth, relevant only to the ascent.
      """

    TargetDepthTimeout = 20 minute
      """
      Maximum wait time for the vehicle to reach the target ballast and trim
      depth.
      """

    SettleTime = 10 minute
      """
      How long to wait after reaching target depth before starting ballast and
      trim.
      """

    EstimationTimeout = 45 minute
      """
      Timeout for ballast and trim estimation.
      """

    MinEstimationTime = 10 minute
      """
      Minimum time to run ballast and trim estimation before determining
      convergence (must be shorter than timeout).
      """

    MassEstimationErrorBound = 0.25 millimeter
      """
      Desired range of the estimated sample mean from the true mean statistic
      (smaller values take longer to converge).
      """

    BuoyEstimationErrorBound = 15 cubic_centimeter
      """
      Desired range of the estimated sample mean from the true mean statistic
      (smaller values take longer to converge).
      """

    EstimationConfidence = 99.9 percent
      """
      Confidence level that the estimator has reached the desired error range
      (larger values take longer to converge).
      """

    WaitToFloat = 5 minute
      """
      Timeout for reaching surface threshold on float ascent, mission will end
      when reached.
      """
  }

  timeout duration=MissionTimeout

  insert Insert/Science.tl

  insert Insert/BackseatDriver.tl

  aggregate RunBallastAndTrim {
    run in sequence

    insert Insert/BallastAndTrim.tl {
      redefineArg InsertDepthDeadband = DepthDeadband
      redefineArg InsertApproachDepthTimeout = TargetDepthTimeout
      redefineArg InsertSettleTime = SettleTime
      redefineArg EstTimeout = EstimationTimeout
      redefineArg MinEstTime = MinEstimationTime
      redefineArg MassEstErrorBound = MassEstimationErrorBound
      redefineArg BuoyEstErrorBound = BuoyEstimationErrorBound
      redefineArg EstConfidence = EstimationConfidence
    }

    aggregate Depth1 {
      run in sequence

      break if ( isNaN ( Depth1 ) )

      assign in sequence BallastAndTrim:InsertDepth = Depth1

      call refId="BallastAndTrim"
    }

    aggregate Depth2 {
      run in sequence

      break if ( isNaN ( Depth2 ) )

      assign in sequence BallastAndTrim:InsertDepth = Depth2

      call refId="BallastAndTrim"
    }
  }

  aggregate Float_Up {
    """
    Float to surface instead of driving.
    """

    run in sequence

    break if (
      Universal:depth < Control:VerticalControl.surfaceThreshold
    )

    behavior Guidance:Buoyancy {
      run in parallel

      set position = Control:VerticalControl.buoyancyDefault
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = WaitToFloat
    }
  }
}


2023-11-14T23:48:37.679Z,1700005717.679 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-11-14T23:48:37.681Z,1700005717.681 [Default] Stopped
2023-11-14T23:48:37.681Z,1700005717.681 [Default](DEBUG): Aggregate::uninitialize Default
2023-11-14T23:48:37.681Z,1700005717.681 [Default:B.GoToSurface] Stopped
2023-11-14T23:48:37.681Z,1700005717.681 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-14T23:48:37.682Z,1700005717.682 [Default:CheckIn] Stopped
2023-11-14T23:48:37.682Z,1700005717.682 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-14T23:48:37.682Z,1700005717.682 [Default:CheckIn:C.Wait] Stopped
2023-11-14T23:48:37.682Z,1700005717.682 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-14T23:48:37.682Z,1700005717.682 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2023-11-14T23:48:37.682Z,1700005717.682 [ballast_and_trim] Running Loop=1
2023-11-14T23:48:37.682Z,1700005717.682 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2023-11-14T23:48:37.682Z,1700005717.682 [ballast_and_trim:Science] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:A] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:B] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:C] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:D] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:E] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:F] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-11-14T23:48:37.683Z,1700005717.683 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-14T23:48:37.696Z,1700005717.696 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2023-11-14T23:48:37.696Z,1700005717.696 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2023-11-14T23:48:37.696Z,1700005717.696 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-11-14T23:48:37.697Z,1700005717.697 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-11-14T23:48:37.697Z,1700005717.697 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2023-11-14T23:48:37.697Z,1700005717.697 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2023-11-14T23:48:37.697Z,1700005717.697 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-11-14T23:48:37.697Z,1700005717.697 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-14T23:48:37.699Z,1700005717.699 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2023-11-14T23:48:37.699Z,1700005717.699 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2023-11-14T23:48:37.704Z,1700005717.704 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-11-14T23:48:37.704Z,1700005717.704 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-14T23:48:37.711Z,1700005717.711 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2023-11-14T23:48:37.719Z,1700005717.719 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2023-11-14T23:48:37.728Z,1700005717.728 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-11-14T23:48:37.729Z,1700005717.729 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-11-14T23:48:37.730Z,1700005717.730 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2023-11-14T23:48:37.730Z,1700005717.730 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2023-11-14T23:48:37.730Z,1700005717.730 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-11-14T23:48:37.731Z,1700005717.731 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-14T23:48:37.739Z,1700005717.739 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2023-11-14T23:48:37.739Z,1700005717.739 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2023-11-14T23:48:37.754Z,1700005717.754 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-11-14T23:48:37.755Z,1700005717.755 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-11-14T23:48:37.768Z,1700005717.768 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2023-11-14T23:48:37.769Z,1700005717.769 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2023-11-14T23:48:37.770Z,1700005717.770 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-11-14T23:48:37.770Z,1700005717.770 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-11-14T23:48:37.771Z,1700005717.771 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1
2023-11-14T23:48:37.776Z,1700005717.776 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger
2023-11-14T23:48:37.776Z,1700005717.776 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-11-14T23:48:37.776Z,1700005717.776 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-11-14T23:48:37.776Z,1700005717.776 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2023-11-14T23:48:37.777Z,1700005717.777 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2023-11-14T23:48:37.778Z,1700005717.778 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-11-14T23:48:37.778Z,1700005717.778 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2023-11-14T23:48:37.778Z,1700005717.778 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2023-11-14T23:48:37.778Z,1700005717.778 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2023-11-14T23:48:37.779Z,1700005717.779 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-11-14T23:48:37.779Z,1700005717.779 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-11-14T23:48:37.779Z,1700005717.779 [ballast_and_trim:Science] Running Loop=1
2023-11-14T23:48:37.784Z,1700005717.784 [ballast_and_trim:Science:ChlThreshTrigger] Stopped
2023-11-14T23:48:37.784Z,1700005717.784 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2023-11-14T23:48:37.785Z,1700005717.785 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectOil] Stopped
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-11-14T23:48:37.786Z,1700005717.786 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:PeakDetectChl] Stopped
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-11-14T23:48:37.787Z,1700005717.787 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-11-14T23:48:37.793Z,1700005717.793 [ballast_and_trim:Science:Read_Oil] Stopped
2023-11-14T23:48:37.793Z,1700005717.793 [ballast_and_trim:Science:F] Running Loop=1
2023-11-14T23:48:37.794Z,1700005717.794 [ballast_and_trim:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence
2023-11-14T23:48:37.794Z,1700005717.794 [ballast_and_trim:Science:E] Running Loop=1
2023-11-14T23:48:37.795Z,1700005717.795 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-11-14T23:48:37.795Z,1700005717.795 [ballast_and_trim:Science:D] Stopped
2023-11-14T23:48:37.795Z,1700005717.795 [ballast_and_trim:Science:C] Running Loop=1
2023-11-14T23:48:37.796Z,1700005717.796 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-11-14T23:48:37.797Z,1700005717.797 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-11-14T23:48:37.797Z,1700005717.797 [ballast_and_trim:Science:B] Running Loop=1
2023-11-14T23:48:37.798Z,1700005717.798 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-11-14T23:48:37.798Z,1700005717.798 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-11-14T23:48:37.798Z,1700005717.798 [ballast_and_trim:Science:A] Stopped
2023-11-14T23:48:38.080Z,1700005718.080 [WetLabsBB2FL](INFO): Powering up
2023-11-14T23:48:38.107Z,1700005718.107 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2023-11-14T23:48:38.112Z,1700005718.112 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-11-14T23:48:38.112Z,1700005718.112 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-11-14T23:48:38.112Z,1700005718.112 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-11-14T23:48:38.112Z,1700005718.112 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-11-14T23:48:38.112Z,1700005718.112 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-11-14T23:48:38.112Z,1700005718.112 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-11-14T23:48:38.112Z,1700005718.112 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2023-11-14T23:48:38.114Z,1700005718.114 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2023-11-14T23:48:38.114Z,1700005718.114 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2023-11-14T23:48:38.114Z,1700005718.114 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2023-11-14T23:48:38.114Z,1700005718.114 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-11-14T23:48:38.114Z,1700005718.114 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-11-14T23:48:38.114Z,1700005718.114 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2023-11-14T23:48:38.115Z,1700005718.115 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-11-14T23:48:38.115Z,1700005718.115 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2023-11-14T23:48:38.115Z,1700005718.115 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2023-11-14T23:48:38.115Z,1700005718.115 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2023-11-14T23:48:38.115Z,1700005718.115 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-11-14T23:48:38.115Z,1700005718.115 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2023-11-14T23:48:38.115Z,1700005718.115 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2023-11-14T23:48:38.120Z,1700005718.120 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2023-11-14T23:48:38.120Z,1700005718.120 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-11-14T23:48:38.120Z,1700005718.120 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2023-11-14T23:48:38.120Z,1700005718.120 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-11-14T23:48:38.120Z,1700005718.120 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-11-14T23:48:38.121Z,1700005718.121 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-11-14T23:48:38.121Z,1700005718.121 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-11-14T23:48:38.121Z,1700005718.121 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-11-14T23:48:38.301Z,1700005718.301 [WetLabsUBAT](INFO): Powering up
2023-11-14T23:48:38.479Z,1700005718.479 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2023-11-14T23:48:38.479Z,1700005718.479 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2023-11-14T23:48:38.479Z,1700005718.479 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2023-11-14T23:48:38.479Z,1700005718.479 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2023-11-14T23:48:38.479Z,1700005718.479 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2023-11-14T23:48:38.479Z,1700005718.479 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-11-14T23:48:38.484Z,1700005718.484 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2023-11-14T23:48:38.484Z,1700005718.484 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2023-11-14T23:48:38.484Z,1700005718.484 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-11-14T23:48:38.484Z,1700005718.484 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2023-11-14T23:48:38.484Z,1700005718.484 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-11-14T23:48:38.898Z,1700005718.898 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-11-14T23:48:38.899Z,1700005718.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2023-11-14T23:48:38.899Z,1700005718.899 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2023-11-14T23:48:39.275Z,1700005719.275 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-11-14T23:48:39.276Z,1700005719.276 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2023-11-14T23:48:39.276Z,1700005719.276 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2023-11-14T23:48:39.276Z,1700005719.276 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2023-11-14T23:48:39.276Z,1700005719.276 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2023-11-14T23:48:41.409Z,1700005721.409 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-11-14T23:48:43.418Z,1700005723.418 [CTD_Seabird](ERROR): Salinity reading out of range: 15.716566 psu
2023-11-14T23:48:43.421Z,1700005723.421 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-14T23:48:48.494Z,1700005728.494 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s.
2023-11-14T23:49:30.613Z,1700005770.613 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,        +0.05,     ,        +0.08,   9.88,  0.00
2023-11-14T23:50:45.556Z,1700005845.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-14T23:50:45.741Z,1700005845.741 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for water velocity. Device response is::WS,-32768,-32768,-3

2023-11-14T23:50:48.568Z,1700005848.568 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.259323
2023-11-14T23:51:20.696Z,1700005880.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-14T23:51:27.795Z,1700005887.795 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-11-14T23:51:27.795Z,1700005887.795 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:51:27.813Z,1700005887.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:51:28.261Z,1700005888.261 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:51:28.261Z,1700005888.261 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-11-14T23:51:55.836Z,1700005915.836 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-14T23:52:25.823Z,1700005945.823 [Radio_Surface](INFO): Powering down
2023-11-14T23:52:30.984Z,1700005950.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-14T23:52:31.988Z,1700005951.988 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-11-14T23:53:49.606Z,1700006029.606 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2023-11-14T23:53:49.606Z,1700006029.606 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-11-14T23:53:49.606Z,1700006029.606 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2023-11-14T23:53:49.606Z,1700006029.606 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-11-14T23:53:49.607Z,1700006029.607 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2023-11-14T23:53:49.607Z,1700006029.607 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2023-11-14T23:53:49.607Z,1700006029.607 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-11-14T23:53:49.607Z,1700006029.607 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2023-11-14T23:53:49.607Z,1700006029.607 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-11-14T23:53:49.607Z,1700006029.607 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2023-11-14T23:53:49.607Z,1700006029.607 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2023-11-14T23:53:49.608Z,1700006029.608 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-11-14T23:53:49.608Z,1700006029.608 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-11-14T23:53:49.608Z,1700006029.608 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2023-11-14T23:53:49.609Z,1700006029.609 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-11-14T23:53:49.609Z,1700006029.609 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2023-11-14T23:53:49.609Z,1700006029.609 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2023-11-14T23:53:49.991Z,1700006029.991 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.045922 m 
2023-11-14T23:53:49.991Z,1700006029.991 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2023-11-14T23:53:49.991Z,1700006029.991 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2023-11-14T23:53:49.992Z,1700006029.992 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2023-11-14T23:53:49.992Z,1700006029.992 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-11-14T23:53:49.992Z,1700006029.992 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-11-14T23:54:21.075Z,1700006061.075 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23111415552652,35.0, +20.2,   0.0,1522.2, ,  0.00,  11.70,  12.05,  11.70,  11.70
2023-11-14T23:54:28.773Z,1700006068.773 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-11-14T23:54:28.773Z,1700006068.773 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:54:28.836Z,1700006068.836 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:54:29.388Z,1700006069.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:54:29.388Z,1700006069.388 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-11-14T23:56:19.317Z,1700006179.317 [CTD_Seabird](ERROR): Failed to parse device response: .92
2023-11-14T23:56:28.730Z,1700006188.730 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-14T23:56:28.730Z,1700006188.730 [NAL9602] Data Fault, FailCount= 3
2023-11-14T23:56:28.730Z,1700006188.730 [NAL9602](ERROR): Data Fault
2023-11-14T23:56:28.920Z,1700006188.920 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-14T23:56:29.134Z,1700006189.134 [NAL9602](INFO): Powering down
2023-11-14T23:56:29.993Z,1700006189.993 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-14T23:56:29.993Z,1700006189.993 [NAL9602] No Fault, FailCount= 3
2023-11-14T23:56:59.437Z,1700006219.437 [NAL9602](INFO): Powering up NAL9602
2023-11-14T23:57:10.338Z,1700006230.338 [NAL9602](INFO): NAL9602 initialized
2023-11-14T23:57:21.259Z,1700006241.259 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23111415582668,35.0, +20.2,   0.0,1522.2, ,  0.00,  33.74,  34.60,  33.39
2023-11-14T23:57:29.757Z,1700006249.757 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-11-14T23:57:29.757Z,1700006249.757 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-14T23:57:29.857Z,1700006249.857 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-14T23:57:30.234Z,1700006250.234 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-14T23:57:30.234Z,1700006250.234 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-11-14T23:59:36.608Z,1700006376.608 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-11-14T23:59:46.312Z,1700006386.312 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23111416005181,35.0, +20.2,   0.0,152
2023-11-15T00:00:30.826Z,1700006430.826 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-11-15T00:00:30.826Z,1700006430.826 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:00:30.843Z,1700006430.843 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:00:31.205Z,1700006431.205 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:00:31.205Z,1700006431.205 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-11-15T00:00:55.409Z,1700006455.409 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23111416020083,35.0   0.0,1522.2,  0
2023-11-15T00:00:56.216Z,1700006456.216 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23111416020186,35.0, +20.2,   0.0,15
2023-11-15T00:03:31.782Z,1700006611.782 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-11-15T00:03:31.783Z,1700006611.783 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:03:31.869Z,1700006611.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:03:32.258Z,1700006612.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:03:32.259Z,1700006612.259 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-11-15T00:03:50.805Z,1700006630.805 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](INFO): Done Waiting.
2023-11-15T00:03:50.805Z,1700006630.805 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2023-11-15T00:03:50.805Z,1700006630.805 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2023-11-15T00:03:50.805Z,1700006630.805 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Running Loop=1
2023-11-15T00:03:51.147Z,1700006631.147 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](INFO): Executing command gfscan
2023-11-15T00:03:51.147Z,1700006631.147 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute] Stopped
2023-11-15T00:03:51.147Z,1700006631.147 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Running Loop=1
2023-11-15T00:03:51.225Z,1700006631.225 [CommandExec](IMPORTANT): got command gfscan
2023-11-15T00:03:51.544Z,1700006631.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F](INFO): Running ballast and trim. Depth = 7.323378 m 
2023-11-15T00:03:51.544Z,1700006631.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F] Stopped
2023-11-15T00:03:51.544Z,1700006631.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Running Loop=1
2023-11-15T00:03:51.544Z,1700006631.544 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Initializing BallastAndTrim.
2023-11-15T00:03:51.575Z,1700006631.575 [CBIT](IMPORTANT): Beginning ground fault scan
2023-11-15T00:03:52.193Z,1700006632.193 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:52.195Z,1700006632.195 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:03:53.198Z,1700006633.198 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:53.202Z,1700006633.202 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:03:54.202Z,1700006634.202 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:54.203Z,1700006634.203 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:03:55.206Z,1700006635.206 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:55.207Z,1700006635.207 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:03:56.209Z,1700006636.209 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:56.211Z,1700006636.211 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:03:57.213Z,1700006637.213 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:57.215Z,1700006637.215 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:03:58.217Z,1700006638.217 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:58.219Z,1700006638.219 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:03:59.221Z,1700006639.221 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:03:59.223Z,1700006639.223 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:04:00.225Z,1700006640.225 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:04:00.227Z,1700006640.227 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:04:01.230Z,1700006641.230 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:04:01.231Z,1700006641.231 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:04:02.233Z,1700006642.233 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-11-15T00:04:02.235Z,1700006642.235 [CTD_Seabird](INFO): some bad data, not updating bins
2023-11-15T00:04:02.488Z,1700006642.488 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.016346
CHAN A1 (24V): 0.046203
CHAN A2 (12V): 0.008850
CHAN A3 (5V): -0.004150
CHAN B0 (3.3V): -0.005330
CHAN B1 (3.15aV): -0.006573
CHAN B2 (3.15bV): -0.006952
CHAN B3 (GND): -0.013408
OPEN: 0.001718
Full Scale: +/- 1 mA
2023-11-15T00:05:29.576Z,1700006729.576 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-11-15T00:06:32.764Z,1700006792.764 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-11-15T00:06:32.764Z,1700006792.764 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:06:32.781Z,1700006792.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:06:33.235Z,1700006793.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:06:33.235Z,1700006793.235 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-11-15T00:06:37.724Z,1700006797.724 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-11-15T00:07:11.924Z,1700006831.924 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-15T00:07:11.924Z,1700006831.924 [NAL9602] Data Fault, FailCount= 4
2023-11-15T00:07:11.924Z,1700006831.924 [NAL9602](ERROR): Data Fault
2023-11-15T00:07:12.042Z,1700006832.042 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-15T00:07:12.321Z,1700006832.321 [NAL9602](INFO): Powering down
2023-11-15T00:07:13.205Z,1700006833.205 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-15T00:07:13.205Z,1700006833.205 [NAL9602] No Fault, FailCount= 4
2023-11-15T00:07:42.625Z,1700006862.625 [NAL9602](INFO): Powering up NAL9602
2023-11-15T00:07:53.526Z,1700006873.526 [NAL9602](INFO): NAL9602 initialized
2023-11-15T00:08:34.664Z,1700006914.664 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-11-15T00:09:16.799Z,1700006956.799 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,     ,        +3.19,       -7.27,   2.60,  0.00
2023-11-15T00:09:22.512Z,1700006962.512 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-11-15T00:09:22.877Z,1700006962.877 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD,       -0.55,       -1.18,        +      +3.19,       -7.38,   2.50,  0.00
2023-11-15T00:09:33.761Z,1700006973.761 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-11-15T00:09:33.761Z,1700006973.761 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:09:33.908Z,1700006973.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:09:34.191Z,1700006974.191 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:09:34.191Z,1700006974.191 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-11-15T00:10:26.694Z,1700007026.694 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,    +1,    +2, 
2023-11-15T00:10:31.930Z,1700007031.930 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23111416113735,0.2,   0.0,1522.2,  0
2023-11-15T00:11:25.672Z,1700007085.672 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS6123116,35.0, +20.2,   0.0,1522.2,  0
2023-11-15T00:12:34.798Z,1700007154.798 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2023-11-15T00:12:34.798Z,1700007154.798 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:12:34.820Z,1700007154.820 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:12:35.181Z,1700007155.181 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:12:35.181Z,1700007155.181 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2023-11-15T00:12:51.785Z,1700007171.785 [RDI_Pathfinder](ERROR): only read 0 of 4 data items for bottom velocity. Device response is::BS    +1,   -16,A
2023-11-15T00:15:35.737Z,1700007335.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2023-11-15T00:15:35.737Z,1700007335.737 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:15:35.754Z,1700007335.754 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:15:36.227Z,1700007336.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:15:36.244Z,1700007336.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2023-11-15T00:17:55.938Z,1700007475.938 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-11-15T00:17:55.938Z,1700007475.938 [NAL9602] Data Fault, FailCount= 5
2023-11-15T00:17:55.938Z,1700007475.938 [NAL9602](ERROR): Data Fault
2023-11-15T00:17:56.008Z,1700007476.008 [CBIT](ERROR): Data Fault in component: NAL9602
2023-11-15T00:17:56.009Z,1700007476.009 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-11-15T00:17:56.341Z,1700007476.341 [NAL9602](INFO): Powering down
2023-11-15T00:17:56.406Z,1700007476.406 [CommandExec](FAULT): Scheduling is paused
2023-11-15T00:17:56.406Z,1700007476.406 [CBIT](INFO): Critical error at 20231115T001756
2023-11-15T00:17:56.406Z,1700007476.406 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-11-15T00:17:56.781Z,1700007476.781 [MissionManager](INFO): MissionManager is completed.
2023-11-15T00:17:56.782Z,1700007476.782 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science:B] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science:C] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science:E] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science:F] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science:OceanCurrent] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2023-11-15T00:17:56.782Z,1700007476.782 [ballast_and_trim:BackseatDriver] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2023-11-15T00:17:56.783Z,1700007476.783 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2023-11-15T00:17:56.788Z,1700007476.788 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-11-15T00:17:56.788Z,1700007476.788 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2023-11-15T00:17:56.788Z,1700007476.788 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2023-11-15T00:17:56.788Z,1700007476.788 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2023-11-15T00:17:56.788Z,1700007476.788 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.] Stopped
2023-11-15T00:17:56.789Z,1700007476.789 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](FAULT): BallastAndTrim aborted prematurely - reporting best estimate. Estimation time: 14.08 minutes.
2023-11-15T00:17:56.790Z,1700007476.790 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated buoyancyNeutral: 201.867450 +/- 17.701564 cc (conf. level 99.90%, sigma: 24.876679 cc).
2023-11-15T00:17:56.791Z,1700007476.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](IMPORTANT): Estimated massDefault: 7.444054 +/- 0.252851 mm (conf. level 99.90%, sigma: 0.355341 mm).
2023-11-15T00:17:56.791Z,1700007476.791 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](INFO): Uninitializing BallastAndTrim.
2023-11-15T00:17:56.791Z,1700007476.791 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2023-11-15T00:17:56.796Z,1700007476.796 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-11-15T00:17:56.796Z,1700007476.796 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2023-11-15T00:17:56.796Z,1700007476.796 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-11-15T00:17:57.166Z,1700007477.166 [MissionManager](IMPORTANT): Started mission Default
2023-11-15T00:17:57.166Z,1700007477.166 [Default] Running Loop=1
2023-11-15T00:17:57.167Z,1700007477.167 [Default](DEBUG): Aggregate::initialize Default
2023-11-15T00:17:57.167Z,1700007477.167 [Default:B.GoToSurface] Running Loop=1
2023-11-15T00:17:57.167Z,1700007477.167 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-11-15T00:17:57.167Z,1700007477.167 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-11-15T00:17:57.167Z,1700007477.167 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-11-15T00:17:57.168Z,1700007477.168 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-11-15T00:17:57.168Z,1700007477.168 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-11-15T00:17:57.169Z,1700007477.169 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-11-15T00:17:57.169Z,1700007477.169 [Default:A.Wait] Running Loop=1
2023-11-15T00:17:57.169Z,1700007477.169 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-11-15T00:17:57.317Z,1700007477.317 [WetLabsBB2FL](INFO): Powering down
2023-11-15T00:17:57.420Z,1700007477.420 [WetLabsUBAT](INFO): Powering down
2023-11-15T00:17:57.588Z,1700007477.588 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -20.00 mm (1 active estimators).
2023-11-15T00:17:57.844Z,1700007477.844 [Radio_Surface](INFO): Powering up
2023-11-15T00:18:03.292Z,1700007483.292 [DataOverHttps](INFO): Radio surface powered ON.
2023-11-15T00:18:10.509Z,1700007490.509 [Default:A.Wait](INFO): Done Waiting.
2023-11-15T00:18:10.509Z,1700007490.509 [Default:A.Wait] Stopped
2023-11-15T00:18:10.510Z,1700007490.510 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-11-15T00:18:33.424Z,1700007513.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-15T00:18:36.750Z,1700007516.750 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2023-11-15T00:18:36.750Z,1700007516.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:18:36.846Z,1700007516.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:18:37.244Z,1700007517.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:18:37.244Z,1700007517.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2023-11-15T00:19:08.564Z,1700007548.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-15T00:19:11.899Z,1700007551.899 [Default:CheckIn] Running Loop=1
2023-11-15T00:19:11.899Z,1700007551.899 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-15T00:19:11.899Z,1700007551.899 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-15T00:19:43.708Z,1700007583.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-15T00:19:56.005Z,1700007596.005 [CBIT](INFO): Clearing failed state for component NAL9602
2023-11-15T00:19:56.005Z,1700007596.005 [NAL9602] No Fault, FailCount= 5
2023-11-15T00:19:56.326Z,1700007596.326 [NAL9602](INFO): Powering up NAL9602
2023-11-15T00:20:07.234Z,1700007607.234 [NAL9602](INFO): NAL9602 initialized
2023-11-15T00:20:18.848Z,1700007618.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-15T00:20:54.008Z,1700007654.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-11-15T00:21:27.892Z,1700007687.892 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003430
2023-11-15T00:21:37.796Z,1700007697.796 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2023-11-15T00:21:37.796Z,1700007697.796 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:21:37.853Z,1700007697.853 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:21:38.272Z,1700007698.272 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:21:38.272Z,1700007698.272 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2023-11-15T00:24:12.072Z,1700007852.072 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-11-15T00:19:11.9Z
2023-11-15T00:24:12.072Z,1700007852.072 [Default:CheckIn:Read_GPS] Stopped
2023-11-15T00:24:12.073Z,1700007852.073 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-15T00:24:20.607Z,1700007860.607 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20231114T232409/Courier0010.lzma
2023-11-15T00:24:21.610Z,1700007861.610 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Courier0010.lzma.bak
2023-11-15T00:24:21.610Z,1700007861.610 [DataOverHttps](INFO): SBD MOMSN=19066437
2023-11-15T00:24:38.805Z,1700007878.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2023-11-15T00:24:38.805Z,1700007878.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:24:38.842Z,1700007878.842 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:24:39.215Z,1700007879.215 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:24:39.215Z,1700007879.215 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2023-11-15T00:24:41.216Z,1700007881.216 [DataOverHttps](INFO): Sending 1370 bytes from file Logs/20231114T232409/Express0011.lzma
2023-11-15T00:24:42.214Z,1700007882.214 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Express0011.lzma.bak
2023-11-15T00:24:42.214Z,1700007882.214 [DataOverHttps](INFO): SBD MOMSN=19066440
2023-11-15T00:24:43.642Z,1700007883.642 [Default:CheckIn:Read_Iridium] Stopped
2023-11-15T00:24:43.642Z,1700007883.642 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-15T00:24:43.642Z,1700007883.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-15T00:26:47.669Z,1700008007.669 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for water mass range. Device response is::WD,       -0.22,        +1.87, 
2023-11-15T00:27:38.546Z,1700008058.546 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS.0,1522.5,  0
2023-11-15T00:27:39.771Z,1700008059.771 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2023-11-15T00:27:39.771Z,1700008059.771 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-11-15T00:27:39.797Z,1700008059.797 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-11-15T00:27:40.179Z,1700008060.179 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-11-15T00:27:40.179Z,1700008060.179 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2023-11-15T00:29:22.843Z,1700008162.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002922.00,A,3648.16490,N,12147.28505,W,1.400,25.19,151123,,,A*40
2023-11-15T00:29:22.846Z,1700008162.846 [NAL9602](INFO): GPS fix at 20231115T002922: (36.802748, -121.788084)
2023-11-15T00:29:44.295Z,1700008184.295 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-11-15T00:29:44.295Z,1700008184.295 [Default:CheckIn:C.Wait] Stopped
2023-11-15T00:29:44.295Z,1700008184.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-15T00:29:44.295Z,1700008184.295 [Default:CheckIn:D] Running Loop=1
2023-11-15T00:29:44.670Z,1700008184.670 [Default:CheckIn:D] Stopped
2023-11-15T00:29:44.670Z,1700008184.670 [Default:CheckIn:E] Running Loop=1
2023-11-15T00:29:45.095Z,1700008185.095 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.791726 min 
2023-11-15T00:29:45.095Z,1700008185.095 [Default:CheckIn:E] Stopped
2023-11-15T00:29:45.095Z,1700008185.095 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-11-15T00:29:45.095Z,1700008185.095 [Default:CheckIn] Stopped
2023-11-15T00:29:45.095Z,1700008185.095 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-15T00:29:45.095Z,1700008185.095 [Default:CheckIn](INFO): Running loop #2
2023-11-15T00:29:45.096Z,1700008185.096 [Default:CheckIn] Running Loop=2
2023-11-15T00:29:45.096Z,1700008185.096 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-11-15T00:29:45.096Z,1700008185.096 [Default:CheckIn:Read_GPS] Running Loop=1
2023-11-15T00:29:47.082Z,1700008187.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002946.00,A,3648.15648,N,12147.28215,W,1.594,25.19,151123,,,A*4C
2023-11-15T00:29:47.096Z,1700008187.096 [NAL9602](INFO): GPS fix at 20231115T002946: (36.802608, -121.788036)
2023-11-15T00:29:47.127Z,1700008187.127 [Default:CheckIn:Read_GPS] Stopped
2023-11-15T00:29:47.127Z,1700008187.127 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-11-15T00:29:55.284Z,1700008195.284 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231114T232409/Courier0016.lzma
2023-11-15T00:29:56.286Z,1700008196.286 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Courier0016.lzma.bak
2023-11-15T00:29:56.286Z,1700008196.286 [DataOverHttps](INFO): SBD MOMSN=19066468
2023-11-15T00:30:12.960Z,1700008212.960 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20231114T232409/Express0017.lzma
2023-11-15T00:30:13.962Z,1700008213.962 [DataOverHttps](INFO): Moved sent file to Logs/20231114T232409/Express0017.lzma.bak
2023-11-15T00:30:13.962Z,1700008213.962 [DataOverHttps](INFO): SBD MOMSN=19066471
2023-11-15T00:30:15.460Z,1700008215.460 [Default:CheckIn:Read_Iridium] Stopped
2023-11-15T00:30:15.460Z,1700008215.460 [Default:CheckIn:C.Wait] Running Loop=1
2023-11-15T00:30:15.460Z,1700008215.460 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-11-15T00:30:19.475Z,1700008219.475 [NAL9602](INFO): Not Powering down - fast GPS
2023-11-15T00:34:32.393Z,1700008472.393 [DataOverHttps](IMPORTANT): SBD MTMSN=20231115T003431
2023-11-15T00:34:40.604Z,1700008480.604 [DataOverHttps](INFO): Received command: restart app
2023-11-15T00:34:40.693Z,1700008480.693 [CommandExec](IMPORTANT): got command restart application
2023-11-15T00:34:41.696Z,1700008481.696 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:41.696Z,1700008481.696 [CommandExec](INFO): Uninitializing the command executive.
2023-11-15T00:34:41.696Z,1700008481.696 [CommandExec](INFO): Uninitializing the command scheduler.
2023-11-15T00:34:41.697Z,1700008481.697 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:41.876Z,1700008481.876 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-11-15T00:34:41.876Z,1700008481.876 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-11-15T00:34:41.876Z,1700008481.876 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:41.877Z,1700008481.877 [NavChartDb](INFO): Join timeout helper Thread ID is 1705
2023-11-15T00:34:42.052Z,1700008482.052 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:42.052Z,1700008482.052 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:42.056Z,1700008482.056 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-11-15T00:34:42.056Z,1700008482.056 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:42.056Z,1700008482.056 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1706
2023-11-15T00:34:42.288Z,1700008482.288 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:42.288Z,1700008482.288 [WetLabsUBAT](INFO): Powering down
2023-11-15T00:34:42.289Z,1700008482.289 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:42.299Z,1700008482.299 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-11-15T00:34:42.299Z,1700008482.299 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:42.299Z,1700008482.299 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1707
2023-11-15T00:34:42.708Z,1700008482.708 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:42.709Z,1700008482.709 [WetLabsBB2FL](INFO): Powering down
2023-11-15T00:34:42.709Z,1700008482.709 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:42.728Z,1700008482.728 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-11-15T00:34:42.728Z,1700008482.728 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:42.728Z,1700008482.728 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1708
2023-11-15T00:34:43.024Z,1700008483.024 [CTD_Seabird](INFO): Powering down
2023-11-15T00:34:43.036Z,1700008483.036 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:43.036Z,1700008483.036 [CTD_Seabird](INFO): Powering down
2023-11-15T00:34:43.048Z,1700008483.048 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:43.048Z,1700008483.048 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-11-15T00:34:43.048Z,1700008483.048 [DAT ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:43.049Z,1700008483.049 [DAT](INFO): Join timeout helper Thread ID is 1709
2023-11-15T00:34:43.068Z,1700008483.068 [DAT](INFO): Powering down
2023-11-15T00:34:43.140Z,1700008483.140 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:43.141Z,1700008483.141 [DAT](INFO): Powering down
2023-11-15T00:34:43.141Z,1700008483.141 [DAT ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:43.149Z,1700008483.149 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-11-15T00:34:43.149Z,1700008483.149 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:43.149Z,1700008483.149 [Radio_Surface](INFO): Join timeout helper Thread ID is 1710
2023-11-15T00:34:43.184Z,1700008483.184 [Radio_Surface](INFO): Powering down
2023-11-15T00:34:43.185Z,1700008483.185 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:43.185Z,1700008483.185 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:43.189Z,1700008483.189 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-11-15T00:34:43.189Z,1700008483.189 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:43.189Z,1700008483.189 [Onboard](INFO): Join timeout helper Thread ID is 1711
2023-11-15T00:34:43.612Z,1700008483.612 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-11-15T00:34:47.892Z,1700008487.892 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:47.892Z,1700008487.892 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:47.912Z,1700008487.912 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-11-15T00:34:47.913Z,1700008487.913 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:47.913Z,1700008487.913 [DataOverHttps](INFO): Join timeout helper Thread ID is 1712
2023-11-15T00:34:48.632Z,1700008488.632 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:48.632Z,1700008488.632 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.637Z,1700008488.637 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-11-15T00:34:48.637Z,1700008488.637 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.637Z,1700008488.637 [BackseatComponent](INFO): Join timeout helper Thread ID is 1713
2023-11-15T00:34:48.644Z,1700008488.644 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:48.644Z,1700008488.644 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.666Z,1700008488.666 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-11-15T00:34:48.666Z,1700008488.666 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.666Z,1700008488.666 [logger](INFO): Join timeout helper Thread ID is 1714
2023-11-15T00:34:48.680Z,1700008488.680 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:48.680Z,1700008488.680 [logger ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.684Z,1700008488.684 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-11-15T00:34:48.684Z,1700008488.684 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.685Z,1700008488.685 [CommandLine](INFO): Join timeout helper Thread ID is 1715
2023-11-15T00:34:48.736Z,1700008488.736 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:48.736Z,1700008488.736 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.744Z,1700008488.744 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-11-15T00:34:48.744Z,1700008488.744 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.745Z,1700008488.745 [CommandExec](INFO): Join timeout helper Thread ID is 1716
2023-11-15T00:34:48.746Z,1700008488.746 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-11-15T00:34:48.746Z,1700008488.746 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:48.746Z,1700008488.746 [controlThread](INFO): Join timeout helper Thread ID is 1717
2023-11-15T00:34:48.928Z,1700008488.928 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-11-15T00:34:48.928Z,1700008488.928 [controlThread](DEBUG): Uninitializing ControlThread
2023-11-15T00:34:48.929Z,1700008488.929 [AHRS_M2](INFO): Powering down
2023-11-15T00:34:49.001Z,1700008489.001 [NAL9602](INFO): Powering down
2023-11-15T00:34:49.072Z,1700008489.072 [RDI_Pathfinder](INFO): Powering down
2023-11-15T00:34:49.073Z,1700008489.073 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-11-15T00:34:49.074Z,1700008489.074 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-11-15T00:34:49.074Z,1700008489.074 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-11-15T00:34:49.075Z,1700008489.075 [MissionManager](INFO): Uninitializing Mission Default
2023-11-15T00:34:49.075Z,1700008489.075 [Default] Stopped
2023-11-15T00:34:49.075Z,1700008489.075 [Default](DEBUG): Aggregate::uninitialize Default
2023-11-15T00:34:49.075Z,1700008489.075 [Default:B.GoToSurface] Stopped
2023-11-15T00:34:49.075Z,1700008489.075 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-11-15T00:34:49.075Z,1700008489.075 [Default:CheckIn] Stopped
2023-11-15T00:34:49.076Z,1700008489.076 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-11-15T00:34:49.076Z,1700008489.076 [Default:CheckIn:C.Wait] Stopped
2023-11-15T00:34:49.076Z,1700008489.076 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-11-15T00:34:49.078Z,1700008489.078 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-11-15T00:34:49.079Z,1700008489.079 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-11-15T00:34:49.079Z,1700008489.079 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-11-15T00:34:49.079Z,1700008489.079 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-11-15T00:34:49.080Z,1700008489.080 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-11-15T00:34:49.080Z,1700008489.080 [BuoyancyServo](INFO): Powering down
2023-11-15T00:34:49.092Z,1700008489.092 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-11-15T00:34:49.092Z,1700008489.092 [ElevatorServo](INFO): Powering down
2023-11-15T00:34:49.093Z,1700008489.093 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-11-15T00:34:49.093Z,1700008489.093 [MassServo](INFO): Powering down
2023-11-15T00:34:49.094Z,1700008489.094 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-11-15T00:34:49.094Z,1700008489.094 [RudderServo](INFO): Powering down
2023-11-15T00:34:49.095Z,1700008489.095 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-11-15T00:34:49.095Z,1700008489.095 [ThrusterServo](INFO): Powering down
2023-11-15T00:34:49.096Z,1700008489.096 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-11-15T00:34:49.096Z,1700008489.096 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-11-15T00:34:49.096Z,1700008489.096 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-11-15T00:34:49.097Z,1700008489.097 [CBIT](DEBUG): Powering off loads.
2023-11-15T00:34:49.108Z,1700008489.108 [CBIT](DEBUG): Disabling WDT.
2023-11-15T00:34:49.120Z,1700008489.120 [CBIT](DEBUG): Opening all GF detection circuits.
2023-11-15T00:34:49.121Z,1700008489.121 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.170Z,1700008489.170 [DAT ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.178Z,1700008489.178 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.180Z,1700008489.180 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.187Z,1700008489.187 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.230Z,1700008489.230 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.288Z,1700008489.288 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.290Z,1700008489.290 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.294Z,1700008489.294 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.302Z,1700008489.302 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-11-15T00:34:49.374Z,1700008489.374 [logger ThreadHandler](INFO): Thread cancelled.