2024-04-23T22:57:29.543Z,1713913049.543 [Supervisor](DEBUG): Initializing supervisor.
2024-04-23T22:57:29.547Z,1713913049.547 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-04-23T22:57:29.548Z,1713913049.548 [SyncHandler](INFO): Protected caller Thread ID is 6178
2024-04-23T22:57:29.548Z,1713913049.548 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-04-23T22:57:29.549Z,1713913049.549 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-04-23T22:57:29.549Z,1713913049.549 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6179
2024-04-23T22:57:29.553Z,1713913049.553 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-04-23T22:57:29.571Z,1713913049.571 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-04-23T22:57:29.572Z,1713913049.572 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-04-23T22:57:29.572Z,1713913049.572 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6180
2024-04-23T22:57:29.576Z,1713913049.576 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-04-23T22:57:29.577Z,1713913049.577 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-04-23T22:57:29.578Z,1713913049.578 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6181
2024-04-23T22:57:29.580Z,1713913049.580 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-04-23T22:57:29.581Z,1713913049.581 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-04-23T22:57:29.581Z,1713913049.581 [logger ThreadHandler](INFO): Protected caller Thread ID is 6182
2024-04-23T22:57:29.585Z,1713913049.585 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-04-23T22:57:29.585Z,1713913049.585 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-04-23T22:57:29.587Z,1713913049.587 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-04-23T22:57:29.867Z,1713913049.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-04-23T22:57:29.868Z,1713913049.868 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-04-23T22:57:29.980Z,1713913049.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-04-23T22:57:29.980Z,1713913049.980 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-04-23T22:57:30.078Z,1713913050.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-04-23T22:57:30.079Z,1713913050.079 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-04-23T22:57:30.574Z,1713913050.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-04-23T22:57:30.575Z,1713913050.575 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-04-23T22:57:31.010Z,1713913051.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-04-23T22:57:31.011Z,1713913051.011 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-04-23T22:57:31.372Z,1713913051.372 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-04-23T22:57:31.372Z,1713913051.372 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-04-23T22:57:31.571Z,1713913051.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-04-23T22:57:31.572Z,1713913051.572 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-04-23T22:57:31.654Z,1713913051.654 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-04-23T22:57:31.737Z,1713913051.737 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-04-23T22:57:32.156Z,1713913052.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-04-23T22:57:32.156Z,1713913052.156 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-04-23T22:57:32.447Z,1713913052.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-04-23T22:57:32.448Z,1713913052.448 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-04-23T22:57:32.978Z,1713913052.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-04-23T22:57:32.978Z,1713913052.978 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-04-23T22:57:33.216Z,1713913053.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-04-23T22:57:33.216Z,1713913053.216 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-04-23T22:57:33.467Z,1713913053.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-04-23T22:57:33.468Z,1713913053.468 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-04-23T22:57:33.611Z,1713913053.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-04-23T22:57:33.612Z,1713913053.612 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-04-23T22:57:34.499Z,1713913054.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-04-23T22:57:34.501Z,1713913054.501 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2024-04-23T22:57:34.502Z,1713913054.502 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2024-04-23T22:57:35.058Z,1713913055.058 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2024-04-23T22:57:35.482Z,1713913055.482 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2024-04-23T22:57:35.753Z,1713913055.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-04-23T22:57:35.753Z,1713913055.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2024-04-23T22:57:35.881Z,1713913055.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2024-04-23T22:57:35.985Z,1713913055.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2024-04-23T22:57:36.081Z,1713913056.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2024-04-23T22:57:36.192Z,1713913056.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2024-04-23T22:57:36.289Z,1713913056.289 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2024-04-23T22:57:36.378Z,1713913056.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2024-04-23T22:57:36.470Z,1713913056.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/workSite.cfg
2024-04-23T22:57:36.556Z,1713913056.556 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2024-04-23T22:57:36.726Z,1713913056.726 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2024-04-23T22:57:36.726Z,1713913056.726 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2024-04-23T22:57:36.735Z,1713913056.735 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-04-23T22:57:36.789Z,1713913056.789 [DepthRateCalculator] Loaded
2024-04-23T22:57:36.789Z,1713913056.789 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-04-23T22:57:36.795Z,1713913056.795 [PitchRateCalculator] Loaded
2024-04-23T22:57:36.795Z,1713913056.795 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-04-23T22:57:36.805Z,1713913056.805 [SpeedCalculator] Loaded
2024-04-23T22:57:36.805Z,1713913056.805 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-04-23T22:57:36.810Z,1713913056.810 [YawRateCalculator] Loaded
2024-04-23T22:57:36.810Z,1713913056.810 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-04-23T22:57:36.831Z,1713913056.831 [ElevatorOffsetCalculator] Loaded
2024-04-23T22:57:36.832Z,1713913056.832 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-04-23T22:57:36.832Z,1713913056.832 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-04-23T22:57:36.833Z,1713913056.833 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-04-23T22:57:36.860Z,1713913056.860 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-04-23T22:57:36.860Z,1713913056.860 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-04-23T22:57:36.870Z,1713913056.870 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-04-23T22:57:36.871Z,1713913056.871 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-04-23T22:57:37.308Z,1713913057.308 [AHRS_M2] Loaded
2024-04-23T22:57:37.308Z,1713913057.308 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-04-23T22:57:37.343Z,1713913057.343 [BackseatComponent] Loaded
2024-04-23T22:57:37.344Z,1713913057.344 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-04-23T22:57:37.345Z,1713913057.345 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0
2024-04-23T22:57:37.345Z,1713913057.345 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6268
2024-04-23T22:57:37.348Z,1713913057.348 [LcmUniversalReporter] Loaded
2024-04-23T22:57:37.348Z,1713913057.348 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-04-23T22:57:38.149Z,1713913058.149 [BPC1] Loaded
2024-04-23T22:57:38.149Z,1713913058.149 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-04-23T22:57:38.227Z,1713913058.227 [DataOverHttps] Loaded
2024-04-23T22:57:38.228Z,1713913058.228 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-04-23T22:57:38.229Z,1713913058.229 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0
2024-04-23T22:57:38.229Z,1713913058.229 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6269
2024-04-23T22:57:38.249Z,1713913058.249 [Depth_Keller] Loaded
2024-04-23T22:57:38.249Z,1713913058.249 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-04-23T22:57:38.254Z,1713913058.254 [DropWeight] Loaded
2024-04-23T22:57:38.254Z,1713913058.254 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-04-23T22:57:38.273Z,1713913058.273 [MultiRay] Loaded
2024-04-23T22:57:38.273Z,1713913058.273 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2024-04-23T22:57:38.335Z,1713913058.335 [NAL9602] Loaded
2024-04-23T22:57:38.335Z,1713913058.335 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-04-23T22:57:38.365Z,1713913058.365 [Onboard] Loaded
2024-04-23T22:57:38.365Z,1713913058.365 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-04-23T22:57:38.366Z,1713913058.366 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0
2024-04-23T22:57:38.366Z,1713913058.366 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6270
2024-04-23T22:57:38.372Z,1713913058.372 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2024-04-23T22:57:38.384Z,1713913058.384 [PowerOnly] Loaded
2024-04-23T22:57:38.384Z,1713913058.384 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-04-23T22:57:38.396Z,1713913058.396 [Power24vConverter] Loaded
2024-04-23T22:57:38.396Z,1713913058.396 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-04-23T22:57:38.410Z,1713913058.410 [Radio_Surface] Loaded
2024-04-23T22:57:38.410Z,1713913058.410 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-04-23T22:57:38.412Z,1713913058.412 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0
2024-04-23T22:57:38.412Z,1713913058.412 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6271
2024-04-23T22:57:38.503Z,1713913058.503 [DAT] Loaded
2024-04-23T22:57:38.503Z,1713913058.503 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-04-23T22:57:38.504Z,1713913058.504 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0
2024-04-23T22:57:38.505Z,1713913058.505 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6272
2024-04-23T22:57:38.505Z,1713913058.505 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-04-23T22:57:38.506Z,1713913058.506 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-04-23T22:57:38.619Z,1713913058.619 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-04-23T22:57:38.619Z,1713913058.619 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-04-23T22:57:38.661Z,1713913058.661 [DeadReckonUsingSpeedCalculator] Loaded
2024-04-23T22:57:38.661Z,1713913058.661 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2024-04-23T22:57:38.674Z,1713913058.674 [NavChart] Loaded
2024-04-23T22:57:38.675Z,1713913058.675 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-04-23T22:57:38.680Z,1713913058.680 [UniversalFixResidualReporter] Loaded
2024-04-23T22:57:38.681Z,1713913058.681 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-04-23T22:57:38.681Z,1713913058.681 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-04-23T22:57:38.682Z,1713913058.682 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-04-23T22:57:38.831Z,1713913058.831 [SBIT](DEBUG): Construct Startup Built In Test.
2024-04-23T22:57:38.845Z,1713913058.845 [SBIT] Loaded
2024-04-23T22:57:38.846Z,1713913058.846 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-04-23T22:57:38.849Z,1713913058.849 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-04-23T22:57:38.861Z,1713913058.861 [IBIT] Loaded
2024-04-23T22:57:38.862Z,1713913058.862 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-04-23T22:57:38.867Z,1713913058.867 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-04-23T22:57:38.970Z,1713913058.970 [CBIT] Loaded
2024-04-23T22:57:38.971Z,1713913058.971 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-04-23T22:57:38.971Z,1713913058.971 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-04-23T22:57:38.972Z,1713913058.972 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-04-23T22:57:39.259Z,1713913059.259 [CTD_Seabird] Loaded
2024-04-23T22:57:39.259Z,1713913059.259 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-04-23T22:57:39.260Z,1713913059.260 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0
2024-04-23T22:57:39.261Z,1713913059.261 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6273
2024-04-23T22:57:39.281Z,1713913059.281 [PAR_Licor] Loaded
2024-04-23T22:57:39.281Z,1713913059.281 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-04-23T22:57:39.323Z,1713913059.323 [WetLabsBB2FL] Loaded
2024-04-23T22:57:39.323Z,1713913059.323 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-04-23T22:57:39.324Z,1713913059.324 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2024-04-23T22:57:39.324Z,1713913059.324 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6274
2024-04-23T22:57:39.325Z,1713913059.325 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-04-23T22:57:39.326Z,1713913059.326 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-04-23T22:57:39.462Z,1713913059.462 [BuoyancyServo] Loaded
2024-04-23T22:57:39.462Z,1713913059.462 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-04-23T22:57:39.485Z,1713913059.485 [ElevatorServo] Loaded
2024-04-23T22:57:39.485Z,1713913059.485 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-04-23T22:57:39.505Z,1713913059.505 [MassServo] Loaded
2024-04-23T22:57:39.506Z,1713913059.506 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-04-23T22:57:39.526Z,1713913059.526 [RudderServo] Loaded
2024-04-23T22:57:39.526Z,1713913059.526 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-04-23T22:57:39.542Z,1713913059.542 [ThrusterHE] Loaded
2024-04-23T22:57:39.542Z,1713913059.542 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-04-23T22:57:39.542Z,1713913059.542 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-04-23T22:57:39.543Z,1713913059.543 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-04-23T22:57:39.672Z,1713913059.672 [VerticalControl](DEBUG): Construct VerticalControl.
2024-04-23T22:57:39.798Z,1713913059.798 [VerticalControl] Loaded
2024-04-23T22:57:39.798Z,1713913059.798 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-04-23T22:57:39.801Z,1713913059.801 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-04-23T22:57:39.940Z,1713913059.940 [HorizontalControl] Loaded
2024-04-23T22:57:39.940Z,1713913059.940 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-04-23T22:57:39.942Z,1713913059.942 [SpeedControl](DEBUG): Construct SpeedControl.
2024-04-23T22:57:39.944Z,1713913059.944 [SpeedControl] Loaded
2024-04-23T22:57:39.944Z,1713913059.944 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-04-23T22:57:39.983Z,1713913059.983 [LoopControl](DEBUG): Construct LoopControl.
2024-04-23T22:57:39.984Z,1713913059.984 [LoopControl] Loaded
2024-04-23T22:57:39.984Z,1713913059.984 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-04-23T22:57:39.985Z,1713913059.985 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-04-23T22:57:39.985Z,1713913059.985 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-04-23T22:57:40.311Z,1713913060.311 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-04-23T22:57:40.311Z,1713913060.311 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-04-23T22:57:40.480Z,1713913060.480 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-04-23T22:57:40.480Z,1713913060.480 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-04-23T22:57:40.834Z,1713913060.834 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-04-23T22:57:40.834Z,1713913060.834 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-04-23T22:57:40.892Z,1713913060.892 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-04-23T22:57:40.898Z,1713913060.898 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-04-23T22:57:40.901Z,1713913060.901 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-04-23T22:57:40.912Z,1713913060.912 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-04-23T22:57:40.913Z,1713913060.913 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C234E0
2024-04-23T22:57:40.914Z,1713913060.914 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6275
2024-04-23T22:57:40.918Z,1713913060.918 [Supervisor](INFO): Main Thread ID is 6177
2024-04-23T22:57:40.918Z,1713913060.918 [Supervisor](DEBUG): Running supervisor.
2024-04-23T22:57:40.919Z,1713913060.919 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6276
2024-04-23T22:57:40.919Z,1713913060.919 [CommandExec](INFO): Initializing the command executive.
2024-04-23T22:57:40.921Z,1713913060.921 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6277
2024-04-23T22:57:40.923Z,1713913060.923 [controlThread ThreadHandler](INFO): Handler Thread ID is 6278
2024-04-23T22:57:40.924Z,1713913060.924 [controlThread](DEBUG): Initializing ControlThread
2024-04-23T22:57:40.924Z,1713913060.924 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-04-23T22:57:40.925Z,1713913060.925 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-04-23T22:57:40.925Z,1713913060.925 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-04-23T22:57:40.925Z,1713913060.925 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-04-23T22:57:40.926Z,1713913060.926 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-04-23T22:57:40.934Z,1713913060.934 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T22:57:40.934Z,1713913060.934 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-04-23T22:57:40.935Z,1713913060.935 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-04-23T22:57:40.935Z,1713913060.935 [SBIT](INFO): Initialize SBIT Component.
2024-04-23T22:57:40.935Z,1713913060.935 [SBIT](IMPORTANT): git: 2024-03-26
2024-04-23T22:57:40.936Z,1713913060.936 [SBIT](INFO): git hash: ad310a373d447f5044d653e05ec2c3eb1a52d830
2024-04-23T22:57:40.936Z,1713913060.936 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-04-23T22:57:40.937Z,1713913060.937 [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-04-23T22:57:40.938Z,1713913060.938 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2024-04-23T22:57:40.939Z,1713913060.939 [IBIT](INFO): Initialize IBIT Component.
2024-04-23T22:57:40.940Z,1713913060.940 [CBIT](DEBUG): Initialize CBIT Component.
2024-04-23T22:57:40.941Z,1713913060.941 [logger ThreadHandler](INFO): Handler Thread ID is 6279
2024-04-23T22:57:40.951Z,1713913060.951 [CBIT](DEBUG): Initialized mux pins.
2024-04-23T22:57:40.951Z,1713913060.951 [CBIT](DEBUG): Initializing the watchdog timer.
2024-04-23T22:57:40.960Z,1713913060.960 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6280
2024-04-23T22:57:40.972Z,1713913060.972 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6281
2024-04-23T22:57:40.973Z,1713913060.973 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-04-23T22:57:40.975Z,1713913060.975 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-04-23T22:57:40.976Z,1713913060.976 [CBIT](DEBUG): Initializing heartbeat.
2024-04-23T22:57:40.984Z,1713913060.984 [Onboard ThreadHandler](INFO): Handler Thread ID is 6282
2024-04-23T22:57:41.001Z,1713913061.001 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6283
2024-04-23T22:57:41.020Z,1713913061.020 [DAT ThreadHandler](INFO): Handler Thread ID is 6284
2024-04-23T22:57:41.021Z,1713913061.021 [DAT](INFO): Powering up
2024-04-23T22:57:41.021Z,1713913061.021 [DAT](DEBUG): Initializing DAT.
2024-04-23T22:57:41.025Z,1713913061.025 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6286
2024-04-23T22:57:41.026Z,1713913061.026 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-04-23T22:57:41.032Z,1713913061.032 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6288
2024-04-23T22:57:41.036Z,1713913061.036 [WetLabsBB2FL](INFO): Powering up
2024-04-23T22:57:41.037Z,1713913061.037 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6290
2024-04-23T22:57:41.041Z,1713913061.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-04-23T22:57:41.041Z,1713913061.041 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-04-23T22:57:41.041Z,1713913061.041 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-04-23T22:57:41.042Z,1713913061.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-04-23T22:57:41.042Z,1713913061.042 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-04-23T22:57:41.042Z,1713913061.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-04-23T22:57:41.042Z,1713913061.042 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-04-23T22:57:41.042Z,1713913061.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-04-23T22:57:41.047Z,1713913061.047 [CBIT](DEBUG): Deactivating GF circuits.
2024-04-23T22:57:41.047Z,1713913061.047 [CBIT](DEBUG): Deactivating emergency mode.
2024-04-23T22:57:41.087Z,1713913061.087 [CBIT](DEBUG): Backplane powered.
2024-04-23T22:57:41.093Z,1713913061.093 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-04-23T22:57:41.094Z,1713913061.094 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-04-23T22:57:41.095Z,1713913061.095 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-04-23T22:57:41.095Z,1713913061.095 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-04-23T22:57:41.096Z,1713913061.096 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-04-23T22:57:41.097Z,1713913061.097 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-04-23T22:57:41.097Z,1713913061.097 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-04-23T22:57:41.100Z,1713913061.100 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-04-23T22:57:41.141Z,1713913061.141 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-04-23T22:57:41.146Z,1713913061.146 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-23T22:57:41.191Z,1713913061.191 [MissionManager](DEBUG):
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-04-23T22:57:41.192Z,1713913061.192 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2024-04-23T22:57:41.252Z,1713913061.252 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-04-23T22:57:41.253Z,1713913061.253 [Default:A.Wait](DEBUG): Construct Wait.
2024-04-23T22:57:41.256Z,1713913061.256 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-04-23T22:57:41.284Z,1713913061.284 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-04-23T22:57:41.286Z,1713913061.286 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-04-23T22:57:41.316Z,1713913061.316 [Default:E.Execute](DEBUG): Construct Execute.
2024-04-23T22:57:41.320Z,1713913061.320 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-04-23T22:57:41.325Z,1713913061.325 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-04-23T22:57:41.340Z,1713913061.340 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-04-23T22:57:41.401Z,1713913061.401 [MultiRay](INFO): Powering up MultiRay Lights
2024-04-23T22:57:41.423Z,1713913061.423 [Radio_Surface](INFO): Powering up
2024-04-23T22:57:41.440Z,1713913061.440 [PowerOnly](INFO): Powering up loadControl
2024-04-23T22:57:41.460Z,1713913061.460 [Power24vConverter](INFO): Powering up.
2024-04-23T22:57:41.496Z,1713913061.496 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:57:41.503Z,1713913061.503 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-04-23T22:57:41.505Z,1713913061.505 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:57:41.511Z,1713913061.511 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-04-23T22:57:41.512Z,1713913061.512 [MassServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:57:41.520Z,1713913061.520 [MassServo](DEBUG): Initializing MassServo.
2024-04-23T22:57:41.520Z,1713913061.520 [RudderServo](DEBUG): Initializing EZServoServo.
2024-04-23T22:57:41.528Z,1713913061.528 [RudderServo](DEBUG): Initializing RudderServo.
2024-04-23T22:57:41.528Z,1713913061.528 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-04-23T22:57:41.536Z,1713913061.536 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-04-23T22:57:41.777Z,1713913061.777 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-23T22:57:41.777Z,1713913061.777 [DropWeight] Hardware Fault, FailCount= 1
2024-04-23T22:57:41.777Z,1713913061.777 [DropWeight](ERROR): Hardware Fault
2024-04-23T22:57:41.843Z,1713913061.843 [CommandExec](FAULT): Scheduling is paused
2024-04-23T22:57:41.847Z,1713913061.847 [CBIT](INFO): Critical error at 20240423T225741
2024-04-23T22:57:41.848Z,1713913061.848 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-04-23T22:57:41.850Z,1713913061.850 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-23T22:57:41.850Z,1713913061.850 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-23T22:57:42.170Z,1713913062.170 [CBIT](INFO): Critical error at 20240423T225741
2024-04-23T22:57:43.648Z,1713913063.648 [WetLabsBB2FL](INFO): Powering down
2024-04-23T22:57:47.149Z,1713913067.149 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-04-23T22:57:50.818Z,1713913070.818 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004895
2024-04-23T22:57:54.132Z,1713913074.132 [DAT](INFO): DAT read:
2024-04-23T22:57:54.133Z,1713913074.133 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-04-23T22:57:55.896Z,1713913075.896 [DAT](INFO): DAT read: MF Frequency Band
2024-04-23T22:57:55.897Z,1713913075.897 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-04-23T22:57:55.898Z,1713913075.898 [DAT](INFO): DAT read: Apr 23 2024 21:19:41
2024-04-23T22:57:56.904Z,1713913076.904 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-04-23T22:57:56.905Z,1713913076.905 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-04-23T22:57:56.906Z,1713913076.906 [DAT](INFO): commRate: 800
2024-04-23T22:57:58.971Z,1713913078.971 [DAT](INFO): entering command mode
2024-04-23T22:57:59.188Z,1713913079.188 [DAT](INFO): DAT read:
2024-04-23T22:57:59.189Z,1713913079.189 [DAT](INFO): DAT read: user:1>
2024-04-23T22:57:59.189Z,1713913079.189 [DAT](INFO): setting verbose to 3
2024-04-23T22:57:59.440Z,1713913079.440 [DAT](INFO): DAT read: user:1>
2024-04-23T22:57:59.441Z,1713913079.441 [DAT](INFO): DAT read: Verbose | 3
2024-04-23T22:57:59.441Z,1713913079.441 [DAT](INFO): set verbose to 3
2024-04-23T22:57:59.441Z,1713913079.441 [DAT](INFO): setting DatVerbose to 27440
2024-04-23T22:57:59.692Z,1713913079.692 [DAT](INFO): DAT read: user:2>
2024-04-23T22:57:59.693Z,1713913079.693 [DAT](INFO): DAT read: DatVerbose | 27440
2024-04-23T22:57:59.693Z,1713913079.693 [DAT](INFO): set DatVerbose to 27440
2024-04-23T22:57:59.694Z,1713913079.694 [DAT](INFO): setting transmit power to 8
2024-04-23T22:57:59.948Z,1713913079.948 [DAT](INFO): DAT read: user:3>
2024-04-23T22:57:59.949Z,1713913079.949 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-04-23T22:57:59.949Z,1713913079.949 [DAT](INFO): set transmit power to 8
2024-04-23T22:57:59.949Z,1713913079.949 [DAT](INFO): setting local address to 9
2024-04-23T22:58:00.200Z,1713913080.200 [DAT](INFO): DAT read: user:4>
2024-04-23T22:58:00.201Z,1713913080.201 [DAT](INFO): DAT read: LocalAddr | 9
2024-04-23T22:58:00.201Z,1713913080.201 [DAT](INFO): set local address to 9
2024-04-23T22:58:00.202Z,1713913080.202 [DAT](INFO): Setting time to: 22:58:0 And date to:4/23/2024
2024-04-23T22:58:00.452Z,1713913080.452 [DAT](INFO): DAT read: user:5>
2024-04-23T22:58:00.453Z,1713913080.453 [DAT](INFO): DAT read: Tue Apr 23, 2024 22:58:00
2024-04-23T22:58:00.453Z,1713913080.453 [DAT](INFO): Local DAT time set to Tue Apr 23, 2024 22:58:00
2024-04-23T22:58:05.622Z,1713913085.622 [SBIT](IMPORTANT): Beginning Startup BIT
2024-04-23T22:58:05.627Z,1713913085.627 [CBIT](IMPORTANT): Beginning ground fault scan
2024-04-23T22:58:08.433Z,1713913088.433 [NAL9602](INFO): Powering up NAL9602
2024-04-23T22:58:16.665Z,1713913096.665 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.014164
CHAN A1 (24V): 0.000908
CHAN A2 (12V): -0.004888
CHAN A3 (5V): -0.003495
CHAN B0 (3.3V): -0.001511
CHAN B1 (3.15aV): -0.000896
CHAN B2 (3.15bV): -0.000747
CHAN B3 (GND): -0.000951
OPEN: 0.004752
Full Scale: +/- 1 mA
2024-04-23T22:58:19.341Z,1713913099.341 [NAL9602](INFO): NAL9602 initialized
2024-04-23T22:58:50.872Z,1713913130.872 [CommandExec](IMPORTANT): got command strobe off
2024-04-23T22:58:50.873Z,1713913130.873 [CommandExec](IMPORTANT): Deactivating strobe
2024-04-23T22:58:59.378Z,1713913139.378 [SBIT](IMPORTANT): SBIT PASSED
2024-04-23T22:58:59.379Z,1713913139.379 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-04-23T22:58:59.380Z,1713913139.380 [SBIT](IMPORTANT): BuoyancyServo.limitLo=500 count;
2024-04-23T22:58:59.380Z,1713913139.380 [SBIT](IMPORTANT): BuoyancyServo.offsetVolume=0 cubic_centimeter;
2024-04-23T22:58:59.380Z,1713913139.380 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;
2024-04-23T22:58:59.380Z,1713913139.380 [SBIT](IMPORTANT): PowerOnly.sampleTime1=0 second;
2024-04-23T22:58:59.380Z,1713913139.380 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool;
2024-04-23T22:58:59.380Z,1713913139.380 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=325.783869 cubic_centimeter;
2024-04-23T22:58:59.380Z,1713913139.380 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.183276 millimeter;
2024-04-23T22:58:59.770Z,1713913139.770 [MissionManager](IMPORTANT): Started mission Startup
2024-04-23T22:58:59.770Z,1713913139.770 [Startup] Running Loop=1
2024-04-23T22:58:59.770Z,1713913139.770 [Startup](DEBUG): Aggregate::initialize Startup
2024-04-23T22:58:59.770Z,1713913139.770 [Startup:A.GoToSurface] Running Loop=1
2024-04-23T22:58:59.770Z,1713913139.770 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-23T22:58:59.798Z,1713913139.798 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-23T22:58:59.798Z,1713913139.798 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-23T22:58:59.799Z,1713913139.799 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-23T22:58:59.799Z,1713913139.799 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-23T22:58:59.800Z,1713913139.800 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-23T22:58:59.801Z,1713913139.801 [Startup:StartupSatComms] Running Loop=1
2024-04-23T22:58:59.801Z,1713913139.801 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-04-23T22:58:59.801Z,1713913139.801 [Startup:StartupSatComms:A] Running Loop=1
2024-04-23T22:59:00.179Z,1713913140.179 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-04-23T22:59:59.959Z,1713913199.959 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-23T22:58:59.8Z
2024-04-23T22:59:59.959Z,1713913199.959 [Startup:StartupSatComms:A] Stopped
2024-04-23T22:59:59.959Z,1713913199.959 [Startup:StartupSatComms:B] Running Loop=1
2024-04-23T23:00:00.354Z,1713913200.354 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-23T23:00:06.807Z,1713913206.807 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T152806/Courier0106.lzma
2024-04-23T23:00:07.816Z,1713913207.816 [DataOverHttps](INFO): Moved sent file to Logs/20240423T152806/Courier0106.lzma.bak
2024-04-23T23:00:07.816Z,1713913207.816 [DataOverHttps](INFO): SBD MOMSN=19424657
2024-04-23T23:00:23.659Z,1713913223.659 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20240423T225729/Courier0000.lzma
2024-04-23T23:00:24.776Z,1713913224.776 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Courier0000.lzma.bak
2024-04-23T23:00:24.776Z,1713913224.776 [DataOverHttps](INFO): SBD MOMSN=19424659
2024-04-23T23:00:41.324Z,1713913241.324 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-04-23T23:00:41.324Z,1713913241.324 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:00:41.325Z,1713913241.325 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2024-04-23T23:00:41.325Z,1713913241.325 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:00:41.392Z,1713913241.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:00:41.392Z,1713913241.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:00:41.752Z,1713913241.752 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:00:41.752Z,1713913241.752 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-04-23T23:00:41.753Z,1713913241.753 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:00:41.753Z,1713913241.753 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2024-04-23T23:00:42.075Z,1713913242.075 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:00:42.719Z,1713913242.719 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20240423T152806/Express0107.lzma
2024-04-23T23:00:43.721Z,1713913243.721 [DataOverHttps](INFO): Moved sent file to Logs/20240423T152806/Express0107.lzma.bak
2024-04-23T23:00:43.721Z,1713913243.721 [DataOverHttps](INFO): SBD MOMSN=19424664
2024-04-23T23:01:00.286Z,1713913260.286 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-23T22:59:59.0Z
2024-04-23T23:01:00.286Z,1713913260.286 [Startup:StartupSatComms:B] Stopped
2024-04-23T23:01:00.287Z,1713913260.287 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-04-23T23:01:00.287Z,1713913260.287 [Startup:StartupSatComms] Stopped
2024-04-23T23:01:00.287Z,1713913260.287 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-04-23T23:01:00.292Z,1713913260.292 [Startup](INFO): Completed Startup
2024-04-23T23:01:00.292Z,1713913260.292 [MissionManager](INFO): Startup is completed.
2024-04-23T23:01:00.292Z,1713913260.292 [MissionManager](INFO): Uninitializing Mission Startup
2024-04-23T23:01:00.293Z,1713913260.293 [Startup] Stopped
2024-04-23T23:01:00.293Z,1713913260.293 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-04-23T23:01:00.293Z,1713913260.293 [Startup:A.GoToSurface] Stopped
2024-04-23T23:01:00.293Z,1713913260.293 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-23T23:01:00.686Z,1713913260.686 [MissionManager](IMPORTANT): Started mission Default
2024-04-23T23:01:00.686Z,1713913260.686 [Default] Running Loop=1
2024-04-23T23:01:00.686Z,1713913260.686 [Default](DEBUG): Aggregate::initialize Default
2024-04-23T23:01:00.686Z,1713913260.686 [Default:B.GoToSurface] Running Loop=1
2024-04-23T23:01:00.686Z,1713913260.686 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-23T23:01:00.687Z,1713913260.687 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-23T23:01:00.691Z,1713913260.691 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-23T23:01:00.691Z,1713913260.691 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-23T23:01:00.692Z,1713913260.692 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-23T23:01:00.692Z,1713913260.692 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-23T23:01:00.692Z,1713913260.692 [Default:A.Wait] Running Loop=1
2024-04-23T23:01:00.692Z,1713913260.692 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:01:03.364Z,1713913263.364 [DataOverHttps](INFO): Sending 872 bytes from file Logs/20240423T225729/Express0001.lzma
2024-04-23T23:01:04.812Z,1713913264.812 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Express0001.lzma.bak
2024-04-23T23:01:04.813Z,1713913264.813 [DataOverHttps](INFO): SBD MOMSN=19424668
2024-04-23T23:01:14.012Z,1713913274.012 [Default:A.Wait](INFO): Done Waiting.
2024-04-23T23:01:14.012Z,1713913274.012 [Default:A.Wait] Stopped
2024-04-23T23:01:14.012Z,1713913274.012 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:01:14.422Z,1713913274.422 [Default:CheckIn] Running Loop=1
2024-04-23T23:01:14.422Z,1713913274.422 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:01:14.422Z,1713913274.422 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:01:14.811Z,1713913274.811 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-04-23T23:03:23.273Z,1713913403.273 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-04-23T23:03:25.589Z,1713913405.589 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_test.xml
2024-04-23T23:03:25.590Z,1713913405.590 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_test.xml
2024-04-23T23:03:25.649Z,1713913405.649 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min
2024-04-23T23:03:25.670Z,1713913405.670 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s
2024-04-23T23:03:25.673Z,1713913405.673 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count
2024-04-23T23:03:25.676Z,1713913405.676 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count
2024-04-23T23:03:25.719Z,1713913405.719 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count
2024-04-23T23:03:25.723Z,1713913405.723 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool
2024-04-23T23:03:25.739Z,1713913405.739 [multiray_test:C.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.742Z,1713913405.742 [multiray_test:E.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.750Z,1713913405.750 [multiray_test:G.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.757Z,1713913405.757 [multiray_test:I.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.761Z,1713913405.761 [multiray_test:K.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.772Z,1713913405.772 [multiray_test:M.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.791Z,1713913405.791 [multiray_test:O.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.795Z,1713913405.795 [multiray_test:Q.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.803Z,1713913405.803 [multiray_test:R.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.806Z,1713913405.806 [multiray_test:T.Wait](DEBUG): Construct Wait.
2024-04-23T23:03:25.817Z,1713913405.817 [MissionManager](DEBUG):
This mission is designed to run in the lab only. Cycles through MultiRay
light modes
How long to let the mission run.
5
How long to wait before changing light modes
5
Light mode = 0
0
Light mode = 1
1
Light mode = 2
2
Automatically set to true if the Multiray is enabled.
2024-04-23T23:03:25.819Z,1713913405.819 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_test.xml
2024-04-23T23:03:26.190Z,1713913406.190 [Default] Stopped
2024-04-23T23:03:26.190Z,1713913406.190 [Default](DEBUG): Aggregate::uninitialize Default
2024-04-23T23:03:26.190Z,1713913406.190 [Default:B.GoToSurface] Stopped
2024-04-23T23:03:26.190Z,1713913406.190 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-23T23:03:26.191Z,1713913406.191 [Default:CheckIn] Stopped
2024-04-23T23:03:26.191Z,1713913406.191 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:03:26.191Z,1713913406.191 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:03:26.191Z,1713913406.191 [MissionManager](IMPORTANT): Started mission multiray_test
2024-04-23T23:03:26.191Z,1713913406.191 [multiray_test] Running Loop=1
2024-04-23T23:03:26.191Z,1713913406.191 [multiray_test](DEBUG): Aggregate::initialize multiray_test
2024-04-23T23:03:26.192Z,1713913406.192 [multiray_test:A] Running Loop=1
2024-04-23T23:03:26.192Z,1713913406.192 [multiray_test:B] Running Loop=1
2024-04-23T23:03:26.192Z,1713913406.192 [multiray_test:B] Stopped
2024-04-23T23:03:26.192Z,1713913406.192 [multiray_test:C.Wait] Running Loop=1
2024-04-23T23:03:26.192Z,1713913406.192 [multiray_test:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:03:26.192Z,1713913406.192 [multiray_test:A] Running Loop=1
2024-04-23T23:03:26.193Z,1713913406.193 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2024-04-23T23:03:27.717Z,1713913407.717 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:03:31.785Z,1713913411.785 [multiray_test:C.Wait](INFO): Done Waiting.
2024-04-23T23:03:31.785Z,1713913411.785 [multiray_test:C.Wait] Stopped
2024-04-23T23:03:31.786Z,1713913411.786 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:03:31.786Z,1713913411.786 [multiray_test:D] Running Loop=1
2024-04-23T23:03:32.291Z,1713913412.291 [multiray_test:D] Stopped
2024-04-23T23:03:32.291Z,1713913412.291 [multiray_test:E.Wait] Running Loop=1
2024-04-23T23:03:32.295Z,1713913412.295 [multiray_test:E.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:03:34.585Z,1713913414.585 [MultiRay](IMPORTANT): Multiray red lights ON
2024-04-23T23:03:37.846Z,1713913417.846 [multiray_test:E.Wait](INFO): Done Waiting.
2024-04-23T23:03:37.846Z,1713913417.846 [multiray_test:E.Wait] Stopped
2024-04-23T23:03:37.846Z,1713913417.846 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:03:37.846Z,1713913417.846 [multiray_test:F] Running Loop=1
2024-04-23T23:03:38.246Z,1713913418.246 [multiray_test:F] Stopped
2024-04-23T23:03:38.246Z,1713913418.246 [multiray_test:G.Wait] Running Loop=1
2024-04-23T23:03:38.246Z,1713913418.246 [multiray_test:G.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:03:39.838Z,1713913419.838 [MultiRay](IMPORTANT): MultiRay white lights ON
2024-04-23T23:03:42.269Z,1713913422.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-04-23T23:03:42.269Z,1713913422.269 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:03:42.270Z,1713913422.270 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2024-04-23T23:03:42.270Z,1713913422.270 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:03:42.280Z,1713913422.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:03:42.280Z,1713913422.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:03:42.689Z,1713913422.689 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:03:42.690Z,1713913422.690 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-04-23T23:03:42.690Z,1713913422.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:03:42.690Z,1713913422.690 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2024-04-23T23:03:43.096Z,1713913423.096 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:03:43.906Z,1713913423.906 [multiray_test:G.Wait](INFO): Done Waiting.
2024-04-23T23:03:43.906Z,1713913423.906 [multiray_test:G.Wait] Stopped
2024-04-23T23:03:43.906Z,1713913423.906 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:03:43.906Z,1713913423.906 [multiray_test:H] Running Loop=1
2024-04-23T23:03:44.294Z,1713913424.294 [multiray_test:H] Stopped
2024-04-23T23:03:44.294Z,1713913424.294 [multiray_test:I.Wait] Running Loop=1
2024-04-23T23:03:44.294Z,1713913424.294 [multiray_test:I.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:03:45.088Z,1713913425.088 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:03:49.946Z,1713913429.946 [multiray_test:I.Wait](INFO): Done Waiting.
2024-04-23T23:03:49.946Z,1713913429.946 [multiray_test:I.Wait] Stopped
2024-04-23T23:03:49.946Z,1713913429.946 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:03:49.946Z,1713913429.946 [multiray_test:J] Running Loop=1
2024-04-23T23:03:50.351Z,1713913430.351 [multiray_test:J] Stopped
2024-04-23T23:03:50.351Z,1713913430.351 [multiray_test:K.Wait] Running Loop=1
2024-04-23T23:03:50.351Z,1713913430.351 [multiray_test:K.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:03:52.823Z,1713913432.823 [MultiRay](IMPORTANT): Multiray red lights ON
2024-04-23T23:03:56.006Z,1713913436.006 [multiray_test:K.Wait](INFO): Done Waiting.
2024-04-23T23:03:56.006Z,1713913436.006 [multiray_test:K.Wait] Stopped
2024-04-23T23:03:56.006Z,1713913436.006 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:03:56.006Z,1713913436.006 [multiray_test:L] Running Loop=1
2024-04-23T23:03:56.418Z,1713913436.418 [multiray_test:L] Stopped
2024-04-23T23:03:56.419Z,1713913436.419 [multiray_test:M.Wait] Running Loop=1
2024-04-23T23:03:56.419Z,1713913436.419 [multiray_test:M.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:03:58.018Z,1713913438.018 [MultiRay](IMPORTANT): MultiRay white lights ON
2024-04-23T23:04:02.094Z,1713913442.094 [multiray_test:M.Wait](INFO): Done Waiting.
2024-04-23T23:04:02.094Z,1713913442.094 [multiray_test:M.Wait] Stopped
2024-04-23T23:04:02.094Z,1713913442.094 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:04:02.095Z,1713913442.095 [multiray_test:N] Running Loop=1
2024-04-23T23:04:02.503Z,1713913442.503 [multiray_test:N] Stopped
2024-04-23T23:04:02.503Z,1713913442.503 [multiray_test:O.Wait] Running Loop=1
2024-04-23T23:04:02.503Z,1713913442.503 [multiray_test:O.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:04:03.273Z,1713913443.273 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:04:08.142Z,1713913448.142 [multiray_test:O.Wait](INFO): Done Waiting.
2024-04-23T23:04:08.142Z,1713913448.142 [multiray_test:O.Wait] Stopped
2024-04-23T23:04:08.142Z,1713913448.142 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:04:08.142Z,1713913448.142 [multiray_test:P] Running Loop=1
2024-04-23T23:04:08.561Z,1713913448.561 [multiray_test:P] Stopped
2024-04-23T23:04:08.561Z,1713913448.561 [multiray_test:Q.Wait] Running Loop=1
2024-04-23T23:04:08.561Z,1713913448.561 [multiray_test:Q.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:04:10.946Z,1713913450.946 [MultiRay](IMPORTANT): Multiray red lights ON
2024-04-23T23:04:14.202Z,1713913454.202 [multiray_test:Q.Wait](INFO): Done Waiting.
2024-04-23T23:04:14.202Z,1713913454.202 [multiray_test:Q.Wait] Stopped
2024-04-23T23:04:14.202Z,1713913454.202 [multiray_test:Q.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:04:14.202Z,1713913454.202 [multiray_test:U] Running Loop=1
2024-04-23T23:04:14.610Z,1713913454.610 [multiray_test:U] Stopped
2024-04-23T23:04:14.610Z,1713913454.610 [multiray_test:R.Wait] Running Loop=1
2024-04-23T23:04:14.610Z,1713913454.610 [multiray_test:R.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:04:16.196Z,1713913456.196 [MultiRay](IMPORTANT): MultiRay white lights ON
2024-04-23T23:04:20.299Z,1713913460.299 [multiray_test:R.Wait](INFO): Done Waiting.
2024-04-23T23:04:20.299Z,1713913460.299 [multiray_test:R.Wait] Stopped
2024-04-23T23:04:20.299Z,1713913460.299 [multiray_test:R.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:04:20.299Z,1713913460.299 [multiray_test:S] Running Loop=1
2024-04-23T23:04:20.687Z,1713913460.687 [multiray_test:S] Stopped
2024-04-23T23:04:20.687Z,1713913460.687 [multiray_test:T.Wait] Running Loop=1
2024-04-23T23:04:20.687Z,1713913460.687 [multiray_test:T.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:04:21.448Z,1713913461.448 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:04:26.314Z,1713913466.314 [multiray_test:T.Wait](INFO): Done Waiting.
2024-04-23T23:04:26.314Z,1713913466.314 [multiray_test:T.Wait] Stopped
2024-04-23T23:04:26.314Z,1713913466.314 [multiray_test:T.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:04:26.314Z,1713913466.314 [multiray_test](INFO): Completed multiray_test
2024-04-23T23:04:26.314Z,1713913466.314 [MissionManager](INFO): multiray_test is completed.
2024-04-23T23:04:26.314Z,1713913466.314 [MissionManager](INFO): Uninitializing Mission multiray_test
2024-04-23T23:04:26.314Z,1713913466.314 [multiray_test] Stopped
2024-04-23T23:04:26.314Z,1713913466.314 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test
2024-04-23T23:04:26.315Z,1713913466.315 [multiray_test:A] Stopped
2024-04-23T23:04:26.726Z,1713913466.726 [MissionManager](IMPORTANT): Started mission Default
2024-04-23T23:04:26.727Z,1713913466.727 [Default] Running Loop=1
2024-04-23T23:04:26.727Z,1713913466.727 [Default](DEBUG): Aggregate::initialize Default
2024-04-23T23:04:26.727Z,1713913466.727 [Default:B.GoToSurface] Running Loop=1
2024-04-23T23:04:26.727Z,1713913466.727 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-23T23:04:26.727Z,1713913466.727 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-23T23:04:26.728Z,1713913466.728 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-23T23:04:26.728Z,1713913466.728 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-23T23:04:26.728Z,1713913466.728 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-23T23:04:26.729Z,1713913466.729 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-23T23:04:26.729Z,1713913466.729 [Default:A.Wait] Running Loop=1
2024-04-23T23:04:26.729Z,1713913466.729 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:04:40.059Z,1713913480.059 [Default:A.Wait](INFO): Done Waiting.
2024-04-23T23:04:40.059Z,1713913480.059 [Default:A.Wait] Stopped
2024-04-23T23:04:40.059Z,1713913480.059 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:04:40.480Z,1713913480.480 [Default:CheckIn] Running Loop=1
2024-04-23T23:04:40.481Z,1713913480.481 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:04:40.481Z,1713913480.481 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:06:43.292Z,1713913603.292 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-04-23T23:06:43.292Z,1713913603.292 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:06:43.293Z,1713913603.293 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2024-04-23T23:06:43.293Z,1713913603.293 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:06:43.304Z,1713913603.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:06:43.304Z,1713913603.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:06:43.708Z,1713913603.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:06:43.708Z,1713913603.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-04-23T23:06:43.708Z,1713913603.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:06:43.708Z,1713913603.708 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2024-04-23T23:06:44.084Z,1713913604.084 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:08:20.213Z,1713913700.213 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T23:08:20.213Z,1713913700.213 [NAL9602] Data Fault, FailCount= 1
2024-04-23T23:08:20.213Z,1713913700.213 [NAL9602](ERROR): Data Fault
2024-04-23T23:08:20.230Z,1713913700.230 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T23:08:20.617Z,1713913700.617 [NAL9602](INFO): Powering down
2024-04-23T23:08:21.464Z,1713913701.464 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T23:08:21.464Z,1713913701.464 [NAL9602] No Fault, FailCount= 1
2024-04-23T23:08:30.827Z,1713913710.827 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/multiray_test.xml
2024-04-23T23:08:30.828Z,1713913710.828 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/multiray_test.xml
2024-04-23T23:08:30.888Z,1713913710.888 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min
2024-04-23T23:08:30.890Z,1713913710.890 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s
2024-04-23T23:08:30.893Z,1713913710.893 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count
2024-04-23T23:08:30.895Z,1713913710.895 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count
2024-04-23T23:08:30.897Z,1713913710.897 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count
2024-04-23T23:08:30.924Z,1713913710.924 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool
2024-04-23T23:08:30.931Z,1713913710.931 [multiray_test:C.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.934Z,1713913710.934 [multiray_test:E.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.937Z,1713913710.937 [multiray_test:G.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.940Z,1713913710.940 [multiray_test:I.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.942Z,1713913710.942 [multiray_test:K.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.945Z,1713913710.945 [multiray_test:M.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.973Z,1713913710.973 [multiray_test:O.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.976Z,1713913710.976 [multiray_test:Q.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.978Z,1713913710.978 [multiray_test:R.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.981Z,1713913710.981 [multiray_test:T.Wait](DEBUG): Construct Wait.
2024-04-23T23:08:30.988Z,1713913710.988 [MissionManager](DEBUG):
This mission is designed to run in the lab only. Cycles through MultiRay
light modes
How long to let the mission run.
5
How long to wait before changing light modes
5
Light mode = 0
0
Light mode = 1
1
Light mode = 2
2
Automatically set to true if the Multiray is enabled.
2024-04-23T23:08:30.989Z,1713913710.989 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/multiray_test.xml
2024-04-23T23:08:31.188Z,1713913711.188 [Default] Stopped
2024-04-23T23:08:31.188Z,1713913711.188 [Default](DEBUG): Aggregate::uninitialize Default
2024-04-23T23:08:31.188Z,1713913711.188 [Default:B.GoToSurface] Stopped
2024-04-23T23:08:31.188Z,1713913711.188 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-23T23:08:31.188Z,1713913711.188 [Default:CheckIn] Stopped
2024-04-23T23:08:31.188Z,1713913711.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:08:31.188Z,1713913711.188 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:08:31.189Z,1713913711.189 [MissionManager](IMPORTANT): Started mission multiray_test
2024-04-23T23:08:31.189Z,1713913711.189 [multiray_test] Running Loop=1
2024-04-23T23:08:31.189Z,1713913711.189 [multiray_test](DEBUG): Aggregate::initialize multiray_test
2024-04-23T23:08:31.189Z,1713913711.189 [multiray_test:A] Running Loop=1
2024-04-23T23:08:31.189Z,1713913711.189 [multiray_test:B] Running Loop=1
2024-04-23T23:08:31.190Z,1713913711.190 [multiray_test:B] Stopped
2024-04-23T23:08:31.190Z,1713913711.190 [multiray_test:C.Wait] Running Loop=1
2024-04-23T23:08:31.190Z,1713913711.190 [multiray_test:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:08:31.190Z,1713913711.190 [multiray_test:A] Running Loop=1
2024-04-23T23:08:31.191Z,1713913711.191 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog
2024-04-23T23:08:32.753Z,1713913712.753 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:08:36.785Z,1713913716.785 [multiray_test:C.Wait](INFO): Done Waiting.
2024-04-23T23:08:36.785Z,1713913716.785 [multiray_test:C.Wait] Stopped
2024-04-23T23:08:36.785Z,1713913716.785 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:08:36.785Z,1713913716.785 [multiray_test:D] Running Loop=1
2024-04-23T23:08:37.189Z,1713913717.189 [multiray_test:D] Stopped
2024-04-23T23:08:37.189Z,1713913717.189 [multiray_test:E.Wait] Running Loop=1
2024-04-23T23:08:37.189Z,1713913717.189 [multiray_test:E.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:08:39.604Z,1713913719.604 [MultiRay](IMPORTANT): Multiray red lights ON
2024-04-23T23:08:42.848Z,1713913722.848 [multiray_test:E.Wait](INFO): Done Waiting.
2024-04-23T23:08:42.848Z,1713913722.848 [multiray_test:E.Wait] Stopped
2024-04-23T23:08:42.848Z,1713913722.848 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:08:42.849Z,1713913722.849 [multiray_test:F] Running Loop=1
2024-04-23T23:08:43.261Z,1713913723.261 [multiray_test:F] Stopped
2024-04-23T23:08:43.261Z,1713913723.261 [multiray_test:G.Wait] Running Loop=1
2024-04-23T23:08:43.261Z,1713913723.261 [multiray_test:G.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:08:44.858Z,1713913724.858 [MultiRay](IMPORTANT): MultiRay white lights ON
2024-04-23T23:08:48.949Z,1713913728.949 [multiray_test:G.Wait](INFO): Done Waiting.
2024-04-23T23:08:48.949Z,1713913728.949 [multiray_test:G.Wait] Stopped
2024-04-23T23:08:48.949Z,1713913728.949 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:08:48.950Z,1713913728.950 [multiray_test:H] Running Loop=1
2024-04-23T23:08:49.318Z,1713913729.318 [multiray_test:H] Stopped
2024-04-23T23:08:49.318Z,1713913729.318 [multiray_test:I.Wait] Running Loop=1
2024-04-23T23:08:49.318Z,1713913729.318 [multiray_test:I.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:08:50.104Z,1713913730.104 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:08:50.748Z,1713913730.748 [NAL9602](INFO): Powering up NAL9602
2024-04-23T23:08:54.973Z,1713913734.973 [multiray_test:I.Wait](INFO): Done Waiting.
2024-04-23T23:08:54.973Z,1713913734.973 [multiray_test:I.Wait] Stopped
2024-04-23T23:08:54.973Z,1713913734.973 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:08:54.973Z,1713913734.973 [multiray_test:J] Running Loop=1
2024-04-23T23:08:55.366Z,1713913735.366 [multiray_test:J] Stopped
2024-04-23T23:08:55.366Z,1713913735.366 [multiray_test:K.Wait] Running Loop=1
2024-04-23T23:08:55.366Z,1713913735.366 [multiray_test:K.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:08:57.844Z,1713913737.844 [MultiRay](IMPORTANT): Multiray red lights ON
2024-04-23T23:09:01.025Z,1713913741.025 [multiray_test:K.Wait](INFO): Done Waiting.
2024-04-23T23:09:01.025Z,1713913741.025 [multiray_test:K.Wait] Stopped
2024-04-23T23:09:01.025Z,1713913741.025 [multiray_test:K.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:09:01.025Z,1713913741.025 [multiray_test:L] Running Loop=1
2024-04-23T23:09:01.509Z,1713913741.509 [multiray_test:L] Stopped
2024-04-23T23:09:01.509Z,1713913741.509 [multiray_test:M.Wait] Running Loop=1
2024-04-23T23:09:01.510Z,1713913741.510 [multiray_test:M.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:09:01.879Z,1713913741.879 [NAL9602](INFO): NAL9602 initialized
2024-04-23T23:09:03.036Z,1713913743.036 [MultiRay](IMPORTANT): MultiRay white lights ON
2024-04-23T23:09:07.085Z,1713913747.085 [multiray_test:M.Wait](INFO): Done Waiting.
2024-04-23T23:09:07.085Z,1713913747.085 [multiray_test:M.Wait] Stopped
2024-04-23T23:09:07.085Z,1713913747.085 [multiray_test:M.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:09:07.086Z,1713913747.086 [multiray_test:N] Running Loop=1
2024-04-23T23:09:07.492Z,1713913747.492 [multiray_test:N] Stopped
2024-04-23T23:09:07.492Z,1713913747.492 [multiray_test:O.Wait] Running Loop=1
2024-04-23T23:09:07.492Z,1713913747.492 [multiray_test:O.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:09:08.288Z,1713913748.288 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:09:13.145Z,1713913753.145 [multiray_test:O.Wait](INFO): Done Waiting.
2024-04-23T23:09:13.145Z,1713913753.145 [multiray_test:O.Wait] Stopped
2024-04-23T23:09:13.145Z,1713913753.145 [multiray_test:O.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:09:13.146Z,1713913753.146 [multiray_test:P] Running Loop=1
2024-04-23T23:09:13.554Z,1713913753.554 [multiray_test:P] Stopped
2024-04-23T23:09:13.554Z,1713913753.554 [multiray_test:Q.Wait] Running Loop=1
2024-04-23T23:09:13.554Z,1713913753.554 [multiray_test:Q.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:09:15.973Z,1713913755.973 [MultiRay](IMPORTANT): Multiray red lights ON
2024-04-23T23:09:19.216Z,1713913759.216 [multiray_test:Q.Wait](INFO): Done Waiting.
2024-04-23T23:09:19.216Z,1713913759.216 [multiray_test:Q.Wait] Stopped
2024-04-23T23:09:19.216Z,1713913759.216 [multiray_test:Q.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:09:19.216Z,1713913759.216 [multiray_test:U] Running Loop=1
2024-04-23T23:09:19.607Z,1713913759.607 [multiray_test:U] Stopped
2024-04-23T23:09:19.607Z,1713913759.607 [multiray_test:R.Wait] Running Loop=1
2024-04-23T23:09:19.607Z,1713913759.607 [multiray_test:R.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:09:21.216Z,1713913761.216 [MultiRay](IMPORTANT): MultiRay white lights ON
2024-04-23T23:09:25.286Z,1713913765.286 [multiray_test:R.Wait](INFO): Done Waiting.
2024-04-23T23:09:25.286Z,1713913765.286 [multiray_test:R.Wait] Stopped
2024-04-23T23:09:25.286Z,1713913765.286 [multiray_test:R.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:09:25.286Z,1713913765.286 [multiray_test:S] Running Loop=1
2024-04-23T23:09:25.693Z,1713913765.693 [multiray_test:S] Stopped
2024-04-23T23:09:25.693Z,1713913765.693 [multiray_test:T.Wait] Running Loop=1
2024-04-23T23:09:25.693Z,1713913765.693 [multiray_test:T.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:09:26.464Z,1713913766.464 [MultiRay](IMPORTANT): MultiRay all lights OFF
2024-04-23T23:09:31.322Z,1713913771.322 [multiray_test:T.Wait](INFO): Done Waiting.
2024-04-23T23:09:31.322Z,1713913771.322 [multiray_test:T.Wait] Stopped
2024-04-23T23:09:31.322Z,1713913771.322 [multiray_test:T.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:09:31.322Z,1713913771.322 [multiray_test](INFO): Completed multiray_test
2024-04-23T23:09:31.323Z,1713913771.323 [MissionManager](INFO): multiray_test is completed.
2024-04-23T23:09:31.323Z,1713913771.323 [MissionManager](INFO): Uninitializing Mission multiray_test
2024-04-23T23:09:31.323Z,1713913771.323 [multiray_test] Stopped
2024-04-23T23:09:31.323Z,1713913771.323 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test
2024-04-23T23:09:31.323Z,1713913771.323 [multiray_test:A] Stopped
2024-04-23T23:09:31.742Z,1713913771.742 [MissionManager](IMPORTANT): Started mission Default
2024-04-23T23:09:31.742Z,1713913771.742 [Default] Running Loop=1
2024-04-23T23:09:31.742Z,1713913771.742 [Default](DEBUG): Aggregate::initialize Default
2024-04-23T23:09:31.742Z,1713913771.742 [Default:B.GoToSurface] Running Loop=1
2024-04-23T23:09:31.742Z,1713913771.742 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-04-23T23:09:31.742Z,1713913771.742 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-04-23T23:09:31.743Z,1713913771.743 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-04-23T23:09:31.743Z,1713913771.743 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-04-23T23:09:31.744Z,1713913771.744 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-04-23T23:09:31.744Z,1713913771.744 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-04-23T23:09:31.744Z,1713913771.744 [Default:A.Wait] Running Loop=1
2024-04-23T23:09:31.744Z,1713913771.744 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:09:44.251Z,1713913784.251 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-04-23T23:09:44.251Z,1713913784.251 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:09:44.253Z,1713913784.253 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2024-04-23T23:09:44.253Z,1713913784.253 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:09:44.296Z,1713913784.296 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:09:44.296Z,1713913784.296 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:09:44.700Z,1713913784.700 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:09:44.701Z,1713913784.701 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-04-23T23:09:44.701Z,1713913784.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:09:44.701Z,1713913784.701 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2024-04-23T23:09:45.080Z,1713913785.080 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:09:45.082Z,1713913785.082 [Default:A.Wait](INFO): Done Waiting.
2024-04-23T23:09:45.082Z,1713913785.082 [Default:A.Wait] Stopped
2024-04-23T23:09:45.082Z,1713913785.082 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:09:45.486Z,1713913785.486 [Default:CheckIn] Running Loop=1
2024-04-23T23:09:45.487Z,1713913785.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:09:45.487Z,1713913785.487 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:12:04.028Z,1713913924.028 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2024-04-23T23:12:42.023Z,1713913962.023 [CBIT](INFO): Clearing failed state for component DropWeight
2024-04-23T23:12:42.024Z,1713913962.024 [DropWeight] No Fault, FailCount= 1
2024-04-23T23:12:45.280Z,1713913965.280 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-04-23T23:12:45.280Z,1713913965.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:12:45.281Z,1713913965.281 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2024-04-23T23:12:45.282Z,1713913965.282 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:12:45.304Z,1713913965.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:12:45.304Z,1713913965.304 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:12:45.694Z,1713913965.694 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:12:45.694Z,1713913965.694 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-04-23T23:12:45.694Z,1713913965.694 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:12:45.694Z,1713913965.694 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2024-04-23T23:12:46.097Z,1713913966.097 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:14:45.686Z,1713914085.686 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T23:09:45.5Z
2024-04-23T23:14:45.686Z,1713914085.686 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:14:45.687Z,1713914085.687 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T23:14:46.073Z,1713914086.073 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-04-23T23:14:47.202Z,1713914087.202 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T225729/Courier0004.lzma
2024-04-23T23:14:47.822Z,1713914087.822 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Courier0004.lzma.bak
2024-04-23T23:14:47.822Z,1713914087.822 [DataOverHttps](INFO): SBD MOMSN=19424715
2024-04-23T23:15:03.819Z,1713914103.819 [DataOverHttps](INFO): Sending 455 bytes from file Logs/20240423T225729/Express0005.lzma
2024-04-23T23:15:04.821Z,1713914104.821 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Express0005.lzma.bak
2024-04-23T23:15:04.821Z,1713914104.821 [DataOverHttps](INFO): SBD MOMSN=19424717
2024-04-23T23:15:06.759Z,1713914106.759 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T23:15:06.759Z,1713914106.759 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T23:15:06.759Z,1713914106.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:15:46.404Z,1713914146.404 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-04-23T23:15:46.404Z,1713914146.404 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:15:46.405Z,1713914146.405 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2024-04-23T23:15:46.405Z,1713914146.405 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:15:46.457Z,1713914146.457 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:15:46.457Z,1713914146.457 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:15:46.813Z,1713914146.813 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:15:46.813Z,1713914146.813 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-04-23T23:15:46.813Z,1713914146.813 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:15:46.813Z,1713914146.813 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2024-04-23T23:15:47.203Z,1713914147.203 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:15:53.900Z,1713914153.900 [DAT](FAULT): LCB fault: Current Limiter Activated.
2024-04-23T23:15:53.900Z,1713914153.900 [DAT] Hardware Fault, FailCount= 1
2024-04-23T23:15:53.900Z,1713914153.900 [DAT](ERROR): Hardware Fault
2024-04-23T23:15:53.900Z,1713914153.900 [DAT](INFO): Powering down
2024-04-23T23:15:54.092Z,1713914154.092 [CBIT](ERROR): Hardware Fault in component: DAT
2024-04-23T23:15:54.375Z,1713914154.375 [DAT](INFO): Powering down
2024-04-23T23:15:54.779Z,1713914154.779 [DAT](FAULT): LCB fault: Current Limiter Activated.
2024-04-23T23:15:55.271Z,1713914155.271 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:15:55.271Z,1713914155.271 [DAT] No Fault, FailCount= 1
2024-04-23T23:15:57.612Z,1713914157.612 [DAT](INFO): Powering up
2024-04-23T23:15:57.612Z,1713914157.612 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:16:57.856Z,1713914217.856 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:16:57.856Z,1713914217.856 [DAT] Communications Fault, FailCount= 2
2024-04-23T23:16:57.856Z,1713914217.856 [DAT](ERROR): Communications Fault
2024-04-23T23:16:57.964Z,1713914217.964 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:16:58.271Z,1713914218.271 [DAT](INFO): Powering down
2024-04-23T23:16:58.748Z,1713914218.748 [DAT](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated.
2024-04-23T23:16:58.748Z,1713914218.748 [DAT] Hardware Fault, FailCount= 2
2024-04-23T23:16:58.748Z,1713914218.748 [DAT](ERROR): Hardware Fault
2024-04-23T23:16:59.506Z,1713914219.506 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:16:59.506Z,1713914219.506 [DAT] No Fault, FailCount= 2
2024-04-23T23:17:01.319Z,1713914221.319 [DAT](INFO): Powering up
2024-04-23T23:17:01.320Z,1713914221.320 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:18:01.332Z,1713914281.332 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:18:01.332Z,1713914281.332 [DAT] Communications Fault, FailCount= 3
2024-04-23T23:18:01.332Z,1713914281.332 [DAT](ERROR): Communications Fault
2024-04-23T23:18:01.735Z,1713914281.735 [DAT](INFO): Powering down
2024-04-23T23:18:01.752Z,1713914281.752 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:18:02.943Z,1713914282.943 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:18:02.944Z,1713914282.944 [DAT] No Fault, FailCount= 3
2024-04-23T23:18:04.799Z,1713914284.799 [DAT](INFO): Powering up
2024-04-23T23:18:04.800Z,1713914284.800 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:18:47.397Z,1713914327.397 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-04-23T23:18:47.397Z,1713914327.397 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:18:47.398Z,1713914327.398 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2024-04-23T23:18:47.398Z,1713914327.398 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:18:47.408Z,1713914327.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:18:47.408Z,1713914327.408 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:18:47.793Z,1713914327.793 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:18:47.793Z,1713914327.793 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-04-23T23:18:47.794Z,1713914327.794 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:18:47.794Z,1713914327.794 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2024-04-23T23:18:48.192Z,1713914328.192 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:19:03.517Z,1713914343.517 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T23:19:03.517Z,1713914343.517 [NAL9602] Data Fault, FailCount= 2
2024-04-23T23:19:03.517Z,1713914343.517 [NAL9602](ERROR): Data Fault
2024-04-23T23:19:03.553Z,1713914343.553 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T23:19:03.921Z,1713914343.921 [NAL9602](INFO): Powering down
2024-04-23T23:19:04.745Z,1713914344.745 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T23:19:04.745Z,1713914344.745 [NAL9602] No Fault, FailCount= 2
2024-04-23T23:19:05.052Z,1713914345.052 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:19:05.052Z,1713914345.052 [DAT] Communications Fault, FailCount= 4
2024-04-23T23:19:05.052Z,1713914345.052 [DAT](ERROR): Communications Fault
2024-04-23T23:19:05.154Z,1713914345.154 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:19:05.455Z,1713914345.455 [DAT](INFO): Powering down
2024-04-23T23:19:06.378Z,1713914346.378 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:19:06.378Z,1713914346.378 [DAT] No Fault, FailCount= 4
2024-04-23T23:19:08.503Z,1713914348.503 [DAT](INFO): Powering up
2024-04-23T23:19:08.503Z,1713914348.503 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:19:34.222Z,1713914374.222 [NAL9602](INFO): Powering up NAL9602
2024-04-23T23:19:45.187Z,1713914385.187 [NAL9602](INFO): NAL9602 initialized
2024-04-23T23:20:07.378Z,1713914407.378 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-23T23:20:07.378Z,1713914407.378 [Default:CheckIn:C.Wait] Stopped
2024-04-23T23:20:07.378Z,1713914407.378 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:20:07.378Z,1713914407.378 [Default:CheckIn:D] Running Loop=1
2024-04-23T23:20:07.786Z,1713914407.786 [Default:CheckIn:D] Stopped
2024-04-23T23:20:07.786Z,1713914407.786 [Default:CheckIn:E] Running Loop=1
2024-04-23T23:20:08.173Z,1713914408.173 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.600732 min
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn:E] Stopped
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn] Stopped
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn](INFO): Running loop #2
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn] Running Loop=2
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:20:08.174Z,1713914408.174 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:20:08.516Z,1713914408.516 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:20:08.516Z,1713914408.516 [DAT] Communications Fault, FailCount= 5
2024-04-23T23:20:08.516Z,1713914408.516 [DAT](ERROR): Communications Fault
2024-04-23T23:20:08.596Z,1713914408.596 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:20:08.919Z,1713914408.919 [DAT](INFO): Powering down
2024-04-23T23:20:09.796Z,1713914409.796 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:20:09.796Z,1713914409.796 [DAT] No Fault, FailCount= 5
2024-04-23T23:20:11.963Z,1713914411.963 [DAT](INFO): Powering up
2024-04-23T23:20:11.964Z,1713914411.964 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:21:11.977Z,1713914471.977 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:21:11.978Z,1713914471.978 [DAT] Communications Fault, FailCount= 6
2024-04-23T23:21:11.978Z,1713914471.978 [DAT](ERROR): Communications Fault
2024-04-23T23:21:12.034Z,1713914472.034 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:21:12.379Z,1713914472.379 [DAT](INFO): Powering down
2024-04-23T23:21:13.632Z,1713914473.632 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:21:13.632Z,1713914473.632 [DAT] No Fault, FailCount= 6
2024-04-23T23:21:15.432Z,1713914475.432 [DAT](INFO): Powering up
2024-04-23T23:21:15.432Z,1713914475.432 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:21:48.426Z,1713914508.426 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-04-23T23:21:48.426Z,1713914508.426 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:21:48.427Z,1713914508.427 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2024-04-23T23:21:48.427Z,1713914508.427 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:21:48.437Z,1713914508.437 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:21:48.438Z,1713914508.438 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:21:48.814Z,1713914508.814 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:21:48.814Z,1713914508.814 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-04-23T23:21:48.814Z,1713914508.814 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:21:48.814Z,1713914508.814 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2024-04-23T23:21:49.175Z,1713914509.175 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:22:15.672Z,1713914535.672 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:22:15.672Z,1713914535.672 [DAT] Communications Fault, FailCount= 7
2024-04-23T23:22:15.672Z,1713914535.672 [DAT](ERROR): Communications Fault
2024-04-23T23:22:15.872Z,1713914535.872 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:22:16.075Z,1713914536.075 [DAT](INFO): Powering down
2024-04-23T23:22:17.075Z,1713914537.075 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:22:17.075Z,1713914537.075 [DAT] No Fault, FailCount= 7
2024-04-23T23:22:19.123Z,1713914539.123 [DAT](INFO): Powering up
2024-04-23T23:22:19.124Z,1713914539.124 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:23:19.372Z,1713914599.372 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:23:19.372Z,1713914599.372 [DAT] Communications Fault, FailCount= 8
2024-04-23T23:23:19.372Z,1713914599.372 [DAT](ERROR): Communications Fault
2024-04-23T23:23:19.670Z,1713914599.670 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:23:19.671Z,1713914599.671 [CBIT](FAULT): Communications Fault in component: DAT
2024-04-23T23:23:19.775Z,1713914599.775 [DAT](INFO): Powering down
2024-04-23T23:24:49.367Z,1713914689.367 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-04-23T23:24:49.368Z,1713914689.368 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:24:49.369Z,1713914689.369 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2024-04-23T23:24:49.369Z,1713914689.369 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:24:49.379Z,1713914689.379 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:24:49.380Z,1713914689.380 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:24:49.778Z,1713914689.778 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:24:49.778Z,1713914689.778 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-04-23T23:24:49.779Z,1713914689.779 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:24:49.779Z,1713914689.779 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2024-04-23T23:24:50.156Z,1713914690.156 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:25:08.388Z,1713914708.388 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T23:20:08.2Z
2024-04-23T23:25:08.388Z,1713914708.388 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:25:08.389Z,1713914708.389 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T23:25:16.015Z,1713914716.015 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T225729/Courier0007.lzma
2024-04-23T23:25:17.017Z,1713914717.017 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Courier0007.lzma.bak
2024-04-23T23:25:17.017Z,1713914717.017 [DataOverHttps](INFO): SBD MOMSN=19424873
2024-04-23T23:25:32.867Z,1713914732.867 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20240423T225729/Express0008.lzma
2024-04-23T23:25:33.869Z,1713914733.869 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Express0008.lzma.bak
2024-04-23T23:25:33.869Z,1713914733.869 [DataOverHttps](INFO): SBD MOMSN=19424875
2024-04-23T23:25:35.864Z,1713914735.864 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T23:25:35.865Z,1713914735.865 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T23:25:35.865Z,1713914735.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:27:50.400Z,1713914870.400 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-04-23T23:27:50.400Z,1713914870.400 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:27:50.401Z,1713914870.401 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2024-04-23T23:27:50.402Z,1713914870.402 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:27:50.420Z,1713914870.420 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:27:50.420Z,1713914870.420 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:27:50.800Z,1713914870.800 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:27:50.800Z,1713914870.800 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-04-23T23:27:50.800Z,1713914870.800 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:27:50.800Z,1713914870.800 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2024-04-23T23:27:51.185Z,1713914871.185 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:28:10.734Z,1713914890.734 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2024-04-23T23:28:10.734Z,1713914890.734 [CommandExec](IMPORTANT): Onboard.Pressure 15.661596 psi
2024-04-23T23:28:19.499Z,1713914899.499 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:28:19.499Z,1713914899.499 [DAT] No Fault, FailCount= 8
2024-04-23T23:28:19.619Z,1713914899.619 [DAT](INFO): Powering up
2024-04-23T23:28:19.620Z,1713914899.620 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:28:41.658Z,1713914921.658 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5.
2024-04-23T23:28:41.662Z,1713914921.662 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-04-23T23:29:19.856Z,1713914959.856 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:29:19.856Z,1713914959.856 [DAT] Communications Fault, FailCount= 1
2024-04-23T23:29:19.856Z,1713914959.856 [DAT](ERROR): Communications Fault
2024-04-23T23:29:20.080Z,1713914960.080 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:29:20.262Z,1713914960.262 [DAT](INFO): Powering down
2024-04-23T23:29:21.288Z,1713914961.288 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:29:21.288Z,1713914961.288 [DAT] No Fault, FailCount= 1
2024-04-23T23:29:23.307Z,1713914963.307 [DAT](INFO): Powering up
2024-04-23T23:29:23.308Z,1713914963.308 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:29:47.109Z,1713914987.109 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T23:29:47.109Z,1713914987.109 [NAL9602] Data Fault, FailCount= 3
2024-04-23T23:29:47.109Z,1713914987.109 [NAL9602](ERROR): Data Fault
2024-04-23T23:29:47.191Z,1713914987.191 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T23:29:47.513Z,1713914987.513 [NAL9602](INFO): Powering down
2024-04-23T23:29:48.363Z,1713914988.363 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T23:29:48.363Z,1713914988.363 [NAL9602] No Fault, FailCount= 3
2024-04-23T23:30:17.817Z,1713915017.817 [NAL9602](INFO): Powering up NAL9602
2024-04-23T23:30:23.327Z,1713915023.327 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:30:23.328Z,1713915023.328 [DAT] Communications Fault, FailCount= 2
2024-04-23T23:30:23.328Z,1713915023.328 [DAT](ERROR): Communications Fault
2024-04-23T23:30:23.521Z,1713915023.521 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:30:23.731Z,1713915023.731 [DAT](INFO): Powering down
2024-04-23T23:30:24.706Z,1713915024.706 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:30:24.706Z,1713915024.706 [DAT] No Fault, FailCount= 2
2024-04-23T23:30:26.779Z,1713915026.779 [DAT](INFO): Powering up
2024-04-23T23:30:26.780Z,1713915026.780 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:30:28.725Z,1713915028.725 [NAL9602](INFO): NAL9602 initialized
2024-04-23T23:30:36.446Z,1713915036.446 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-23T23:30:36.447Z,1713915036.447 [Default:CheckIn:C.Wait] Stopped
2024-04-23T23:30:36.447Z,1713915036.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:30:36.447Z,1713915036.447 [Default:CheckIn:D] Running Loop=1
2024-04-23T23:30:36.808Z,1713915036.808 [Default:CheckIn:D] Stopped
2024-04-23T23:30:36.808Z,1713915036.808 [Default:CheckIn:E] Running Loop=1
2024-04-23T23:30:37.298Z,1713915037.298 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.084436 min
2024-04-23T23:30:37.299Z,1713915037.299 [Default:CheckIn:E] Stopped
2024-04-23T23:30:37.299Z,1713915037.299 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-23T23:30:37.299Z,1713915037.299 [Default:CheckIn] Stopped
2024-04-23T23:30:37.299Z,1713915037.299 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:30:37.299Z,1713915037.299 [Default:CheckIn](INFO): Running loop #3
2024-04-23T23:30:37.299Z,1713915037.299 [Default:CheckIn] Running Loop=3
2024-04-23T23:30:37.300Z,1713915037.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:30:37.300Z,1713915037.300 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:30:51.368Z,1713915051.368 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-04-23T23:30:51.368Z,1713915051.368 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:30:51.370Z,1713915051.370 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2024-04-23T23:30:51.370Z,1713915051.370 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:30:51.381Z,1713915051.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:30:51.381Z,1713915051.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:30:51.781Z,1713915051.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:30:51.781Z,1713915051.781 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-04-23T23:30:51.782Z,1713915051.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:30:51.782Z,1713915051.782 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2024-04-23T23:30:52.160Z,1713915052.160 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:31:22.080Z,1713915082.080 [CBIT](FAULT): WATER ALARM STERN.
2024-04-23T23:31:22.080Z,1713915082.080 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2024-04-23T23:31:22.501Z,1713915082.501 [CBIT](FAULT): WATER ALARM STERN.
2024-04-23T23:31:22.502Z,1713915082.502 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2024-04-23T23:31:26.860Z,1713915086.860 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:31:26.860Z,1713915086.860 [DAT] Communications Fault, FailCount= 3
2024-04-23T23:31:26.860Z,1713915086.860 [DAT](ERROR): Communications Fault
2024-04-23T23:31:26.948Z,1713915086.948 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:31:27.263Z,1713915087.263 [DAT](INFO): Powering down
2024-04-23T23:31:28.164Z,1713915088.164 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:31:28.164Z,1713915088.164 [DAT] No Fault, FailCount= 3
2024-04-23T23:31:30.339Z,1713915090.339 [DAT](INFO): Powering up
2024-04-23T23:31:30.340Z,1713915090.340 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:32:08.562Z,1713915128.562 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:32:08.564Z,1713915128.564 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2024-04-23T23:32:10.178Z,1713915130.178 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:32:10.183Z,1713915130.183 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2024-04-23T23:32:12.988Z,1713915132.988 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:32:12.989Z,1713915132.989 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2024-04-23T23:32:30.352Z,1713915150.352 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:32:30.352Z,1713915150.352 [DAT] Communications Fault, FailCount= 4
2024-04-23T23:32:30.352Z,1713915150.352 [DAT](ERROR): Communications Fault
2024-04-23T23:32:30.753Z,1713915150.753 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:32:30.755Z,1713915150.755 [DAT](INFO): Powering down
2024-04-23T23:32:31.992Z,1713915151.992 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:32:31.992Z,1713915151.992 [DAT] No Fault, FailCount= 4
2024-04-23T23:32:33.803Z,1713915153.803 [DAT](INFO): Powering up
2024-04-23T23:32:33.803Z,1713915153.803 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:33:12.761Z,1713915192.761 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:33:12.763Z,1713915192.763 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2024-04-23T23:33:18.852Z,1713915198.852 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:33:18.854Z,1713915198.854 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2024-04-23T23:33:19.233Z,1713915199.233 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:33:19.234Z,1713915199.234 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2024-04-23T23:33:19.652Z,1713915199.652 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:33:19.653Z,1713915199.653 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2024-04-23T23:33:20.060Z,1713915200.060 [CBIT](FAULT): WATER ALARM BOW.
2024-04-23T23:33:20.093Z,1713915200.093 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL: BOW. BURNWIRE ACTIVATED
2024-04-23T23:33:20.093Z,1713915200.093 [Supervisor](INFO): Stop Mission called by CBIT::checkLeak
2024-04-23T23:33:20.093Z,1713915200.093 [CBIT](IMPORTANT): No ESP to power off.
2024-04-23T23:33:20.463Z,1713915200.463 [CBIT](INFO): Critical error at 20240423T233320
2024-04-23T23:33:21.232Z,1713915201.232 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-04-23T23:33:21.232Z,1713915201.232 [DropWeight] Hardware Fault, FailCount= 1
2024-04-23T23:33:21.232Z,1713915201.232 [DropWeight](ERROR): Hardware Fault
2024-04-23T23:33:21.315Z,1713915201.315 [CBIT](INFO): Critical error at 20240423T233321
2024-04-23T23:33:21.321Z,1713915201.321 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-04-23T23:33:21.322Z,1713915201.322 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-04-23T23:33:21.661Z,1713915201.661 [CBIT](INFO): Critical error at 20240423T233321
2024-04-23T23:33:34.040Z,1713915214.040 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:33:34.040Z,1713915214.040 [DAT] Communications Fault, FailCount= 5
2024-04-23T23:33:34.040Z,1713915214.040 [DAT](ERROR): Communications Fault
2024-04-23T23:33:34.178Z,1713915214.178 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:33:34.444Z,1713915214.444 [DAT](INFO): Powering down
2024-04-23T23:33:35.402Z,1713915215.402 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:33:35.402Z,1713915215.402 [DAT] No Fault, FailCount= 5
2024-04-23T23:33:37.491Z,1713915217.491 [DAT](INFO): Powering up
2024-04-23T23:33:37.492Z,1713915217.492 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:33:52.358Z,1713915232.358 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-04-23T23:33:52.358Z,1713915232.358 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:33:52.359Z,1713915232.359 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2024-04-23T23:33:52.359Z,1713915232.359 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:33:52.371Z,1713915232.371 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:33:52.371Z,1713915232.371 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:33:52.758Z,1713915232.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:33:52.758Z,1713915232.758 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-04-23T23:33:52.758Z,1713915232.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:33:52.758Z,1713915232.758 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2024-04-23T23:33:53.156Z,1713915233.156 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:34:37.532Z,1713915277.532 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:34:37.532Z,1713915277.532 [DAT] Communications Fault, FailCount= 6
2024-04-23T23:34:37.532Z,1713915277.532 [DAT](ERROR): Communications Fault
2024-04-23T23:34:37.623Z,1713915277.623 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:34:37.951Z,1713915277.951 [DAT](INFO): Powering down
2024-04-23T23:34:39.221Z,1713915279.221 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:34:39.222Z,1713915279.222 [DAT] No Fault, FailCount= 6
2024-04-23T23:34:40.999Z,1713915280.999 [DAT](INFO): Powering up
2024-04-23T23:34:40.999Z,1713915280.999 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:35:37.389Z,1713915337.389 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T23:30:37.3Z
2024-04-23T23:35:37.390Z,1713915337.390 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:35:37.390Z,1713915337.390 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-04-23T23:35:41.244Z,1713915341.244 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:35:41.244Z,1713915341.244 [DAT] Communications Fault, FailCount= 7
2024-04-23T23:35:41.244Z,1713915341.244 [DAT](ERROR): Communications Fault
2024-04-23T23:35:41.482Z,1713915341.482 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:35:41.648Z,1713915341.648 [DAT](INFO): Powering down
2024-04-23T23:35:42.723Z,1713915342.723 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:35:42.724Z,1713915342.724 [DAT] No Fault, FailCount= 7
2024-04-23T23:35:44.147Z,1713915344.147 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20240423T225729/Courier0010.lzma
2024-04-23T23:35:44.699Z,1713915344.699 [DAT](INFO): Powering up
2024-04-23T23:35:44.700Z,1713915344.700 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:35:45.149Z,1713915345.149 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Courier0010.lzma.bak
2024-04-23T23:35:45.149Z,1713915345.149 [DataOverHttps](INFO): SBD MOMSN=19424901
2024-04-23T23:36:00.767Z,1713915360.767 [DataOverHttps](INFO): Sending 400 bytes from file Logs/20240423T225729/Express0011.lzma
2024-04-23T23:36:01.769Z,1713915361.769 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Express0011.lzma.bak
2024-04-23T23:36:01.769Z,1713915361.769 [DataOverHttps](INFO): SBD MOMSN=19424909
2024-04-23T23:36:03.665Z,1713915363.665 [Default:CheckIn:Read_Iridium] Stopped
2024-04-23T23:36:03.665Z,1713915363.665 [Default:CheckIn:C.Wait] Running Loop=1
2024-04-23T23:36:03.665Z,1713915363.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-04-23T23:36:44.712Z,1713915404.712 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:36:44.712Z,1713915404.712 [DAT] Communications Fault, FailCount= 8
2024-04-23T23:36:44.712Z,1713915404.712 [DAT](ERROR): Communications Fault
2024-04-23T23:36:44.882Z,1713915404.882 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:36:44.883Z,1713915404.883 [CBIT](FAULT): Communications Fault in component: DAT
2024-04-23T23:36:45.115Z,1713915405.115 [DAT](INFO): Powering down
2024-04-23T23:36:53.348Z,1713915413.348 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-04-23T23:36:53.348Z,1713915413.348 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:36:53.349Z,1713915413.349 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2024-04-23T23:36:53.350Z,1713915413.350 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:36:53.361Z,1713915413.361 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:36:53.361Z,1713915413.361 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:36:53.774Z,1713915413.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:36:53.774Z,1713915413.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-04-23T23:36:53.774Z,1713915413.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:36:53.774Z,1713915413.774 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2024-04-23T23:36:54.151Z,1713915414.151 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:39:54.329Z,1713915594.329 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-04-23T23:39:54.329Z,1713915594.329 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:39:54.331Z,1713915594.331 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2024-04-23T23:39:54.331Z,1713915594.331 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:39:54.341Z,1713915594.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:39:54.341Z,1713915594.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:39:54.750Z,1713915594.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:39:54.750Z,1713915594.750 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-04-23T23:39:54.750Z,1713915594.750 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:39:54.750Z,1713915594.750 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2024-04-23T23:39:55.148Z,1713915595.148 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:40:29.877Z,1713915629.877 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-04-23T23:40:29.877Z,1713915629.877 [NAL9602] Data Fault, FailCount= 4
2024-04-23T23:40:29.877Z,1713915629.877 [NAL9602](ERROR): Data Fault
2024-04-23T23:40:29.903Z,1713915629.903 [CBIT](ERROR): Data Fault in component: NAL9602
2024-04-23T23:40:30.285Z,1713915630.285 [NAL9602](INFO): Powering down
2024-04-23T23:40:31.114Z,1713915631.114 [CBIT](INFO): Clearing failed state for component NAL9602
2024-04-23T23:40:31.114Z,1713915631.114 [NAL9602] No Fault, FailCount= 4
2024-04-23T23:41:00.585Z,1713915660.585 [NAL9602](INFO): Powering up NAL9602
2024-04-23T23:41:04.226Z,1713915664.226 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-04-23T23:41:04.226Z,1713915664.226 [Default:CheckIn:C.Wait] Stopped
2024-04-23T23:41:04.226Z,1713915664.226 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-04-23T23:41:04.226Z,1713915664.226 [Default:CheckIn:D] Running Loop=1
2024-04-23T23:41:04.640Z,1713915664.640 [Default:CheckIn:D] Stopped
2024-04-23T23:41:04.640Z,1713915664.640 [Default:CheckIn:E] Running Loop=1
2024-04-23T23:41:05.049Z,1713915665.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.548293 min
2024-04-23T23:41:05.049Z,1713915665.049 [Default:CheckIn:E] Stopped
2024-04-23T23:41:05.049Z,1713915665.049 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-04-23T23:41:05.049Z,1713915665.049 [Default:CheckIn] Stopped
2024-04-23T23:41:05.049Z,1713915665.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:41:05.049Z,1713915665.049 [Default:CheckIn](INFO): Running loop #4
2024-04-23T23:41:05.050Z,1713915665.050 [Default:CheckIn] Running Loop=4
2024-04-23T23:41:05.050Z,1713915665.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-04-23T23:41:05.050Z,1713915665.050 [Default:CheckIn:Read_GPS] Running Loop=1
2024-04-23T23:41:11.489Z,1713915671.489 [NAL9602](INFO): NAL9602 initialized
2024-04-23T23:41:45.066Z,1713915705.066 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:41:45.066Z,1713915705.066 [DAT] No Fault, FailCount= 8
2024-04-23T23:41:45.359Z,1713915705.359 [DAT](INFO): Powering up
2024-04-23T23:41:45.360Z,1713915705.360 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:42:45.376Z,1713915765.376 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:42:45.376Z,1713915765.376 [DAT] Communications Fault, FailCount= 1
2024-04-23T23:42:45.376Z,1713915765.376 [DAT](ERROR): Communications Fault
2024-04-23T23:42:45.681Z,1713915765.681 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:42:45.779Z,1713915765.779 [DAT](INFO): Powering down
2024-04-23T23:42:46.855Z,1713915766.855 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:42:46.855Z,1713915766.855 [DAT] No Fault, FailCount= 1
2024-04-23T23:42:48.827Z,1713915768.827 [DAT](INFO): Powering up
2024-04-23T23:42:48.828Z,1713915768.828 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:42:55.322Z,1713915775.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-04-23T23:42:55.322Z,1713915775.322 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-04-23T23:42:55.323Z,1713915775.323 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2024-04-23T23:42:55.323Z,1713915775.323 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2024-04-23T23:42:55.333Z,1713915775.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-04-23T23:42:55.334Z,1713915775.334 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2024-04-23T23:42:55.738Z,1713915775.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-04-23T23:42:55.738Z,1713915775.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-04-23T23:42:55.739Z,1713915775.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2024-04-23T23:42:55.739Z,1713915775.739 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2024-04-23T23:42:56.145Z,1713915776.145 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2024-04-23T23:43:39.468Z,1713915819.468 [CommandExec](IMPORTANT): got command burn off
2024-04-23T23:43:39.468Z,1713915819.468 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-04-23T23:43:44.148Z,1713915824.148 [CommandExec](IMPORTANT): got command burn on
2024-04-23T23:43:44.148Z,1713915824.148 [CommandExec](IMPORTANT): Activating dropweight wire
2024-04-23T23:43:47.580Z,1713915827.580 [CommandExec](IMPORTANT): got command burn off
2024-04-23T23:43:47.580Z,1713915827.580 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-04-23T23:43:48.860Z,1713915828.860 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2024-04-23T23:43:48.860Z,1713915828.860 [DAT] Communications Fault, FailCount= 2
2024-04-23T23:43:48.860Z,1713915828.860 [DAT](ERROR): Communications Fault
2024-04-23T23:43:49.102Z,1713915829.102 [CBIT](ERROR): Communications Fault in component: DAT
2024-04-23T23:43:49.263Z,1713915829.263 [DAT](INFO): Powering down
2024-04-23T23:43:50.282Z,1713915830.282 [CBIT](INFO): Clearing failed state for component DAT
2024-04-23T23:43:50.282Z,1713915830.282 [DAT] No Fault, FailCount= 2
2024-04-23T23:43:52.320Z,1713915832.320 [DAT](INFO): Powering up
2024-04-23T23:43:52.320Z,1713915832.320 [DAT](DEBUG): Initializing DAT.
2024-04-23T23:44:29.856Z,1713915869.856 [CommandExec](IMPORTANT): got command restart system
2024-04-23T23:44:31.927Z,1713915871.927 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:31.928Z,1713915871.928 [CommandExec](INFO): Uninitializing the command executive.
2024-04-23T23:44:31.928Z,1713915871.928 [CommandExec](INFO): Uninitializing the command scheduler.
2024-04-23T23:44:31.928Z,1713915871.928 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.079Z,1713915872.079 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-04-23T23:44:32.079Z,1713915872.079 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-04-23T23:44:32.079Z,1713915872.079 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.080Z,1713915872.080 [NavChartDb](INFO): Join timeout helper Thread ID is 6407
2024-04-23T23:44:32.431Z,1713915872.431 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:32.432Z,1713915872.432 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.445Z,1713915872.445 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-04-23T23:44:32.445Z,1713915872.445 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.446Z,1713915872.446 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6408
2024-04-23T23:44:32.659Z,1713915872.659 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:32.660Z,1713915872.660 [WetLabsBB2FL](INFO): Powering down
2024-04-23T23:44:32.661Z,1713915872.661 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.679Z,1713915872.679 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-04-23T23:44:32.679Z,1713915872.679 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.680Z,1713915872.680 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6409
2024-04-23T23:44:32.819Z,1713915872.819 [CTD_Seabird](INFO): Powering down
2024-04-23T23:44:32.831Z,1713915872.831 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:32.831Z,1713915872.831 [CTD_Seabird](INFO): Powering down
2024-04-23T23:44:32.843Z,1713915872.843 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.855Z,1713915872.855 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-04-23T23:44:32.855Z,1713915872.855 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:32.856Z,1713915872.856 [DAT](INFO): Join timeout helper Thread ID is 6410
2024-04-23T23:44:32.919Z,1713915872.919 [DAT](INFO): Powering down
2024-04-23T23:44:32.992Z,1713915872.992 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:32.992Z,1713915872.992 [DAT](INFO): Powering down
2024-04-23T23:44:32.993Z,1713915872.993 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.007Z,1713915873.007 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-04-23T23:44:33.007Z,1713915873.007 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.008Z,1713915873.008 [Radio_Surface](INFO): Join timeout helper Thread ID is 6411
2024-04-23T23:44:33.215Z,1713915873.215 [Radio_Surface](INFO): Powering down
2024-04-23T23:44:33.216Z,1713915873.216 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:33.217Z,1713915873.217 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.232Z,1713915873.232 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-04-23T23:44:33.232Z,1713915873.232 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.233Z,1713915873.233 [Onboard](INFO): Join timeout helper Thread ID is 6412
2024-04-23T23:44:33.412Z,1713915873.412 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:33.412Z,1713915873.412 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.437Z,1713915873.437 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-04-23T23:44:33.437Z,1713915873.437 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.437Z,1713915873.437 [DataOverHttps](INFO): Join timeout helper Thread ID is 6413
2024-04-23T23:44:33.547Z,1713915873.547 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:33.548Z,1713915873.548 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.560Z,1713915873.560 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-04-23T23:44:33.560Z,1713915873.560 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.561Z,1713915873.561 [BackseatComponent](INFO): Join timeout helper Thread ID is 6414
2024-04-23T23:44:33.731Z,1713915873.731 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:33.732Z,1713915873.732 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.744Z,1713915873.744 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-04-23T23:44:33.744Z,1713915873.744 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.744Z,1713915873.744 [logger](INFO): Join timeout helper Thread ID is 6415
2024-04-23T23:44:33.780Z,1713915873.780 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:33.780Z,1713915873.780 [logger ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.784Z,1713915873.784 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-04-23T23:44:33.784Z,1713915873.784 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.784Z,1713915873.784 [CommandLine](INFO): Join timeout helper Thread ID is 6416
2024-04-23T23:44:33.839Z,1713915873.839 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:33.840Z,1713915873.840 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.844Z,1713915873.844 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-04-23T23:44:33.844Z,1713915873.844 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.844Z,1713915873.844 [CommandExec](INFO): Join timeout helper Thread ID is 6417
2024-04-23T23:44:33.846Z,1713915873.846 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-04-23T23:44:33.846Z,1713915873.846 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:33.846Z,1713915873.846 [controlThread](INFO): Join timeout helper Thread ID is 6418
2024-04-23T23:44:33.883Z,1713915873.883 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-04-23T23:44:33.884Z,1713915873.884 [controlThread](DEBUG): Uninitializing ControlThread
2024-04-23T23:44:33.884Z,1713915873.884 [AHRS_M2](INFO): Powering down
2024-04-23T23:44:33.956Z,1713915873.956 [MultiRay](INFO): Powering down.
2024-04-23T23:44:33.957Z,1713915873.957 [NAL9602](INFO): Powering down
2024-04-23T23:44:33.959Z,1713915873.959 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-04-23T23:44:33.960Z,1713915873.960 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-04-23T23:44:33.960Z,1713915873.960 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-04-23T23:44:33.961Z,1713915873.961 [MissionManager](INFO): Uninitializing Mission Default
2024-04-23T23:44:33.961Z,1713915873.961 [Default] Stopped
2024-04-23T23:44:33.961Z,1713915873.961 [Default](DEBUG): Aggregate::uninitialize Default
2024-04-23T23:44:33.961Z,1713915873.961 [Default:B.GoToSurface] Stopped
2024-04-23T23:44:33.961Z,1713915873.961 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-04-23T23:44:33.961Z,1713915873.961 [Default:CheckIn] Stopped
2024-04-23T23:44:33.962Z,1713915873.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-04-23T23:44:33.962Z,1713915873.962 [Default:CheckIn:Read_GPS] Stopped
2024-04-23T23:44:33.965Z,1713915873.965 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-04-23T23:44:33.965Z,1713915873.965 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-04-23T23:44:33.965Z,1713915873.965 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-04-23T23:44:33.966Z,1713915873.966 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-04-23T23:44:33.966Z,1713915873.966 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-04-23T23:44:33.966Z,1713915873.966 [BuoyancyServo](INFO): Powering down
2024-04-23T23:44:33.979Z,1713915873.979 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-04-23T23:44:33.979Z,1713915873.979 [ElevatorServo](INFO): Powering down
2024-04-23T23:44:33.980Z,1713915873.980 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-04-23T23:44:33.980Z,1713915873.980 [MassServo](INFO): Powering down
2024-04-23T23:44:33.981Z,1713915873.981 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-04-23T23:44:33.982Z,1713915873.982 [RudderServo](INFO): Powering down
2024-04-23T23:44:33.982Z,1713915873.982 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-04-23T23:44:33.982Z,1713915873.982 [ThrusterHE](INFO): Powering down
2024-04-23T23:44:33.984Z,1713915873.984 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-04-23T23:44:33.984Z,1713915873.984 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-04-23T23:44:33.984Z,1713915873.984 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-04-23T23:44:33.984Z,1713915873.984 [CBIT](DEBUG): Powering off loads.
2024-04-23T23:44:33.995Z,1713915873.995 [CBIT](DEBUG): Disabling WDT.
2024-04-23T23:44:34.007Z,1713915874.007 [CBIT](DEBUG): Opening all GF detection circuits.
2024-04-23T23:44:34.008Z,1713915874.008 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.037Z,1713915874.037 [DAT ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.043Z,1713915874.043 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.046Z,1713915874.046 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.053Z,1713915874.053 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.098Z,1713915874.098 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.148Z,1713915874.148 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.153Z,1713915874.153 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.208Z,1713915874.208 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-04-23T23:44:34.256Z,1713915874.256 [logger ThreadHandler](INFO): Thread cancelled.