2023-09-19T19:37:26.084Z,1695152246.084 [Supervisor](DEBUG): Initializing supervisor.
2023-09-19T19:37:26.088Z,1695152246.088 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-09-19T19:37:26.089Z,1695152246.089 [SyncHandler](INFO): Protected caller Thread ID is 1189
2023-09-19T19:37:26.089Z,1695152246.089 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-09-19T19:37:26.090Z,1695152246.090 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-09-19T19:37:26.090Z,1695152246.090 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1190
2023-09-19T19:37:26.094Z,1695152246.094 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-09-19T19:37:26.112Z,1695152246.112 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-09-19T19:37:26.113Z,1695152246.113 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-09-19T19:37:26.113Z,1695152246.113 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1191
2023-09-19T19:37:26.117Z,1695152246.117 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-09-19T19:37:26.118Z,1695152246.118 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-09-19T19:37:26.118Z,1695152246.118 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1192
2023-09-19T19:37:26.120Z,1695152246.120 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-09-19T19:37:26.121Z,1695152246.121 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-09-19T19:37:26.122Z,1695152246.122 [logger ThreadHandler](INFO): Protected caller Thread ID is 1193
2023-09-19T19:37:26.125Z,1695152246.125 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-09-19T19:37:26.126Z,1695152246.126 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-09-19T19:37:26.127Z,1695152246.127 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-09-19T19:37:26.338Z,1695152246.338 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-09-19T19:37:26.338Z,1695152246.338 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-09-19T19:37:26.445Z,1695152246.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-09-19T19:37:26.446Z,1695152246.446 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-09-19T19:37:26.542Z,1695152246.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-09-19T19:37:26.542Z,1695152246.542 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-09-19T19:37:27.017Z,1695152247.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-09-19T19:37:27.018Z,1695152247.018 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-09-19T19:37:27.367Z,1695152247.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-09-19T19:37:27.367Z,1695152247.367 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-09-19T19:37:27.774Z,1695152247.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-09-19T19:37:27.775Z,1695152247.775 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-09-19T19:37:27.964Z,1695152247.964 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-09-19T19:37:27.964Z,1695152247.964 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-09-19T19:37:28.044Z,1695152248.044 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-09-19T19:37:28.125Z,1695152248.125 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-09-19T19:37:28.530Z,1695152248.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-09-19T19:37:28.531Z,1695152248.531 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-09-19T19:37:28.811Z,1695152248.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-09-19T19:37:28.812Z,1695152248.812 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-09-19T19:37:29.339Z,1695152249.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-09-19T19:37:29.340Z,1695152249.340 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-09-19T19:37:29.563Z,1695152249.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-09-19T19:37:29.564Z,1695152249.564 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-09-19T19:37:29.799Z,1695152249.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-09-19T19:37:29.800Z,1695152249.800 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-09-19T19:37:29.941Z,1695152249.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-09-19T19:37:29.942Z,1695152249.942 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-09-19T19:37:30.781Z,1695152250.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-09-19T19:37:30.783Z,1695152250.783 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2023-09-19T19:37:30.784Z,1695152250.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2023-09-19T19:37:30.926Z,1695152250.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2023-09-19T19:37:31.239Z,1695152251.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2023-09-19T19:37:31.745Z,1695152251.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-09-19T19:37:31.746Z,1695152251.746 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2023-09-19T19:37:31.872Z,1695152251.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2023-09-19T19:37:31.983Z,1695152251.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2023-09-19T19:37:32.078Z,1695152252.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2023-09-19T19:37:32.188Z,1695152252.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2023-09-19T19:37:32.284Z,1695152252.284 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2023-09-19T19:37:32.371Z,1695152252.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2023-09-19T19:37:32.472Z,1695152252.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2023-09-19T19:37:32.884Z,1695152252.884 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2023-09-19T19:37:32.884Z,1695152252.884 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-09-19T19:37:32.902Z,1695152252.902 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-09-19T19:37:33.020Z,1695152253.020 [DepthRateCalculator] Loaded
2023-09-19T19:37:33.020Z,1695152253.020 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-09-19T19:37:33.026Z,1695152253.026 [PitchRateCalculator] Loaded
2023-09-19T19:37:33.026Z,1695152253.026 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-09-19T19:37:33.037Z,1695152253.037 [SpeedCalculator] Loaded
2023-09-19T19:37:33.037Z,1695152253.037 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-09-19T19:37:33.042Z,1695152253.042 [YawRateCalculator] Loaded
2023-09-19T19:37:33.042Z,1695152253.042 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-09-19T19:37:33.064Z,1695152253.064 [ElevatorOffsetCalculator] Loaded
2023-09-19T19:37:33.064Z,1695152253.064 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-09-19T19:37:33.064Z,1695152253.064 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-09-19T19:37:33.065Z,1695152253.065 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-09-19T19:37:33.092Z,1695152253.092 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-09-19T19:37:33.092Z,1695152253.092 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-09-19T19:37:33.102Z,1695152253.102 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-09-19T19:37:33.103Z,1695152253.103 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-09-19T19:37:33.553Z,1695152253.553 [AHRS_M2] Loaded
2023-09-19T19:37:33.553Z,1695152253.553 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-09-19T19:37:33.588Z,1695152253.588 [BackseatComponent] Loaded
2023-09-19T19:37:33.588Z,1695152253.588 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-09-19T19:37:33.590Z,1695152253.590 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0
2023-09-19T19:37:33.590Z,1695152253.590 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1275
2023-09-19T19:37:33.593Z,1695152253.593 [LcmUniversalReporter] Loaded
2023-09-19T19:37:33.593Z,1695152253.593 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-09-19T19:37:34.395Z,1695152254.395 [BPC1] Loaded
2023-09-19T19:37:34.395Z,1695152254.395 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-09-19T19:37:34.468Z,1695152254.468 [DataOverHttps] Loaded
2023-09-19T19:37:34.468Z,1695152254.468 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-09-19T19:37:34.469Z,1695152254.469 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0
2023-09-19T19:37:34.469Z,1695152254.469 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1276
2023-09-19T19:37:34.490Z,1695152254.490 [Depth_Keller] Loaded
2023-09-19T19:37:34.490Z,1695152254.490 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-09-19T19:37:34.509Z,1695152254.509 [MultiRay] Loaded
2023-09-19T19:37:34.509Z,1695152254.509 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2023-09-19T19:37:34.571Z,1695152254.571 [NAL9602] Loaded
2023-09-19T19:37:34.571Z,1695152254.571 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-09-19T19:37:34.601Z,1695152254.601 [Onboard] Loaded
2023-09-19T19:37:34.601Z,1695152254.601 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-09-19T19:37:34.602Z,1695152254.602 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0
2023-09-19T19:37:34.603Z,1695152254.603 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1277
2023-09-19T19:37:34.609Z,1695152254.609 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2023-09-19T19:37:34.620Z,1695152254.620 [PowerOnly] Loaded
2023-09-19T19:37:34.621Z,1695152254.621 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-09-19T19:37:34.632Z,1695152254.632 [Power24vConverter] Loaded
2023-09-19T19:37:34.632Z,1695152254.632 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-09-19T19:37:34.647Z,1695152254.647 [Radio_Surface] Loaded
2023-09-19T19:37:34.647Z,1695152254.647 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-09-19T19:37:34.648Z,1695152254.648 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0
2023-09-19T19:37:34.648Z,1695152254.648 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1278
2023-09-19T19:37:34.650Z,1695152254.650 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-09-19T19:37:34.650Z,1695152254.650 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-09-19T19:37:34.762Z,1695152254.762 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-09-19T19:37:34.762Z,1695152254.762 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-09-19T19:37:34.804Z,1695152254.804 [DeadReckonUsingSpeedCalculator] Loaded
2023-09-19T19:37:34.804Z,1695152254.804 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-09-19T19:37:34.818Z,1695152254.818 [NavChart] Loaded
2023-09-19T19:37:34.818Z,1695152254.818 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-09-19T19:37:34.824Z,1695152254.824 [UniversalFixResidualReporter] Loaded
2023-09-19T19:37:34.824Z,1695152254.824 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-09-19T19:37:34.825Z,1695152254.825 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-09-19T19:37:34.826Z,1695152254.826 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-09-19T19:37:34.971Z,1695152254.971 [SBIT](DEBUG): Construct Startup Built In Test.
2023-09-19T19:37:34.985Z,1695152254.985 [SBIT] Loaded
2023-09-19T19:37:34.985Z,1695152254.985 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-09-19T19:37:34.988Z,1695152254.988 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-09-19T19:37:35.001Z,1695152255.001 [IBIT] Loaded
2023-09-19T19:37:35.001Z,1695152255.001 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-09-19T19:37:35.007Z,1695152255.007 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-09-19T19:37:35.110Z,1695152255.110 [CBIT] Loaded
2023-09-19T19:37:35.111Z,1695152255.111 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-09-19T19:37:35.111Z,1695152255.111 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-09-19T19:37:35.112Z,1695152255.112 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-09-19T19:37:35.287Z,1695152255.287 [CTD_Seabird] Loaded
2023-09-19T19:37:35.287Z,1695152255.287 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-09-19T19:37:35.288Z,1695152255.288 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2023-09-19T19:37:35.288Z,1695152255.288 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1279
2023-09-19T19:37:35.309Z,1695152255.309 [PAR_Licor] Loaded
2023-09-19T19:37:35.309Z,1695152255.309 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-09-19T19:37:35.349Z,1695152255.349 [WetLabsBB2FL] Loaded
2023-09-19T19:37:35.350Z,1695152255.350 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-09-19T19:37:35.351Z,1695152255.351 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2023-09-19T19:37:35.351Z,1695152255.351 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1280
2023-09-19T19:37:35.352Z,1695152255.352 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-09-19T19:37:35.353Z,1695152255.353 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-09-19T19:37:35.487Z,1695152255.487 [BuoyancyServo] Loaded
2023-09-19T19:37:35.487Z,1695152255.487 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-09-19T19:37:35.509Z,1695152255.509 [ElevatorServo] Loaded
2023-09-19T19:37:35.509Z,1695152255.509 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-09-19T19:37:35.531Z,1695152255.531 [MassServo] Loaded
2023-09-19T19:37:35.532Z,1695152255.532 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-09-19T19:37:35.552Z,1695152255.552 [RudderServo] Loaded
2023-09-19T19:37:35.552Z,1695152255.552 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-09-19T19:37:35.568Z,1695152255.568 [ThrusterHE] Loaded
2023-09-19T19:37:35.569Z,1695152255.569 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-09-19T19:37:35.569Z,1695152255.569 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-09-19T19:37:35.570Z,1695152255.570 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-09-19T19:37:35.621Z,1695152255.621 [VerticalControl](DEBUG): Construct VerticalControl.
2023-09-19T19:37:35.672Z,1695152255.672 [VerticalControl] Loaded
2023-09-19T19:37:35.672Z,1695152255.672 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-09-19T19:37:35.675Z,1695152255.675 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-09-19T19:37:35.713Z,1695152255.713 [HorizontalControl] Loaded
2023-09-19T19:37:35.713Z,1695152255.713 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-09-19T19:37:35.716Z,1695152255.716 [SpeedControl](DEBUG): Construct SpeedControl.
2023-09-19T19:37:35.717Z,1695152255.717 [SpeedControl] Loaded
2023-09-19T19:37:35.718Z,1695152255.718 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-09-19T19:37:35.720Z,1695152255.720 [LoopControl](DEBUG): Construct LoopControl.
2023-09-19T19:37:35.721Z,1695152255.721 [LoopControl] Loaded
2023-09-19T19:37:35.721Z,1695152255.721 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-09-19T19:37:35.722Z,1695152255.722 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-09-19T19:37:35.722Z,1695152255.722 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-09-19T19:37:35.827Z,1695152255.827 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-09-19T19:37:35.828Z,1695152255.828 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-09-19T19:37:36.232Z,1695152256.232 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-09-19T19:37:36.232Z,1695152256.232 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-09-19T19:37:36.692Z,1695152256.692 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-09-19T19:37:36.693Z,1695152256.693 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-09-19T19:37:36.767Z,1695152256.767 [StratificationFrontDetector](INFO): thresholdVTHI set to: 0.399988 degC
2023-09-19T19:37:36.767Z,1695152256.767 [StratificationFrontDetector](DEBUG): (re)initializing
2023-09-19T19:37:36.767Z,1695152256.767 [StratificationFrontDetector] Loaded
2023-09-19T19:37:36.767Z,1695152256.767 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-09-19T19:37:36.768Z,1695152256.768 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-09-19T19:37:36.774Z,1695152256.774 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-09-19T19:37:36.777Z,1695152256.777 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-09-19T19:37:36.788Z,1695152256.788 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-09-19T19:37:36.789Z,1695152256.789 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BCB4E0
2023-09-19T19:37:36.789Z,1695152256.789 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1281
2023-09-19T19:37:36.794Z,1695152256.794 [Supervisor](INFO): Main Thread ID is 829
2023-09-19T19:37:36.794Z,1695152256.794 [Supervisor](DEBUG): Running supervisor.
2023-09-19T19:37:36.794Z,1695152256.794 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1282
2023-09-19T19:37:36.795Z,1695152256.795 [CommandExec](INFO): Initializing the command executive.
2023-09-19T19:37:36.796Z,1695152256.796 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1283
2023-09-19T19:37:36.800Z,1695152256.800 [controlThread ThreadHandler](INFO): Handler Thread ID is 1284
2023-09-19T19:37:36.800Z,1695152256.800 [controlThread](DEBUG): Initializing ControlThread
2023-09-19T19:37:36.801Z,1695152256.801 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-09-19T19:37:36.801Z,1695152256.801 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-09-19T19:37:36.802Z,1695152256.802 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-09-19T19:37:36.802Z,1695152256.802 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-09-19T19:37:36.802Z,1695152256.802 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-09-19T19:37:36.809Z,1695152256.809 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:37:36.809Z,1695152256.809 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-09-19T19:37:36.809Z,1695152256.809 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-09-19T19:37:36.810Z,1695152256.810 [SBIT](INFO): Initialize SBIT Component.
2023-09-19T19:37:36.810Z,1695152256.810 [SBIT](IMPORTANT): git: 2023-09-18-3-g7ee55e0de
2023-09-19T19:37:36.810Z,1695152256.810 [SBIT](INFO): git hash: 7ee55e0ded82548f5c252ba0d9a41930617440e4
2023-09-19T19:37:36.811Z,1695152256.811 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-09-19T19:37:36.812Z,1695152256.812 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-09-19T19:37:36.813Z,1695152256.813 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2023-09-19T19:37:36.813Z,1695152256.813 [IBIT](INFO): Initialize IBIT Component.
2023-09-19T19:37:36.814Z,1695152256.814 [CBIT](DEBUG): Initialize CBIT Component.
2023-09-19T19:37:36.815Z,1695152256.815 [logger ThreadHandler](INFO): Handler Thread ID is 1285
2023-09-19T19:37:36.827Z,1695152256.827 [CBIT](DEBUG): Initialized mux pins.
2023-09-19T19:37:36.827Z,1695152256.827 [CBIT](DEBUG): Initializing the watchdog timer.
2023-09-19T19:37:36.836Z,1695152256.836 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1286
2023-09-19T19:37:36.848Z,1695152256.848 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1287
2023-09-19T19:37:36.848Z,1695152256.848 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-09-19T19:37:36.851Z,1695152256.851 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-09-19T19:37:36.852Z,1695152256.852 [CBIT](DEBUG): Initializing heartbeat.
2023-09-19T19:37:36.860Z,1695152256.860 [Onboard ThreadHandler](INFO): Handler Thread ID is 1288
2023-09-19T19:37:36.890Z,1695152256.890 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1289
2023-09-19T19:37:36.900Z,1695152256.900 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1290
2023-09-19T19:37:36.900Z,1695152256.900 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-09-19T19:37:36.905Z,1695152256.905 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1292
2023-09-19T19:37:36.908Z,1695152256.908 [WetLabsBB2FL](INFO): Powering up
2023-09-19T19:37:36.909Z,1695152256.909 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1294
2023-09-19T19:37:36.911Z,1695152256.911 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-09-19T19:37:36.911Z,1695152256.911 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-09-19T19:37:36.912Z,1695152256.912 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-09-19T19:37:36.912Z,1695152256.912 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-09-19T19:37:36.912Z,1695152256.912 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-09-19T19:37:36.912Z,1695152256.912 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-09-19T19:37:36.912Z,1695152256.912 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-09-19T19:37:36.912Z,1695152256.912 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-09-19T19:37:36.923Z,1695152256.923 [CBIT](DEBUG): Deactivating GF circuits.
2023-09-19T19:37:36.923Z,1695152256.923 [CBIT](DEBUG): Deactivating emergency mode.
2023-09-19T19:37:36.963Z,1695152256.963 [CBIT](DEBUG): Backplane powered.
2023-09-19T19:37:36.968Z,1695152256.968 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-09-19T19:37:36.970Z,1695152256.970 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-09-19T19:37:36.971Z,1695152256.971 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-09-19T19:37:36.972Z,1695152256.972 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-09-19T19:37:36.973Z,1695152256.973 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-09-19T19:37:36.986Z,1695152256.986 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-19T19:37:37.037Z,1695152257.037 [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-09-19T19:37:37.038Z,1695152257.038 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-09-19T19:37:37.088Z,1695152257.088 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-09-19T19:37:37.090Z,1695152257.090 [Default:A.Wait](DEBUG): Construct Wait.
2023-09-19T19:37:37.092Z,1695152257.092 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-09-19T19:37:37.127Z,1695152257.127 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-09-19T19:37:37.130Z,1695152257.130 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-09-19T19:37:37.152Z,1695152257.152 [Default:E.Execute](DEBUG): Construct Execute.
2023-09-19T19:37:37.156Z,1695152257.156 [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-09-19T19:37:37.178Z,1695152257.178 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-09-19T19:37:37.212Z,1695152257.212 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-09-19T19:37:37.277Z,1695152257.277 [MultiRay](INFO): Powering up MultiRay Lights
2023-09-19T19:37:37.291Z,1695152257.291 [Radio_Surface](INFO): Powering up
2023-09-19T19:37:37.320Z,1695152257.320 [Power24vConverter](INFO): Powering up.
2023-09-19T19:37:37.352Z,1695152257.352 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-09-19T19:37:37.359Z,1695152257.359 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-09-19T19:37:37.360Z,1695152257.360 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-19T19:37:37.375Z,1695152257.375 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-19T19:37:37.376Z,1695152257.376 [MassServo](DEBUG): Initializing EZServoServo.
2023-09-19T19:37:37.383Z,1695152257.383 [MassServo](DEBUG): Initializing MassServo.
2023-09-19T19:37:37.384Z,1695152257.384 [RudderServo](DEBUG): Initializing EZServoServo.
2023-09-19T19:37:37.391Z,1695152257.391 [RudderServo](DEBUG): Initializing RudderServo.
2023-09-19T19:37:37.392Z,1695152257.392 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-09-19T19:37:37.403Z,1695152257.403 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-09-19T19:37:39.519Z,1695152259.519 [WetLabsBB2FL](INFO): Powering down
2023-09-19T19:37:42.996Z,1695152262.996 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-09-19T19:37:43.931Z,1695152263.931 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-09-19T19:38:04.713Z,1695152284.713 [NAL9602](INFO): Powering up NAL9602
2023-09-19T19:38:15.631Z,1695152295.631 [NAL9602](INFO): NAL9602 initialized
2023-09-19T19:38:36.247Z,1695152316.247 [SBIT](IMPORTANT): Beginning Startup BIT
2023-09-19T19:38:36.252Z,1695152316.252 [CBIT](IMPORTANT): Beginning ground fault scan
2023-09-19T19:38:47.210Z,1695152327.210 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006590
CHAN A1 (24V): 0.000621
CHAN A2 (12V): -0.005105
CHAN A3 (5V): -0.002724
CHAN B0 (3.3V): -0.001476
CHAN B1 (3.15aV): -0.000705
CHAN B2 (3.15bV): -0.001523
CHAN B3 (GND): -0.000521
OPEN: 0.004226
Full Scale: +/- 1 mA
2023-09-19T19:38:51.899Z,1695152331.899 [ElevatorServo](ERROR): getPosition uart error serial timeout
2023-09-19T19:38:51.899Z,1695152331.899 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2023-09-19T19:38:51.899Z,1695152331.899 [ElevatorServo] Communications Fault, FailCount= 1
2023-09-19T19:38:51.899Z,1695152331.899 [ElevatorServo](ERROR): Communications Fault
2023-09-19T19:38:52.012Z,1695152332.012 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2023-09-19T19:38:52.042Z,1695152332.042 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-19T19:38:52.042Z,1695152332.042 [ElevatorServo](INFO): Powering down
2023-09-19T19:38:52.850Z,1695152332.850 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-09-19T19:38:52.968Z,1695152332.968 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-09-19T19:38:53.028Z,1695152333.028 [CBIT](INFO): Clearing failed state for component ElevatorServo
2023-09-19T19:38:53.028Z,1695152333.028 [ElevatorServo] No Fault, FailCount= 1
2023-09-19T19:39:30.423Z,1695152370.423 [SBIT](IMPORTANT): SBIT PASSED
2023-09-19T19:39:30.424Z,1695152370.424 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-09-19T19:39:30.424Z,1695152370.424 [SBIT](IMPORTANT): BackseatComponent.missionCritical=1 bool;

2023-09-19T19:39:30.424Z,1695152370.424 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=2 minute;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_disk_space 100.000000 gigabyte;

2023-09-19T19:39:30.425Z,1695152370.425 [SBIT](IMPORTANT): Express linearApproximation _.ayeris_particle_counts 300.000000 count_per_second;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): Express none _.ayeris_particle_shape;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): Express none _.ayeris_particle_slope;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2023-09-19T19:39:30.426Z,1695152370.426 [SBIT](IMPORTANT): VerticalControl.massDeadband=1 millimeter;

2023-09-19T19:39:30.427Z,1695152370.427 [SBIT](IMPORTANT): VerticalControl.massDefault=1 centimeter;

2023-09-19T19:39:30.427Z,1695152370.427 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-09-19T19:39:30.427Z,1695152370.427 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-09-19T19:39:30.818Z,1695152370.818 [MissionManager](IMPORTANT): Started mission Startup
2023-09-19T19:39:30.819Z,1695152370.819 [Startup] Running Loop=1
2023-09-19T19:39:30.819Z,1695152370.819 [Startup](DEBUG): Aggregate::initialize Startup
2023-09-19T19:39:30.819Z,1695152370.819 [Startup:A.GoToSurface] Running Loop=1
2023-09-19T19:39:30.819Z,1695152370.819 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-19T19:39:30.819Z,1695152370.819 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-19T19:39:30.820Z,1695152370.820 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-19T19:39:30.820Z,1695152370.820 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-19T19:39:30.821Z,1695152370.821 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-19T19:39:30.821Z,1695152370.821 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-19T19:39:30.822Z,1695152370.822 [Startup:StartupSatComms] Running Loop=1
2023-09-19T19:39:30.822Z,1695152370.822 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-09-19T19:39:30.822Z,1695152370.822 [Startup:StartupSatComms:A] Running Loop=1
2023-09-19T19:39:31.232Z,1695152371.232 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-09-19T19:39:36.869Z,1695152376.869 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-09-19T19:40:31.088Z,1695152431.088 [Startup:StartupSatComms:A](INFO): Timed out from 2023-09-19T19:39:30.8Z
2023-09-19T19:40:31.088Z,1695152431.088 [Startup:StartupSatComms:A] Stopped
2023-09-19T19:40:31.088Z,1695152431.088 [Startup:StartupSatComms:B] Running Loop=1
2023-09-19T19:40:31.438Z,1695152431.438 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-19T19:40:37.081Z,1695152437.081 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-09-19T19:40:37.081Z,1695152437.081 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T19:40:37.082Z,1695152437.082 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-09-19T19:40:37.082Z,1695152437.082 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T19:40:37.093Z,1695152437.093 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T19:40:37.093Z,1695152437.093 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T19:40:37.504Z,1695152437.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T19:40:37.504Z,1695152437.504 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-09-19T19:40:37.505Z,1695152437.505 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T19:40:37.505Z,1695152437.505 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-09-19T19:40:37.884Z,1695152437.884 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:41:00.021Z,1695152460.021 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004437
2023-09-19T19:41:08.336Z,1695152468.336 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20230919T184713/Courier0016.lzma
2023-09-19T19:41:09.337Z,1695152469.337 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Courier0016.lzma.bak
2023-09-19T19:41:09.337Z,1695152469.337 [DataOverHttps](INFO): SBD MOMSN=18797667
2023-09-19T19:41:28.239Z,1695152488.239 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230919T193726/Courier0000.lzma
2023-09-19T19:41:29.241Z,1695152489.241 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Courier0000.lzma.bak
2023-09-19T19:41:29.241Z,1695152489.241 [DataOverHttps](INFO): SBD MOMSN=18797671
2023-09-19T19:41:31.230Z,1695152491.230 [Startup:StartupSatComms:B](INFO): Timed out from 2023-09-19T19:40:31.1Z
2023-09-19T19:41:31.231Z,1695152491.231 [Startup:StartupSatComms:B] Stopped
2023-09-19T19:41:31.231Z,1695152491.231 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-09-19T19:41:31.235Z,1695152491.235 [Startup:StartupSatComms] Stopped
2023-09-19T19:41:31.235Z,1695152491.235 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-09-19T19:41:31.236Z,1695152491.236 [Startup](INFO): Completed Startup
2023-09-19T19:41:31.236Z,1695152491.236 [MissionManager](INFO): Startup is completed.
2023-09-19T19:41:31.236Z,1695152491.236 [MissionManager](INFO): Uninitializing Mission Startup
2023-09-19T19:41:31.236Z,1695152491.236 [Startup] Stopped
2023-09-19T19:41:31.236Z,1695152491.236 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-09-19T19:41:31.237Z,1695152491.237 [Startup:A.GoToSurface] Stopped
2023-09-19T19:41:31.237Z,1695152491.237 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-19T19:41:31.638Z,1695152491.638 [MissionManager](IMPORTANT): Started mission Default
2023-09-19T19:41:31.638Z,1695152491.638 [Default] Running Loop=1
2023-09-19T19:41:31.638Z,1695152491.638 [Default](DEBUG): Aggregate::initialize Default
2023-09-19T19:41:31.638Z,1695152491.638 [Default:B.GoToSurface] Running Loop=1
2023-09-19T19:41:31.638Z,1695152491.638 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-19T19:41:31.638Z,1695152491.638 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-19T19:41:31.639Z,1695152491.639 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-19T19:41:31.643Z,1695152491.643 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-19T19:41:31.644Z,1695152491.644 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-19T19:41:31.644Z,1695152491.644 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-19T19:41:31.644Z,1695152491.644 [Default:A.Wait] Running Loop=1
2023-09-19T19:41:31.644Z,1695152491.644 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-19T19:41:44.959Z,1695152504.959 [Default:A.Wait](INFO): Done Waiting.
2023-09-19T19:41:44.959Z,1695152504.959 [Default:A.Wait] Stopped
2023-09-19T19:41:44.959Z,1695152504.959 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T19:41:45.364Z,1695152505.364 [Default:CheckIn] Running Loop=1
2023-09-19T19:41:45.364Z,1695152505.364 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T19:41:45.365Z,1695152505.365 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T19:41:45.775Z,1695152505.775 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-09-19T19:42:09.696Z,1695152529.696 [NAL9602](INFO): SBD MO Status=0, MOMSN=13558, MT Status=0, MTMSN=0
2023-09-19T19:42:09.697Z,1695152529.697 [NAL9602](INFO): No messages in MT queue
2023-09-19T19:42:14.224Z,1695152534.224 [DataOverHttps](INFO): Sending 714 bytes from file Logs/20230919T184713/Express0017.lzma
2023-09-19T19:42:15.225Z,1695152535.225 [DataOverHttps](INFO): Moved sent file to Logs/20230919T184713/Express0017.lzma.bak
2023-09-19T19:42:15.225Z,1695152535.225 [DataOverHttps](INFO): SBD MOMSN=18797674
2023-09-19T19:42:50.465Z,1695152570.465 [DataOverHttps](IMPORTANT): SBD MTMSN=20230919T194249
2023-09-19T19:42:57.639Z,1695152577.639 [DataOverHttps](INFO): Received command: load Maintenance/tank_ballast_and_trim.tl;set ballast_and_trim.Depth1 5 m;set ballast_and_trim.SettleTime 120 min;set ballast_and_trim:BackseatDriver.EnableBackseat 1 bool;run
2023-09-19T19:42:57.714Z,1695152577.714 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-09-19T19:42:57.714Z,1695152577.714 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-09-19T19:42:57.715Z,1695152577.715 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx
2023-09-19T19:42:57.841Z,1695152577.841 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2023-09-19T19:42:57.848Z,1695152577.848 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2023-09-19T19:42:57.867Z,1695152577.867 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2023-09-19T19:42:57.870Z,1695152577.870 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2023-09-19T19:42:57.884Z,1695152577.884 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2023-09-19T19:42:57.889Z,1695152577.889 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2023-09-19T19:42:57.892Z,1695152577.892 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2023-09-19T19:42:57.899Z,1695152577.899 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2023-09-19T19:42:57.902Z,1695152577.902 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2023-09-19T19:42:57.916Z,1695152577.916 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2023-09-19T19:42:57.924Z,1695152577.924 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2023-09-19T19:42:57.931Z,1695152577.931 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2023-09-19T19:42:57.934Z,1695152577.934 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2023-09-19T19:42:57.941Z,1695152577.941 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2023-09-19T19:42:57.942Z,1695152577.942 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-09-19T19:42:57.942Z,1695152577.942 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-09-19T19:42:58.397Z,1695152578.397 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2023-09-19T19:42:58.400Z,1695152578.400 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2023-09-19T19:42:58.403Z,1695152578.403 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2023-09-19T19:42:58.405Z,1695152578.405 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2023-09-19T19:42:58.434Z,1695152578.434 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2023-09-19T19:42:58.437Z,1695152578.437 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2023-09-19T19:42:58.440Z,1695152578.440 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2023-09-19T19:42:58.442Z,1695152578.442 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2023-09-19T19:42:58.455Z,1695152578.455 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2023-09-19T19:42:58.462Z,1695152578.462 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2023-09-19T19:42:58.466Z,1695152578.466 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2023-09-19T19:42:58.469Z,1695152578.469 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2023-09-19T19:42:58.471Z,1695152578.471 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2023-09-19T19:42:58.474Z,1695152578.474 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2023-09-19T19:42:58.499Z,1695152578.499 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-09-19T19:42:58.502Z,1695152578.502 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2023-09-19T19:42:58.506Z,1695152578.506 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2023-09-19T19:42:58.519Z,1695152578.519 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2023-09-19T19:42:58.522Z,1695152578.522 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2023-09-19T19:42:58.526Z,1695152578.526 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-09-19T19:42:58.547Z,1695152578.547 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool
2023-09-19T19:42:58.550Z,1695152578.550 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2023-09-19T19:42:58.553Z,1695152578.553 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2023-09-19T19:42:58.572Z,1695152578.572 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2023-09-19T19:42:58.575Z,1695152578.575 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2023-09-19T19:42:58.598Z,1695152578.598 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-09-19T19:42:58.620Z,1695152578.620 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2023-09-19T19:42:58.622Z,1695152578.622 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2023-09-19T19:42:58.629Z,1695152578.629 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2023-09-19T19:42:58.636Z,1695152578.636 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2023-09-19T19:42:58.660Z,1695152578.660 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2023-09-19T19:42:58.662Z,1695152578.662 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2023-09-19T19:42:58.674Z,1695152578.674 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2023-09-19T19:42:58.693Z,1695152578.693 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2023-09-19T19:42:58.700Z,1695152578.700 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2023-09-19T19:42:58.709Z,1695152578.709 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2023-09-19T19:42:58.712Z,1695152578.712 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2023-09-19T19:42:58.724Z,1695152578.724 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2023-09-19T19:42:58.727Z,1695152578.727 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2023-09-19T19:42:58.737Z,1695152578.737 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2023-09-19T19:42:58.740Z,1695152578.740 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2023-09-19T19:42:58.748Z,1695152578.748 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2023-09-19T19:42:58.751Z,1695152578.751 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2023-09-19T19:42:58.762Z,1695152578.762 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2023-09-19T19:42:58.765Z,1695152578.765 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2023-09-19T19:42:58.781Z,1695152578.781 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2023-09-19T19:42:58.785Z,1695152578.785 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2023-09-19T19:42:58.796Z,1695152578.796 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2023-09-19T19:42:58.803Z,1695152578.803 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2023-09-19T19:42:58.806Z,1695152578.806 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2023-09-19T19:42:58.813Z,1695152578.813 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2023-09-19T19:42:58.825Z,1695152578.825 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2023-09-19T19:42:58.828Z,1695152578.828 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2023-09-19T19:42:58.839Z,1695152578.839 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2023-09-19T19:42:58.842Z,1695152578.842 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2023-09-19T19:42:58.850Z,1695152578.850 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2023-09-19T19:42:58.853Z,1695152578.853 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2023-09-19T19:42:58.864Z,1695152578.864 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2023-09-19T19:42:58.868Z,1695152578.868 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2023-09-19T19:42:58.880Z,1695152578.880 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2023-09-19T19:42:58.887Z,1695152578.887 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2023-09-19T19:42:58.890Z,1695152578.890 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2023-09-19T19:42:58.898Z,1695152578.898 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2023-09-19T19:42:58.901Z,1695152578.901 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2023-09-19T19:42:58.916Z,1695152578.916 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2023-09-19T19:42:58.926Z,1695152578.926 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2023-09-19T19:42:58.929Z,1695152578.929 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2023-09-19T19:42:58.932Z,1695152578.932 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2023-09-19T19:42:58.955Z,1695152578.955 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2023-09-19T19:42:58.958Z,1695152578.958 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2023-09-19T19:42:58.961Z,1695152578.961 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2023-09-19T19:42:59.059Z,1695152579.059 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-19T19:42:59.084Z,1695152579.084 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-19T19:42:59.121Z,1695152579.121 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-19T19:42:59.156Z,1695152579.156 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-19T19:42:59.170Z,1695152579.170 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-19T19:42:59.190Z,1695152579.190 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-19T19:42:59.231Z,1695152579.231 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-09-19T19:42:59.259Z,1695152579.259 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-09-19T19:42:59.301Z,1695152579.301 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-09-19T19:42:59.307Z,1695152579.307 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-09-19T19:42:59.307Z,1695152579.307 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-09-19T19:42:59.333Z,1695152579.333 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool
2023-09-19T19:42:59.352Z,1695152579.352 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-09-19T19:42:59.356Z,1695152579.356 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl
2023-09-19T19:42:59.356Z,1695152579.356 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx
2023-09-19T19:42:59.599Z,1695152579.599 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2023-09-19T19:42:59.602Z,1695152579.602 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2023-09-19T19:42:59.605Z,1695152579.605 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2023-09-19T19:42:59.623Z,1695152579.623 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2023-09-19T19:42:59.626Z,1695152579.625 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2023-09-19T19:42:59.628Z,1695152579.628 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2023-09-19T19:42:59.644Z,1695152579.644 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2023-09-19T19:42:59.647Z,1695152579.647 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2023-09-19T19:42:59.650Z,1695152579.650 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2023-09-19T19:42:59.653Z,1695152579.653 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2023-09-19T19:42:59.673Z,1695152579.673 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2023-09-19T19:42:59.684Z,1695152579.684 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2023-09-19T19:42:59.687Z,1695152579.687 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2023-09-19T19:42:59.690Z,1695152579.690 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2023-09-19T19:42:59.734Z,1695152579.734 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2023-09-19T19:42:59.741Z,1695152579.741 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-19T19:42:59.754Z,1695152579.754 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2023-09-19T19:42:59.763Z,1695152579.763 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2023-09-19T19:42:59.807Z,1695152579.807 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2023-09-19T19:42:59.818Z,1695152579.818 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2023-09-19T19:42:59.847Z,1695152579.847 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2023-09-19T19:42:59.855Z,1695152579.855 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2023-09-19T19:42:59.858Z,1695152579.858 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2023-09-19T19:42:59.874Z,1695152579.874 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2023-09-19T19:42:59.879Z,1695152579.879 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:E.Execute](DEBUG): Construct Execute.
2023-09-19T19:42:59.889Z,1695152579.889 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:G.](DEBUG): Construct BallastAndTrim.
2023-09-19T19:42:59.927Z,1695152579.927 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2023-09-19T19:42:59.929Z,1695152579.929 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2023-09-19T19:42:59.935Z,1695152579.935 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2023-09-19T19:42:59.937Z,1695152579.937 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2023-09-19T19:42:59.939Z,1695152579.939 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2023-09-19T19:42:59.945Z,1695152579.945 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2023-09-19T19:42:59.946Z,1695152579.946 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2023-09-19T19:42:59.952Z,1695152579.952 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2023-09-19T19:42:59.980Z,1695152579.980 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2023-09-19T19:42:59.990Z,1695152579.990 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2023-09-19T19:43:00.041Z,1695152580.041 [MissionManager](DEBUG): 
mission ballast_and_trim {
  arguments {
    MissionTimeout = 120 minute
      """
      Maximum duration of mission
      """

    SkipComms = false
      """
      Skip communications at start of mission.
      """

    Depth1 = 7 meter

    Depth2 = NaN meter

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

    SurfaceThreshold = 2.5 meter
      """
      Surface threshold depth.
      """

    MaxWaitOnSurface = 20 minute
      """
      Maximum wait time for the vehicle to go under the surface (i.e., reach
      the surface threshold 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 duration=MissionTimeout

  insert Insert/Science.tl

  insert Insert/BackseatDriver.tl

  aggregate RunBallastAndTrim {
    run in sequence

    insert Insert/BallastAndTrim.tl {
      redefineArg InsertDepthDeadband = DepthDeadband
      redefineArg InsertApproachDepthTimeout = MaxWaitOnSurface
      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 = Depth1

      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-09-19T19:43:00.041Z,1695152580.041 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-09-19T19:43:07.932Z,1695152587.932 [CommandExec](IMPORTANT): got command set ballast_and_trim.Depth1 5 meter
2023-09-19T19:43:07.933Z,1695152587.933 [CommandExec](IMPORTANT): got command set ballast_and_trim.SettleTime 120 minute
2023-09-19T19:43:07.934Z,1695152587.934 [CommandExec](IMPORTANT): got command set ballast_and_trim:BackseatDriver.EnableBackseat 1 bool
2023-09-19T19:43:07.934Z,1695152587.934 [CommandExec](IMPORTANT): got command run
2023-09-19T19:43:07.938Z,1695152587.938 [CommandExec](IMPORTANT): Running 
2023-09-19T19:43:08.293Z,1695152588.293 [Default] Stopped
2023-09-19T19:43:08.293Z,1695152588.293 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-19T19:43:08.293Z,1695152588.293 [Default:B.GoToSurface] Stopped
2023-09-19T19:43:08.293Z,1695152588.293 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-19T19:43:08.293Z,1695152588.293 [Default:CheckIn] Stopped
2023-09-19T19:43:08.293Z,1695152588.293 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T19:43:08.294Z,1695152588.294 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T19:43:08.294Z,1695152588.294 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim] Running Loop=1
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim:Science] Running Loop=1
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim:Science:A] Running Loop=1
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim:Science:B] Running Loop=1
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim:Science:C] Running Loop=1
2023-09-19T19:43:08.294Z,1695152588.294 [ballast_and_trim:Science:D] Running Loop=1
2023-09-19T19:43:08.295Z,1695152588.295 [ballast_and_trim:Science:E] Running Loop=1
2023-09-19T19:43:08.295Z,1695152588.295 [ballast_and_trim:Science:F] Running Loop=1
2023-09-19T19:43:08.295Z,1695152588.295 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2023-09-19T19:43:08.295Z,1695152588.295 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2023-09-19T19:43:08.295Z,1695152588.295 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2023-09-19T19:43:08.295Z,1695152588.295 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-09-19T19:43:08.295Z,1695152588.295 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-19T19:43:08.297Z,1695152588.297 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2023-09-19T19:43:08.298Z,1695152588.298 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2023-09-19T19:43:08.298Z,1695152588.298 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-19T19:43:08.298Z,1695152588.298 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-19T19:43:08.298Z,1695152588.298 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2023-09-19T19:43:08.298Z,1695152588.298 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2023-09-19T19:43:08.298Z,1695152588.298 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-09-19T19:43:08.298Z,1695152588.298 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-19T19:43:08.301Z,1695152588.301 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2023-09-19T19:43:08.301Z,1695152588.301 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2023-09-19T19:43:08.301Z,1695152588.301 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-09-19T19:43:08.301Z,1695152588.301 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-19T19:43:08.303Z,1695152588.303 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2023-09-19T19:43:08.303Z,1695152588.303 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2023-09-19T19:43:08.303Z,1695152588.303 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-19T19:43:08.303Z,1695152588.303 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-19T19:43:08.304Z,1695152588.304 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2023-09-19T19:43:08.304Z,1695152588.304 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2023-09-19T19:43:08.304Z,1695152588.304 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-09-19T19:43:08.304Z,1695152588.304 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-19T19:43:08.305Z,1695152588.305 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2023-09-19T19:43:08.306Z,1695152588.306 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2023-09-19T19:43:08.306Z,1695152588.306 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-09-19T19:43:08.306Z,1695152588.306 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-09-19T19:43:08.307Z,1695152588.307 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2023-09-19T19:43:08.308Z,1695152588.308 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2023-09-19T19:43:08.308Z,1695152588.308 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-09-19T19:43:08.308Z,1695152588.308 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-09-19T19:43:08.309Z,1695152588.309 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1
2023-09-19T19:43:08.309Z,1695152588.309 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger
2023-09-19T19:43:08.309Z,1695152588.309 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-09-19T19:43:08.309Z,1695152588.309 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-09-19T19:43:08.309Z,1695152588.309 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2023-09-19T19:43:08.310Z,1695152588.310 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2023-09-19T19:43:08.311Z,1695152588.311 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-09-19T19:43:08.311Z,1695152588.311 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2023-09-19T19:43:08.312Z,1695152588.312 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2023-09-19T19:43:08.312Z,1695152588.312 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2023-09-19T19:43:08.312Z,1695152588.312 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-09-19T19:43:08.312Z,1695152588.312 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-09-19T19:43:08.312Z,1695152588.312 [ballast_and_trim:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2023-09-19T19:43:08.356Z,1695152588.356 [ballast_and_trim:Science] Running Loop=1
2023-09-19T19:43:08.357Z,1695152588.357 [ballast_and_trim:Science:ChlThreshTrigger] Stopped
2023-09-19T19:43:08.357Z,1695152588.357 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger
2023-09-19T19:43:08.357Z,1695152588.357 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-09-19T19:43:08.357Z,1695152588.357 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-09-19T19:43:08.357Z,1695152588.357 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2023-09-19T19:43:08.357Z,1695152588.357 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2023-09-19T19:43:08.357Z,1695152588.357 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-19T19:43:08.358Z,1695152588.358 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-19T19:43:08.359Z,1695152588.359 [ballast_and_trim:Science:PeakDetectOil] Stopped
2023-09-19T19:43:08.359Z,1695152588.359 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2023-09-19T19:43:08.359Z,1695152588.359 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-09-19T19:43:08.363Z,1695152588.363 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectChl] Stopped
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-09-19T19:43:08.364Z,1695152588.364 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-09-19T19:43:08.365Z,1695152588.365 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-09-19T19:43:08.365Z,1695152588.365 [ballast_and_trim:Science:Read_Oil] Stopped
2023-09-19T19:43:08.365Z,1695152588.365 [ballast_and_trim:Science:F] Stopped
2023-09-19T19:43:08.365Z,1695152588.365 [ballast_and_trim:Science:E] Running Loop=1
2023-09-19T19:43:08.366Z,1695152588.366 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-09-19T19:43:08.366Z,1695152588.366 [ballast_and_trim:Science:D] Stopped
2023-09-19T19:43:08.366Z,1695152588.366 [ballast_and_trim:Science:C] Running Loop=1
2023-09-19T19:43:08.366Z,1695152588.366 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-09-19T19:43:08.368Z,1695152588.368 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-09-19T19:43:08.368Z,1695152588.368 [ballast_and_trim:Science:B] Running Loop=1
2023-09-19T19:43:08.369Z,1695152588.369 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-09-19T19:43:08.370Z,1695152588.370 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-09-19T19:43:08.370Z,1695152588.370 [ballast_and_trim:Science:A] Stopped
2023-09-19T19:43:08.383Z,1695152588.383 [BackseatComponent](INFO): Powering up
2023-09-19T19:43:08.384Z,1695152588.384 [BackseatComponent](INFO): Subscribing to LCM channels.
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-09-19T19:43:08.695Z,1695152588.695 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2023-09-19T19:43:08.697Z,1695152588.697 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2023-09-19T19:43:08.697Z,1695152588.697 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2023-09-19T19:43:08.698Z,1695152588.698 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2023-09-19T19:43:08.699Z,1695152588.699 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2023-09-19T19:43:08.699Z,1695152588.699 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-09-19T19:43:08.699Z,1695152588.699 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2023-09-19T19:43:08.699Z,1695152588.699 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-09-19T19:43:08.700Z,1695152588.700 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-09-19T19:43:08.700Z,1695152588.700 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-09-19T19:43:08.700Z,1695152588.700 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-09-19T19:43:08.700Z,1695152588.700 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-09-19T19:43:08.777Z,1695152588.777 [WetLabsBB2FL](INFO): Powering up
2023-09-19T19:43:09.116Z,1695152589.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2023-09-19T19:43:09.116Z,1695152589.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2023-09-19T19:43:09.116Z,1695152589.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2023-09-19T19:43:09.116Z,1695152589.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2023-09-19T19:43:09.116Z,1695152589.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2023-09-19T19:43:09.116Z,1695152589.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-09-19T19:43:09.116Z,1695152589.116 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2023-09-19T19:43:09.117Z,1695152589.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2023-09-19T19:43:09.117Z,1695152589.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-09-19T19:43:09.117Z,1695152589.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2023-09-19T19:43:09.117Z,1695152589.117 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-09-19T19:43:09.526Z,1695152589.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2023-09-19T19:43:09.527Z,1695152589.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2023-09-19T19:43:09.527Z,1695152589.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T19:43:09.527Z,1695152589.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-09-19T19:43:09.527Z,1695152589.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2023-09-19T19:43:09.527Z,1695152589.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-09-19T19:43:09.527Z,1695152589.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2023-09-19T19:43:09.527Z,1695152589.527 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-09-19T19:43:09.528Z,1695152589.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-09-19T19:43:09.528Z,1695152589.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2023-09-19T19:43:09.528Z,1695152589.528 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2023-09-19T19:43:09.923Z,1695152589.923 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-09-19T19:43:09.936Z,1695152589.936 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 5.000000 m 
2023-09-19T19:43:09.936Z,1695152589.936 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2023-09-19T19:43:09.936Z,1695152589.936 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2023-09-19T19:43:09.936Z,1695152589.936 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2023-09-19T19:43:12.269Z,1695152592.269 [CTD_Seabird](ERROR): Salinity reading out of range: 1048.579956 psu
2023-09-19T19:43:12.271Z,1695152592.271 [CTD_Seabird](INFO): some bad data, not updating bins
2023-09-19T19:43:38.194Z,1695152618.194 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-09-19T19:43:38.194Z,1695152618.194 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T19:43:38.195Z,1695152618.195 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2023-09-19T19:43:38.195Z,1695152618.195 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T19:43:38.288Z,1695152618.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T19:43:38.288Z,1695152618.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T19:43:38.727Z,1695152618.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T19:43:38.727Z,1695152618.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-09-19T19:43:38.728Z,1695152618.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T19:43:38.728Z,1695152618.728 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2023-09-19T19:43:39.003Z,1695152619.003 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:43:56.871Z,1695152636.871 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T19:43:58.881Z,1695152638.881 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.241028
2023-09-19T19:44:04.294Z,1695152644.294 [BackseatComponent](IMPORTANT): ikshana: running backseat application.
2023-09-19T19:44:06.809Z,1695152646.809 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2023-09-19T19:44:06.809Z,1695152646.809 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2023-09-19T19:44:32.016Z,1695152672.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T19:44:52.145Z,1695152692.145 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2023-09-19T19:44:52.145Z,1695152692.145 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-09-19T19:44:52.145Z,1695152692.145 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2023-09-19T19:44:52.145Z,1695152692.145 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-09-19T19:44:52.145Z,1695152692.145 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-09-19T19:44:52.146Z,1695152692.146 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-09-19T19:44:52.147Z,1695152692.147 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2023-09-19T19:44:52.147Z,1695152692.147 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-09-19T19:44:52.147Z,1695152692.147 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2023-09-19T19:44:52.148Z,1695152692.148 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2023-09-19T19:44:52.537Z,1695152692.537 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 5.034777 m 
2023-09-19T19:44:52.537Z,1695152692.537 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2023-09-19T19:44:52.537Z,1695152692.537 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2023-09-19T19:44:52.538Z,1695152692.538 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2023-09-19T19:44:52.538Z,1695152692.538 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-09-19T19:44:52.538Z,1695152692.538 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-09-19T19:45:05.032Z,1695152705.032 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-09-19T19:45:05.036Z,1695152705.036 [BPC1](INFO): Received data from all battery sticks.
2023-09-19T19:45:07.155Z,1695152707.155 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T19:45:09.164Z,1695152709.164 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-19T19:45:09.471Z,1695152709.471 [Radio_Surface](INFO): Powering down
2023-09-19T19:46:39.168Z,1695152799.168 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-09-19T19:46:39.168Z,1695152799.168 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T19:46:39.169Z,1695152799.169 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2023-09-19T19:46:39.169Z,1695152799.169 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T19:46:39.241Z,1695152799.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T19:46:39.241Z,1695152799.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T19:46:39.648Z,1695152799.648 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T19:46:39.648Z,1695152799.648 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-09-19T19:46:39.648Z,1695152799.648 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T19:46:39.648Z,1695152799.648 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2023-09-19T19:46:39.982Z,1695152799.982 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:48:17.333Z,1695152897.333 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T19:48:17.333Z,1695152897.333 [NAL9602] Data Fault, FailCount= 1
2023-09-19T19:48:17.333Z,1695152897.333 [NAL9602](ERROR): Data Fault
2023-09-19T19:48:17.400Z,1695152897.400 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T19:48:17.737Z,1695152897.737 [NAL9602](INFO): Powering down
2023-09-19T19:48:18.641Z,1695152898.641 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T19:48:18.641Z,1695152898.641 [NAL9602] No Fault, FailCount= 1
2023-09-19T19:48:48.037Z,1695152928.037 [NAL9602](INFO): Powering up NAL9602
2023-09-19T19:48:58.945Z,1695152938.945 [NAL9602](INFO): NAL9602 initialized
2023-09-19T19:49:40.160Z,1695152980.160 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-09-19T19:49:40.160Z,1695152980.160 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T19:49:40.161Z,1695152980.161 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2023-09-19T19:49:40.161Z,1695152980.161 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T19:49:40.185Z,1695152980.185 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T19:49:40.186Z,1695152980.186 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T19:49:40.616Z,1695152980.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T19:49:40.617Z,1695152980.617 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-09-19T19:49:40.617Z,1695152980.617 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T19:49:40.617Z,1695152980.617 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2023-09-19T19:49:40.969Z,1695152980.969 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:52:41.153Z,1695153161.153 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-09-19T19:52:41.153Z,1695153161.153 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T19:52:41.154Z,1695153161.154 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2023-09-19T19:52:41.154Z,1695153161.154 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T19:52:41.190Z,1695153161.190 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T19:52:41.190Z,1695153161.190 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T19:52:41.577Z,1695153161.577 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T19:52:41.577Z,1695153161.577 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-09-19T19:52:41.578Z,1695153161.578 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T19:52:41.578Z,1695153161.578 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2023-09-19T19:52:41.968Z,1695153161.968 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:54:04.830Z,1695153244.830 [ballast_and_trim:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.data_dir_logging_rate with code: 1977
2023-09-19T19:55:42.145Z,1695153342.145 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-09-19T19:55:42.145Z,1695153342.145 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T19:55:42.146Z,1695153342.146 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2023-09-19T19:55:42.146Z,1695153342.146 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T19:55:42.162Z,1695153342.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T19:55:42.162Z,1695153342.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T19:55:42.708Z,1695153342.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T19:55:42.708Z,1695153342.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-09-19T19:55:42.708Z,1695153342.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T19:55:42.708Z,1695153342.708 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2023-09-19T19:55:42.961Z,1695153342.961 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:58:43.148Z,1695153523.148 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-09-19T19:58:43.148Z,1695153523.148 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T19:58:43.150Z,1695153523.150 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2023-09-19T19:58:43.150Z,1695153523.150 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T19:58:43.168Z,1695153523.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T19:58:43.168Z,1695153523.168 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T19:58:43.570Z,1695153523.570 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T19:58:43.570Z,1695153523.570 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-09-19T19:58:43.570Z,1695153523.570 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T19:58:43.570Z,1695153523.570 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2023-09-19T19:58:43.963Z,1695153523.963 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T19:59:00.113Z,1695153540.113 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T19:59:00.113Z,1695153540.113 [NAL9602] Data Fault, FailCount= 2
2023-09-19T19:59:00.113Z,1695153540.113 [NAL9602](ERROR): Data Fault
2023-09-19T19:59:00.185Z,1695153540.185 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T19:59:00.508Z,1695153540.508 [NAL9602](INFO): Powering down
2023-09-19T19:59:01.400Z,1695153541.400 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T19:59:01.400Z,1695153541.400 [NAL9602] No Fault, FailCount= 2
2023-09-19T19:59:30.809Z,1695153570.809 [NAL9602](INFO): Powering up NAL9602
2023-09-19T19:59:40.480Z,1695153580.480 [CommandExec](IMPORTANT): got command stop
2023-09-19T19:59:40.480Z,1695153580.480 [CommandExec](IMPORTANT): Scheduling is paused
2023-09-19T19:59:40.481Z,1695153580.481 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2023-09-19T19:59:40.524Z,1695153580.524 [MissionManager](INFO): MissionManager is completed.
2023-09-19T19:59:40.524Z,1695153580.524 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2023-09-19T19:59:40.524Z,1695153580.524 [ballast_and_trim] Stopped
2023-09-19T19:59:40.524Z,1695153580.524 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science:B] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science:C] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science:E] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science:OceanCurrent] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:BackseatDriver] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped
2023-09-19T19:59:40.525Z,1695153580.525 [ballast_and_trim:RunBallastAndTrim] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2023-09-19T19:59:40.526Z,1695153580.526 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T19:59:40.527Z,1695153580.527 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2023-09-19T19:59:40.527Z,1695153580.527 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-09-19T19:59:40.527Z,1695153580.527 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2023-09-19T19:59:40.560Z,1695153580.560 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-09-19T19:59:40.891Z,1695153580.891 [WetLabsBB2FL](INFO): Powering down
2023-09-19T19:59:40.935Z,1695153580.935 [MissionManager](IMPORTANT): Started mission Default
2023-09-19T19:59:40.935Z,1695153580.935 [Default] Running Loop=1
2023-09-19T19:59:40.939Z,1695153580.939 [Default](DEBUG): Aggregate::initialize Default
2023-09-19T19:59:40.939Z,1695153580.939 [Default:B.GoToSurface] Running Loop=1
2023-09-19T19:59:40.939Z,1695153580.939 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-09-19T19:59:40.940Z,1695153580.940 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-09-19T19:59:40.940Z,1695153580.940 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-09-19T19:59:40.940Z,1695153580.940 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-09-19T19:59:40.940Z,1695153580.940 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-09-19T19:59:40.941Z,1695153580.941 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-09-19T19:59:40.941Z,1695153580.941 [Default:A.Wait] Running Loop=1
2023-09-19T19:59:40.941Z,1695153580.941 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-09-19T19:59:40.961Z,1695153580.961 [VerticalControl](CRITICAL): Failure to ascend, stopping mission after 316.731354 seconds, depthRate=0.003297 m/s, pitch=-11.999137 deg.
2023-09-19T19:59:40.989Z,1695153580.989 [CommandExec](FAULT): Scheduling is paused
2023-09-19T19:59:40.989Z,1695153580.989 [CBIT](INFO): Critical error at 20230919T195940
2023-09-19T19:59:41.100Z,1695153581.100 [BackseatComponent](INFO): Sent LCM shutdown request.
2023-09-19T19:59:41.100Z,1695153581.100 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2023-09-19T19:59:41.347Z,1695153581.347 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -27.00 mm (1 active estimators).
2023-09-19T19:59:41.459Z,1695153581.459 [Radio_Surface](INFO): Powering up
2023-09-19T19:59:41.721Z,1695153581.721 [NAL9602](INFO): NAL9602 initialized
2023-09-19T19:59:46.767Z,1695153586.767 [DataOverHttps](INFO): Radio surface powered ON.
2023-09-19T19:59:54.272Z,1695153594.272 [Default:A.Wait](INFO): Done Waiting.
2023-09-19T19:59:54.272Z,1695153594.272 [Default:A.Wait] Stopped
2023-09-19T19:59:54.272Z,1695153594.272 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T19:59:59.275Z,1695153599.275 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2023-09-19T19:59:59.276Z,1695153599.276 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2023-09-19T19:59:59.276Z,1695153599.276 [LcmUniversalReporter](INFO): Deactivating messaging.
2023-09-19T19:59:59.901Z,1695153599.901 [Power24vConverter](INFO): Powering down.
2023-09-19T20:00:16.891Z,1695153616.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T20:00:41.144Z,1695153641.144 [Default:CheckIn] Running Loop=1
2023-09-19T20:00:41.144Z,1695153641.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T20:00:41.145Z,1695153641.145 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T20:00:52.035Z,1695153652.035 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T20:01:27.179Z,1695153687.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T20:01:44.139Z,1695153704.139 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-09-19T20:01:44.139Z,1695153704.139 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:01:44.140Z,1695153704.140 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2023-09-19T20:01:44.140Z,1695153704.140 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:01:44.151Z,1695153704.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:01:44.152Z,1695153704.152 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:01:44.566Z,1695153704.566 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:01:44.566Z,1695153704.566 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-09-19T20:01:44.567Z,1695153704.567 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:01:44.567Z,1695153704.567 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2023-09-19T20:01:44.968Z,1695153704.968 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:02:02.320Z,1695153722.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T20:02:37.463Z,1695153757.463 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-09-19T20:03:09.679Z,1695153789.679 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002995
2023-09-19T20:04:45.149Z,1695153885.149 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-09-19T20:04:45.149Z,1695153885.149 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:04:45.150Z,1695153885.150 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2023-09-19T20:04:45.150Z,1695153885.150 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:04:45.161Z,1695153885.161 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:04:45.161Z,1695153885.161 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:04:45.578Z,1695153885.578 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:04:45.578Z,1695153885.578 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-09-19T20:04:45.578Z,1695153885.578 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:04:45.578Z,1695153885.578 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2023-09-19T20:04:45.955Z,1695153885.955 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:05:41.357Z,1695153941.357 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T20:00:41.1Z
2023-09-19T20:05:41.357Z,1695153941.357 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T20:05:41.357Z,1695153941.357 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T20:05:41.734Z,1695153941.734 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-09-19T20:05:47.792Z,1695153947.792 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20230919T193726/Courier0004.lzma
2023-09-19T20:05:48.805Z,1695153948.805 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Courier0004.lzma.bak
2023-09-19T20:05:48.805Z,1695153948.805 [DataOverHttps](INFO): SBD MOMSN=18798014
2023-09-19T20:06:09.763Z,1695153969.763 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T193726/Courier0007.lzma
2023-09-19T20:06:10.765Z,1695153970.765 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Courier0007.lzma.bak
2023-09-19T20:06:10.765Z,1695153970.765 [DataOverHttps](INFO): SBD MOMSN=18798017
2023-09-19T20:06:28.389Z,1695153988.389 [DataOverHttps](INFO): Sending 1097 bytes from file Logs/20230919T193726/Express0001.lzma
2023-09-19T20:06:29.389Z,1695153989.389 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Express0001.lzma.bak
2023-09-19T20:06:29.389Z,1695153989.389 [DataOverHttps](INFO): SBD MOMSN=18798019
2023-09-19T20:06:47.020Z,1695154007.020 [DataOverHttps](INFO): Sending 620 bytes from file Logs/20230919T193726/Express0005.lzma
2023-09-19T20:06:48.021Z,1695154008.021 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Express0005.lzma.bak
2023-09-19T20:06:48.021Z,1695154008.021 [DataOverHttps](INFO): SBD MOMSN=18798052
2023-09-19T20:07:05.626Z,1695154025.626 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T193726/Express0008.lzma
2023-09-19T20:07:06.629Z,1695154026.629 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Express0008.lzma.bak
2023-09-19T20:07:06.629Z,1695154026.629 [DataOverHttps](INFO): SBD MOMSN=18798075
2023-09-19T20:07:09.950Z,1695154029.950 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T20:07:09.951Z,1695154029.951 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T20:07:09.951Z,1695154029.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T20:07:46.312Z,1695154066.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-09-19T20:07:46.312Z,1695154066.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:07:46.313Z,1695154066.313 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2023-09-19T20:07:46.313Z,1695154066.313 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:07:46.332Z,1695154066.332 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:07:46.333Z,1695154066.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:07:46.751Z,1695154066.751 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:07:46.751Z,1695154066.751 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-09-19T20:07:46.752Z,1695154066.752 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:07:46.752Z,1695154066.752 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2023-09-19T20:07:47.116Z,1695154067.116 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:09:43.448Z,1695154183.448 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-09-19T20:09:43.449Z,1695154183.449 [NAL9602] Data Fault, FailCount= 3
2023-09-19T20:09:43.449Z,1695154183.449 [NAL9602](ERROR): Data Fault
2023-09-19T20:09:43.571Z,1695154183.571 [CBIT](ERROR): Data Fault in component: NAL9602
2023-09-19T20:09:43.856Z,1695154183.856 [NAL9602](INFO): Powering down
2023-09-19T20:09:44.730Z,1695154184.730 [CBIT](INFO): Clearing failed state for component NAL9602
2023-09-19T20:09:44.730Z,1695154184.730 [NAL9602] No Fault, FailCount= 3
2023-09-19T20:10:14.173Z,1695154214.173 [NAL9602](INFO): Powering up NAL9602
2023-09-19T20:10:25.077Z,1695154225.077 [NAL9602](INFO): NAL9602 initialized
2023-09-19T20:10:47.309Z,1695154247.309 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-09-19T20:10:47.309Z,1695154247.309 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:10:47.310Z,1695154247.310 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2023-09-19T20:10:47.310Z,1695154247.310 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:10:47.356Z,1695154247.356 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:10:47.356Z,1695154247.356 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:10:47.717Z,1695154247.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:10:47.717Z,1695154247.717 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-09-19T20:10:47.718Z,1695154247.718 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:10:47.718Z,1695154247.718 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2023-09-19T20:10:48.132Z,1695154248.132 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:12:10.529Z,1695154330.529 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-09-19T20:12:10.529Z,1695154330.529 [Default:CheckIn:C.Wait] Stopped
2023-09-19T20:12:10.529Z,1695154330.529 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T20:12:10.529Z,1695154330.529 [Default:CheckIn:D] Running Loop=1
2023-09-19T20:12:10.933Z,1695154330.933 [Default:CheckIn:D] Stopped
2023-09-19T20:12:10.933Z,1695154330.933 [Default:CheckIn:E] Running Loop=1
2023-09-19T20:12:11.338Z,1695154331.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.499975 min 
2023-09-19T20:12:11.339Z,1695154331.339 [Default:CheckIn:E] Stopped
2023-09-19T20:12:11.339Z,1695154331.339 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-09-19T20:12:11.339Z,1695154331.339 [Default:CheckIn] Stopped
2023-09-19T20:12:11.339Z,1695154331.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T20:12:11.339Z,1695154331.339 [Default:CheckIn](INFO): Running loop #2
2023-09-19T20:12:11.339Z,1695154331.339 [Default:CheckIn] Running Loop=2
2023-09-19T20:12:11.340Z,1695154331.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-09-19T20:12:11.340Z,1695154331.340 [Default:CheckIn:Read_GPS] Running Loop=1
2023-09-19T20:13:48.305Z,1695154428.305 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-09-19T20:13:48.305Z,1695154428.305 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:13:48.306Z,1695154428.306 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2023-09-19T20:13:48.306Z,1695154428.306 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:13:48.381Z,1695154428.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:13:48.381Z,1695154428.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:13:48.768Z,1695154428.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:13:48.769Z,1695154428.769 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-09-19T20:13:48.769Z,1695154428.769 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:13:48.769Z,1695154428.769 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2023-09-19T20:13:49.121Z,1695154429.121 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:16:49.351Z,1695154609.351 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-09-19T20:16:49.351Z,1695154609.351 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-09-19T20:16:49.364Z,1695154609.364 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2023-09-19T20:16:49.364Z,1695154609.364 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-09-19T20:16:49.374Z,1695154609.374 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-09-19T20:16:49.374Z,1695154609.374 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-09-19T20:16:49.750Z,1695154609.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-09-19T20:16:49.751Z,1695154609.751 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-09-19T20:16:49.755Z,1695154609.755 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-09-19T20:16:49.755Z,1695154609.755 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2023-09-19T20:16:50.148Z,1695154610.148 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-09-19T20:17:11.565Z,1695154631.565 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-19T20:12:11.3Z
2023-09-19T20:17:11.565Z,1695154631.565 [Default:CheckIn:Read_GPS] Stopped
2023-09-19T20:17:11.565Z,1695154631.565 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-09-19T20:17:19.942Z,1695154639.942 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230919T193726/Courier0010.lzma
2023-09-19T20:17:20.945Z,1695154640.945 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Courier0010.lzma.bak
2023-09-19T20:17:20.945Z,1695154640.945 [DataOverHttps](INFO): SBD MOMSN=18798194
2023-09-19T20:17:39.267Z,1695154659.267 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20230919T193726/Express0011.lzma
2023-09-19T20:17:40.269Z,1695154660.269 [DataOverHttps](INFO): Moved sent file to Logs/20230919T193726/Express0011.lzma.bak
2023-09-19T20:17:40.269Z,1695154660.269 [DataOverHttps](INFO): SBD MOMSN=18798196
2023-09-19T20:17:43.941Z,1695154663.941 [Default:CheckIn:Read_Iridium] Stopped
2023-09-19T20:17:43.941Z,1695154663.941 [Default:CheckIn:C.Wait] Running Loop=1
2023-09-19T20:17:43.942Z,1695154663.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-09-19T20:19:38.344Z,1695154778.344 [CommandExec](IMPORTANT): got command restart application
2023-09-19T20:19:39.347Z,1695154779.347 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:39.347Z,1695154779.347 [CommandExec](INFO): Uninitializing the command executive.
2023-09-19T20:19:39.347Z,1695154779.347 [CommandExec](INFO): Uninitializing the command scheduler.
2023-09-19T20:19:39.348Z,1695154779.348 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:39.503Z,1695154779.503 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-09-19T20:19:39.503Z,1695154779.503 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-09-19T20:19:39.503Z,1695154779.503 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:39.504Z,1695154779.504 [NavChartDb](INFO): Join timeout helper Thread ID is 1595
2023-09-19T20:19:39.576Z,1695154779.576 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:39.576Z,1695154779.576 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:39.586Z,1695154779.586 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-09-19T20:19:39.586Z,1695154779.586 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:39.586Z,1695154779.586 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1596
2023-09-19T20:19:39.787Z,1695154779.787 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:39.787Z,1695154779.787 [WetLabsBB2FL](INFO): Powering down
2023-09-19T20:19:39.788Z,1695154779.788 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:39.807Z,1695154779.807 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-09-19T20:19:39.807Z,1695154779.807 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:39.808Z,1695154779.808 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1597
2023-09-19T20:19:40.415Z,1695154780.415 [CTD_Seabird](INFO): Powering down
2023-09-19T20:19:40.431Z,1695154780.431 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:40.431Z,1695154780.431 [CTD_Seabird](INFO): Powering down
2023-09-19T20:19:40.443Z,1695154780.443 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:40.451Z,1695154780.451 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-09-19T20:19:40.451Z,1695154780.451 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:40.451Z,1695154780.451 [Radio_Surface](INFO): Join timeout helper Thread ID is 1598
2023-09-19T20:19:40.789Z,1695154780.789 [Radio_Surface](INFO): Powering down
2023-09-19T20:19:40.789Z,1695154780.789 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:40.790Z,1695154780.790 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:40.795Z,1695154780.795 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-09-19T20:19:40.795Z,1695154780.795 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:40.795Z,1695154780.795 [Onboard](INFO): Join timeout helper Thread ID is 1599
2023-09-19T20:19:41.007Z,1695154781.007 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-09-19T20:19:43.983Z,1695154783.983 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:43.983Z,1695154783.983 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:43.000Z,1695154784.000 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-09-19T20:19:44.000Z,1695154784.000 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.000Z,1695154784.000 [DataOverHttps](INFO): Join timeout helper Thread ID is 1600
2023-09-19T20:19:44.019Z,1695154784.019 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:44.020Z,1695154784.020 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.032Z,1695154784.032 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-09-19T20:19:44.032Z,1695154784.032 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.032Z,1695154784.032 [BackseatComponent](INFO): Join timeout helper Thread ID is 1601
2023-09-19T20:19:44.227Z,1695154784.227 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:44.228Z,1695154784.228 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.248Z,1695154784.248 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-09-19T20:19:44.248Z,1695154784.248 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.248Z,1695154784.248 [logger](INFO): Join timeout helper Thread ID is 1602
2023-09-19T20:19:44.275Z,1695154784.275 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:44.275Z,1695154784.275 [logger ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.288Z,1695154784.288 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-09-19T20:19:44.288Z,1695154784.288 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.288Z,1695154784.288 [CommandLine](INFO): Join timeout helper Thread ID is 1603
2023-09-19T20:19:44.311Z,1695154784.311 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:44.311Z,1695154784.311 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.328Z,1695154784.328 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-09-19T20:19:44.328Z,1695154784.328 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.328Z,1695154784.328 [CommandExec](INFO): Join timeout helper Thread ID is 1604
2023-09-19T20:19:44.332Z,1695154784.332 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-09-19T20:19:44.332Z,1695154784.332 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.332Z,1695154784.332 [controlThread](INFO): Join timeout helper Thread ID is 1605
2023-09-19T20:19:44.369Z,1695154784.369 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-09-19T20:19:44.369Z,1695154784.369 [controlThread](DEBUG): Uninitializing ControlThread
2023-09-19T20:19:44.369Z,1695154784.369 [AHRS_M2](INFO): Powering down
2023-09-19T20:19:44.440Z,1695154784.440 [MultiRay](INFO): Powering down.
2023-09-19T20:19:44.441Z,1695154784.441 [NAL9602](INFO): Powering down
2023-09-19T20:19:44.443Z,1695154784.443 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-09-19T20:19:44.444Z,1695154784.444 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-09-19T20:19:44.444Z,1695154784.444 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-09-19T20:19:44.445Z,1695154784.445 [MissionManager](INFO): Uninitializing Mission Default
2023-09-19T20:19:44.445Z,1695154784.445 [Default] Stopped
2023-09-19T20:19:44.445Z,1695154784.445 [Default](DEBUG): Aggregate::uninitialize Default
2023-09-19T20:19:44.445Z,1695154784.445 [Default:B.GoToSurface] Stopped
2023-09-19T20:19:44.445Z,1695154784.445 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-09-19T20:19:44.445Z,1695154784.445 [Default:CheckIn] Stopped
2023-09-19T20:19:44.445Z,1695154784.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-09-19T20:19:44.445Z,1695154784.445 [Default:CheckIn:C.Wait] Stopped
2023-09-19T20:19:44.445Z,1695154784.445 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-09-19T20:19:44.448Z,1695154784.448 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-09-19T20:19:44.449Z,1695154784.448 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-09-19T20:19:44.449Z,1695154784.449 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-09-19T20:19:44.449Z,1695154784.449 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-09-19T20:19:44.449Z,1695154784.449 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-09-19T20:19:44.449Z,1695154784.449 [BuoyancyServo](INFO): Powering down
2023-09-19T20:19:44.463Z,1695154784.463 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-09-19T20:19:44.463Z,1695154784.463 [ElevatorServo](INFO): Powering down
2023-09-19T20:19:44.464Z,1695154784.464 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-09-19T20:19:44.464Z,1695154784.464 [MassServo](INFO): Powering down
2023-09-19T20:19:44.465Z,1695154784.465 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-09-19T20:19:44.465Z,1695154784.465 [RudderServo](INFO): Powering down
2023-09-19T20:19:44.466Z,1695154784.466 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-09-19T20:19:44.466Z,1695154784.466 [ThrusterHE](INFO): Powering down
2023-09-19T20:19:44.467Z,1695154784.467 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-09-19T20:19:44.467Z,1695154784.467 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-09-19T20:19:44.468Z,1695154784.468 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-09-19T20:19:44.468Z,1695154784.468 [CBIT](DEBUG): Powering off loads.
2023-09-19T20:19:44.479Z,1695154784.479 [CBIT](DEBUG): Disabling WDT.
2023-09-19T20:19:44.491Z,1695154784.491 [CBIT](DEBUG): Opening all GF detection circuits.
2023-09-19T20:19:44.492Z,1695154784.492 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.514Z,1695154784.514 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.518Z,1695154784.518 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.525Z,1695154784.525 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.568Z,1695154784.568 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.617Z,1695154784.617 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.628Z,1695154784.628 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.678Z,1695154784.678 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-09-19T20:19:44.725Z,1695154784.725 [logger ThreadHandler](INFO): Thread cancelled.