2023-04-07T23:03:02.667Z,1680908582.667 [Supervisor](DEBUG): Initializing supervisor.
2023-04-07T23:03:02.671Z,1680908582.671 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-04-07T23:03:02.672Z,1680908582.672 [SyncHandler](INFO): Protected caller Thread ID is 9243
2023-04-07T23:03:02.672Z,1680908582.672 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-04-07T23:03:02.673Z,1680908582.673 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-04-07T23:03:02.674Z,1680908582.674 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9244
2023-04-07T23:03:02.678Z,1680908582.678 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-04-07T23:03:02.695Z,1680908582.695 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-04-07T23:03:02.696Z,1680908582.696 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-04-07T23:03:02.696Z,1680908582.696 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9245
2023-04-07T23:03:02.701Z,1680908582.701 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-04-07T23:03:02.702Z,1680908582.702 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-04-07T23:03:02.702Z,1680908582.702 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9246
2023-04-07T23:03:02.704Z,1680908582.704 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-04-07T23:03:02.705Z,1680908582.705 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-04-07T23:03:02.705Z,1680908582.705 [logger ThreadHandler](INFO): Protected caller Thread ID is 9247
2023-04-07T23:03:02.709Z,1680908582.709 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-04-07T23:03:02.709Z,1680908582.709 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-04-07T23:03:02.715Z,1680908582.715 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-04-07T23:03:02.874Z,1680908582.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-04-07T23:03:02.876Z,1680908582.876 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-04-07T23:03:03.087Z,1680908583.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-04-07T23:03:03.089Z,1680908583.089 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-04-07T23:03:03.336Z,1680908583.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-04-07T23:03:03.338Z,1680908583.338 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-04-07T23:03:03.455Z,1680908583.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-04-07T23:03:03.456Z,1680908583.456 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-04-07T23:03:04.119Z,1680908584.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-04-07T23:03:04.119Z,1680908584.119 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-04-07T23:03:04.581Z,1680908584.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-04-07T23:03:04.582Z,1680908584.582 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-04-07T23:03:05.051Z,1680908585.051 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-04-07T23:03:05.051Z,1680908585.051 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-04-07T23:03:05.437Z,1680908585.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-04-07T23:03:05.438Z,1680908585.438 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-04-07T23:03:05.752Z,1680908585.752 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-04-07T23:03:05.753Z,1680908585.753 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-04-07T23:03:06.112Z,1680908586.112 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-04-07T23:03:06.113Z,1680908586.113 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-04-07T23:03:06.343Z,1680908586.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-04-07T23:03:06.343Z,1680908586.343 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-04-07T23:03:06.894Z,1680908586.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-04-07T23:03:07.362Z,1680908587.362 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-04-07T23:03:07.451Z,1680908587.451 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-04-07T23:03:07.532Z,1680908587.532 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-04-07T23:03:07.717Z,1680908587.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-04-07T23:03:07.718Z,1680908587.718 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-04-07T23:03:07.938Z,1680908587.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-04-07T23:03:07.940Z,1680908587.940 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-04-07T23:03:07.943Z,1680908587.943 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-04-07T23:03:08.027Z,1680908588.027 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-04-07T23:03:08.286Z,1680908588.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-04-07T23:03:08.287Z,1680908588.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-04-07T23:03:08.384Z,1680908588.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-04-07T23:03:08.539Z,1680908588.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-04-07T23:03:08.713Z,1680908588.713 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-04-07T23:03:08.798Z,1680908588.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-04-07T23:03:08.907Z,1680908588.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-04-07T23:03:09.010Z,1680908589.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-04-07T23:03:09.162Z,1680908589.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-04-07T23:03:09.257Z,1680908589.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-04-07T23:03:09.355Z,1680908589.355 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-04-07T23:03:09.372Z,1680908589.372 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-04-07T23:03:09.518Z,1680908589.518 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-04-07T23:03:09.519Z,1680908589.519 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-04-07T23:03:09.675Z,1680908589.675 [BuoyancyServo] Loaded
2023-04-07T23:03:09.675Z,1680908589.675 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-04-07T23:03:09.696Z,1680908589.696 [ElevatorServo] Loaded
2023-04-07T23:03:09.696Z,1680908589.696 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-04-07T23:03:09.716Z,1680908589.716 [MassServo] Loaded
2023-04-07T23:03:09.716Z,1680908589.716 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-04-07T23:03:09.736Z,1680908589.736 [RudderServo] Loaded
2023-04-07T23:03:09.737Z,1680908589.737 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-04-07T23:03:09.752Z,1680908589.752 [ThrusterHE] Loaded
2023-04-07T23:03:09.752Z,1680908589.752 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-04-07T23:03:09.753Z,1680908589.753 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-04-07T23:03:09.753Z,1680908589.753 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-04-07T23:03:09.895Z,1680908589.895 [SBIT](DEBUG): Construct Startup Built In Test.
2023-04-07T23:03:09.911Z,1680908589.911 [SBIT] Loaded
2023-04-07T23:03:09.911Z,1680908589.911 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-04-07T23:03:09.913Z,1680908589.913 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-04-07T23:03:09.934Z,1680908589.934 [IBIT] Loaded
2023-04-07T23:03:09.934Z,1680908589.934 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-04-07T23:03:09.940Z,1680908589.940 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-04-07T23:03:10.155Z,1680908590.155 [CBIT] Loaded
2023-04-07T23:03:10.156Z,1680908590.156 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-04-07T23:03:10.156Z,1680908590.156 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-04-07T23:03:10.157Z,1680908590.157 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-04-07T23:03:10.321Z,1680908590.321 [CTD_Seabird] Loaded
2023-04-07T23:03:10.321Z,1680908590.321 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-04-07T23:03:10.322Z,1680908590.322 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406644E0
2023-04-07T23:03:10.322Z,1680908590.322 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9329
2023-04-07T23:03:10.354Z,1680908590.354 [ESPComponent] Loaded
2023-04-07T23:03:10.354Z,1680908590.354 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-04-07T23:03:10.373Z,1680908590.373 [PAR_Licor] Loaded
2023-04-07T23:03:10.373Z,1680908590.373 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-04-07T23:03:10.404Z,1680908590.404 [WetLabsBB2FL] Loaded
2023-04-07T23:03:10.404Z,1680908590.404 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-04-07T23:03:10.405Z,1680908590.405 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406944E0
2023-04-07T23:03:10.406Z,1680908590.406 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9330
2023-04-07T23:03:10.406Z,1680908590.406 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-04-07T23:03:10.407Z,1680908590.407 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-04-07T23:03:10.456Z,1680908590.456 [DepthRateCalculator] Loaded
2023-04-07T23:03:10.456Z,1680908590.456 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-04-07T23:03:10.462Z,1680908590.462 [PitchRateCalculator] Loaded
2023-04-07T23:03:10.462Z,1680908590.462 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-04-07T23:03:10.472Z,1680908590.472 [SpeedCalculator] Loaded
2023-04-07T23:03:10.472Z,1680908590.472 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-04-07T23:03:10.486Z,1680908590.486 [VerticalHomogeneityIndexCalculator] Loaded
2023-04-07T23:03:10.487Z,1680908590.487 [ComponentRegistry](DEBUG): SyncComponent "VerticalHomogeneityIndexCalculator" handled in the control thread.
2023-04-07T23:03:10.491Z,1680908590.491 [YawRateCalculator] Loaded
2023-04-07T23:03:10.491Z,1680908590.491 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-04-07T23:03:10.511Z,1680908590.511 [ElevatorOffsetCalculator] Loaded
2023-04-07T23:03:10.511Z,1680908590.511 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-04-07T23:03:10.511Z,1680908590.511 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-04-07T23:03:10.512Z,1680908590.512 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-04-07T23:03:10.609Z,1680908590.609 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-04-07T23:03:10.610Z,1680908590.610 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-04-07T23:03:10.637Z,1680908590.637 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-04-07T23:03:10.637Z,1680908590.637 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-04-07T23:03:10.749Z,1680908590.749 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-04-07T23:03:10.749Z,1680908590.749 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-04-07T23:03:10.763Z,1680908590.763 [NavChart] Loaded
2023-04-07T23:03:10.763Z,1680908590.763 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-04-07T23:03:10.768Z,1680908590.768 [UniversalFixResidualReporter] Loaded
2023-04-07T23:03:10.769Z,1680908590.769 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-04-07T23:03:10.769Z,1680908590.769 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-04-07T23:03:10.770Z,1680908590.770 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-04-07T23:03:11.185Z,1680908591.185 [AHRS_M2] Loaded
2023-04-07T23:03:11.185Z,1680908591.185 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-04-07T23:03:11.219Z,1680908591.219 [BackseatComponent] Loaded
2023-04-07T23:03:11.219Z,1680908591.219 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-04-07T23:03:11.220Z,1680908591.220 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0
2023-04-07T23:03:11.221Z,1680908591.221 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9331
2023-04-07T23:03:11.224Z,1680908591.224 [LcmUniversalReporter] Loaded
2023-04-07T23:03:11.224Z,1680908591.224 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-04-07T23:03:11.956Z,1680908591.956 [BPC1] Loaded
2023-04-07T23:03:11.956Z,1680908591.956 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-04-07T23:03:12.039Z,1680908592.039 [DataOverHttps] Loaded
2023-04-07T23:03:12.039Z,1680908592.039 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-04-07T23:03:12.040Z,1680908592.040 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A544E0
2023-04-07T23:03:12.040Z,1680908592.040 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9332
2023-04-07T23:03:12.061Z,1680908592.061 [Depth_Keller] Loaded
2023-04-07T23:03:12.061Z,1680908592.061 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-04-07T23:03:12.066Z,1680908592.066 [DropWeight] Loaded
2023-04-07T23:03:12.067Z,1680908592.067 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-04-07T23:03:12.105Z,1680908592.105 [DVL_micro] Loaded
2023-04-07T23:03:12.106Z,1680908592.106 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-04-07T23:03:12.166Z,1680908592.166 [NAL9602] Loaded
2023-04-07T23:03:12.166Z,1680908592.166 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-04-07T23:03:12.193Z,1680908592.193 [Onboard] Loaded
2023-04-07T23:03:12.193Z,1680908592.193 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-04-07T23:03:12.194Z,1680908592.194 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A844E0
2023-04-07T23:03:12.195Z,1680908592.195 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9333
2023-04-07T23:03:12.207Z,1680908592.207 [Power24vConverter] Loaded
2023-04-07T23:03:12.207Z,1680908592.207 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-04-07T23:03:12.220Z,1680908592.220 [Radio_Surface] Loaded
2023-04-07T23:03:12.221Z,1680908592.221 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-04-07T23:03:12.222Z,1680908592.222 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AB44E0
2023-04-07T23:03:12.222Z,1680908592.222 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9334
2023-04-07T23:03:12.315Z,1680908592.315 [DAT] Loaded
2023-04-07T23:03:12.315Z,1680908592.315 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-04-07T23:03:12.316Z,1680908592.316 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AE44E0
2023-04-07T23:03:12.317Z,1680908592.317 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9335
2023-04-07T23:03:12.317Z,1680908592.317 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-04-07T23:03:12.318Z,1680908592.318 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-04-07T23:03:12.505Z,1680908592.505 [StratificationFrontDetector](IMPORTANT): thresholdVTHI set to: 0.050012 degC
2023-04-07T23:03:12.505Z,1680908592.505 [StratificationFrontDetector](INFO): (re)initializing
2023-04-07T23:03:12.506Z,1680908592.506 [StratificationFrontDetector] Loaded
2023-04-07T23:03:12.506Z,1680908592.506 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2023-04-07T23:03:12.506Z,1680908592.506 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-04-07T23:03:12.507Z,1680908592.507 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-04-07T23:03:12.619Z,1680908592.619 [VerticalControl](DEBUG): Construct VerticalControl.
2023-04-07T23:03:12.708Z,1680908592.708 [VerticalControl] Loaded
2023-04-07T23:03:12.708Z,1680908592.708 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-04-07T23:03:12.711Z,1680908592.711 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-04-07T23:03:12.764Z,1680908592.764 [HorizontalControl] Loaded
2023-04-07T23:03:12.764Z,1680908592.764 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-04-07T23:03:12.767Z,1680908592.767 [SpeedControl](DEBUG): Construct SpeedControl.
2023-04-07T23:03:12.768Z,1680908592.768 [SpeedControl] Loaded
2023-04-07T23:03:12.769Z,1680908592.769 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-04-07T23:03:12.772Z,1680908592.772 [LoopControl](DEBUG): Construct LoopControl.
2023-04-07T23:03:12.779Z,1680908592.779 [LoopControl] Loaded
2023-04-07T23:03:12.779Z,1680908592.779 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-04-07T23:03:12.780Z,1680908592.780 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-04-07T23:03:12.819Z,1680908592.819 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-04-07T23:03:12.866Z,1680908592.866 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-04-07T23:03:12.867Z,1680908592.867 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-04-07T23:03:13.316Z,1680908593.316 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-04-07T23:03:13.322Z,1680908593.322 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-04-07T23:03:13.325Z,1680908593.325 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-04-07T23:03:13.336Z,1680908593.336 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-04-07T23:03:13.337Z,1680908593.337 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BDE4E0
2023-04-07T23:03:13.337Z,1680908593.337 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9336
2023-04-07T23:03:13.342Z,1680908593.342 [Supervisor](INFO): Main Thread ID is 5613
2023-04-07T23:03:13.342Z,1680908593.342 [Supervisor](DEBUG): Running supervisor.
2023-04-07T23:03:13.343Z,1680908593.343 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9337
2023-04-07T23:03:13.343Z,1680908593.343 [CommandExec](INFO): Initializing the command executive.
2023-04-07T23:03:13.344Z,1680908593.344 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9338
2023-04-07T23:03:13.347Z,1680908593.347 [controlThread ThreadHandler](INFO): Handler Thread ID is 9339
2023-04-07T23:03:13.347Z,1680908593.347 [controlThread](DEBUG): Initializing ControlThread
2023-04-07T23:03:13.349Z,1680908593.349 [SBIT](INFO): Initialize SBIT Component.
2023-04-07T23:03:13.349Z,1680908593.349 [SBIT](IMPORTANT): git: 2023-04-04
2023-04-07T23:03:13.349Z,1680908593.349 [SBIT](INFO): git hash: b8e3dc7784c205eaa6d34dbcc6cfad52b0792170
2023-04-07T23:03:13.350Z,1680908593.350 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-04-07T23:03:13.351Z,1680908593.351 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-04-07T23:03:13.352Z,1680908593.352 [SBIT](INFO): Beginning SBIT in 145.000000 seconds.
2023-04-07T23:03:13.353Z,1680908593.353 [IBIT](INFO): Initialize IBIT Component.
2023-04-07T23:03:13.354Z,1680908593.354 [CBIT](DEBUG): Initialize CBIT Component.
2023-04-07T23:03:13.354Z,1680908593.355 [logger ThreadHandler](INFO): Handler Thread ID is 9340
2023-04-07T23:03:13.366Z,1680908593.366 [CBIT](DEBUG): Initialized mux pins.
2023-04-07T23:03:13.366Z,1680908593.366 [CBIT](DEBUG): Initializing the watchdog timer.
2023-04-07T23:03:13.374Z,1680908593.374 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9341
2023-04-07T23:03:13.375Z,1680908593.375 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-04-07T23:03:13.379Z,1680908593.379 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9343
2023-04-07T23:03:13.382Z,1680908593.382 [WetLabsBB2FL](INFO): Powering up
2023-04-07T23:03:13.383Z,1680908593.383 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9345
2023-04-07T23:03:13.390Z,1680908593.390 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-04-07T23:03:13.390Z,1680908593.390 [CBIT](DEBUG): Initializing heartbeat.
2023-04-07T23:03:13.391Z,1680908593.391 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9346
2023-04-07T23:03:13.392Z,1680908593.392 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-04-07T23:03:13.406Z,1680908593.406 [Onboard ThreadHandler](INFO): Handler Thread ID is 9347
2023-04-07T23:03:13.423Z,1680908593.423 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9348
2023-04-07T23:03:13.442Z,1680908593.442 [DAT ThreadHandler](INFO): Handler Thread ID is 9349
2023-04-07T23:03:13.443Z,1680908593.443 [DAT](INFO): Powering up
2023-04-07T23:03:13.443Z,1680908593.443 [DAT](DEBUG): Initializing DAT.
2023-04-07T23:03:13.447Z,1680908593.447 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9351
2023-04-07T23:03:13.452Z,1680908593.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-04-07T23:03:13.452Z,1680908593.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-04-07T23:03:13.452Z,1680908593.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-04-07T23:03:13.452Z,1680908593.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-04-07T23:03:13.452Z,1680908593.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-04-07T23:03:13.452Z,1680908593.452 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-04-07T23:03:13.453Z,1680908593.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-04-07T23:03:13.453Z,1680908593.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-04-07T23:03:13.453Z,1680908593.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000
2023-04-07T23:03:13.453Z,1680908593.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000
2023-04-07T23:03:13.462Z,1680908593.462 [CBIT](DEBUG): Deactivating GF circuits.
2023-04-07T23:03:13.462Z,1680908593.462 [CBIT](DEBUG): Deactivating emergency mode.
2023-04-07T23:03:13.502Z,1680908593.502 [CBIT](DEBUG): Backplane powered.
2023-04-07T23:03:13.506Z,1680908593.506 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-04-07T23:03:13.506Z,1680908593.506 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-04-07T23:03:13.507Z,1680908593.507 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-04-07T23:03:13.507Z,1680908593.507 [VerticalHomogeneityIndexCalculator](DEBUG): (re)initializing
2023-04-07T23:03:13.508Z,1680908593.508 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-04-07T23:03:13.508Z,1680908593.508 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-04-07T23:03:13.509Z,1680908593.509 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-04-07T23:03:13.510Z,1680908593.510 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-04-07T23:03:13.520Z,1680908593.520 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-04-07T23:03:13.530Z,1680908593.530 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-04-07T23:03:13.531Z,1680908593.531 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-04-07T23:03:13.531Z,1680908593.531 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-04-07T23:03:13.532Z,1680908593.532 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-04-07T23:03:13.573Z,1680908593.573 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-07T23:03:13.616Z,1680908593.616 [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-04-07T23:03:13.617Z,1680908593.617 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-04-07T23:03:13.699Z,1680908593.699 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-04-07T23:03:13.700Z,1680908593.700 [Default:A.Wait](DEBUG): Construct Wait.
2023-04-07T23:03:13.733Z,1680908593.733 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-04-07T23:03:13.776Z,1680908593.776 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-04-07T23:03:13.779Z,1680908593.779 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-04-07T23:03:13.794Z,1680908593.794 [Default:E.Execute](DEBUG): Construct Execute.
2023-04-07T23:03:13.797Z,1680908593.797 [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-04-07T23:03:13.818Z,1680908593.818 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-07T23:03:13.830Z,1680908593.830 [Radio_Surface](INFO): Powering up
2023-04-07T23:03:13.863Z,1680908593.863 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-04-07T23:03:13.931Z,1680908593.931 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2023-04-07T23:03:13.939Z,1680908593.939 [Power24vConverter](INFO): Powering up.
2023-04-07T23:03:13.955Z,1680908593.955 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-04-07T23:03:13.995Z,1680908593.995 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-04-07T23:03:14.002Z,1680908594.002 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-04-07T23:03:14.003Z,1680908594.003 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-04-07T23:03:14.010Z,1680908594.010 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-04-07T23:03:14.011Z,1680908594.011 [MassServo](DEBUG): Initializing EZServoServo.
2023-04-07T23:03:14.018Z,1680908594.018 [MassServo](DEBUG): Initializing MassServo.
2023-04-07T23:03:14.019Z,1680908594.019 [RudderServo](DEBUG): Initializing EZServoServo.
2023-04-07T23:03:14.026Z,1680908594.026 [RudderServo](DEBUG): Initializing RudderServo.
2023-04-07T23:03:14.027Z,1680908594.027 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-04-07T23:03:14.034Z,1680908594.034 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-04-07T23:03:15.490Z,1680908595.490 [WetLabsBB2FL](INFO): Powering down
2023-04-07T23:03:25.034Z,1680908605.034 [DAT](INFO): DAT read: 

2023-04-07T23:03:25.036Z,1680908605.036 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2023-04-07T23:03:26.799Z,1680908606.799 [DAT](INFO): DAT read: MF Frequency Band

2023-04-07T23:03:26.800Z,1680908606.800 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2023-04-07T23:03:26.801Z,1680908606.801 [DAT](INFO): DAT read: Apr  7 2023 23:03:21 

2023-04-07T23:03:27.807Z,1680908607.807 [DAT](INFO): DAT read: Features enabled [Bearing]

2023-04-07T23:03:27.808Z,1680908607.808 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2023-04-07T23:03:27.808Z,1680908607.808 [DAT](INFO): commRate: 1200
2023-04-07T23:03:29.874Z,1680908609.874 [DAT](INFO): entering command mode
2023-04-07T23:03:30.074Z,1680908610.074 [DAT](INFO): DAT read: 

2023-04-07T23:03:30.075Z,1680908610.075 [DAT](INFO): DAT read: user:1>

2023-04-07T23:03:30.075Z,1680908610.075 [DAT](INFO): setting verbose to 3
2023-04-07T23:03:30.326Z,1680908610.326 [DAT](INFO): DAT read: user:1>

2023-04-07T23:03:30.328Z,1680908610.328 [DAT](INFO): DAT read: Verbose         | 3           

2023-04-07T23:03:30.328Z,1680908610.328 [DAT](INFO): set verbose to 3
2023-04-07T23:03:30.328Z,1680908610.328 [DAT](INFO): setting DatVerbose to 27440
2023-04-07T23:03:30.578Z,1680908610.578 [DAT](INFO): DAT read: user:2>

2023-04-07T23:03:30.579Z,1680908610.579 [DAT](INFO): DAT read: DatVerbose      | 27440       

2023-04-07T23:03:30.580Z,1680908610.580 [DAT](INFO): set DatVerbose to 27440
2023-04-07T23:03:30.580Z,1680908610.580 [DAT](INFO): setting transmit power to 8
2023-04-07T23:03:30.830Z,1680908610.830 [DAT](INFO): DAT read: user:3>

2023-04-07T23:03:30.831Z,1680908610.831 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2023-04-07T23:03:30.832Z,1680908610.832 [DAT](INFO): set transmit power to 8
2023-04-07T23:03:30.832Z,1680908610.832 [DAT](INFO): setting local address to 4
2023-04-07T23:03:31.082Z,1680908611.082 [DAT](INFO): DAT read: user:4>

2023-04-07T23:03:31.083Z,1680908611.083 [DAT](INFO): DAT read: LocalAddr       | 4           

2023-04-07T23:03:31.084Z,1680908611.084 [DAT](INFO): set local address to 4
2023-04-07T23:03:31.085Z,1680908611.085 [DAT](INFO): Setting time to: 23:3:31 And date to:4/7/2023
2023-04-07T23:03:31.334Z,1680908611.334 [DAT](INFO): DAT read: user:5>

2023-04-07T23:03:31.335Z,1680908611.335 [DAT](INFO): DAT read: Fri Apr 7, 2023  23:03:31  

2023-04-07T23:03:31.336Z,1680908611.336 [DAT](INFO): Local DAT time set to Fri Apr 7, 2023  23:03:31  

2023-04-07T23:03:42.524Z,1680908622.524 [NAL9602](INFO): Powering up NAL9602
2023-04-07T23:03:53.429Z,1680908633.429 [NAL9602](INFO): NAL9602 initialized
2023-04-07T23:05:20.704Z,1680908720.704 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:05:20.705Z,1680908720.705 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.9,0000.0,1489.0,,-00113,-00241,-00439,+00000,A
2023-04-07T23:05:38.892Z,1680908738.892 [SBIT](IMPORTANT): Beginning Startup BIT
2023-04-07T23:05:38.896Z,1680908738.896 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-07T23:05:50.237Z,1680908750.237 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005722
CHAN A1 (24V): 0.002841
CHAN A2 (12V): -0.006282
CHAN A3 (5V): -0.003673
CHAN B0 (3.3V): -0.001508
CHAN B1 (3.15aV): -0.001318
CHAN B2 (3.15bV): -0.001406
CHAN B3 (GND): -0.000203
OPEN: 0.000473
Full Scale: +/- 1 mA
2023-04-07T23:06:08.850Z,1680908768.850 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:06:08.850Z,1680908768.850 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.8,0000.0,10
2023-04-07T23:06:13.659Z,1680908773.659 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-04-07T23:06:13.659Z,1680908773.659 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:06:13.669Z,1680908773.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:06:14.067Z,1680908774.067 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:06:14.067Z,1680908774.067 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-04-07T23:06:18.884Z,1680908778.884 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:06:18.884Z,1680908778.884 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.8,0000.,000
2023-04-07T23:06:33.021Z,1680908793.021 [SBIT](IMPORTANT): SBIT PASSED
2023-04-07T23:06:33.022Z,1680908793.022 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): CBIT.gfScanTimeout=1 hour;

2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): Depth_Keller.offset=-0.8 decibar;

2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=4 minute;

2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): Express linearApproximation VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index 0.050000 celsius;

2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff470nm;

2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff650nm;

2023-04-07T23:06:33.023Z,1680908793.023 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=1 bool;

2023-04-07T23:06:33.024Z,1680908793.024 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-04-07T23:06:33.024Z,1680908793.024 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-04-07T23:06:33.024Z,1680908793.024 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=155.681673 cubic_centimeter;

2023-04-07T23:06:33.024Z,1680908793.024 [SBIT](IMPORTANT): VerticalControl.massDefault=31.311287 millimeter;

2023-04-07T23:06:33.024Z,1680908793.024 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=15 meter;

2023-04-07T23:06:33.024Z,1680908793.024 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=20 meter;

2023-04-07T23:06:33.024Z,1680908793.024 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=1 bool;

2023-04-07T23:06:33.426Z,1680908793.426 [MissionManager](IMPORTANT): Started mission Startup
2023-04-07T23:06:33.427Z,1680908793.427 [Startup] Running Loop=1
2023-04-07T23:06:33.427Z,1680908793.427 [Startup](DEBUG): Aggregate::initialize Startup
2023-04-07T23:06:33.427Z,1680908793.427 [Startup:A.GoToSurface] Running Loop=1
2023-04-07T23:06:33.427Z,1680908793.427 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-07T23:06:33.427Z,1680908793.427 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-07T23:06:33.428Z,1680908793.428 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-07T23:06:33.428Z,1680908793.428 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-07T23:06:33.429Z,1680908793.429 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-07T23:06:33.429Z,1680908793.429 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-07T23:06:33.430Z,1680908793.430 [Startup:StartupSatComms] Running Loop=1
2023-04-07T23:06:33.430Z,1680908793.430 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-04-07T23:06:33.431Z,1680908793.431 [Startup:StartupSatComms:A] Running Loop=1
2023-04-07T23:06:33.864Z,1680908793.864 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-04-07T23:07:29.712Z,1680908849.712 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004678
2023-04-07T23:07:33.670Z,1680908853.670 [Startup:StartupSatComms:A](INFO): Timed out from 2023-04-07T23:06:33.4Z
2023-04-07T23:07:33.670Z,1680908853.670 [Startup:StartupSatComms:A] Stopped
2023-04-07T23:07:33.670Z,1680908853.670 [Startup:StartupSatComms:B] Running Loop=1
2023-04-07T23:07:34.016Z,1680908854.016 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-07T23:07:44.221Z,1680908864.221 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20230406T234414/Courier0388.lzma
2023-04-07T23:07:45.224Z,1680908865.224 [DataOverHttps](INFO): Moved sent file to Logs/20230406T234414/Courier0388.lzma.bak
2023-04-07T23:07:45.224Z,1680908865.224 [DataOverHttps](INFO): SBD MOMSN=18171506
2023-04-07T23:08:00.897Z,1680908880.897 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230407T230302/Courier0000.lzma
2023-04-07T23:08:01.899Z,1680908881.899 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0000.lzma.bak
2023-04-07T23:08:01.900Z,1680908881.900 [DataOverHttps](INFO): SBD MOMSN=18171509
2023-04-07T23:08:17.682Z,1680908897.682 [DataOverHttps](INFO): Sending 379 bytes from file Logs/20230406T234414/Express0389.lzma
2023-04-07T23:08:18.684Z,1680908898.684 [DataOverHttps](INFO): Moved sent file to Logs/20230406T234414/Express0389.lzma.bak
2023-04-07T23:08:18.684Z,1680908898.684 [DataOverHttps](INFO): SBD MOMSN=18171512
2023-04-07T23:08:33.956Z,1680908913.956 [Startup:StartupSatComms:B](INFO): Timed out from 2023-04-07T23:07:33.7Z
2023-04-07T23:08:33.956Z,1680908913.956 [Startup:StartupSatComms:B] Stopped
2023-04-07T23:08:33.957Z,1680908913.957 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-04-07T23:08:33.957Z,1680908913.957 [Startup:StartupSatComms] Stopped
2023-04-07T23:08:33.957Z,1680908913.957 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-04-07T23:08:33.962Z,1680908913.962 [Startup](INFO): Completed Startup
2023-04-07T23:08:33.962Z,1680908913.962 [MissionManager](INFO): Startup is completed.
2023-04-07T23:08:33.962Z,1680908913.962 [MissionManager](INFO): Uninitializing Mission Startup
2023-04-07T23:08:33.962Z,1680908913.962 [Startup] Stopped
2023-04-07T23:08:33.962Z,1680908913.962 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-04-07T23:08:33.962Z,1680908913.962 [Startup:A.GoToSurface] Stopped
2023-04-07T23:08:33.962Z,1680908913.962 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-07T23:08:34.552Z,1680908914.552 [MissionManager](IMPORTANT): Started mission Default
2023-04-07T23:08:34.552Z,1680908914.552 [Default] Running Loop=1
2023-04-07T23:08:34.552Z,1680908914.552 [Default](DEBUG): Aggregate::initialize Default
2023-04-07T23:08:34.552Z,1680908914.552 [Default:B.GoToSurface] Running Loop=1
2023-04-07T23:08:34.552Z,1680908914.552 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-04-07T23:08:34.553Z,1680908914.553 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-04-07T23:08:34.553Z,1680908914.553 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-04-07T23:08:34.553Z,1680908914.553 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-04-07T23:08:34.582Z,1680908914.582 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-04-07T23:08:34.582Z,1680908914.582 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-04-07T23:08:34.583Z,1680908914.583 [Default:A.Wait] Running Loop=1
2023-04-07T23:08:34.583Z,1680908914.583 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-04-07T23:08:36.032Z,1680908916.032 [DataOverHttps](INFO): Sending 1000 bytes from file Logs/20230407T230302/Express0001.lzma
2023-04-07T23:08:37.032Z,1680908917.032 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0001.lzma.bak
2023-04-07T23:08:37.032Z,1680908917.032 [DataOverHttps](INFO): SBD MOMSN=18171522
2023-04-07T23:08:47.705Z,1680908927.705 [Default:A.Wait](INFO): Done Waiting.
2023-04-07T23:08:47.705Z,1680908927.705 [Default:A.Wait] Stopped
2023-04-07T23:08:47.705Z,1680908927.705 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-04-07T23:08:48.080Z,1680908928.080 [Default:CheckIn] Running Loop=1
2023-04-07T23:08:48.080Z,1680908928.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-07T23:08:48.081Z,1680908928.081 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-07T23:08:48.477Z,1680908928.477 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-04-07T23:08:57.325Z,1680908937.325 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-07T23:09:14.784Z,1680908954.783 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-04-07T23:09:14.784Z,1680908954.784 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:09:14.794Z,1680908954.794 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:09:15.121Z,1680908955.121 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:09:15.121Z,1680908955.121 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-04-07T23:09:41.428Z,1680908981.428 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-07T23:09:41.428Z,1680908981.428 [DVL_micro](ERROR): Failed to parse:
:BI,-00252,-0007,+00000,I
2023-04-07T23:10:37.799Z,1680909037.799 [CommandExec](IMPORTANT): got command failComponent
2023-04-07T23:10:37.799Z,1680909037.799 [CommandExec](IMPORTANT): Failed components:
2023-04-07T23:10:37.799Z,1680909037.799 [CommandExec](IMPORTANT): No failed Components.
2023-04-07T23:10:48.434Z,1680909048.434 [BPC1](ERROR): Battery stick #39 (s/n: 0487) reported OVER_TEMP_ALARM. Status code: 0x1717.
2023-04-07T23:10:48.434Z,1680909048.434 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 5.
2023-04-07T23:10:48.438Z,1680909048.438 [BPC1](INFO): Received data from all battery sticks.
2023-04-07T23:12:15.722Z,1680909135.722 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-04-07T23:12:15.722Z,1680909135.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:12:15.741Z,1680909135.741 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:12:16.153Z,1680909136.153 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:12:16.158Z,1680909136.158 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-04-07T23:12:58.944Z,1680909178.944 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:12:58.945Z,1680909178.945 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+20.8,0000.0,1489.0,000
2023-04-07T23:13:48.269Z,1680909228.269 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-07T23:08:48.1Z
2023-04-07T23:13:48.269Z,1680909228.269 [Default:CheckIn:Read_GPS] Stopped
2023-04-07T23:13:48.269Z,1680909228.269 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-07T23:13:48.662Z,1680909228.662 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-04-07T23:13:50.418Z,1680909230.418 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230407T230302/Courier0004.lzma
2023-04-07T23:13:51.267Z,1680909231.267 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0004.lzma.bak
2023-04-07T23:13:51.268Z,1680909231.268 [DataOverHttps](INFO): SBD MOMSN=18171556
2023-04-07T23:13:55.352Z,1680909235.352 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-07T23:13:55.352Z,1680909235.352 [NAL9602] Data Fault, FailCount= 1
2023-04-07T23:13:55.352Z,1680909235.352 [NAL9602](ERROR): Data Fault
2023-04-07T23:13:55.405Z,1680909235.405 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-07T23:13:55.780Z,1680909235.780 [NAL9602](INFO): Powering down
2023-04-07T23:13:56.599Z,1680909236.599 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-07T23:13:56.599Z,1680909236.599 [NAL9602] No Fault, FailCount= 1
2023-04-07T23:14:07.677Z,1680909247.677 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20230407T230302/Express0005.lzma
2023-04-07T23:14:08.680Z,1680909248.680 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0005.lzma.bak
2023-04-07T23:14:08.680Z,1680909248.680 [DataOverHttps](INFO): SBD MOMSN=18171558
2023-04-07T23:14:10.318Z,1680909250.318 [Default:CheckIn:Read_Iridium] Stopped
2023-04-07T23:14:10.318Z,1680909250.318 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-07T23:14:10.318Z,1680909250.318 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-07T23:14:26.056Z,1680909266.056 [NAL9602](INFO): Powering up NAL9602
2023-04-07T23:14:36.960Z,1680909276.960 [NAL9602](INFO): NAL9602 initialized
2023-04-07T23:15:16.582Z,1680909316.582 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-04-07T23:15:16.582Z,1680909316.582 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:15:16.601Z,1680909316.601 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:15:16.996Z,1680909316.996 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:15:16.997Z,1680909316.997 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-04-07T23:16:21.634Z,1680909381.634 [DVL_micro](ERROR): Failed to parse:99.999,+9999.999
2023-04-07T23:16:38.479Z,1680909398.479 [CommandExec](IMPORTANT): got command strobe off
2023-04-07T23:16:38.479Z,1680909398.479 [CommandExec](IMPORTANT): Deactivating strobe
2023-04-07T23:16:46.615Z,1680909406.615 [CommandExec](IMPORTANT): got command get platform_battery_charge
2023-04-07T23:16:46.616Z,1680909406.616 [CommandExec](FAULT): Element has no value
2023-04-07T23:17:02.347Z,1680909422.347 [CommandExec](IMPORTANT): got command get BPC1.platform_battery_charge
2023-04-07T23:17:02.348Z,1680909422.348 [CommandExec](IMPORTANT): BPC1.platform_battery_charge 351.541016 Ah
2023-04-07T23:18:17.588Z,1680909497.588 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-04-07T23:18:17.588Z,1680909497.588 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:18:17.611Z,1680909497.611 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:18:18.011Z,1680909498.011 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:18:18.011Z,1680909498.011 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-04-07T23:19:10.888Z,1680909550.888 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-07T23:19:10.888Z,1680909550.888 [Default:CheckIn:C.Wait] Stopped
2023-04-07T23:19:10.888Z,1680909550.888 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-07T23:19:10.888Z,1680909550.888 [Default:CheckIn:D] Running Loop=1
2023-04-07T23:19:11.295Z,1680909551.295 [Default:CheckIn:D] Stopped
2023-04-07T23:19:11.295Z,1680909551.295 [Default:CheckIn:E] Running Loop=1
2023-04-07T23:19:11.732Z,1680909551.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.612379 min 
2023-04-07T23:19:11.732Z,1680909551.732 [Default:CheckIn:E] Stopped
2023-04-07T23:19:11.732Z,1680909551.732 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-07T23:19:11.732Z,1680909551.732 [Default:CheckIn] Stopped
2023-04-07T23:19:11.733Z,1680909551.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-07T23:19:11.733Z,1680909551.733 [Default:CheckIn](INFO): Running loop #2
2023-04-07T23:19:11.733Z,1680909551.733 [Default:CheckIn] Running Loop=2
2023-04-07T23:19:11.733Z,1680909551.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-07T23:19:11.733Z,1680909551.733 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-07T23:21:18.571Z,1680909678.571 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-04-07T23:21:18.571Z,1680909678.571 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:21:18.611Z,1680909678.611 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:21:18.972Z,1680909678.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:21:18.972Z,1680909678.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-04-07T23:21:25.820Z,1680909685.820 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:21:25.820Z,1680909685.820 [DVL_micro](ERROR): Failed to parse:
:TS000000000000000,35.0,+20.8,0000.0,1489.0,000
2023-04-07T23:21:46.666Z,1680909706.666 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-07T23:22:17.790Z,1680909737.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-07T23:22:48.922Z,1680909768.922 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-07T23:23:07.231Z,1680909787.231 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.7,0000.0,1489.0,000
2023-04-07T23:23:20.050Z,1680909800.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-07T23:23:50.067Z,1680909830.067 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-07T23:23:50.067Z,1680909830.067 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2023-04-07T23:23:51.174Z,1680909831.174 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-04-07T23:23:54.186Z,1680909834.186 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747391
2023-04-07T23:24:11.881Z,1680909851.881 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-07T23:19:11.7Z
2023-04-07T23:24:11.881Z,1680909851.881 [Default:CheckIn:Read_GPS] Stopped
2023-04-07T23:24:11.881Z,1680909851.881 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-07T23:24:19.727Z,1680909859.727 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-04-07T23:24:19.727Z,1680909859.727 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:24:19.738Z,1680909859.738 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:24:19.956Z,1680909859.956 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:24:19.956Z,1680909859.956 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-04-07T23:24:40.545Z,1680909880.545 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-07T23:24:40.545Z,1680909880.545 [NAL9602] Data Fault, FailCount= 2
2023-04-07T23:24:40.545Z,1680909880.545 [NAL9602](ERROR): Data Fault
2023-04-07T23:24:40.585Z,1680909880.585 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-07T23:24:40.961Z,1680909880.961 [NAL9602](INFO): Powering down
2023-04-07T23:24:41.773Z,1680909881.773 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-07T23:24:41.773Z,1680909881.773 [NAL9602] No Fault, FailCount= 2
2023-04-07T23:24:52.218Z,1680909892.218 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002766
2023-04-07T23:24:59.605Z,1680909899.605 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230407T230302/Courier0007.lzma
2023-04-07T23:25:00.608Z,1680909900.608 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0007.lzma.bak
2023-04-07T23:25:00.608Z,1680909900.608 [DataOverHttps](INFO): SBD MOMSN=18171579
2023-04-07T23:25:11.308Z,1680909911.308 [NAL9602](INFO): Powering up NAL9602
2023-04-07T23:25:16.394Z,1680909916.394 [DataOverHttps](INFO): Sending 305 bytes from file Logs/20230407T230302/Express0008.lzma
2023-04-07T23:25:17.396Z,1680909917.396 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0008.lzma.bak
2023-04-07T23:25:17.396Z,1680909917.396 [DataOverHttps](INFO): SBD MOMSN=18171581
2023-04-07T23:25:19.020Z,1680909919.020 [Default:CheckIn:Read_Iridium] Stopped
2023-04-07T23:25:19.020Z,1680909919.020 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-07T23:25:19.020Z,1680909919.020 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-07T23:25:22.220Z,1680909922.220 [NAL9602](INFO): NAL9602 initialized
2023-04-07T23:26:29.698Z,1680909989.698 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:26:29.698Z,1680909989.698 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.8,0000.0,1489.WI,+00050,-00038,-00557,+000I,+00050,-00038,-00557,+00000,I
2023-04-07T23:27:07.664Z,1680910027.664 [DVL_micro](ERROR): Failed to parse:
:SA,-03.75,+02.92,334.3
2023-04-07T23:27:20.611Z,1680910040.611 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-04-07T23:27:20.611Z,1680910040.611 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:27:20.621Z,1680910040.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:27:21.036Z,1680910041.036 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:27:21.036Z,1680910041.036 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-04-07T23:29:54.990Z,1680910194.990 [DVL_micro](ERROR): DVL uart error: serial timeout
2023-04-07T23:29:54.990Z,1680910194.990 [DVL_micro] Communications Fault, FailCount= 1
2023-04-07T23:29:54.990Z,1680910194.990 [DVL_micro](ERROR): Communications Fault
2023-04-07T23:29:54.990Z,1680910194.990 [DVL_micro](ERROR): Failed to parse:
2023-04-07T23:29:55.053Z,1680910195.053 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-04-07T23:29:55.394Z,1680910195.394 [DVL_micro](INFO): Powering down
2023-04-07T23:29:56.161Z,1680910196.161 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-07T23:29:56.161Z,1680910196.161 [DVL_micro] No Fault, FailCount= 1
2023-04-07T23:30:19.580Z,1680910219.580 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-07T23:30:19.580Z,1680910219.580 [Default:CheckIn:C.Wait] Stopped
2023-04-07T23:30:19.580Z,1680910219.580 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-07T23:30:19.580Z,1680910219.580 [Default:CheckIn:D] Running Loop=1
2023-04-07T23:30:19.000Z,1680910220.000 [Default:CheckIn:D] Stopped
2023-04-07T23:30:19.000Z,1680910220.000 [Default:CheckIn:E] Running Loop=1
2023-04-07T23:30:20.396Z,1680910220.396 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.757463 min 
2023-04-07T23:30:20.396Z,1680910220.396 [Default:CheckIn:E] Stopped
2023-04-07T23:30:20.396Z,1680910220.396 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-07T23:30:20.396Z,1680910220.396 [Default:CheckIn] Stopped
2023-04-07T23:30:20.396Z,1680910220.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-07T23:30:20.397Z,1680910220.397 [Default:CheckIn](INFO): Running loop #3
2023-04-07T23:30:20.397Z,1680910220.397 [Default:CheckIn] Running Loop=3
2023-04-07T23:30:20.397Z,1680910220.397 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-07T23:30:20.397Z,1680910220.397 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-07T23:30:21.596Z,1680910221.596 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-04-07T23:30:21.596Z,1680910221.596 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:30:21.637Z,1680910221.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:30:22.029Z,1680910222.029 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:30:22.029Z,1680910222.029 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-04-07T23:31:02.462Z,1680910262.462 [DVL_micro](ERROR): DVL uart error: serial timeout
2023-04-07T23:31:02.462Z,1680910262.462 [DVL_micro] Communications Fault, FailCount= 1
2023-04-07T23:31:02.462Z,1680910262.462 [DVL_micro](ERROR): Communications Fault
2023-04-07T23:31:02.462Z,1680910262.462 [DVL_micro](ERROR): Failed to parse:
2023-04-07T23:31:02.515Z,1680910262.515 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-04-07T23:31:02.858Z,1680910262.858 [DVL_micro](INFO): Powering down
2023-04-07T23:31:03.612Z,1680910263.612 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-07T23:31:03.612Z,1680910263.612 [DVL_micro] No Fault, FailCount= 1
2023-04-07T23:33:22.599Z,1680910402.599 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-04-07T23:33:22.599Z,1680910402.599 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:33:22.609Z,1680910402.609 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:33:23.027Z,1680910403.027 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:33:23.027Z,1680910403.027 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-04-07T23:35:20.553Z,1680910520.553 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-07T23:30:20.4Z
2023-04-07T23:35:20.554Z,1680910520.554 [Default:CheckIn:Read_GPS] Stopped
2023-04-07T23:35:20.554Z,1680910520.554 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-07T23:35:23.368Z,1680910523.368 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-07T23:35:23.368Z,1680910523.368 [NAL9602] Data Fault, FailCount= 3
2023-04-07T23:35:23.368Z,1680910523.368 [NAL9602](ERROR): Data Fault
2023-04-07T23:35:23.385Z,1680910523.385 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-07T23:35:23.777Z,1680910523.777 [NAL9602](INFO): Powering down
2023-04-07T23:35:24.650Z,1680910524.650 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-07T23:35:24.650Z,1680910524.650 [NAL9602] No Fault, FailCount= 3
2023-04-07T23:35:28.341Z,1680910528.341 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230407T230302/Courier0010.lzma
2023-04-07T23:35:29.343Z,1680910529.343 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0010.lzma.bak
2023-04-07T23:35:29.344Z,1680910529.344 [DataOverHttps](INFO): SBD MOMSN=18171608
2023-04-07T23:35:48.381Z,1680910548.381 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20230407T230302/Express0011.lzma
2023-04-07T23:35:49.383Z,1680910549.383 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0011.lzma.bak
2023-04-07T23:35:49.384Z,1680910549.384 [DataOverHttps](INFO): SBD MOMSN=18171611
2023-04-07T23:35:50.916Z,1680910550.916 [Default:CheckIn:Read_Iridium] Stopped
2023-04-07T23:35:50.916Z,1680910550.916 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-07T23:35:50.916Z,1680910550.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-07T23:35:54.144Z,1680910554.144 [NAL9602](INFO): Powering up NAL9602
2023-04-07T23:36:05.045Z,1680910565.045 [NAL9602](INFO): NAL9602 initialized
2023-04-07T23:36:05.453Z,1680910565.453 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:36:05.453Z,1680910565.453 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+200,1489.0,000
2023-04-07T23:36:23.667Z,1680910583.667 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-04-07T23:36:23.667Z,1680910583.667 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:36:23.703Z,1680910583.703 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:36:24.071Z,1680910584.071 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:36:24.071Z,1680910584.071 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-04-07T23:39:24.650Z,1680910764.650 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2023-04-07T23:39:24.650Z,1680910764.650 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:39:24.661Z,1680910764.661 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:39:25.050Z,1680910765.050 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:39:25.050Z,1680910765.050 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2023-04-07T23:39:58.585Z,1680910798.585 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:39:58.585Z,1680910798.585 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.8,0000.0,1489.WI,+00046,-00252,-00455,+00000,A
2023-04-07T23:40:51.499Z,1680910851.499 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-07T23:40:51.499Z,1680910851.499 [Default:CheckIn:C.Wait] Stopped
2023-04-07T23:40:51.499Z,1680910851.499 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-07T23:40:51.499Z,1680910851.499 [Default:CheckIn:D] Running Loop=1
2023-04-07T23:40:51.927Z,1680910851.927 [Default:CheckIn:D] Stopped
2023-04-07T23:40:51.927Z,1680910851.927 [Default:CheckIn:E] Running Loop=1
2023-04-07T23:40:52.324Z,1680910852.324 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.289587 min 
2023-04-07T23:40:52.324Z,1680910852.324 [Default:CheckIn:E] Stopped
2023-04-07T23:40:52.324Z,1680910852.324 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-07T23:40:52.324Z,1680910852.324 [Default:CheckIn] Stopped
2023-04-07T23:40:52.324Z,1680910852.324 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-07T23:40:52.325Z,1680910852.325 [Default:CheckIn](INFO): Running loop #4
2023-04-07T23:40:52.325Z,1680910852.325 [Default:CheckIn] Running Loop=4
2023-04-07T23:40:52.325Z,1680910852.325 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-07T23:40:52.325Z,1680910852.325 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-07T23:42:25.647Z,1680910945.647 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2023-04-07T23:42:25.647Z,1680910945.647 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:42:25.657Z,1680910945.657 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:42:26.039Z,1680910946.039 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:42:26.039Z,1680910946.039 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2023-04-07T23:45:26.670Z,1680911126.670 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2023-04-07T23:45:26.670Z,1680911126.670 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:45:26.681Z,1680911126.681 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:45:27.104Z,1680911127.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:45:27.104Z,1680911127.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2023-04-07T23:45:30.306Z,1680911130.306 [DVL_micro](ERROR): Failed to parse:
:000000000,35.0,+20.9,0000.0,1489.0,000
2023-04-07T23:45:52.593Z,1680911152.593 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-07T23:40:52.3Z
2023-04-07T23:45:52.593Z,1680911152.593 [Default:CheckIn:Read_GPS] Stopped
2023-04-07T23:45:52.593Z,1680911152.593 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-07T23:45:59.789Z,1680911159.789 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230407T230302/Courier0013.lzma
2023-04-07T23:46:00.791Z,1680911160.791 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0013.lzma.bak
2023-04-07T23:46:00.792Z,1680911160.792 [DataOverHttps](INFO): SBD MOMSN=18171632
2023-04-07T23:46:07.120Z,1680911167.120 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-07T23:46:07.120Z,1680911167.120 [NAL9602] Data Fault, FailCount= 4
2023-04-07T23:46:07.120Z,1680911167.120 [NAL9602](ERROR): Data Fault
2023-04-07T23:46:07.141Z,1680911167.141 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-07T23:46:07.524Z,1680911167.524 [NAL9602](INFO): Powering down
2023-04-07T23:46:08.365Z,1680911168.365 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-07T23:46:08.365Z,1680911168.365 [NAL9602] No Fault, FailCount= 4
2023-04-07T23:46:16.381Z,1680911176.381 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20230407T230302/Express0014.lzma
2023-04-07T23:46:17.384Z,1680911177.384 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0014.lzma.bak
2023-04-07T23:46:17.384Z,1680911177.384 [DataOverHttps](INFO): SBD MOMSN=18171634
2023-04-07T23:46:18.862Z,1680911178.862 [Default:CheckIn:Read_Iridium] Stopped
2023-04-07T23:46:18.862Z,1680911178.862 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-07T23:46:18.863Z,1680911178.863 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-07T23:46:23.299Z,1680911183.299 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:46:23.299Z,1680911183.299 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+20.,1489.0,000
2023-04-07T23:46:37.927Z,1680911197.927 [NAL9602](INFO): Powering up NAL9602
2023-04-07T23:46:46.594Z,1680911206.594 [CommandExec](IMPORTANT): got command failComponent
2023-04-07T23:46:46.594Z,1680911206.594 [CommandExec](IMPORTANT): Failed components:
2023-04-07T23:46:46.595Z,1680911206.595 [CommandExec](IMPORTANT): No failed Components.
2023-04-07T23:46:49.133Z,1680911209.133 [NAL9602](INFO): NAL9602 initialized
2023-04-07T23:46:55.791Z,1680911215.791 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position
2023-04-07T23:46:56.069Z,1680911216.069 [Reporter](INFO): platform_buoyancy_position 950.028198 cc
2023-04-07T23:48:27.751Z,1680911307.751 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2023-04-07T23:48:27.751Z,1680911307.751 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:48:27.762Z,1680911307.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:48:28.150Z,1680911308.150 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:48:28.150Z,1680911308.150 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2023-04-07T23:51:12.165Z,1680911472.165 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:51:12.165Z,1680911472.165 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.91489.0,000
2023-04-07T23:51:19.479Z,1680911479.479 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-07T23:51:19.479Z,1680911479.479 [Default:CheckIn:C.Wait] Stopped
2023-04-07T23:51:19.479Z,1680911479.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-07T23:51:19.479Z,1680911479.479 [Default:CheckIn:D] Running Loop=1
2023-04-07T23:51:19.832Z,1680911479.832 [Default:CheckIn:D] Stopped
2023-04-07T23:51:19.832Z,1680911479.832 [Default:CheckIn:E] Running Loop=1
2023-04-07T23:51:20.228Z,1680911480.228 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.754659 min 
2023-04-07T23:51:20.228Z,1680911480.228 [Default:CheckIn:E] Stopped
2023-04-07T23:51:20.228Z,1680911480.228 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-07T23:51:20.228Z,1680911480.228 [Default:CheckIn] Stopped
2023-04-07T23:51:20.228Z,1680911480.228 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-07T23:51:20.229Z,1680911480.229 [Default:CheckIn](INFO): Running loop #5
2023-04-07T23:51:20.229Z,1680911480.229 [Default:CheckIn] Running Loop=5
2023-04-07T23:51:20.229Z,1680911480.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-07T23:51:20.229Z,1680911480.229 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-07T23:51:28.725Z,1680911488.725 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2023-04-07T23:51:28.725Z,1680911488.725 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:51:28.736Z,1680911488.736 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:51:29.123Z,1680911489.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:51:29.123Z,1680911489.123 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2023-04-07T23:52:05.070Z,1680911525.070 [DVL_micro](ERROR): only read 0 of 4 data items
2023-04-07T23:52:05.071Z,1680911525.071 [DVL_micro](ERROR): Failed to parse:
:RD,,+9999.99,+9999.99,+9999.99
2023-04-07T23:53:36.372Z,1680911616.372 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-07T23:53:36.372Z,1680911616.372 [DVL_micro](ERROR): Failed to parse:
:BI,-0786,-00631,+00000,I
2023-04-07T23:54:29.763Z,1680911669.763 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2023-04-07T23:54:29.763Z,1680911669.763 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:54:29.790Z,1680911669.790 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:54:30.159Z,1680911670.159 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:54:30.159Z,1680911670.159 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2023-04-07T23:56:20.465Z,1680911780.465 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-04-07T23:51:20.2Z
2023-04-07T23:56:20.465Z,1680911780.465 [Default:CheckIn:Read_GPS] Stopped
2023-04-07T23:56:20.466Z,1680911780.466 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-07T23:56:28.025Z,1680911788.025 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230407T230302/Courier0016.lzma
2023-04-07T23:56:29.028Z,1680911789.028 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0016.lzma.bak
2023-04-07T23:56:29.028Z,1680911789.028 [DataOverHttps](INFO): SBD MOMSN=18171645
2023-04-07T23:56:48.050Z,1680911808.050 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20230407T230302/Express0017.lzma
2023-04-07T23:56:49.052Z,1680911809.052 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0017.lzma.bak
2023-04-07T23:56:49.052Z,1680911809.052 [DataOverHttps](INFO): SBD MOMSN=18171647
2023-04-07T23:56:50.436Z,1680911810.436 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-04-07T23:56:50.436Z,1680911810.436 [NAL9602] Data Fault, FailCount= 5
2023-04-07T23:56:50.436Z,1680911810.436 [NAL9602](ERROR): Data Fault
2023-04-07T23:56:50.443Z,1680911810.443 [Default:CheckIn:Read_Iridium] Stopped
2023-04-07T23:56:50.443Z,1680911810.443 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-07T23:56:50.443Z,1680911810.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-07T23:56:50.453Z,1680911810.453 [CBIT](ERROR): Data Fault in component: NAL9602
2023-04-07T23:56:50.453Z,1680911810.453 [CBIT](CRITICAL): Data Fault in component: NAL9602
2023-04-07T23:56:50.837Z,1680911810.837 [NAL9602](INFO): Powering down
2023-04-07T23:56:50.869Z,1680911810.869 [CommandExec](FAULT): Scheduling is paused
2023-04-07T23:56:50.869Z,1680911810.869 [CBIT](INFO): Critical error at 20230407T235650
2023-04-07T23:56:50.869Z,1680911810.869 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-04-07T23:57:30.835Z,1680911850.835 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2023-04-07T23:57:30.836Z,1680911850.836 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-07T23:57:30.846Z,1680911850.846 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-07T23:57:31.249Z,1680911851.249 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-07T23:57:31.249Z,1680911851.249 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2023-04-07T23:57:34.858Z,1680911854.858 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:57:34.859Z,1680911854.859 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+20.1,089.0,000
2023-04-07T23:57:44.124Z,1680911864.124 [CommandExec](IMPORTANT): got command get platform_mass_position centimeter
2023-04-07T23:57:44.124Z,1680911864.124 [CommandExec](IMPORTANT): platform_mass_position 3.131104 cm
2023-04-07T23:57:51.727Z,1680911871.727 [CommandExec](IMPORTANT): got command show variable limitAft
2023-04-07T23:57:51.801Z,1680911871.801 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter)
2023-04-07T23:58:09.172Z,1680911889.172 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2023-04-07T23:58:09.173Z,1680911889.173 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft -15.000000 mm
2023-04-07T23:58:11.272Z,1680911891.272 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-07T23:58:11.273Z,1680911891.273 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.6,089.0,000
2023-04-07T23:58:20.043Z,1680911900.043 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault -1 centimeter persist
2023-04-07T23:58:50.455Z,1680911930.455 [CBIT](INFO): Clearing failed state for component NAL9602
2023-04-07T23:58:50.455Z,1680911930.455 [NAL9602] No Fault, FailCount= 5
2023-04-07T23:58:50.816Z,1680911930.816 [NAL9602](INFO): Powering up NAL9602
2023-04-07T23:59:01.736Z,1680911941.736 [NAL9602](INFO): NAL9602 initialized
2023-04-07T23:59:33.424Z,1680911973.424 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 500 cubic_centimeter
2023-04-07T23:59:33.436Z,1680911973.436 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2023-04-07T23:59:33.721Z,1680911973.721 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-07T23:59:39.844Z,1680911979.844 [Reporter](INFO): platform_buoyancy_position 950.296753 cc
2023-04-07T23:59:40.192Z,1680911980.192 [Reporter](INFO): platform_buoyancy_position 950.028198 cc
2023-04-07T23:59:40.608Z,1680911980.608 [Reporter](INFO): platform_buoyancy_position 948.013672 cc
2023-04-07T23:59:40.990Z,1680911980.990 [Reporter](INFO): platform_buoyancy_position 939.015503 cc
2023-04-07T23:59:41.432Z,1680911981.432 [Reporter](INFO): platform_buoyancy_position 937.001099 cc
2023-04-07T23:59:41.812Z,1680911981.812 [Reporter](INFO): platform_buoyancy_position 932.972046 cc
2023-04-07T23:59:42.188Z,1680911982.188 [Reporter](INFO): platform_buoyancy_position 928.540161 cc
2023-04-07T23:59:42.628Z,1680911982.628 [Reporter](INFO): platform_buoyancy_position 924.376831 cc
2023-04-07T23:59:43.010Z,1680911983.010 [Reporter](INFO): platform_buoyancy_position 920.079224 cc
2023-04-07T23:59:43.425Z,1680911983.425 [Reporter](INFO): platform_buoyancy_position 913.632874 cc
2023-04-07T23:59:43.834Z,1680911983.834 [Reporter](INFO): platform_buoyancy_position 909.200928 cc
2023-04-07T23:59:44.229Z,1680911984.229 [Reporter](INFO): platform_buoyancy_position 904.903320 cc
2023-04-07T23:59:44.620Z,1680911984.620 [Reporter](INFO): platform_buoyancy_position 898.591248 cc
2023-04-07T23:59:45.020Z,1680911985.020 [Reporter](INFO): platform_buoyancy_position 894.159363 cc
2023-04-07T23:59:45.464Z,1680911985.464 [Reporter](INFO): platform_buoyancy_position 887.712952 cc
2023-04-07T23:59:45.868Z,1680911985.868 [Reporter](INFO): platform_buoyancy_position 881.669434 cc
2023-04-07T23:59:46.252Z,1680911986.252 [Reporter](INFO): platform_buoyancy_position 876.968933 cc
2023-04-07T23:59:46.273Z,1680911986.273 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter
2023-04-07T23:59:46.659Z,1680911986.659 [Reporter](INFO): platform_buoyancy_position 872.939941 cc
2023-04-07T23:59:47.024Z,1680911987.024 [Reporter](INFO): platform_buoyancy_position 868.373718 cc
2023-04-07T23:59:47.447Z,1680911987.447 [Reporter](INFO): platform_buoyancy_position 862.061646 cc
2023-04-07T23:59:47.836Z,1680911987.836 [Reporter](INFO): platform_buoyancy_position 856.555359 cc
2023-04-07T23:59:48.233Z,1680911988.233 [Reporter](INFO): platform_buoyancy_position 851.317627 cc
2023-04-07T23:59:48.690Z,1680911988.690 [Reporter](INFO): platform_buoyancy_position 844.736938 cc
2023-04-07T23:59:49.056Z,1680911989.056 [Reporter](INFO): platform_buoyancy_position 840.439331 cc
2023-04-07T23:59:49.476Z,1680911989.476 [Reporter](INFO): platform_buoyancy_position 835.604553 cc
2023-04-07T23:59:49.864Z,1680911989.864 [Reporter](INFO): platform_buoyancy_position 829.829651 cc
2023-04-07T23:59:50.258Z,1680911990.258 [Reporter](INFO): platform_buoyancy_position 825.397766 cc
2023-04-07T23:59:50.676Z,1680911990.676 [Reporter](INFO): platform_buoyancy_position 819.085632 cc
2023-04-07T23:59:51.068Z,1680911991.068 [Reporter](INFO): platform_buoyancy_position 814.519470 cc
2023-04-07T23:59:51.502Z,1680911991.502 [Reporter](INFO): platform_buoyancy_position 808.341614 cc
2023-04-07T23:59:51.896Z,1680911991.896 [Reporter](INFO): platform_buoyancy_position 803.909729 cc
2023-04-07T23:59:52.304Z,1680911992.304 [Reporter](INFO): platform_buoyancy_position 797.597656 cc
2023-04-07T23:59:52.706Z,1680911992.706 [Reporter](INFO): platform_buoyancy_position 793.165710 cc
2023-04-07T23:59:53.076Z,1680911993.076 [Reporter](INFO): platform_buoyancy_position 788.868164 cc
2023-04-07T23:59:53.492Z,1680911993.492 [Reporter](INFO): platform_buoyancy_position 782.421753 cc
2023-04-07T23:59:53.900Z,1680911993.900 [Reporter](INFO): platform_buoyancy_position 778.124146 cc
2023-04-07T23:59:54.312Z,1680911994.312 [Reporter](INFO): platform_buoyancy_position 771.677734 cc
2023-04-07T23:59:54.729Z,1680911994.729 [Reporter](INFO): platform_buoyancy_position 767.380127 cc
2023-04-07T23:59:55.138Z,1680911995.138 [Reporter](INFO): platform_buoyancy_position 761.068054 cc
2023-04-07T23:59:55.550Z,1680911995.550 [Reporter](INFO): platform_buoyancy_position 756.501831 cc
2023-04-07T23:59:55.922Z,1680911995.922 [Reporter](INFO): platform_buoyancy_position 750.458313 cc
2023-04-07T23:59:56.411Z,1680911996.411 [Reporter](INFO): platform_buoyancy_position 743.877625 cc
2023-04-07T23:59:56.748Z,1680911996.748 [Reporter](INFO): platform_buoyancy_position 739.580017 cc
2023-04-07T23:59:57.144Z,1680911997.144 [Reporter](INFO): platform_buoyancy_position 735.148132 cc
2023-04-07T23:59:57.580Z,1680911997.580 [Reporter](INFO): platform_buoyancy_position 728.701721 cc
2023-04-07T23:59:57.967Z,1680911997.967 [Reporter](INFO): platform_buoyancy_position 724.404114 cc
2023-04-07T23:59:58.396Z,1680911998.396 [Reporter](INFO): platform_buoyancy_position 718.092041 cc
2023-04-07T23:59:58.764Z,1680911998.764 [Reporter](INFO): platform_buoyancy_position 713.794434 cc
2023-04-07T23:59:59.167Z,1680911999.167 [Reporter](INFO): platform_buoyancy_position 707.348022 cc
2023-04-07T23:59:59.580Z,1680911999.580 [Reporter](INFO): platform_buoyancy_position 702.916138 cc
2023-04-07T23:59:59.956Z,1680911999.956 [Reporter](INFO): platform_buoyancy_position 698.618530 cc
2023-04-08T00:00:00.392Z,1680912000.392 [Reporter](INFO): platform_buoyancy_position 692.172119 cc
2023-04-08T00:00:00.784Z,1680912000.784 [Reporter](INFO): platform_buoyancy_position 687.874512 cc
2023-04-08T00:00:01.204Z,1680912001.204 [Reporter](INFO): platform_buoyancy_position 681.428101 cc
2023-04-08T00:00:01.560Z,1680912001.560 [Reporter](INFO): platform_buoyancy_position 677.130554 cc
2023-04-08T00:00:01.972Z,1680912001.972 [Reporter](INFO): platform_buoyancy_position 670.952759 cc
2023-04-08T00:00:02.388Z,1680912002.388 [Reporter](INFO): platform_buoyancy_position 666.386536 cc
2023-04-08T00:00:02.784Z,1680912002.784 [Reporter](INFO): platform_buoyancy_position 659.940125 cc
2023-04-08T00:00:03.176Z,1680912003.176 [Reporter](INFO): platform_buoyancy_position 655.642517 cc
2023-04-08T00:00:03.630Z,1680912003.630 [Reporter](INFO): platform_buoyancy_position 649.330444 cc
2023-04-08T00:00:04.004Z,1680912004.004 [Reporter](INFO): platform_buoyancy_position 645.032837 cc
2023-04-08T00:00:04.432Z,1680912004.432 [Reporter](INFO): platform_buoyancy_position 638.855042 cc
2023-04-08T00:00:04.792Z,1680912004.792 [Reporter](INFO): platform_buoyancy_position 634.288818 cc
2023-04-08T00:00:05.251Z,1680912005.251 [Reporter](INFO): platform_buoyancy_position 627.976746 cc
2023-04-08T00:00:05.615Z,1680912005.615 [Reporter](INFO): platform_buoyancy_position 623.410522 cc
2023-04-08T00:00:06.037Z,1680912006.037 [Reporter](INFO): platform_buoyancy_position 618.844299 cc
2023-04-08T00:00:06.443Z,1680912006.443 [Reporter](INFO): platform_buoyancy_position 612.666504 cc
2023-04-08T00:00:06.840Z,1680912006.840 [Reporter](INFO): platform_buoyancy_position 608.234619 cc
2023-04-08T00:00:07.273Z,1680912007.273 [Reporter](INFO): platform_buoyancy_position 601.922546 cc
2023-04-08T00:00:07.628Z,1680912007.628 [Reporter](INFO): platform_buoyancy_position 597.490601 cc
2023-04-08T00:00:08.028Z,1680912008.028 [Reporter](INFO): platform_buoyancy_position 591.312805 cc
2023-04-08T00:00:08.436Z,1680912008.436 [Reporter](INFO): platform_buoyancy_position 587.015198 cc
2023-04-08T00:00:08.852Z,1680912008.852 [Reporter](INFO): platform_buoyancy_position 580.568848 cc
2023-04-08T00:00:09.265Z,1680912009.265 [Reporter](INFO): platform_buoyancy_position 576.136902 cc
2023-04-08T00:00:09.678Z,1680912009.678 [Reporter](INFO): platform_buoyancy_position 569.824829 cc
2023-04-08T00:00:10.077Z,1680912010.077 [Reporter](INFO): platform_buoyancy_position 565.392944 cc
2023-04-08T00:00:10.492Z,1680912010.492 [Reporter](INFO): platform_buoyancy_position 559.080811 cc
2023-04-08T00:00:10.871Z,1680912010.871 [Reporter](INFO): platform_buoyancy_position 554.648926 cc
2023-04-08T00:00:11.289Z,1680912011.289 [Reporter](INFO): platform_buoyancy_position 548.739746 cc
2023-04-08T00:00:11.672Z,1680912011.672 [Reporter](INFO): platform_buoyancy_position 543.904907 cc
2023-04-08T00:00:12.080Z,1680912012.080 [Reporter](INFO): platform_buoyancy_position 539.473022 cc
2023-04-08T00:00:12.500Z,1680912012.500 [Reporter](INFO): platform_buoyancy_position 533.160950 cc
2023-04-08T00:00:12.905Z,1680912012.905 [Reporter](INFO): platform_buoyancy_position 527.788940 cc
2023-04-08T00:00:13.324Z,1680912013.324 [Reporter](INFO): platform_buoyancy_position 522.416931 cc
2023-04-08T00:00:13.692Z,1680912013.692 [Reporter](INFO): platform_buoyancy_position 518.119324 cc
2023-04-08T00:00:14.107Z,1680912014.107 [Reporter](INFO): platform_buoyancy_position 511.672943 cc
2023-04-08T00:00:14.548Z,1680912014.548 [Reporter](INFO): platform_buoyancy_position 507.509674 cc
2023-04-08T00:00:14.912Z,1680912014.912 [Reporter](INFO): platform_buoyancy_position 503.212036 cc
2023-04-08T00:00:15.312Z,1680912015.312 [Reporter](INFO): platform_buoyancy_position 500.526031 cc
2023-04-08T00:00:15.724Z,1680912015.724 [Reporter](INFO): platform_buoyancy_position 494.482574 cc
2023-04-08T00:00:16.160Z,1680912016.160 [Reporter](INFO): platform_buoyancy_position 490.050659 cc
2023-04-08T00:00:16.546Z,1680912016.546 [Reporter](INFO): platform_buoyancy_position 484.275726 cc
2023-04-08T00:00:16.934Z,1680912016.934 [Reporter](INFO): platform_buoyancy_position 479.709564 cc
2023-04-08T00:00:17.343Z,1680912017.343 [Reporter](INFO): platform_buoyancy_position 475.143341 cc
2023-04-08T00:00:17.748Z,1680912017.748 [Reporter](INFO): platform_buoyancy_position 468.965546 cc
2023-04-08T00:00:18.160Z,1680912018.160 [Reporter](INFO): platform_buoyancy_position 464.533661 cc
2023-04-08T00:00:18.560Z,1680912018.560 [Reporter](INFO): platform_buoyancy_position 459.967438 cc
2023-04-08T00:00:18.958Z,1680912018.958 [Reporter](INFO): platform_buoyancy_position 453.655365 cc
2023-04-08T00:00:19.380Z,1680912019.380 [Reporter](INFO): platform_buoyancy_position 449.492035 cc
2023-04-08T00:00:19.752Z,1680912019.752 [Reporter](INFO): platform_buoyancy_position 443.179962 cc
2023-04-08T00:00:20.161Z,1680912020.161 [Reporter](INFO): platform_buoyancy_position 438.345123 cc
2023-04-08T00:00:20.564Z,1680912020.564 [Reporter](INFO): platform_buoyancy_position 432.167328 cc
2023-04-08T00:00:20.964Z,1680912020.964 [Reporter](INFO): platform_buoyancy_position 427.735443 cc
2023-04-08T00:00:21.400Z,1680912021.400 [Reporter](INFO): platform_buoyancy_position 421.557648 cc
2023-04-08T00:00:21.785Z,1680912021.785 [Reporter](INFO): platform_buoyancy_position 417.260040 cc
2023-04-08T00:00:22.208Z,1680912022.208 [Reporter](INFO): platform_buoyancy_position 410.813629 cc
2023-04-08T00:00:22.596Z,1680912022.596 [Reporter](INFO): platform_buoyancy_position 406.516052 cc
2023-04-08T00:00:22.984Z,1680912022.984 [Reporter](INFO): platform_buoyancy_position 401.278351 cc
2023-04-08T00:00:23.394Z,1680912023.394 [Reporter](INFO): platform_buoyancy_position 393.623230 cc
2023-04-08T00:00:23.792Z,1680912023.792 [Reporter](INFO): platform_buoyancy_position 389.191345 cc
2023-04-08T00:00:24.216Z,1680912024.216 [Reporter](INFO): platform_buoyancy_position 385.028046 cc
2023-04-08T00:00:24.620Z,1680912024.620 [Reporter](INFO): platform_buoyancy_position 378.581635 cc
2023-04-08T00:00:25.044Z,1680912025.044 [Reporter](INFO): platform_buoyancy_position 374.149750 cc
2023-04-08T00:00:25.426Z,1680912025.426 [Reporter](INFO): platform_buoyancy_position 369.583527 cc
2023-04-08T00:00:25.804Z,1680912025.804 [Reporter](INFO): platform_buoyancy_position 363.405731 cc
2023-04-08T00:00:26.220Z,1680912026.220 [Reporter](INFO): platform_buoyancy_position 357.899445 cc
2023-04-08T00:00:26.629Z,1680912026.629 [Reporter](INFO): platform_buoyancy_position 352.796051 cc
2023-04-08T00:00:27.080Z,1680912027.080 [Reporter](INFO): platform_buoyancy_position 346.886841 cc
2023-04-08T00:00:27.453Z,1680912027.453 [Reporter](INFO): platform_buoyancy_position 342.052032 cc
2023-04-08T00:00:27.864Z,1680912027.864 [Reporter](INFO): platform_buoyancy_position 336.545746 cc
2023-04-08T00:00:28.298Z,1680912028.298 [Reporter](INFO): platform_buoyancy_position 331.173737 cc
2023-04-08T00:00:28.656Z,1680912028.656 [Reporter](INFO): platform_buoyancy_position 326.876129 cc
2023-04-08T00:00:29.076Z,1680912029.076 [Reporter](INFO): platform_buoyancy_position 320.698334 cc
2023-04-08T00:00:29.468Z,1680912029.468 [Reporter](INFO): platform_buoyancy_position 316.266449 cc
2023-04-08T00:00:29.903Z,1680912029.903 [Reporter](INFO): platform_buoyancy_position 309.820038 cc
2023-04-08T00:00:30.311Z,1680912030.311 [Reporter](INFO): platform_buoyancy_position 305.522430 cc
2023-04-08T00:00:30.682Z,1680912030.682 [Reporter](INFO): platform_buoyancy_position 299.613220 cc
2023-04-08T00:00:31.106Z,1680912031.106 [Reporter](INFO): platform_buoyancy_position 294.778442 cc
2023-04-08T00:00:31.472Z,1680912031.472 [Reporter](INFO): platform_buoyancy_position 290.346527 cc
2023-04-08T00:00:31.838Z,1680912031.838 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2023-04-08T00:00:31.838Z,1680912031.838 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-08T00:00:31.887Z,1680912031.887 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-08T00:00:31.888Z,1680912031.888 [Reporter](INFO): platform_buoyancy_position 283.900146 cc
2023-04-08T00:00:32.287Z,1680912032.287 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-08T00:00:32.287Z,1680912032.287 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2023-04-08T00:00:32.289Z,1680912032.289 [Reporter](INFO): platform_buoyancy_position 279.602539 cc
2023-04-08T00:00:32.688Z,1680912032.688 [Reporter](INFO): platform_buoyancy_position 273.424744 cc
2023-04-08T00:00:33.111Z,1680912033.111 [Reporter](INFO): platform_buoyancy_position 268.858521 cc
2023-04-08T00:00:33.496Z,1680912033.496 [Reporter](INFO): platform_buoyancy_position 262.546417 cc
2023-04-08T00:00:33.932Z,1680912033.932 [Reporter](INFO): platform_buoyancy_position 257.040131 cc
2023-04-08T00:00:34.330Z,1680912034.330 [Reporter](INFO): platform_buoyancy_position 251.668121 cc
2023-04-08T00:00:34.713Z,1680912034.713 [Reporter](INFO): platform_buoyancy_position 247.504822 cc
2023-04-08T00:00:35.132Z,1680912035.132 [Reporter](INFO): platform_buoyancy_position 241.192719 cc
2023-04-08T00:00:35.526Z,1680912035.526 [Reporter](INFO): platform_buoyancy_position 236.760834 cc
2023-04-08T00:00:35.933Z,1680912035.933 [Reporter](INFO): platform_buoyancy_position 231.254517 cc
2023-04-08T00:00:36.428Z,1680912036.428 [Reporter](INFO): platform_buoyancy_position 224.002319 cc
2023-04-08T00:00:36.728Z,1680912036.728 [Reporter](INFO): platform_buoyancy_position 219.704742 cc
2023-04-08T00:00:37.196Z,1680912037.196 [Reporter](INFO): platform_buoyancy_position 215.004242 cc
2023-04-08T00:00:37.560Z,1680912037.560 [Reporter](INFO): platform_buoyancy_position 210.706635 cc
2023-04-08T00:00:38.004Z,1680912038.004 [Reporter](INFO): platform_buoyancy_position 206.543335 cc
2023-04-08T00:00:45.734Z,1680912045.734 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter
2023-04-08T00:00:48.064Z,1680912048.064 [Reporter](INFO): platform_buoyancy_position 202.245728 cc
2023-04-08T00:00:48.450Z,1680912048.450 [Reporter](INFO): platform_buoyancy_position 202.380035 cc
2023-04-08T00:00:48.861Z,1680912048.861 [Reporter](INFO): platform_buoyancy_position 200.096924 cc
2023-04-08T00:00:49.264Z,1680912049.264 [Reporter](INFO): platform_buoyancy_position 195.933624 cc
2023-04-08T00:00:49.649Z,1680912049.649 [Reporter](INFO): platform_buoyancy_position 191.636017 cc
2023-04-08T00:00:50.068Z,1680912050.068 [Reporter](INFO): platform_buoyancy_position 187.204132 cc
2023-04-08T00:00:50.471Z,1680912050.471 [Reporter](INFO): platform_buoyancy_position 182.100739 cc
2023-04-08T00:00:50.882Z,1680912050.882 [Reporter](INFO): platform_buoyancy_position 176.191528 cc
2023-04-08T00:00:51.288Z,1680912051.288 [Reporter](INFO): platform_buoyancy_position 170.148010 cc
2023-04-08T00:00:51.685Z,1680912051.685 [Reporter](INFO): platform_buoyancy_position 165.716125 cc
2023-04-08T00:00:52.093Z,1680912052.093 [Reporter](INFO): platform_buoyancy_position 159.404022 cc
2023-04-08T00:00:52.472Z,1680912052.472 [Reporter](INFO): platform_buoyancy_position 154.972107 cc
2023-04-08T00:00:52.956Z,1680912052.956 [Reporter](INFO): platform_buoyancy_position 148.928619 cc
2023-04-08T00:00:53.290Z,1680912053.290 [Reporter](INFO): platform_buoyancy_position 144.496704 cc
2023-04-08T00:00:53.688Z,1680912053.688 [Reporter](INFO): platform_buoyancy_position 140.064819 cc
2023-04-08T00:00:54.113Z,1680912054.113 [Reporter](INFO): platform_buoyancy_position 133.484100 cc
2023-04-08T00:00:54.508Z,1680912054.508 [Reporter](INFO): platform_buoyancy_position 129.186523 cc
2023-04-08T00:00:54.936Z,1680912054.936 [Reporter](INFO): platform_buoyancy_position 122.874405 cc
2023-04-08T00:00:55.322Z,1680912055.322 [Reporter](INFO): platform_buoyancy_position 118.442513 cc
2023-04-08T00:00:55.701Z,1680912055.701 [Reporter](INFO): platform_buoyancy_position 113.876305 cc
2023-04-08T00:00:56.136Z,1680912056.136 [Reporter](INFO): platform_buoyancy_position 107.832817 cc
2023-04-08T00:01:51.008Z,1680912111.008 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-08T00:01:51.008Z,1680912111.008 [Default:CheckIn:C.Wait] Stopped
2023-04-08T00:01:51.008Z,1680912111.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-08T00:01:51.009Z,1680912111.009 [Default:CheckIn:D] Running Loop=1
2023-04-08T00:01:51.484Z,1680912111.484 [Default:CheckIn:D] Stopped
2023-04-08T00:01:51.484Z,1680912111.484 [Default:CheckIn:E] Running Loop=1
2023-04-08T00:01:51.829Z,1680912111.829 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.282190 min 
2023-04-08T00:01:51.829Z,1680912111.829 [Default:CheckIn:E] Stopped
2023-04-08T00:01:51.829Z,1680912111.829 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-08T00:01:51.829Z,1680912111.829 [Default:CheckIn] Stopped
2023-04-08T00:01:51.829Z,1680912111.829 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-08T00:01:51.830Z,1680912111.830 [Default:CheckIn](INFO): Running loop #6
2023-04-08T00:01:51.830Z,1680912111.830 [Default:CheckIn] Running Loop=6
2023-04-08T00:01:51.830Z,1680912111.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-08T00:01:51.830Z,1680912111.830 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-08T00:03:32.835Z,1680912212.835 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2023-04-08T00:03:32.835Z,1680912212.835 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-04-08T00:03:32.872Z,1680912212.872 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-04-08T00:03:33.331Z,1680912213.331 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-04-08T00:03:33.331Z,1680912213.331 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2023-04-08T00:03:36.847Z,1680912216.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000333.00,A,3648.16339,N,12147.28225,W,0.447,175.41,080423,,,A*7A
2023-04-08T00:03:36.851Z,1680912216.851 [NAL9602](INFO): GPS fix at 20230408T000333: (36.802723, -121.788038)
2023-04-08T00:03:36.871Z,1680912216.871 [Default:CheckIn:Read_GPS] Stopped
2023-04-08T00:03:36.871Z,1680912216.871 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-08T00:03:49.210Z,1680912229.210 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20230407T230302/Courier0019.lzma
2023-04-08T00:03:50.212Z,1680912230.212 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0019.lzma.bak
2023-04-08T00:03:50.212Z,1680912230.212 [DataOverHttps](INFO): SBD MOMSN=18171662
2023-04-08T00:04:07.434Z,1680912247.434 [DataOverHttps](INFO): Sending 547 bytes from file Logs/20230407T230302/Express0020.lzma
2023-04-08T00:04:08.436Z,1680912248.436 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0020.lzma.bak
2023-04-08T00:04:08.436Z,1680912248.436 [DataOverHttps](INFO): SBD MOMSN=18171666
2023-04-08T00:04:09.319Z,1680912249.319 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-08T00:04:10.178Z,1680912250.178 [Default:CheckIn:Read_Iridium] Stopped
2023-04-08T00:04:10.178Z,1680912250.178 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-08T00:04:10.178Z,1680912250.178 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-08T00:04:21.843Z,1680912261.843 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-08T00:04:21.843Z,1680912261.843 [DVL_micro](ERROR): Failed to parse:
:BI,+00000,00000,+00000,I
2023-04-08T00:05:50.381Z,1680912350.381 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:06:01.261Z,1680912361.261 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.029733
CHAN A1 (24V): 0.036859
CHAN A2 (12V): -0.019163
CHAN A3 (5V): -0.028700
CHAN B0 (3.3V): -0.013867
CHAN B1 (3.15aV): -0.009018
CHAN B2 (3.15bV): -0.004156
CHAN B3 (GND): -0.017637
OPEN: 0.007118
Full Scale: +/- 1 mA
2023-04-08T00:09:10.716Z,1680912550.716 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-08T00:09:10.716Z,1680912550.716 [Default:CheckIn:C.Wait] Stopped
2023-04-08T00:09:10.716Z,1680912550.716 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-08T00:09:10.716Z,1680912550.716 [Default:CheckIn:D] Running Loop=1
2023-04-08T00:09:11.120Z,1680912551.120 [Default:CheckIn:D] Stopped
2023-04-08T00:09:11.120Z,1680912551.120 [Default:CheckIn:E] Running Loop=1
2023-04-08T00:09:11.525Z,1680912551.525 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.609456 min 
2023-04-08T00:09:11.525Z,1680912551.525 [Default:CheckIn:E] Stopped
2023-04-08T00:09:11.525Z,1680912551.525 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-08T00:09:11.525Z,1680912551.525 [Default:CheckIn] Stopped
2023-04-08T00:09:11.526Z,1680912551.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-08T00:09:11.526Z,1680912551.526 [Default:CheckIn](INFO): Running loop #7
2023-04-08T00:09:11.526Z,1680912551.526 [Default:CheckIn] Running Loop=7
2023-04-08T00:09:11.526Z,1680912551.526 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-08T00:09:11.526Z,1680912551.526 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-08T00:09:13.543Z,1680912553.543 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000912.00,A,3648.16545,N,12147.28401,W,0.175,175.41,080423,,,A*7A
2023-04-08T00:09:13.545Z,1680912553.545 [NAL9602](INFO): GPS fix at 20230408T000912: (36.802757, -121.788067)
2023-04-08T00:09:13.555Z,1680912553.555 [Default:CheckIn:Read_GPS] Stopped
2023-04-08T00:09:13.555Z,1680912553.555 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-08T00:09:21.205Z,1680912561.205 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230407T230302/Courier0022.lzma
2023-04-08T00:09:22.208Z,1680912562.208 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0022.lzma.bak
2023-04-08T00:09:22.208Z,1680912562.208 [DataOverHttps](INFO): SBD MOMSN=18171681
2023-04-08T00:09:41.175Z,1680912581.175 [DataOverHttps](INFO): Sending 344 bytes from file Logs/20230407T230302/Express0023.lzma
2023-04-08T00:09:42.176Z,1680912582.176 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0023.lzma.bak
2023-04-08T00:09:42.176Z,1680912582.176 [DataOverHttps](INFO): SBD MOMSN=18171684
2023-04-08T00:09:43.898Z,1680912583.898 [Default:CheckIn:Read_Iridium] Stopped
2023-04-08T00:09:43.898Z,1680912583.898 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-08T00:09:43.899Z,1680912583.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-08T00:11:24.477Z,1680912684.477 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-08T00:11:24.477Z,1680912684.477 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000018.8,0000.0,00
2023-04-08T00:14:16.180Z,1680912856.180 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-08T00:14:43.019Z,1680912883.019 [CommandExec](IMPORTANT): got command maintain clear
2023-04-08T00:14:43.287Z,1680912883.287 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,VerticalHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-04-08T00:14:44.485Z,1680912884.485 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-08T00:14:44.485Z,1680912884.485 [Default:CheckIn:C.Wait] Stopped
2023-04-08T00:14:44.485Z,1680912884.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-08T00:14:44.485Z,1680912884.485 [Default:CheckIn:D] Running Loop=1
2023-04-08T00:14:44.887Z,1680912884.887 [Default:CheckIn:D] Stopped
2023-04-08T00:14:44.887Z,1680912884.887 [Default:CheckIn:E] Running Loop=1
2023-04-08T00:14:44.899Z,1680912884.899 [CommandExec](IMPORTANT): got command report clear
2023-04-08T00:14:45.281Z,1680912885.281 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.172245 min 
2023-04-08T00:14:45.281Z,1680912885.281 [Default:CheckIn:E] Stopped
2023-04-08T00:14:45.281Z,1680912885.281 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-08T00:14:45.281Z,1680912885.281 [Default:CheckIn] Stopped
2023-04-08T00:14:45.281Z,1680912885.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-08T00:14:45.281Z,1680912885.281 [Default:CheckIn](INFO): Running loop #8
2023-04-08T00:14:45.282Z,1680912885.282 [Default:CheckIn] Running Loop=8
2023-04-08T00:14:45.282Z,1680912885.282 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-08T00:14:45.282Z,1680912885.282 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-08T00:14:46.983Z,1680912886.983 [CommandExec](IMPORTANT): got command burn off
2023-04-08T00:14:46.983Z,1680912886.983 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-04-08T00:14:47.288Z,1680912887.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001446.00,A,3648.16618,N,12147.28592,W,0.156,175.41,080423,,,A*76
2023-04-08T00:14:47.291Z,1680912887.291 [NAL9602](INFO): GPS fix at 20230408T001446: (36.802770, -121.788099)
2023-04-08T00:14:47.301Z,1680912887.301 [Default:CheckIn:Read_GPS] Stopped
2023-04-08T00:14:47.301Z,1680912887.301 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-08T00:14:50.623Z,1680912890.623 [CommandExec](IMPORTANT): got command ibit
2023-04-08T00:14:51.008Z,1680912891.008 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-04-08T00:14:51.008Z,1680912891.008 [IBIT](IMPORTANT): Beginning control surface checks.
2023-04-08T00:14:51.040Z,1680912891.040 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:14:52.664Z,1680912892.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001451.00,A,3648.16591,N,12147.28589,W,0.156,175.41,080423,,,A*78
2023-04-08T00:14:52.671Z,1680912892.671 [NAL9602](INFO): GPS fix at 20230408T001451: (36.802765, -121.788098)
2023-04-08T00:14:54.580Z,1680912894.580 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-08T00:14:54.580Z,1680912894.580 [DVL_micro](ERROR): Failed to parse:
:BI,+01018,+01254,-0000,I
2023-04-08T00:14:55.772Z,1680912895.772 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001455.00,A,3648.16555,N,12147.28589,W,0.058,175.41,080423,,,A*7B
2023-04-08T00:14:55.774Z,1680912895.774 [NAL9602](INFO): GPS fix at 20230408T001455: (36.802759, -121.788098)
2023-04-08T00:14:55.811Z,1680912895.811 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230407T230302/Courier0025.lzma
2023-04-08T00:14:56.811Z,1680912896.811 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0025.lzma.bak
2023-04-08T00:14:56.812Z,1680912896.812 [DataOverHttps](INFO): SBD MOMSN=18171701
2023-04-08T00:14:59.020Z,1680912899.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001458.00,A,3648.16531,N,12147.28575,W,0.078,175.41,080423,,,A*75
2023-04-08T00:14:59.023Z,1680912899.023 [NAL9602](INFO): GPS fix at 20230408T001458: (36.802755, -121.788096)
2023-04-08T00:15:01.845Z,1680912901.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001501.00,A,3648.16521,N,12147.28557,W,0.175,175.41,080423,,,A*75
2023-04-08T00:15:01.848Z,1680912901.848 [NAL9602](INFO): GPS fix at 20230408T001501: (36.802754, -121.788093)
2023-04-08T00:15:01.947Z,1680912901.947 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.033454
CHAN A1 (24V): 0.046616
CHAN A2 (12V): -0.020925
CHAN A3 (5V): -0.024866
CHAN B0 (3.3V): -0.018519
CHAN B1 (3.15aV): -0.009451
CHAN B2 (3.15bV): -0.005331
CHAN B3 (GND): -0.017384
OPEN: 0.006312
Full Scale: +/- 1 mA
2023-04-08T00:15:04.680Z,1680912904.680 [DVL_micro](ERROR): Failed to parse:
:WI,+01011,+0113,+00000,A
2023-04-08T00:15:05.076Z,1680912905.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001504.00,A,3648.16504,N,12147.28554,W,0.156,175.41,080423,,,A*75
2023-04-08T00:15:05.079Z,1680912905.079 [NAL9602](INFO): GPS fix at 20230408T001504: (36.802751, -121.788092)
2023-04-08T00:15:07.911Z,1680912907.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001507.00,A,3648.16496,N,12147.28574,W,0.253,175.41,080423,,,A*78
2023-04-08T00:15:07.913Z,1680912907.913 [NAL9602](INFO): GPS fix at 20230408T001507: (36.802749, -121.788096)
2023-04-08T00:15:10.733Z,1680912910.733 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001510.00,A,3648.16488,N,12147.28575,W,0.078,175.41,080423,,,A*7B
2023-04-08T00:15:10.736Z,1680912910.736 [NAL9602](INFO): GPS fix at 20230408T001510: (36.802748, -121.788096)
2023-04-08T00:15:13.694Z,1680912913.694 [DataOverHttps](INFO): Sending 283 bytes from file Logs/20230407T230302/Express0026.lzma
2023-04-08T00:15:13.969Z,1680912913.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001513.00,A,3648.16481,N,12147.28580,W,0.097,175.41,080423,,,A*7A
2023-04-08T00:15:13.971Z,1680912913.971 [NAL9602](INFO): GPS fix at 20230408T001513: (36.802747, -121.788097)
2023-04-08T00:15:14.696Z,1680912914.696 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0026.lzma.bak
2023-04-08T00:15:14.696Z,1680912914.696 [DataOverHttps](INFO): SBD MOMSN=18171704
2023-04-08T00:15:16.817Z,1680912916.817 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001516.00,A,3648.16464,N,12147.28578,W,0.117,175.41,080423,,,A*7A
2023-04-08T00:15:16.824Z,1680912916.824 [NAL9602](INFO): GPS fix at 20230408T001516: (36.802744, -121.788096)
2023-04-08T00:15:20.049Z,1680912920.049 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2023-04-08T00:15:20.050Z,1680912920.050 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.0,009.0,000
2023-04-08T00:15:20.052Z,1680912920.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001519.00,A,3648.16447,N,12147.28567,W,0.136,175.41,080423,,,A*79
2023-04-08T00:15:20.059Z,1680912920.059 [NAL9602](INFO): GPS fix at 20230408T001519: (36.802741, -121.788094)
2023-04-08T00:15:20.507Z,1680912920.507 [CommandExec](IMPORTANT): got command gfscan
2023-04-08T00:15:20.528Z,1680912920.528 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:15:22.856Z,1680912922.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001522.00,A,3648.16429,N,12147.28563,W,0.156,175.41,080423,,,A*7B
2023-04-08T00:15:22.858Z,1680912922.858 [NAL9602](INFO): GPS fix at 20230408T001522: (36.802738, -121.788094)
2023-04-08T00:15:26.094Z,1680912926.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001525.00,A,3648.16424,N,12147.28552,W,0.058,175.41,080423,,,A*7C
2023-04-08T00:15:26.096Z,1680912926.096 [NAL9602](INFO): GPS fix at 20230408T001525: (36.802737, -121.788092)
2023-04-08T00:15:28.913Z,1680912928.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001528.00,A,3648.16413,N,12147.28515,W,0.117,175.41,080423,,,A*7C
2023-04-08T00:15:28.915Z,1680912928.915 [NAL9602](INFO): GPS fix at 20230408T001528: (36.802735, -121.788086)
2023-04-08T00:15:31.741Z,1680912931.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001531.00,A,3648.16405,N,12147.28500,W,0.175,175.41,080423,,,A*73
2023-04-08T00:15:31.744Z,1680912931.744 [NAL9602](INFO): GPS fix at 20230408T001531: (36.802734, -121.788083)
2023-04-08T00:15:31.816Z,1680912931.816 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.034607
CHAN A1 (24V): 0.043444
CHAN A2 (12V): -0.019312
CHAN A3 (5V): -0.028412
CHAN B0 (3.3V): -0.014917
CHAN B1 (3.15aV): -0.008529
CHAN B2 (3.15bV): -0.006008
CHAN B3 (GND): -0.013832
OPEN: 0.006224
Full Scale: +/- 1 mA
2023-04-08T00:15:35.063Z,1680912935.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001534.00,A,3648.16390,N,12147.28525,W,0.136,175.41,080423,,,A*7D
2023-04-08T00:15:35.065Z,1680912935.065 [NAL9602](INFO): GPS fix at 20230408T001534: (36.802732, -121.788088)
2023-04-08T00:15:37.881Z,1680912937.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001537.00,A,3648.16370,N,12147.28469,W,0.505,175.41,080423,,,A*7D
2023-04-08T00:15:37.896Z,1680912937.896 [NAL9602](INFO): GPS fix at 20230408T001537: (36.802728, -121.788078)
2023-04-08T00:15:38.909Z,1680912938.909 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadB3
2023-04-08T00:15:40.107Z,1680912940.107 [CommandExec](IMPORTANT): got command gfscan
2023-04-08T00:15:40.516Z,1680912940.516 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:15:40.749Z,1680912940.749 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001540.00,A,3648.16355,N,12147.28465,W,0.156,175.41,080423,,,A*74
2023-04-08T00:15:40.756Z,1680912940.756 [NAL9602](INFO): GPS fix at 20230408T001540: (36.802726, -121.788077)
2023-04-08T00:15:44.006Z,1680912944.006 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001543.00,A,3648.16324,N,12147.28479,W,0.175,175.41,080423,,,A*7D
2023-04-08T00:15:44.008Z,1680912944.008 [NAL9602](INFO): GPS fix at 20230408T001543: (36.802721, -121.788080)
2023-04-08T00:15:46.861Z,1680912946.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001546.00,A,3648.16304,N,12147.28472,W,0.058,175.41,080423,,,A*7F
2023-04-08T00:15:46.868Z,1680912946.868 [NAL9602](INFO): GPS fix at 20230408T001546: (36.802717, -121.788079)
2023-04-08T00:15:50.089Z,1680912950.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001549.00,A,3648.16268,N,12147.28404,W,0.408,175.41,080423,,,A*7B
2023-04-08T00:15:50.095Z,1680912950.095 [NAL9602](INFO): GPS fix at 20230408T001549: (36.802711, -121.788067)
2023-04-08T00:15:51.367Z,1680912951.367 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.034256
CHAN A1 (24V): 0.038341
CHAN A2 (12V): -0.022172
CHAN A3 (5V): -0.024378
CHAN B0 (3.3V): -0.014451
CHAN B1 (3.15aV): -0.008673
CHAN B2 (3.15bV): -0.005605
CHAN B3 (GND): -0.017193
OPEN: 0.005600
Full Scale: +/- 1 mA
2023-04-08T00:15:52.959Z,1680912952.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001552.00,A,3648.16250,N,12147.28437,W,0.156,175.41,080423,,,A*74
2023-04-08T00:15:52.991Z,1680912952.991 [NAL9602](INFO): GPS fix at 20230408T001552: (36.802708, -121.788073)
2023-04-08T00:15:55.729Z,1680912955.729 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001555.00,A,3648.16223,N,12147.28440,W,0.136,175.41,080423,,,A*71
2023-04-08T00:15:55.731Z,1680912955.731 [NAL9602](INFO): GPS fix at 20230408T001555: (36.802704, -121.788073)
2023-04-08T00:15:58.974Z,1680912958.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001558.00,A,3648.16198,N,12147.28368,W,0.272,175.41,080423,,,A*71
2023-04-08T00:15:58.976Z,1680912958.976 [NAL9602](INFO): GPS fix at 20230408T001558: (36.802700, -121.788061)
2023-04-08T00:16:01.323Z,1680912961.323 [CommandExec](IMPORTANT): got command get 
2023-04-08T00:16:01.323Z,1680912961.323 [CommandExec](FAULT): Incomplete syntax. Try: help get
2023-04-08T00:16:01.798Z,1680912961.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001601.00,A,3648.16171,N,12147.28337,W,0.369,175.41,080423,,,A*78
2023-04-08T00:16:01.800Z,1680912961.800 [NAL9602](INFO): GPS fix at 20230408T001601: (36.802695, -121.788056)
2023-04-08T00:16:05.028Z,1680912965.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001604.00,A,3648.16147,N,12147.28280,W,0.369,175.41,080423,,,A*75
2023-04-08T00:16:05.030Z,1680912965.030 [NAL9602](INFO): GPS fix at 20230408T001604: (36.802691, -121.788047)
2023-04-08T00:16:06.667Z,1680912966.667 [CommandExec](IMPORTANT): got command get DVL_micro.loadControl
2023-04-08T00:16:06.667Z,1680912966.667 [CommandExec](IMPORTANT): DVL_micro.loadControl /dev/loadB4
2023-04-08T00:16:07.892Z,1680912967.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001607.00,A,3648.16152,N,12147.28367,W,0.350,175.41,080423,,,A*70
2023-04-08T00:16:07.898Z,1680912967.898 [NAL9602](INFO): GPS fix at 20230408T001607: (36.802692, -121.788061)
2023-04-08T00:16:10.708Z,1680912970.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001610.00,A,3648.16162,N,12147.28389,W,0.350,175.41,080423,,,A*75
2023-04-08T00:16:10.717Z,1680912970.717 [NAL9602](INFO): GPS fix at 20230408T001610: (36.802694, -121.788065)
2023-04-08T00:16:13.938Z,1680912973.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001613.00,A,3648.16139,N,12147.28205,W,0.816,175.41,080423,,,A*74
2023-04-08T00:16:13.940Z,1680912973.940 [NAL9602](INFO): GPS fix at 20230408T001613: (36.802690, -121.788034)
2023-04-08T00:16:15.107Z,1680912975.107 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadB4
2023-04-08T00:16:16.383Z,1680912976.383 [CommandExec](IMPORTANT): got command gfscan
2023-04-08T00:16:16.434Z,1680912976.434 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:16:16.746Z,1680912976.746 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001616.00,A,3648.16139,N,12147.28121,W,0.797,175.41,080423,,,A*72
2023-04-08T00:16:16.748Z,1680912976.748 [NAL9602](INFO): GPS fix at 20230408T001616: (36.802690, -121.788020)
2023-04-08T00:16:19.977Z,1680912979.977 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001619.00,A,3648.16132,N,12147.28086,W,0.350,175.41,080423,,,A*75
2023-04-08T00:16:19.983Z,1680912979.983 [NAL9602](INFO): GPS fix at 20230408T001619: (36.802689, -121.788014)
2023-04-08T00:16:22.879Z,1680912982.879 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001622.00,A,3648.16126,N,12147.27944,W,0.603,175.41,080423,,,A*73
2023-04-08T00:16:22.881Z,1680912982.881 [NAL9602](INFO): GPS fix at 20230408T001622: (36.802688, -121.787991)
2023-04-08T00:16:23.619Z,1680912983.619 [DVL_micro](ERROR): No DVL communication! Re-initializing
2023-04-08T00:16:23.619Z,1680912983.619 [DVL_micro] Communications Fault, FailCount= 1
2023-04-08T00:16:23.619Z,1680912983.619 [DVL_micro](ERROR): Communications Fault
2023-04-08T00:16:23.706Z,1680912983.706 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-04-08T00:16:24.098Z,1680912984.098 [DVL_micro](INFO): Powering down
2023-04-08T00:16:24.865Z,1680912984.865 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-08T00:16:24.865Z,1680912984.865 [DVL_micro] No Fault, FailCount= 1
2023-04-08T00:16:26.034Z,1680912986.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001625.00,A,3648.16121,N,12147.27803,W,0.797,175.41,080423,,,A*7D
2023-04-08T00:16:26.036Z,1680912986.036 [NAL9602](INFO): GPS fix at 20230408T001625: (36.802687, -121.787967)
2023-04-08T00:16:27.349Z,1680912987.349 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006138
CHAN A1 (24V): 0.002955
CHAN A2 (12V): -0.004764
CHAN A3 (5V): -0.002536
CHAN B0 (3.3V): -0.000886
CHAN B1 (3.15aV): -0.001088
CHAN B2 (3.15bV): 0.003307
CHAN B3 (GND): -0.006219
OPEN: 0.001866
Full Scale: +/- 1 mA
2023-04-08T00:16:28.860Z,1680912988.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001628.00,A,3648.16130,N,12147.27729,W,0.564,175.41,080423,,,A*79
2023-04-08T00:16:28.863Z,1680912988.863 [NAL9602](INFO): GPS fix at 20230408T001628: (36.802688, -121.787955)
2023-04-08T00:16:31.685Z,1680912991.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001631.00,A,3648.16131,N,12147.27683,W,0.447,175.41,080423,,,A*71
2023-04-08T00:16:31.687Z,1680912991.687 [NAL9602](INFO): GPS fix at 20230408T001631: (36.802689, -121.787947)
2023-04-08T00:16:32.651Z,1680912992.651 [CommandExec](IMPORTANT): got command gfscan
2023-04-08T00:16:33.042Z,1680912993.042 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:16:34.953Z,1680912994.953 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001634.00,A,3648.16136,N,12147.27665,W,0.156,175.41,080423,,,A*7E
2023-04-08T00:16:34.971Z,1680912994.971 [NAL9602](INFO): GPS fix at 20230408T001634: (36.802689, -121.787944)
2023-04-08T00:16:37.847Z,1680912997.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001637.00,A,3648.16169,N,12147.27675,W,0.078,175.41,080423,,,A*7B
2023-04-08T00:16:37.849Z,1680912997.849 [NAL9602](INFO): GPS fix at 20230408T001637: (36.802695, -121.787946)
2023-04-08T00:16:41.181Z,1680913001.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001640.00,A,3648.16189,N,12147.27690,W,0.272,175.41,080423,,,A*76
2023-04-08T00:16:41.207Z,1680913001.207 [NAL9602](INFO): GPS fix at 20230408T001640: (36.802698, -121.787948)
2023-04-08T00:16:41.979Z,1680913001.979 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2023-04-08T00:16:42.450Z,1680913002.450 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 351.541016
Voltage: 16.413000
2023-04-08T00:16:42.451Z,1680913002.451 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-04-08T00:16:42.451Z,1680913002.451 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-04-08T00:16:43.048Z,1680913003.048 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-04-08T00:16:43.048Z,1680913003.048 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-04-08T00:16:43.048Z,1680913003.048 [IBIT](IMPORTANT): Pressure:9.458688 PSI
2023-04-08T00:16:43.049Z,1680913003.049 [IBIT](IMPORTANT): Humidity:3.851789 %
2023-04-08T00:16:43.346Z,1680913003.346 [IBIT](IMPORTANT): Vehicle Pitch:-2.524384 degrees
2023-04-08T00:16:43.346Z,1680913003.346 [IBIT](IMPORTANT): Vehicle  Roll:3.682642 degrees
2023-04-08T00:16:43.346Z,1680913003.346 [IBIT](IMPORTANT): Vehicle  Heading:20.087603 degrees
2023-04-08T00:16:43.708Z,1680913003.708 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-04-08T00:16:43.709Z,1680913003.709 [IBIT](IMPORTANT): buoyancyNeutral: 155.681671 cc
2023-04-08T00:16:43.709Z,1680913003.709 [IBIT](IMPORTANT): massDefault: -1.000000 cm
2023-04-08T00:16:43.709Z,1680913003.709 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-04-08T00:16:43.722Z,1680913003.722 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2023-04-08T00:16:43.722Z,1680913003.722 [IBIT](IMPORTANT): IBIT FAILED
2023-04-08T00:16:44.007Z,1680913004.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001643.00,A,3648.16203,N,12147.27695,W,0.330,175.41,080423,,,A*76
2023-04-08T00:16:44.015Z,1680913004.015 [NAL9602](INFO): GPS fix at 20230408T001643: (36.802701, -121.787949)
2023-04-08T00:16:44.084Z,1680913004.084 [Default:CheckIn:Read_Iridium] Stopped
2023-04-08T00:16:44.084Z,1680913004.084 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-08T00:16:44.084Z,1680913004.084 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-08T00:16:44.990Z,1680913004.990 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.033779
CHAN A1 (24V): 0.041997
CHAN A2 (12V): -0.020481
CHAN A3 (5V): -0.028545
CHAN B0 (3.3V): -0.011016
CHAN B1 (3.15aV): -0.006897
CHAN B2 (3.15bV): -0.004027
CHAN B3 (GND): -0.016888
OPEN: 0.006400
Full Scale: +/- 1 mA
2023-04-08T00:16:54.126Z,1680913014.126 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2023-04-08T00:17:16.350Z,1680913036.350 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-08T00:19:52.707Z,1680913192.707 [DVL_micro](ERROR): No DVL communication! Re-initializing
2023-04-08T00:19:52.707Z,1680913192.707 [DVL_micro] Communications Fault, FailCount= 1
2023-04-08T00:19:52.707Z,1680913192.707 [DVL_micro](ERROR): Communications Fault
2023-04-08T00:19:52.762Z,1680913192.762 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-04-08T00:19:53.182Z,1680913193.182 [DVL_micro](INFO): Powering down
2023-04-08T00:19:53.963Z,1680913193.963 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-08T00:19:53.963Z,1680913193.963 [DVL_micro] No Fault, FailCount= 1
2023-04-08T00:20:11.751Z,1680913211.751 [DVL_micro](FAULT): Failed to initialize within timeout.
2023-04-08T00:20:11.751Z,1680913211.751 [DVL_micro] Communications Fault, FailCount= 2
2023-04-08T00:20:11.751Z,1680913211.751 [DVL_micro](ERROR): Communications Fault
2023-04-08T00:20:11.770Z,1680913211.770 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-04-08T00:20:12.186Z,1680913212.186 [DVL_micro](INFO): Powering down
2023-04-08T00:20:12.962Z,1680913212.962 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-08T00:20:12.962Z,1680913212.962 [DVL_micro] No Fault, FailCount= 2
2023-04-08T00:20:30.699Z,1680913230.699 [DVL_micro](FAULT): Failed to initialize within timeout.
2023-04-08T00:20:30.699Z,1680913230.699 [DVL_micro] Communications Fault, FailCount= 3
2023-04-08T00:20:30.700Z,1680913230.700 [DVL_micro](ERROR): Communications Fault
2023-04-08T00:20:30.743Z,1680913230.743 [CBIT](ERROR): Communications Fault in component: DVL_micro
2023-04-08T00:20:30.744Z,1680913230.744 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2023-04-08T00:20:31.162Z,1680913231.162 [DVL_micro](INFO): Powering down
2023-04-08T00:20:31.208Z,1680913231.208 [CBIT](INFO): Critical error at 20230408T002030
2023-04-08T00:21:44.636Z,1680913304.636 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-08T00:21:44.636Z,1680913304.636 [Default:CheckIn:C.Wait] Stopped
2023-04-08T00:21:44.636Z,1680913304.636 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-08T00:21:44.636Z,1680913304.636 [Default:CheckIn:D] Running Loop=1
2023-04-08T00:21:45.036Z,1680913305.036 [Default:CheckIn:D] Stopped
2023-04-08T00:21:45.036Z,1680913305.036 [Default:CheckIn:E] Running Loop=1
2023-04-08T00:21:45.445Z,1680913305.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.174731 min 
2023-04-08T00:21:45.445Z,1680913305.445 [Default:CheckIn:E] Stopped
2023-04-08T00:21:45.445Z,1680913305.445 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-08T00:21:45.445Z,1680913305.445 [Default:CheckIn] Stopped
2023-04-08T00:21:45.445Z,1680913305.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-08T00:21:45.446Z,1680913305.446 [Default:CheckIn](INFO): Running loop #9
2023-04-08T00:21:45.446Z,1680913305.446 [Default:CheckIn] Running Loop=9
2023-04-08T00:21:45.446Z,1680913305.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-08T00:21:45.446Z,1680913305.446 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-08T00:21:47.452Z,1680913307.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002146.00,A,3648.16178,N,12147.28164,W,0.233,116.66,080423,,,A*7C
2023-04-08T00:21:47.454Z,1680913307.454 [NAL9602](INFO): GPS fix at 20230408T002146: (36.802696, -121.788027)
2023-04-08T00:21:47.492Z,1680913307.492 [Default:CheckIn:Read_GPS] Stopped
2023-04-08T00:21:47.492Z,1680913307.492 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-08T00:21:55.181Z,1680913315.181 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20230407T230302/Courier0028.lzma
2023-04-08T00:21:56.184Z,1680913316.184 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0028.lzma.bak
2023-04-08T00:21:56.184Z,1680913316.184 [DataOverHttps](INFO): SBD MOMSN=18171712
2023-04-08T00:22:11.979Z,1680913331.979 [DataOverHttps](INFO): Sending 1120 bytes from file Logs/20230407T230302/Express0029.lzma
2023-04-08T00:22:12.980Z,1680913332.980 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0029.lzma.bak
2023-04-08T00:22:12.980Z,1680913332.980 [DataOverHttps](INFO): SBD MOMSN=18171716
2023-04-08T00:22:14.528Z,1680913334.528 [Default:CheckIn:Read_Iridium] Stopped
2023-04-08T00:22:14.528Z,1680913334.528 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-08T00:22:14.528Z,1680913334.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-08T00:22:18.174Z,1680913338.174 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-08T00:22:18.254Z,1680913338.254 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-04-08T00:23:30.940Z,1680913410.940 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-08T00:23:30.940Z,1680913410.940 [DVL_micro] No Fault, FailCount= 3
2023-04-08T00:23:38.031Z,1680913418.031 [CommandExec](IMPORTANT): got command gfscan
2023-04-08T00:23:38.181Z,1680913418.181 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:23:46.238Z,1680913426.238 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2023-04-08T00:23:46.238Z,1680913426.238 [DVL_micro] Data Fault, FailCount= 1
2023-04-08T00:23:46.238Z,1680913426.238 [DVL_micro](ERROR): Data Fault
2023-04-08T00:23:46.303Z,1680913426.303 [CBIT](ERROR): Data Fault in component: DVL_micro
2023-04-08T00:23:46.710Z,1680913426.710 [DVL_micro](INFO): Powering down
2023-04-08T00:23:47.486Z,1680913427.486 [CBIT](INFO): Clearing failed state for component DVL_micro
2023-04-08T00:23:47.486Z,1680913427.486 [DVL_micro] No Fault, FailCount= 1
2023-04-08T00:23:49.128Z,1680913429.128 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006183
CHAN A1 (24V): 0.003179
CHAN A2 (12V): -0.004729
CHAN A3 (5V): -0.002304
CHAN B0 (3.3V): -0.000870
CHAN B1 (3.15aV): -0.001444
CHAN B2 (3.15bV): -0.000819
CHAN B3 (GND): 0.000023
OPEN: 0.001871
Full Scale: +/- 1 mA
2023-04-08T00:23:57.094Z,1680913437.094 [CommandExec](IMPORTANT): got command failComponent
2023-04-08T00:23:57.094Z,1680913437.095 [CommandExec](IMPORTANT): Failed components:
2023-04-08T00:23:57.095Z,1680913437.095 [CommandExec](IMPORTANT): No failed Components.
2023-04-08T00:24:05.638Z,1680913445.638 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro
2023-04-08T00:26:49.263Z,1680913609.263 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-04-08T00:27:15.143Z,1680913635.143 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-08T00:27:15.143Z,1680913635.143 [Default:CheckIn:C.Wait] Stopped
2023-04-08T00:27:15.143Z,1680913635.143 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-08T00:27:15.143Z,1680913635.143 [Default:CheckIn:D] Running Loop=1
2023-04-08T00:27:15.528Z,1680913635.528 [Default:CheckIn:D] Stopped
2023-04-08T00:27:15.528Z,1680913635.528 [Default:CheckIn:E] Running Loop=1
2023-04-08T00:27:15.932Z,1680913635.932 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.682926 min 
2023-04-08T00:27:15.932Z,1680913635.932 [Default:CheckIn:E] Stopped
2023-04-08T00:27:15.932Z,1680913635.932 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-08T00:27:15.932Z,1680913635.932 [Default:CheckIn] Stopped
2023-04-08T00:27:15.933Z,1680913635.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-08T00:27:15.933Z,1680913635.933 [Default:CheckIn](INFO): Running loop #10
2023-04-08T00:27:15.933Z,1680913635.933 [Default:CheckIn] Running Loop=10
2023-04-08T00:27:15.933Z,1680913635.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-08T00:27:15.933Z,1680913635.933 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-08T00:27:17.959Z,1680913637.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002717.00,A,3648.16463,N,12147.28247,W,0.525,166.64,080423,,,D*73
2023-04-08T00:27:17.961Z,1680913637.961 [NAL9602](INFO): GPS fix at 20230408T002717: (36.802744, -121.788041)
2023-04-08T00:27:17.971Z,1680913637.971 [Default:CheckIn:Read_GPS] Stopped
2023-04-08T00:27:17.971Z,1680913637.971 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-08T00:27:22.796Z,1680913642.796 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2023-04-08T00:27:26.061Z,1680913646.061 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230407T230302/Courier0031.lzma
2023-04-08T00:27:27.063Z,1680913647.063 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0031.lzma.bak
2023-04-08T00:27:27.064Z,1680913647.064 [DataOverHttps](INFO): SBD MOMSN=18171770
2023-04-08T00:27:47.274Z,1680913667.274 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20230407T230302/Express0032.lzma
2023-04-08T00:27:48.276Z,1680913668.276 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0032.lzma.bak
2023-04-08T00:27:48.276Z,1680913668.276 [DataOverHttps](INFO): SBD MOMSN=18171773
2023-04-08T00:27:49.620Z,1680913669.620 [Default:CheckIn:Read_Iridium] Stopped
2023-04-08T00:27:49.620Z,1680913669.620 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-08T00:27:49.621Z,1680913669.621 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-08T00:27:50.413Z,1680913670.413 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-08T00:27:58.544Z,1680913678.544 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-04-08T00:31:18.750Z,1680913878.750 [CommandExec](IMPORTANT): got command ibit
2023-04-08T00:31:18.919Z,1680913878.919 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-04-08T00:31:18.919Z,1680913878.919 [IBIT](IMPORTANT): Beginning control surface checks.
2023-04-08T00:31:18.922Z,1680913878.922 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:31:20.492Z,1680913880.492 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003119.00,A,3648.14893,N,12147.25533,W,2.216,200.78,080423,,,D*79
2023-04-08T00:31:20.494Z,1680913880.494 [NAL9602](INFO): GPS fix at 20230408T003119: (36.802482, -121.787589)
2023-04-08T00:31:20.898Z,1680913880.898 [DVL_micro](ERROR): only read 3 of 4 data items
2023-04-08T00:31:20.898Z,1680913880.898 [DVL_micro](ERROR): Failed to parse:
:BI,+01054,+0071,+00000,I
2023-04-08T00:31:29.823Z,1680913889.823 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.034991
CHAN A1 (24V): 0.038509
CHAN A2 (12V): -0.020378
CHAN A3 (5V): -0.025039
CHAN B0 (3.3V): -0.015495
CHAN B1 (3.15aV): -0.009230
CHAN B2 (3.15bV): -0.005976
CHAN B3 (GND): -0.022363
OPEN: 0.006482
Full Scale: +/- 1 mA
2023-04-08T00:31:51.204Z,1680913911.204 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2023-04-08T00:31:51.286Z,1680913911.286 [NAL9602](ERROR): received: 
+CSQ:0

OK
2023-04-08T00:32:05.347Z,1680913925.347 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802483 Longitude: -121.787590
2023-04-08T00:32:05.751Z,1680913925.751 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 351.541016
Voltage: 16.452999
2023-04-08T00:32:05.752Z,1680913925.752 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-04-08T00:32:05.752Z,1680913925.752 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-04-08T00:32:06.187Z,1680913926.187 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-04-08T00:32:06.187Z,1680913926.187 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-04-08T00:32:06.188Z,1680913926.188 [IBIT](IMPORTANT): Pressure:9.437591 PSI
2023-04-08T00:32:06.188Z,1680913926.188 [IBIT](IMPORTANT): Humidity:3.900623 %
2023-04-08T00:32:06.589Z,1680913926.589 [IBIT](IMPORTANT): Vehicle Pitch:-2.302573 degrees
2023-04-08T00:32:06.589Z,1680913926.589 [IBIT](IMPORTANT): Vehicle  Roll:3.417482 degrees
2023-04-08T00:32:06.589Z,1680913926.589 [IBIT](IMPORTANT): Vehicle  Heading:198.479980 degrees
2023-04-08T00:32:06.971Z,1680913926.971 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-04-08T00:32:06.971Z,1680913926.971 [IBIT](IMPORTANT): buoyancyNeutral: 155.681671 cc
2023-04-08T00:32:06.972Z,1680913926.972 [IBIT](IMPORTANT): massDefault: -1.000000 cm
2023-04-08T00:32:06.972Z,1680913926.972 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-04-08T00:32:06.972Z,1680913926.972 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2023-04-08T00:32:06.972Z,1680913926.972 [IBIT](IMPORTANT): IBIT PASSED
2023-04-08T00:32:14.225Z,1680913934.225 [NAL9602](INFO): SBD MO Status=0, MOMSN=1863, MT Status=0, MTMSN=0
2023-04-08T00:32:14.225Z,1680913934.225 [NAL9602](INFO): No messages in MT queue
2023-04-08T00:32:14.225Z,1680913934.225 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2023-04-08T00:32:44.034Z,1680913964.034 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality
2023-04-08T00:32:44.931Z,1680913964.931 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-08T00:32:44.977Z,1680913964.977 [Reporter](INFO): NAL9602.sigQuality 0 count
2023-04-08T00:32:50.070Z,1680913970.070 [CommandExec](IMPORTANT): got command ibit
2023-04-08T00:32:50.208Z,1680913970.208 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-04-08T00:32:50.208Z,1680913970.208 [Default:CheckIn:C.Wait] Stopped
2023-04-08T00:32:50.208Z,1680913970.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-08T00:32:50.208Z,1680913970.208 [Default:CheckIn:D] Running Loop=1
2023-04-08T00:32:50.215Z,1680913970.215 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-04-08T00:32:50.215Z,1680913970.215 [IBIT](IMPORTANT): Beginning control surface checks.
2023-04-08T00:32:50.219Z,1680913970.219 [CBIT](IMPORTANT): Beginning ground fault scan
2023-04-08T00:32:51.893Z,1680913971.893 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003251.00,A,3648.09050,N,12147.28541,W,2.780,203.83,080423,,,D*78
2023-04-08T00:32:51.908Z,1680913971.908 [NAL9602](INFO): GPS fix at 20230408T003251: (36.801508, -121.788090)
2023-04-08T00:32:56.311Z,1680913976.311 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-04-08T00:33:01.145Z,1680913981.145 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.031313
CHAN A1 (24V): 0.040078
CHAN A2 (12V): -0.018233
CHAN A3 (5V): -0.026029
CHAN B0 (3.3V): -0.015694
CHAN B1 (3.15aV): -0.009342
CHAN B2 (3.15bV): -0.006129
CHAN B3 (GND): -0.017551
OPEN: 0.006341
Full Scale: +/- 1 mA
2023-04-08T00:33:08.383Z,1680913988.383 [NAL9602](INFO): SBD MO Status=2, MOMSN=1864, MT Status=2, MTMSN=0
2023-04-08T00:33:08.383Z,1680913988.383 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-04-08T00:33:13.117Z,1680913993.117 [Reporter](INFO): NAL9602.sigQuality 4 count
2023-04-08T00:33:19.023Z,1680913999.023 [CommandExec](IMPORTANT): got command report clear
2023-04-08T00:33:21.207Z,1680914001.207 [CommandExec](IMPORTANT): got command burn off
2023-04-08T00:33:21.207Z,1680914001.207 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-04-08T00:33:22.564Z,1680914002.564 [CommandExec](IMPORTANT): got command maintain clear
2023-04-08T00:33:23.332Z,1680914003.332 [NAL9602](INFO): SBD MO Status=0, MOMSN=1864, MT Status=0, MTMSN=0
2023-04-08T00:33:23.332Z,1680914003.332 [NAL9602](INFO): No messages in MT queue
2023-04-08T00:33:24.545Z,1680914004.545 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003323.00,A,3648.07114,N,12147.29436,W,1.983,180.79,080423,,,D*70
2023-04-08T00:33:24.547Z,1680914004.547 [NAL9602](INFO): GPS fix at 20230408T003323: (36.801186, -121.788239)
2023-04-08T00:33:26.974Z,1680914006.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003326.00,A,3648.06970,N,12147.29376,W,1.613,145.39,080423,,,D*76
2023-04-08T00:33:26.977Z,1680914006.977 [NAL9602](INFO): GPS fix at 20230408T003326: (36.801162, -121.788229)
2023-04-08T00:33:30.221Z,1680914010.221 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003329.00,A,3648.06914,N,12147.29319,W,0.039,140.98,080423,,,D*73
2023-04-08T00:33:30.227Z,1680914010.227 [NAL9602](INFO): GPS fix at 20230408T003329: (36.801152, -121.788220)
2023-04-08T00:33:33.048Z,1680914013.048 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003332.00,A,3648.06872,N,12147.29258,W,0.991,127.80,080423,,,D*7F
2023-04-08T00:33:33.071Z,1680914013.071 [NAL9602](INFO): GPS fix at 20230408T003332: (36.801145, -121.788210)
2023-04-08T00:33:35.868Z,1680914015.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003335.00,A,3648.06837,N,12147.29129,W,1.244,107.21,080423,,,D*77
2023-04-08T00:33:35.879Z,1680914015.879 [NAL9602](INFO): GPS fix at 20230408T003335: (36.801139, -121.788188)
2023-04-08T00:33:36.313Z,1680914016.313 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.801140 Longitude: -121.788185
2023-04-08T00:33:36.689Z,1680914016.689 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 351.541016
Voltage: 16.452999
2023-04-08T00:33:36.690Z,1680914016.690 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-04-08T00:33:36.690Z,1680914016.690 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-04-08T00:33:37.117Z,1680914017.117 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-04-08T00:33:37.117Z,1680914017.117 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-04-08T00:33:37.118Z,1680914017.118 [IBIT](IMPORTANT): Pressure:9.525481 PSI
2023-04-08T00:33:37.118Z,1680914017.118 [IBIT](IMPORTANT): Humidity:3.851789 %
2023-04-08T00:33:37.549Z,1680914017.549 [IBIT](IMPORTANT): Vehicle Pitch:-3.109707 degrees
2023-04-08T00:33:37.554Z,1680914017.554 [IBIT](IMPORTANT): Vehicle  Roll:3.677566 degrees
2023-04-08T00:33:37.554Z,1680914017.554 [IBIT](IMPORTANT): Vehicle  Heading:105.715630 degrees
2023-04-08T00:33:37.935Z,1680914017.935 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-04-08T00:33:37.936Z,1680914017.936 [IBIT](IMPORTANT): buoyancyNeutral: 155.681671 cc
2023-04-08T00:33:37.936Z,1680914017.936 [IBIT](IMPORTANT): massDefault: -1.000000 cm
2023-04-08T00:33:37.936Z,1680914017.936 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2023-04-08T00:33:37.936Z,1680914017.936 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2023-04-08T00:33:37.937Z,1680914017.937 [IBIT](IMPORTANT): IBIT PASSED
2023-04-08T00:33:38.326Z,1680914018.326 [Default:CheckIn:D] Stopped
2023-04-08T00:33:38.326Z,1680914018.326 [Default:CheckIn:E] Running Loop=1
2023-04-08T00:33:38.713Z,1680914018.713 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.062907 min 
2023-04-08T00:33:38.713Z,1680914018.713 [Default:CheckIn:E] Stopped
2023-04-08T00:33:38.713Z,1680914018.713 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-04-08T00:33:38.713Z,1680914018.713 [Default:CheckIn] Stopped
2023-04-08T00:33:38.713Z,1680914018.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-08T00:33:38.713Z,1680914018.713 [Default:CheckIn](INFO): Running loop #11
2023-04-08T00:33:38.714Z,1680914018.714 [Default:CheckIn] Running Loop=11
2023-04-08T00:33:38.714Z,1680914018.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-04-08T00:33:38.714Z,1680914018.714 [Default:CheckIn:Read_GPS] Running Loop=1
2023-04-08T00:33:40.740Z,1680914020.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003340.00,A,3648.06800,N,12147.28895,W,1.458,97.01,080423,,,D*4F
2023-04-08T00:33:40.743Z,1680914020.743 [NAL9602](INFO): GPS fix at 20230408T003340: (36.801133, -121.788149)
2023-04-08T00:33:40.761Z,1680914020.761 [Default:CheckIn:Read_GPS] Stopped
2023-04-08T00:33:40.761Z,1680914020.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-04-08T00:33:49.509Z,1680914029.509 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230407T230302/Courier0034.lzma
2023-04-08T00:33:50.512Z,1680914030.512 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Courier0034.lzma.bak
2023-04-08T00:33:50.512Z,1680914030.512 [DataOverHttps](INFO): SBD MOMSN=18171858
2023-04-08T00:34:06.579Z,1680914046.579 [DataOverHttps](INFO): Sending 1090 bytes from file Logs/20230407T230302/Express0035.lzma
2023-04-08T00:34:07.580Z,1680914047.580 [DataOverHttps](INFO): Moved sent file to Logs/20230407T230302/Express0035.lzma.bak
2023-04-08T00:34:07.580Z,1680914047.580 [DataOverHttps](IMPORTANT): SBD MOMSN=18171864, MTMSN=20230408T003406
2023-04-08T00:34:09.409Z,1680914049.409 [Default:CheckIn:Read_Iridium] Stopped
2023-04-08T00:34:09.409Z,1680914049.409 [Default:CheckIn:C.Wait] Running Loop=1
2023-04-08T00:34:09.409Z,1680914049.409 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-04-08T00:34:13.483Z,1680914053.483 [NAL9602](INFO): Not Powering down - fast GPS
2023-04-08T00:34:15.278Z,1680914055.278 [DataOverHttps](INFO): Received command: restart app
2023-04-08T00:34:15.323Z,1680914055.323 [CommandExec](IMPORTANT): got command restart application
2023-04-08T00:34:16.326Z,1680914056.326 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:16.326Z,1680914056.326 [CommandExec](INFO): Uninitializing the command executive.
2023-04-08T00:34:16.326Z,1680914056.326 [CommandExec](INFO): Uninitializing the command scheduler.
2023-04-08T00:34:16.326Z,1680914056.326 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.402Z,1680914056.402 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-04-08T00:34:16.402Z,1680914056.402 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-04-08T00:34:16.402Z,1680914056.402 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.403Z,1680914056.403 [NavChartDb](INFO): Join timeout helper Thread ID is 342
2023-04-08T00:34:16.430Z,1680914056.430 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:16.430Z,1680914056.430 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.442Z,1680914056.442 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-04-08T00:34:16.442Z,1680914056.442 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.442Z,1680914056.442 [DAT](INFO): Join timeout helper Thread ID is 343
2023-04-08T00:34:16.454Z,1680914056.454 [DAT](INFO): Powering down
2023-04-08T00:34:16.526Z,1680914056.526 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:16.527Z,1680914056.527 [DAT](INFO): Powering down
2023-04-08T00:34:16.527Z,1680914056.527 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.546Z,1680914056.546 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-04-08T00:34:16.546Z,1680914056.546 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.546Z,1680914056.546 [Radio_Surface](INFO): Join timeout helper Thread ID is 344
2023-04-08T00:34:16.838Z,1680914056.838 [Radio_Surface](INFO): Powering down
2023-04-08T00:34:16.839Z,1680914056.839 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:16.839Z,1680914056.839 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.854Z,1680914056.854 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-04-08T00:34:16.854Z,1680914056.854 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:16.854Z,1680914056.854 [Onboard](INFO): Join timeout helper Thread ID is 345
2023-04-08T00:34:17.282Z,1680914057.282 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-04-08T00:34:18.022Z,1680914058.022 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:18.022Z,1680914058.022 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.038Z,1680914058.038 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-04-08T00:34:18.038Z,1680914058.038 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.038Z,1680914058.038 [DataOverHttps](INFO): Join timeout helper Thread ID is 346
2023-04-08T00:34:18.286Z,1680914058.286 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:18.286Z,1680914058.286 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.311Z,1680914058.311 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-04-08T00:34:18.311Z,1680914058.311 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.311Z,1680914058.311 [BackseatComponent](INFO): Join timeout helper Thread ID is 347
2023-04-08T00:34:18.430Z,1680914058.430 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:18.430Z,1680914058.430 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.445Z,1680914058.445 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-04-08T00:34:18.445Z,1680914058.445 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.456Z,1680914058.456 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 348
2023-04-08T00:34:18.702Z,1680914058.702 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:18.702Z,1680914058.702 [WetLabsBB2FL](INFO): Powering down
2023-04-08T00:34:18.703Z,1680914058.703 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.715Z,1680914058.715 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-04-08T00:34:18.715Z,1680914058.715 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:18.715Z,1680914058.715 [CTD_Seabird](INFO): Join timeout helper Thread ID is 349
2023-04-08T00:34:19.522Z,1680914059.522 [CTD_Seabird](INFO): Powering down
2023-04-08T00:34:19.534Z,1680914059.534 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:19.534Z,1680914059.534 [CTD_Seabird](INFO): Powering down
2023-04-08T00:34:19.546Z,1680914059.546 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:19.560Z,1680914059.560 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-04-08T00:34:19.560Z,1680914059.560 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:19.560Z,1680914059.560 [logger](INFO): Join timeout helper Thread ID is 350
2023-04-08T00:34:19.582Z,1680914059.582 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:19.582Z,1680914059.582 [logger ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:19.598Z,1680914059.598 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-04-08T00:34:19.599Z,1680914059.599 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:19.599Z,1680914059.599 [CommandLine](INFO): Join timeout helper Thread ID is 351
2023-04-08T00:34:19.666Z,1680914059.666 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:19.666Z,1680914059.666 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:19.678Z,1680914059.678 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-04-08T00:34:19.679Z,1680914059.679 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:19.679Z,1680914059.679 [CommandExec](INFO): Join timeout helper Thread ID is 352
2023-04-08T00:34:19.680Z,1680914059.680 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-04-08T00:34:19.680Z,1680914059.680 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:19.681Z,1680914059.681 [controlThread](INFO): Join timeout helper Thread ID is 353
2023-04-08T00:34:19.906Z,1680914059.906 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-04-08T00:34:19.906Z,1680914059.906 [controlThread](DEBUG): Uninitializing ControlThread
2023-04-08T00:34:19.908Z,1680914059.908 [AHRS_M2](INFO): Powering down
2023-04-08T00:34:20.050Z,1680914060.050 [DVL_micro](INFO): Powering down
2023-04-08T00:34:20.051Z,1680914060.051 [NAL9602](INFO): Powering down
2023-04-08T00:34:20.052Z,1680914060.052 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-04-08T00:34:20.053Z,1680914060.053 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-04-08T00:34:20.054Z,1680914060.054 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-04-08T00:34:20.055Z,1680914060.055 [MissionManager](INFO): Uninitializing Mission Default
2023-04-08T00:34:20.055Z,1680914060.055 [Default] Stopped
2023-04-08T00:34:20.055Z,1680914060.055 [Default](DEBUG): Aggregate::uninitialize Default
2023-04-08T00:34:20.055Z,1680914060.055 [Default:B.GoToSurface] Stopped
2023-04-08T00:34:20.055Z,1680914060.055 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-04-08T00:34:20.055Z,1680914060.055 [Default:CheckIn] Stopped
2023-04-08T00:34:20.055Z,1680914060.055 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-04-08T00:34:20.055Z,1680914060.055 [Default:CheckIn:C.Wait] Stopped
2023-04-08T00:34:20.055Z,1680914060.055 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-04-08T00:34:20.058Z,1680914060.058 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-04-08T00:34:20.058Z,1680914060.058 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-04-08T00:34:20.059Z,1680914060.059 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-04-08T00:34:20.059Z,1680914060.059 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-04-08T00:34:20.059Z,1680914060.059 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-04-08T00:34:20.060Z,1680914060.060 [BuoyancyServo](INFO): Powering down
2023-04-08T00:34:20.074Z,1680914060.074 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-04-08T00:34:20.074Z,1680914060.074 [ElevatorServo](INFO): Powering down
2023-04-08T00:34:20.075Z,1680914060.075 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-04-08T00:34:20.075Z,1680914060.075 [MassServo](INFO): Powering down
2023-04-08T00:34:20.076Z,1680914060.076 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-04-08T00:34:20.076Z,1680914060.076 [RudderServo](INFO): Powering down
2023-04-08T00:34:20.077Z,1680914060.077 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-04-08T00:34:20.077Z,1680914060.077 [ThrusterHE](INFO): Powering down
2023-04-08T00:34:20.078Z,1680914060.078 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-04-08T00:34:20.078Z,1680914060.078 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-04-08T00:34:20.078Z,1680914060.078 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-04-08T00:34:20.079Z,1680914060.079 [CBIT](DEBUG): Powering off loads.
2023-04-08T00:34:20.090Z,1680914060.090 [CBIT](DEBUG): Disabling WDT.
2023-04-08T00:34:20.102Z,1680914060.102 [CBIT](DEBUG): Opening all GF detection circuits.
2023-04-08T00:34:20.103Z,1680914060.103 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.151Z,1680914060.151 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.157Z,1680914060.157 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.189Z,1680914060.189 [DAT ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.193Z,1680914060.193 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.195Z,1680914060.195 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.203Z,1680914060.203 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.247Z,1680914060.247 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.302Z,1680914060.302 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-04-08T00:34:20.372Z,1680914060.372 [logger ThreadHandler](INFO): Thread cancelled.