2024-09-16T22:39:29.449Z,1726526369.449 [Supervisor](DEBUG): Initializing supervisor.
2024-09-16T22:39:29.454Z,1726526369.454 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-09-16T22:39:29.454Z,1726526369.454 [SyncHandler](INFO): Protected caller Thread ID is 3991
2024-09-16T22:39:29.455Z,1726526369.455 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-09-16T22:39:29.456Z,1726526369.456 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-09-16T22:39:29.456Z,1726526369.456 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3992
2024-09-16T22:39:29.461Z,1726526369.461 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-09-16T22:39:29.478Z,1726526369.478 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-09-16T22:39:29.479Z,1726526369.479 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-09-16T22:39:29.479Z,1726526369.479 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3993
2024-09-16T22:39:29.483Z,1726526369.483 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-09-16T22:39:29.484Z,1726526369.484 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-09-16T22:39:29.484Z,1726526369.484 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3994
2024-09-16T22:39:29.486Z,1726526369.486 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-09-16T22:39:29.487Z,1726526369.487 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-09-16T22:39:29.488Z,1726526369.488 [logger ThreadHandler](INFO): Protected caller Thread ID is 3995
2024-09-16T22:39:29.491Z,1726526369.491 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-09-16T22:39:29.491Z,1726526369.491 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-09-16T22:39:29.493Z,1726526369.493 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-09-16T22:39:29.601Z,1726526369.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-09-16T22:39:29.602Z,1726526369.602 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-09-16T22:39:29.852Z,1726526369.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-09-16T22:39:29.853Z,1726526369.853 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-09-16T22:39:30.134Z,1726526370.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-09-16T22:39:30.134Z,1726526370.134 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-09-16T22:39:30.256Z,1726526370.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-09-16T22:39:30.257Z,1726526370.257 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-09-16T22:39:31.039Z,1726526371.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-09-16T22:39:31.039Z,1726526371.039 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-09-16T22:39:31.577Z,1726526371.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-09-16T22:39:31.578Z,1726526371.578 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-09-16T22:39:32.449Z,1726526372.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-09-16T22:39:32.449Z,1726526372.449 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-09-16T22:39:33.086Z,1726526373.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-09-16T22:39:33.087Z,1726526373.087 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-09-16T22:39:33.449Z,1726526373.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-09-16T22:39:33.449Z,1726526373.449 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-09-16T22:39:33.851Z,1726526373.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-09-16T22:39:33.852Z,1726526373.852 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-09-16T22:39:34.098Z,1726526374.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-09-16T22:39:34.098Z,1726526374.098 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-09-16T22:39:34.252Z,1726526374.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-09-16T22:39:34.253Z,1726526374.253 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-09-16T22:39:34.346Z,1726526374.346 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-09-16T22:39:34.438Z,1726526374.438 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-09-16T22:39:34.641Z,1726526374.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-09-16T22:39:34.641Z,1726526374.641 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-09-16T22:39:34.888Z,1726526374.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-09-16T22:39:34.890Z,1726526374.890 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2024-09-16T22:39:34.891Z,1726526374.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2024-09-16T22:39:34.987Z,1726526374.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2024-09-16T22:39:35.262Z,1726526375.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-09-16T22:39:35.262Z,1726526375.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2024-09-16T22:39:35.370Z,1726526375.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2024-09-16T22:39:35.550Z,1726526375.550 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2024-09-16T22:39:35.662Z,1726526375.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2024-09-16T22:39:35.786Z,1726526375.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2024-09-16T22:39:35.912Z,1726526375.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2024-09-16T22:39:36.032Z,1726526376.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2024-09-16T22:39:36.209Z,1726526376.209 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2024-09-16T22:39:36.319Z,1726526376.319 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2024-09-16T22:39:36.416Z,1726526376.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2024-09-16T22:39:36.527Z,1726526376.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2024-09-16T22:39:36.629Z,1726526376.629 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2024-09-16T22:39:36.629Z,1726526376.629 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-09-16T22:39:36.637Z,1726526376.637 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-09-16T22:39:36.810Z,1726526376.810 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-09-16T22:39:36.810Z,1726526376.810 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-09-16T22:39:36.965Z,1726526376.965 [BuoyancyServo] Loaded
2024-09-16T22:39:36.966Z,1726526376.966 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-09-16T22:39:36.988Z,1726526376.988 [ElevatorServo] Loaded
2024-09-16T22:39:36.988Z,1726526376.988 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-09-16T22:39:37.010Z,1726526377.010 [MassServo] Loaded
2024-09-16T22:39:37.010Z,1726526377.010 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-09-16T22:39:37.031Z,1726526377.031 [RudderServo] Loaded
2024-09-16T22:39:37.032Z,1726526377.032 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-09-16T22:39:37.049Z,1726526377.049 [ThrusterHE] Loaded
2024-09-16T22:39:37.049Z,1726526377.049 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-09-16T22:39:37.049Z,1726526377.049 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-09-16T22:39:37.050Z,1726526377.050 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-09-16T22:39:37.187Z,1726526377.187 [SBIT](DEBUG): Construct Startup Built In Test.
2024-09-16T22:39:37.202Z,1726526377.202 [SBIT] Loaded
2024-09-16T22:39:37.202Z,1726526377.202 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-09-16T22:39:37.205Z,1726526377.205 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-09-16T22:39:37.224Z,1726526377.224 [IBIT] Loaded
2024-09-16T22:39:37.225Z,1726526377.225 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-09-16T22:39:37.230Z,1726526377.230 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-09-16T22:39:37.445Z,1726526377.445 [CBIT] Loaded
2024-09-16T22:39:37.446Z,1726526377.446 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-09-16T22:39:37.446Z,1726526377.446 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-09-16T22:39:37.446Z,1726526377.446 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-09-16T22:39:37.880Z,1726526377.880 [CTD_Seabird] Loaded
2024-09-16T22:39:37.880Z,1726526377.880 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-09-16T22:39:37.881Z,1726526377.881 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406784E0
2024-09-16T22:39:37.882Z,1726526377.882 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4083
2024-09-16T22:39:37.924Z,1726526377.924 [ESPComponent] Loaded
2024-09-16T22:39:37.924Z,1726526377.924 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2024-09-16T22:39:38.029Z,1726526378.029 [PAR_Licor] Loaded
2024-09-16T22:39:38.030Z,1726526378.030 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-09-16T22:39:38.072Z,1726526378.072 [WetLabsBB2FL] Loaded
2024-09-16T22:39:38.072Z,1726526378.072 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-09-16T22:39:38.073Z,1726526378.073 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A84E0
2024-09-16T22:39:38.074Z,1726526378.074 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4084
2024-09-16T22:39:38.074Z,1726526378.074 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-09-16T22:39:38.075Z,1726526378.075 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-09-16T22:39:38.126Z,1726526378.126 [DepthRateCalculator] Loaded
2024-09-16T22:39:38.126Z,1726526378.126 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-09-16T22:39:38.131Z,1726526378.131 [PitchRateCalculator] Loaded
2024-09-16T22:39:38.131Z,1726526378.131 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-09-16T22:39:38.141Z,1726526378.141 [SpeedCalculator] Loaded
2024-09-16T22:39:38.141Z,1726526378.141 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-09-16T22:39:38.146Z,1726526378.146 [YawRateCalculator] Loaded
2024-09-16T22:39:38.146Z,1726526378.146 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-09-16T22:39:38.165Z,1726526378.165 [ElevatorOffsetCalculator] Loaded
2024-09-16T22:39:38.165Z,1726526378.165 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-09-16T22:39:38.166Z,1726526378.166 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-09-16T22:39:38.166Z,1726526378.166 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-09-16T22:39:38.275Z,1726526378.275 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-09-16T22:39:38.275Z,1726526378.275 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-09-16T22:39:38.302Z,1726526378.302 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-09-16T22:39:38.302Z,1726526378.302 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-09-16T22:39:38.410Z,1726526378.410 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-09-16T22:39:38.411Z,1726526378.411 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-09-16T22:39:38.424Z,1726526378.424 [NavChart] Loaded
2024-09-16T22:39:38.424Z,1726526378.424 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-09-16T22:39:38.430Z,1726526378.430 [UniversalFixResidualReporter] Loaded
2024-09-16T22:39:38.430Z,1726526378.430 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-09-16T22:39:38.430Z,1726526378.430 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-09-16T22:39:38.431Z,1726526378.431 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-09-16T22:39:38.888Z,1726526378.888 [AHRS_M2] Loaded
2024-09-16T22:39:38.889Z,1726526378.889 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-09-16T22:39:38.926Z,1726526378.926 [BackseatComponent] Loaded
2024-09-16T22:39:38.927Z,1726526378.927 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-09-16T22:39:38.928Z,1726526378.928 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A694E0
2024-09-16T22:39:38.928Z,1726526378.928 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4085
2024-09-16T22:39:38.931Z,1726526378.931 [LcmUniversalReporter] Loaded
2024-09-16T22:39:38.931Z,1726526378.931 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-09-16T22:39:39.660Z,1726526379.660 [BPC1] Loaded
2024-09-16T22:39:39.661Z,1726526379.661 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-09-16T22:39:39.749Z,1726526379.749 [DATMMP] Loaded
2024-09-16T22:39:39.749Z,1726526379.749 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2024-09-16T22:39:39.750Z,1726526379.750 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 40A9A4E0
2024-09-16T22:39:39.750Z,1726526379.750 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 4086
2024-09-16T22:39:39.820Z,1726526379.820 [DataOverHttps] Loaded
2024-09-16T22:39:39.820Z,1726526379.820 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-09-16T22:39:39.821Z,1726526379.821 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0
2024-09-16T22:39:39.822Z,1726526379.822 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4087
2024-09-16T22:39:39.844Z,1726526379.844 [Depth_Keller] Loaded
2024-09-16T22:39:39.845Z,1726526379.845 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-09-16T22:39:39.850Z,1726526379.850 [DropWeight] Loaded
2024-09-16T22:39:39.850Z,1726526379.850 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-09-16T22:39:39.890Z,1726526379.890 [DVL_micro] Loaded
2024-09-16T22:39:39.891Z,1726526379.891 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2024-09-16T22:39:39.951Z,1726526379.951 [NAL9602] Loaded
2024-09-16T22:39:39.952Z,1726526379.952 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-09-16T22:39:39.977Z,1726526379.977 [Onboard] Loaded
2024-09-16T22:39:39.977Z,1726526379.977 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-09-16T22:39:39.978Z,1726526379.978 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0
2024-09-16T22:39:39.978Z,1726526379.978 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4088
2024-09-16T22:39:39.992Z,1726526379.992 [Power24vConverter] Loaded
2024-09-16T22:39:39.992Z,1726526379.992 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-09-16T22:39:40.008Z,1726526380.008 [Radio_Surface] Loaded
2024-09-16T22:39:40.008Z,1726526380.008 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-09-16T22:39:40.009Z,1726526380.009 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B2A4E0
2024-09-16T22:39:40.009Z,1726526380.009 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4089
2024-09-16T22:39:40.025Z,1726526380.025 [Sonardyne_Nano] Loaded
2024-09-16T22:39:40.025Z,1726526380.025 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-09-16T22:39:40.026Z,1726526380.026 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-09-16T22:39:40.026Z,1726526380.026 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-09-16T22:39:40.095Z,1726526380.095 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-09-16T22:39:40.095Z,1726526380.095 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-09-16T22:39:40.147Z,1726526380.147 [VerticalControl](DEBUG): Construct VerticalControl.
2024-09-16T22:39:40.196Z,1726526380.196 [VerticalControl] Loaded
2024-09-16T22:39:40.197Z,1726526380.197 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-09-16T22:39:40.200Z,1726526380.200 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-09-16T22:39:40.242Z,1726526380.242 [HorizontalControl] Loaded
2024-09-16T22:39:40.242Z,1726526380.242 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-09-16T22:39:40.245Z,1726526380.245 [SpeedControl](DEBUG): Construct SpeedControl.
2024-09-16T22:39:40.246Z,1726526380.246 [SpeedControl] Loaded
2024-09-16T22:39:40.246Z,1726526380.246 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-09-16T22:39:40.249Z,1726526380.249 [LoopControl](DEBUG): Construct LoopControl.
2024-09-16T22:39:40.250Z,1726526380.250 [LoopControl] Loaded
2024-09-16T22:39:40.250Z,1726526380.250 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-09-16T22:39:40.250Z,1726526380.250 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-09-16T22:39:40.251Z,1726526380.251 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-09-16T22:39:40.261Z,1726526380.261 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-09-16T22:39:40.262Z,1726526380.262 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-09-16T22:39:40.595Z,1726526380.595 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-09-16T22:39:40.602Z,1726526380.602 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-09-16T22:39:40.604Z,1726526380.604 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-09-16T22:39:40.616Z,1726526380.616 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-09-16T22:39:40.617Z,1726526380.617 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C524E0
2024-09-16T22:39:40.617Z,1726526380.617 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4090
2024-09-16T22:39:40.621Z,1726526380.621 [Supervisor](INFO): Main Thread ID is 3198
2024-09-16T22:39:40.622Z,1726526380.622 [Supervisor](DEBUG): Running supervisor.
2024-09-16T22:39:40.622Z,1726526380.622 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4091
2024-09-16T22:39:40.623Z,1726526380.623 [CommandExec](INFO): Initializing the command executive.
2024-09-16T22:39:40.624Z,1726526380.624 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4092
2024-09-16T22:39:40.626Z,1726526380.626 [controlThread ThreadHandler](INFO): Handler Thread ID is 4093
2024-09-16T22:39:40.627Z,1726526380.627 [controlThread](DEBUG): Initializing ControlThread
2024-09-16T22:39:40.628Z,1726526380.628 [SBIT](INFO): Initialize SBIT Component.
2024-09-16T22:39:40.629Z,1726526380.629 [SBIT](IMPORTANT): git: 2024-09-16_B
2024-09-16T22:39:40.629Z,1726526380.629 [SBIT](INFO): git hash: 99f9462fcf59f51f8462e4e844b71b4239fb11a8
2024-09-16T22:39:40.629Z,1726526380.629 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-09-16T22:39:40.631Z,1726526380.631 [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-09-16T22:39:40.632Z,1726526380.632 [SBIT](INFO): Beginning SBIT in 70.000000 seconds.
2024-09-16T22:39:40.632Z,1726526380.632 [IBIT](INFO): Initialize IBIT Component.
2024-09-16T22:39:40.633Z,1726526380.633 [CBIT](DEBUG): Initialize CBIT Component.
2024-09-16T22:39:40.634Z,1726526380.634 [logger ThreadHandler](INFO): Handler Thread ID is 4094
2024-09-16T22:39:40.644Z,1726526380.644 [CBIT](DEBUG): Initialized mux pins.
2024-09-16T22:39:40.644Z,1726526380.644 [CBIT](DEBUG): Initializing the watchdog timer.
2024-09-16T22:39:40.657Z,1726526380.657 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4095
2024-09-16T22:39:40.658Z,1726526380.658 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-09-16T22:39:40.662Z,1726526380.662 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4097
2024-09-16T22:39:40.665Z,1726526380.665 [WetLabsBB2FL](INFO): Powering up
2024-09-16T22:39:40.666Z,1726526380.666 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4099
2024-09-16T22:39:40.669Z,1726526380.669 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-09-16T22:39:40.669Z,1726526380.669 [CBIT](DEBUG): Initializing heartbeat.
2024-09-16T22:39:40.685Z,1726526380.685 [DATMMP ThreadHandler](INFO): Handler Thread ID is 4100
2024-09-16T22:39:40.685Z,1726526380.685 [DATMMP](INFO): Start
2024-09-16T22:39:40.686Z,1726526380.686 [DATMMP](INFO): Powering up
2024-09-16T22:39:40.686Z,1726526380.686 [DATMMP](DEBUG): Initializing DATMMP.
2024-09-16T22:39:40.690Z,1726526380.690 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4102
2024-09-16T22:39:40.691Z,1726526380.691 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-09-16T22:39:40.705Z,1726526380.705 [Onboard ThreadHandler](INFO): Handler Thread ID is 4103
2024-09-16T22:39:40.722Z,1726526380.722 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4104
2024-09-16T22:39:40.738Z,1726526380.738 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4105
2024-09-16T22:39:40.740Z,1726526380.740 [CBIT](DEBUG): Deactivating GF circuits.
2024-09-16T22:39:40.741Z,1726526380.741 [CBIT](DEBUG): Deactivating emergency mode.
2024-09-16T22:39:40.741Z,1726526380.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-09-16T22:39:40.741Z,1726526380.741 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-09-16T22:39:40.741Z,1726526380.741 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-09-16T22:39:40.742Z,1726526380.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-09-16T22:39:40.742Z,1726526380.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-09-16T22:39:40.742Z,1726526380.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-09-16T22:39:40.742Z,1726526380.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-09-16T22:39:40.742Z,1726526380.742 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-09-16T22:39:40.780Z,1726526380.780 [CBIT](DEBUG): Backplane powered.
2024-09-16T22:39:40.785Z,1726526380.785 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-09-16T22:39:40.785Z,1726526380.785 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-09-16T22:39:40.786Z,1726526380.786 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-09-16T22:39:40.786Z,1726526380.786 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-09-16T22:39:40.786Z,1726526380.786 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-09-16T22:39:40.787Z,1726526380.787 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-09-16T22:39:40.788Z,1726526380.788 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-09-16T22:39:40.803Z,1726526380.803 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-09-16T22:39:40.812Z,1726526380.812 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-09-16T22:39:40.813Z,1726526380.813 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-09-16T22:39:40.814Z,1726526380.814 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-09-16T22:39:40.814Z,1726526380.814 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-09-16T22:39:40.815Z,1726526380.815 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-09-16T22:39:40.815Z,1726526380.815 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-09-16T22:39:40.825Z,1726526380.825 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-09-16T22:39:40.903Z,1726526380.903 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-09-16T22:39:40.907Z,1726526380.907 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T22:39:40.937Z,1726526380.937 [DATMMP](INFO): Starting
2024-09-16T22:39:40.955Z,1726526380.955 [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-09-16T22:39:40.956Z,1726526380.956 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-09-16T22:39:40.980Z,1726526380.980 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-09-16T22:39:40.982Z,1726526380.982 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-09-16T22:39:41.129Z,1726526381.129 [Radio_Surface](INFO): Powering up
2024-09-16T22:39:41.189Z,1726526381.189 [DATMMP](INFO): Starting
2024-09-16T22:39:41.278Z,1726526381.278 [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-09-16T22:39:41.284Z,1726526381.284 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-09-16T22:39:41.301Z,1726526381.301 [Default:A.Wait](DEBUG): Construct Wait.
2024-09-16T22:39:41.303Z,1726526381.303 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-09-16T22:39:41.328Z,1726526381.328 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-09-16T22:39:41.346Z,1726526381.346 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-09-16T22:39:41.352Z,1726526381.352 [Default:E.Execute](DEBUG): Construct Execute.
2024-09-16T22:39:41.355Z,1726526381.355 [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-09-16T22:39:41.372Z,1726526381.372 [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-09-16T22:39:41.397Z,1726526381.397 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-09-16T22:39:41.440Z,1726526381.440 [DATMMP](INFO): Starting
2024-09-16T22:39:41.457Z,1726526381.457 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2024-09-16T22:39:41.466Z,1726526381.466 [Power24vConverter](INFO): Powering up.
2024-09-16T22:39:41.466Z,1726526381.466 [Sonardyne_Nano](INFO): Initializing.
2024-09-16T22:39:41.493Z,1726526381.493 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-09-16T22:39:41.522Z,1726526381.522 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-09-16T22:39:41.529Z,1726526381.529 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-09-16T22:39:41.530Z,1726526381.530 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-09-16T22:39:41.537Z,1726526381.537 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-09-16T22:39:41.538Z,1726526381.538 [MassServo](DEBUG): Initializing EZServoServo.
2024-09-16T22:39:41.545Z,1726526381.545 [MassServo](DEBUG): Initializing MassServo.
2024-09-16T22:39:41.546Z,1726526381.546 [RudderServo](DEBUG): Initializing EZServoServo.
2024-09-16T22:39:41.553Z,1726526381.553 [RudderServo](DEBUG): Initializing RudderServo.
2024-09-16T22:39:41.554Z,1726526381.554 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-09-16T22:39:41.561Z,1726526381.561 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-09-16T22:39:41.693Z,1726526381.693 [DATMMP](INFO): Starting
2024-09-16T22:39:41.828Z,1726526381.828 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-09-16T22:39:41.828Z,1726526381.828 [DropWeight] Hardware Fault, FailCount= 1
2024-09-16T22:39:41.828Z,1726526381.828 [DropWeight](ERROR): Hardware Fault
2024-09-16T22:39:41.844Z,1726526381.844 [CommandExec](FAULT): Scheduling is paused
2024-09-16T22:39:41.844Z,1726526381.844 [CBIT](INFO): Critical error at 20240916T223941
2024-09-16T22:39:41.853Z,1726526381.853 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-09-16T22:39:41.855Z,1726526381.855 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-09-16T22:39:41.856Z,1726526381.856 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-09-16T22:39:41.945Z,1726526381.945 [DATMMP](INFO): Starting
2024-09-16T22:39:42.196Z,1726526382.196 [DATMMP](INFO): Starting
2024-09-16T22:39:42.210Z,1726526382.210 [CBIT](INFO): Critical error at 20240916T223941
2024-09-16T22:39:42.449Z,1726526382.449 [DATMMP](INFO): Starting
2024-09-16T22:39:42.702Z,1726526382.702 [DATMMP](INFO): Starting
2024-09-16T22:39:42.773Z,1726526382.773 [WetLabsBB2FL](INFO): Powering down
2024-09-16T22:39:42.953Z,1726526382.953 [DATMMP](INFO): Starting
2024-09-16T22:39:43.204Z,1726526383.204 [DATMMP](INFO): Starting
2024-09-16T22:39:43.407Z,1726526383.407 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-09-16T22:39:43.457Z,1726526383.457 [DATMMP](INFO): Starting
2024-09-16T22:39:43.709Z,1726526383.709 [DATMMP](INFO): Starting
2024-09-16T22:39:43.961Z,1726526383.961 [DATMMP](INFO): Starting
2024-09-16T22:39:44.212Z,1726526384.212 [DATMMP](INFO): Starting
2024-09-16T22:39:44.465Z,1726526384.465 [DATMMP](INFO): Starting
2024-09-16T22:39:44.699Z,1726526384.699 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2024-09-16T22:39:44.717Z,1726526384.717 [DATMMP](INFO): Starting
2024-09-16T22:39:44.969Z,1726526384.969 [DATMMP](INFO): Starting
2024-09-16T22:39:45.225Z,1726526385.225 [DATMMP](INFO): Starting
2024-09-16T22:39:45.477Z,1726526385.477 [DATMMP](INFO): Starting
2024-09-16T22:39:45.729Z,1726526385.729 [DATMMP](INFO): Starting
2024-09-16T22:39:45.982Z,1726526385.982 [DATMMP](INFO): Starting
2024-09-16T22:39:46.233Z,1726526386.233 [DATMMP](INFO): Starting
2024-09-16T22:39:46.485Z,1726526386.485 [DATMMP](INFO): Starting
2024-09-16T22:39:46.737Z,1726526386.737 [DATMMP](INFO): Starting
2024-09-16T22:39:46.989Z,1726526386.989 [DATMMP](INFO): Starting
2024-09-16T22:39:47.242Z,1726526387.242 [DATMMP](INFO): Starting
2024-09-16T22:39:47.273Z,1726526387.273 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-09-16T22:39:47.493Z,1726526387.493 [DATMMP](INFO): Starting
2024-09-16T22:39:47.745Z,1726526387.745 [DATMMP](INFO): Starting
2024-09-16T22:39:47.997Z,1726526387.997 [DATMMP](INFO): Starting
2024-09-16T22:39:48.248Z,1726526388.248 [DATMMP](INFO): Starting
2024-09-16T22:39:48.501Z,1726526388.501 [DATMMP](INFO): Starting
2024-09-16T22:39:48.753Z,1726526388.753 [DATMMP](INFO): Starting
2024-09-16T22:39:49.005Z,1726526389.005 [DATMMP](INFO): Starting
2024-09-16T22:39:49.257Z,1726526389.257 [DATMMP](INFO): Starting
2024-09-16T22:39:49.509Z,1726526389.509 [DATMMP](INFO): Starting
2024-09-16T22:39:49.761Z,1726526389.761 [DATMMP](INFO): Starting
2024-09-16T22:39:50.013Z,1726526390.013 [DATMMP](INFO): Starting
2024-09-16T22:39:50.265Z,1726526390.265 [DATMMP](INFO): Starting
2024-09-16T22:39:50.517Z,1726526390.517 [DATMMP](INFO): Starting
2024-09-16T22:39:50.770Z,1726526390.770 [DATMMP](INFO): Starting
2024-09-16T22:39:51.021Z,1726526391.021 [DATMMP](INFO): Starting
2024-09-16T22:39:51.273Z,1726526391.273 [DATMMP](INFO): Starting
2024-09-16T22:39:51.525Z,1726526391.525 [DATMMP](INFO): Starting
2024-09-16T22:39:51.777Z,1726526391.777 [DATMMP](INFO): Starting
2024-09-16T22:39:52.029Z,1726526392.029 [DATMMP](INFO): Starting
2024-09-16T22:39:52.280Z,1726526392.280 [DATMMP](INFO): Starting
2024-09-16T22:39:52.533Z,1726526392.533 [DATMMP](INFO): Starting
2024-09-16T22:39:52.785Z,1726526392.785 [DATMMP](INFO): Starting
2024-09-16T22:39:52.785Z,1726526392.785 [DATMMP](INFO): DAT read:
2024-09-16T22:39:52.786Z,1726526392.786 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-09-16T22:39:53.037Z,1726526393.037 [DATMMP](INFO): Starting
2024-09-16T22:39:53.289Z,1726526393.289 [DATMMP](INFO): Starting
2024-09-16T22:39:53.541Z,1726526393.541 [DATMMP](INFO): Starting
2024-09-16T22:39:53.793Z,1726526393.793 [DATMMP](INFO): Starting
2024-09-16T22:39:54.045Z,1726526394.045 [DATMMP](INFO): Starting
2024-09-16T22:39:54.297Z,1726526394.297 [DATMMP](INFO): Starting
2024-09-16T22:39:54.324Z,1726526394.324 [DATMMP](INFO): DAT read: MF Frequency Band
2024-09-16T22:39:54.326Z,1726526394.326 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-09-16T22:39:54.326Z,1726526394.326 [DATMMP](INFO): DAT read: Aug 16 2000 22:39:49
2024-09-16T22:39:54.549Z,1726526394.549 [DATMMP](INFO): Starting
2024-09-16T22:39:54.801Z,1726526394.801 [DATMMP](INFO): Starting
2024-09-16T22:39:55.053Z,1726526395.053 [DATMMP](INFO): Starting
2024-09-16T22:39:55.305Z,1726526395.305 [DATMMP](INFO): Starting
2024-09-16T22:39:55.557Z,1726526395.557 [DATMMP](INFO): Starting
2024-09-16T22:39:55.558Z,1726526395.558 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2024-09-16T22:39:55.559Z,1726526395.559 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-09-16T22:39:55.559Z,1726526395.559 [DATMMP](INFO): commRate: 1200
2024-09-16T22:39:55.809Z,1726526395.809 [DATMMP](INFO): Starting
2024-09-16T22:39:56.061Z,1726526396.061 [DATMMP](INFO): Starting
2024-09-16T22:39:56.313Z,1726526396.313 [DATMMP](INFO): Starting
2024-09-16T22:39:56.565Z,1726526396.565 [DATMMP](INFO): Starting
2024-09-16T22:39:56.817Z,1726526396.817 [DATMMP](INFO): Starting
2024-09-16T22:39:57.069Z,1726526397.069 [DATMMP](INFO): Starting
2024-09-16T22:39:57.321Z,1726526397.321 [DATMMP](INFO): Starting
2024-09-16T22:39:57.573Z,1726526397.573 [DATMMP](INFO): Starting
2024-09-16T22:39:57.625Z,1726526397.625 [DATMMP](INFO): entering command mode
2024-09-16T22:39:57.825Z,1726526397.825 [DATMMP](INFO): Starting
2024-09-16T22:39:57.825Z,1726526397.825 [DATMMP](INFO): DAT read:
2024-09-16T22:39:57.825Z,1726526397.825 [DATMMP](INFO): DAT read: user:1>
2024-09-16T22:39:57.826Z,1726526397.826 [DATMMP](DEBUG): read user prompt 1: user:1>
2024-09-16T22:39:57.826Z,1726526397.826 [DATMMP](INFO): entering MMP mode
2024-09-16T22:39:58.081Z,1726526398.081 [DATMMP](INFO): Starting
2024-09-16T22:39:58.081Z,1726526398.081 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T22:39:58.083Z,1726526398.083 [DATMMP](INFO): status rx: x1
2024-09-16T22:39:58.084Z,1726526398.084 [DATMMP](INFO): MMP status: 1: started
2024-09-16T22:39:58.084Z,1726526398.084 [DATMMP](INFO): Received message type: status
2024-09-16T22:39:58.084Z,1726526398.084 [DATMMP](INFO): Handled
2024-09-16T22:39:58.329Z,1726526398.329 [DATMMP](INFO): Starting
2024-09-16T22:39:58.330Z,1726526398.330 [DATMMP](INFO): Sent Tx Power Config 8
2024-09-16T22:39:58.330Z,1726526398.330 [DATMMP](INFO): Setting local address: 5
2024-09-16T22:39:58.332Z,1726526398.332 [DATMMP](INFO): Setting time to: 22:39:58 And date to:9/16/2024
2024-09-16T22:39:58.581Z,1726526398.581 [DATMMP](INFO): Starting
2024-09-16T22:39:58.581Z,1726526398.581 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T22:39:58.582Z,1726526398.582 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2024-09-16T22:39:58.582Z,1726526398.582 [DATMMP](INFO): Received message type: mdmpower
2024-09-16T22:39:58.582Z,1726526398.582 [DATMMP](INFO): Handled
2024-09-16T22:39:58.833Z,1726526398.833 [DATMMP](INFO): Starting
2024-09-16T22:39:58.834Z,1726526398.834 [DATMMP](INFO): GSXN notify for xid: 10
2024-09-16T22:39:58.835Z,1726526398.835 [DATMMP](INFO): sreg_txpower rx: x8
2024-09-16T22:39:58.835Z,1726526398.835 [DATMMP](INFO): Received message type: sreg_txpower
2024-09-16T22:39:58.835Z,1726526398.835 [DATMMP](INFO): Match for : sreg_txpower
2024-09-16T22:39:58.835Z,1726526398.835 [DATMMP](INFO): ACK
2024-09-16T22:39:59.085Z,1726526399.085 [DATMMP](INFO): Starting
2024-09-16T22:39:59.085Z,1726526399.085 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T22:39:59.086Z,1726526399.086 [DATMMP](INFO): mdmlocal rx: x5 x0 x0 x0
2024-09-16T22:39:59.086Z,1726526399.086 [DATMMP](INFO): Received message type: mdmlocal
2024-09-16T22:39:59.086Z,1726526399.086 [DATMMP](INFO): Handled
2024-09-16T22:39:59.177Z,1726526399.177 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2024-09-16T22:39:59.337Z,1726526399.337 [DATMMP](INFO): Starting
2024-09-16T22:39:59.337Z,1726526399.337 [DATMMP](INFO): GSXN notify for xid: 11
2024-09-16T22:39:59.338Z,1726526399.338 [DATMMP](INFO): sreg_locaddr rx: x5
2024-09-16T22:39:59.338Z,1726526399.338 [DATMMP](INFO): Received message type: sreg_locaddr
2024-09-16T22:39:59.338Z,1726526399.338 [DATMMP](INFO): Match for : sreg_locaddr
2024-09-16T22:39:59.338Z,1726526399.338 [DATMMP](INFO): ACK
2024-09-16T22:39:59.589Z,1726526399.589 [DATMMP](INFO): Starting
2024-09-16T22:39:59.589Z,1726526399.589 [DATMMP](INFO): GSXN notify for xid: 12
2024-09-16T22:39:59.590Z,1726526399.590 [DATMMP](INFO): timedate rx: x0 x16 x27 x3A x8 x10 x7C x0
2024-09-16T22:39:59.590Z,1726526399.590 [DATMMP](INFO): Received message type: timedate
2024-09-16T22:39:59.591Z,1726526399.591 [DATMMP](INFO): Match for : timedate
2024-09-16T22:39:59.591Z,1726526399.591 [DATMMP](INFO): ACK
2024-09-16T22:39:59.841Z,1726526399.841 [DATMMP](INFO): Starting
2024-09-16T22:39:59.841Z,1726526399.841 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T22:39:59.842Z,1726526399.842 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2024-09-16T22:39:59.842Z,1726526399.842 [DATMMP](INFO): Received message type: sys_verb
2024-09-16T22:39:59.842Z,1726526399.842 [DATMMP](INFO): Handled
2024-09-16T22:40:00.093Z,1726526400.093 [DATMMP](INFO): Starting
2024-09-16T22:40:00.093Z,1726526400.093 [DATMMP](INFO): GSXN notify for xid: 13
2024-09-16T22:40:00.094Z,1726526400.094 [DATMMP](INFO): sreg_verbosity rx: x0
2024-09-16T22:40:00.094Z,1726526400.094 [DATMMP](INFO): Received message type: sreg_verbosity
2024-09-16T22:40:00.094Z,1726526400.094 [DATMMP](INFO): Match for : sreg_verbosity
2024-09-16T22:40:00.094Z,1726526400.094 [DATMMP](INFO): ACK
2024-09-16T22:40:00.345Z,1726526400.345 [DATMMP](INFO): Starting
2024-09-16T22:40:00.345Z,1726526400.345 [DATMMP](INFO): GSXN notify for xid: 14
2024-09-16T22:40:00.346Z,1726526400.346 [DATMMP](INFO): version rx: x2 x5
2024-09-16T22:40:00.346Z,1726526400.346 [DATMMP](INFO): DatMMPVersion: 2.5
2024-09-16T22:40:00.347Z,1726526400.347 [DATMMP](INFO): Received message type: version
2024-09-16T22:40:00.347Z,1726526400.347 [DATMMP](INFO): Match for : version
2024-09-16T22:40:00.347Z,1726526400.347 [DATMMP](INFO): ACK
2024-09-16T22:40:00.347Z,1726526400.347 [DATMMP](INFO): in MMP mode: config complete going to runnable
2024-09-16T22:40:10.084Z,1726526410.084 [NAL9602](INFO): Powering up NAL9602
2024-09-16T22:40:14.725Z,1726526414.725 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T22:40:14.726Z,1726526414.726 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2024-09-16T22:40:14.726Z,1726526414.726 [DATMMP](INFO): Received message type: timesync
2024-09-16T22:40:14.726Z,1726526414.726 [DATMMP](INFO): Handled
2024-09-16T22:40:20.983Z,1726526420.983 [NAL9602](INFO): NAL9602 initialized
2024-09-16T22:40:24.573Z,1726526424.573 [DATMMP](INFO): GSXN notify for xid: 0
2024-09-16T22:40:24.574Z,1726526424.574 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2024-09-16T22:40:24.574Z,1726526424.574 [DATMMP](INFO): Received message type: timesync
2024-09-16T22:40:24.574Z,1726526424.574 [DATMMP](INFO): Handled
2024-09-16T22:40:51.305Z,1726526451.305 [SBIT](IMPORTANT): Beginning Startup BIT
2024-09-16T22:40:51.309Z,1726526451.309 [CBIT](IMPORTANT): Beginning ground fault scan
2024-09-16T22:41:02.253Z,1726526462.253 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006035
CHAN A1 (24V): -0.030504
CHAN A2 (12V): -0.005925
CHAN A3 (5V): -0.002710
CHAN B0 (3.3V): -0.001476
CHAN B1 (3.15aV): -0.000964
CHAN B2 (3.15bV): -0.000972
CHAN B3 (GND): -0.000354
OPEN: 0.006851
Full Scale: +/- 1 mA
2024-09-16T22:41:45.448Z,1726526505.448 [SBIT](IMPORTANT): SBIT PASSED
2024-09-16T22:41:45.449Z,1726526505.449 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-09-16T22:41:45.450Z,1726526505.450 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2024-09-16T22:41:45.450Z,1726526505.450 [SBIT](IMPORTANT): CBIT.gf24Offset=180 microampere;
2024-09-16T22:41:45.450Z,1726526505.450 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-09-16T22:41:45.450Z,1726526505.450 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-09-16T22:41:45.450Z,1726526505.450 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-09-16T22:41:45.450Z,1726526505.450 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;
2024-09-16T22:41:45.450Z,1726526505.450 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=280.676 cubic_centimeter;
2024-09-16T22:41:45.451Z,1726526505.451 [SBIT](IMPORTANT): VerticalControl.massDefault=12.513 millimeter;
2024-09-16T22:41:45.857Z,1726526505.857 [MissionManager](IMPORTANT): Started mission Startup
2024-09-16T22:41:45.857Z,1726526505.857 [Startup] Running Loop=1
2024-09-16T22:41:45.857Z,1726526505.857 [Startup](DEBUG): Aggregate::initialize Startup
2024-09-16T22:41:45.857Z,1726526505.857 [Startup:A.GoToSurface] Running Loop=1
2024-09-16T22:41:45.858Z,1726526505.858 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T22:41:45.858Z,1726526505.858 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T22:41:45.858Z,1726526505.858 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T22:41:45.859Z,1726526505.859 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T22:41:45.859Z,1726526505.859 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T22:41:45.859Z,1726526505.859 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T22:41:45.860Z,1726526505.860 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T22:41:45.862Z,1726526505.862 [Startup:StartupSatComms] Running Loop=1
2024-09-16T22:41:45.862Z,1726526505.862 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-09-16T22:41:45.862Z,1726526505.862 [Startup:StartupSatComms:A] Running Loop=1
2024-09-16T22:41:46.235Z,1726526506.235 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-09-16T22:41:52.631Z,1726526512.631 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004901
2024-09-16T22:42:40.813Z,1726526560.813 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-09-16T22:42:40.813Z,1726526560.813 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T22:42:40.829Z,1726526560.829 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T22:42:41.235Z,1726526561.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T22:42:41.235Z,1726526561.235 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-09-16T22:42:46.030Z,1726526566.030 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-16T22:41:45.9Z
2024-09-16T22:42:46.030Z,1726526566.030 [Startup:StartupSatComms:A] Stopped
2024-09-16T22:42:46.030Z,1726526566.030 [Startup:StartupSatComms:B] Running Loop=1
2024-09-16T22:42:46.444Z,1726526566.444 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T22:42:52.564Z,1726526572.564 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240916T183021/Courier0013.lzma
2024-09-16T22:42:53.567Z,1726526573.567 [DataOverHttps](INFO): Moved sent file to Logs/20240916T183021/Courier0013.lzma.bak
2024-09-16T22:42:53.567Z,1726526573.567 [DataOverHttps](INFO): SBD MOMSN=23192703
2024-09-16T22:43:09.345Z,1726526589.345 [DataOverHttps](INFO): Sending 360 bytes from file Logs/20240916T183021/Courier0019.lzma
2024-09-16T22:43:10.346Z,1726526590.346 [DataOverHttps](INFO): Moved sent file to Logs/20240916T183021/Courier0019.lzma.bak
2024-09-16T22:43:10.346Z,1726526590.346 [DataOverHttps](INFO): SBD MOMSN=23192705
2024-09-16T22:43:26.180Z,1726526606.180 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20240916T223929/Courier0000.lzma
2024-09-16T22:43:27.183Z,1726526607.183 [DataOverHttps](INFO): Moved sent file to Logs/20240916T223929/Courier0000.lzma.bak
2024-09-16T22:43:27.183Z,1726526607.183 [DataOverHttps](INFO): SBD MOMSN=23192714
2024-09-16T22:43:42.928Z,1726526622.928 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20240916T183021/Express0014.lzma
2024-09-16T22:43:43.930Z,1726526623.930 [DataOverHttps](INFO): Moved sent file to Logs/20240916T183021/Express0014.lzma.bak
2024-09-16T22:43:43.930Z,1726526623.930 [DataOverHttps](INFO): SBD MOMSN=23192719
2024-09-16T22:43:46.236Z,1726526626.236 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-16T22:42:46.0Z
2024-09-16T22:43:46.236Z,1726526626.236 [Startup:StartupSatComms:B] Stopped
2024-09-16T22:43:46.237Z,1726526626.237 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-09-16T22:43:46.237Z,1726526626.237 [Startup:StartupSatComms] Stopped
2024-09-16T22:43:46.237Z,1726526626.237 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-09-16T22:43:46.238Z,1726526626.238 [Startup](INFO): Completed Startup
2024-09-16T22:43:46.238Z,1726526626.238 [MissionManager](INFO): Startup is completed.
2024-09-16T22:43:46.238Z,1726526626.238 [MissionManager](INFO): Uninitializing Mission Startup
2024-09-16T22:43:46.238Z,1726526626.238 [Startup] Stopped
2024-09-16T22:43:46.238Z,1726526626.238 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-09-16T22:43:46.238Z,1726526626.238 [Startup:A.GoToSurface] Stopped
2024-09-16T22:43:46.238Z,1726526626.238 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T22:43:46.634Z,1726526626.634 [MissionManager](IMPORTANT): Started mission Default
2024-09-16T22:43:46.634Z,1726526626.634 [Default] Running Loop=1
2024-09-16T22:43:46.634Z,1726526626.634 [Default](DEBUG): Aggregate::initialize Default
2024-09-16T22:43:46.634Z,1726526626.634 [Default:B.GoToSurface] Running Loop=1
2024-09-16T22:43:46.634Z,1726526626.634 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-09-16T22:43:46.635Z,1726526626.635 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-09-16T22:43:46.635Z,1726526626.635 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-09-16T22:43:46.635Z,1726526626.635 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-09-16T22:43:46.635Z,1726526626.635 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-09-16T22:43:46.636Z,1726526626.636 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-09-16T22:43:46.636Z,1726526626.636 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-09-16T22:43:46.661Z,1726526626.661 [Default:A.Wait] Running Loop=1
2024-09-16T22:43:46.661Z,1726526626.661 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-09-16T22:43:59.824Z,1726526639.824 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20240916T183021/Express0017.lzma
2024-09-16T22:43:59.965Z,1726526639.965 [Default:A.Wait](INFO): Done Waiting.
2024-09-16T22:43:59.965Z,1726526639.965 [Default:A.Wait] Stopped
2024-09-16T22:43:59.965Z,1726526639.965 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T22:44:00.386Z,1726526640.386 [Default:CheckIn] Running Loop=1
2024-09-16T22:44:00.387Z,1726526640.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T22:44:00.387Z,1726526640.387 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T22:44:00.786Z,1726526640.786 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-09-16T22:44:00.826Z,1726526640.826 [DataOverHttps](INFO): Moved sent file to Logs/20240916T183021/Express0017.lzma.bak
2024-09-16T22:44:00.826Z,1726526640.826 [DataOverHttps](INFO): SBD MOMSN=23192726
2024-09-16T22:45:23.983Z,1726526723.983 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-09-16T22:45:41.763Z,1726526741.763 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-09-16T22:45:41.763Z,1726526741.763 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T22:45:41.774Z,1726526741.774 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T22:45:42.207Z,1726526742.207 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T22:45:42.207Z,1726526742.207 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-09-16T22:47:17.108Z,1726526837.108 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2024-09-16T22:47:17.111Z,1726526837.111 [BPC1](INFO): Received data from all battery sticks.
2024-09-16T22:48:42.769Z,1726526922.769 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-09-16T22:48:42.769Z,1726526922.769 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T22:48:42.780Z,1726526922.780 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T22:48:43.199Z,1726526923.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T22:48:43.199Z,1726526923.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-09-16T22:49:00.563Z,1726526940.563 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-16T22:44:00.4Z
2024-09-16T22:49:00.563Z,1726526940.563 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T22:49:00.563Z,1726526940.563 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T22:49:00.946Z,1726526940.946 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-09-16T22:49:03.628Z,1726526943.628 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240916T223929/Courier0004.lzma
2024-09-16T22:49:04.622Z,1726526944.622 [DataOverHttps](INFO): Moved sent file to Logs/20240916T223929/Courier0004.lzma.bak
2024-09-16T22:49:04.622Z,1726526944.622 [DataOverHttps](INFO): SBD MOMSN=23192821
2024-09-16T22:49:20.375Z,1726526960.375 [DataOverHttps](INFO): Sending 1482 bytes from file Logs/20240916T183021/Express0020.lzma
2024-09-16T22:49:21.374Z,1726526961.374 [DataOverHttps](INFO): Moved sent file to Logs/20240916T183021/Express0020.lzma.bak
2024-09-16T22:49:21.374Z,1726526961.374 [DataOverHttps](INFO): SBD MOMSN=23192823
2024-09-16T22:49:37.093Z,1726526977.093 [DataOverHttps](INFO): Sending 898 bytes from file Logs/20240916T223929/Express0001.lzma
2024-09-16T22:49:38.094Z,1726526978.094 [DataOverHttps](INFO): Moved sent file to Logs/20240916T223929/Express0001.lzma.bak
2024-09-16T22:49:38.094Z,1726526978.094 [DataOverHttps](INFO): SBD MOMSN=23192919
2024-09-16T22:49:52.745Z,1726526992.745 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20240916T223929/Express0005.lzma
2024-09-16T22:49:53.742Z,1726526993.742 [DataOverHttps](INFO): Moved sent file to Logs/20240916T223929/Express0005.lzma.bak
2024-09-16T22:49:53.742Z,1726526993.742 [DataOverHttps](INFO): SBD MOMSN=23192941
2024-09-16T22:49:55.134Z,1726526995.134 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T22:49:55.134Z,1726526995.134 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T22:49:55.134Z,1726526995.134 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T22:50:23.023Z,1726527023.023 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-09-16T22:50:23.023Z,1726527023.023 [NAL9602] Data Fault, FailCount= 1
2024-09-16T22:50:23.023Z,1726527023.023 [NAL9602](ERROR): Data Fault
2024-09-16T22:50:23.105Z,1726527023.105 [CBIT](ERROR): Data Fault in component: NAL9602
2024-09-16T22:50:23.402Z,1726527023.402 [NAL9602](INFO): Powering down
2024-09-16T22:50:24.262Z,1726527024.262 [CBIT](INFO): Clearing failed state for component NAL9602
2024-09-16T22:50:24.262Z,1726527024.262 [NAL9602] No Fault, FailCount= 1
2024-09-16T22:50:53.711Z,1726527053.711 [NAL9602](INFO): Powering up NAL9602
2024-09-16T22:51:04.611Z,1726527064.611 [NAL9602](INFO): NAL9602 initialized
2024-09-16T22:51:43.825Z,1726527103.825 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-09-16T22:51:43.825Z,1726527103.825 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T22:51:43.836Z,1726527103.836 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T22:51:44.223Z,1726527104.223 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T22:51:44.223Z,1726527104.223 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-09-16T22:54:41.000Z,1726527282.000 [CBIT](INFO): Clearing failed state for component DropWeight
2024-09-16T22:54:41.000Z,1726527282.000 [DropWeight] No Fault, FailCount= 1
2024-09-16T22:54:44.817Z,1726527284.817 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-09-16T22:54:44.817Z,1726527284.817 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T22:54:44.827Z,1726527284.827 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T22:54:45.231Z,1726527285.231 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T22:54:45.231Z,1726527285.231 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-09-16T22:54:55.733Z,1726527295.733 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-09-16T22:54:55.733Z,1726527295.733 [Default:CheckIn:C.Wait] Stopped
2024-09-16T22:54:55.733Z,1726527295.733 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T22:54:55.733Z,1726527295.733 [Default:CheckIn:D] Running Loop=1
2024-09-16T22:54:56.111Z,1726527296.111 [Default:CheckIn:D] Stopped
2024-09-16T22:54:56.111Z,1726527296.111 [Default:CheckIn:E] Running Loop=1
2024-09-16T22:54:56.543Z,1726527296.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.157945 min
2024-09-16T22:54:56.543Z,1726527296.543 [Default:CheckIn:E] Stopped
2024-09-16T22:54:56.543Z,1726527296.543 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-09-16T22:54:56.543Z,1726527296.543 [Default:CheckIn] Stopped
2024-09-16T22:54:56.543Z,1726527296.543 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T22:54:56.543Z,1726527296.543 [Default:CheckIn](INFO): Running loop #2
2024-09-16T22:54:56.544Z,1726527296.544 [Default:CheckIn] Running Loop=2
2024-09-16T22:54:56.544Z,1726527296.544 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-09-16T22:54:56.544Z,1726527296.544 [Default:CheckIn:Read_GPS] Running Loop=1
2024-09-16T22:57:45.833Z,1726527465.833 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-09-16T22:57:45.833Z,1726527465.833 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T22:57:45.865Z,1726527465.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T22:57:46.246Z,1726527466.246 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T22:57:46.247Z,1726527466.247 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-09-16T22:59:56.746Z,1726527596.746 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-09-16T22:54:56.5Z
2024-09-16T22:59:56.746Z,1726527596.746 [Default:CheckIn:Read_GPS] Stopped
2024-09-16T22:59:56.746Z,1726527596.746 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-09-16T22:59:57.518Z,1726527597.518 [Default:CheckIn:Read_Iridium] Stopped
2024-09-16T22:59:57.518Z,1726527597.518 [Default:CheckIn:C.Wait] Running Loop=1
2024-09-16T22:59:57.518Z,1726527597.518 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-09-16T23:00:04.381Z,1726527604.381 [DVL_micro](ERROR): Failed to parse:
:BI,-00024,+00077,-00032,+00000,I
2024-09-16T23:00:46.834Z,1726527646.834 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-09-16T23:00:46.834Z,1726527646.834 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-09-16T23:00:46.863Z,1726527646.863 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-09-16T23:00:47.228Z,1726527647.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-09-16T23:00:47.228Z,1726527647.228 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-09-16T23:01:06.605Z,1726527666.605 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-09-16T23:01:06.605Z,1726527666.605 [NAL9602] Data Fault, FailCount= 2
2024-09-16T23:01:06.605Z,1726527666.605 [NAL9602](ERROR): Data Fault
2024-09-16T23:01:06.621Z,1726527666.621 [CBIT](ERROR): Data Fault in component: NAL9602
2024-09-16T23:01:07.000Z,1726527667.000 [NAL9602](INFO): Powering down
2024-09-16T23:01:07.822Z,1726527667.822 [CBIT](INFO): Clearing failed state for component NAL9602
2024-09-16T23:01:07.822Z,1726527667.822 [NAL9602] No Fault, FailCount= 2
2024-09-16T23:01:37.336Z,1726527697.336 [NAL9602](INFO): Powering up NAL9602
2024-09-16T23:01:48.210Z,1726527708.210 [NAL9602](INFO): NAL9602 initialized
2024-09-16T23:03:22.510Z,1726527802.510 [CommandExec](IMPORTANT): got command restart system
2024-09-16T23:03:24.633Z,1726527804.633 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:24.633Z,1726527804.633 [CommandExec](INFO): Uninitializing the command executive.
2024-09-16T23:03:24.633Z,1726527804.633 [CommandExec](INFO): Uninitializing the command scheduler.
2024-09-16T23:03:24.634Z,1726527804.634 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:24.753Z,1726527804.753 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-09-16T23:03:24.753Z,1726527804.753 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-09-16T23:03:24.753Z,1726527804.753 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:24.754Z,1726527804.754 [NavChartDb](INFO): Join timeout helper Thread ID is 4142
2024-09-16T23:03:25.033Z,1726527805.033 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:25.033Z,1726527805.033 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:25.040Z,1726527805.040 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-09-16T23:03:25.041Z,1726527805.041 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:25.041Z,1726527805.041 [Radio_Surface](INFO): Join timeout helper Thread ID is 4143
2024-09-16T23:03:25.293Z,1726527805.293 [Radio_Surface](INFO): Powering down
2024-09-16T23:03:25.294Z,1726527805.294 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:25.294Z,1726527805.294 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:25.304Z,1726527805.304 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-09-16T23:03:25.305Z,1726527805.305 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:25.305Z,1726527805.305 [Onboard](INFO): Join timeout helper Thread ID is 4144
2024-09-16T23:03:25.749Z,1726527805.749 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-09-16T23:03:26.917Z,1726527806.917 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:26.917Z,1726527806.917 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:26.920Z,1726527806.920 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-09-16T23:03:26.921Z,1726527806.921 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:26.921Z,1726527806.921 [DataOverHttps](INFO): Join timeout helper Thread ID is 4145
2024-09-16T23:03:27.757Z,1726527807.757 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:27.757Z,1726527807.757 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:27.770Z,1726527807.770 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler
2024-09-16T23:03:27.770Z,1726527807.770 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:27.770Z,1726527807.770 [DATMMP](INFO): Join timeout helper Thread ID is 4146
2024-09-16T23:03:27.873Z,1726527807.873 [DATMMP](INFO): Stop
2024-09-16T23:03:27.873Z,1726527807.873 [DATMMP](INFO): uninitialize
2024-09-16T23:03:27.873Z,1726527807.873 [DATMMP](INFO): Powering down
2024-09-16T23:03:27.949Z,1726527807.949 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:27.949Z,1726527807.949 [DATMMP](INFO): uninitialize
2024-09-16T23:03:27.950Z,1726527807.950 [DATMMP](INFO): Powering down
2024-09-16T23:03:27.951Z,1726527807.951 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:27.953Z,1726527807.953 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-09-16T23:03:27.953Z,1726527807.953 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:27.954Z,1726527807.954 [BackseatComponent](INFO): Join timeout helper Thread ID is 4147
2024-09-16T23:03:28.113Z,1726527808.113 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:28.113Z,1726527808.113 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.131Z,1726527808.131 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-09-16T23:03:28.132Z,1726527808.132 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.132Z,1726527808.132 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4148
2024-09-16T23:03:28.525Z,1726527808.525 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:28.525Z,1726527808.525 [WetLabsBB2FL](INFO): Powering down
2024-09-16T23:03:28.526Z,1726527808.526 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.533Z,1726527808.533 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-09-16T23:03:28.533Z,1726527808.533 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.534Z,1726527808.534 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4149
2024-09-16T23:03:28.649Z,1726527808.649 [CTD_Seabird](INFO): Powering down
2024-09-16T23:03:28.661Z,1726527808.661 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:28.661Z,1726527808.661 [CTD_Seabird](INFO): Powering down
2024-09-16T23:03:28.673Z,1726527808.673 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.677Z,1726527808.677 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-09-16T23:03:28.677Z,1726527808.677 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.678Z,1726527808.678 [logger](INFO): Join timeout helper Thread ID is 4150
2024-09-16T23:03:28.709Z,1726527808.709 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:28.709Z,1726527808.709 [logger ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.721Z,1726527808.721 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-09-16T23:03:28.721Z,1726527808.721 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.722Z,1726527808.722 [CommandLine](INFO): Join timeout helper Thread ID is 4151
2024-09-16T23:03:28.797Z,1726527808.797 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:28.797Z,1726527808.797 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.801Z,1726527808.801 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-09-16T23:03:28.801Z,1726527808.801 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.802Z,1726527808.802 [CommandExec](INFO): Join timeout helper Thread ID is 4152
2024-09-16T23:03:28.809Z,1726527808.809 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-09-16T23:03:28.809Z,1726527808.809 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:28.810Z,1726527808.810 [controlThread](INFO): Join timeout helper Thread ID is 4153
2024-09-16T23:03:28.834Z,1726527808.834 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-09-16T23:03:28.834Z,1726527808.834 [controlThread](DEBUG): Uninitializing ControlThread
2024-09-16T23:03:28.836Z,1726527808.836 [AHRS_M2](INFO): Powering down
2024-09-16T23:03:29.005Z,1726527809.005 [DVL_micro](INFO): Powering down
2024-09-16T23:03:29.006Z,1726527809.006 [NAL9602](INFO): Powering down
2024-09-16T23:03:29.007Z,1726527809.007 [Sonardyne_Nano](INFO): Powering down
2024-09-16T23:03:29.125Z,1726527809.125 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-09-16T23:03:29.126Z,1726527809.126 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-09-16T23:03:29.127Z,1726527809.127 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-09-16T23:03:29.127Z,1726527809.127 [MissionManager](INFO): Uninitializing Mission Default
2024-09-16T23:03:29.127Z,1726527809.127 [Default] Stopped
2024-09-16T23:03:29.127Z,1726527809.127 [Default](DEBUG): Aggregate::uninitialize Default
2024-09-16T23:03:29.127Z,1726527809.127 [Default:B.GoToSurface] Stopped
2024-09-16T23:03:29.127Z,1726527809.127 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-09-16T23:03:29.127Z,1726527809.127 [Default:CheckIn] Stopped
2024-09-16T23:03:29.128Z,1726527809.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-09-16T23:03:29.128Z,1726527809.128 [Default:CheckIn:C.Wait] Stopped
2024-09-16T23:03:29.128Z,1726527809.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-09-16T23:03:29.131Z,1726527809.131 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-09-16T23:03:29.131Z,1726527809.131 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-09-16T23:03:29.131Z,1726527809.131 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-09-16T23:03:29.132Z,1726527809.132 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-09-16T23:03:29.132Z,1726527809.132 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-09-16T23:03:29.132Z,1726527809.132 [BuoyancyServo](INFO): Powering down
2024-09-16T23:03:29.145Z,1726527809.145 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-09-16T23:03:29.145Z,1726527809.145 [ElevatorServo](INFO): Powering down
2024-09-16T23:03:29.146Z,1726527809.146 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-09-16T23:03:29.146Z,1726527809.146 [MassServo](INFO): Powering down
2024-09-16T23:03:29.147Z,1726527809.147 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-09-16T23:03:29.147Z,1726527809.147 [RudderServo](INFO): Powering down
2024-09-16T23:03:29.148Z,1726527809.148 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-09-16T23:03:29.148Z,1726527809.148 [ThrusterHE](INFO): Powering down
2024-09-16T23:03:29.149Z,1726527809.149 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-09-16T23:03:29.149Z,1726527809.149 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-09-16T23:03:29.150Z,1726527809.150 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-09-16T23:03:29.150Z,1726527809.150 [CBIT](DEBUG): Powering off loads.
2024-09-16T23:03:29.160Z,1726527809.160 [CBIT](DEBUG): Disabling WDT.
2024-09-16T23:03:29.172Z,1726527809.172 [CBIT](DEBUG): Opening all GF detection circuits.
2024-09-16T23:03:29.174Z,1726527809.174 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.226Z,1726527809.226 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.233Z,1726527809.233 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.273Z,1726527809.273 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.275Z,1726527809.275 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.283Z,1726527809.283 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.289Z,1726527809.289 [DATMMP ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.332Z,1726527809.332 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.398Z,1726527809.398 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-09-16T23:03:29.463Z,1726527809.463 [logger ThreadHandler](INFO): Thread cancelled.