2023-08-04T23:27:55.040Z,1691191675.040 [Supervisor](DEBUG): Initializing supervisor.
2023-08-04T23:27:55.045Z,1691191675.045 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-04T23:27:55.045Z,1691191675.045 [SyncHandler](INFO): Protected caller Thread ID is 1046
2023-08-04T23:27:55.046Z,1691191675.046 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-04T23:27:55.047Z,1691191675.047 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-04T23:27:55.047Z,1691191675.047 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1047
2023-08-04T23:27:55.051Z,1691191675.051 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-04T23:27:55.068Z,1691191675.068 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-04T23:27:55.069Z,1691191675.069 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-04T23:27:55.069Z,1691191675.069 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1048
2023-08-04T23:27:55.073Z,1691191675.073 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-04T23:27:55.074Z,1691191675.074 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-04T23:27:55.075Z,1691191675.075 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1049
2023-08-04T23:27:55.077Z,1691191675.077 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-04T23:27:55.078Z,1691191675.078 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-04T23:27:55.078Z,1691191675.078 [logger ThreadHandler](INFO): Protected caller Thread ID is 1050
2023-08-04T23:27:55.082Z,1691191675.082 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-04T23:27:55.082Z,1691191675.082 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-04T23:27:55.084Z,1691191675.084 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-04T23:27:55.182Z,1691191675.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-04T23:27:55.182Z,1691191675.182 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-04T23:27:55.858Z,1691191675.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-04T23:27:55.858Z,1691191675.858 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-04T23:27:56.073Z,1691191676.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-04T23:27:56.074Z,1691191676.074 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-04T23:27:56.154Z,1691191676.154 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-04T23:27:56.284Z,1691191676.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-04T23:27:56.285Z,1691191676.285 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-04T23:27:56.367Z,1691191676.367 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-04T23:27:56.476Z,1691191676.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-04T23:27:56.477Z,1691191676.477 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-04T23:27:56.848Z,1691191676.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-04T23:27:56.848Z,1691191676.848 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-04T23:27:57.321Z,1691191677.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-04T23:27:57.321Z,1691191677.321 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-04T23:27:57.532Z,1691191677.532 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-04T23:27:57.533Z,1691191677.533 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-04T23:27:57.718Z,1691191677.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-04T23:27:57.719Z,1691191677.719 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-04T23:27:58.220Z,1691191678.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-04T23:27:58.221Z,1691191678.221 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-04T23:27:58.499Z,1691191678.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-04T23:27:58.499Z,1691191678.499 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-04T23:27:58.718Z,1691191678.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-04T23:27:58.719Z,1691191678.719 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-04T23:27:59.108Z,1691191679.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-04T23:27:59.108Z,1691191679.108 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-04T23:27:59.766Z,1691191679.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-04T23:27:59.768Z,1691191679.768 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2023-08-04T23:27:59.769Z,1691191679.769 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2023-08-04T23:27:59.975Z,1691191679.975 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2023-08-04T23:28:00.419Z,1691191680.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2023-08-04T23:28:00.575Z,1691191680.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2023-08-04T23:28:00.663Z,1691191680.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2023-08-04T23:28:00.759Z,1691191680.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2023-08-04T23:28:00.875Z,1691191680.875 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2023-08-04T23:28:01.143Z,1691191681.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-04T23:28:01.143Z,1691191681.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2023-08-04T23:28:01.239Z,1691191681.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2023-08-04T23:28:01.338Z,1691191681.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2023-08-04T23:28:01.478Z,1691191681.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2023-08-04T23:28:01.580Z,1691191681.580 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2023-08-04T23:28:01.580Z,1691191681.580 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-08-04T23:28:01.589Z,1691191681.589 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-04T23:28:01.687Z,1691191681.687 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-04T23:28:01.687Z,1691191681.687 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-04T23:28:01.737Z,1691191681.737 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-04T23:28:01.796Z,1691191681.796 [VerticalControl] Loaded
2023-08-04T23:28:01.796Z,1691191681.796 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-04T23:28:01.799Z,1691191681.799 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-04T23:28:01.840Z,1691191681.840 [HorizontalControl] Loaded
2023-08-04T23:28:01.840Z,1691191681.840 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-04T23:28:01.842Z,1691191681.842 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-04T23:28:01.845Z,1691191681.845 [SpeedControl] Loaded
2023-08-04T23:28:01.845Z,1691191681.845 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-04T23:28:01.848Z,1691191681.848 [LoopControl](DEBUG): Construct LoopControl.
2023-08-04T23:28:01.848Z,1691191681.848 [LoopControl] Loaded
2023-08-04T23:28:01.849Z,1691191681.849 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-04T23:28:01.849Z,1691191681.849 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-04T23:28:01.850Z,1691191681.850 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-04T23:28:01.899Z,1691191681.899 [DepthRateCalculator] Loaded
2023-08-04T23:28:01.899Z,1691191681.899 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-04T23:28:01.904Z,1691191681.904 [PitchRateCalculator] Loaded
2023-08-04T23:28:01.904Z,1691191681.904 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-04T23:28:01.914Z,1691191681.914 [SpeedCalculator] Loaded
2023-08-04T23:28:01.914Z,1691191681.914 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-04T23:28:01.918Z,1691191681.918 [YawRateCalculator] Loaded
2023-08-04T23:28:01.918Z,1691191681.918 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-04T23:28:01.936Z,1691191681.936 [ElevatorOffsetCalculator] Loaded
2023-08-04T23:28:01.937Z,1691191681.937 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-04T23:28:01.937Z,1691191681.937 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-04T23:28:01.938Z,1691191681.938 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-04T23:28:01.964Z,1691191681.964 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-04T23:28:01.965Z,1691191681.965 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-04T23:28:02.019Z,1691191682.019 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-04T23:28:02.019Z,1691191682.019 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-04T23:28:02.317Z,1691191682.317 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-04T23:28:02.318Z,1691191682.318 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-04T23:28:02.462Z,1691191682.462 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-04T23:28:02.462Z,1691191682.462 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-04T23:28:02.900Z,1691191682.900 [AHRS_M2] Loaded
2023-08-04T23:28:02.900Z,1691191682.900 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-04T23:28:02.935Z,1691191682.935 [BackseatComponent] Loaded
2023-08-04T23:28:02.936Z,1691191682.936 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-04T23:28:02.937Z,1691191682.937 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4098F4E0
2023-08-04T23:28:02.937Z,1691191682.937 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1132
2023-08-04T23:28:02.940Z,1691191682.940 [LcmUniversalReporter] Loaded
2023-08-04T23:28:02.940Z,1691191682.940 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-04T23:28:03.745Z,1691191683.745 [BPC1] Loaded
2023-08-04T23:28:03.745Z,1691191683.745 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-04T23:28:03.818Z,1691191683.818 [DataOverHttps] Loaded
2023-08-04T23:28:03.818Z,1691191683.818 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-04T23:28:03.819Z,1691191683.819 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2023-08-04T23:28:03.819Z,1691191683.819 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1133
2023-08-04T23:28:03.840Z,1691191683.840 [Depth_Keller] Loaded
2023-08-04T23:28:03.840Z,1691191683.840 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-04T23:28:03.845Z,1691191683.845 [DropWeight] Loaded
2023-08-04T23:28:03.845Z,1691191683.845 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-04T23:28:03.908Z,1691191683.908 [NAL9602] Loaded
2023-08-04T23:28:03.908Z,1691191683.908 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-04T23:28:03.938Z,1691191683.938 [Onboard] Loaded
2023-08-04T23:28:03.939Z,1691191683.939 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-04T23:28:03.940Z,1691191683.940 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2023-08-04T23:28:03.940Z,1691191683.940 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1134
2023-08-04T23:28:03.946Z,1691191683.946 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2023-08-04T23:28:03.957Z,1691191683.957 [PowerOnly] Loaded
2023-08-04T23:28:03.958Z,1691191683.958 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-08-04T23:28:03.969Z,1691191683.969 [Power24vConverter] Loaded
2023-08-04T23:28:03.970Z,1691191683.970 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-04T23:28:03.983Z,1691191683.983 [Radio_Surface] Loaded
2023-08-04T23:28:03.983Z,1691191683.983 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-04T23:28:03.984Z,1691191683.984 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0
2023-08-04T23:28:03.984Z,1691191683.984 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1135
2023-08-04T23:28:04.030Z,1691191684.030 [RDI_Pathfinder] Loaded
2023-08-04T23:28:04.030Z,1691191684.030 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2023-08-04T23:28:04.121Z,1691191684.121 [DAT] Loaded
2023-08-04T23:28:04.121Z,1691191684.121 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-08-04T23:28:04.122Z,1691191684.122 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A4F4E0
2023-08-04T23:28:04.123Z,1691191684.123 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1136
2023-08-04T23:28:04.123Z,1691191684.123 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-04T23:28:04.124Z,1691191684.124 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-04T23:28:04.511Z,1691191684.511 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-08-04T23:28:04.511Z,1691191684.511 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-08-04T23:28:04.525Z,1691191684.525 [NavChart] Loaded
2023-08-04T23:28:04.525Z,1691191684.525 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-04T23:28:04.531Z,1691191684.531 [UniversalFixResidualReporter] Loaded
2023-08-04T23:28:04.531Z,1691191684.531 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-04T23:28:04.532Z,1691191684.532 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-04T23:28:04.532Z,1691191684.532 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-04T23:28:04.790Z,1691191684.790 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-04T23:28:04.851Z,1691191684.851 [SBIT] Loaded
2023-08-04T23:28:04.851Z,1691191684.851 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-04T23:28:04.878Z,1691191684.878 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-04T23:28:04.907Z,1691191684.907 [IBIT] Loaded
2023-08-04T23:28:04.907Z,1691191684.907 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-04T23:28:04.921Z,1691191684.921 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-04T23:28:05.026Z,1691191685.026 [CBIT] Loaded
2023-08-04T23:28:05.027Z,1691191685.027 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-04T23:28:05.027Z,1691191685.027 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-04T23:28:05.027Z,1691191685.027 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-04T23:28:05.153Z,1691191685.153 [BuoyancyServo] Loaded
2023-08-04T23:28:05.153Z,1691191685.153 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-04T23:28:05.174Z,1691191685.174 [ElevatorServo] Loaded
2023-08-04T23:28:05.174Z,1691191685.174 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-04T23:28:05.194Z,1691191685.194 [MassServo] Loaded
2023-08-04T23:28:05.194Z,1691191685.194 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-04T23:28:05.212Z,1691191685.212 [RudderServo] Loaded
2023-08-04T23:28:05.213Z,1691191685.213 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-04T23:28:05.230Z,1691191685.230 [ThrusterServo] Loaded
2023-08-04T23:28:05.230Z,1691191685.230 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-08-04T23:28:05.231Z,1691191685.231 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-04T23:28:05.231Z,1691191685.231 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-04T23:28:05.241Z,1691191685.241 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-04T23:28:05.242Z,1691191685.242 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-04T23:28:05.412Z,1691191685.412 [CTD_Seabird] Loaded
2023-08-04T23:28:05.412Z,1691191685.412 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-04T23:28:05.413Z,1691191685.413 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9A4E0
2023-08-04T23:28:05.413Z,1691191685.413 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1137
2023-08-04T23:28:05.434Z,1691191685.434 [PAR_Licor] Loaded
2023-08-04T23:28:05.434Z,1691191685.434 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-04T23:28:05.474Z,1691191685.474 [WetLabsBB2FL] Loaded
2023-08-04T23:28:05.474Z,1691191685.474 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-04T23:28:05.475Z,1691191685.475 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCA4E0
2023-08-04T23:28:05.476Z,1691191685.476 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1138
2023-08-04T23:28:05.495Z,1691191685.495 [WetLabsUBAT] Loaded
2023-08-04T23:28:05.495Z,1691191685.495 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2023-08-04T23:28:05.496Z,1691191685.496 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40BFA4E0
2023-08-04T23:28:05.497Z,1691191685.497 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1139
2023-08-04T23:28:05.497Z,1691191685.497 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-04T23:28:05.504Z,1691191685.504 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-04T23:28:05.506Z,1691191685.506 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-04T23:28:05.518Z,1691191685.518 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-04T23:28:05.519Z,1691191685.519 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C2A4E0
2023-08-04T23:28:05.519Z,1691191685.519 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1140
2023-08-04T23:28:05.523Z,1691191685.523 [Supervisor](INFO): Main Thread ID is 9888
2023-08-04T23:28:05.524Z,1691191685.524 [Supervisor](DEBUG): Running supervisor.
2023-08-04T23:28:05.524Z,1691191685.524 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1141
2023-08-04T23:28:05.525Z,1691191685.525 [CommandExec](INFO): Initializing the command executive.
2023-08-04T23:28:05.526Z,1691191685.526 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1142
2023-08-04T23:28:05.529Z,1691191685.529 [controlThread ThreadHandler](INFO): Handler Thread ID is 1143
2023-08-04T23:28:05.530Z,1691191685.530 [controlThread](DEBUG): Initializing ControlThread
2023-08-04T23:28:05.531Z,1691191685.531 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-04T23:28:05.533Z,1691191685.533 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-04T23:28:05.533Z,1691191685.533 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-04T23:28:05.534Z,1691191685.534 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-04T23:28:05.534Z,1691191685.534 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-04T23:28:05.534Z,1691191685.534 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-04T23:28:05.535Z,1691191685.535 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-04T23:28:05.535Z,1691191685.535 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-04T23:28:05.535Z,1691191685.535 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-04T23:28:05.540Z,1691191685.540 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-04T23:28:05.541Z,1691191685.541 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-04T23:28:05.541Z,1691191685.541 [SBIT](INFO): Initialize SBIT Component.
2023-08-04T23:28:05.542Z,1691191685.542 [SBIT](IMPORTANT): git: 2023-08-02-8-ga2b2183f7
2023-08-04T23:28:05.542Z,1691191685.542 [SBIT](INFO): git hash: a2b2183f71e07b707bfeaaf571c74c3561721119
2023-08-04T23:28:05.542Z,1691191685.542 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-04T23:28:05.543Z,1691191685.543 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-08-04T23:28:05.544Z,1691191685.544 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2023-08-04T23:28:05.545Z,1691191685.545 [IBIT](INFO): Initialize IBIT Component.
2023-08-04T23:28:05.546Z,1691191685.546 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-04T23:28:05.547Z,1691191685.547 [logger ThreadHandler](INFO): Handler Thread ID is 1144
2023-08-04T23:28:05.558Z,1691191685.558 [CBIT](DEBUG): Initialized mux pins.
2023-08-04T23:28:05.558Z,1691191685.558 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-04T23:28:05.566Z,1691191685.566 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1145
2023-08-04T23:28:05.578Z,1691191685.578 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1146
2023-08-04T23:28:05.579Z,1691191685.579 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-04T23:28:05.582Z,1691191685.582 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-04T23:28:05.582Z,1691191685.582 [CBIT](DEBUG): Initializing heartbeat.
2023-08-04T23:28:05.590Z,1691191685.590 [Onboard ThreadHandler](INFO): Handler Thread ID is 1147
2023-08-04T23:28:05.607Z,1691191685.607 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1148
2023-08-04T23:28:05.627Z,1691191685.627 [DAT ThreadHandler](INFO): Handler Thread ID is 1149
2023-08-04T23:28:05.627Z,1691191685.627 [DAT](INFO): Powering up
2023-08-04T23:28:05.627Z,1691191685.627 [DAT](DEBUG): Initializing DAT.
2023-08-04T23:28:05.631Z,1691191685.631 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1151
2023-08-04T23:28:05.632Z,1691191685.632 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-04T23:28:05.635Z,1691191685.635 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1153
2023-08-04T23:28:05.638Z,1691191685.638 [WetLabsBB2FL](INFO): Powering up
2023-08-04T23:28:05.639Z,1691191685.639 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1155
2023-08-04T23:28:05.642Z,1691191685.642 [WetLabsUBAT](INFO): Powering up
2023-08-04T23:28:05.644Z,1691191685.644 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1157
2023-08-04T23:28:05.647Z,1691191685.647 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-08-04T23:28:05.647Z,1691191685.647 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-08-04T23:28:05.647Z,1691191685.647 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-08-04T23:28:05.648Z,1691191685.648 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-08-04T23:28:05.648Z,1691191685.648 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-08-04T23:28:05.648Z,1691191685.648 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-08-04T23:28:05.648Z,1691191685.648 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-08-04T23:28:05.648Z,1691191685.648 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-08-04T23:28:05.654Z,1691191685.654 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-04T23:28:05.654Z,1691191685.654 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-04T23:28:05.694Z,1691191685.694 [CBIT](DEBUG): Backplane powered.
2023-08-04T23:28:05.699Z,1691191685.699 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-04T23:28:05.719Z,1691191685.719 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-04T23:28:05.751Z,1691191685.751 [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-08-04T23:28:05.752Z,1691191685.752 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-04T23:28:05.857Z,1691191685.857 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-04T23:28:05.875Z,1691191685.875 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-04T23:28:05.877Z,1691191685.877 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-04T23:28:05.913Z,1691191685.913 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-04T23:28:05.915Z,1691191685.915 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-04T23:28:05.937Z,1691191685.937 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-04T23:28:05.957Z,1691191685.957 [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-08-04T23:28:05.962Z,1691191685.962 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-08-04T23:28:05.968Z,1691191685.968 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-04T23:28:06.014Z,1691191686.014 [Radio_Surface](INFO): Powering up
2023-08-04T23:28:06.051Z,1691191686.051 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993016 m
2023-08-04T23:28:06.053Z,1691191686.053 [PowerOnly](INFO): Powering up loadControl
2023-08-04T23:28:06.071Z,1691191686.071 [Power24vConverter](INFO): Powering up.
2023-08-04T23:28:06.072Z,1691191686.072 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2023-08-04T23:28:06.088Z,1691191686.088 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-08-04T23:28:06.147Z,1691191686.147 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-04T23:28:06.154Z,1691191686.154 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-04T23:28:06.155Z,1691191686.155 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-04T23:28:06.162Z,1691191686.162 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-04T23:28:06.163Z,1691191686.163 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-04T23:28:06.170Z,1691191686.170 [MassServo](DEBUG): Initializing MassServo.
2023-08-04T23:28:06.171Z,1691191686.171 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-04T23:28:06.178Z,1691191686.178 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-04T23:28:06.179Z,1691191686.179 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-08-04T23:28:06.186Z,1691191686.186 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-08-04T23:28:08.250Z,1691191688.250 [WetLabsBB2FL](INFO): Powering down
2023-08-04T23:28:14.910Z,1691191694.910 [WetLabsUBAT](INFO): Powering down
2023-08-04T23:28:17.723Z,1691191697.723 [DAT](INFO): DAT read: 

2023-08-04T23:28:17.724Z,1691191697.724 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-08-04T23:28:19.489Z,1691191699.489 [DAT](INFO): DAT read: MF Frequency Band

2023-08-04T23:28:19.492Z,1691191699.492 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-08-04T23:28:19.495Z,1691191699.495 [DAT](INFO): DAT read: Aug  4 2023 23:28:13 

2023-08-04T23:28:20.498Z,1691191700.498 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-08-04T23:28:20.747Z,1691191700.747 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2023-08-04T23:28:20.748Z,1691191700.748 [DAT](INFO): commRate: 800
2023-08-04T23:28:22.814Z,1691191702.814 [DAT](INFO): entering command mode
2023-08-04T23:28:23.014Z,1691191703.014 [DAT](INFO): DAT read: 

2023-08-04T23:28:23.015Z,1691191703.015 [DAT](INFO): DAT read: user:1>

2023-08-04T23:28:23.015Z,1691191703.015 [DAT](INFO): setting verbose to 3
2023-08-04T23:28:23.266Z,1691191703.266 [DAT](INFO): DAT read: user:1>

2023-08-04T23:28:23.267Z,1691191703.267 [DAT](INFO): DAT read: Verbose         | 3           

2023-08-04T23:28:23.268Z,1691191703.268 [DAT](INFO): set verbose to 3
2023-08-04T23:28:23.268Z,1691191703.268 [DAT](INFO): setting DatVerbose to 27440
2023-08-04T23:28:23.520Z,1691191703.520 [DAT](INFO): DAT read: user:2>

2023-08-04T23:28:23.524Z,1691191703.524 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-08-04T23:28:23.525Z,1691191703.525 [DAT](INFO): set DatVerbose to 27440
2023-08-04T23:28:23.525Z,1691191703.525 [DAT](INFO): setting transmit power to 8
2023-08-04T23:28:23.770Z,1691191703.770 [DAT](INFO): DAT read: user:3>

2023-08-04T23:28:23.771Z,1691191703.771 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-08-04T23:28:23.772Z,1691191703.772 [DAT](INFO): set transmit power to 8
2023-08-04T23:28:23.772Z,1691191703.772 [DAT](INFO): setting local address to 10
2023-08-04T23:28:24.023Z,1691191704.023 [DAT](INFO): DAT read: user:4>

2023-08-04T23:28:24.024Z,1691191704.024 [DAT](INFO): DAT read: LocalAddr       | 10          

2023-08-04T23:28:24.024Z,1691191704.024 [DAT](INFO): set local address to 10
2023-08-04T23:28:24.025Z,1691191704.025 [DAT](INFO): Setting time to: 23:28:24 And date to:8/4/2023
2023-08-04T23:28:24.274Z,1691191704.274 [DAT](INFO): DAT read: user:5>

2023-08-04T23:28:24.275Z,1691191704.275 [DAT](INFO): DAT read: Fri Aug 4, 2023  23:28:24  

2023-08-04T23:28:24.276Z,1691191704.276 [DAT](INFO): Local DAT time set to Fri Aug 4, 2023  23:28:24  

2023-08-04T23:28:24.969Z,1691191704.969 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for beam range. Device response is::RA,  0.00,  96    +0.00,  20.00,  9.15
2023-08-04T23:28:34.255Z,1691191714.255 [NAL9602](INFO): Powering up NAL9602
2023-08-04T23:28:45.163Z,1691191725.163 [NAL9602](INFO): NAL9602 initialized
2023-08-04T23:28:52.081Z,1691191732.081 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-04T23:28:52.089Z,1691191732.089 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-04T23:29:03.018Z,1691191743.018 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006179
CHAN A1 (24V): 0.025032
CHAN A2 (12V): 0.005122
CHAN A3 (5V): 0.002542
CHAN B0 (3.3V): 0.003121
CHAN B1 (3.15aV): 0.003645
CHAN B2 (3.15bV): 0.003635
CHAN B3 (GND): 0.002357
OPEN: 0.006671
Full Scale: +/- 1 mA
2023-08-04T23:29:46.206Z,1691191786.206 [SBIT](IMPORTANT): SBIT PASSED
2023-08-04T23:29:46.207Z,1691191786.207 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-08-04T23:29:46.207Z,1691191786.207 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): CBIT.gfScanTimeout=5 minute;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=10 ampere_hour;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=11.5 volt;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=279.462762 cubic_centimeter;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): VerticalControl.massDefault=6.724128 millimeter;

2023-08-04T23:29:46.208Z,1691191786.208 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool;

2023-08-04T23:29:46.600Z,1691191786.600 [MissionManager](IMPORTANT): Started mission Startup
2023-08-04T23:29:46.600Z,1691191786.600 [Startup] Running Loop=1
2023-08-04T23:29:46.601Z,1691191786.601 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-04T23:29:46.601Z,1691191786.601 [Startup:A.GoToSurface] Running Loop=1
2023-08-04T23:29:46.601Z,1691191786.601 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-04T23:29:46.601Z,1691191786.601 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-04T23:29:46.606Z,1691191786.606 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-04T23:29:46.606Z,1691191786.606 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-04T23:29:46.607Z,1691191786.607 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-04T23:29:46.607Z,1691191786.607 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-04T23:29:46.608Z,1691191786.608 [Startup:StartupSatComms] Running Loop=1
2023-08-04T23:29:46.608Z,1691191786.608 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-04T23:29:46.608Z,1691191786.608 [Startup:StartupSatComms:A] Running Loop=1
2023-08-04T23:29:47.019Z,1691191787.019 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-04T23:30:46.819Z,1691191846.819 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-04T23:29:46.6Z
2023-08-04T23:30:46.819Z,1691191846.819 [Startup:StartupSatComms:A] Stopped
2023-08-04T23:30:46.819Z,1691191846.819 [Startup:StartupSatComms:B] Running Loop=1
2023-08-04T23:30:47.197Z,1691191847.197 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-04T23:31:05.777Z,1691191865.777 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-08-04T23:31:05.777Z,1691191865.777 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-08-04T23:31:05.821Z,1691191865.821 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-08-04T23:31:06.230Z,1691191866.230 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-08-04T23:31:06.230Z,1691191866.230 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-08-04T23:31:29.113Z,1691191889.113 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004442
2023-08-04T23:31:36.614Z,1691191896.614 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230804T200832/Courier0082.lzma
2023-08-04T23:31:37.616Z,1691191897.616 [DataOverHttps](INFO): Moved sent file to Logs/20230804T200832/Courier0082.lzma.bak
2023-08-04T23:31:37.616Z,1691191897.616 [DataOverHttps](INFO): SBD MOMSN=18519392
2023-08-04T23:31:47.003Z,1691191907.003 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-04T23:30:46.8Z
2023-08-04T23:31:47.003Z,1691191907.003 [Startup:StartupSatComms:B] Stopped
2023-08-04T23:31:47.004Z,1691191907.004 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-04T23:31:47.004Z,1691191907.004 [Startup:StartupSatComms] Stopped
2023-08-04T23:31:47.004Z,1691191907.004 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-04T23:31:47.004Z,1691191907.004 [Startup](INFO): Completed Startup
2023-08-04T23:31:47.005Z,1691191907.005 [MissionManager](INFO): Startup is completed.
2023-08-04T23:31:47.005Z,1691191907.005 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-04T23:31:47.005Z,1691191907.005 [Startup] Stopped
2023-08-04T23:31:47.005Z,1691191907.005 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-04T23:31:47.005Z,1691191907.005 [Startup:A.GoToSurface] Stopped
2023-08-04T23:31:47.005Z,1691191907.005 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-04T23:31:47.398Z,1691191907.398 [MissionManager](IMPORTANT): Started mission Default
2023-08-04T23:31:47.398Z,1691191907.398 [Default] Running Loop=1
2023-08-04T23:31:47.398Z,1691191907.398 [Default](DEBUG): Aggregate::initialize Default
2023-08-04T23:31:47.398Z,1691191907.398 [Default:B.GoToSurface] Running Loop=1
2023-08-04T23:31:47.398Z,1691191907.398 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-04T23:31:47.399Z,1691191907.399 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-04T23:31:47.399Z,1691191907.399 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-04T23:31:47.399Z,1691191907.399 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-04T23:31:47.399Z,1691191907.399 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-04T23:31:47.400Z,1691191907.400 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-04T23:31:47.400Z,1691191907.400 [Default:A.Wait] Running Loop=1
2023-08-04T23:31:47.400Z,1691191907.400 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-04T23:31:53.953Z,1691191913.953 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230804T232755/Courier0000.lzma
2023-08-04T23:31:54.955Z,1691191914.955 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Courier0000.lzma.bak
2023-08-04T23:31:54.956Z,1691191914.956 [DataOverHttps](INFO): SBD MOMSN=18519394
2023-08-04T23:32:00.737Z,1691191920.737 [Default:A.Wait](INFO): Done Waiting.
2023-08-04T23:32:00.737Z,1691191920.737 [Default:A.Wait] Stopped
2023-08-04T23:32:00.737Z,1691191920.737 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-04T23:32:01.148Z,1691191921.148 [Default:CheckIn] Running Loop=1
2023-08-04T23:32:01.148Z,1691191921.148 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-04T23:32:01.148Z,1691191921.148 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-04T23:32:01.537Z,1691191921.537 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-04T23:33:48.279Z,1691192028.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-04T23:33:52.725Z,1691192032.725 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233351.00,A,3648.16376,N,12147.28005,W,0.136,310.70,040823,,,A*77
2023-08-04T23:33:52.737Z,1691192032.737 [NAL9602](INFO): GPS fix at 20230804T233351: (36.802729, -121.788001)
2023-08-04T23:33:52.768Z,1691192032.768 [Default:CheckIn:Read_GPS] Stopped
2023-08-04T23:33:52.768Z,1691192032.768 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-04T23:33:53.165Z,1691192033.165 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-04T23:34:02.978Z,1691192042.978 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230804T232755/Courier0004.lzma
2023-08-04T23:34:03.257Z,1691192043.257 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-04T23:34:03.979Z,1691192043.979 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Courier0004.lzma.bak
2023-08-04T23:34:03.980Z,1691192043.980 [DataOverHttps](INFO): SBD MOMSN=18519404
2023-08-04T23:34:14.204Z,1691192054.204 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006021
CHAN A1 (24V): 0.024778
CHAN A2 (12V): 0.004868
CHAN A3 (5V): 0.003020
CHAN B0 (3.3V): 0.004007
CHAN B1 (3.15aV): 0.002906
CHAN B2 (3.15bV): 0.003039
CHAN B3 (GND): 0.002607
OPEN: 0.006831
Full Scale: +/- 1 mA
2023-08-04T23:34:21.686Z,1691192061.686 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20230804T200832/Express0083.lzma
2023-08-04T23:34:22.696Z,1691192062.696 [DataOverHttps](INFO): Moved sent file to Logs/20230804T200832/Express0083.lzma.bak
2023-08-04T23:34:22.696Z,1691192062.696 [DataOverHttps](INFO): SBD MOMSN=18519407
2023-08-04T23:34:25.479Z,1691192065.479 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-04T23:34:44.751Z,1691192084.751 [DataOverHttps](INFO): Sending 891 bytes from file Logs/20230804T232755/Express0001.lzma
2023-08-04T23:34:45.751Z,1691192085.751 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0001.lzma.bak
2023-08-04T23:34:45.752Z,1691192085.752 [DataOverHttps](INFO): SBD MOMSN=18519413
2023-08-04T23:35:02.746Z,1691192102.746 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20230804T232755/Express0005.lzma
2023-08-04T23:35:03.747Z,1691192103.747 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0005.lzma.bak
2023-08-04T23:35:03.748Z,1691192103.748 [DataOverHttps](INFO): SBD MOMSN=18519434
2023-08-04T23:35:05.278Z,1691192105.278 [Default:CheckIn:Read_Iridium] Stopped
2023-08-04T23:35:05.279Z,1691192105.279 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-04T23:35:05.279Z,1691192105.279 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-04T23:35:33.545Z,1691192133.545 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-04T23:35:33.553Z,1691192133.553 [BPC1](INFO): Received data from all battery sticks.
2023-08-04T23:39:14.263Z,1691192354.263 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-04T23:39:25.124Z,1691192365.124 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006363
CHAN A1 (24V): 0.025200
CHAN A2 (12V): 0.005097
CHAN A3 (5V): 0.003187
CHAN B0 (3.3V): 0.003928
CHAN B1 (3.15aV): 0.003357
CHAN B2 (3.15bV): 0.004315
CHAN B3 (GND): 0.002606
OPEN: 0.007063
Full Scale: +/- 1 mA
2023-08-04T23:40:05.910Z,1691192405.910 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-04T23:40:05.910Z,1691192405.910 [Default:CheckIn:C.Wait] Stopped
2023-08-04T23:40:05.910Z,1691192405.910 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-04T23:40:05.910Z,1691192405.910 [Default:CheckIn:D] Running Loop=1
2023-08-04T23:40:06.330Z,1691192406.330 [Default:CheckIn:D] Stopped
2023-08-04T23:40:06.330Z,1691192406.330 [Default:CheckIn:E] Running Loop=1
2023-08-04T23:40:06.722Z,1691192406.722 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.315532 min 
2023-08-04T23:40:06.722Z,1691192406.722 [Default:CheckIn:E] Stopped
2023-08-04T23:40:06.722Z,1691192406.722 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-04T23:40:06.722Z,1691192406.722 [Default:CheckIn] Stopped
2023-08-04T23:40:06.722Z,1691192406.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-04T23:40:06.723Z,1691192406.723 [Default:CheckIn](INFO): Running loop #2
2023-08-04T23:40:06.723Z,1691192406.723 [Default:CheckIn] Running Loop=2
2023-08-04T23:40:06.723Z,1691192406.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-04T23:40:06.723Z,1691192406.723 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-04T23:40:08.724Z,1691192408.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234007.00,A,3648.16687,N,12147.28241,W,0.194,39.11,040823,,,A*4E
2023-08-04T23:40:08.727Z,1691192408.727 [NAL9602](INFO): GPS fix at 20230804T234007: (36.802781, -121.788040)
2023-08-04T23:40:08.763Z,1691192408.763 [Default:CheckIn:Read_GPS] Stopped
2023-08-04T23:40:08.763Z,1691192408.763 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-04T23:40:18.077Z,1691192418.077 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20230804T232755/Courier0007.lzma
2023-08-04T23:40:19.080Z,1691192419.080 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Courier0007.lzma.bak
2023-08-04T23:40:19.080Z,1691192419.080 [DataOverHttps](INFO): SBD MOMSN=18519445
2023-08-04T23:40:38.990Z,1691192438.990 [DataOverHttps](INFO): Sending 382 bytes from file Logs/20230804T232755/Express0008.lzma
2023-08-04T23:40:39.487Z,1691192439.487 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-04T23:40:39.562Z,1691192439.562 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-04T23:40:39.992Z,1691192439.992 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0008.lzma.bak
2023-08-04T23:40:39.992Z,1691192439.992 [DataOverHttps](INFO): SBD MOMSN=18519448
2023-08-04T23:40:41.576Z,1691192441.576 [Default:CheckIn:Read_Iridium] Stopped
2023-08-04T23:40:41.576Z,1691192441.576 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-04T23:40:41.576Z,1691192441.576 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-04T23:41:24.247Z,1691192484.247 [DataOverHttps](IMPORTANT): SBD MTMSN=20230804T234123
2023-08-04T23:41:31.934Z,1691192491.934 [DataOverHttps](INFO): Received command: load Maintenance/tank_ballast_and_trim.tl;set ballast_and_trim.MissionTimeout 30 min;set ballast_and_trim.SettleTime 30 min;run
2023-08-04T23:41:31.985Z,1691192491.985 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-08-04T23:41:31.987Z,1691192491.987 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-08-04T23:41:31.988Z,1691192491.988 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file ./Missions/Maintenance/tank_ballast_and_trim.tx
2023-08-04T23:41:32.140Z,1691192492.140 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 120.000000 min
2023-08-04T23:41:32.143Z,1691192492.143 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool
2023-08-04T23:41:32.146Z,1691192492.146 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 7.000000 m
2023-08-04T23:41:32.149Z,1691192492.149 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m
2023-08-04T23:41:32.152Z,1691192492.152 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m
2023-08-04T23:41:32.175Z,1691192492.175 [MissionManager](INFO): DefineArg ballast_and_trim.SurfaceThreshold = 2.500000 m
2023-08-04T23:41:32.182Z,1691192492.182 [MissionManager](INFO): DefineArg ballast_and_trim.MaxWaitOnSurface = 20.000000 min
2023-08-04T23:41:32.185Z,1691192492.185 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min
2023-08-04T23:41:32.204Z,1691192492.204 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min
2023-08-04T23:41:32.223Z,1691192492.223 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min
2023-08-04T23:41:32.261Z,1691192492.261 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.250000 mm
2023-08-04T23:41:32.282Z,1691192492.282 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 15.000000 cc
2023-08-04T23:41:32.285Z,1691192492.285 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 99.900002 %
2023-08-04T23:41:32.292Z,1691192492.292 [MissionManager](INFO): DefineArg ballast_and_trim.WaitToFloat = 5.000000 min
2023-08-04T23:41:32.292Z,1691192492.292 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl
2023-08-04T23:41:32.292Z,1691192492.292 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/Science.tx
2023-08-04T23:41:33.084Z,1691192493.084 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool
2023-08-04T23:41:33.088Z,1691192493.088 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min
2023-08-04T23:41:33.091Z,1691192493.091 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool
2023-08-04T23:41:33.093Z,1691192493.093 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool
2023-08-04T23:41:33.097Z,1691192493.097 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool
2023-08-04T23:41:33.100Z,1691192493.100 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool
2023-08-04T23:41:33.102Z,1691192493.102 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DetectTrough = 0 bool
2023-08-04T23:41:33.105Z,1691192493.105 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count
2023-08-04T23:41:33.108Z,1691192493.108 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count
2023-08-04T23:41:33.111Z,1691192493.111 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 %
2023-08-04T23:41:33.114Z,1691192493.114 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool
2023-08-04T23:41:33.117Z,1691192493.117 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool
2023-08-04T23:41:33.119Z,1691192493.119 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool
2023-08-04T23:41:33.122Z,1691192493.122 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool
2023-08-04T23:41:33.125Z,1691192493.125 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2023-08-04T23:41:33.128Z,1691192493.128 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool
2023-08-04T23:41:33.223Z,1691192493.223 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool
2023-08-04T23:41:33.253Z,1691192493.253 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool
2023-08-04T23:41:33.286Z,1691192493.286 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 1 bool
2023-08-04T23:41:33.302Z,1691192493.302 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool
2023-08-04T23:41:33.314Z,1691192493.314 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 1 bool
2023-08-04T23:41:33.317Z,1691192493.317 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count
2023-08-04T23:41:33.324Z,1691192493.324 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count
2023-08-04T23:41:33.339Z,1691192493.339 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakShallowBound = nan m
2023-08-04T23:41:33.347Z,1691192493.347 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDeepBound = nan m
2023-08-04T23:41:33.354Z,1691192493.354 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m
2023-08-04T23:41:33.357Z,1691192493.357 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool
2023-08-04T23:41:33.372Z,1691192493.372 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l
2023-08-04T23:41:33.376Z,1691192493.376 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l
2023-08-04T23:41:33.399Z,1691192493.399 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count
2023-08-04T23:41:33.406Z,1691192493.406 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l
2023-08-04T23:41:33.409Z,1691192493.409 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m
2023-08-04T23:41:33.416Z,1691192493.416 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC
2023-08-04T23:41:33.429Z,1691192493.429 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg
2023-08-04T23:41:33.441Z,1691192493.441 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg
2023-08-04T23:41:33.457Z,1691192493.457 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l
2023-08-04T23:41:33.464Z,1691192493.464 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m
2023-08-04T23:41:33.468Z,1691192493.468 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg
2023-08-04T23:41:33.483Z,1691192493.483 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg
2023-08-04T23:41:33.489Z,1691192493.489 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3
2023-08-04T23:41:33.512Z,1691192493.512 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m
2023-08-04T23:41:33.516Z,1691192493.516 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg
2023-08-04T23:41:33.536Z,1691192493.536 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg
2023-08-04T23:41:33.543Z,1691192493.543 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb
2023-08-04T23:41:33.558Z,1691192493.558 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m
2023-08-04T23:41:33.561Z,1691192493.561 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC
2023-08-04T23:41:33.568Z,1691192493.568 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg
2023-08-04T23:41:33.572Z,1691192493.572 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg
2023-08-04T23:41:33.612Z,1691192493.612 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchFDOMDistance = nan m
2023-08-04T23:41:33.635Z,1691192493.635 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu
2023-08-04T23:41:33.650Z,1691192493.650 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m
2023-08-04T23:41:33.653Z,1691192493.653 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC
2023-08-04T23:41:33.676Z,1691192493.676 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg
2023-08-04T23:41:33.688Z,1691192493.688 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg
2023-08-04T23:41:33.700Z,1691192493.700 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l
2023-08-04T23:41:33.713Z,1691192493.713 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m
2023-08-04T23:41:33.725Z,1691192493.725 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg
2023-08-04T23:41:33.736Z,1691192493.736 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg
2023-08-04T23:41:33.739Z,1691192493.739 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m
2023-08-04T23:41:33.746Z,1691192493.746 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu
2023-08-04T23:41:33.749Z,1691192493.749 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m
2023-08-04T23:41:33.757Z,1691192493.757 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg
2023-08-04T23:41:33.768Z,1691192493.768 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg
2023-08-04T23:41:33.775Z,1691192493.775 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m
2023-08-04T23:41:33.779Z,1691192493.779 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu
2023-08-04T23:41:33.790Z,1691192493.790 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m
2023-08-04T23:41:33.793Z,1691192493.793 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg
2023-08-04T23:41:33.801Z,1691192493.801 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg
2023-08-04T23:41:33.811Z,1691192493.811 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m
2023-08-04T23:41:33.814Z,1691192493.814 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool
2023-08-04T23:41:33.817Z,1691192493.817 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l
2023-08-04T23:41:33.911Z,1691192493.911 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-04T23:41:33.945Z,1691192493.945 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-08-04T23:41:34.015Z,1691192494.015 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-04T23:41:34.066Z,1691192494.066 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-04T23:41:34.106Z,1691192494.106 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-08-04T23:41:34.156Z,1691192494.156 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-04T23:41:34.235Z,1691192494.235 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2023-08-04T23:41:34.319Z,1691192494.319 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2023-08-04T23:41:34.352Z,1691192494.352 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect.
2023-08-04T23:41:34.375Z,1691192494.375 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2023-08-04T23:41:34.375Z,1691192494.375 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BackseatDriver.tx
2023-08-04T23:41:34.455Z,1691192494.455 [MissionManager](INFO): DefineArg ballast_and_trim:BackseatDriver.EnableBackseat = 0 bool
2023-08-04T23:41:34.457Z,1691192494.457 [ballast_and_trim:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2023-08-04T23:41:34.469Z,1691192494.469 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BallastAndTrim.tl
2023-08-04T23:41:34.478Z,1691192494.478 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Insert/BallastAndTrim.tx
2023-08-04T23:41:34.741Z,1691192494.741 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepth = 25.000000 m
2023-08-04T23:41:34.749Z,1691192494.749 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSpeed = 0.000000 m/s
2023-08-04T23:41:34.759Z,1691192494.759 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthRate = 0.400000 m/s
2023-08-04T23:41:34.763Z,1691192494.763 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachPitchLimit = 20.000000 arcdeg
2023-08-04T23:41:34.787Z,1691192494.787 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachSettleTimePreDive = 0.000000 min
2023-08-04T23:41:34.802Z,1691192494.802 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = 20.000000 min
2023-08-04T23:41:34.814Z,1691192494.814 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = 10.000000 min
2023-08-04T23:41:34.817Z,1691192494.817 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = 45.000000 min
2023-08-04T23:41:34.824Z,1691192494.824 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = 10.000000 min
2023-08-04T23:41:34.835Z,1691192494.835 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = 0.250000 mm
2023-08-04T23:41:34.843Z,1691192494.843 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = 15.000000 cc
2023-08-04T23:41:34.862Z,1691192494.862 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = 99.900002 %
2023-08-04T23:41:34.865Z,1691192494.865 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = 1.000000 m
2023-08-04T23:41:34.880Z,1691192494.880 [MissionManager](INFO): DefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertMassDeadband = 0.250000 mm
2023-08-04T23:41:34.927Z,1691192494.927 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Construct.
2023-08-04T23:41:34.944Z,1691192494.944 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Construct Buoyancy.
2023-08-04T23:41:34.951Z,1691192494.951 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Construct.
2023-08-04T23:41:34.991Z,1691192494.991 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Construct Wait.
2023-08-04T23:41:34.998Z,1691192494.998 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Construct.
2023-08-04T23:41:35.007Z,1691192495.007 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Construct.
2023-08-04T23:41:35.024Z,1691192495.024 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch:A_Timeout:B.Execute](DEBUG): Construct Execute.
2023-08-04T23:41:35.031Z,1691192495.031 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Construct.
2023-08-04T23:41:35.055Z,1691192495.055 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Construct.
2023-08-04T23:41:35.080Z,1691192495.080 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Construct Wait.
2023-08-04T23:41:35.104Z,1691192495.104 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:F.](DEBUG): Construct BallastAndTrim.
2023-08-04T23:41:35.158Z,1691192495.158 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertDepthDeadband = reader:ballast_and_trim.DepthDeadband
2023-08-04T23:41:35.160Z,1691192495.160 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertApproachDepthTimeout = reader:ballast_and_trim.MaxWaitOnSurface
2023-08-04T23:41:35.178Z,1691192495.178 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.InsertSettleTime = reader:ballast_and_trim.SettleTime
2023-08-04T23:41:35.179Z,1691192495.179 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstTimeout = reader:ballast_and_trim.EstimationTimeout
2023-08-04T23:41:35.186Z,1691192495.186 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MinEstTime = reader:ballast_and_trim.MinEstimationTime
2023-08-04T23:41:35.188Z,1691192495.188 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.MassEstErrorBound = reader:ballast_and_trim.MassEstimationErrorBound
2023-08-04T23:41:35.189Z,1691192495.189 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.BuoyEstErrorBound = reader:ballast_and_trim.BuoyEstimationErrorBound
2023-08-04T23:41:35.227Z,1691192495.227 [MissionManager](INFO): RedefineArg ballast_and_trim:RunBallastAndTrim:BallastAndTrim.EstConfidence = reader:ballast_and_trim.EstimationConfidence
2023-08-04T23:41:35.309Z,1691192495.309 [ballast_and_trim:Float_Up:A.Buoyancy](DEBUG): Construct Buoyancy.
2023-08-04T23:41:35.317Z,1691192495.317 [ballast_and_trim:Float_Up:B.Wait](DEBUG): Construct Wait.
2023-08-04T23:41:35.423Z,1691192495.423 [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-08-04T23:41:35.424Z,1691192495.424 [CommandExec](IMPORTANT): Loaded ./Missions/Maintenance/tank_ballast_and_trim.tl
2023-08-04T23:41:36.478Z,1691192496.478 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,   +30,   +14,   :BE,   +30,   +14,    -8,A
2023-08-04T23:41:42.336Z,1691192502.336 [CommandExec](IMPORTANT): got command set ballast_and_trim.MissionTimeout 30 minute
2023-08-04T23:41:42.337Z,1691192502.337 [CommandExec](IMPORTANT): got command set ballast_and_trim.SettleTime 30 minute
2023-08-04T23:41:42.338Z,1691192502.338 [CommandExec](IMPORTANT): got command run
2023-08-04T23:41:42.346Z,1691192502.346 [CommandExec](IMPORTANT): Running 
2023-08-04T23:41:42.517Z,1691192502.517 [Default] Stopped
2023-08-04T23:41:42.517Z,1691192502.517 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-04T23:41:42.517Z,1691192502.517 [Default:B.GoToSurface] Stopped
2023-08-04T23:41:42.517Z,1691192502.517 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-04T23:41:42.518Z,1691192502.518 [Default:CheckIn] Stopped
2023-08-04T23:41:42.518Z,1691192502.518 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-04T23:41:42.518Z,1691192502.518 [Default:CheckIn:C.Wait] Stopped
2023-08-04T23:41:42.518Z,1691192502.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-04T23:41:42.518Z,1691192502.518 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2023-08-04T23:41:42.518Z,1691192502.518 [ballast_and_trim] Running Loop=1
2023-08-04T23:41:42.518Z,1691192502.518 [ballast_and_trim](DEBUG): Aggregate::initialize ballast_and_trim
2023-08-04T23:41:42.518Z,1691192502.518 [ballast_and_trim:Science] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science](DEBUG): Aggregate::initialize ballast_and_trim:Science
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:A] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:B] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:C] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:D] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:E] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:F] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:Read_Oil] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:PeakDetectChl] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectChl
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2023-08-04T23:41:42.519Z,1691192502.519 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-04T23:41:42.522Z,1691192502.522 [ballast_and_trim:Science:HighestChlPeakReport] Running Loop=1
2023-08-04T23:41:42.522Z,1691192502.522 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestChlPeakReport
2023-08-04T23:41:42.522Z,1691192502.522 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-08-04T23:41:42.522Z,1691192502.522 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-08-04T23:41:42.522Z,1691192502.522 [ballast_and_trim:Science:PeakDetectNO3] Running Loop=1
2023-08-04T23:41:42.523Z,1691192502.523 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectNO3
2023-08-04T23:41:42.523Z,1691192502.523 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2023-08-04T23:41:42.523Z,1691192502.523 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-04T23:41:42.525Z,1691192502.525 [ballast_and_trim:Science:PeakDetectOil] Running Loop=1
2023-08-04T23:41:42.525Z,1691192502.525 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectOil
2023-08-04T23:41:42.525Z,1691192502.525 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2023-08-04T23:41:42.525Z,1691192502.525 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-04T23:41:42.527Z,1691192502.527 [ballast_and_trim:Science:HighestOilPeakReport] Running Loop=1
2023-08-04T23:41:42.527Z,1691192502.527 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestOilPeakReport
2023-08-04T23:41:42.527Z,1691192502.527 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-08-04T23:41:42.527Z,1691192502.527 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-08-04T23:41:42.528Z,1691192502.528 [ballast_and_trim:Science:PeakDetectFDOM] Running Loop=1
2023-08-04T23:41:42.528Z,1691192502.528 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectFDOM
2023-08-04T23:41:42.528Z,1691192502.528 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2023-08-04T23:41:42.528Z,1691192502.528 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-04T23:41:42.530Z,1691192502.530 [ballast_and_trim:Science:PeakDetectSalinity] Running Loop=1
2023-08-04T23:41:42.530Z,1691192502.530 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize ballast_and_trim:Science:PeakDetectSalinity
2023-08-04T23:41:42.530Z,1691192502.530 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1
2023-08-04T23:41:42.530Z,1691192502.530 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize.
2023-08-04T23:41:42.532Z,1691192502.532 [ballast_and_trim:Science:HighestSaltPeakReport] Running Loop=1
2023-08-04T23:41:42.532Z,1691192502.532 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize ballast_and_trim:Science:HighestSaltPeakReport
2023-08-04T23:41:42.532Z,1691192502.532 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1
2023-08-04T23:41:42.532Z,1691192502.532 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2023-08-04T23:41:42.533Z,1691192502.533 [ballast_and_trim:Science:ChlThreshTrigger] Running Loop=1
2023-08-04T23:41:42.533Z,1691192502.533 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize ballast_and_trim:Science:ChlThreshTrigger
2023-08-04T23:41:42.533Z,1691192502.533 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1
2023-08-04T23:41:42.533Z,1691192502.533 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize.
2023-08-04T23:41:42.554Z,1691192502.554 [ballast_and_trim:Science:OceanCurrent] Running Loop=1
2023-08-04T23:41:42.554Z,1691192502.554 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::initialize ballast_and_trim:Science:OceanCurrent
2023-08-04T23:41:42.554Z,1691192502.554 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-08-04T23:41:42.554Z,1691192502.554 [ballast_and_trim:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::initialize ballast_and_trim:BackseatDriver
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Running Loop=1
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:RunBallastAndTrim] Running Loop=1
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:RunBallastAndTrim:Depth1] Running Loop=1
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-08-04T23:41:42.555Z,1691192502.555 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Running Loop=1
2023-08-04T23:41:42.556Z,1691192502.556 [ballast_and_trim:RunBallastAndTrim:Depth1:A] Stopped
2023-08-04T23:41:42.556Z,1691192502.556 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Running Loop=1
2023-08-04T23:41:42.556Z,1691192502.556 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-08-04T23:41:42.556Z,1691192502.556 [ballast_and_trim:BackseatDriver] Running Loop=1
2023-08-04T23:41:42.557Z,1691192502.557 [ballast_and_trim:Science] Running Loop=1
2023-08-04T23:41:42.562Z,1691192502.562 [ballast_and_trim:Science:ChlThreshTrigger] Stopped
2023-08-04T23:41:42.562Z,1691192502.562 [ballast_and_trim:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:ChlThreshTrigger
2023-08-04T23:41:42.562Z,1691192502.562 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect] Stopped
2023-08-04T23:41:42.562Z,1691192502.562 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize.
2023-08-04T23:41:42.562Z,1691192502.562 [ballast_and_trim:Science:HighestSaltPeakReport] Stopped
2023-08-04T23:41:42.562Z,1691192502.562 [ballast_and_trim:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestSaltPeakReport
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectSalinity] Stopped
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectSalinity
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectFDOM] Stopped
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectFDOM
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:HighestOilPeakReport] Stopped
2023-08-04T23:41:42.563Z,1691192502.563 [ballast_and_trim:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestOilPeakReport
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectOil] Stopped
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectOil
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectNO3] Stopped
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectNO3
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:HighestChlPeakReport] Stopped
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:HighestChlPeakReport
2023-08-04T23:41:42.564Z,1691192502.564 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:PeakDetectChl] Stopped
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:PeakDetectChl
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:OceanCurrent:A.] Running Loop=1
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:Read_Oil] Stopped
2023-08-04T23:41:42.565Z,1691192502.565 [ballast_and_trim:Science:F] Running Loop=1
2023-08-04T23:41:42.570Z,1691192502.570 [ballast_and_trim:Science:F](DEBUG): Initialize ReadDataComponent to sense WetLabsUBAT.average_bioluminescence
2023-08-04T23:41:42.570Z,1691192502.570 [ballast_and_trim:Science:E] Running Loop=1
2023-08-04T23:41:42.571Z,1691192502.571 [ballast_and_trim:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2023-08-04T23:41:42.571Z,1691192502.571 [ballast_and_trim:Science:D] Stopped
2023-08-04T23:41:42.571Z,1691192502.571 [ballast_and_trim:Science:C] Running Loop=1
2023-08-04T23:41:42.572Z,1691192502.572 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2023-08-04T23:41:42.572Z,1691192502.572 [ballast_and_trim:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2023-08-04T23:41:42.573Z,1691192502.573 [ballast_and_trim:Science:B] Running Loop=1
2023-08-04T23:41:42.573Z,1691192502.573 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2023-08-04T23:41:42.578Z,1691192502.578 [ballast_and_trim:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2023-08-04T23:41:42.578Z,1691192502.578 [ballast_and_trim:Science:A] Stopped
2023-08-04T23:41:42.613Z,1691192502.613 [WetLabsBB2FL](INFO): Powering up
2023-08-04T23:41:42.923Z,1691192502.923 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Running Loop=1
2023-08-04T23:41:42.923Z,1691192502.923 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-08-04T23:41:42.924Z,1691192502.924 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-08-04T23:41:42.924Z,1691192502.924 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-08-04T23:41:42.924Z,1691192502.924 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-08-04T23:41:42.924Z,1691192502.924 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-08-04T23:41:42.924Z,1691192502.924 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-08-04T23:41:42.924Z,1691192502.924 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Running Loop=1
2023-08-04T23:41:42.930Z,1691192502.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F](INFO): Going to target depth. Speed set to 0.000000 m/s 
2023-08-04T23:41:42.930Z,1691192502.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:F] Stopped
2023-08-04T23:41:42.930Z,1691192502.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Running Loop=1
2023-08-04T23:41:42.930Z,1691192502.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-08-04T23:41:42.930Z,1691192502.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-08-04T23:41:42.930Z,1691192502.930 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Running Loop=1
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Running Loop=1
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point](DEBUG): Initialize.
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Running Loop=1
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Running Loop=1
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch](DEBUG): Initialize.
2023-08-04T23:41:42.931Z,1691192502.931 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Running Loop=1
2023-08-04T23:41:42.932Z,1691192502.932 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-08-04T23:41:42.932Z,1691192502.932 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Running Loop=1
2023-08-04T23:41:42.932Z,1691192502.932 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Running Loop=1
2023-08-04T23:41:42.932Z,1691192502.932 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Running Loop=1
2023-08-04T23:41:42.932Z,1691192502.932 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Running Loop=1
2023-08-04T23:41:42.932Z,1691192502.932 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Running Loop=1
2023-08-04T23:41:42.933Z,1691192502.933 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Running Loop=1
2023-08-04T23:41:43.151Z,1691192503.151 [WetLabsUBAT](INFO): Powering up
2023-08-04T23:41:43.308Z,1691192503.308 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A](INFO): Waiting for 0.000000 min while pumping down to neutral.
2023-08-04T23:41:43.308Z,1691192503.308 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:A] Stopped
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Running Loop=1
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Initialize Wait Component.
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold] Stopped
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:A.Point] Stopped
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy] Stopped
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ActuatorHold:C.Pitch] Stopped
2023-08-04T23:41:43.309Z,1691192503.309 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Running Loop=1
2023-08-04T23:41:43.729Z,1691192503.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](INFO): Done Waiting.
2023-08-04T23:41:43.729Z,1691192503.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait] Stopped
2023-08-04T23:41:43.729Z,1691192503.729 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive:B.Wait](DEBUG): Uninitialize Wait Component.
2023-08-04T23:41:43.730Z,1691192503.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-08-04T23:41:43.730Z,1691192503.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive] Stopped
2023-08-04T23:41:43.730Z,1691192503.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:WaitPreDive
2023-08-04T23:41:43.730Z,1691192503.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Running Loop=1
2023-08-04T23:41:43.730Z,1691192503.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-08-04T23:41:43.730Z,1691192503.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-08-04T23:41:43.730Z,1691192503.730 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Initialize.
2023-08-04T23:41:43.731Z,1691192503.731 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Running Loop=1
2023-08-04T23:41:44.131Z,1691192504.131 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Running Loop=1
2023-08-04T23:41:44.132Z,1691192504.132 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A](INFO): Moving to 7.000000 m 
2023-08-04T23:41:44.132Z,1691192504.132 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:A] Stopped
2023-08-04T23:41:44.132Z,1691192504.132 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Running Loop=1
2023-08-04T23:41:44.132Z,1691192504.132 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch](DEBUG): Initialize.
2023-08-04T23:41:46.155Z,1691192506.155 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2023-08-04T23:41:53.351Z,1691192513.351 [WetLabsUBAT](FAULT): UBAT flow rate is below the specified threshold of 0.05 l/s.
2023-08-04T23:43:10.470Z,1691192590.470 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.338867
2023-08-04T23:43:41.594Z,1691192621.594 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-04T23:43:55.174Z,1691192635.174 [Radio_Surface](INFO): Powering down
2023-08-04T23:44:04.330Z,1691192644.330 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:C.Pitch] Stopped
2023-08-04T23:44:04.331Z,1691192644.331 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-08-04T23:44:04.331Z,1691192644.331 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth] Stopped
2023-08-04T23:44:04.331Z,1691192644.331 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth
2023-08-04T23:44:04.331Z,1691192644.331 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed] Stopped
2023-08-04T23:44:04.331Z,1691192644.331 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:ApproachDepth:B.SetSpeed](DEBUG): Uninitialize.
2023-08-04T23:44:04.332Z,1691192644.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](INFO): Completed ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-08-04T23:44:04.332Z,1691192644.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth] Stopped
2023-08-04T23:44:04.332Z,1691192644.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth
2023-08-04T23:44:04.332Z,1691192644.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:GoToTargetDepth:A] Stopped
2023-08-04T23:44:04.332Z,1691192644.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Running Loop=1
2023-08-04T23:44:04.332Z,1691192644.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::initialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-08-04T23:44:04.332Z,1691192644.332 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-08-04T23:44:04.333Z,1691192644.333 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Initialize.
2023-08-04T23:44:04.333Z,1691192644.333 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-08-04T23:44:04.333Z,1691192644.333 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch](DEBUG): Initialize.
2023-08-04T23:44:04.333Z,1691192644.333 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Running Loop=1
2023-08-04T23:44:04.779Z,1691192644.779 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C](INFO): Waiting for the vehicle to settle. Depth = 7.059991 m 
2023-08-04T23:44:04.779Z,1691192644.779 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:C] Stopped
2023-08-04T23:44:04.779Z,1691192644.779 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Running Loop=1
2023-08-04T23:44:04.779Z,1691192644.779 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Initialize Wait Component.
2023-08-04T23:44:04.779Z,1691192644.779 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Running Loop=1
2023-08-04T23:44:04.780Z,1691192644.780 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Running Loop=1
2023-08-04T23:44:16.734Z,1691192656.734 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-04T23:44:17.738Z,1691192657.738 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-08-04T23:44:21.850Z,1691192661.850 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-08-04T23:44:25.500Z,1691192665.500 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-04T23:44:25.816Z,1691192665.816 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:25.818Z,1691192665.818 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:26.820Z,1691192666.820 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:26.821Z,1691192666.821 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:27.823Z,1691192667.823 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:27.825Z,1691192667.825 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:28.827Z,1691192668.827 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:28.828Z,1691192668.828 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:29.836Z,1691192669.836 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:29.838Z,1691192669.838 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:30.839Z,1691192670.839 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:30.840Z,1691192670.840 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:31.844Z,1691192671.844 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:31.851Z,1691192671.851 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:32.851Z,1691192672.851 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:32.855Z,1691192672.855 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:33.852Z,1691192673.852 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:33.857Z,1691192673.857 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:34.856Z,1691192674.856 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:34.860Z,1691192674.860 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:35.855Z,1691192675.855 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:44:35.857Z,1691192675.857 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:44:36.687Z,1691192676.687 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005448
CHAN A1 (24V): 0.024897
CHAN A2 (12V): 0.005107
CHAN A3 (5V): 0.003413
CHAN B0 (3.3V): 0.003863
CHAN B1 (3.15aV): 0.003530
CHAN B2 (3.15bV): 0.003780
CHAN B3 (GND): 0.002227
OPEN: 0.007648
Full Scale: +/- 1 mA
2023-08-04T23:45:11.367Z,1691192711.367 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-04T23:45:20.838Z,1691192720.838 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-08-04T23:45:42.080Z,1691192742.080 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-04T23:47:16.754Z,1691192836.754 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-08-04T23:49:36.931Z,1691192976.931 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-04T23:49:37.100Z,1691192977.100 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:37.102Z,1691192977.102 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:38.104Z,1691192978.104 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:38.105Z,1691192978.105 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:39.107Z,1691192979.107 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:39.108Z,1691192979.108 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:40.119Z,1691192980.119 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:40.121Z,1691192980.121 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:41.115Z,1691192981.115 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:41.117Z,1691192981.117 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:42.120Z,1691192982.120 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:42.121Z,1691192982.121 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:43.124Z,1691192983.124 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:43.125Z,1691192983.125 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:44.128Z,1691192984.128 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:44.129Z,1691192984.129 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:45.131Z,1691192985.131 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:45.133Z,1691192985.133 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:46.136Z,1691192986.136 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:46.137Z,1691192986.137 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:47.139Z,1691192987.139 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:49:47.140Z,1691192987.140 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:49:47.910Z,1691192987.910 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.007272
CHAN A1 (24V): 0.024403
CHAN A2 (12V): 0.005127
CHAN A3 (5V): 0.002972
CHAN B0 (3.3V): 0.002998
CHAN B1 (3.15aV): 0.003376
CHAN B2 (3.15bV): 0.003862
CHAN B3 (GND): 0.001294
OPEN: 0.006862
Full Scale: +/- 1 mA
2023-08-04T23:54:47.999Z,1691193287.999 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-04T23:54:48.383Z,1691193288.383 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:48.385Z,1691193288.385 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:49.388Z,1691193289.388 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:49.389Z,1691193289.389 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:50.391Z,1691193290.391 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:50.393Z,1691193290.393 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:51.395Z,1691193291.395 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:51.397Z,1691193291.397 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:52.400Z,1691193292.400 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:52.401Z,1691193292.401 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:53.404Z,1691193293.404 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:53.405Z,1691193293.405 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:54.407Z,1691193294.407 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:54.409Z,1691193294.409 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:55.413Z,1691193295.413 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:55.415Z,1691193295.415 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:56.415Z,1691193296.415 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:56.417Z,1691193296.417 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:57.420Z,1691193297.420 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:57.421Z,1691193297.421 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:58.423Z,1691193298.423 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:54:58.425Z,1691193298.425 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-04T23:54:58.956Z,1691193298.956 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006427
CHAN A1 (24V): 0.025200
CHAN A2 (12V): 0.005787
CHAN A3 (5V): 0.003522
CHAN B0 (3.3V): 0.003772
CHAN B1 (3.15aV): 0.003636
CHAN B2 (3.15bV): 0.003418
CHAN B3 (GND): 0.002630
OPEN: 0.006809
Full Scale: +/- 1 mA
2023-08-04T23:59:59.043Z,1691193599.043 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-04T23:59:59.652Z,1691193599.652 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-04T23:59:59.655Z,1691193599.655 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:00.656Z,1691193600.656 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:00.657Z,1691193600.657 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:01.661Z,1691193601.661 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:01.662Z,1691193601.662 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:02.663Z,1691193602.663 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:02.665Z,1691193602.665 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:03.667Z,1691193603.667 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:03.668Z,1691193603.668 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:04.673Z,1691193604.673 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:04.675Z,1691193604.675 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:05.676Z,1691193605.676 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:05.678Z,1691193605.678 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:06.680Z,1691193606.680 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:06.683Z,1691193606.683 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:07.683Z,1691193607.683 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:07.684Z,1691193607.684 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:08.662Z,1691193608.662 [WetLabsUBAT](ERROR): Failed to parse incomplete device message.
2023-08-05T00:00:08.687Z,1691193608.687 [CTD_Seabird](ERROR): Failed to parse device response: 
2023-08-05T00:00:09.691Z,1691193609.691 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:00:09.692Z,1691193609.692 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:00:10.139Z,1691193610.139 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006301
CHAN A1 (24V): 0.025373
CHAN A2 (12V): 0.005139
CHAN A3 (5V): 0.003320
CHAN B0 (3.3V): 0.003725
CHAN B1 (3.15aV): 0.003318
CHAN B2 (3.15bV): 0.003345
CHAN B3 (GND): 0.002333
OPEN: 0.007145
Full Scale: +/- 1 mA
2023-08-05T00:04:02.628Z,1691193842.628 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,       -0.97,        +1.13 2.3
2023-08-05T00:05:10.562Z,1691193910.562 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:05:10.916Z,1691193910.916 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:10.917Z,1691193910.917 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:11.920Z,1691193911.920 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:11.922Z,1691193911.922 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:12.971Z,1691193912.971 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:12.972Z,1691193912.972 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:13.966Z,1691193913.966 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:13.967Z,1691193913.967 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:14.967Z,1691193914.967 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:14.968Z,1691193914.968 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:15.970Z,1691193915.970 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:15.972Z,1691193915.972 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:16.970Z,1691193916.970 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:16.972Z,1691193916.972 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:17.966Z,1691193917.966 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:17.967Z,1691193917.967 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:18.970Z,1691193918.970 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:18.972Z,1691193918.972 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:19.960Z,1691193919.960 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:19.961Z,1691193919.961 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:20.963Z,1691193920.963 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:05:20.965Z,1691193920.965 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:05:21.454Z,1691193921.454 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006432
CHAN A1 (24V): 0.025546
CHAN A2 (12V): 0.005289
CHAN A3 (5V): 0.002681
CHAN B0 (3.3V): 0.004186
CHAN B1 (3.15aV): 0.003935
CHAN B2 (3.15bV): 0.002948
CHAN B3 (GND): 0.002222
OPEN: 0.006992
Full Scale: +/- 1 mA
2023-08-05T00:07:42.817Z,1691194062.817 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,23080416084084,353,   0.0,1525.1,  0
2023-08-05T00:10:03.024Z,1691194203.024 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,   +0,    +0,A
2023-08-05T00:10:21.643Z,1691194221.643 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:10:22.227Z,1691194222.227 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:22.229Z,1691194222.229 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:23.240Z,1691194223.240 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:23.241Z,1691194223.241 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:24.243Z,1691194224.243 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:24.245Z,1691194224.245 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:25.248Z,1691194225.248 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:25.249Z,1691194225.249 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:26.253Z,1691194226.253 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:26.254Z,1691194226.254 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:27.256Z,1691194227.256 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:27.257Z,1691194227.257 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:28.259Z,1691194228.259 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:28.261Z,1691194228.260 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:29.263Z,1691194229.263 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:29.265Z,1691194229.265 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:30.269Z,1691194230.269 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:30.271Z,1691194230.271 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:31.272Z,1691194231.272 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:31.273Z,1691194231.273 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:32.276Z,1691194232.276 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2023-08-05T00:10:32.277Z,1691194232.277 [CTD_Seabird](INFO): some bad data, not updating bins
2023-08-05T00:10:32.591Z,1691194232.591 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006441
CHAN A1 (24V): 0.025565
CHAN A2 (12V): 0.005786
CHAN A3 (5V): 0.002951
CHAN B0 (3.3V): 0.004107
CHAN B1 (3.15aV): 0.003975
CHAN B2 (3.15bV): 0.003662
CHAN B3 (GND): 0.003068
OPEN: 0.007000
Full Scale: +/- 1 mA
2023-08-05T00:11:42.830Z,1691194302.830 [ballast_and_trim](INFO): Timed out from 2023-08-04T23:41:42.5Z
2023-08-05T00:11:42.830Z,1691194302.830 [MissionManager](INFO): ballast_and_trim is completed.
2023-08-05T00:11:42.830Z,1691194302.830 [MissionManager](INFO): Uninitializing Mission ballast_and_trim
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim](DEBUG): Aggregate::uninitialize ballast_and_trim
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science](DEBUG): Aggregate::uninitialize ballast_and_trim:Science
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science:B] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science:C] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science:E] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science:F] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science:OceanCurrent] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science:OceanCurrent](DEBUG): Aggregate::uninitialize ballast_and_trim:Science:OceanCurrent
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:Science:OceanCurrent:A.] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:BackseatDriver] Stopped
2023-08-05T00:11:42.831Z,1691194302.831 [ballast_and_trim:BackseatDriver](DEBUG): Aggregate::uninitialize ballast_and_trim:BackseatDriver
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:BackseatDriver:A.BackseatDriver] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:A] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:B] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:C] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:D] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:E] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed] Stopped
2023-08-05T00:11:42.832Z,1691194302.832 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:A.SetSpeed](DEBUG): Uninitialize.
2023-08-05T00:11:42.833Z,1691194302.833 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:B.Pitch] Stopped
2023-08-05T00:11:42.833Z,1691194302.833 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait] Stopped
2023-08-05T00:11:42.833Z,1691194302.833 [ballast_and_trim:RunBallastAndTrim:BallastAndTrim:BallastAndTrimAtTargetDepth:D.Wait](DEBUG): Uninitialize Wait Component.
2023-08-05T00:11:42.833Z,1691194302.833 [ballast_and_trim:RunBallastAndTrim:Depth1] Stopped
2023-08-05T00:11:42.833Z,1691194302.833 [ballast_and_trim:RunBallastAndTrim:Depth1](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1
2023-08-05T00:11:42.833Z,1691194302.833 [ballast_and_trim:RunBallastAndTrim:Depth1:B] Stopped
2023-08-05T00:11:42.833Z,1691194302.833 [ballast_and_trim:RunBallastAndTrim:Depth1:B](DEBUG): Aggregate::uninitialize ballast_and_trim:RunBallastAndTrim:Depth1:B
2023-08-05T00:11:43.264Z,1691194303.264 [MissionManager](IMPORTANT): Started mission Default
2023-08-05T00:11:43.264Z,1691194303.264 [Default] Running Loop=1
2023-08-05T00:11:43.264Z,1691194303.264 [Default](DEBUG): Aggregate::initialize Default
2023-08-05T00:11:43.264Z,1691194303.264 [Default:B.GoToSurface] Running Loop=1
2023-08-05T00:11:43.265Z,1691194303.265 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-05T00:11:43.265Z,1691194303.265 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-05T00:11:43.265Z,1691194303.265 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-05T00:11:43.266Z,1691194303.266 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-05T00:11:43.266Z,1691194303.266 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-05T00:11:43.266Z,1691194303.266 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-05T00:11:43.267Z,1691194303.267 [Default:A.Wait] Running Loop=1
2023-08-05T00:11:43.267Z,1691194303.267 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-05T00:11:43.294Z,1691194303.294 [WetLabsBB2FL](INFO): Powering down
2023-08-05T00:11:43.398Z,1691194303.398 [WetLabsUBAT](INFO): Powering down
2023-08-05T00:11:43.615Z,1691194303.615 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -20.00 mm (1 active estimators).
2023-08-05T00:11:43.830Z,1691194303.830 [Radio_Surface](INFO): Powering up
2023-08-05T00:11:49.638Z,1691194309.638 [DataOverHttps](INFO): Radio surface powered ON.
2023-08-05T00:11:56.572Z,1691194316.572 [Default:A.Wait](INFO): Done Waiting.
2023-08-05T00:11:56.572Z,1691194316.572 [Default:A.Wait] Stopped
2023-08-05T00:11:56.572Z,1691194316.572 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-05T00:12:13.937Z,1691194333.937 [Default:B.GoToSurface](ERROR): Altitude too low: 0.50
2023-08-05T00:12:14.320Z,1691194334.320 [Default:B.GoToSurface](ERROR): Altitude too low: 0.50
2023-08-05T00:12:14.768Z,1691194334.768 [Default:B.GoToSurface](ERROR): Altitude too low: 0.46
2023-08-05T00:12:15.127Z,1691194335.127 [Default:B.GoToSurface](ERROR): Altitude too low: 0.46
2023-08-05T00:12:15.540Z,1691194335.540 [Default:B.GoToSurface](ERROR): Altitude too low: 0.46
2023-08-05T00:12:15.964Z,1691194335.964 [Default:B.GoToSurface](ERROR): Altitude too low: 0.48
2023-08-05T00:12:16.338Z,1691194336.338 [Default:B.GoToSurface](ERROR): Altitude too low: 0.48
2023-08-05T00:12:16.759Z,1691194336.759 [Default:B.GoToSurface](ERROR): Altitude too low: 0.50
2023-08-05T00:12:17.157Z,1691194337.157 [Default:B.GoToSurface](ERROR): Altitude too low: 0.50
2023-08-05T00:12:17.551Z,1691194337.551 [Default:B.GoToSurface](ERROR): Altitude too low: 0.50
2023-08-05T00:12:18.091Z,1691194338.091 [Default:B.GoToSurface](ERROR): Altitude too low: 0.35
2023-08-05T00:12:18.355Z,1691194338.355 [Default:B.GoToSurface](ERROR): Altitude too low: 0.35
2023-08-05T00:12:18.792Z,1691194338.792 [Default:B.GoToSurface](ERROR): Altitude too low: 0.36
2023-08-05T00:12:19.168Z,1691194339.168 [Default:B.GoToSurface](ERROR): Altitude too low: 0.36
2023-08-05T00:12:19.565Z,1691194339.565 [Default:B.GoToSurface](ERROR): Altitude too low: 0.36
2023-08-05T00:12:19.770Z,1691194339.770 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:12:19.995Z,1691194339.995 [Default:B.GoToSurface](ERROR): Altitude too low: 0.37
2023-08-05T00:12:20.394Z,1691194340.394 [Default:B.GoToSurface](ERROR): Altitude too low: 0.37
2023-08-05T00:12:20.819Z,1691194340.819 [Default:B.GoToSurface](ERROR): Altitude too low: 0.37
2023-08-05T00:12:21.187Z,1691194341.187 [Default:B.GoToSurface](ERROR): Altitude too low: 0.37
2023-08-05T00:12:21.588Z,1691194341.588 [Default:B.GoToSurface](ERROR): Altitude too low: 0.37
2023-08-05T00:12:54.914Z,1691194374.914 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:13:30.058Z,1691194410.058 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:14:01.402Z,1691194441.402 [Default:CheckIn] Running Loop=1
2023-08-05T00:14:01.402Z,1691194441.402 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-05T00:14:01.402Z,1691194441.402 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-05T00:14:05.199Z,1691194445.199 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:14:34.086Z,1691194474.086 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-05T00:14:34.170Z,1691194474.170 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-08-05T00:14:40.346Z,1691194480.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:15:14.438Z,1691194514.438 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003061
2023-08-05T00:15:32.745Z,1691194532.745 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:15:43.684Z,1691194543.684 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006741
CHAN A1 (24V): 0.025355
CHAN A2 (12V): 0.005642
CHAN A3 (5V): 0.002858
CHAN B0 (3.3V): 0.003559
CHAN B1 (3.15aV): 0.003815
CHAN B2 (3.15bV): 0.003224
CHAN B3 (GND): 0.001832
OPEN: 0.006604
Full Scale: +/- 1 mA
2023-08-05T00:19:01.599Z,1691194741.599 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-08-05T00:14:01.4Z
2023-08-05T00:19:01.599Z,1691194741.599 [Default:CheckIn:Read_GPS] Stopped
2023-08-05T00:19:01.599Z,1691194741.599 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-05T00:19:05.227Z,1691194745.227 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-05T00:19:09.409Z,1691194749.409 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230804T232755/Courier0010.lzma
2023-08-05T00:19:10.412Z,1691194750.412 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Courier0010.lzma.bak
2023-08-05T00:19:10.413Z,1691194750.413 [DataOverHttps](INFO): SBD MOMSN=18519649
2023-08-05T00:19:26.783Z,1691194766.783 [DataOverHttps](INFO): Sending 1390 bytes from file Logs/20230804T232755/Express0011.lzma
2023-08-05T00:19:27.931Z,1691194767.931 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0011.lzma.bak
2023-08-05T00:19:27.931Z,1691194767.931 [DataOverHttps](INFO): SBD MOMSN=18519651
2023-08-05T00:19:35.927Z,1691194775.927 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-05T00:19:44.013Z,1691194784.013 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230804T232755/Express0014.lzma
2023-08-05T00:19:45.015Z,1691194785.015 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0014.lzma.bak
2023-08-05T00:19:45.016Z,1691194785.016 [DataOverHttps](INFO): SBD MOMSN=18519683
2023-08-05T00:19:46.442Z,1691194786.442 [Default:CheckIn:Read_Iridium] Stopped
2023-08-05T00:19:46.443Z,1691194786.443 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-05T00:19:46.443Z,1691194786.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-05T00:20:43.847Z,1691194843.847 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:20:54.792Z,1691194854.792 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006644
CHAN A1 (24V): 0.025167
CHAN A2 (12V): 0.005415
CHAN A3 (5V): 0.003040
CHAN B0 (3.3V): 0.003615
CHAN B1 (3.15aV): 0.003421
CHAN B2 (3.15bV): 0.003030
CHAN B3 (GND): 0.001779
OPEN: 0.006716
Full Scale: +/- 1 mA
2023-08-05T00:24:47.058Z,1691195087.058 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-05T00:24:47.058Z,1691195087.058 [Default:CheckIn:C.Wait] Stopped
2023-08-05T00:24:47.058Z,1691195087.058 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-05T00:24:47.059Z,1691195087.059 [Default:CheckIn:D] Running Loop=1
2023-08-05T00:24:47.469Z,1691195087.469 [Default:CheckIn:D] Stopped
2023-08-05T00:24:47.469Z,1691195087.469 [Default:CheckIn:E] Running Loop=1
2023-08-05T00:24:47.862Z,1691195087.862 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.070070 min 
2023-08-05T00:24:47.862Z,1691195087.862 [Default:CheckIn:E] Stopped
2023-08-05T00:24:47.862Z,1691195087.862 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-05T00:24:47.862Z,1691195087.862 [Default:CheckIn] Stopped
2023-08-05T00:24:47.862Z,1691195087.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-05T00:24:47.862Z,1691195087.862 [Default:CheckIn](INFO): Running loop #2
2023-08-05T00:24:47.863Z,1691195087.863 [Default:CheckIn] Running Loop=2
2023-08-05T00:24:47.863Z,1691195087.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-05T00:24:47.863Z,1691195087.863 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-05T00:24:49.471Z,1691195089.471 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-08-05T00:24:49.471Z,1691195089.471 [NAL9602] Data Fault, FailCount= 1
2023-08-05T00:24:49.471Z,1691195089.471 [NAL9602](ERROR): Data Fault
2023-08-05T00:24:49.493Z,1691195089.493 [CBIT](ERROR): Data Fault in component: NAL9602
2023-08-05T00:24:49.871Z,1691195089.871 [NAL9602](INFO): Powering down
2023-08-05T00:24:50.755Z,1691195090.755 [CBIT](INFO): Clearing failed state for component NAL9602
2023-08-05T00:24:50.755Z,1691195090.755 [NAL9602] No Fault, FailCount= 1
2023-08-05T00:25:20.171Z,1691195120.171 [NAL9602](INFO): Powering up NAL9602
2023-08-05T00:25:31.079Z,1691195131.079 [NAL9602](INFO): NAL9602 initialized
2023-08-05T00:25:54.990Z,1691195154.990 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:26:05.867Z,1691195165.867 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006223
CHAN A1 (24V): 0.024711
CHAN A2 (12V): 0.005304
CHAN A3 (5V): 0.002781
CHAN B0 (3.3V): 0.003071
CHAN B1 (3.15aV): 0.003091
CHAN B2 (3.15bV): 0.002480
CHAN B3 (GND): 0.001217
OPEN: 0.006844
Full Scale: +/- 1 mA
2023-08-05T00:29:10.929Z,1691195350.929 [NAL9602](INFO): SBD MO Status=2, MOMSN=29174, MT Status=2, MTMSN=0
2023-08-05T00:29:10.929Z,1691195350.929 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-05T00:29:48.159Z,1691195388.159 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-08-05T00:24:47.9Z
2023-08-05T00:29:48.159Z,1691195388.159 [Default:CheckIn:Read_GPS] Stopped
2023-08-05T00:29:48.159Z,1691195388.159 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-05T00:29:56.209Z,1691195396.209 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230804T232755/Courier0016.lzma
2023-08-05T00:29:57.211Z,1691195397.211 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Courier0016.lzma.bak
2023-08-05T00:29:57.212Z,1691195397.212 [DataOverHttps](INFO): SBD MOMSN=18519704
2023-08-05T00:30:13.511Z,1691195413.511 [DataOverHttps](INFO): Sending 439 bytes from file Logs/20230804T232755/Express0017.lzma
2023-08-05T00:30:14.515Z,1691195414.515 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0017.lzma.bak
2023-08-05T00:30:14.515Z,1691195414.515 [DataOverHttps](INFO): SBD MOMSN=18519706
2023-08-05T00:30:16.046Z,1691195416.046 [Default:CheckIn:Read_Iridium] Stopped
2023-08-05T00:30:16.046Z,1691195416.046 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-05T00:30:16.046Z,1691195416.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-05T00:30:34.246Z,1691195434.246 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-05T00:31:04.963Z,1691195464.963 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-05T00:31:06.182Z,1691195466.182 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:31:17.119Z,1691195477.119 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006998
CHAN A1 (24V): 0.024865
CHAN A2 (12V): 0.005075
CHAN A3 (5V): 0.002634
CHAN B0 (3.3V): 0.003060
CHAN B1 (3.15aV): 0.003173
CHAN B2 (3.15bV): 0.002921
CHAN B3 (GND): 0.002160
OPEN: 0.006704
Full Scale: +/- 1 mA
2023-08-05T00:35:16.759Z,1691195716.759 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-05T00:35:16.759Z,1691195716.759 [Default:CheckIn:C.Wait] Stopped
2023-08-05T00:35:16.759Z,1691195716.759 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-05T00:35:16.760Z,1691195716.760 [Default:CheckIn:D] Running Loop=1
2023-08-05T00:35:17.091Z,1691195717.091 [Default:CheckIn:D] Stopped
2023-08-05T00:35:17.091Z,1691195717.091 [Default:CheckIn:E] Running Loop=1
2023-08-05T00:35:17.475Z,1691195717.475 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.563770 min 
2023-08-05T00:35:17.475Z,1691195717.475 [Default:CheckIn:E] Stopped
2023-08-05T00:35:17.476Z,1691195717.476 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-05T00:35:17.476Z,1691195717.476 [Default:CheckIn] Stopped
2023-08-05T00:35:17.476Z,1691195717.476 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-05T00:35:17.476Z,1691195717.476 [Default:CheckIn](INFO): Running loop #3
2023-08-05T00:35:17.476Z,1691195717.476 [Default:CheckIn] Running Loop=3
2023-08-05T00:35:17.476Z,1691195717.476 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-05T00:35:17.476Z,1691195717.476 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-05T00:35:19.481Z,1691195719.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003517.00,A,3648.16684,N,12147.28377,W,0.505,358.46,050823,,,A*70
2023-08-05T00:35:19.484Z,1691195719.484 [NAL9602](INFO): GPS fix at 20230805T003517: (36.802781, -121.788063)
2023-08-05T00:35:19.495Z,1691195719.495 [Default:CheckIn:Read_GPS] Stopped
2023-08-05T00:35:19.495Z,1691195719.495 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-05T00:35:27.225Z,1691195727.225 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230804T232755/Courier0019.lzma
2023-08-05T00:35:28.227Z,1691195728.227 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Courier0019.lzma.bak
2023-08-05T00:35:28.228Z,1691195728.228 [DataOverHttps](INFO): SBD MOMSN=18519737
2023-08-05T00:35:44.458Z,1691195744.458 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20230804T232755/Express0020.lzma
2023-08-05T00:35:45.459Z,1691195745.459 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0020.lzma.bak
2023-08-05T00:35:45.460Z,1691195745.460 [DataOverHttps](INFO): SBD MOMSN=18519742
2023-08-05T00:35:46.993Z,1691195746.993 [Default:CheckIn:Read_Iridium] Stopped
2023-08-05T00:35:46.993Z,1691195746.993 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-05T00:35:46.993Z,1691195746.993 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-05T00:35:50.183Z,1691195750.183 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-08-05T00:35:50.266Z,1691195750.266 [NAL9602](ERROR): received: 
+CSQ:0

OK174, 2, 0, 0, 0

OK
2023-08-05T00:36:17.285Z,1691195777.285 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:36:28.183Z,1691195788.183 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006003
CHAN A1 (24V): 0.024535
CHAN A2 (12V): 0.004779
CHAN A3 (5V): 0.002092
CHAN B0 (3.3V): 0.003490
CHAN B1 (3.15aV): 0.003294
CHAN B2 (3.15bV): 0.002718
CHAN B3 (GND): 0.001981
OPEN: 0.006528
Full Scale: +/- 1 mA
2023-08-05T00:37:39.680Z,1691195859.680 [NAL9602](INFO): SBD MO Status=2, MOMSN=29174, MT Status=2, MTMSN=0
2023-08-05T00:37:39.680Z,1691195859.680 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-05T00:40:21.279Z,1691196021.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-08-05T00:40:47.554Z,1691196047.554 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-05T00:40:47.554Z,1691196047.554 [Default:CheckIn:C.Wait] Stopped
2023-08-05T00:40:47.554Z,1691196047.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-05T00:40:47.555Z,1691196047.555 [Default:CheckIn:D] Running Loop=1
2023-08-05T00:40:47.959Z,1691196047.959 [Default:CheckIn:D] Stopped
2023-08-05T00:40:47.959Z,1691196047.959 [Default:CheckIn:E] Running Loop=1
2023-08-05T00:40:48.382Z,1691196048.382 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.078241 min 
2023-08-05T00:40:48.382Z,1691196048.382 [Default:CheckIn:E] Stopped
2023-08-05T00:40:48.382Z,1691196048.382 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-05T00:40:48.383Z,1691196048.383 [Default:CheckIn] Stopped
2023-08-05T00:40:48.383Z,1691196048.383 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-05T00:40:48.383Z,1691196048.383 [Default:CheckIn](INFO): Running loop #4
2023-08-05T00:40:48.383Z,1691196048.383 [Default:CheckIn] Running Loop=4
2023-08-05T00:40:48.383Z,1691196048.383 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-05T00:40:48.383Z,1691196048.383 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-05T00:40:50.379Z,1691196050.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004048.00,A,3648.16649,N,12147.28203,W,0.914,358.46,050823,,,A*77
2023-08-05T00:40:50.381Z,1691196050.381 [NAL9602](INFO): GPS fix at 20230805T004048: (36.802775, -121.788034)
2023-08-05T00:40:50.393Z,1691196050.393 [Default:CheckIn:Read_GPS] Stopped
2023-08-05T00:40:50.393Z,1691196050.393 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-05T00:41:02.401Z,1691196062.401 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230804T232755/Courier0022.lzma
2023-08-05T00:41:03.387Z,1691196063.387 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Courier0022.lzma.bak
2023-08-05T00:41:03.388Z,1691196063.388 [DataOverHttps](INFO): SBD MOMSN=18519813
2023-08-05T00:41:19.986Z,1691196079.986 [DataOverHttps](INFO): Sending 376 bytes from file Logs/20230804T232755/Express0023.lzma
2023-08-05T00:41:20.989Z,1691196080.989 [DataOverHttps](INFO): Moved sent file to Logs/20230804T232755/Express0023.lzma.bak
2023-08-05T00:41:20.989Z,1691196080.989 [DataOverHttps](INFO): SBD MOMSN=18519818
2023-08-05T00:41:22.268Z,1691196082.268 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-05T00:41:22.743Z,1691196082.743 [Default:CheckIn:Read_Iridium] Stopped
2023-08-05T00:41:22.743Z,1691196082.743 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-05T00:41:22.743Z,1691196082.743 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-05T00:41:28.325Z,1691196088.325 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-05T00:41:39.238Z,1691196099.238 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005529
CHAN A1 (24V): 0.024058
CHAN A2 (12V): 0.003955
CHAN A3 (5V): 0.002670
CHAN B0 (3.3V): 0.003042
CHAN B1 (3.15aV): 0.002427
CHAN B2 (3.15bV): 0.003371
CHAN B3 (GND): 0.001350
OPEN: 0.006297
Full Scale: +/- 1 mA
2023-08-05T00:42:28.066Z,1691196148.066 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:42:30.074Z,1691196150.074 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255108
2023-08-05T00:43:02.470Z,1691196182.470 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-05T00:43:02.473Z,1691196182.473 [BPC1](INFO): Received data from all battery sticks.
2023-08-05T00:43:03.206Z,1691196183.206 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:43:38.358Z,1691196218.358 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:44:13.510Z,1691196253.510 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:44:48.653Z,1691196288.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-08-05T00:45:03.506Z,1691196303.506 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003511
2023-08-05T00:46:23.238Z,1691196383.238 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-05T00:46:23.238Z,1691196383.238 [Default:CheckIn:C.Wait] Stopped
2023-08-05T00:46:23.238Z,1691196383.238 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-05T00:46:23.238Z,1691196383.238 [Default:CheckIn:D] Running Loop=1
2023-08-05T00:46:23.643Z,1691196383.643 [Default:CheckIn:D] Stopped
2023-08-05T00:46:23.643Z,1691196383.643 [Default:CheckIn:E] Running Loop=1
2023-08-05T00:46:24.057Z,1691196384.057 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.672978 min 
2023-08-05T00:46:24.057Z,1691196384.057 [Default:CheckIn:E] Stopped
2023-08-05T00:46:24.057Z,1691196384.057 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-05T00:46:24.057Z,1691196384.057 [Default:CheckIn] Stopped
2023-08-05T00:46:24.062Z,1691196384.062 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-05T00:46:24.062Z,1691196384.062 [Default:CheckIn](INFO): Running loop #5
2023-08-05T00:46:24.062Z,1691196384.062 [Default:CheckIn] Running Loop=5
2023-08-05T00:46:24.062Z,1691196384.062 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-05T00:46:24.063Z,1691196384.063 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-05T00:46:26.058Z,1691196386.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004624.00,A,3648.15720,N,12147.29142,W,1.050,135.94,050823,,,A*7F
2023-08-05T00:46:26.061Z,1691196386.061 [NAL9602](INFO): GPS fix at 20230805T004624: (36.802620, -121.788190)
2023-08-05T00:46:26.072Z,1691196386.072 [Default:CheckIn:Read_GPS] Stopped
2023-08-05T00:46:26.072Z,1691196386.072 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-05T00:46:27.602Z,1691196387.602 [CommandExec](IMPORTANT): got command quit
2023-08-05T00:46:28.606Z,1691196388.606 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-08-05T00:46:28.607Z,1691196388.607 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:28.607Z,1691196388.607 [CommandExec](INFO): Uninitializing the command executive.
2023-08-05T00:46:28.608Z,1691196388.608 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-05T00:46:28.608Z,1691196388.608 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:28.686Z,1691196388.686 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-05T00:46:28.686Z,1691196388.686 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-05T00:46:28.686Z,1691196388.686 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:28.687Z,1691196388.687 [NavChartDb](INFO): Join timeout helper Thread ID is 1550
2023-08-05T00:46:29.014Z,1691196389.014 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:29.014Z,1691196389.014 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.026Z,1691196389.026 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2023-08-05T00:46:29.026Z,1691196389.026 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.026Z,1691196389.026 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1551
2023-08-05T00:46:29.178Z,1691196389.178 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:29.178Z,1691196389.178 [WetLabsUBAT](INFO): Powering down
2023-08-05T00:46:29.179Z,1691196389.179 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.186Z,1691196389.186 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-05T00:46:29.186Z,1691196389.186 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.186Z,1691196389.186 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1552
2023-08-05T00:46:29.394Z,1691196389.394 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:29.394Z,1691196389.394 [WetLabsBB2FL](INFO): Powering down
2023-08-05T00:46:29.395Z,1691196389.395 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.406Z,1691196389.406 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-05T00:46:29.406Z,1691196389.406 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.406Z,1691196389.406 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1553
2023-08-05T00:46:29.494Z,1691196389.494 [CTD_Seabird](INFO): Powering down
2023-08-05T00:46:29.506Z,1691196389.506 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:29.506Z,1691196389.506 [CTD_Seabird](INFO): Powering down
2023-08-05T00:46:29.518Z,1691196389.518 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.522Z,1691196389.522 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-08-05T00:46:29.522Z,1691196389.522 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.522Z,1691196389.522 [DAT](INFO): Join timeout helper Thread ID is 1554
2023-08-05T00:46:29.730Z,1691196389.730 [DAT](INFO): Powering down
2023-08-05T00:46:29.802Z,1691196389.802 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:29.802Z,1691196389.802 [DAT](INFO): Powering down
2023-08-05T00:46:29.803Z,1691196389.803 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.811Z,1691196389.811 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-05T00:46:29.811Z,1691196389.811 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:29.811Z,1691196389.811 [Radio_Surface](INFO): Join timeout helper Thread ID is 1555
2023-08-05T00:46:30.054Z,1691196390.054 [Radio_Surface](INFO): Powering down
2023-08-05T00:46:30.055Z,1691196390.055 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:30.055Z,1691196390.055 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:30.059Z,1691196390.059 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-05T00:46:30.059Z,1691196390.059 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:30.059Z,1691196390.059 [Onboard](INFO): Join timeout helper Thread ID is 1556
2023-08-05T00:46:34.558Z,1691196394.558 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:34.558Z,1691196394.558 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:34.570Z,1691196394.570 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-05T00:46:34.570Z,1691196394.570 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:34.571Z,1691196394.571 [DataOverHttps](INFO): Join timeout helper Thread ID is 1557
2023-08-05T00:46:34.898Z,1691196394.898 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:34.901Z,1691196394.901 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:34.919Z,1691196394.919 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-08-05T00:46:34.919Z,1691196394.919 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:34.919Z,1691196394.919 [BackseatComponent](INFO): Join timeout helper Thread ID is 1558
2023-08-05T00:46:35.006Z,1691196395.006 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:35.006Z,1691196395.006 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.022Z,1691196395.022 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-05T00:46:35.022Z,1691196395.022 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.023Z,1691196395.023 [logger](INFO): Join timeout helper Thread ID is 1559
2023-08-05T00:46:35.054Z,1691196395.054 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:35.054Z,1691196395.054 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.066Z,1691196395.066 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-05T00:46:35.066Z,1691196395.066 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.067Z,1691196395.067 [CommandLine](INFO): Join timeout helper Thread ID is 1560
2023-08-05T00:46:35.094Z,1691196395.094 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:35.094Z,1691196395.094 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.106Z,1691196395.106 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-05T00:46:35.106Z,1691196395.106 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.107Z,1691196395.107 [CommandExec](INFO): Join timeout helper Thread ID is 1561
2023-08-05T00:46:35.108Z,1691196395.108 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-05T00:46:35.108Z,1691196395.108 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.108Z,1691196395.108 [controlThread](INFO): Join timeout helper Thread ID is 1562
2023-08-05T00:46:35.326Z,1691196395.326 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-05T00:46:35.326Z,1691196395.326 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-05T00:46:35.327Z,1691196395.327 [AHRS_M2](INFO): Powering down
2023-08-05T00:46:35.398Z,1691196395.398 [NAL9602](INFO): Powering down
2023-08-05T00:46:35.470Z,1691196395.470 [RDI_Pathfinder](INFO): Powering down
2023-08-05T00:46:35.471Z,1691196395.471 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-05T00:46:35.472Z,1691196395.472 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-05T00:46:35.472Z,1691196395.472 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-05T00:46:35.473Z,1691196395.473 [MissionManager](INFO): Uninitializing Mission Default
2023-08-05T00:46:35.473Z,1691196395.473 [Default] Stopped
2023-08-05T00:46:35.473Z,1691196395.473 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-05T00:46:35.473Z,1691196395.473 [Default:B.GoToSurface] Stopped
2023-08-05T00:46:35.473Z,1691196395.473 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-05T00:46:35.473Z,1691196395.473 [Default:CheckIn] Stopped
2023-08-05T00:46:35.474Z,1691196395.474 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-05T00:46:35.474Z,1691196395.474 [Default:CheckIn:Read_Iridium] Stopped
2023-08-05T00:46:35.476Z,1691196395.476 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-05T00:46:35.477Z,1691196395.477 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-05T00:46:35.477Z,1691196395.477 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-05T00:46:35.477Z,1691196395.477 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-05T00:46:35.478Z,1691196395.478 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-05T00:46:35.478Z,1691196395.478 [BuoyancyServo](INFO): Powering down
2023-08-05T00:46:35.490Z,1691196395.490 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-05T00:46:35.490Z,1691196395.490 [ElevatorServo](INFO): Powering down
2023-08-05T00:46:35.491Z,1691196395.491 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-05T00:46:35.491Z,1691196395.491 [MassServo](INFO): Powering down
2023-08-05T00:46:35.492Z,1691196395.492 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-05T00:46:35.492Z,1691196395.492 [RudderServo](INFO): Powering down
2023-08-05T00:46:35.493Z,1691196395.493 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-08-05T00:46:35.493Z,1691196395.493 [ThrusterServo](INFO): Powering down
2023-08-05T00:46:35.494Z,1691196395.494 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-05T00:46:35.494Z,1691196395.494 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-05T00:46:35.494Z,1691196395.494 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-05T00:46:35.494Z,1691196395.494 [CBIT](DEBUG): Powering off loads.
2023-08-05T00:46:35.505Z,1691196395.505 [CBIT](DEBUG): Disabling WDT.
2023-08-05T00:46:35.517Z,1691196395.517 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-05T00:46:35.518Z,1691196395.518 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.568Z,1691196395.568 [DAT ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.576Z,1691196395.576 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.578Z,1691196395.578 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.584Z,1691196395.584 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.627Z,1691196395.627 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.685Z,1691196395.685 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.687Z,1691196395.687 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.691Z,1691196395.691 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.699Z,1691196395.699 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-05T00:46:35.772Z,1691196395.772 [logger ThreadHandler](INFO): Thread cancelled.