2025-03-31T15:43:00.138Z,1743435780.138 [Supervisor](DEBUG): Initializing supervisor.
2025-03-31T15:43:00.142Z,1743435780.142 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-31T15:43:00.143Z,1743435780.143 [SyncHandler](INFO): Protected caller Thread ID is 6302
2025-03-31T15:43:00.143Z,1743435780.143 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-31T15:43:00.144Z,1743435780.144 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-31T15:43:00.144Z,1743435780.144 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6303
2025-03-31T15:43:00.148Z,1743435780.148 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-31T15:43:00.169Z,1743435780.169 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-31T15:43:00.170Z,1743435780.170 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-31T15:43:00.170Z,1743435780.170 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6304
2025-03-31T15:43:00.174Z,1743435780.174 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-31T15:43:00.175Z,1743435780.175 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-31T15:43:00.176Z,1743435780.176 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6305
2025-03-31T15:43:00.178Z,1743435780.178 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-31T15:43:00.179Z,1743435780.179 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-31T15:43:00.179Z,1743435780.179 [logger ThreadHandler](INFO): Protected caller Thread ID is 6306
2025-03-31T15:43:00.183Z,1743435780.183 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-31T15:43:00.183Z,1743435780.183 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-31T15:43:00.185Z,1743435780.185 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-31T15:43:00.534Z,1743435780.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-31T15:43:00.535Z,1743435780.535 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-31T15:43:00.627Z,1743435780.627 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-31T15:43:00.745Z,1743435780.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-31T15:43:00.745Z,1743435780.745 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-31T15:43:00.885Z,1743435780.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-31T15:43:00.886Z,1743435780.886 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-31T15:43:01.241Z,1743435781.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-31T15:43:01.241Z,1743435781.241 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-31T15:43:01.348Z,1743435781.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-31T15:43:01.349Z,1743435781.349 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-31T15:43:01.775Z,1743435781.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-31T15:43:01.776Z,1743435781.776 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-31T15:43:02.011Z,1743435782.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-31T15:43:02.011Z,1743435782.011 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-31T15:43:02.102Z,1743435782.102 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-31T15:43:02.352Z,1743435782.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-31T15:43:02.352Z,1743435782.352 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-31T15:43:02.583Z,1743435782.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-31T15:43:02.583Z,1743435782.583 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-31T15:43:03.119Z,1743435783.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-31T15:43:03.120Z,1743435783.120 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-31T15:43:03.321Z,1743435783.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-31T15:43:03.322Z,1743435783.322 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-31T15:43:03.617Z,1743435783.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-31T15:43:03.617Z,1743435783.617 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-31T15:43:04.495Z,1743435784.495 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-31T15:43:04.496Z,1743435784.496 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-31T15:43:05.083Z,1743435785.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-31T15:43:05.085Z,1743435785.085 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-03-31T15:43:05.086Z,1743435785.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-03-31T15:43:05.194Z,1743435785.194 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-03-31T15:43:05.337Z,1743435785.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-03-31T15:43:05.433Z,1743435785.433 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-03-31T15:43:05.552Z,1743435785.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-03-31T15:43:05.651Z,1743435785.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-03-31T15:43:05.922Z,1743435785.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-31T15:43:05.923Z,1743435785.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-03-31T15:43:06.044Z,1743435786.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-03-31T15:43:06.154Z,1743435786.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-03-31T15:43:06.308Z,1743435786.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-03-31T15:43:06.406Z,1743435786.406 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2025-03-31T15:43:06.406Z,1743435786.406 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-03-31T15:43:06.429Z,1743435786.429 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-31T15:43:06.566Z,1743435786.566 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-31T15:43:06.566Z,1743435786.566 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-31T15:43:06.622Z,1743435786.622 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-31T15:43:06.683Z,1743435786.683 [VerticalControl] Loaded
2025-03-31T15:43:06.683Z,1743435786.683 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-31T15:43:06.686Z,1743435786.686 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-31T15:43:06.733Z,1743435786.733 [HorizontalControl] Loaded
2025-03-31T15:43:06.733Z,1743435786.733 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-31T15:43:06.736Z,1743435786.736 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-31T15:43:06.739Z,1743435786.739 [SpeedControl] Loaded
2025-03-31T15:43:06.739Z,1743435786.739 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-31T15:43:06.741Z,1743435786.741 [LoopControl](DEBUG): Construct LoopControl.
2025-03-31T15:43:06.742Z,1743435786.742 [LoopControl] Loaded
2025-03-31T15:43:06.742Z,1743435786.742 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-31T15:43:06.743Z,1743435786.743 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-31T15:43:06.743Z,1743435786.743 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-31T15:43:06.803Z,1743435786.803 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-31T15:43:06.804Z,1743435786.804 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-31T15:43:07.308Z,1743435787.308 [AHRS_M2] Loaded
2025-03-31T15:43:07.309Z,1743435787.309 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-31T15:43:07.347Z,1743435787.347 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4
2025-03-31T15:43:07.348Z,1743435787.348 [BackseatComponent] Loaded
2025-03-31T15:43:07.348Z,1743435787.348 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-03-31T15:43:07.349Z,1743435787.349 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0
2025-03-31T15:43:07.349Z,1743435787.349 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6385
2025-03-31T15:43:07.352Z,1743435787.352 [LcmUniversalReporter] Loaded
2025-03-31T15:43:07.352Z,1743435787.352 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-03-31T15:43:08.150Z,1743435788.150 [BPC1] Loaded
2025-03-31T15:43:08.150Z,1743435788.150 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-31T15:43:08.235Z,1743435788.235 [DataOverHttps] Loaded
2025-03-31T15:43:08.235Z,1743435788.235 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-31T15:43:08.236Z,1743435788.236 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0
2025-03-31T15:43:08.236Z,1743435788.236 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6386
2025-03-31T15:43:08.260Z,1743435788.260 [Depth_Keller] Loaded
2025-03-31T15:43:08.260Z,1743435788.260 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-31T15:43:08.265Z,1743435788.265 [DropWeight] Loaded
2025-03-31T15:43:08.265Z,1743435788.265 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-31T15:43:08.329Z,1743435788.329 [NAL9602] Loaded
2025-03-31T15:43:08.329Z,1743435788.329 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-31T15:43:08.359Z,1743435788.359 [Onboard] Loaded
2025-03-31T15:43:08.359Z,1743435788.359 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-31T15:43:08.360Z,1743435788.360 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0
2025-03-31T15:43:08.361Z,1743435788.361 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6387
2025-03-31T15:43:08.375Z,1743435788.375 [Power24vConverter] Loaded
2025-03-31T15:43:08.375Z,1743435788.375 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-31T15:43:08.391Z,1743435788.391 [Radio_Surface] Loaded
2025-03-31T15:43:08.391Z,1743435788.391 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-31T15:43:08.392Z,1743435788.392 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0
2025-03-31T15:43:08.392Z,1743435788.392 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6388
2025-03-31T15:43:08.408Z,1743435788.408 [Sonardyne_Nano] Loaded
2025-03-31T15:43:08.408Z,1743435788.408 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-31T15:43:08.433Z,1743435788.433 [Waterlinked] Loaded
2025-03-31T15:43:08.433Z,1743435788.433 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-03-31T15:43:08.434Z,1743435788.434 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-31T15:43:08.434Z,1743435788.434 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-31T15:43:08.445Z,1743435788.445 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-31T15:43:08.445Z,1743435788.445 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-31T15:43:08.474Z,1743435788.474 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-31T15:43:08.475Z,1743435788.475 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-31T15:43:08.640Z,1743435788.640 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-31T15:43:08.649Z,1743435788.649 [SBIT] Loaded
2025-03-31T15:43:08.649Z,1743435788.649 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-31T15:43:08.652Z,1743435788.652 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-31T15:43:08.665Z,1743435788.665 [IBIT] Loaded
2025-03-31T15:43:08.665Z,1743435788.665 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-31T15:43:08.670Z,1743435788.670 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-31T15:43:08.750Z,1743435788.750 [CBIT] Loaded
2025-03-31T15:43:08.750Z,1743435788.750 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-31T15:43:08.781Z,1743435788.781 [GFScanner] Loaded
2025-03-31T15:43:08.782Z,1743435788.782 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-31T15:43:08.782Z,1743435788.782 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-31T15:43:08.782Z,1743435788.782 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-31T15:43:09.133Z,1743435789.133 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-31T15:43:09.134Z,1743435789.134 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-31T15:43:09.186Z,1743435789.186 [DepthRateCalculator] Loaded
2025-03-31T15:43:09.187Z,1743435789.187 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-31T15:43:09.192Z,1743435789.192 [PitchRateCalculator] Loaded
2025-03-31T15:43:09.192Z,1743435789.192 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-31T15:43:09.202Z,1743435789.202 [SpeedCalculator] Loaded
2025-03-31T15:43:09.203Z,1743435789.203 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-31T15:43:09.207Z,1743435789.207 [YawRateCalculator] Loaded
2025-03-31T15:43:09.207Z,1743435789.207 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-31T15:43:09.226Z,1743435789.226 [ElevatorOffsetCalculator] Loaded
2025-03-31T15:43:09.226Z,1743435789.226 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-31T15:43:09.226Z,1743435789.226 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-31T15:43:09.227Z,1743435789.227 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-31T15:43:09.411Z,1743435789.411 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-31T15:43:09.411Z,1743435789.411 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-31T15:43:09.526Z,1743435789.526 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-31T15:43:09.526Z,1743435789.526 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-31T15:43:09.540Z,1743435789.540 [NavChart] Loaded
2025-03-31T15:43:09.540Z,1743435789.540 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-31T15:43:09.546Z,1743435789.546 [UniversalFixResidualReporter] Loaded
2025-03-31T15:43:09.546Z,1743435789.546 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-31T15:43:09.547Z,1743435789.547 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-31T15:43:09.548Z,1743435789.548 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-31T15:43:09.703Z,1743435789.703 [BuoyancyServo] Loaded
2025-03-31T15:43:09.703Z,1743435789.703 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-31T15:43:09.726Z,1743435789.726 [ElevatorServo] Loaded
2025-03-31T15:43:09.727Z,1743435789.727 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-31T15:43:09.748Z,1743435789.748 [MassServo] Loaded
2025-03-31T15:43:09.749Z,1743435789.749 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-31T15:43:09.769Z,1743435789.769 [RudderServo] Loaded
2025-03-31T15:43:09.770Z,1743435789.770 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-31T15:43:09.787Z,1743435789.787 [ThrusterHE] Loaded
2025-03-31T15:43:09.787Z,1743435789.787 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-31T15:43:09.787Z,1743435789.787 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-31T15:43:09.788Z,1743435789.788 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-31T15:43:09.958Z,1743435789.958 [CTD_Seabird] Loaded
2025-03-31T15:43:09.958Z,1743435789.958 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-31T15:43:09.959Z,1743435789.959 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD54E0
2025-03-31T15:43:09.959Z,1743435789.959 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6389
2025-03-31T15:43:09.996Z,1743435789.996 [ESPComponent] Loaded
2025-03-31T15:43:09.996Z,1743435789.996 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-03-31T15:43:10.019Z,1743435790.019 [PAR_Licor] Loaded
2025-03-31T15:43:10.019Z,1743435790.019 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-31T15:43:10.020Z,1743435790.020 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-31T15:43:10.026Z,1743435790.026 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-31T15:43:10.029Z,1743435790.029 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-31T15:43:10.040Z,1743435790.040 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-31T15:43:10.041Z,1743435790.041 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0
2025-03-31T15:43:10.041Z,1743435790.041 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6390
2025-03-31T15:43:10.046Z,1743435790.046 [Supervisor](INFO): Main Thread ID is 6139
2025-03-31T15:43:10.046Z,1743435790.046 [Supervisor](DEBUG): Running supervisor.
2025-03-31T15:43:10.046Z,1743435790.046 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6391
2025-03-31T15:43:10.047Z,1743435790.047 [CommandExec](INFO): Initializing the command executive.
2025-03-31T15:43:10.049Z,1743435790.049 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6392
2025-03-31T15:43:10.051Z,1743435790.051 [controlThread ThreadHandler](INFO): Handler Thread ID is 6393
2025-03-31T15:43:10.051Z,1743435790.051 [controlThread](DEBUG): Initializing ControlThread
2025-03-31T15:43:10.052Z,1743435790.052 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-31T15:43:10.054Z,1743435790.054 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-31T15:43:10.054Z,1743435790.054 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-31T15:43:10.055Z,1743435790.055 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-31T15:43:10.058Z,1743435790.058 [SBIT](INFO): Initialize SBIT Component.
2025-03-31T15:43:10.059Z,1743435790.059 [SBIT](IMPORTANT): git: 2025-03-31
2025-03-31T15:43:10.059Z,1743435790.059 [SBIT](INFO): git hash: fa5a0d22cc02557daa9f4ab77c7f518dfe223d1b
2025-03-31T15:43:10.059Z,1743435790.059 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-31T15:43:10.060Z,1743435790.060 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2025-03-31T15:43:10.061Z,1743435790.061 [SBIT](INFO): Beginning SBIT in 79.000000 seconds.
2025-03-31T15:43:10.062Z,1743435790.062 [IBIT](INFO): Initialize IBIT Component.
2025-03-31T15:43:10.063Z,1743435790.063 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-31T15:43:10.064Z,1743435790.064 [logger ThreadHandler](INFO): Handler Thread ID is 6394
2025-03-31T15:43:10.075Z,1743435790.075 [CBIT](DEBUG): Initialized mux pins.
2025-03-31T15:43:10.075Z,1743435790.075 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-31T15:43:10.084Z,1743435790.084 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6395
2025-03-31T15:43:10.099Z,1743435790.099 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-31T15:43:10.100Z,1743435790.100 [CBIT](DEBUG): Initializing heartbeat.
2025-03-31T15:43:10.100Z,1743435790.100 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6396
2025-03-31T15:43:10.102Z,1743435790.102 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-31T15:43:10.116Z,1743435790.116 [Onboard ThreadHandler](INFO): Handler Thread ID is 6397
2025-03-31T15:43:10.133Z,1743435790.133 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6398
2025-03-31T15:43:10.152Z,1743435790.152 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6399
2025-03-31T15:43:10.153Z,1743435790.153 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-31T15:43:10.157Z,1743435790.157 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6401
2025-03-31T15:43:10.159Z,1743435790.159 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-31T15:43:10.160Z,1743435790.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-31T15:43:10.160Z,1743435790.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-31T15:43:10.160Z,1743435790.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-31T15:43:10.160Z,1743435790.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-31T15:43:10.160Z,1743435790.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-31T15:43:10.160Z,1743435790.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-31T15:43:10.161Z,1743435790.161 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-31T15:43:10.171Z,1743435790.171 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-31T15:43:10.211Z,1743435790.211 [CBIT](DEBUG): Backplane powered.
2025-03-31T15:43:10.212Z,1743435790.212 [GFScanner](DEBUG): Initializing GFScanner
2025-03-31T15:43:10.212Z,1743435790.212 [GFScanner](DEBUG): Deactivating GF circuits.
2025-03-31T15:43:10.216Z,1743435790.216 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-31T15:43:10.216Z,1743435790.216 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-31T15:43:10.217Z,1743435790.217 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-31T15:43:10.217Z,1743435790.217 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-31T15:43:10.217Z,1743435790.217 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-31T15:43:10.219Z,1743435790.219 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-31T15:43:10.219Z,1743435790.219 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-31T15:43:10.228Z,1743435790.228 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-31T15:43:10.229Z,1743435790.229 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-31T15:43:10.229Z,1743435790.229 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-31T15:43:10.230Z,1743435790.230 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-31T15:43:10.314Z,1743435790.314 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission 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>

2025-03-31T15:43:10.318Z,1743435790.318 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-31T15:43:10.368Z,1743435790.368 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Startup {
  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


2025-03-31T15:43:10.379Z,1743435790.379 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-31T15:43:10.379Z,1743435790.379 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-31T15:43:10.380Z,1743435790.380 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-31T15:43:10.543Z,1743435790.543 [Radio_Surface](INFO): Powering up
2025-03-31T15:43:10.677Z,1743435790.677 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <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>

2025-03-31T15:43:10.699Z,1743435790.699 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-31T15:43:10.701Z,1743435790.701 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-31T15:43:10.703Z,1743435790.703 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-31T15:43:10.764Z,1743435790.764 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-31T15:43:10.766Z,1743435790.766 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-31T15:43:10.784Z,1743435790.784 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-31T15:43:10.787Z,1743435790.787 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P4H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

    syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-03-31T15:43:10.800Z,1743435790.800 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-03-31T15:43:10.815Z,1743435790.815 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-31T15:43:10.876Z,1743435790.876 [Depth_Keller](INFO): Initializing.
2025-03-31T15:43:10.877Z,1743435790.877 [Power24vConverter](INFO): Powering up.
2025-03-31T15:43:10.878Z,1743435790.878 [Sonardyne_Nano](INFO): Initializing.
2025-03-31T15:43:10.920Z,1743435790.920 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-03-31T15:43:10.946Z,1743435790.946 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-31T15:43:10.952Z,1743435790.952 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-31T15:43:10.953Z,1743435790.953 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-31T15:43:10.960Z,1743435790.960 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-31T15:43:10.960Z,1743435790.960 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-31T15:43:10.968Z,1743435790.968 [MassServo](DEBUG): Initializing MassServo.
2025-03-31T15:43:10.968Z,1743435790.968 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-31T15:43:10.976Z,1743435790.976 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-31T15:43:10.976Z,1743435790.976 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-31T15:43:10.984Z,1743435790.984 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-31T15:43:12.830Z,1743435792.830 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-31T15:43:14.124Z,1743435794.124 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-03-31T15:43:16.848Z,1743435796.848 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-03-31T15:43:17.220Z,1743435797.220 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-03-31T15:43:38.393Z,1743435818.393 [NAL9602](INFO): Powering up NAL9602
2025-03-31T15:43:48.244Z,1743435828.244 [Waterlinked](INFO): Powering down
2025-03-31T15:43:49.363Z,1743435829.363 [NAL9602](INFO): NAL9602 initialized
2025-03-31T15:44:14.421Z,1743435854.421 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:44:28.200Z,1743435868.200 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:44:29.810Z,1743435869.810 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-31T15:44:29.814Z,1743435869.814 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-31T15:44:34.420Z,1743435874.420 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.030695
CHAN A1 (24V): 0.001331
CHAN A2 (12V): 0.000150
CHAN A3 (5V): -0.001719
CHAN B0 (3.3V): 0.000541
CHAN B1 (3.15aV): 0.000490
CHAN B2 (3.15bV): 0.000523
CHAN B3 (GND): -0.001315
OPEN: 0.006940
Full Scale: +/- 1 mA
2025-03-31T15:44:40.720Z,1743435880.720 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:44:52.854Z,1743435892.855 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:45:05.781Z,1743435905.781 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:45:18.413Z,1743435918.413 [CommandExec](IMPORTANT): got command strobe off
2025-03-31T15:45:18.413Z,1743435918.413 [CommandExec](IMPORTANT): Deactivating strobe
2025-03-31T15:45:18.725Z,1743435918.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:45:23.957Z,1743435923.957 [SBIT](IMPORTANT): SBIT PASSED
2025-03-31T15:45:23.957Z,1743435923.957 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-03-31T15:45:23.958Z,1743435923.958 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count;

2025-03-31T15:45:23.958Z,1743435923.958 [SBIT](IMPORTANT): CBIT.abortDepth=305 meter;

2025-03-31T15:45:23.958Z,1743435923.958 [SBIT](IMPORTANT): CBIT.stopDepth=295 meter;

2025-03-31T15:45:23.958Z,1743435923.958 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2025-03-31T15:45:23.958Z,1743435923.958 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute;

2025-03-31T15:45:23.959Z,1743435923.959 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2025-03-31T15:45:23.959Z,1743435923.959 [SBIT](IMPORTANT): Express linearApproximation PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;

2025-03-31T15:45:23.959Z,1743435923.959 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-03-31T15:45:23.959Z,1743435923.959 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 1.000000 count_per_second;

2025-03-31T15:45:23.959Z,1743435923.959 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 25.000000 count_per_second;

2025-03-31T15:45:23.959Z,1743435923.959 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_caseHumidity 10.000000 percent;

2025-03-31T15:45:23.959Z,1743435923.959 [SBIT](IMPORTANT): Express none _.planktivore_diatoms;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): Express none _.planktivore_dinoflagellates;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): Express none _.predict_plankton_data_pct_dinos_sqerr;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2025-03-31T15:45:23.960Z,1743435923.960 [SBIT](IMPORTANT): MassServo.loadAtStartup=1 bool;

2025-03-31T15:45:23.961Z,1743435923.961 [SBIT](IMPORTANT): Normal all _.planktivore_diatoms;

2025-03-31T15:45:23.961Z,1743435923.961 [SBIT](IMPORTANT): Normal all _.planktivore_dinoflagellates;

2025-03-31T15:45:23.961Z,1743435923.961 [SBIT](IMPORTANT): Normal linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.100000 none;

2025-03-31T15:45:23.961Z,1743435923.961 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=0 bool;

2025-03-31T15:45:23.961Z,1743435923.961 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=196 cubic_centimeter;

2025-03-31T15:45:23.961Z,1743435923.961 [SBIT](IMPORTANT): VerticalControl.massDefault=14.870359 millimeter;

2025-03-31T15:45:23.961Z,1743435923.961 [SBIT](IMPORTANT): WetLabsBB2FL.loadAtStartup=0 bool;

2025-03-31T15:45:24.349Z,1743435924.349 [MissionManager](IMPORTANT): Started mission Startup
2025-03-31T15:45:24.350Z,1743435924.350 [Startup] Running Loop=1
2025-03-31T15:45:24.350Z,1743435924.350 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-31T15:45:24.350Z,1743435924.350 [Startup:A.GoToSurface] Running Loop=1
2025-03-31T15:45:24.350Z,1743435924.350 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-31T15:45:24.351Z,1743435924.351 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-31T15:45:24.351Z,1743435924.351 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-31T15:45:24.352Z,1743435924.352 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-31T15:45:24.352Z,1743435924.352 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-31T15:45:24.352Z,1743435924.352 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-31T15:45:24.353Z,1743435924.353 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-31T15:45:24.354Z,1743435924.354 [Startup:StartupSatComms] Running Loop=1
2025-03-31T15:45:24.355Z,1743435924.355 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-31T15:45:24.355Z,1743435924.355 [Startup:StartupSatComms:A] Running Loop=1
2025-03-31T15:45:24.750Z,1743435924.750 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-31T15:45:31.240Z,1743435931.240 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:45:43.771Z,1743435943.771 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:45:53.134Z,1743435953.134 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004682
2025-03-31T15:45:56.710Z,1743435956.710 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:46:10.396Z,1743435970.396 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-31T15:46:10.396Z,1743435970.396 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T15:46:10.407Z,1743435970.407 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T15:46:10.814Z,1743435970.814 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T15:46:10.814Z,1743435970.814 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-03-31T15:46:24.565Z,1743435984.565 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-31T15:45:24.4Z
2025-03-31T15:46:24.566Z,1743435984.566 [Startup:StartupSatComms:A] Stopped
2025-03-31T15:46:24.566Z,1743435984.566 [Startup:StartupSatComms:B] Running Loop=1
2025-03-31T15:46:24.997Z,1743435984.997 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-31T15:46:32.542Z,1743435992.542 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-03-31T15:46:32.543Z,1743435992.543 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 88.000000 %
2025-03-31T15:46:32.765Z,1743435992.765 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250331T154300/Courier0000.lzma
2025-03-31T15:46:33.765Z,1743435993.765 [DataOverHttps](INFO): Moved sent file to Logs/20250331T154300/Courier0000.lzma.bak
2025-03-31T15:46:33.765Z,1743435993.765 [DataOverHttps](INFO): SBD MOMSN=24502071
2025-03-31T15:46:36.303Z,1743435996.303 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T15:46:49.327Z,1743436009.327 [DataOverHttps](INFO): Sending 1393 bytes from file Logs/20250331T154300/Express0001.lzma
2025-03-31T15:46:50.325Z,1743436010.325 [DataOverHttps](INFO): Moved sent file to Logs/20250331T154300/Express0001.lzma.bak
2025-03-31T15:46:50.325Z,1743436010.325 [DataOverHttps](INFO): SBD MOMSN=24502074
2025-03-31T15:46:51.634Z,1743436011.634 [Startup:StartupSatComms:B] Stopped
2025-03-31T15:46:51.634Z,1743436011.634 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-31T15:46:51.634Z,1743436011.634 [Startup:StartupSatComms] Stopped
2025-03-31T15:46:51.634Z,1743436011.634 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-31T15:46:51.636Z,1743436011.636 [Startup](INFO): Completed Startup
2025-03-31T15:46:51.636Z,1743436011.636 [MissionManager](INFO): Startup is completed.
2025-03-31T15:46:51.636Z,1743436011.636 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-31T15:46:51.636Z,1743436011.636 [Startup] Stopped
2025-03-31T15:46:51.636Z,1743436011.636 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-31T15:46:51.636Z,1743436011.636 [Startup:A.GoToSurface] Stopped
2025-03-31T15:46:51.636Z,1743436011.636 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-31T15:46:52.025Z,1743436012.025 [MissionManager](IMPORTANT): Started mission Default
2025-03-31T15:46:52.026Z,1743436012.026 [Default] Running Loop=1
2025-03-31T15:46:52.026Z,1743436012.026 [Default](DEBUG): Aggregate::initialize Default
2025-03-31T15:46:52.026Z,1743436012.026 [Default:B.GoToSurface] Running Loop=1
2025-03-31T15:46:52.026Z,1743436012.026 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-31T15:46:52.026Z,1743436012.026 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-31T15:46:52.026Z,1743436012.026 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-31T15:46:52.027Z,1743436012.027 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-31T15:46:52.027Z,1743436012.027 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-31T15:46:52.056Z,1743436012.056 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-31T15:46:52.056Z,1743436012.056 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-31T15:46:52.056Z,1743436012.056 [Default:A.Wait] Running Loop=1
2025-03-31T15:46:52.056Z,1743436012.056 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-31T15:47:05.400Z,1743436025.400 [Default:A.Wait](INFO): Done Waiting.
2025-03-31T15:47:05.400Z,1743436025.400 [Default:A.Wait] Stopped
2025-03-31T15:47:05.400Z,1743436025.400 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-31T15:47:05.766Z,1743436025.766 [Default:CheckIn] Running Loop=1
2025-03-31T15:47:05.766Z,1743436025.766 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-31T15:47:05.766Z,1743436025.766 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-31T15:47:06.170Z,1743436026.170 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-31T15:47:57.846Z,1743436077.846 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-03-31T15:48:21.280Z,1743436101.280 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:48:36.630Z,1743436116.630 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:48:51.173Z,1743436131.173 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:48:53.197Z,1743436133.197 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-31T15:49:06.185Z,1743436146.185 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:49:11.389Z,1743436151.389 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-03-31T15:49:11.389Z,1743436151.389 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T15:49:11.400Z,1743436151.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T15:49:11.806Z,1743436151.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T15:49:11.806Z,1743436151.806 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-03-31T15:49:18.300Z,1743436158.300 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T15:49:21.070Z,1743436161.070 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:49:36.018Z,1743436176.018 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:49:51.370Z,1743436191.370 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:50:05.918Z,1743436205.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:50:20.863Z,1743436220.863 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:50:36.213Z,1743436236.213 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:52:04.373Z,1743436324.373 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:52:05.941Z,1743436325.941 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-31T15:47:05.8Z
2025-03-31T15:52:05.941Z,1743436325.941 [Default:CheckIn:Read_GPS] Stopped
2025-03-31T15:52:05.941Z,1743436325.941 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-31T15:52:06.354Z,1743436326.354 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-31T15:52:07.201Z,1743436327.201 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T15:52:12.405Z,1743436332.405 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-03-31T15:52:12.405Z,1743436332.405 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T15:52:12.416Z,1743436332.416 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T15:52:12.739Z,1743436332.739 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250331T154300/Courier0004.lzma
2025-03-31T15:52:12.839Z,1743436332.839 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T15:52:12.839Z,1743436332.839 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-03-31T15:52:13.741Z,1743436333.741 [DataOverHttps](INFO): Moved sent file to Logs/20250331T154300/Courier0004.lzma.bak
2025-03-31T15:52:13.741Z,1743436333.741 [DataOverHttps](INFO): SBD MOMSN=24502124
2025-03-31T15:52:29.463Z,1743436349.463 [DataOverHttps](INFO): Sending 258 bytes from file Logs/20250331T154300/Express0005.lzma
2025-03-31T15:52:30.465Z,1743436350.465 [DataOverHttps](INFO): Moved sent file to Logs/20250331T154300/Express0005.lzma.bak
2025-03-31T15:52:30.465Z,1743436350.465 [DataOverHttps](INFO): SBD MOMSN=24502126
2025-03-31T15:52:31.475Z,1743436351.475 [Default:CheckIn:Read_Iridium] Stopped
2025-03-31T15:52:31.475Z,1743436351.475 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-31T15:52:31.475Z,1743436351.475 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-31T15:53:00.527Z,1743436380.527 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:53:06.948Z,1743436386.948 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:53:19.500Z,1743436399.500 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:53:32.031Z,1743436412.031 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:53:37.960Z,1743436417.960 [CommandExec](IMPORTANT): got command failComponent
2025-03-31T15:53:37.960Z,1743436417.960 [CommandExec](IMPORTANT): Failed components:
2025-03-31T15:53:37.960Z,1743436417.960 [CommandExec](IMPORTANT): No failed Components.
2025-03-31T15:53:44.148Z,1743436424.148 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:53:49.368Z,1743436429.368 [Power24vConverter](INFO): Powering down.
2025-03-31T15:53:52.181Z,1743436432.181 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-31T15:53:52.181Z,1743436432.181 [NAL9602] Data Fault, FailCount= 1
2025-03-31T15:53:52.181Z,1743436432.181 [NAL9602](ERROR): Data Fault
2025-03-31T15:53:52.235Z,1743436432.235 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-31T15:53:52.585Z,1743436432.585 [NAL9602](INFO): Powering down
2025-03-31T15:53:53.419Z,1743436433.419 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-31T15:53:53.419Z,1743436433.419 [NAL9602] No Fault, FailCount= 1
2025-03-31T15:53:56.271Z,1743436436.271 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:53:58.504Z,1743436438.504 [CommandExec](IMPORTANT): got command failComponent
2025-03-31T15:53:58.504Z,1743436438.504 [CommandExec](IMPORTANT): Failed components:
2025-03-31T15:53:58.504Z,1743436438.504 [CommandExec](IMPORTANT): No failed Components.
2025-03-31T15:54:08.791Z,1743436448.791 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:54:21.723Z,1743436461.723 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:54:22.889Z,1743436462.889 [NAL9602](INFO): Powering up NAL9602
2025-03-31T15:54:33.801Z,1743436473.801 [NAL9602](INFO): NAL9602 initialized
2025-03-31T15:54:34.244Z,1743436474.244 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:54:47.174Z,1743436487.174 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:54:59.692Z,1743436499.692 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:55:12.223Z,1743436512.223 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:55:13.397Z,1743436513.397 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-03-31T15:55:13.397Z,1743436513.397 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T15:55:13.432Z,1743436513.432 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T15:55:13.809Z,1743436513.809 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T15:55:13.809Z,1743436513.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-03-31T15:55:24.757Z,1743436524.757 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:55:53.845Z,1743436553.845 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T15:56:20.562Z,1743436580.562 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:57:01.254Z,1743436621.254 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-03-31T15:57:31.973Z,1743436651.973 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-31T15:57:31.973Z,1743436651.973 [Default:CheckIn:C.Wait] Stopped
2025-03-31T15:57:31.974Z,1743436651.974 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-31T15:57:31.974Z,1743436651.974 [Default:CheckIn:D] Running Loop=1
2025-03-31T15:57:32.387Z,1743436652.387 [Default:CheckIn:D] Stopped
2025-03-31T15:57:32.387Z,1743436652.388 [Default:CheckIn:E] Running Loop=1
2025-03-31T15:57:32.778Z,1743436652.778 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.672693 min 
2025-03-31T15:57:32.778Z,1743436652.778 [Default:CheckIn:E] Stopped
2025-03-31T15:57:32.778Z,1743436652.778 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-31T15:57:32.778Z,1743436652.778 [Default:CheckIn] Stopped
2025-03-31T15:57:32.778Z,1743436652.778 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-31T15:57:32.778Z,1743436652.778 [Default:CheckIn](INFO): Running loop #2
2025-03-31T15:57:32.778Z,1743436652.778 [Default:CheckIn] Running Loop=2
2025-03-31T15:57:32.779Z,1743436652.779 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-31T15:57:32.779Z,1743436652.779 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-31T15:57:48.524Z,1743436668.524 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:58:03.465Z,1743436683.465 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:58:14.429Z,1743436694.429 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-03-31T15:58:14.429Z,1743436694.429 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T15:58:14.443Z,1743436694.443 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T15:58:14.817Z,1743436694.817 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T15:58:14.817Z,1743436694.817 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-03-31T15:58:17.655Z,1743436697.655 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T15:58:26.153Z,1743436706.153 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T15:58:32.960Z,1743436712.960 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:58:47.501Z,1743436727.501 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:59:02.916Z,1743436742.916 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:59:18.616Z,1743436758.616 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:59:33.562Z,1743436773.562 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T15:59:48.909Z,1743436788.909 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:00:03.858Z,1743436803.858 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:01:15.398Z,1743436875.398 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-03-31T16:01:15.398Z,1743436875.398 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T16:01:15.409Z,1743436875.409 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T16:01:15.830Z,1743436875.830 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T16:01:15.830Z,1743436875.830 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-03-31T16:01:34.628Z,1743436894.628 [CommandExec](IMPORTANT): got command burn on
2025-03-31T16:01:34.628Z,1743436894.628 [CommandExec](IMPORTANT): Activating dropweight wire
2025-03-31T16:02:13.686Z,1743436933.686 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:02:14.993Z,1743436934.993 [CommandExec](IMPORTANT): got command burn off
2025-03-31T16:02:14.993Z,1743436934.993 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-03-31T16:02:32.986Z,1743436952.986 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-31T15:57:32.8Z
2025-03-31T16:02:32.986Z,1743436952.986 [Default:CheckIn:Read_GPS] Stopped
2025-03-31T16:02:32.986Z,1743436952.986 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-31T16:02:34.192Z,1743436954.192 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:02:40.399Z,1743436960.399 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250331T154300/Courier0007.lzma
2025-03-31T16:02:41.401Z,1743436961.401 [DataOverHttps](INFO): Moved sent file to Logs/20250331T154300/Courier0007.lzma.bak
2025-03-31T16:02:41.401Z,1743436961.401 [DataOverHttps](INFO): SBD MOMSN=24502183
2025-03-31T16:02:46.391Z,1743436966.391 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:02:57.016Z,1743436977.016 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20250331T154300/Express0008.lzma
2025-03-31T16:02:58.017Z,1743436978.017 [DataOverHttps](INFO): Moved sent file to Logs/20250331T154300/Express0008.lzma.bak
2025-03-31T16:02:58.017Z,1743436978.017 [DataOverHttps](INFO): SBD MOMSN=24502185
2025-03-31T16:02:58.874Z,1743436978.874 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:02:59.281Z,1743436979.281 [Default:CheckIn:Read_Iridium] Stopped
2025-03-31T16:02:59.282Z,1743436979.282 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-31T16:02:59.282Z,1743436979.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-31T16:03:11.800Z,1743436991.800 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:03:24.731Z,1743437004.731 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:03:37.655Z,1743437017.655 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:03:50.991Z,1743437030.991 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:04:03.938Z,1743437043.938 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:04:16.443Z,1743437056.443 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:04:16.484Z,1743437056.484 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-03-31T16:04:16.484Z,1743437056.484 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T16:04:16.495Z,1743437056.495 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T16:04:16.858Z,1743437056.858 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T16:04:16.858Z,1743437056.858 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-03-31T16:04:28.562Z,1743437068.562 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:04:34.985Z,1743437074.985 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-31T16:04:34.985Z,1743437074.985 [NAL9602] Data Fault, FailCount= 2
2025-03-31T16:04:34.985Z,1743437074.985 [NAL9602](ERROR): Data Fault
2025-03-31T16:04:35.047Z,1743437075.047 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-31T16:04:35.381Z,1743437075.381 [NAL9602](INFO): Powering down
2025-03-31T16:04:36.216Z,1743437076.216 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-31T16:04:36.218Z,1743437076.218 [NAL9602] No Fault, FailCount= 2
2025-03-31T16:04:41.087Z,1743437081.087 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:04:54.431Z,1743437094.431 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:05:02.102Z,1743437102.102 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T16:05:05.681Z,1743437105.681 [NAL9602](INFO): Powering up NAL9602
2025-03-31T16:05:15.029Z,1743437115.029 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T16:05:16.598Z,1743437116.598 [NAL9602](INFO): NAL9602 initialized
2025-03-31T16:05:25.882Z,1743437125.882 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2025-03-31T16:05:49.366Z,1743437149.366 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:06:38.668Z,1743437198.668 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:07:17.420Z,1743437237.420 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-03-31T16:07:17.420Z,1743437237.420 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T16:07:17.430Z,1743437237.430 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T16:07:17.810Z,1743437237.810 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T16:07:17.810Z,1743437237.810 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-03-31T16:07:18.594Z,1743437238.594 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-03-31T16:07:25.461Z,1743437245.461 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:07:40.016Z,1743437260.016 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:07:50.916Z,1743437270.916 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-03-31T16:07:54.549Z,1743437274.549 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:07:59.849Z,1743437279.849 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-31T16:07:59.849Z,1743437279.849 [Default:CheckIn:C.Wait] Stopped
2025-03-31T16:07:59.849Z,1743437279.849 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-31T16:07:59.849Z,1743437279.849 [Default:CheckIn:D] Running Loop=1
2025-03-31T16:08:00.256Z,1743437280.256 [Default:CheckIn:D] Stopped
2025-03-31T16:08:00.257Z,1743437280.257 [Default:CheckIn:E] Running Loop=1
2025-03-31T16:08:00.654Z,1743437280.654 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  21.137181 min 
2025-03-31T16:08:00.654Z,1743437280.654 [Default:CheckIn:E] Stopped
2025-03-31T16:08:00.654Z,1743437280.654 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-31T16:08:00.654Z,1743437280.654 [Default:CheckIn] Stopped
2025-03-31T16:08:00.654Z,1743437280.654 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-31T16:08:00.654Z,1743437280.654 [Default:CheckIn](INFO): Running loop #3
2025-03-31T16:08:00.654Z,1743437280.654 [Default:CheckIn] Running Loop=3
2025-03-31T16:08:00.655Z,1743437280.655 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-31T16:08:00.655Z,1743437280.655 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-31T16:08:09.497Z,1743437289.497 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:08:24.849Z,1743437304.849 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:08:39.808Z,1743437319.808 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:08:54.764Z,1743437334.764 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:09:10.501Z,1743437350.501 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-03-31T16:09:17.826Z,1743437357.826 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-31T16:09:24.690Z,1743437364.690 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:09:40.052Z,1743437380.052 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:10:18.405Z,1743437418.405 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-03-31T16:10:18.405Z,1743437418.405 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-31T16:10:18.440Z,1743437418.440 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-31T16:10:18.801Z,1743437418.801 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-31T16:10:18.802Z,1743437418.802 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-03-31T16:10:20.459Z,1743437420.459 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:10:32.977Z,1743437432.977 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:12:01.001Z,1743437521.001 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:12:13.158Z,1743437533.158 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:12:25.284Z,1743437545.284 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:12:37.804Z,1743437557.804 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:12:50.332Z,1743437570.332 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-31T16:12:51.245Z,1743437571.245 [CommandExec](IMPORTANT): got command restart application
2025-03-31T16:12:52.247Z,1743437572.247 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-03-31T16:12:52.248Z,1743437572.248 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:52.248Z,1743437572.248 [CommandExec](INFO): Uninitializing the command executive.
2025-03-31T16:12:52.248Z,1743437572.248 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-31T16:12:52.248Z,1743437572.248 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:52.423Z,1743437572.423 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-03-31T16:12:52.423Z,1743437572.423 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-31T16:12:52.424Z,1743437572.424 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:52.424Z,1743437572.424 [NavChartDb](INFO): Join timeout helper Thread ID is 6453
2025-03-31T16:12:52.508Z,1743437572.508 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:52.508Z,1743437572.508 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:52.523Z,1743437572.523 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-31T16:12:52.523Z,1743437572.523 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:52.524Z,1743437572.524 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6454
2025-03-31T16:12:53.511Z,1743437573.511 [CTD_Seabird](INFO): Powering down
2025-03-31T16:12:53.523Z,1743437573.523 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:53.524Z,1743437573.524 [CTD_Seabird](INFO): Powering down
2025-03-31T16:12:53.536Z,1743437573.536 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:53.555Z,1743437573.555 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-31T16:12:53.556Z,1743437573.556 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:53.556Z,1743437573.556 [Radio_Surface](INFO): Join timeout helper Thread ID is 6455
2025-03-31T16:12:53.871Z,1743437573.871 [Radio_Surface](INFO): Powering down
2025-03-31T16:12:53.872Z,1743437573.872 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:53.873Z,1743437573.873 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:53.875Z,1743437573.875 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-31T16:12:53.875Z,1743437573.875 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:53.876Z,1743437573.876 [Onboard](INFO): Join timeout helper Thread ID is 6456
2025-03-31T16:12:54.536Z,1743437574.536 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-31T16:12:56.616Z,1743437576.616 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:56.616Z,1743437576.616 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:56.635Z,1743437576.635 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-31T16:12:56.635Z,1743437576.635 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:56.636Z,1743437576.636 [DataOverHttps](INFO): Join timeout helper Thread ID is 6457
2025-03-31T16:12:57.560Z,1743437577.560 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:57.560Z,1743437577.560 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.581Z,1743437577.581 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-03-31T16:12:57.581Z,1743437577.581 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.581Z,1743437577.581 [BackseatComponent](INFO): Join timeout helper Thread ID is 6458
2025-03-31T16:12:57.759Z,1743437577.759 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:57.760Z,1743437577.760 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.771Z,1743437577.771 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-31T16:12:57.771Z,1743437577.771 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.772Z,1743437577.772 [logger](INFO): Join timeout helper Thread ID is 6459
2025-03-31T16:12:57.789Z,1743437577.789 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:57.789Z,1743437577.789 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.808Z,1743437577.808 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-31T16:12:57.808Z,1743437577.808 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.808Z,1743437577.808 [CommandLine](INFO): Join timeout helper Thread ID is 6460
2025-03-31T16:12:57.887Z,1743437577.887 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:57.888Z,1743437577.888 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.908Z,1743437577.908 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-31T16:12:57.908Z,1743437577.908 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.908Z,1743437577.908 [CommandExec](INFO): Join timeout helper Thread ID is 6461
2025-03-31T16:12:57.910Z,1743437577.910 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-31T16:12:57.910Z,1743437577.910 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:57.910Z,1743437577.910 [controlThread](INFO): Join timeout helper Thread ID is 6462
2025-03-31T16:12:57.951Z,1743437577.951 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-31T16:12:57.952Z,1743437577.952 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-31T16:12:57.952Z,1743437577.952 [AHRS_M2](INFO): Powering down
2025-03-31T16:12:58.032Z,1743437578.032 [NAL9602](INFO): Powering down
2025-03-31T16:12:58.033Z,1743437578.033 [Sonardyne_Nano](INFO): Powering down
2025-03-31T16:12:58.152Z,1743437578.152 [Waterlinked](INFO): Powering down
2025-03-31T16:12:58.236Z,1743437578.236 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-31T16:12:58.236Z,1743437578.236 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-31T16:12:58.237Z,1743437578.237 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-31T16:12:58.237Z,1743437578.237 [MissionManager](INFO): Uninitializing Mission Default
2025-03-31T16:12:58.237Z,1743437578.237 [Default] Stopped
2025-03-31T16:12:58.238Z,1743437578.238 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-31T16:12:58.238Z,1743437578.238 [Default:B.GoToSurface] Stopped
2025-03-31T16:12:58.238Z,1743437578.238 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-31T16:12:58.238Z,1743437578.238 [Default:CheckIn] Stopped
2025-03-31T16:12:58.238Z,1743437578.238 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-31T16:12:58.238Z,1743437578.238 [Default:CheckIn:Read_GPS] Stopped
2025-03-31T16:12:58.241Z,1743437578.241 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-31T16:12:58.241Z,1743437578.241 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-31T16:12:58.242Z,1743437578.242 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-31T16:12:58.242Z,1743437578.242 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-31T16:12:58.242Z,1743437578.242 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-31T16:12:58.242Z,1743437578.242 [BuoyancyServo](INFO): Powering down
2025-03-31T16:12:58.255Z,1743437578.255 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-31T16:12:58.256Z,1743437578.256 [ElevatorServo](INFO): Powering down
2025-03-31T16:12:58.256Z,1743437578.256 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-31T16:12:58.256Z,1743437578.256 [MassServo](INFO): Powering down
2025-03-31T16:12:58.257Z,1743437578.257 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-31T16:12:58.257Z,1743437578.257 [RudderServo](INFO): Powering down
2025-03-31T16:12:58.258Z,1743437578.258 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-31T16:12:58.258Z,1743437578.258 [ThrusterHE](INFO): Powering down
2025-03-31T16:12:58.259Z,1743437578.259 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-31T16:12:58.260Z,1743437578.260 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-31T16:12:58.260Z,1743437578.260 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-31T16:12:58.260Z,1743437578.260 [CBIT](DEBUG): Powering off loads.
2025-03-31T16:12:58.271Z,1743437578.271 [CBIT](DEBUG): Disabling WDT.
2025-03-31T16:12:58.283Z,1743437578.283 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-31T16:12:58.284Z,1743437578.284 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-31T16:12:58.285Z,1743437578.285 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:58.314Z,1743437578.314 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:58.317Z,1743437578.317 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:58.324Z,1743437578.324 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:58.368Z,1743437578.368 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:58.401Z,1743437578.401 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-31T16:12:58.401Z,1743437578.401 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-31T16:12:58.460Z,1743437578.460 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:58.469Z,1743437578.469 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-31T16:12:58.533Z,1743437578.533 [logger ThreadHandler](INFO): Thread cancelled.