2024-08-26T22:57:26.922Z,1724713046.922 [Supervisor](DEBUG): Initializing supervisor.
2024-08-26T22:57:26.926Z,1724713046.926 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-08-26T22:57:26.926Z,1724713046.926 [SyncHandler](INFO): Protected caller Thread ID is 2844
2024-08-26T22:57:26.927Z,1724713046.927 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-08-26T22:57:26.928Z,1724713046.928 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-08-26T22:57:26.928Z,1724713046.928 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2845
2024-08-26T22:57:26.932Z,1724713046.932 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-08-26T22:57:26.950Z,1724713046.950 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-08-26T22:57:26.951Z,1724713046.951 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-08-26T22:57:26.951Z,1724713046.951 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2846
2024-08-26T22:57:26.955Z,1724713046.955 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-08-26T22:57:26.956Z,1724713046.956 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-08-26T22:57:26.957Z,1724713046.957 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2847
2024-08-26T22:57:26.959Z,1724713046.959 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-08-26T22:57:26.959Z,1724713046.959 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-08-26T22:57:26.960Z,1724713046.960 [logger ThreadHandler](INFO): Protected caller Thread ID is 2848
2024-08-26T22:57:26.963Z,1724713046.963 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-08-26T22:57:26.964Z,1724713046.964 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-08-26T22:57:26.965Z,1724713046.965 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-08-26T22:57:27.073Z,1724713047.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-08-26T22:57:27.073Z,1724713047.073 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-08-26T22:57:27.318Z,1724713047.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-08-26T22:57:27.318Z,1724713047.318 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-08-26T22:57:27.594Z,1724713047.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-08-26T22:57:27.595Z,1724713047.595 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-08-26T22:57:27.715Z,1724713047.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-08-26T22:57:27.715Z,1724713047.715 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-08-26T22:57:28.483Z,1724713048.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-08-26T22:57:28.483Z,1724713048.483 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-08-26T22:57:29.003Z,1724713049.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-08-26T22:57:29.003Z,1724713049.003 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-08-26T22:57:29.554Z,1724713049.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-08-26T22:57:29.555Z,1724713049.555 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-08-26T22:57:29.988Z,1724713049.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-08-26T22:57:29.989Z,1724713049.989 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-08-26T22:57:30.355Z,1724713050.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-08-26T22:57:30.355Z,1724713050.355 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-08-26T22:57:30.830Z,1724713050.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-08-26T22:57:30.831Z,1724713050.831 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-08-26T22:57:31.077Z,1724713051.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-08-26T22:57:31.078Z,1724713051.078 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-08-26T22:57:31.230Z,1724713051.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-08-26T22:57:31.230Z,1724713051.230 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-08-26T22:57:31.323Z,1724713051.323 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-08-26T22:57:31.414Z,1724713051.414 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-08-26T22:57:31.612Z,1724713051.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-08-26T22:57:31.613Z,1724713051.613 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-08-26T22:57:32.458Z,1724713052.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-08-26T22:57:32.460Z,1724713052.460 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2024-08-26T22:57:32.461Z,1724713052.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2024-08-26T22:57:32.557Z,1724713052.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2024-08-26T22:57:32.831Z,1724713052.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-08-26T22:57:32.831Z,1724713052.831 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2024-08-26T22:57:32.940Z,1724713052.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2024-08-26T22:57:33.117Z,1724713053.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2024-08-26T22:57:33.227Z,1724713053.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2024-08-26T22:57:33.349Z,1724713053.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2024-08-26T22:57:33.473Z,1724713053.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2024-08-26T22:57:33.591Z,1724713053.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2024-08-26T22:57:33.764Z,1724713053.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2024-08-26T22:57:33.873Z,1724713053.873 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2024-08-26T22:57:33.969Z,1724713053.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2024-08-26T22:57:34.078Z,1724713054.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2024-08-26T22:57:34.182Z,1724713054.182 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2024-08-26T22:57:34.182Z,1724713054.182 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-08-26T22:57:34.189Z,1724713054.189 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-08-26T22:57:34.356Z,1724713054.356 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-08-26T22:57:34.357Z,1724713054.357 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-08-26T22:57:34.488Z,1724713054.488 [BuoyancyServo] Loaded
2024-08-26T22:57:34.489Z,1724713054.489 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-08-26T22:57:34.512Z,1724713054.512 [ElevatorServo] Loaded
2024-08-26T22:57:34.512Z,1724713054.512 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-08-26T22:57:34.534Z,1724713054.534 [MassServo] Loaded
2024-08-26T22:57:34.534Z,1724713054.534 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-08-26T22:57:34.556Z,1724713054.556 [RudderServo] Loaded
2024-08-26T22:57:34.556Z,1724713054.556 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-08-26T22:57:34.573Z,1724713054.573 [ThrusterHE] Loaded
2024-08-26T22:57:34.574Z,1724713054.574 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-08-26T22:57:34.574Z,1724713054.574 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-08-26T22:57:34.575Z,1724713054.575 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-08-26T22:57:34.708Z,1724713054.708 [SBIT](DEBUG): Construct Startup Built In Test.
2024-08-26T22:57:34.723Z,1724713054.723 [SBIT] Loaded
2024-08-26T22:57:34.723Z,1724713054.723 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-08-26T22:57:34.726Z,1724713054.726 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-08-26T22:57:34.746Z,1724713054.746 [IBIT] Loaded
2024-08-26T22:57:34.747Z,1724713054.747 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-08-26T22:57:34.752Z,1724713054.752 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-08-26T22:57:34.972Z,1724713054.972 [CBIT] Loaded
2024-08-26T22:57:34.972Z,1724713054.972 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-08-26T22:57:34.973Z,1724713054.973 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-08-26T22:57:34.973Z,1724713054.973 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-08-26T22:57:35.141Z,1724713055.141 [CTD_Seabird] Loaded
2024-08-26T22:57:35.141Z,1724713055.141 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-08-26T22:57:35.142Z,1724713055.142 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0
2024-08-26T22:57:35.142Z,1724713055.142 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2937
2024-08-26T22:57:35.177Z,1724713055.177 [ESPComponent] Loaded
2024-08-26T22:57:35.177Z,1724713055.177 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-08-26T22:57:35.198Z,1724713055.198 [PAR_Licor] Loaded
2024-08-26T22:57:35.199Z,1724713055.199 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-08-26T22:57:35.242Z,1724713055.242 [WetLabsBB2FL] Loaded
2024-08-26T22:57:35.242Z,1724713055.242 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-08-26T22:57:35.243Z,1724713055.243 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0
2024-08-26T22:57:35.244Z,1724713055.244 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2938
2024-08-26T22:57:35.244Z,1724713055.244 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-08-26T22:57:35.245Z,1724713055.245 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-08-26T22:57:35.294Z,1724713055.294 [DepthRateCalculator] Loaded
2024-08-26T22:57:35.295Z,1724713055.295 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-08-26T22:57:35.300Z,1724713055.300 [PitchRateCalculator] Loaded
2024-08-26T22:57:35.300Z,1724713055.300 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-08-26T22:57:35.310Z,1724713055.310 [SpeedCalculator] Loaded
2024-08-26T22:57:35.310Z,1724713055.310 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-08-26T22:57:35.315Z,1724713055.315 [YawRateCalculator] Loaded
2024-08-26T22:57:35.315Z,1724713055.315 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-08-26T22:57:35.334Z,1724713055.334 [ElevatorOffsetCalculator] Loaded
2024-08-26T22:57:35.335Z,1724713055.335 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-08-26T22:57:35.335Z,1724713055.335 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-08-26T22:57:35.336Z,1724713055.336 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-08-26T22:57:35.441Z,1724713055.441 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-08-26T22:57:35.441Z,1724713055.441 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-08-26T22:57:35.467Z,1724713055.467 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-08-26T22:57:35.468Z,1724713055.468 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-08-26T22:57:35.577Z,1724713055.577 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-08-26T22:57:35.578Z,1724713055.578 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-08-26T22:57:35.591Z,1724713055.591 [NavChart] Loaded
2024-08-26T22:57:35.592Z,1724713055.592 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-08-26T22:57:35.597Z,1724713055.597 [UniversalFixResidualReporter] Loaded
2024-08-26T22:57:35.597Z,1724713055.597 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-08-26T22:57:35.598Z,1724713055.598 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-08-26T22:57:35.598Z,1724713055.598 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-08-26T22:57:36.313Z,1724713056.313 [AHRS_M2] Loaded
2024-08-26T22:57:36.313Z,1724713056.313 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-08-26T22:57:36.352Z,1724713056.352 [BackseatComponent] Loaded
2024-08-26T22:57:36.352Z,1724713056.352 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-08-26T22:57:36.353Z,1724713056.353 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A694E0
2024-08-26T22:57:36.353Z,1724713056.353 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2939
2024-08-26T22:57:36.356Z,1724713056.356 [LcmUniversalReporter] Loaded
2024-08-26T22:57:36.357Z,1724713056.357 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-08-26T22:57:37.099Z,1724713057.099 [BPC1] Loaded
2024-08-26T22:57:37.099Z,1724713057.099 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-08-26T22:57:37.208Z,1724713057.208 [DATMMP] Loaded
2024-08-26T22:57:37.208Z,1724713057.208 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-08-26T22:57:37.209Z,1724713057.209 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0
2024-08-26T22:57:37.209Z,1724713057.209 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 2940
2024-08-26T22:57:37.446Z,1724713057.446 [DataOverHttps] Loaded
2024-08-26T22:57:37.447Z,1724713057.447 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-08-26T22:57:37.448Z,1724713057.448 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0
2024-08-26T22:57:37.467Z,1724713057.467 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2941
2024-08-26T22:57:37.532Z,1724713057.532 [Depth_Keller] Loaded
2024-08-26T22:57:37.533Z,1724713057.533 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-08-26T22:57:37.538Z,1724713057.538 [DropWeight] Loaded
2024-08-26T22:57:37.538Z,1724713057.538 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-08-26T22:57:37.579Z,1724713057.579 [DVL_micro] Loaded
2024-08-26T22:57:37.580Z,1724713057.580 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-08-26T22:57:37.649Z,1724713057.649 [NAL9602] Loaded
2024-08-26T22:57:37.649Z,1724713057.649 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-08-26T22:57:37.675Z,1724713057.675 [Onboard] Loaded
2024-08-26T22:57:37.675Z,1724713057.675 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-08-26T22:57:37.676Z,1724713057.676 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0
2024-08-26T22:57:37.677Z,1724713057.677 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2942
2024-08-26T22:57:37.691Z,1724713057.691 [Power24vConverter] Loaded
2024-08-26T22:57:37.691Z,1724713057.691 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-08-26T22:57:37.708Z,1724713057.708 [Radio_Surface] Loaded
2024-08-26T22:57:37.709Z,1724713057.709 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-08-26T22:57:37.710Z,1724713057.710 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B2A4E0
2024-08-26T22:57:37.710Z,1724713057.710 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2943
2024-08-26T22:57:37.726Z,1724713057.726 [Sonardyne_Nano] Loaded
2024-08-26T22:57:37.726Z,1724713057.726 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-08-26T22:57:37.726Z,1724713057.726 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-08-26T22:57:37.727Z,1724713057.727 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-08-26T22:57:37.794Z,1724713057.794 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-08-26T22:57:37.794Z,1724713057.794 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-08-26T22:57:37.844Z,1724713057.844 [VerticalControl](DEBUG): Construct VerticalControl.
2024-08-26T22:57:37.895Z,1724713057.895 [VerticalControl] Loaded
2024-08-26T22:57:37.895Z,1724713057.895 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-08-26T22:57:37.898Z,1724713057.898 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-08-26T22:57:37.941Z,1724713057.941 [HorizontalControl] Loaded
2024-08-26T22:57:37.942Z,1724713057.942 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-08-26T22:57:37.944Z,1724713057.944 [SpeedControl](DEBUG): Construct SpeedControl.
2024-08-26T22:57:37.946Z,1724713057.946 [SpeedControl] Loaded
2024-08-26T22:57:37.946Z,1724713057.946 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-08-26T22:57:37.949Z,1724713057.949 [LoopControl](DEBUG): Construct LoopControl.
2024-08-26T22:57:37.949Z,1724713057.949 [LoopControl] Loaded
2024-08-26T22:57:37.949Z,1724713057.949 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-08-26T22:57:37.950Z,1724713057.950 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-08-26T22:57:37.950Z,1724713057.950 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-08-26T22:57:37.960Z,1724713057.960 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-08-26T22:57:37.961Z,1724713057.961 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-08-26T22:57:38.282Z,1724713058.282 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-08-26T22:57:38.289Z,1724713058.289 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-08-26T22:57:38.291Z,1724713058.291 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-08-26T22:57:38.303Z,1724713058.303 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-08-26T22:57:38.304Z,1724713058.304 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-08-26T22:57:38.304Z,1724713058.304 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2944
2024-08-26T22:57:38.309Z,1724713058.309 [Supervisor](INFO): Main Thread ID is 2843
2024-08-26T22:57:38.309Z,1724713058.309 [Supervisor](DEBUG): Running supervisor.
2024-08-26T22:57:38.309Z,1724713058.309 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2945
2024-08-26T22:57:38.310Z,1724713058.310 [CommandExec](INFO): Initializing the command executive.
2024-08-26T22:57:38.311Z,1724713058.311 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2946
2024-08-26T22:57:38.314Z,1724713058.314 [controlThread ThreadHandler](INFO): Handler Thread ID is 2947
2024-08-26T22:57:38.314Z,1724713058.314 [controlThread](DEBUG): Initializing ControlThread
2024-08-26T22:57:38.316Z,1724713058.316 [SBIT](INFO): Initialize SBIT Component.
2024-08-26T22:57:38.316Z,1724713058.316 [SBIT](IMPORTANT): git: 2024-08-12-95-g598b95f60
2024-08-26T22:57:38.316Z,1724713058.316 [SBIT](INFO): git hash: 598b95f6033d24136933a3d5d1ddac5d4570ac95
2024-08-26T22:57:38.317Z,1724713058.317 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-08-26T22:57:38.318Z,1724713058.318 [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
2024-08-26T22:57:38.319Z,1724713058.319 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2024-08-26T22:57:38.320Z,1724713058.320 [IBIT](INFO): Initialize IBIT Component.
2024-08-26T22:57:38.321Z,1724713058.321 [CBIT](DEBUG): Initialize CBIT Component.
2024-08-26T22:57:38.322Z,1724713058.322 [logger ThreadHandler](INFO): Handler Thread ID is 2948
2024-08-26T22:57:38.332Z,1724713058.332 [CBIT](DEBUG): Initialized mux pins.
2024-08-26T22:57:38.332Z,1724713058.332 [CBIT](DEBUG): Initializing the watchdog timer.
2024-08-26T22:57:38.340Z,1724713058.340 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2949
2024-08-26T22:57:38.341Z,1724713058.341 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-08-26T22:57:38.345Z,1724713058.345 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2951
2024-08-26T22:57:38.348Z,1724713058.348 [WetLabsBB2FL](INFO): Powering up
2024-08-26T22:57:38.350Z,1724713058.350 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2953
2024-08-26T22:57:38.356Z,1724713058.356 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-08-26T22:57:38.356Z,1724713058.356 [CBIT](DEBUG): Initializing heartbeat.
2024-08-26T22:57:38.357Z,1724713058.357 [DATMMP ThreadHandler](INFO): Handler Thread ID is 2954
2024-08-26T22:57:38.358Z,1724713058.358 [DATMMP](INFO): Start
2024-08-26T22:57:38.358Z,1724713058.358 [DATMMP](INFO): Powering up
2024-08-26T22:57:38.358Z,1724713058.358 [DATMMP](DEBUG): Initializing DATMMP.
2024-08-26T22:57:38.361Z,1724713058.361 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2956
2024-08-26T22:57:38.363Z,1724713058.363 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-08-26T22:57:38.373Z,1724713058.373 [Onboard ThreadHandler](INFO): Handler Thread ID is 2957
2024-08-26T22:57:38.390Z,1724713058.390 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2958
2024-08-26T22:57:38.408Z,1724713058.408 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2959
2024-08-26T22:57:38.410Z,1724713058.410 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK1NORSO.000
2024-08-26T22:57:38.410Z,1724713058.410 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK1NORSO.000
2024-08-26T22:57:38.411Z,1724713058.411 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK2NORS.000
2024-08-26T22:57:38.411Z,1724713058.411 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK2NORS.000
2024-08-26T22:57:38.411Z,1724713058.411 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/DK3HORRO.000
2024-08-26T22:57:38.411Z,1724713058.411 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from DK3HORRO.000
2024-08-26T22:57:38.428Z,1724713058.428 [CBIT](DEBUG): Deactivating GF circuits.
2024-08-26T22:57:38.428Z,1724713058.428 [CBIT](DEBUG): Deactivating emergency mode.
2024-08-26T22:57:38.468Z,1724713058.468 [CBIT](DEBUG): Backplane powered.
2024-08-26T22:57:38.472Z,1724713058.472 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-08-26T22:57:38.473Z,1724713058.473 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-08-26T22:57:38.473Z,1724713058.473 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-08-26T22:57:38.473Z,1724713058.473 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-08-26T22:57:38.474Z,1724713058.474 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-08-26T22:57:38.475Z,1724713058.475 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-08-26T22:57:38.475Z,1724713058.475 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-08-26T22:57:38.494Z,1724713058.494 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-08-26T22:57:38.496Z,1724713058.496 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-08-26T22:57:38.497Z,1724713058.497 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-08-26T22:57:38.497Z,1724713058.497 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-08-26T22:57:38.498Z,1724713058.498 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-08-26T22:57:38.499Z,1724713058.499 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-08-26T22:57:38.499Z,1724713058.499 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-08-26T22:57:38.508Z,1724713058.508 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-08-26T22:57:38.577Z,1724713058.577 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-08-26T22:57:38.602Z,1724713058.602 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-26T22:57:38.612Z,1724713058.612 [DATMMP](INFO): Starting
2024-08-26T22:57:38.639Z,1724713058.639 [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
}
}
}
2024-08-26T22:57:38.639Z,1724713058.639 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-08-26T22:57:38.639Z,1724713058.639 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-08-26T22:57:38.640Z,1724713058.640 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-08-26T22:57:38.796Z,1724713058.796 [Radio_Surface](INFO): Powering up
2024-08-26T22:57:38.864Z,1724713058.864 [DATMMP](INFO): Starting
2024-08-26T22:57:38.941Z,1724713058.941 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-08-26T22:57:38.963Z,1724713058.963 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-08-26T22:57:38.964Z,1724713058.964 [Default:A.Wait](DEBUG): Construct Wait.
2024-08-26T22:57:38.966Z,1724713058.966 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-08-26T22:57:39.015Z,1724713059.015 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-08-26T22:57:39.017Z,1724713059.017 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-08-26T22:57:39.035Z,1724713059.035 [Default:E.Execute](DEBUG): Construct Execute.
2024-08-26T22:57:39.062Z,1724713059.062 [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"
}
}
2024-08-26T22:57:39.066Z,1724713059.066 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-26T22:57:39.105Z,1724713059.105 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-26T22:57:39.116Z,1724713059.116 [DATMMP](INFO): Starting
2024-08-26T22:57:39.169Z,1724713059.169 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2024-08-26T22:57:39.177Z,1724713059.177 [Power24vConverter](INFO): Powering up.
2024-08-26T22:57:39.178Z,1724713059.178 [Sonardyne_Nano](INFO): Initializing.
2024-08-26T22:57:39.204Z,1724713059.204 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-08-26T22:57:39.229Z,1724713059.229 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-08-26T22:57:39.236Z,1724713059.236 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-08-26T22:57:39.237Z,1724713059.237 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-08-26T22:57:39.244Z,1724713059.244 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-08-26T22:57:39.245Z,1724713059.245 [MassServo](DEBUG): Initializing EZServoServo.
2024-08-26T22:57:39.252Z,1724713059.252 [MassServo](DEBUG): Initializing MassServo.
2024-08-26T22:57:39.253Z,1724713059.253 [RudderServo](DEBUG): Initializing EZServoServo.
2024-08-26T22:57:39.260Z,1724713059.260 [RudderServo](DEBUG): Initializing RudderServo.
2024-08-26T22:57:39.261Z,1724713059.261 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-08-26T22:57:39.268Z,1724713059.268 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-08-26T22:57:39.368Z,1724713059.368 [DATMMP](INFO): Starting
2024-08-26T22:57:39.526Z,1724713059.526 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-08-26T22:57:39.526Z,1724713059.526 [DropWeight] Hardware Fault, FailCount= 1
2024-08-26T22:57:39.526Z,1724713059.526 [DropWeight](ERROR): Hardware Fault
2024-08-26T22:57:39.562Z,1724713059.562 [CommandExec](FAULT): Scheduling is paused
2024-08-26T22:57:39.562Z,1724713059.562 [CBIT](INFO): Critical error at 20240826T225739
2024-08-26T22:57:39.562Z,1724713059.562 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-08-26T22:57:39.573Z,1724713059.573 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-08-26T22:57:39.574Z,1724713059.574 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-08-26T22:57:39.620Z,1724713059.620 [DATMMP](INFO): Starting
2024-08-26T22:57:39.872Z,1724713059.872 [DATMMP](INFO): Starting
2024-08-26T22:57:39.902Z,1724713059.902 [CBIT](INFO): Critical error at 20240826T225739
2024-08-26T22:57:40.124Z,1724713060.124 [DATMMP](INFO): Starting
2024-08-26T22:57:40.376Z,1724713060.376 [DATMMP](INFO): Starting
2024-08-26T22:57:40.453Z,1724713060.453 [WetLabsBB2FL](INFO): Powering down
2024-08-26T22:57:40.628Z,1724713060.628 [DATMMP](INFO): Starting
2024-08-26T22:57:40.880Z,1724713060.880 [DATMMP](INFO): Starting
2024-08-26T22:57:41.132Z,1724713061.132 [DATMMP](INFO): Starting
2024-08-26T22:57:41.384Z,1724713061.384 [DATMMP](INFO): Starting
2024-08-26T22:57:41.503Z,1724713061.503 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-08-26T22:57:41.636Z,1724713061.636 [DATMMP](INFO): Starting
2024-08-26T22:57:41.888Z,1724713061.888 [DATMMP](INFO): Starting
2024-08-26T22:57:42.140Z,1724713062.140 [DATMMP](INFO): Starting
2024-08-26T22:57:42.392Z,1724713062.392 [DATMMP](INFO): Starting
2024-08-26T22:57:42.644Z,1724713062.644 [DATMMP](INFO): Starting
2024-08-26T22:57:42.741Z,1724713062.741 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2024-08-26T22:57:42.896Z,1724713062.896 [DATMMP](INFO): Starting
2024-08-26T22:57:43.148Z,1724713063.148 [DATMMP](INFO): Starting
2024-08-26T22:57:43.400Z,1724713063.400 [DATMMP](INFO): Starting
2024-08-26T22:57:43.652Z,1724713063.652 [DATMMP](INFO): Starting
2024-08-26T22:57:43.904Z,1724713063.904 [DATMMP](INFO): Starting
2024-08-26T22:57:44.156Z,1724713064.156 [DATMMP](INFO): Starting
2024-08-26T22:57:44.408Z,1724713064.408 [DATMMP](INFO): Starting
2024-08-26T22:57:44.660Z,1724713064.660 [DATMMP](INFO): Starting
2024-08-26T22:57:44.912Z,1724713064.912 [DATMMP](INFO): Starting
2024-08-26T22:57:44.973Z,1724713064.973 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-08-26T22:57:45.164Z,1724713065.164 [DATMMP](INFO): Starting
2024-08-26T22:57:45.416Z,1724713065.416 [DATMMP](INFO): Starting
2024-08-26T22:57:45.668Z,1724713065.668 [DATMMP](INFO): Starting
2024-08-26T22:57:45.920Z,1724713065.920 [DATMMP](INFO): Starting
2024-08-26T22:57:46.172Z,1724713066.172 [DATMMP](INFO): Starting
2024-08-26T22:57:46.424Z,1724713066.424 [DATMMP](INFO): Starting
2024-08-26T22:57:46.676Z,1724713066.676 [DATMMP](INFO): Starting
2024-08-26T22:57:46.928Z,1724713066.928 [DATMMP](INFO): Starting
2024-08-26T22:57:47.180Z,1724713067.180 [DATMMP](INFO): Starting
2024-08-26T22:57:47.432Z,1724713067.432 [DATMMP](INFO): Starting
2024-08-26T22:57:47.684Z,1724713067.684 [DATMMP](INFO): Starting
2024-08-26T22:57:47.936Z,1724713067.936 [DATMMP](INFO): Starting
2024-08-26T22:57:48.188Z,1724713068.188 [DATMMP](INFO): Starting
2024-08-26T22:57:48.440Z,1724713068.440 [DATMMP](INFO): Starting
2024-08-26T22:57:48.692Z,1724713068.692 [DATMMP](INFO): Starting
2024-08-26T22:57:48.944Z,1724713068.944 [DATMMP](INFO): Starting
2024-08-26T22:57:49.196Z,1724713069.196 [DATMMP](INFO): Starting
2024-08-26T22:57:49.448Z,1724713069.448 [DATMMP](INFO): Starting
2024-08-26T22:57:49.700Z,1724713069.700 [DATMMP](INFO): Starting
2024-08-26T22:57:49.952Z,1724713069.952 [DATMMP](INFO): Starting
2024-08-26T22:57:50.204Z,1724713070.204 [DATMMP](INFO): Starting
2024-08-26T22:57:50.457Z,1724713070.457 [DATMMP](INFO): Starting
2024-08-26T22:57:50.458Z,1724713070.458 [DATMMP](INFO): DAT read:
2024-08-26T22:57:50.459Z,1724713070.459 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-08-26T22:57:50.708Z,1724713070.708 [DATMMP](INFO): Starting
2024-08-26T22:57:50.960Z,1724713070.960 [DATMMP](INFO): Starting
2024-08-26T22:57:51.212Z,1724713071.212 [DATMMP](INFO): Starting
2024-08-26T22:57:51.464Z,1724713071.464 [DATMMP](INFO): Starting
2024-08-26T22:57:51.716Z,1724713071.716 [DATMMP](INFO): Starting
2024-08-26T22:57:51.968Z,1724713071.968 [DATMMP](INFO): Starting
2024-08-26T22:57:51.969Z,1724713071.969 [DATMMP](INFO): DAT read: MF Frequency Band
2024-08-26T22:57:51.970Z,1724713071.970 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-08-26T22:57:51.971Z,1724713071.971 [DATMMP](INFO): DAT read: Jul 26 2000 22:57:46
2024-08-26T22:57:52.220Z,1724713072.220 [DATMMP](INFO): Starting
2024-08-26T22:57:52.472Z,1724713072.472 [DATMMP](INFO): Starting
2024-08-26T22:57:52.538Z,1724713072.538 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004885
2024-08-26T22:57:52.724Z,1724713072.724 [DATMMP](INFO): Starting
2024-08-26T22:57:52.976Z,1724713072.976 [DATMMP](INFO): Starting
2024-08-26T22:57:53.228Z,1724713073.228 [DATMMP](INFO): Starting
2024-08-26T22:57:53.229Z,1724713073.229 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-08-26T22:57:53.230Z,1724713073.230 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-08-26T22:57:53.230Z,1724713073.230 [DATMMP](INFO): commRate: 1200
2024-08-26T22:57:53.480Z,1724713073.480 [DATMMP](INFO): Starting
2024-08-26T22:57:53.732Z,1724713073.732 [DATMMP](INFO): Starting
2024-08-26T22:57:53.984Z,1724713073.984 [DATMMP](INFO): Starting
2024-08-26T22:57:54.236Z,1724713074.236 [DATMMP](INFO): Starting
2024-08-26T22:57:54.488Z,1724713074.488 [DATMMP](INFO): Starting
2024-08-26T22:57:54.740Z,1724713074.740 [DATMMP](INFO): Starting
2024-08-26T22:57:54.992Z,1724713074.992 [DATMMP](INFO): Starting
2024-08-26T22:57:55.244Z,1724713075.244 [DATMMP](INFO): Starting
2024-08-26T22:57:55.296Z,1724713075.296 [DATMMP](INFO): entering command mode
2024-08-26T22:57:55.496Z,1724713075.496 [DATMMP](INFO): Starting
2024-08-26T22:57:55.496Z,1724713075.496 [DATMMP](INFO): DAT read:
2024-08-26T22:57:55.497Z,1724713075.497 [DATMMP](INFO): DAT read: user:1>
2024-08-26T22:57:55.497Z,1724713075.497 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-08-26T22:57:55.498Z,1724713075.497 [DATMMP](INFO): entering MMP mode
2024-08-26T22:57:55.748Z,1724713075.748 [DATMMP](INFO): Starting
2024-08-26T22:57:55.749Z,1724713075.749 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:57:55.750Z,1724713075.750 [DATMMP](INFO): status rx: x1
2024-08-26T22:57:55.751Z,1724713075.751 [DATMMP](INFO): MMP status: 1: started
2024-08-26T22:57:55.751Z,1724713075.751 [DATMMP](INFO): Received message type: status
2024-08-26T22:57:55.751Z,1724713075.751 [DATMMP](INFO): Handled
2024-08-26T22:57:56.001Z,1724713076.001 [DATMMP](INFO): Starting
2024-08-26T22:57:56.003Z,1724713076.003 [DATMMP](INFO): Sent Tx Power Config 8
2024-08-26T22:57:56.003Z,1724713076.003 [DATMMP](INFO): Setting local address: 5
2024-08-26T22:57:56.013Z,1724713076.013 [DATMMP](INFO): Setting time to: 22:57:56 And date to:8/26/2024
2024-08-26T22:57:56.252Z,1724713076.252 [DATMMP](INFO): Starting
2024-08-26T22:57:56.253Z,1724713076.253 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:57:56.253Z,1724713076.253 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-08-26T22:57:56.254Z,1724713076.254 [DATMMP](INFO): Received message type: mdmpower
2024-08-26T22:57:56.254Z,1724713076.254 [DATMMP](INFO): Handled
2024-08-26T22:57:56.504Z,1724713076.504 [DATMMP](INFO): Starting
2024-08-26T22:57:56.505Z,1724713076.505 [DATMMP](INFO): GSXN notify for xid: 10
2024-08-26T22:57:56.506Z,1724713076.506 [DATMMP](INFO): sreg_txpower rx: x8
2024-08-26T22:57:56.506Z,1724713076.506 [DATMMP](INFO): Received message type: sreg_txpower
2024-08-26T22:57:56.506Z,1724713076.506 [DATMMP](INFO): Match for : sreg_txpower
2024-08-26T22:57:56.506Z,1724713076.506 [DATMMP](INFO): ACK
2024-08-26T22:57:56.756Z,1724713076.756 [DATMMP](INFO): Starting
2024-08-26T22:57:56.757Z,1724713076.757 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:57:56.759Z,1724713076.759 [DATMMP](INFO): mdmlocal rx: x5 x0 x0 x0
2024-08-26T22:57:56.759Z,1724713076.759 [DATMMP](INFO): Received message type: mdmlocal
2024-08-26T22:57:56.759Z,1724713076.759 [DATMMP](INFO): Handled
2024-08-26T22:57:57.008Z,1724713077.008 [DATMMP](INFO): Starting
2024-08-26T22:57:57.009Z,1724713077.009 [DATMMP](INFO): GSXN notify for xid: 11
2024-08-26T22:57:57.009Z,1724713077.009 [DATMMP](INFO): sreg_locaddr rx: x5
2024-08-26T22:57:57.009Z,1724713077.009 [DATMMP](INFO): Received message type: sreg_locaddr
2024-08-26T22:57:57.009Z,1724713077.009 [DATMMP](INFO): Match for : sreg_locaddr
2024-08-26T22:57:57.010Z,1724713077.010 [DATMMP](INFO): ACK
2024-08-26T22:57:57.260Z,1724713077.260 [DATMMP](INFO): Starting
2024-08-26T22:57:57.260Z,1724713077.260 [DATMMP](INFO): GSXN notify for xid: 12
2024-08-26T22:57:57.262Z,1724713077.262 [DATMMP](INFO): timedate rx: x0 x16 x39 x38 x7 x1A x7C x0
2024-08-26T22:57:57.262Z,1724713077.262 [DATMMP](INFO): Received message type: timedate
2024-08-26T22:57:57.262Z,1724713077.262 [DATMMP](INFO): Match for : timedate
2024-08-26T22:57:57.262Z,1724713077.262 [DATMMP](INFO): ACK
2024-08-26T22:57:57.512Z,1724713077.512 [DATMMP](INFO): Starting
2024-08-26T22:57:57.513Z,1724713077.513 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:57:57.513Z,1724713077.513 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-08-26T22:57:57.514Z,1724713077.514 [DATMMP](INFO): Received message type: sys_verb
2024-08-26T22:57:57.514Z,1724713077.514 [DATMMP](INFO): Handled
2024-08-26T22:57:57.764Z,1724713077.764 [DATMMP](INFO): Starting
2024-08-26T22:57:57.764Z,1724713077.764 [DATMMP](INFO): GSXN notify for xid: 13
2024-08-26T22:57:57.765Z,1724713077.765 [DATMMP](INFO): sreg_verbosity rx: x0
2024-08-26T22:57:57.765Z,1724713077.765 [DATMMP](INFO): Received message type: sreg_verbosity
2024-08-26T22:57:57.765Z,1724713077.765 [DATMMP](INFO): Match for : sreg_verbosity
2024-08-26T22:57:57.766Z,1724713077.766 [DATMMP](INFO): ACK
2024-08-26T22:57:58.016Z,1724713078.016 [DATMMP](INFO): Starting
2024-08-26T22:57:58.017Z,1724713078.017 [DATMMP](INFO): GSXN notify for xid: 14
2024-08-26T22:57:58.017Z,1724713078.017 [DATMMP](INFO): version rx: x2 x5
2024-08-26T22:57:58.018Z,1724713078.018 [DATMMP](INFO): DatMMPVersion: 2.5
2024-08-26T22:57:58.018Z,1724713078.018 [DATMMP](INFO): Received message type: version
2024-08-26T22:57:58.018Z,1724713078.018 [DATMMP](INFO): Match for : version
2024-08-26T22:57:58.018Z,1724713078.018 [DATMMP](INFO): ACK
2024-08-26T22:57:58.018Z,1724713078.018 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-08-26T22:58:03.412Z,1724713083.412 [CommandExec](IMPORTANT): got command failComponent
2024-08-26T22:58:03.413Z,1724713083.413 [CommandExec](IMPORTANT): Failed components:
2024-08-26T22:58:03.413Z,1724713083.413 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2024-08-26T22:58:07.766Z,1724713087.766 [NAL9602](INFO): Powering up NAL9602
2024-08-26T22:58:11.389Z,1724713091.389 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:58:11.389Z,1724713091.389 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-08-26T22:58:11.390Z,1724713091.390 [DATMMP](INFO): Received message type: timesync
2024-08-26T22:58:11.390Z,1724713091.390 [DATMMP](INFO): Handled
2024-08-26T22:58:18.675Z,1724713098.675 [NAL9602](INFO): NAL9602 initialized
2024-08-26T22:58:21.224Z,1724713101.224 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:58:21.225Z,1724713101.225 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-08-26T22:58:21.225Z,1724713101.225 [DATMMP](INFO): Received message type: timesync
2024-08-26T22:58:21.226Z,1724713101.226 [DATMMP](INFO): Handled
2024-08-26T22:58:31.310Z,1724713111.310 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:58:31.311Z,1724713111.311 [DATMMP](INFO): timesync rx: x0 x0 x0 x0
2024-08-26T22:58:31.311Z,1724713111.311 [DATMMP](INFO): Received message type: timesync
2024-08-26T22:58:31.312Z,1724713111.312 [DATMMP](INFO): Handled
2024-08-26T22:58:38.102Z,1724713118.102 [SBIT](IMPORTANT): Beginning Startup BIT
2024-08-26T22:58:38.107Z,1724713118.107 [CBIT](IMPORTANT): Beginning ground fault scan
2024-08-26T22:58:49.436Z,1724713129.436 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006151
CHAN A1 (24V): 0.001424
CHAN A2 (12V): -0.004958
CHAN A3 (5V): -0.003167
CHAN B0 (3.3V): -0.000424
CHAN B1 (3.15aV): -0.001571
CHAN B2 (3.15bV): -0.001467
CHAN B3 (GND): -0.000598
OPEN: 0.006722
Full Scale: +/- 1 mA
2024-08-26T22:58:52.229Z,1724713132.229 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:58:52.230Z,1724713132.230 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-08-26T22:58:52.230Z,1724713132.230 [DATMMP](INFO): Received message type: timesync
2024-08-26T22:58:52.230Z,1724713132.230 [DATMMP](INFO): Handled
2024-08-26T22:59:02.309Z,1724713142.309 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T22:59:02.309Z,1724713142.309 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-08-26T22:59:02.310Z,1724713142.310 [DATMMP](INFO): Received message type: timesync
2024-08-26T22:59:02.310Z,1724713142.310 [DATMMP](INFO): Handled
2024-08-26T22:59:05.609Z,1724713145.609 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-26T22:59:31.465Z,1724713171.465 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-26T22:59:32.247Z,1724713172.247 [SBIT](IMPORTANT): SBIT PASSED
2024-08-26T22:59:32.247Z,1724713172.247 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-08-26T22:59:32.248Z,1724713172.248 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-08-26T22:59:32.248Z,1724713172.248 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-08-26T22:59:32.248Z,1724713172.248 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-08-26T22:59:32.248Z,1724713172.248 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-08-26T22:59:32.248Z,1724713172.248 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=88.359306 cubic_centimeter;
2024-08-26T22:59:32.248Z,1724713172.248 [SBIT](IMPORTANT): VerticalControl.massDefault=1 centimeter;
2024-08-26T22:59:32.629Z,1724713172.629 [MissionManager](IMPORTANT): Started mission Startup
2024-08-26T22:59:32.629Z,1724713172.629 [Startup] Running Loop=1
2024-08-26T22:59:32.629Z,1724713172.629 [Startup](DEBUG): Aggregate::initialize Startup
2024-08-26T22:59:32.629Z,1724713172.629 [Startup:A.GoToSurface] Running Loop=1
2024-08-26T22:59:32.629Z,1724713172.629 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-26T22:59:32.630Z,1724713172.630 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-26T22:59:32.630Z,1724713172.630 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-26T22:59:32.631Z,1724713172.631 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-26T22:59:32.631Z,1724713172.631 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-26T22:59:32.631Z,1724713172.631 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-26T22:59:32.632Z,1724713172.632 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-26T22:59:32.633Z,1724713172.633 [Startup:StartupSatComms] Running Loop=1
2024-08-26T22:59:32.634Z,1724713172.634 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-08-26T22:59:32.634Z,1724713172.634 [Startup:StartupSatComms:A] Running Loop=1
2024-08-26T22:59:33.037Z,1724713173.037 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-08-26T23:00:32.907Z,1724713232.907 [Startup:StartupSatComms:A](INFO): Timed out from 2024-08-26T22:59:32.6Z
2024-08-26T23:00:32.907Z,1724713232.907 [Startup:StartupSatComms:A] Stopped
2024-08-26T23:00:32.927Z,1724713232.927 [Startup:StartupSatComms:B] Running Loop=1
2024-08-26T23:00:33.294Z,1724713233.294 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-26T23:00:38.652Z,1724713238.652 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-08-26T23:00:38.652Z,1724713238.652 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:00:38.670Z,1724713238.670 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:00:38.929Z,1724713238.929 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:00:38.929Z,1724713238.929 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-08-26T23:00:40.819Z,1724713240.819 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240826T223000/Courier0010.lzma
2024-08-26T23:00:41.822Z,1724713241.822 [DataOverHttps](INFO): Moved sent file to Logs/20240826T223000/Courier0010.lzma.bak
2024-08-26T23:00:41.822Z,1724713241.822 [DataOverHttps](INFO): SBD MOMSN=23068063
2024-08-26T23:00:58.049Z,1724713258.049 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20240826T225726/Courier0000.lzma
2024-08-26T23:00:59.050Z,1724713259.050 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0000.lzma.bak
2024-08-26T23:00:59.050Z,1724713259.050 [DataOverHttps](INFO): SBD MOMSN=23068065
2024-08-26T23:01:15.563Z,1724713275.563 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20240826T223000/Express0011.lzma
2024-08-26T23:01:16.566Z,1724713276.566 [DataOverHttps](INFO): Moved sent file to Logs/20240826T223000/Express0011.lzma.bak
2024-08-26T23:01:16.566Z,1724713276.566 [DataOverHttps](INFO): SBD MOMSN=23068070
2024-08-26T23:01:17.666Z,1724713277.666 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-08-26T23:01:18.188Z,1724713278.188 [NAL9602](ERROR): received:
2024-08-26T23:01:33.262Z,1724713293.262 [Startup:StartupSatComms:B](INFO): Timed out from 2024-08-26T23:00:32.9Z
2024-08-26T23:01:33.262Z,1724713293.262 [Startup:StartupSatComms:B] Stopped
2024-08-26T23:01:33.262Z,1724713293.262 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-08-26T23:01:33.262Z,1724713293.262 [Startup:StartupSatComms] Stopped
2024-08-26T23:01:33.262Z,1724713293.262 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-08-26T23:01:33.263Z,1724713293.263 [Startup](INFO): Completed Startup
2024-08-26T23:01:33.263Z,1724713293.263 [MissionManager](INFO): Startup is completed.
2024-08-26T23:01:33.264Z,1724713293.264 [MissionManager](INFO): Uninitializing Mission Startup
2024-08-26T23:01:33.264Z,1724713293.264 [Startup] Stopped
2024-08-26T23:01:33.264Z,1724713293.264 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-08-26T23:01:33.264Z,1724713293.264 [Startup:A.GoToSurface] Stopped
2024-08-26T23:01:33.264Z,1724713293.264 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-26T23:01:33.677Z,1724713293.677 [MissionManager](IMPORTANT): Started mission Default
2024-08-26T23:01:33.677Z,1724713293.677 [Default] Running Loop=1
2024-08-26T23:01:33.677Z,1724713293.677 [Default](DEBUG): Aggregate::initialize Default
2024-08-26T23:01:33.677Z,1724713293.677 [Default:B.GoToSurface] Running Loop=1
2024-08-26T23:01:33.677Z,1724713293.677 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-08-26T23:01:33.678Z,1724713293.678 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-08-26T23:01:33.678Z,1724713293.678 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-08-26T23:01:33.678Z,1724713293.678 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-08-26T23:01:33.678Z,1724713293.678 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-08-26T23:01:33.679Z,1724713293.679 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-08-26T23:01:33.679Z,1724713293.679 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-08-26T23:01:33.679Z,1724713293.679 [Default:A.Wait] Running Loop=1
2024-08-26T23:01:33.679Z,1724713293.679 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-08-26T23:01:33.974Z,1724713293.974 [DataOverHttps](INFO): Sending 889 bytes from file Logs/20240826T225726/Express0001.lzma
2024-08-26T23:01:34.973Z,1724713294.973 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0001.lzma.bak
2024-08-26T23:01:34.974Z,1724713294.974 [DataOverHttps](INFO): SBD MOMSN=23068078
2024-08-26T23:01:47.011Z,1724713307.011 [Default:A.Wait](INFO): Done Waiting.
2024-08-26T23:01:47.011Z,1724713307.011 [Default:A.Wait] Stopped
2024-08-26T23:01:47.011Z,1724713307.011 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-08-26T23:01:47.377Z,1724713307.377 [Default:CheckIn] Running Loop=1
2024-08-26T23:01:47.377Z,1724713307.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-26T23:01:47.377Z,1724713307.377 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-26T23:01:47.784Z,1724713307.784 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-08-26T23:01:49.826Z,1724713309.826 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-26T23:03:13.396Z,1724713393.396 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-08-26T23:03:13.920Z,1724713393.920 [NAL9602](ERROR): received:
2024-08-26T23:03:21.654Z,1724713401.654 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-08-26T23:03:28.558Z,1724713408.558 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-26T23:03:39.443Z,1724713419.443 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-08-26T23:03:39.443Z,1724713419.443 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:03:39.459Z,1724713419.459 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:03:39.970Z,1724713419.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:03:39.970Z,1724713419.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-08-26T23:03:40.715Z,1724713420.715 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-26T23:03:53.380Z,1724713433.380 [NAL9602](ERROR): getFix uart error: serial timeout
2024-08-26T23:04:23.741Z,1724713463.741 [NAL9602](ERROR): parseGSV uart error: serial timeout
2024-08-26T23:04:43.548Z,1724713483.548 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2024-08-26T23:04:43.549Z,1724713483.549 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2024-08-26T23:04:43.556Z,1724713483.556 [BPC1](INFO): Received data from all battery sticks.
2024-08-26T23:04:48.594Z,1724713488.594 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2024-08-26T23:04:48.598Z,1724713488.598 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2024-08-26T23:04:48.823Z,1724713488.823 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-26T23:04:55.360Z,1724713495.360 [NAL9602](ERROR): getFix uart error: serial timeout
2024-08-26T23:05:10.321Z,1724713510.321 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2024-08-26T23:05:25.723Z,1724713525.723 [NAL9602](ERROR): parseGSV uart error: serial timeout
2024-08-26T23:05:32.832Z,1724713532.832 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2024-08-26T23:05:53.557Z,1724713553.557 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2024-08-26T23:05:57.336Z,1724713557.336 [NAL9602](ERROR): getFix uart error: serial timeout
2024-08-26T23:06:07.110Z,1724713567.110 [CommandExec](IMPORTANT): got command get platform_rudder_angle degree
2024-08-26T23:06:07.111Z,1724713567.111 [CommandExec](IMPORTANT): platform_rudder_angle 15.002222 arcdeg
2024-08-26T23:06:19.876Z,1724713579.876 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0
2024-08-26T23:06:19.876Z,1724713579.876 [CommandExec](FAULT): Incomplete syntax. Try: help maintain
2024-08-26T23:06:27.750Z,1724713587.750 [NAL9602](ERROR): parseGSV uart error: serial timeout
2024-08-26T23:06:31.827Z,1724713591.827 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2024-08-26T23:06:40.286Z,1724713600.286 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-08-26T23:06:40.286Z,1724713600.286 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:06:40.298Z,1724713600.298 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:06:40.717Z,1724713600.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:06:40.717Z,1724713600.717 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-08-26T23:06:47.553Z,1724713607.553 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-26T23:01:47.4Z
2024-08-26T23:06:47.553Z,1724713607.553 [Default:CheckIn:Read_GPS] Stopped
2024-08-26T23:06:47.553Z,1724713607.553 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-26T23:06:47.958Z,1724713607.958 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-08-26T23:06:55.323Z,1724713615.323 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20240826T225726/Courier0004.lzma
2024-08-26T23:06:56.326Z,1724713616.326 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0004.lzma.bak
2024-08-26T23:06:56.326Z,1724713616.326 [DataOverHttps](INFO): SBD MOMSN=23068135
2024-08-26T23:06:59.416Z,1724713619.416 [NAL9602](ERROR): getFix uart error: serial timeout
2024-08-26T23:07:13.719Z,1724713633.719 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20240826T225726/Express0005.lzma
2024-08-26T23:07:14.714Z,1724713634.714 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0005.lzma.bak
2024-08-26T23:07:14.714Z,1724713634.714 [DataOverHttps](INFO): SBD MOMSN=23068138
2024-08-26T23:07:17.684Z,1724713637.684 [Default:CheckIn:Read_Iridium] Stopped
2024-08-26T23:07:17.684Z,1724713637.684 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-26T23:07:17.684Z,1724713637.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-26T23:07:29.794Z,1724713649.794 [NAL9602](ERROR): parseGSV uart error: serial timeout
2024-08-26T23:07:37.396Z,1724713657.396 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree
2024-08-26T23:07:37.399Z,1724713657.399 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread.
2024-08-26T23:07:37.488Z,1724713657.488 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-26T23:07:56.264Z,1724713676.264 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree
2024-08-26T23:08:01.392Z,1724713681.392 [NAL9602](ERROR): getFix uart error: serial timeout
2024-08-26T23:08:10.713Z,1724713690.713 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 0 degree
2024-08-26T23:08:31.814Z,1724713711.814 [NAL9602](ERROR): parseGSV uart error: serial timeout
2024-08-26T23:08:32.606Z,1724713712.606 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-26T23:08:32.606Z,1724713712.606 [NAL9602] Data Fault, FailCount= 1
2024-08-26T23:08:32.606Z,1724713712.606 [NAL9602](ERROR): Data Fault
2024-08-26T23:08:32.713Z,1724713712.713 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-26T23:08:33.022Z,1724713713.022 [NAL9602](INFO): Powering down
2024-08-26T23:08:33.843Z,1724713713.843 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-26T23:08:33.843Z,1724713713.843 [NAL9602] No Fault, FailCount= 1
2024-08-26T23:08:46.433Z,1724713726.433 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2024-08-26T23:08:46.436Z,1724713726.436 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2024-08-26T23:08:46.778Z,1724713726.778 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-26T23:09:03.306Z,1724713743.306 [NAL9602](INFO): Powering up NAL9602
2024-08-26T23:09:10.032Z,1724713750.032 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 0 revolution_per_minute
2024-08-26T23:09:14.207Z,1724713754.207 [NAL9602](INFO): NAL9602 initialized
2024-08-26T23:09:41.304Z,1724713781.304 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-08-26T23:09:41.304Z,1724713781.304 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:09:41.316Z,1724713781.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:09:41.719Z,1724713781.719 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:09:41.719Z,1724713781.719 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-08-26T23:10:13.114Z,1724713813.114 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter
2024-08-26T23:10:13.118Z,1724713813.118 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2024-08-26T23:10:13.262Z,1724713813.262 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,Maintain_SpeedControl.propOmegaAction,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-26T23:11:56.615Z,1724713916.615 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2024-08-26T23:11:56.615Z,1724713916.615 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 41.000000 mm
2024-08-26T23:12:09.764Z,1724713929.764 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitAft
2024-08-26T23:12:09.765Z,1724713929.765 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitAft -13.000000 mm
2024-08-26T23:12:18.483Z,1724713938.483 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-26T23:12:18.483Z,1724713938.483 [Default:CheckIn:C.Wait] Stopped
2024-08-26T23:12:18.483Z,1724713938.483 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-26T23:12:18.483Z,1724713938.483 [Default:CheckIn:D] Running Loop=1
2024-08-26T23:12:18.898Z,1724713938.898 [Default:CheckIn:D] Stopped
2024-08-26T23:12:18.898Z,1724713938.898 [Default:CheckIn:E] Running Loop=1
2024-08-26T23:12:19.304Z,1724713939.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.753675 min
2024-08-26T23:12:19.304Z,1724713939.304 [Default:CheckIn:E] Stopped
2024-08-26T23:12:19.304Z,1724713939.304 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-26T23:12:19.304Z,1724713939.304 [Default:CheckIn] Stopped
2024-08-26T23:12:19.304Z,1724713939.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-26T23:12:19.304Z,1724713939.304 [Default:CheckIn](INFO): Running loop #2
2024-08-26T23:12:19.305Z,1724713939.305 [Default:CheckIn] Running Loop=2
2024-08-26T23:12:19.305Z,1724713939.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-26T23:12:19.305Z,1724713939.305 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-26T23:12:39.593Z,1724713959.593 [CBIT](INFO): Clearing failed state for component DropWeight
2024-08-26T23:12:39.593Z,1724713959.593 [DropWeight] No Fault, FailCount= 1
2024-08-26T23:12:42.304Z,1724713962.304 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-08-26T23:12:42.304Z,1724713962.304 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:12:42.352Z,1724713962.352 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:12:42.722Z,1724713962.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:12:42.722Z,1724713962.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-08-26T23:12:54.869Z,1724713974.869 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 41 millimeter
2024-08-26T23:12:54.877Z,1724713974.877 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2024-08-26T23:12:54.893Z,1724713974.893 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,Maintain_VerticalControl.elevatorAngleAction,Maintain_SpeedControl.propOmegaAction,Maintain_VerticalControl.buoyancyAction,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-26T23:13:36.504Z,1724714016.504 [MassServo](FAULT): Reading outside of valid range:443421.000000
2024-08-26T23:15:43.332Z,1724714143.332 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-08-26T23:15:43.332Z,1724714143.332 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:15:43.406Z,1724714143.406 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:15:43.792Z,1724714143.792 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:15:43.793Z,1724714143.793 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-08-26T23:16:48.254Z,1724714208.254 [CommandExec](IMPORTANT): got command get platform_mass_position
2024-08-26T23:16:48.255Z,1724714208.255 [CommandExec](IMPORTANT): platform_mass_position 0.040672 m
2024-08-26T23:17:19.500Z,1724714239.500 [DVL_micro](ERROR): DVL uart error: serial timeout
2024-08-26T23:17:19.500Z,1724714239.500 [DVL_micro] Communications Fault, FailCount= 1
2024-08-26T23:17:19.500Z,1724714239.500 [DVL_micro](ERROR): Communications Fault
2024-08-26T23:17:19.500Z,1724714239.500 [DVL_micro](ERROR): Failed to parse:
2024-08-26T23:17:19.550Z,1724714239.550 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-26T23:12:19.3Z
2024-08-26T23:17:19.551Z,1724714239.551 [Default:CheckIn:Read_GPS] Stopped
2024-08-26T23:17:19.551Z,1724714239.551 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-26T23:17:19.621Z,1724714239.621 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-08-26T23:17:19.944Z,1724714239.944 [DVL_micro](INFO): Powering down
2024-08-26T23:17:20.732Z,1724714240.732 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-08-26T23:17:20.732Z,1724714240.732 [DVL_micro] No Fault, FailCount= 1
2024-08-26T23:17:28.835Z,1724714248.835 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240826T225726/Courier0007.lzma
2024-08-26T23:17:29.838Z,1724714249.838 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0007.lzma.bak
2024-08-26T23:17:29.838Z,1724714249.838 [DataOverHttps](INFO): SBD MOMSN=23068209
2024-08-26T23:17:38.833Z,1724714258.833 [DVL_micro](ERROR): Failed to parse:
:WI,+00000,+00118,+00044,+00000,A
2024-08-26T23:17:49.328Z,1724714269.328 [DataOverHttps](INFO): Sending 535 bytes from file Logs/20240826T225726/Express0008.lzma
2024-08-26T23:17:50.330Z,1724714270.330 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0008.lzma.bak
2024-08-26T23:17:50.330Z,1724714270.330 [DataOverHttps](INFO): SBD MOMSN=23068211
2024-08-26T23:17:53.397Z,1724714273.397 [Default:CheckIn:Read_Iridium] Stopped
2024-08-26T23:17:53.397Z,1724714273.397 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-26T23:17:53.397Z,1724714273.397 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-26T23:18:31.694Z,1724714311.694 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction -13 millimeter
2024-08-26T23:18:44.304Z,1724714324.304 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-08-26T23:18:44.304Z,1724714324.304 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:18:44.341Z,1724714324.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:18:44.749Z,1724714324.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:18:44.749Z,1724714324.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-08-26T23:19:16.194Z,1724714356.194 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-26T23:19:16.194Z,1724714356.194 [NAL9602] Data Fault, FailCount= 2
2024-08-26T23:19:16.194Z,1724714356.194 [NAL9602](ERROR): Data Fault
2024-08-26T23:19:16.252Z,1724714356.252 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-26T23:19:16.599Z,1724714356.599 [NAL9602](INFO): Powering down
2024-08-26T23:19:17.456Z,1724714357.456 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-26T23:19:17.456Z,1724714357.456 [NAL9602] No Fault, FailCount= 2
2024-08-26T23:19:46.895Z,1724714386.895 [NAL9602](INFO): Powering up NAL9602
2024-08-26T23:19:57.803Z,1724714397.803 [NAL9602](INFO): NAL9602 initialized
2024-08-26T23:20:18.243Z,1724714418.243 [CommandExec](IMPORTANT): got command get platform_mass_position
2024-08-26T23:20:18.252Z,1724714418.252 [CommandExec](IMPORTANT): platform_mass_position -0.012999 m
2024-08-26T23:21:12.308Z,1724714472.308 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T23:21:12.309Z,1724714472.309 [DATMMP](INFO): timesync rx: x2 x0 x0 x0
2024-08-26T23:21:12.310Z,1724714472.310 [DATMMP](INFO): Received message type: timesync
2024-08-26T23:21:12.310Z,1724714472.310 [DATMMP](INFO): Handled
2024-08-26T23:21:22.136Z,1724714482.136 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T23:21:22.137Z,1724714482.137 [DATMMP](INFO): timesync rx: x2 x1 x1 x0
2024-08-26T23:21:22.138Z,1724714482.138 [DATMMP](INFO): Received message type: timesync
2024-08-26T23:21:22.138Z,1724714482.138 [DATMMP](INFO): Handled
2024-08-26T23:21:32.244Z,1724714492.244 [DATMMP](INFO): GSXN notify for xid: 0
2024-08-26T23:21:32.245Z,1724714492.245 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-08-26T23:21:32.246Z,1724714492.246 [DATMMP](INFO): Received message type: timesync
2024-08-26T23:21:32.246Z,1724714492.246 [DATMMP](INFO): Handled
2024-08-26T23:21:45.330Z,1724714505.330 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-08-26T23:21:45.330Z,1724714505.330 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:21:45.342Z,1724714505.342 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:21:45.762Z,1724714505.762 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:21:45.762Z,1724714505.762 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-08-26T23:22:54.010Z,1724714574.010 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-26T23:22:54.010Z,1724714574.010 [Default:CheckIn:C.Wait] Stopped
2024-08-26T23:22:54.010Z,1724714574.010 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-26T23:22:54.010Z,1724714574.010 [Default:CheckIn:D] Running Loop=1
2024-08-26T23:22:54.413Z,1724714574.413 [Default:CheckIn:D] Stopped
2024-08-26T23:22:54.413Z,1724714574.413 [Default:CheckIn:E] Running Loop=1
2024-08-26T23:22:54.849Z,1724714574.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.345603 min
2024-08-26T23:22:54.849Z,1724714574.849 [Default:CheckIn:E] Stopped
2024-08-26T23:22:54.849Z,1724714574.849 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-26T23:22:54.849Z,1724714574.849 [Default:CheckIn] Stopped
2024-08-26T23:22:54.849Z,1724714574.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-26T23:22:54.850Z,1724714574.850 [Default:CheckIn](INFO): Running loop #3
2024-08-26T23:22:54.850Z,1724714574.850 [Default:CheckIn] Running Loop=3
2024-08-26T23:22:54.850Z,1724714574.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-26T23:22:54.850Z,1724714574.850 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-26T23:24:46.340Z,1724714686.340 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-08-26T23:24:46.340Z,1724714686.340 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:24:46.382Z,1724714686.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:24:46.743Z,1724714686.743 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:24:46.743Z,1724714686.743 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-08-26T23:25:51.290Z,1724714751.290 [CommandExec](IMPORTANT): got command maintain clear
2024-08-26T23:25:51.402Z,1724714751.402 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-08-26T23:27:47.328Z,1724714867.328 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-08-26T23:27:47.328Z,1724714867.328 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:27:47.368Z,1724714867.368 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:27:47.794Z,1724714867.794 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:27:47.794Z,1724714867.794 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-08-26T23:27:55.001Z,1724714875.001 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-26T23:22:54.9Z
2024-08-26T23:27:55.001Z,1724714875.001 [Default:CheckIn:Read_GPS] Stopped
2024-08-26T23:27:55.001Z,1724714875.001 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-26T23:28:01.939Z,1724714881.939 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240826T225726/Courier0010.lzma
2024-08-26T23:28:02.942Z,1724714882.942 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0010.lzma.bak
2024-08-26T23:28:02.942Z,1724714882.942 [DataOverHttps](INFO): SBD MOMSN=23068234
2024-08-26T23:28:20.192Z,1724714900.192 [DataOverHttps](INFO): Sending 277 bytes from file Logs/20240826T225726/Express0011.lzma
2024-08-26T23:28:21.195Z,1724714901.195 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0011.lzma.bak
2024-08-26T23:28:21.195Z,1724714901.195 [DataOverHttps](INFO): SBD MOMSN=23068236
2024-08-26T23:28:24.108Z,1724714904.108 [Default:CheckIn:Read_Iridium] Stopped
2024-08-26T23:28:24.108Z,1724714904.108 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-26T23:28:24.108Z,1724714904.108 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-26T23:30:01.050Z,1724715001.050 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-26T23:30:01.050Z,1724715001.050 [NAL9602] Data Fault, FailCount= 3
2024-08-26T23:30:01.050Z,1724715001.050 [NAL9602](ERROR): Data Fault
2024-08-26T23:30:01.094Z,1724715001.094 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-26T23:30:01.458Z,1724715001.458 [NAL9602](INFO): Powering down
2024-08-26T23:30:02.298Z,1724715002.298 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-26T23:30:02.299Z,1724715002.299 [NAL9602] No Fault, FailCount= 3
2024-08-26T23:30:31.758Z,1724715031.758 [NAL9602](INFO): Powering up NAL9602
2024-08-26T23:30:42.662Z,1724715042.662 [NAL9602](INFO): NAL9602 initialized
2024-08-26T23:30:48.324Z,1724715048.324 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-08-26T23:30:48.324Z,1724715048.324 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:30:48.335Z,1724715048.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:30:48.757Z,1724715048.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:30:48.758Z,1724715048.758 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-08-26T23:33:24.698Z,1724715204.698 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-26T23:33:24.698Z,1724715204.698 [Default:CheckIn:C.Wait] Stopped
2024-08-26T23:33:24.698Z,1724715204.698 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-26T23:33:24.698Z,1724715204.698 [Default:CheckIn:D] Running Loop=1
2024-08-26T23:33:25.109Z,1724715205.109 [Default:CheckIn:D] Stopped
2024-08-26T23:33:25.109Z,1724715205.109 [Default:CheckIn:E] Running Loop=1
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.857194 min
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn:E] Stopped
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn] Stopped
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn](INFO): Running loop #4
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn] Running Loop=4
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-26T23:33:25.502Z,1724715205.502 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-26T23:33:49.346Z,1724715229.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-08-26T23:33:49.346Z,1724715229.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:33:49.356Z,1724715229.356 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:33:49.746Z,1724715229.746 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:33:49.746Z,1724715229.746 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-08-26T23:36:50.358Z,1724715410.358 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-08-26T23:36:50.358Z,1724715410.358 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:36:50.370Z,1724715410.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:36:50.737Z,1724715410.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:36:50.737Z,1724715410.737 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-08-26T23:38:25.678Z,1724715505.678 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-26T23:33:25.5Z
2024-08-26T23:38:25.678Z,1724715505.678 [Default:CheckIn:Read_GPS] Stopped
2024-08-26T23:38:25.678Z,1724715505.678 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-26T23:38:33.467Z,1724715513.467 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240826T225726/Courier0013.lzma
2024-08-26T23:38:34.470Z,1724715514.470 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0013.lzma.bak
2024-08-26T23:38:34.470Z,1724715514.470 [DataOverHttps](INFO): SBD MOMSN=23068265
2024-08-26T23:38:51.983Z,1724715531.983 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240826T225726/Express0014.lzma
2024-08-26T23:38:52.986Z,1724715532.986 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0014.lzma.bak
2024-08-26T23:38:52.986Z,1724715532.986 [DataOverHttps](INFO): SBD MOMSN=23068267
2024-08-26T23:38:55.578Z,1724715535.578 [Default:CheckIn:Read_Iridium] Stopped
2024-08-26T23:38:55.578Z,1724715535.578 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-26T23:38:55.578Z,1724715535.578 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-26T23:39:51.320Z,1724715591.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-08-26T23:39:51.320Z,1724715591.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:39:51.331Z,1724715591.331 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:39:51.737Z,1724715591.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:39:51.737Z,1724715591.737 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-08-26T23:40:44.632Z,1724715644.632 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-26T23:40:44.632Z,1724715644.632 [NAL9602] Data Fault, FailCount= 4
2024-08-26T23:40:44.632Z,1724715644.632 [NAL9602](ERROR): Data Fault
2024-08-26T23:40:44.681Z,1724715644.681 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-26T23:40:45.030Z,1724715645.030 [NAL9602](INFO): Powering down
2024-08-26T23:40:45.856Z,1724715645.856 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-26T23:40:45.856Z,1724715645.856 [NAL9602] No Fault, FailCount= 4
2024-08-26T23:41:15.327Z,1724715675.327 [NAL9602](INFO): Powering up NAL9602
2024-08-26T23:41:26.245Z,1724715686.245 [NAL9602](INFO): NAL9602 initialized
2024-08-26T23:41:57.344Z,1724715717.344 [DVL_micro](ERROR): Failed to parse:
:BI,-00044,-00066,+00075,+00000,I
2024-08-26T23:42:40.973Z,1724715760.973 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-08-26T23:42:40.973Z,1724715760.973 [DropWeight] Hardware Fault, FailCount= 1
2024-08-26T23:42:40.973Z,1724715760.973 [DropWeight](ERROR): Hardware Fault
2024-08-26T23:42:41.082Z,1724715761.082 [CBIT](INFO): Critical error at 20240826T234240
2024-08-26T23:42:41.090Z,1724715761.090 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-08-26T23:42:41.091Z,1724715761.091 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-08-26T23:42:41.450Z,1724715761.450 [CBIT](INFO): Critical error at 20240826T234241
2024-08-26T23:42:52.312Z,1724715772.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-08-26T23:42:52.312Z,1724715772.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:42:52.322Z,1724715772.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:42:52.725Z,1724715772.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:42:52.725Z,1724715772.725 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-08-26T23:43:56.131Z,1724715836.131 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-26T23:43:56.131Z,1724715836.131 [Default:CheckIn:C.Wait] Stopped
2024-08-26T23:43:56.131Z,1724715836.131 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-26T23:43:56.131Z,1724715836.131 [Default:CheckIn:D] Running Loop=1
2024-08-26T23:43:56.538Z,1724715836.538 [Default:CheckIn:D] Stopped
2024-08-26T23:43:56.538Z,1724715836.538 [Default:CheckIn:E] Running Loop=1
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.381018 min
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn:E] Stopped
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn] Stopped
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn](INFO): Running loop #5
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn] Running Loop=5
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-26T23:43:56.935Z,1724715836.935 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-26T23:45:53.301Z,1724715953.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-08-26T23:45:53.301Z,1724715953.301 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:45:53.332Z,1724715953.332 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:45:53.695Z,1724715953.695 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:45:53.696Z,1724715953.696 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-08-26T23:48:54.297Z,1724716134.297 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-08-26T23:48:54.297Z,1724716134.297 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:48:54.327Z,1724716134.327 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:48:54.707Z,1724716134.707 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:48:54.707Z,1724716134.707 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-08-26T23:48:57.129Z,1724716137.129 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-26T23:43:56.9Z
2024-08-26T23:48:57.129Z,1724716137.129 [Default:CheckIn:Read_GPS] Stopped
2024-08-26T23:48:57.129Z,1724716137.129 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-26T23:49:03.979Z,1724716143.979 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20240826T225726/Courier0016.lzma
2024-08-26T23:49:04.982Z,1724716144.982 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0016.lzma.bak
2024-08-26T23:49:04.982Z,1724716144.982 [DataOverHttps](INFO): SBD MOMSN=23068342
2024-08-26T23:49:22.424Z,1724716162.424 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240826T225726/Express0017.lzma
2024-08-26T23:49:23.426Z,1724716163.426 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0017.lzma.bak
2024-08-26T23:49:23.426Z,1724716163.426 [DataOverHttps](INFO): SBD MOMSN=23068346
2024-08-26T23:49:26.269Z,1724716166.269 [Default:CheckIn:Read_Iridium] Stopped
2024-08-26T23:49:26.269Z,1724716166.269 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-26T23:49:26.269Z,1724716166.269 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-26T23:51:29.491Z,1724716289.491 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-26T23:51:29.491Z,1724716289.491 [NAL9602] Data Fault, FailCount= 5
2024-08-26T23:51:29.491Z,1724716289.491 [NAL9602](ERROR): Data Fault
2024-08-26T23:51:29.513Z,1724716289.513 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-26T23:51:29.514Z,1724716289.514 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-08-26T23:51:29.871Z,1724716289.871 [NAL9602](INFO): Powering down
2024-08-26T23:51:29.887Z,1724716289.887 [CBIT](INFO): Critical error at 20240826T235129
2024-08-26T23:51:55.336Z,1724716315.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2024-08-26T23:51:55.336Z,1724716315.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:51:55.368Z,1724716315.368 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:51:55.751Z,1724716315.751 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:51:55.751Z,1724716315.751 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2024-08-26T23:53:29.880Z,1724716409.880 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-26T23:53:29.880Z,1724716409.880 [NAL9602] No Fault, FailCount= 5
2024-08-26T23:53:30.266Z,1724716410.266 [NAL9602](INFO): Powering up NAL9602
2024-08-26T23:53:41.170Z,1724716421.170 [NAL9602](INFO): NAL9602 initialized
2024-08-26T23:54:26.841Z,1724716466.841 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-26T23:54:26.841Z,1724716466.841 [Default:CheckIn:C.Wait] Stopped
2024-08-26T23:54:26.841Z,1724716466.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-26T23:54:26.841Z,1724716466.841 [Default:CheckIn:D] Running Loop=1
2024-08-26T23:54:27.260Z,1724716467.260 [Default:CheckIn:D] Stopped
2024-08-26T23:54:27.260Z,1724716467.260 [Default:CheckIn:E] Running Loop=1
2024-08-26T23:54:27.657Z,1724716467.657 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.893042 min
2024-08-26T23:54:27.657Z,1724716467.657 [Default:CheckIn:E] Stopped
2024-08-26T23:54:27.658Z,1724716467.658 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-26T23:54:27.658Z,1724716467.658 [Default:CheckIn] Stopped
2024-08-26T23:54:27.658Z,1724716467.658 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-26T23:54:27.658Z,1724716467.658 [Default:CheckIn](INFO): Running loop #6
2024-08-26T23:54:27.658Z,1724716467.658 [Default:CheckIn] Running Loop=6
2024-08-26T23:54:27.658Z,1724716467.658 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-26T23:54:27.658Z,1724716467.658 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-26T23:54:56.324Z,1724716496.324 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2024-08-26T23:54:56.324Z,1724716496.324 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:54:56.335Z,1724716496.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:54:56.751Z,1724716496.751 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:54:56.751Z,1724716496.751 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2024-08-26T23:57:41.190Z,1724716661.190 [CBIT](INFO): Clearing failed state for component DropWeight
2024-08-26T23:57:41.190Z,1724716661.190 [DropWeight] No Fault, FailCount= 1
2024-08-26T23:57:57.326Z,1724716677.326 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2024-08-26T23:57:57.326Z,1724716677.326 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-26T23:57:57.336Z,1724716677.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-26T23:57:57.766Z,1724716677.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-26T23:57:57.766Z,1724716677.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2024-08-26T23:59:27.819Z,1724716767.819 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-26T23:54:27.7Z
2024-08-26T23:59:27.819Z,1724716767.819 [Default:CheckIn:Read_GPS] Stopped
2024-08-26T23:59:27.819Z,1724716767.819 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-26T23:59:35.377Z,1724716775.377 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20240826T225726/Courier0019.lzma
2024-08-26T23:59:36.378Z,1724716776.378 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0019.lzma.bak
2024-08-26T23:59:36.378Z,1724716776.378 [DataOverHttps](INFO): SBD MOMSN=23068414
2024-08-26T23:59:52.623Z,1724716792.623 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240826T225726/Express0020.lzma
2024-08-26T23:59:53.626Z,1724716793.626 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0020.lzma.bak
2024-08-26T23:59:53.626Z,1724716793.626 [DataOverHttps](INFO): SBD MOMSN=23068417
2024-08-26T23:59:56.555Z,1724716796.555 [Default:CheckIn:Read_Iridium] Stopped
2024-08-26T23:59:56.555Z,1724716796.555 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-26T23:59:56.555Z,1724716796.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-27T00:00:58.396Z,1724716858.396 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2024-08-27T00:00:58.396Z,1724716858.396 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:00:58.430Z,1724716858.430 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:00:58.817Z,1724716858.817 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:00:58.817Z,1724716858.817 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2024-08-27T00:03:44.407Z,1724717024.407 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-27T00:03:44.407Z,1724717024.407 [NAL9602] Data Fault, FailCount= 1
2024-08-27T00:03:44.407Z,1724717024.407 [NAL9602](ERROR): Data Fault
2024-08-27T00:03:44.423Z,1724717024.423 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-27T00:03:44.820Z,1724717024.820 [NAL9602](INFO): Powering down
2024-08-27T00:03:45.634Z,1724717025.634 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-27T00:03:45.634Z,1724717025.634 [NAL9602] No Fault, FailCount= 1
2024-08-27T00:03:59.360Z,1724717039.360 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2024-08-27T00:03:59.361Z,1724717039.361 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:03:59.371Z,1724717039.371 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:03:59.773Z,1724717039.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:03:59.773Z,1724717039.773 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2024-08-27T00:04:15.110Z,1724717055.110 [NAL9602](INFO): Powering up NAL9602
2024-08-27T00:04:26.031Z,1724717066.031 [NAL9602](INFO): NAL9602 initialized
2024-08-27T00:04:51.880Z,1724717091.880 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2024-08-27T00:04:51.880Z,1724717091.880 [AHRS_M2] Data Fault, FailCount= 1
2024-08-27T00:04:51.880Z,1724717091.880 [AHRS_M2](ERROR): Data Fault
2024-08-27T00:04:51.969Z,1724717091.969 [CBIT](ERROR): Data Fault in component: AHRS_M2
2024-08-27T00:04:52.269Z,1724717092.269 [AHRS_M2](INFO): Powering down
2024-08-27T00:04:53.117Z,1724717093.117 [CBIT](INFO): Clearing failed state for component AHRS_M2
2024-08-27T00:04:53.118Z,1724717093.118 [AHRS_M2] No Fault, FailCount= 1
2024-08-27T00:04:53.485Z,1724717093.485 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-08-27T00:04:57.040Z,1724717097.040 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:04:57.093Z,1724717097.093 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-27T00:04:57.093Z,1724717097.093 [Default:CheckIn:C.Wait] Stopped
2024-08-27T00:04:57.093Z,1724717097.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-27T00:04:57.093Z,1724717097.093 [Default:CheckIn:D] Running Loop=1
2024-08-27T00:04:57.484Z,1724717097.484 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:04:57.545Z,1724717097.545 [Default:CheckIn:D] Stopped
2024-08-27T00:04:57.545Z,1724717097.545 [Default:CheckIn:E] Running Loop=1
2024-08-27T00:04:57.936Z,1724717097.936 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:04:57.952Z,1724717097.952 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.397791 min
2024-08-27T00:04:57.952Z,1724717097.952 [Default:CheckIn:E] Stopped
2024-08-27T00:04:57.952Z,1724717097.952 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-27T00:04:57.952Z,1724717097.952 [Default:CheckIn] Stopped
2024-08-27T00:04:57.952Z,1724717097.952 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-27T00:04:57.952Z,1724717097.952 [Default:CheckIn](INFO): Running loop #7
2024-08-27T00:04:57.952Z,1724717097.952 [Default:CheckIn] Running Loop=7
2024-08-27T00:04:57.953Z,1724717097.953 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-27T00:04:57.953Z,1724717097.953 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-27T00:04:58.352Z,1724717098.352 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:04:58.772Z,1724717098.772 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:04:59.192Z,1724717099.192 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:04:59.620Z,1724717099.620 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:00.064Z,1724717100.064 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:00.512Z,1724717100.512 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:00.932Z,1724717100.932 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:01.352Z,1724717101.352 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:01.773Z,1724717101.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:02.188Z,1724717102.188 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:02.644Z,1724717102.644 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:03.104Z,1724717103.104 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:03.540Z,1724717103.540 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:05:03.960Z,1724717103.960 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2024-08-27T00:06:10.770Z,1724717170.770 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:07:00.424Z,1724717220.424 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2024-08-27T00:07:00.424Z,1724717220.424 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:07:00.435Z,1724717220.435 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:07:00.845Z,1724717220.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:07:00.845Z,1724717220.845 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2024-08-27T00:07:30.750Z,1724717250.750 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:08:38.219Z,1724717318.219 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:09:58.186Z,1724717398.186 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-27T00:04:57.0Z
2024-08-27T00:09:58.186Z,1724717398.186 [Default:CheckIn:Read_GPS] Stopped
2024-08-27T00:09:58.186Z,1724717398.186 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-27T00:10:01.452Z,1724717401.452 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2024-08-27T00:10:01.452Z,1724717401.452 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:10:01.467Z,1724717401.467 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:10:01.900Z,1724717401.900 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:10:01.900Z,1724717401.900 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2024-08-27T00:10:05.383Z,1724717405.383 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240826T225726/Courier0022.lzma
2024-08-27T00:10:06.386Z,1724717406.386 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0022.lzma.bak
2024-08-27T00:10:06.386Z,1724717406.386 [DataOverHttps](INFO): SBD MOMSN=23068508
2024-08-27T00:10:27.477Z,1724717427.477 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20240826T225726/Express0023.lzma
2024-08-27T00:10:28.480Z,1724717428.480 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0023.lzma.bak
2024-08-27T00:10:28.481Z,1724717428.481 [DataOverHttps](INFO): SBD MOMSN=23068510
2024-08-27T00:10:33.891Z,1724717433.891 [Default:CheckIn:Read_Iridium] Stopped
2024-08-27T00:10:33.891Z,1724717433.891 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-27T00:10:33.891Z,1724717433.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-27T00:10:42.806Z,1724717442.806 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:10:53.233Z,1724717453.233 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:12:28.165Z,1724717548.165 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:12:29.780Z,1724717549.780 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:13:02.457Z,1724717582.457 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2024-08-27T00:13:02.457Z,1724717582.457 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:13:02.467Z,1724717582.467 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:13:02.873Z,1724717582.873 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:13:02.873Z,1724717582.873 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2024-08-27T00:13:08.569Z,1724717588.569 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:14:15.641Z,1724717655.641 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:14:27.313Z,1724717667.313 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-27T00:14:27.313Z,1724717667.313 [NAL9602] Data Fault, FailCount= 2
2024-08-27T00:14:27.313Z,1724717667.313 [NAL9602](ERROR): Data Fault
2024-08-27T00:14:27.329Z,1724717667.329 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-27T00:14:27.763Z,1724717667.763 [NAL9602](INFO): Powering down
2024-08-27T00:14:28.601Z,1724717668.601 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-27T00:14:28.601Z,1724717668.601 [NAL9602] No Fault, FailCount= 2
2024-08-27T00:14:58.002Z,1724717698.002 [NAL9602](INFO): Powering up NAL9602
2024-08-27T00:15:08.973Z,1724717708.973 [NAL9602](INFO): NAL9602 initialized
2024-08-27T00:15:34.389Z,1724717734.389 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-27T00:15:34.389Z,1724717734.389 [Default:CheckIn:C.Wait] Stopped
2024-08-27T00:15:34.389Z,1724717734.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-27T00:15:34.389Z,1724717734.389 [Default:CheckIn:D] Running Loop=1
2024-08-27T00:15:34.793Z,1724717734.793 [Default:CheckIn:D] Stopped
2024-08-27T00:15:34.793Z,1724717734.793 [Default:CheckIn:E] Running Loop=1
2024-08-27T00:15:35.188Z,1724717735.188 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.018604 min
2024-08-27T00:15:35.188Z,1724717735.188 [Default:CheckIn:E] Stopped
2024-08-27T00:15:35.188Z,1724717735.188 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-27T00:15:35.188Z,1724717735.188 [Default:CheckIn] Stopped
2024-08-27T00:15:35.188Z,1724717735.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-27T00:15:35.189Z,1724717735.189 [Default:CheckIn](INFO): Running loop #8
2024-08-27T00:15:35.189Z,1724717735.189 [Default:CheckIn] Running Loop=8
2024-08-27T00:15:35.189Z,1724717735.189 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-27T00:15:35.189Z,1724717735.189 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-27T00:15:36.429Z,1724717736.429 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:16:03.458Z,1724717763.458 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2024-08-27T00:16:03.458Z,1724717763.458 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:16:03.469Z,1724717763.469 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:16:03.931Z,1724717763.931 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:16:03.931Z,1724717763.931 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2024-08-27T00:17:39.354Z,1724717859.354 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:19:04.496Z,1724717944.496 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2024-08-27T00:19:04.496Z,1724717944.496 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:19:04.507Z,1724717944.507 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:19:04.925Z,1724717944.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:19:04.925Z,1724717944.925 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2024-08-27T00:19:37.207Z,1724717977.207 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2024-08-27T00:19:42.452Z,1724717982.452 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-08-27T00:20:21.698Z,1724718021.698 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:20:35.430Z,1724718035.430 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-08-27T00:15:35.2Z
2024-08-27T00:20:35.430Z,1724718035.430 [Default:CheckIn:Read_GPS] Stopped
2024-08-27T00:20:35.430Z,1724718035.430 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-08-27T00:20:43.067Z,1724718043.067 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240826T225726/Courier0025.lzma
2024-08-27T00:20:44.071Z,1724718044.071 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Courier0025.lzma.bak
2024-08-27T00:20:44.071Z,1724718044.071 [DataOverHttps](INFO): SBD MOMSN=23068515
2024-08-27T00:21:00.436Z,1724718060.436 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20240826T225726/Express0026.lzma
2024-08-27T00:21:02.469Z,1724718062.469 [DataOverHttps](INFO): Moved sent file to Logs/20240826T225726/Express0026.lzma.bak
2024-08-27T00:21:02.469Z,1724718062.469 [DataOverHttps](INFO): SBD MOMSN=23068518
2024-08-27T00:21:04.497Z,1724718064.497 [Default:CheckIn:Read_Iridium] Stopped
2024-08-27T00:21:04.497Z,1724718064.497 [Default:CheckIn:C.Wait] Running Loop=1
2024-08-27T00:21:04.497Z,1724718064.497 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-08-27T00:21:06.937Z,1724718066.937 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:21:43.706Z,1724718103.706 [DVL_micro](ERROR): No DVL communication! Re-initializing
2024-08-27T00:21:43.706Z,1724718103.706 [DVL_micro] Communications Fault, FailCount= 1
2024-08-27T00:21:43.706Z,1724718103.706 [DVL_micro](ERROR): Communications Fault
2024-08-27T00:21:43.724Z,1724718103.724 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-08-27T00:21:44.148Z,1724718104.148 [DVL_micro](INFO): Powering down
2024-08-27T00:21:44.933Z,1724718104.933 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-08-27T00:21:44.933Z,1724718104.933 [DVL_micro] No Fault, FailCount= 1
2024-08-27T00:21:58.254Z,1724718118.254 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:22:02.658Z,1724718122.658 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-08-27T00:22:02.658Z,1724718122.658 [DVL_micro] Communications Fault, FailCount= 2
2024-08-27T00:22:02.658Z,1724718122.658 [DVL_micro](ERROR): Communications Fault
2024-08-27T00:22:02.675Z,1724718122.675 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-08-27T00:22:03.144Z,1724718123.144 [DVL_micro](INFO): Powering down
2024-08-27T00:22:03.897Z,1724718123.897 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-08-27T00:22:03.897Z,1724718123.897 [DVL_micro] No Fault, FailCount= 2
2024-08-27T00:22:05.473Z,1724718125.473 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2024-08-27T00:22:05.473Z,1724718125.473 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:22:05.483Z,1724718125.483 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:22:05.899Z,1724718125.899 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:22:05.899Z,1724718125.899 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2024-08-27T00:22:20.599Z,1724718140.599 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:22:21.625Z,1724718141.625 [DVL_micro](FAULT): Failed to initialize within timeout.
2024-08-27T00:22:21.626Z,1724718141.626 [DVL_micro] Communications Fault, FailCount= 3
2024-08-27T00:22:21.626Z,1724718141.626 [DVL_micro](ERROR): Communications Fault
2024-08-27T00:22:21.651Z,1724718141.651 [CBIT](ERROR): Communications Fault in component: DVL_micro
2024-08-27T00:22:21.651Z,1724718141.651 [CBIT](CRITICAL): Communications Fault in component: DVL_micro
2024-08-27T00:22:22.132Z,1724718142.132 [DVL_micro](INFO): Powering down
2024-08-27T00:22:22.165Z,1724718142.165 [CBIT](INFO): Critical error at 20240827T002221
2024-08-27T00:23:36.033Z,1724718216.033 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:24:00.672Z,1724718240.672 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:25:06.469Z,1724718306.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2024-08-27T00:25:06.469Z,1724718306.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:25:06.479Z,1724718306.479 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:25:06.905Z,1724718306.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:25:06.905Z,1724718306.905 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2024-08-27T00:25:10.971Z,1724718310.971 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-08-27T00:25:10.971Z,1724718310.971 [NAL9602] Data Fault, FailCount= 3
2024-08-27T00:25:10.971Z,1724718310.971 [NAL9602](ERROR): Data Fault
2024-08-27T00:25:10.987Z,1724718310.987 [CBIT](ERROR): Data Fault in component: NAL9602
2024-08-27T00:25:11.317Z,1724718311.317 [NAL9602](INFO): Powering down
2024-08-27T00:25:12.153Z,1724718312.153 [CBIT](INFO): Clearing failed state for component NAL9602
2024-08-27T00:25:12.153Z,1724718312.153 [NAL9602] No Fault, FailCount= 3
2024-08-27T00:25:21.847Z,1724718321.847 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-08-27T00:25:21.847Z,1724718321.847 [DVL_micro] No Fault, FailCount= 3
2024-08-27T00:25:24.304Z,1724718324.304 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:25:37.171Z,1724718337.171 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout.
2024-08-27T00:25:37.172Z,1724718337.172 [DVL_micro] Data Fault, FailCount= 1
2024-08-27T00:25:37.172Z,1724718337.172 [DVL_micro](ERROR): Data Fault
2024-08-27T00:25:37.189Z,1724718337.189 [CBIT](ERROR): Data Fault in component: DVL_micro
2024-08-27T00:25:37.728Z,1724718337.728 [DVL_micro](INFO): Powering down
2024-08-27T00:25:38.418Z,1724718338.418 [CBIT](INFO): Clearing failed state for component DVL_micro
2024-08-27T00:25:38.418Z,1724718338.418 [DVL_micro] No Fault, FailCount= 1
2024-08-27T00:25:41.622Z,1724718341.622 [NAL9602](INFO): Powering up NAL9602
2024-08-27T00:25:52.534Z,1724718352.534 [NAL9602](INFO): NAL9602 initialized
2024-08-27T00:25:56.562Z,1724718356.562 [DVL_micro](ERROR): Failed to parse:
:WI,-00072,+00017,+00110,+00000,A
2024-08-27T00:25:59.401Z,1724718359.401 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro
2024-08-27T00:26:05.185Z,1724718365.185 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-08-27T00:26:05.185Z,1724718365.185 [Default:CheckIn:C.Wait] Stopped
2024-08-27T00:26:05.185Z,1724718365.185 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-08-27T00:26:05.185Z,1724718365.185 [Default:CheckIn:D] Running Loop=1
2024-08-27T00:26:05.497Z,1724718365.497 [Default:CheckIn:D] Stopped
2024-08-27T00:26:05.497Z,1724718365.497 [Default:CheckIn:E] Running Loop=1
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.530339 min
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn:E] Stopped
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn] Stopped
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn](INFO): Running loop #9
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn] Running Loop=9
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-08-27T00:26:05.886Z,1724718365.886 [Default:CheckIn:Read_GPS] Running Loop=1
2024-08-27T00:27:17.828Z,1724718437.828 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-08-27T00:27:42.422Z,1724718462.422 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-08-27T00:27:42.422Z,1724718462.422 [DropWeight] Hardware Fault, FailCount= 1
2024-08-27T00:27:42.422Z,1724718462.422 [DropWeight](ERROR): Hardware Fault
2024-08-27T00:27:42.437Z,1724718462.437 [CBIT](INFO): Critical error at 20240827T002742
2024-08-27T00:27:42.440Z,1724718462.440 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-08-27T00:27:42.440Z,1724718462.440 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-08-27T00:27:42.856Z,1724718462.856 [CBIT](INFO): Critical error at 20240827T002742
2024-08-27T00:28:07.457Z,1724718487.457 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2024-08-27T00:28:07.457Z,1724718487.457 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-08-27T00:28:07.468Z,1724718487.468 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-08-27T00:28:07.914Z,1724718487.914 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:28:07.990Z,1724718487.990 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-08-27T00:28:07.990Z,1724718487.990 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2024-08-27T00:29:42.452Z,1724718582.452 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:30:22.860Z,1724718622.860 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-08-27T00:30:31.653Z,1724718631.653 [CommandExec](IMPORTANT): got command quit
2024-08-27T00:30:32.656Z,1724718632.656 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:32.656Z,1724718632.656 [CommandExec](INFO): Uninitializing the command executive.
2024-08-27T00:30:32.656Z,1724718632.656 [CommandExec](INFO): Uninitializing the command scheduler.
2024-08-27T00:30:32.657Z,1724718632.657 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:32.804Z,1724718632.804 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-08-27T00:30:32.804Z,1724718632.804 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-08-27T00:30:32.804Z,1724718632.804 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:32.805Z,1724718632.805 [NavChartDb](INFO): Join timeout helper Thread ID is 3064
2024-08-27T00:30:33.080Z,1724718633.080 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:33.081Z,1724718633.081 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:33.094Z,1724718633.094 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-08-27T00:30:33.094Z,1724718633.094 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:33.094Z,1724718633.094 [Radio_Surface](INFO): Join timeout helper Thread ID is 3065
2024-08-27T00:30:33.164Z,1724718633.164 [Radio_Surface](INFO): Powering down
2024-08-27T00:30:33.165Z,1724718633.165 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:33.165Z,1724718633.165 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:33.174Z,1724718633.174 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-08-27T00:30:33.175Z,1724718633.175 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:33.175Z,1724718633.175 [Onboard](INFO): Join timeout helper Thread ID is 3066
2024-08-27T00:30:34.164Z,1724718634.164 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-08-27T00:30:38.080Z,1724718638.080 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:38.080Z,1724718638.080 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.096Z,1724718638.096 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-08-27T00:30:38.096Z,1724718638.096 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.096Z,1724718638.096 [DataOverHttps](INFO): Join timeout helper Thread ID is 3067
2024-08-27T00:30:38.180Z,1724718638.180 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:38.180Z,1724718638.180 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.188Z,1724718638.188 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-08-27T00:30:38.188Z,1724718638.188 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.188Z,1724718638.188 [DATMMP](INFO): Join timeout helper Thread ID is 3068
2024-08-27T00:30:38.268Z,1724718638.268 [DATMMP](INFO): Stop
2024-08-27T00:30:38.268Z,1724718638.268 [DATMMP](INFO): uninitialize
2024-08-27T00:30:38.268Z,1724718638.268 [DATMMP](INFO): Powering down
2024-08-27T00:30:38.341Z,1724718638.341 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:38.341Z,1724718638.341 [DATMMP](INFO): uninitialize
2024-08-27T00:30:38.341Z,1724718638.341 [DATMMP](INFO): Powering down
2024-08-27T00:30:38.342Z,1724718638.342 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.361Z,1724718638.361 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-08-27T00:30:38.361Z,1724718638.361 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.361Z,1724718638.361 [BackseatComponent](INFO): Join timeout helper Thread ID is 3069
2024-08-27T00:30:38.420Z,1724718638.420 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:38.421Z,1724718638.421 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.444Z,1724718638.444 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-08-27T00:30:38.444Z,1724718638.444 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.445Z,1724718638.445 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3070
2024-08-27T00:30:38.632Z,1724718638.632 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:38.633Z,1724718638.633 [WetLabsBB2FL](INFO): Powering down
2024-08-27T00:30:38.634Z,1724718638.634 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.657Z,1724718638.657 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-08-27T00:30:38.657Z,1724718638.657 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:38.657Z,1724718638.657 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3071
2024-08-27T00:30:39.532Z,1724718639.532 [CTD_Seabird](INFO): Powering down
2024-08-27T00:30:39.545Z,1724718639.545 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:39.545Z,1724718639.545 [CTD_Seabird](INFO): Powering down
2024-08-27T00:30:39.560Z,1724718639.560 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:39.577Z,1724718639.577 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-08-27T00:30:39.577Z,1724718639.577 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:39.577Z,1724718639.577 [logger](INFO): Join timeout helper Thread ID is 3072
2024-08-27T00:30:39.592Z,1724718639.592 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:39.592Z,1724718639.592 [logger ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:39.597Z,1724718639.597 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-08-27T00:30:39.597Z,1724718639.597 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:39.597Z,1724718639.597 [CommandLine](INFO): Join timeout helper Thread ID is 3073
2024-08-27T00:30:39.696Z,1724718639.696 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:39.696Z,1724718639.696 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:39.698Z,1724718639.698 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-08-27T00:30:39.698Z,1724718639.698 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:39.698Z,1724718639.698 [CommandExec](INFO): Join timeout helper Thread ID is 3074
2024-08-27T00:30:39.699Z,1724718639.699 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-08-27T00:30:39.699Z,1724718639.699 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:39.700Z,1724718639.700 [controlThread](INFO): Join timeout helper Thread ID is 3075
2024-08-27T00:30:39.744Z,1724718639.744 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-08-27T00:30:39.744Z,1724718639.744 [controlThread](DEBUG): Uninitializing ControlThread
2024-08-27T00:30:39.746Z,1724718639.746 [AHRS_M2](INFO): Powering down
2024-08-27T00:30:39.912Z,1724718639.912 [DVL_micro](INFO): Powering down
2024-08-27T00:30:39.913Z,1724718639.913 [NAL9602](INFO): Powering down
2024-08-27T00:30:39.914Z,1724718639.914 [Sonardyne_Nano](INFO): Powering down
2024-08-27T00:30:40.032Z,1724718640.032 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-08-27T00:30:40.033Z,1724718640.033 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-08-27T00:30:40.034Z,1724718640.034 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-08-27T00:30:40.034Z,1724718640.034 [MissionManager](INFO): Uninitializing Mission Default
2024-08-27T00:30:40.034Z,1724718640.034 [Default] Stopped
2024-08-27T00:30:40.034Z,1724718640.034 [Default](DEBUG): Aggregate::uninitialize Default
2024-08-27T00:30:40.034Z,1724718640.034 [Default:B.GoToSurface] Stopped
2024-08-27T00:30:40.034Z,1724718640.034 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-08-27T00:30:40.035Z,1724718640.035 [Default:CheckIn] Stopped
2024-08-27T00:30:40.035Z,1724718640.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-08-27T00:30:40.035Z,1724718640.035 [Default:CheckIn:Read_GPS] Stopped
2024-08-27T00:30:40.038Z,1724718640.038 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-08-27T00:30:40.038Z,1724718640.038 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-08-27T00:30:40.038Z,1724718640.038 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-08-27T00:30:40.038Z,1724718640.038 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-08-27T00:30:40.039Z,1724718640.039 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-08-27T00:30:40.039Z,1724718640.039 [BuoyancyServo](INFO): Powering down
2024-08-27T00:30:40.052Z,1724718640.052 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-08-27T00:30:40.052Z,1724718640.052 [ElevatorServo](INFO): Powering down
2024-08-27T00:30:40.053Z,1724718640.053 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-08-27T00:30:40.053Z,1724718640.053 [MassServo](INFO): Powering down
2024-08-27T00:30:40.054Z,1724718640.054 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-08-27T00:30:40.054Z,1724718640.054 [RudderServo](INFO): Powering down
2024-08-27T00:30:40.055Z,1724718640.055 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-08-27T00:30:40.055Z,1724718640.055 [ThrusterHE](INFO): Powering down
2024-08-27T00:30:40.056Z,1724718640.056 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-08-27T00:30:40.056Z,1724718640.056 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-08-27T00:30:40.056Z,1724718640.056 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-08-27T00:30:40.056Z,1724718640.056 [CBIT](DEBUG): Powering off loads.
2024-08-27T00:30:40.068Z,1724718640.068 [CBIT](DEBUG): Disabling WDT.
2024-08-27T00:30:40.080Z,1724718640.080 [CBIT](DEBUG): Opening all GF detection circuits.
2024-08-27T00:30:40.081Z,1724718640.081 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.131Z,1724718640.131 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.138Z,1724718640.138 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.168Z,1724718640.168 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.170Z,1724718640.170 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.177Z,1724718640.177 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.183Z,1724718640.183 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.225Z,1724718640.225 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.278Z,1724718640.278 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-08-27T00:30:40.340Z,1724718640.340 [logger ThreadHandler](INFO): Thread cancelled.