2024-04-01T22:11:51.055Z,1712009511.055 [Supervisor](DEBUG): Initializing supervisor. 2024-04-01T22:11:51.060Z,1712009511.060 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-01T22:11:51.061Z,1712009511.061 [SyncHandler](INFO): Protected caller Thread ID is 1242 2024-04-01T22:11:51.062Z,1712009511.062 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-01T22:11:51.063Z,1712009511.063 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-01T22:11:51.063Z,1712009511.063 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1243 2024-04-01T22:11:51.067Z,1712009511.067 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-01T22:11:51.086Z,1712009511.086 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-01T22:11:51.087Z,1712009511.087 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-01T22:11:51.087Z,1712009511.087 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1244 2024-04-01T22:11:51.092Z,1712009511.092 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-01T22:11:51.092Z,1712009511.092 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-01T22:11:51.093Z,1712009511.093 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1245 2024-04-01T22:11:51.095Z,1712009511.095 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-01T22:11:51.096Z,1712009511.096 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-01T22:11:51.096Z,1712009511.096 [logger ThreadHandler](INFO): Protected caller Thread ID is 1246 2024-04-01T22:11:51.100Z,1712009511.100 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-01T22:11:51.100Z,1712009511.100 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-01T22:11:51.102Z,1712009511.102 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-01T22:11:51.450Z,1712009511.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-01T22:11:51.450Z,1712009511.450 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-01T22:11:52.208Z,1712009512.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-01T22:11:52.209Z,1712009512.209 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-01T22:11:52.328Z,1712009512.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-01T22:11:52.329Z,1712009512.329 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-01T22:11:52.869Z,1712009512.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-01T22:11:52.870Z,1712009512.870 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-01T22:11:52.977Z,1712009512.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-01T22:11:52.978Z,1712009512.978 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-01T22:11:53.070Z,1712009513.070 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-01T22:11:53.500Z,1712009513.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-01T22:11:53.501Z,1712009513.501 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-01T22:11:53.743Z,1712009513.743 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-01T22:11:53.744Z,1712009513.744 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-01T22:11:53.893Z,1712009513.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-01T22:11:53.894Z,1712009513.894 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-01T22:11:54.156Z,1712009514.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-01T22:11:54.157Z,1712009514.157 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-01T22:11:54.712Z,1712009514.712 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-01T22:11:54.713Z,1712009514.713 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-01T22:11:55.203Z,1712009515.203 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-01T22:11:55.204Z,1712009515.204 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-01T22:11:55.408Z,1712009515.408 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-01T22:11:55.765Z,1712009515.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-01T22:11:55.766Z,1712009515.766 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-01T22:11:56.010Z,1712009516.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-01T22:11:56.010Z,1712009516.010 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-01T22:11:56.334Z,1712009516.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-01T22:11:56.336Z,1712009516.336 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2024-04-01T22:11:56.336Z,1712009516.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2024-04-01T22:11:56.456Z,1712009516.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2024-04-01T22:11:56.620Z,1712009516.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2024-04-01T22:11:56.716Z,1712009516.716 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2024-04-01T22:11:56.810Z,1712009516.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2024-04-01T22:11:56.931Z,1712009516.931 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2024-04-01T22:11:57.048Z,1712009517.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2024-04-01T22:11:57.321Z,1712009517.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-01T22:11:57.322Z,1712009517.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2024-04-01T22:11:57.467Z,1712009517.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2024-04-01T22:11:57.572Z,1712009517.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2024-04-01T22:11:57.675Z,1712009517.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2024-04-01T22:11:57.789Z,1712009517.789 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2024-04-01T22:11:57.790Z,1712009517.790 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-04-01T22:11:57.801Z,1712009517.801 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-01T22:11:57.920Z,1712009517.920 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-01T22:11:57.921Z,1712009517.921 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-01T22:11:57.931Z,1712009517.931 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-01T22:11:57.932Z,1712009517.932 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-01T22:11:57.984Z,1712009517.984 [DepthRateCalculator] Loaded 2024-04-01T22:11:57.984Z,1712009517.984 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-01T22:11:57.990Z,1712009517.990 [PitchRateCalculator] Loaded 2024-04-01T22:11:57.990Z,1712009517.990 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-01T22:11:57.000Z,1712009518.000 [SpeedCalculator] Loaded 2024-04-01T22:11:58.000Z,1712009518.000 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-01T22:11:58.005Z,1712009518.005 [YawRateCalculator] Loaded 2024-04-01T22:11:58.005Z,1712009518.005 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-01T22:11:58.027Z,1712009518.027 [ElevatorOffsetCalculator] Loaded 2024-04-01T22:11:58.027Z,1712009518.027 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-01T22:11:58.028Z,1712009518.028 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-01T22:11:58.028Z,1712009518.028 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-01T22:11:58.081Z,1712009518.081 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-01T22:11:58.138Z,1712009518.138 [VerticalControl] Loaded 2024-04-01T22:11:58.138Z,1712009518.138 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-01T22:11:58.141Z,1712009518.141 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-01T22:11:58.183Z,1712009518.183 [HorizontalControl] Loaded 2024-04-01T22:11:58.183Z,1712009518.183 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-01T22:11:58.185Z,1712009518.185 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-01T22:11:58.188Z,1712009518.188 [SpeedControl] Loaded 2024-04-01T22:11:58.188Z,1712009518.188 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-01T22:11:58.191Z,1712009518.191 [LoopControl](DEBUG): Construct LoopControl. 2024-04-01T22:11:58.192Z,1712009518.192 [LoopControl] Loaded 2024-04-01T22:11:58.192Z,1712009518.192 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-01T22:11:58.192Z,1712009518.192 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-01T22:11:58.193Z,1712009518.193 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-01T22:11:58.306Z,1712009518.306 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-01T22:11:58.307Z,1712009518.307 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-01T22:11:58.320Z,1712009518.320 [NavChart] Loaded 2024-04-01T22:11:58.320Z,1712009518.320 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-01T22:11:58.326Z,1712009518.326 [UniversalFixResidualReporter] Loaded 2024-04-01T22:11:58.326Z,1712009518.326 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-01T22:11:58.327Z,1712009518.327 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-01T22:11:58.327Z,1712009518.327 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-01T22:11:58.486Z,1712009518.486 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-01T22:11:58.486Z,1712009518.486 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-01T22:11:58.955Z,1712009518.955 [AHRS_M2] Loaded 2024-04-01T22:11:58.955Z,1712009518.955 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-01T22:11:58.990Z,1712009518.990 [BackseatComponent] Loaded 2024-04-01T22:11:58.990Z,1712009518.990 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-04-01T22:11:58.992Z,1712009518.992 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409124E0 2024-04-01T22:11:58.992Z,1712009518.992 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1328 2024-04-01T22:11:58.995Z,1712009518.995 [LcmUniversalReporter] Loaded 2024-04-01T22:11:58.995Z,1712009518.995 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-04-01T22:11:59.798Z,1712009519.798 [BPC1] Loaded 2024-04-01T22:11:59.798Z,1712009519.798 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-01T22:11:59.872Z,1712009519.872 [DataOverHttps] Loaded 2024-04-01T22:11:59.872Z,1712009519.872 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-01T22:11:59.873Z,1712009519.873 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409424E0 2024-04-01T22:11:59.874Z,1712009519.874 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1329 2024-04-01T22:11:59.894Z,1712009519.894 [Depth_Keller] Loaded 2024-04-01T22:11:59.894Z,1712009519.894 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-01T22:11:59.899Z,1712009519.899 [DropWeight] Loaded 2024-04-01T22:11:59.899Z,1712009519.899 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-01T22:11:59.936Z,1712009519.936 [DVL_micro] Loaded 2024-04-01T22:11:59.937Z,1712009519.937 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-04-01T22:11:59.998Z,1712009519.998 [NAL9602] Loaded 2024-04-01T22:11:59.998Z,1712009519.998 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-01T22:12:00.028Z,1712009520.028 [Onboard] Loaded 2024-04-01T22:12:00.028Z,1712009520.028 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-01T22:12:00.029Z,1712009520.029 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409724E0 2024-04-01T22:12:00.029Z,1712009520.029 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1330 2024-04-01T22:12:00.041Z,1712009520.041 [Power24vConverter] Loaded 2024-04-01T22:12:00.042Z,1712009520.042 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-01T22:12:00.055Z,1712009520.055 [Radio_Surface] Loaded 2024-04-01T22:12:00.055Z,1712009520.055 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-01T22:12:00.056Z,1712009520.056 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A24E0 2024-04-01T22:12:00.056Z,1712009520.056 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1331 2024-04-01T22:12:00.070Z,1712009520.070 [Sonardyne_Nano] Loaded 2024-04-01T22:12:00.070Z,1712009520.070 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-04-01T22:12:00.162Z,1712009520.162 [DAT] Loaded 2024-04-01T22:12:00.163Z,1712009520.163 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-01T22:12:00.164Z,1712009520.164 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409D24E0 2024-04-01T22:12:00.164Z,1712009520.164 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1332 2024-04-01T22:12:00.165Z,1712009520.165 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-01T22:12:00.165Z,1712009520.165 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-01T22:12:00.302Z,1712009520.302 [BuoyancyServo] Loaded 2024-04-01T22:12:00.303Z,1712009520.303 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-01T22:12:00.324Z,1712009520.324 [ElevatorServo] Loaded 2024-04-01T22:12:00.324Z,1712009520.324 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-01T22:12:00.343Z,1712009520.343 [MassServo] Loaded 2024-04-01T22:12:00.344Z,1712009520.344 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-01T22:12:00.362Z,1712009520.362 [RudderServo] Loaded 2024-04-01T22:12:00.362Z,1712009520.362 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-01T22:12:00.377Z,1712009520.377 [ThrusterHE] Loaded 2024-04-01T22:12:00.377Z,1712009520.377 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-01T22:12:00.378Z,1712009520.378 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-01T22:12:00.378Z,1712009520.378 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-01T22:12:00.589Z,1712009520.589 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-01T22:12:00.590Z,1712009520.590 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-01T22:12:00.691Z,1712009520.691 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-01T22:12:00.691Z,1712009520.691 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-01T22:12:01.358Z,1712009521.358 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-01T22:12:01.358Z,1712009521.358 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-01T22:12:01.536Z,1712009521.536 [CTD_Seabird] Loaded 2024-04-01T22:12:01.536Z,1712009521.536 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-01T22:12:01.538Z,1712009521.538 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B884E0 2024-04-01T22:12:01.538Z,1712009521.538 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1333 2024-04-01T22:12:01.572Z,1712009521.572 [ESPComponent] Loaded 2024-04-01T22:12:01.572Z,1712009521.572 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-04-01T22:12:01.592Z,1712009521.592 [PAR_Licor] Loaded 2024-04-01T22:12:01.593Z,1712009521.593 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-01T22:12:01.636Z,1712009521.636 [WetLabsBB2FL] Loaded 2024-04-01T22:12:01.637Z,1712009521.637 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-01T22:12:01.638Z,1712009521.638 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BB84E0 2024-04-01T22:12:01.638Z,1712009521.638 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1334 2024-04-01T22:12:01.639Z,1712009521.639 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-01T22:12:01.639Z,1712009521.639 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-01T22:12:01.786Z,1712009521.786 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-01T22:12:01.795Z,1712009521.795 [SBIT] Loaded 2024-04-01T22:12:01.796Z,1712009521.796 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-01T22:12:01.799Z,1712009521.799 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-01T22:12:01.812Z,1712009521.812 [IBIT] Loaded 2024-04-01T22:12:01.812Z,1712009521.812 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-01T22:12:01.818Z,1712009521.818 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-01T22:12:01.925Z,1712009521.925 [CBIT] Loaded 2024-04-01T22:12:01.925Z,1712009521.925 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-01T22:12:01.926Z,1712009521.926 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-01T22:12:01.932Z,1712009521.932 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-01T22:12:01.935Z,1712009521.935 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-01T22:12:01.947Z,1712009521.947 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-01T22:12:01.948Z,1712009521.948 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C274E0 2024-04-01T22:12:01.948Z,1712009521.948 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1335 2024-04-01T22:12:01.953Z,1712009521.953 [Supervisor](INFO): Main Thread ID is 827 2024-04-01T22:12:01.953Z,1712009521.953 [Supervisor](DEBUG): Running supervisor. 2024-04-01T22:12:01.954Z,1712009521.954 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1336 2024-04-01T22:12:01.954Z,1712009521.954 [CommandExec](INFO): Initializing the command executive. 2024-04-01T22:12:01.956Z,1712009521.956 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1337 2024-04-01T22:12:01.958Z,1712009521.958 [controlThread ThreadHandler](INFO): Handler Thread ID is 1338 2024-04-01T22:12:01.959Z,1712009521.959 [controlThread](DEBUG): Initializing ControlThread 2024-04-01T22:12:01.959Z,1712009521.959 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-01T22:12:01.960Z,1712009521.960 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-01T22:12:01.960Z,1712009521.960 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-01T22:12:01.960Z,1712009521.960 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-01T22:12:01.961Z,1712009521.961 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-01T22:12:01.961Z,1712009521.961 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-01T22:12:01.963Z,1712009521.963 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-01T22:12:01.964Z,1712009521.964 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-01T22:12:01.964Z,1712009521.964 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-01T22:12:01.965Z,1712009521.965 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-01T22:12:01.966Z,1712009521.966 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-01T22:12:01.978Z,1712009521.978 [SBIT](INFO): Initialize SBIT Component. 2024-04-01T22:12:01.978Z,1712009521.978 [SBIT](IMPORTANT): git: 2024-03-26-19-gde66f5fd8 2024-04-01T22:12:01.978Z,1712009521.978 [SBIT](INFO): git hash: de66f5fd802c1e8ada03c532d8f0e1a16215eef6 2024-04-01T22:12:01.979Z,1712009521.979 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-01T22:12:01.980Z,1712009521.980 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-04-01T22:12:01.981Z,1712009521.981 [SBIT](INFO): Beginning SBIT in 104.000000 seconds. 2024-04-01T22:12:01.982Z,1712009521.982 [IBIT](INFO): Initialize IBIT Component. 2024-04-01T22:12:01.982Z,1712009521.982 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-01T22:12:01.983Z,1712009521.983 [logger ThreadHandler](INFO): Handler Thread ID is 1339 2024-04-01T22:12:01.994Z,1712009521.994 [CBIT](DEBUG): Initialized mux pins. 2024-04-01T22:12:01.994Z,1712009521.994 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-01T22:12:02.002Z,1712009522.002 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1340 2024-04-01T22:12:02.014Z,1712009522.014 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1341 2024-04-01T22:12:02.016Z,1712009522.016 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-01T22:12:02.018Z,1712009522.018 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2024-04-01T22:12:02.018Z,1712009522.018 [CBIT](DEBUG): Initializing heartbeat. 2024-04-01T22:12:02.026Z,1712009522.026 [Onboard ThreadHandler](INFO): Handler Thread ID is 1342 2024-04-01T22:12:02.044Z,1712009522.044 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1343 2024-04-01T22:12:02.066Z,1712009522.066 [DAT ThreadHandler](INFO): Handler Thread ID is 1344 2024-04-01T22:12:02.067Z,1712009522.067 [DAT](INFO): Powering up 2024-04-01T22:12:02.067Z,1712009522.067 [DAT](DEBUG): Initializing DAT. 2024-04-01T22:12:02.071Z,1712009522.071 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1346 2024-04-01T22:12:02.072Z,1712009522.072 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-01T22:12:02.075Z,1712009522.075 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1348 2024-04-01T22:12:02.078Z,1712009522.078 [WetLabsBB2FL](INFO): Powering up 2024-04-01T22:12:02.079Z,1712009522.079 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1350 2024-04-01T22:12:02.082Z,1712009522.082 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-04-01T22:12:02.083Z,1712009522.083 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-04-01T22:12:02.083Z,1712009522.083 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-04-01T22:12:02.083Z,1712009522.083 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-04-01T22:12:02.083Z,1712009522.083 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-04-01T22:12:02.083Z,1712009522.083 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-04-01T22:12:02.083Z,1712009522.083 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-04-01T22:12:02.084Z,1712009522.084 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-04-01T22:12:02.090Z,1712009522.090 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-01T22:12:02.090Z,1712009522.090 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-01T22:12:02.129Z,1712009522.129 [CBIT](DEBUG): Backplane powered. 2024-04-01T22:12:02.131Z,1712009522.131 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-01T22:12:02.131Z,1712009522.131 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-04-01T22:12:02.145Z,1712009522.145 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-01T22:12:02.173Z,1712009522.173 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-04-01T22:12:02.174Z,1712009522.174 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-04-01T22:12:02.174Z,1712009522.174 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-04-01T22:12:02.266Z,1712009522.266 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-01T22:12:02.267Z,1712009522.267 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-01T22:12:02.270Z,1712009522.270 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-01T22:12:02.329Z,1712009522.329 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-01T22:12:02.346Z,1712009522.346 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-01T22:12:02.352Z,1712009522.352 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-01T22:12:02.367Z,1712009522.367 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-04-01T22:12:02.373Z,1712009522.373 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-04-01T22:12:02.400Z,1712009522.400 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-01T22:12:02.450Z,1712009522.450 [Radio_Surface](INFO): Powering up 2024-04-01T22:12:02.479Z,1712009522.479 [Power24vConverter](INFO): Powering up. 2024-04-01T22:12:02.479Z,1712009522.479 [Sonardyne_Nano](INFO): Initializing. 2024-04-01T22:12:02.556Z,1712009522.556 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-01T22:12:02.562Z,1712009522.562 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-01T22:12:02.563Z,1712009522.563 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-01T22:12:02.571Z,1712009522.571 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-01T22:12:02.572Z,1712009522.572 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-01T22:12:02.579Z,1712009522.579 [MassServo](DEBUG): Initializing MassServo. 2024-04-01T22:12:02.579Z,1712009522.579 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-01T22:12:02.586Z,1712009522.586 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-01T22:12:02.587Z,1712009522.587 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-01T22:12:02.594Z,1712009522.594 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-01T22:12:02.596Z,1712009522.596 [CommandExec](FAULT): Scheduling is paused 2024-04-01T22:12:02.596Z,1712009522.596 [CBIT](INFO): Critical error at 20240401T221202 2024-04-01T22:12:02.596Z,1712009522.596 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-04-01T22:12:04.690Z,1712009524.690 [WetLabsBB2FL](INFO): Powering down 2024-04-01T22:12:06.046Z,1712009526.046 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2024-04-01T22:12:08.143Z,1712009528.143 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-04-01T22:12:13.914Z,1712009533.914 [DAT](INFO): DAT read: 2024-04-01T22:12:13.915Z,1712009533.915 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-01T22:12:15.678Z,1712009535.678 [DAT](INFO): DAT read: MF Frequency Band 2024-04-01T22:12:15.680Z,1712009535.680 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-01T22:12:15.680Z,1712009535.680 [DAT](INFO): DAT read: Apr 1 2024 22:12:23 2024-04-01T22:12:16.687Z,1712009536.687 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-01T22:12:16.688Z,1712009536.688 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-01T22:12:16.688Z,1712009536.688 [DAT](INFO): commRate: 800 2024-04-01T22:12:18.754Z,1712009538.754 [DAT](INFO): entering command mode 2024-04-01T22:12:18.954Z,1712009538.954 [DAT](INFO): DAT read: 2024-04-01T22:12:18.955Z,1712009538.955 [DAT](INFO): DAT read: user:1> 2024-04-01T22:12:18.955Z,1712009538.955 [DAT](INFO): setting verbose to 3 2024-04-01T22:12:19.206Z,1712009539.206 [DAT](INFO): DAT read: user:1> 2024-04-01T22:12:19.207Z,1712009539.207 [DAT](INFO): DAT read: Verbose | 3 2024-04-01T22:12:19.208Z,1712009539.208 [DAT](INFO): set verbose to 3 2024-04-01T22:12:19.208Z,1712009539.208 [DAT](INFO): setting DatVerbose to 27440 2024-04-01T22:12:19.458Z,1712009539.458 [DAT](INFO): DAT read: user:2> 2024-04-01T22:12:19.459Z,1712009539.459 [DAT](INFO): DAT read: DatVerbose | 27440 2024-04-01T22:12:19.459Z,1712009539.459 [DAT](INFO): set DatVerbose to 27440 2024-04-01T22:12:19.460Z,1712009539.460 [DAT](INFO): setting transmit power to 8 2024-04-01T22:12:19.710Z,1712009539.710 [DAT](INFO): DAT read: user:3> 2024-04-01T22:12:19.711Z,1712009539.711 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-04-01T22:12:19.711Z,1712009539.711 [DAT](INFO): set transmit power to 8 2024-04-01T22:12:19.712Z,1712009539.712 [DAT](INFO): setting local address to 11 2024-04-01T22:12:19.962Z,1712009539.962 [DAT](INFO): DAT read: user:4> 2024-04-01T22:12:19.963Z,1712009539.963 [DAT](INFO): DAT read: LocalAddr | 11 2024-04-01T22:12:19.964Z,1712009539.964 [DAT](INFO): set local address to 11 2024-04-01T22:12:19.965Z,1712009539.965 [DAT](INFO): Setting time to: 22:12:19 And date to:4/1/2024 2024-04-01T22:12:20.214Z,1712009540.214 [DAT](INFO): DAT read: user:5> 2024-04-01T22:12:20.216Z,1712009540.216 [DAT](INFO): DAT read: Mon Apr 1, 2024 22:12:19 2024-04-01T22:12:20.216Z,1712009540.216 [DAT](INFO): Local DAT time set to Mon Apr 1, 2024 22:12:19 2024-04-01T22:12:30.260Z,1712009550.260 [NAL9602](INFO): Powering up NAL9602 2024-04-01T22:12:41.173Z,1712009561.173 [NAL9602](INFO): NAL9602 initialized 2024-04-01T22:13:16.312Z,1712009596.312 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T22:13:46.632Z,1712009626.632 [SBIT](IMPORTANT): Beginning Startup BIT 2024-04-01T22:13:46.637Z,1712009626.637 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-01T22:13:57.670Z,1712009637.670 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.011221 CHAN A1 (24V): -0.002944 CHAN A2 (12V): -0.000211 CHAN A3 (5V): 0.003771 CHAN B0 (3.3V): 0.000890 CHAN B1 (3.15aV): 0.000559 CHAN B2 (3.15bV): 0.000925 CHAN B3 (GND): 0.000099 OPEN: 0.005585 Full Scale: +/- 1 mA 2024-04-01T22:14:30.113Z,1712009670.113 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005676 2024-04-01T22:14:35.631Z,1712009675.631 [CommandExec](IMPORTANT): got command show variable nano 2024-04-01T22:14:35.651Z,1712009675.651 [CommandExec](IMPORTANT): NanoDVR.loadAtStartup (bool) 2024-04-01T22:14:35.652Z,1712009675.652 [CommandExec](IMPORTANT): NanoDVR.simulateHardware (bool) 2024-04-01T22:14:35.652Z,1712009675.652 [CommandExec](IMPORTANT): NanoDVR.sampleTime (second) 2024-04-01T22:14:35.656Z,1712009675.656 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool) 2024-04-01T22:14:35.656Z,1712009675.656 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool) 2024-04-01T22:14:35.657Z,1712009675.657 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent) 2024-04-01T22:14:35.657Z,1712009675.657 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent) 2024-04-01T22:14:35.711Z,1712009675.711 [CommandExec](IMPORTANT): NanoDVR.loadControl (none) 2024-04-01T22:14:35.711Z,1712009675.711 [CommandExec](IMPORTANT): NanoDVR.uart (none) 2024-04-01T22:14:35.711Z,1712009675.711 [CommandExec](IMPORTANT): NanoDVR.baud (bit_per_second) 2024-04-01T22:14:35.713Z,1712009675.713 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none) 2024-04-01T22:14:35.722Z,1712009675.722 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none) 2024-04-01T22:14:35.722Z,1712009675.722 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second) 2024-04-01T22:14:35.779Z,1712009675.779 [CommandExec](IMPORTANT): Sonardyne_Nano.enableBroadcast (bool) 2024-04-01T22:14:35.779Z,1712009675.779 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage (volt) 2024-04-01T22:14:35.780Z,1712009675.780 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgVoltage (volt) 2024-04-01T22:14:35.780Z,1712009675.780 [CommandExec](IMPORTANT): Sonardyne_Nano.component_current (milliampere) 2024-04-01T22:14:35.781Z,1712009675.781 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent (milliampere) 2024-04-01T22:14:35.781Z,1712009675.781 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent (percent) 2024-04-01T22:14:40.801Z,1712009680.801 [SBIT](IMPORTANT): SBIT PASSED 2024-04-01T22:14:40.802Z,1712009680.802 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count; 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): DVL_micro.baud=19200 bit_per_second; 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=75.046417 cubic_centimeter; 2024-04-01T22:14:40.803Z,1712009680.803 [SBIT](IMPORTANT): VerticalControl.massDefault=21.239223 millimeter; 2024-04-01T22:14:41.230Z,1712009681.230 [MissionManager](IMPORTANT): Started mission Startup 2024-04-01T22:14:41.231Z,1712009681.231 [Startup] Running Loop=1 2024-04-01T22:14:41.231Z,1712009681.231 [Startup](DEBUG): Aggregate::initialize Startup 2024-04-01T22:14:41.231Z,1712009681.231 [Startup:A.GoToSurface] Running Loop=1 2024-04-01T22:14:41.232Z,1712009681.232 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-01T22:14:41.232Z,1712009681.232 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-01T22:14:41.233Z,1712009681.233 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-01T22:14:41.238Z,1712009681.238 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-01T22:14:41.238Z,1712009681.238 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-01T22:14:41.239Z,1712009681.239 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-01T22:14:41.241Z,1712009681.241 [Startup:StartupSatComms] Running Loop=1 2024-04-01T22:14:41.246Z,1712009681.246 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-04-01T22:14:41.246Z,1712009681.246 [Startup:StartupSatComms:A] Running Loop=1 2024-04-01T22:14:41.588Z,1712009681.588 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-04-01T22:14:43.725Z,1712009683.725 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-04-01T22:14:43.725Z,1712009683.725 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2024-04-01T22:14:56.402Z,1712009696.402 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.loadAtStartup 2024-04-01T22:14:56.403Z,1712009696.403 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup 1 bool 2024-04-01T22:15:02.243Z,1712009702.243 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-04-01T22:15:02.243Z,1712009702.243 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:15:02.278Z,1712009702.278 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:15:02.658Z,1712009702.658 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:15:02.658Z,1712009702.658 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-04-01T22:15:06.034Z,1712009706.034 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.simulateHardware 2024-04-01T22:15:06.034Z,1712009706.034 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware 0 bool 2024-04-01T22:15:13.735Z,1712009713.735 [CommandExec](IMPORTANT): got command show variable sonardyneNano 2024-04-01T22:15:16.335Z,1712009716.335 [CommandExec](IMPORTANT): got command show variable nano 2024-04-01T22:15:16.361Z,1712009716.361 [CommandExec](IMPORTANT): NanoDVR.loadAtStartup (bool) 2024-04-01T22:15:16.361Z,1712009716.361 [CommandExec](IMPORTANT): NanoDVR.simulateHardware (bool) 2024-04-01T22:15:16.395Z,1712009716.395 [CommandExec](IMPORTANT): NanoDVR.sampleTime (second) 2024-04-01T22:15:16.399Z,1712009716.399 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool) 2024-04-01T22:15:16.399Z,1712009716.399 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool) 2024-04-01T22:15:16.400Z,1712009716.400 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent) 2024-04-01T22:15:16.400Z,1712009716.400 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent) 2024-04-01T22:15:16.467Z,1712009716.467 [CommandExec](IMPORTANT): NanoDVR.loadControl (none) 2024-04-01T22:15:16.467Z,1712009716.467 [CommandExec](IMPORTANT): NanoDVR.uart (none) 2024-04-01T22:15:16.468Z,1712009716.468 [CommandExec](IMPORTANT): NanoDVR.baud (bit_per_second) 2024-04-01T22:15:16.471Z,1712009716.471 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none) 2024-04-01T22:15:16.471Z,1712009716.471 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none) 2024-04-01T22:15:16.472Z,1712009716.472 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second) 2024-04-01T22:15:16.517Z,1712009716.517 [CommandExec](IMPORTANT): Sonardyne_Nano.enableBroadcast (bool) 2024-04-01T22:15:16.517Z,1712009716.517 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage (volt) 2024-04-01T22:15:16.550Z,1712009716.550 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgVoltage (volt) 2024-04-01T22:15:16.550Z,1712009716.550 [CommandExec](IMPORTANT): Sonardyne_Nano.component_current (milliampere) 2024-04-01T22:15:16.551Z,1712009716.551 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent (milliampere) 2024-04-01T22:15:16.551Z,1712009716.551 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent (percent) 2024-04-01T22:15:26.327Z,1712009726.327 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.component_voltage 2024-04-01T22:15:26.328Z,1712009726.328 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage 12.092340 V 2024-04-01T22:15:41.042Z,1712009741.042 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.component_avgCurrent 2024-04-01T22:15:41.042Z,1712009741.042 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent 154.139999 mA 2024-04-01T22:15:41.396Z,1712009741.396 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-01T22:14:41.2Z 2024-04-01T22:15:41.396Z,1712009741.396 [Startup:StartupSatComms:A] Stopped 2024-04-01T22:15:41.396Z,1712009741.396 [Startup:StartupSatComms:B] Running Loop=1 2024-04-01T22:15:41.779Z,1712009741.779 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-01T22:15:42.607Z,1712009742.607 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20240401T214632/Courier0010.lzma 2024-04-01T22:15:43.072Z,1712009743.072 [DataOverHttps](INFO): Moved sent file to Logs/20240401T214632/Courier0010.lzma.bak 2024-04-01T22:15:43.072Z,1712009743.072 [DataOverHttps](INFO): SBD MOMSN=19370880 2024-04-01T22:15:58.969Z,1712009758.969 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20240401T221151/Courier0000.lzma 2024-04-01T22:15:59.971Z,1712009759.971 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0000.lzma.bak 2024-04-01T22:15:59.972Z,1712009759.972 [DataOverHttps](INFO): SBD MOMSN=19370883 2024-04-01T22:16:01.390Z,1712009761.390 [DVL_micro](ERROR): DVL uart error: serial timeout 2024-04-01T22:16:01.390Z,1712009761.390 [DVL_micro] Communications Fault, FailCount= 1 2024-04-01T22:16:01.390Z,1712009761.390 [DVL_micro](ERROR): Communications Fault 2024-04-01T22:16:01.390Z,1712009761.390 [DVL_micro](ERROR): Failed to parse: 2024-04-01T22:16:01.471Z,1712009761.471 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-01T22:16:01.826Z,1712009761.826 [DVL_micro](INFO): Powering down 2024-04-01T22:16:02.570Z,1712009762.570 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-01T22:16:02.570Z,1712009762.570 [DVL_micro] No Fault, FailCount= 1 2024-04-01T22:16:15.523Z,1712009775.523 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20240401T214632/Express0011.lzma 2024-04-01T22:16:16.523Z,1712009776.523 [DataOverHttps](INFO): Moved sent file to Logs/20240401T214632/Express0011.lzma.bak 2024-04-01T22:16:16.523Z,1712009776.523 [DataOverHttps](INFO): SBD MOMSN=19370887 2024-04-01T22:16:25.199Z,1712009785.199 [CommandExec](IMPORTANT): got command strobe off 2024-04-01T22:16:25.199Z,1712009785.199 [CommandExec](IMPORTANT): Deactivating strobe 2024-04-01T22:16:31.863Z,1712009791.863 [CommandExec](IMPORTANT): got command show variable nano 2024-04-01T22:16:31.964Z,1712009791.964 [CommandExec](IMPORTANT): NanoDVR.loadAtStartup (bool) 2024-04-01T22:16:31.965Z,1712009791.965 [CommandExec](IMPORTANT): NanoDVR.simulateHardware (bool) 2024-04-01T22:16:31.965Z,1712009791.965 [CommandExec](IMPORTANT): NanoDVR.sampleTime (second) 2024-04-01T22:16:32.070Z,1712009792.070 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool) 2024-04-01T22:16:32.070Z,1712009792.070 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool) 2024-04-01T22:16:32.071Z,1712009792.071 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent) 2024-04-01T22:16:32.071Z,1712009792.071 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent) 2024-04-01T22:16:32.138Z,1712009792.138 [CommandExec](IMPORTANT): NanoDVR.loadControl (none) 2024-04-01T22:16:32.139Z,1712009792.139 [CommandExec](IMPORTANT): NanoDVR.uart (none) 2024-04-01T22:16:32.139Z,1712009792.139 [CommandExec](IMPORTANT): NanoDVR.baud (bit_per_second) 2024-04-01T22:16:32.141Z,1712009792.141 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none) 2024-04-01T22:16:32.141Z,1712009792.141 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none) 2024-04-01T22:16:32.150Z,1712009792.150 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second) 2024-04-01T22:16:32.230Z,1712009792.230 [CommandExec](IMPORTANT): Sonardyne_Nano.enableBroadcast (bool) 2024-04-01T22:16:32.230Z,1712009792.230 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage (volt) 2024-04-01T22:16:32.230Z,1712009792.230 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgVoltage (volt) 2024-04-01T22:16:32.231Z,1712009792.231 [CommandExec](IMPORTANT): Sonardyne_Nano.component_current (milliampere) 2024-04-01T22:16:32.231Z,1712009792.231 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent (milliampere) 2024-04-01T22:16:32.231Z,1712009792.231 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent (percent) 2024-04-01T22:16:32.328Z,1712009792.328 [DataOverHttps](INFO): Sending 1413 bytes from file Logs/20240401T221151/Express0001.lzma 2024-04-01T22:16:33.328Z,1712009793.328 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0001.lzma.bak 2024-04-01T22:16:33.328Z,1712009793.328 [DataOverHttps](INFO): SBD MOMSN=19370896 2024-04-01T22:16:35.299Z,1712009795.299 [Startup:StartupSatComms:B] Stopped 2024-04-01T22:16:35.299Z,1712009795.299 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-04-01T22:16:35.299Z,1712009795.299 [Startup:StartupSatComms] Stopped 2024-04-01T22:16:35.299Z,1712009795.299 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-04-01T22:16:35.300Z,1712009795.300 [Startup](INFO): Completed Startup 2024-04-01T22:16:35.300Z,1712009795.300 [MissionManager](INFO): Startup is completed. 2024-04-01T22:16:35.301Z,1712009795.301 [MissionManager](INFO): Uninitializing Mission Startup 2024-04-01T22:16:35.301Z,1712009795.301 [Startup] Stopped 2024-04-01T22:16:35.301Z,1712009795.301 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-04-01T22:16:35.301Z,1712009795.301 [Startup:A.GoToSurface] Stopped 2024-04-01T22:16:35.301Z,1712009795.301 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-01T22:16:35.696Z,1712009795.696 [MissionManager](IMPORTANT): Started mission Default 2024-04-01T22:16:35.696Z,1712009795.696 [Default] Running Loop=1 2024-04-01T22:16:35.696Z,1712009795.696 [Default](DEBUG): Aggregate::initialize Default 2024-04-01T22:16:35.696Z,1712009795.696 [Default:B.GoToSurface] Running Loop=1 2024-04-01T22:16:35.696Z,1712009795.696 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-01T22:16:35.696Z,1712009795.696 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-01T22:16:35.697Z,1712009795.697 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-01T22:16:35.697Z,1712009795.697 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-01T22:16:35.697Z,1712009795.697 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-01T22:16:35.722Z,1712009795.722 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-01T22:16:35.722Z,1712009795.722 [Default:A.Wait] Running Loop=1 2024-04-01T22:16:35.722Z,1712009795.722 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-04-01T22:16:42.759Z,1712009802.759 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-04-01T22:16:42.760Z,1712009802.760 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2024-04-01T22:16:49.013Z,1712009809.013 [Default:A.Wait](INFO): Done Waiting. 2024-04-01T22:16:49.013Z,1712009809.013 [Default:A.Wait] Stopped 2024-04-01T22:16:49.013Z,1712009809.013 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T22:16:49.417Z,1712009809.417 [Default:CheckIn] Running Loop=1 2024-04-01T22:16:49.417Z,1712009809.417 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T22:16:49.417Z,1712009809.417 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T22:16:49.813Z,1712009809.813 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-04-01T22:17:44.897Z,1712009864.897 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-04-01T22:17:44.898Z,1712009864.898 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2024-04-01T22:17:45.156Z,1712009865.156 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-01T22:18:03.345Z,1712009883.345 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-04-01T22:18:03.346Z,1712009883.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:18:03.381Z,1712009883.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:18:03.756Z,1712009883.756 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:18:03.756Z,1712009883.756 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-04-01T22:18:27.900Z,1712009907.900 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-04-01T22:18:27.900Z,1712009907.900 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2024-04-01T22:18:34.436Z,1712009914.436 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T22:18:34.463Z,1712009914.463 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2024-04-01T22:18:34.471Z,1712009914.471 [BPC1](INFO): Received data from all battery sticks. 2024-04-01T22:19:03.116Z,1712009943.116 [CommandExec](IMPORTANT): got command failComponent 2024-04-01T22:19:03.116Z,1712009943.116 [CommandExec](IMPORTANT): Failed components: 2024-04-01T22:19:03.117Z,1712009943.117 [CommandExec](IMPORTANT): No failed Components. 2024-04-01T22:19:07.031Z,1712009947.031 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-04-01T22:19:07.031Z,1712009947.031 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2024-04-01T22:19:11.521Z,1712009951.521 [CommandExec](IMPORTANT): got command failComponent hardware Sonardyne_Nano 2024-04-01T22:19:11.521Z,1712009951.521 [Sonardyne_Nano] Hardware Fault, FailCount= 1 2024-04-01T22:19:11.521Z,1712009951.521 [Sonardyne_Nano](ERROR): Hardware Fault 2024-04-01T22:19:11.522Z,1712009951.522 [CommandExec](IMPORTANT): Sonardyne_Nano failureMode is Hardware Fault 2024-04-01T22:19:11.626Z,1712009951.626 [Sonardyne_Nano](INFO): Powering down 2024-04-01T22:19:11.765Z,1712009951.765 [CBIT](ERROR): Hardware Fault in component: Sonardyne_Nano 2024-04-01T22:19:12.466Z,1712009952.466 [CBIT](INFO): Clearing failed state for component Sonardyne_Nano 2024-04-01T22:19:12.466Z,1712009952.466 [Sonardyne_Nano] No Fault, FailCount= 1 2024-04-01T22:19:12.824Z,1712009952.824 [Sonardyne_Nano](INFO): Initializing. 2024-04-01T22:19:53.465Z,1712009993.465 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-04-01T22:19:53.466Z,1712009993.466 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2024-04-01T22:20:46.867Z,1712010046.867 [CommandExec](IMPORTANT): got command show variable nano 2024-04-01T22:20:46.885Z,1712010046.885 [CommandExec](IMPORTANT): NanoDVR.loadAtStartup (bool) 2024-04-01T22:20:46.886Z,1712010046.886 [CommandExec](IMPORTANT): NanoDVR.simulateHardware (bool) 2024-04-01T22:20:46.886Z,1712010046.886 [CommandExec](IMPORTANT): NanoDVR.sampleTime (second) 2024-04-01T22:20:46.890Z,1712010046.890 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool) 2024-04-01T22:20:46.890Z,1712010046.890 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool) 2024-04-01T22:20:46.891Z,1712010046.891 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent) 2024-04-01T22:20:46.891Z,1712010046.891 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent) 2024-04-01T22:20:47.046Z,1712010047.046 [CommandExec](IMPORTANT): NanoDVR.loadControl (none) 2024-04-01T22:20:47.046Z,1712010047.046 [CommandExec](IMPORTANT): NanoDVR.uart (none) 2024-04-01T22:20:47.046Z,1712010047.046 [CommandExec](IMPORTANT): NanoDVR.baud (bit_per_second) 2024-04-01T22:20:47.048Z,1712010047.048 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none) 2024-04-01T22:20:47.048Z,1712010047.048 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none) 2024-04-01T22:20:47.049Z,1712010047.049 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second) 2024-04-01T22:20:47.200Z,1712010047.200 [CommandExec](IMPORTANT): Sonardyne_Nano.enableBroadcast (bool) 2024-04-01T22:20:47.200Z,1712010047.200 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage (volt) 2024-04-01T22:20:47.200Z,1712010047.200 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgVoltage (volt) 2024-04-01T22:20:47.201Z,1712010047.201 [CommandExec](IMPORTANT): Sonardyne_Nano.component_current (milliampere) 2024-04-01T22:20:47.201Z,1712010047.201 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent (milliampere) 2024-04-01T22:20:47.230Z,1712010047.230 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent (percent) 2024-04-01T22:20:53.360Z,1712010053.360 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.component_avgCurrent 2024-04-01T22:20:53.360Z,1712010053.360 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent 18.350000 mA 2024-04-01T22:21:01.805Z,1712010061.805 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.component_avgCurrent 2024-04-01T22:21:01.806Z,1712010061.806 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent 11.010000 mA 2024-04-01T22:21:04.335Z,1712010064.335 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-04-01T22:21:04.335Z,1712010064.335 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:21:04.345Z,1712010064.345 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:21:04.748Z,1712010064.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:21:04.748Z,1712010064.748 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-04-01T22:21:31.716Z,1712010091.716 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.component_avgCurrent 2024-04-01T22:21:31.717Z,1712010091.717 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent 150.470001 mA 2024-04-01T22:21:41.213Z,1712010101.213 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2024-04-01T22:21:41.213Z,1712010101.213 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2024-04-01T22:21:49.584Z,1712010109.584 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T22:16:49.4Z 2024-04-01T22:21:49.584Z,1712010109.584 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T22:21:49.584Z,1712010109.584 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T22:21:49.990Z,1712010109.990 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-01T22:21:57.009Z,1712010117.009 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240401T221151/Courier0004.lzma 2024-04-01T22:21:58.011Z,1712010118.011 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0004.lzma.bak 2024-04-01T22:21:58.011Z,1712010118.011 [DataOverHttps](INFO): SBD MOMSN=19370976 2024-04-01T22:22:13.702Z,1712010133.702 [DataOverHttps](INFO): Sending 797 bytes from file Logs/20240401T221151/Express0005.lzma 2024-04-01T22:22:14.703Z,1712010134.703 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0005.lzma.bak 2024-04-01T22:22:14.704Z,1712010134.704 [DataOverHttps](INFO): SBD MOMSN=19370978 2024-04-01T22:22:16.672Z,1712010136.672 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T22:22:16.672Z,1712010136.672 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T22:22:16.672Z,1712010136.672 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T22:22:42.904Z,1712010162.904 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T22:22:42.904Z,1712010162.904 [NAL9602] Data Fault, FailCount= 1 2024-04-01T22:22:42.904Z,1712010162.904 [NAL9602](ERROR): Data Fault 2024-04-01T22:22:42.952Z,1712010162.952 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T22:22:43.307Z,1712010163.307 [NAL9602](INFO): Powering down 2024-04-01T22:22:44.135Z,1712010164.135 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T22:22:44.135Z,1712010164.135 [NAL9602] No Fault, FailCount= 1 2024-04-01T22:23:13.608Z,1712010193.608 [NAL9602](INFO): Powering up NAL9602 2024-04-01T22:23:24.512Z,1712010204.512 [NAL9602](INFO): NAL9602 initialized 2024-04-01T22:24:05.338Z,1712010245.338 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-04-01T22:24:05.338Z,1712010245.338 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:24:05.383Z,1712010245.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:24:05.798Z,1712010245.798 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:24:05.798Z,1712010245.798 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-04-01T22:27:06.433Z,1712010426.433 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-04-01T22:27:06.433Z,1712010426.433 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:27:06.469Z,1712010426.469 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:27:06.808Z,1712010426.808 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:27:06.808Z,1712010426.808 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-04-01T22:27:17.332Z,1712010437.332 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T22:27:17.332Z,1712010437.332 [Default:CheckIn:C.Wait] Stopped 2024-04-01T22:27:17.332Z,1712010437.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T22:27:17.332Z,1712010437.332 [Default:CheckIn:D] Running Loop=1 2024-04-01T22:27:17.731Z,1712010437.731 [Default:CheckIn:D] Stopped 2024-04-01T22:27:17.731Z,1712010437.731 [Default:CheckIn:E] Running Loop=1 2024-04-01T22:27:18.124Z,1712010438.124 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.700584 min 2024-04-01T22:27:18.124Z,1712010438.124 [Default:CheckIn:E] Stopped 2024-04-01T22:27:18.124Z,1712010438.124 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T22:27:18.124Z,1712010438.124 [Default:CheckIn] Stopped 2024-04-01T22:27:18.125Z,1712010438.125 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T22:27:18.125Z,1712010438.125 [Default:CheckIn](INFO): Running loop #2 2024-04-01T22:27:18.125Z,1712010438.125 [Default:CheckIn] Running Loop=2 2024-04-01T22:27:18.125Z,1712010438.125 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T22:27:18.125Z,1712010438.125 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T22:30:07.406Z,1712010607.406 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-04-01T22:30:07.407Z,1712010607.407 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:30:07.417Z,1712010607.417 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:30:07.819Z,1712010607.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:30:07.820Z,1712010607.820 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-04-01T22:32:18.336Z,1712010738.336 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T22:27:18.1Z 2024-04-01T22:32:18.336Z,1712010738.336 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T22:32:18.336Z,1712010738.336 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T22:32:25.905Z,1712010745.905 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240401T221151/Courier0007.lzma 2024-04-01T22:32:26.908Z,1712010746.908 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0007.lzma.bak 2024-04-01T22:32:26.908Z,1712010746.908 [DataOverHttps](INFO): SBD MOMSN=19371059 2024-04-01T22:32:48.421Z,1712010768.421 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20240401T221151/Express0008.lzma 2024-04-01T22:32:49.423Z,1712010769.423 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0008.lzma.bak 2024-04-01T22:32:49.424Z,1712010769.424 [DataOverHttps](INFO): SBD MOMSN=19371061 2024-04-01T22:32:51.554Z,1712010771.554 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T22:32:51.554Z,1712010771.554 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T22:32:51.554Z,1712010771.554 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T22:33:08.503Z,1712010788.503 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-04-01T22:33:08.503Z,1712010788.503 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:33:08.514Z,1712010788.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:33:08.920Z,1712010788.920 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:33:08.920Z,1712010788.920 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-04-01T22:33:27.454Z,1712010807.454 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T22:33:27.454Z,1712010807.454 [NAL9602] Data Fault, FailCount= 2 2024-04-01T22:33:27.454Z,1712010807.454 [NAL9602](ERROR): Data Fault 2024-04-01T22:33:27.474Z,1712010807.474 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T22:33:27.864Z,1712010807.864 [NAL9602](INFO): Powering down 2024-04-01T22:33:28.688Z,1712010808.688 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T22:33:28.688Z,1712010808.688 [NAL9602] No Fault, FailCount= 2 2024-04-01T22:33:58.162Z,1712010838.162 [NAL9602](INFO): Powering up NAL9602 2024-04-01T22:34:09.082Z,1712010849.082 [NAL9602](INFO): NAL9602 initialized 2024-04-01T22:36:09.519Z,1712010969.519 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-04-01T22:36:09.519Z,1712010969.519 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:36:09.530Z,1712010969.530 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:36:09.936Z,1712010969.936 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:36:09.936Z,1712010969.936 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-04-01T22:37:52.127Z,1712011072.127 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T22:37:52.127Z,1712011072.127 [Default:CheckIn:C.Wait] Stopped 2024-04-01T22:37:52.128Z,1712011072.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T22:37:52.128Z,1712011072.128 [Default:CheckIn:D] Running Loop=1 2024-04-01T22:37:52.516Z,1712011072.516 [Default:CheckIn:D] Stopped 2024-04-01T22:37:52.516Z,1712011072.516 [Default:CheckIn:E] Running Loop=1 2024-04-01T22:37:52.936Z,1712011072.936 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.280332 min 2024-04-01T22:37:52.936Z,1712011072.936 [Default:CheckIn:E] Stopped 2024-04-01T22:37:52.936Z,1712011072.936 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T22:37:52.936Z,1712011072.936 [Default:CheckIn] Stopped 2024-04-01T22:37:52.936Z,1712011072.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T22:37:52.937Z,1712011072.937 [Default:CheckIn](INFO): Running loop #3 2024-04-01T22:37:52.937Z,1712011072.937 [Default:CheckIn] Running Loop=3 2024-04-01T22:37:52.937Z,1712011072.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T22:37:52.937Z,1712011072.937 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T22:39:10.481Z,1712011150.481 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2024-04-01T22:39:10.481Z,1712011150.481 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:39:10.492Z,1712011150.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:39:10.901Z,1712011150.901 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:39:10.901Z,1712011150.901 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2024-04-01T22:42:11.479Z,1712011331.479 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2024-04-01T22:42:11.479Z,1712011331.479 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:42:11.514Z,1712011331.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:42:11.887Z,1712011331.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:42:11.887Z,1712011331.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2024-04-01T22:42:53.092Z,1712011373.092 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T22:37:52.9Z 2024-04-01T22:42:53.092Z,1712011373.092 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T22:42:53.092Z,1712011373.092 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T22:43:00.537Z,1712011380.537 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240401T221151/Courier0010.lzma 2024-04-01T22:43:01.539Z,1712011381.539 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0010.lzma.bak 2024-04-01T22:43:01.540Z,1712011381.540 [DataOverHttps](INFO): SBD MOMSN=19371083 2024-04-01T22:43:17.301Z,1712011397.301 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240401T221151/Express0011.lzma 2024-04-01T22:43:18.303Z,1712011398.303 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0011.lzma.bak 2024-04-01T22:43:18.304Z,1712011398.304 [DataOverHttps](INFO): SBD MOMSN=19371085 2024-04-01T22:43:20.212Z,1712011400.212 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T22:43:20.212Z,1712011400.212 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T22:43:20.212Z,1712011400.212 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T22:44:10.275Z,1712011450.275 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T22:44:10.276Z,1712011450.276 [NAL9602] Data Fault, FailCount= 3 2024-04-01T22:44:10.276Z,1712011450.276 [NAL9602](ERROR): Data Fault 2024-04-01T22:44:10.294Z,1712011450.294 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T22:44:10.676Z,1712011450.676 [NAL9602](INFO): Powering down 2024-04-01T22:44:11.504Z,1712011451.504 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T22:44:11.504Z,1712011451.504 [NAL9602] No Fault, FailCount= 3 2024-04-01T22:44:40.990Z,1712011480.990 [NAL9602](INFO): Powering up NAL9602 2024-04-01T22:44:51.893Z,1712011491.893 [NAL9602](INFO): NAL9602 initialized 2024-04-01T22:45:12.513Z,1712011512.513 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2024-04-01T22:45:12.513Z,1712011512.513 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:45:12.523Z,1712011512.523 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:45:12.950Z,1712011512.950 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:45:12.951Z,1712011512.951 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2024-04-01T22:48:13.503Z,1712011693.503 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2024-04-01T22:48:13.504Z,1712011693.504 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:48:13.514Z,1712011693.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:48:13.918Z,1712011693.918 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:48:13.918Z,1712011693.918 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2024-04-01T22:48:20.796Z,1712011700.796 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T22:48:20.796Z,1712011700.796 [Default:CheckIn:C.Wait] Stopped 2024-04-01T22:48:20.796Z,1712011700.796 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T22:48:20.796Z,1712011700.796 [Default:CheckIn:D] Running Loop=1 2024-04-01T22:48:21.213Z,1712011701.213 [Default:CheckIn:D] Stopped 2024-04-01T22:48:21.213Z,1712011701.213 [Default:CheckIn:E] Running Loop=1 2024-04-01T22:48:21.604Z,1712011701.604 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.758608 min 2024-04-01T22:48:21.604Z,1712011701.604 [Default:CheckIn:E] Stopped 2024-04-01T22:48:21.604Z,1712011701.604 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T22:48:21.604Z,1712011701.604 [Default:CheckIn] Stopped 2024-04-01T22:48:21.604Z,1712011701.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T22:48:21.605Z,1712011701.605 [Default:CheckIn](INFO): Running loop #4 2024-04-01T22:48:21.605Z,1712011701.605 [Default:CheckIn] Running Loop=4 2024-04-01T22:48:21.605Z,1712011701.605 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T22:48:21.605Z,1712011701.605 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T22:51:14.493Z,1712011874.493 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2024-04-01T22:51:14.493Z,1712011874.493 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:51:14.504Z,1712011874.504 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:51:14.917Z,1712011874.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:51:14.917Z,1712011874.917 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2024-04-01T22:53:21.792Z,1712012001.792 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T22:48:21.6Z 2024-04-01T22:53:21.792Z,1712012001.792 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T22:53:21.792Z,1712012001.792 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T22:53:28.613Z,1712012008.613 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240401T221151/Courier0013.lzma 2024-04-01T22:53:29.616Z,1712012009.616 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0013.lzma.bak 2024-04-01T22:53:29.617Z,1712012009.617 [DataOverHttps](INFO): SBD MOMSN=19371101 2024-04-01T22:53:46.129Z,1712012026.129 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240401T221151/Express0014.lzma 2024-04-01T22:53:47.132Z,1712012027.132 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0014.lzma.bak 2024-04-01T22:53:47.132Z,1712012027.132 [DataOverHttps](INFO): SBD MOMSN=19371103 2024-04-01T22:53:49.704Z,1712012029.704 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T22:53:49.704Z,1712012029.704 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T22:53:49.704Z,1712012029.704 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T22:54:15.583Z,1712012055.583 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2024-04-01T22:54:15.583Z,1712012055.583 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:54:15.594Z,1712012055.594 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:54:15.972Z,1712012055.972 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:54:15.972Z,1712012055.972 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2024-04-01T22:54:54.736Z,1712012094.736 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T22:54:54.736Z,1712012094.736 [NAL9602] Data Fault, FailCount= 4 2024-04-01T22:54:54.736Z,1712012094.736 [NAL9602](ERROR): Data Fault 2024-04-01T22:54:54.783Z,1712012094.783 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T22:54:55.143Z,1712012095.143 [NAL9602](INFO): Powering down 2024-04-01T22:54:55.969Z,1712012095.969 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T22:54:55.969Z,1712012095.969 [NAL9602] No Fault, FailCount= 4 2024-04-01T22:55:25.440Z,1712012125.440 [NAL9602](INFO): Powering up NAL9602 2024-04-01T22:55:36.352Z,1712012136.352 [NAL9602](INFO): NAL9602 initialized 2024-04-01T22:57:16.571Z,1712012236.571 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2024-04-01T22:57:16.571Z,1712012236.571 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T22:57:16.583Z,1712012236.583 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T22:57:16.992Z,1712012236.992 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T22:57:16.992Z,1712012236.992 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2024-04-01T22:58:50.305Z,1712012330.305 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T22:58:50.305Z,1712012330.305 [Default:CheckIn:C.Wait] Stopped 2024-04-01T22:58:50.305Z,1712012330.305 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T22:58:50.305Z,1712012330.305 [Default:CheckIn:D] Running Loop=1 2024-04-01T22:58:50.684Z,1712012330.684 [Default:CheckIn:D] Stopped 2024-04-01T22:58:50.684Z,1712012330.684 [Default:CheckIn:E] Running Loop=1 2024-04-01T22:58:51.100Z,1712012331.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.249797 min 2024-04-01T22:58:51.100Z,1712012331.100 [Default:CheckIn:E] Stopped 2024-04-01T22:58:51.100Z,1712012331.100 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T22:58:51.100Z,1712012331.100 [Default:CheckIn] Stopped 2024-04-01T22:58:51.101Z,1712012331.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T22:58:51.101Z,1712012331.101 [Default:CheckIn](INFO): Running loop #5 2024-04-01T22:58:51.101Z,1712012331.101 [Default:CheckIn] Running Loop=5 2024-04-01T22:58:51.101Z,1712012331.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T22:58:51.101Z,1712012331.101 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T23:00:17.571Z,1712012417.571 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2024-04-01T23:00:17.571Z,1712012417.571 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:00:17.582Z,1712012417.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:00:17.999Z,1712012417.999 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:00:17.999Z,1712012417.999 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2024-04-01T23:03:18.602Z,1712012598.602 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2024-04-01T23:03:18.602Z,1712012598.602 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:03:18.612Z,1712012598.612 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:03:19.006Z,1712012599.006 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:03:19.006Z,1712012599.006 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2024-04-01T23:03:51.289Z,1712012631.289 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T22:58:51.1Z 2024-04-01T23:03:51.289Z,1712012631.289 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T23:03:51.289Z,1712012631.289 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T23:03:58.261Z,1712012638.261 [DataOverHttps](INFO): Sending 37 bytes from file Logs/20240401T221151/Courier0016.lzma 2024-04-01T23:03:59.263Z,1712012639.263 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0016.lzma.bak 2024-04-01T23:03:59.263Z,1712012639.263 [DataOverHttps](INFO): SBD MOMSN=19371119 2024-04-01T23:04:15.101Z,1712012655.101 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240401T221151/Express0017.lzma 2024-04-01T23:04:16.103Z,1712012656.103 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0017.lzma.bak 2024-04-01T23:04:16.104Z,1712012656.104 [DataOverHttps](INFO): SBD MOMSN=19371121 2024-04-01T23:04:17.968Z,1712012657.968 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T23:04:17.968Z,1712012657.968 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T23:04:17.968Z,1712012657.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T23:05:39.162Z,1712012739.162 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T23:05:39.162Z,1712012739.162 [NAL9602] Data Fault, FailCount= 5 2024-04-01T23:05:39.162Z,1712012739.162 [NAL9602](ERROR): Data Fault 2024-04-01T23:05:39.179Z,1712012739.179 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T23:05:39.180Z,1712012739.180 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-04-01T23:05:39.552Z,1712012739.552 [NAL9602](INFO): Powering down 2024-04-01T23:05:39.583Z,1712012739.583 [CBIT](INFO): Critical error at 20240401T230539 2024-04-01T23:06:19.554Z,1712012779.554 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2024-04-01T23:06:19.554Z,1712012779.554 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:06:19.564Z,1712012779.564 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:06:19.974Z,1712012779.974 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:06:19.974Z,1712012779.974 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2024-04-01T23:07:39.560Z,1712012859.560 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T23:07:39.560Z,1712012859.560 [NAL9602] No Fault, FailCount= 5 2024-04-01T23:07:39.968Z,1712012859.968 [NAL9602](INFO): Powering up NAL9602 2024-04-01T23:07:50.856Z,1712012870.856 [NAL9602](INFO): NAL9602 initialized 2024-04-01T23:09:18.532Z,1712012958.532 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T23:09:18.532Z,1712012958.532 [Default:CheckIn:C.Wait] Stopped 2024-04-01T23:09:18.532Z,1712012958.532 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T23:09:18.532Z,1712012958.532 [Default:CheckIn:D] Running Loop=1 2024-04-01T23:09:18.940Z,1712012958.940 [Default:CheckIn:D] Stopped 2024-04-01T23:09:18.940Z,1712012958.940 [Default:CheckIn:E] Running Loop=1 2024-04-01T23:09:19.364Z,1712012959.364 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.720740 min 2024-04-01T23:09:19.364Z,1712012959.364 [Default:CheckIn:E] Stopped 2024-04-01T23:09:19.364Z,1712012959.364 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T23:09:19.365Z,1712012959.365 [Default:CheckIn] Stopped 2024-04-01T23:09:19.365Z,1712012959.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T23:09:19.365Z,1712012959.365 [Default:CheckIn](INFO): Running loop #6 2024-04-01T23:09:19.365Z,1712012959.365 [Default:CheckIn] Running Loop=6 2024-04-01T23:09:19.365Z,1712012959.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T23:09:19.365Z,1712012959.365 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T23:09:20.571Z,1712012960.571 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2024-04-01T23:09:20.571Z,1712012960.571 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:09:20.582Z,1712012960.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:09:20.989Z,1712012960.989 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:09:20.989Z,1712012960.989 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2024-04-01T23:12:21.571Z,1712013141.571 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2024-04-01T23:12:21.571Z,1712013141.571 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:12:21.582Z,1712013141.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:12:21.960Z,1712013141.960 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:12:21.960Z,1712013141.960 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2024-04-01T23:14:19.545Z,1712013259.545 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T23:09:19.4Z 2024-04-01T23:14:19.545Z,1712013259.545 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T23:14:19.545Z,1712013259.545 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T23:14:26.321Z,1712013266.321 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20240401T221151/Courier0019.lzma 2024-04-01T23:14:27.791Z,1712013267.791 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0019.lzma.bak 2024-04-01T23:14:27.791Z,1712013267.791 [DataOverHttps](INFO): SBD MOMSN=19371137 2024-04-01T23:14:43.081Z,1712013283.081 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240401T221151/Express0020.lzma 2024-04-01T23:14:44.083Z,1712013284.083 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0020.lzma.bak 2024-04-01T23:14:44.084Z,1712013284.084 [DataOverHttps](INFO): SBD MOMSN=19371140 2024-04-01T23:14:46.191Z,1712013286.191 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T23:14:46.191Z,1712013286.191 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T23:14:46.191Z,1712013286.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T23:15:22.555Z,1712013322.555 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2024-04-01T23:15:22.555Z,1712013322.555 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:15:22.566Z,1712013322.566 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:15:22.964Z,1712013322.964 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:15:22.964Z,1712013322.964 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2024-04-01T23:17:52.012Z,1712013472.012 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T23:17:52.012Z,1712013472.012 [NAL9602] Data Fault, FailCount= 1 2024-04-01T23:17:52.012Z,1712013472.012 [NAL9602](ERROR): Data Fault 2024-04-01T23:17:52.030Z,1712013472.030 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T23:17:52.412Z,1712013472.412 [NAL9602](INFO): Powering down 2024-04-01T23:17:53.277Z,1712013473.277 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T23:17:53.277Z,1712013473.277 [NAL9602] No Fault, FailCount= 1 2024-04-01T23:18:22.716Z,1712013502.716 [NAL9602](INFO): Powering up NAL9602 2024-04-01T23:18:23.550Z,1712013503.550 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2024-04-01T23:18:23.550Z,1712013503.550 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:18:23.561Z,1712013503.561 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:18:23.962Z,1712013503.962 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:18:23.962Z,1712013503.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2024-04-01T23:18:33.624Z,1712013513.624 [NAL9602](INFO): NAL9602 initialized 2024-04-01T23:19:40.679Z,1712013580.679 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:19:46.780Z,1712013586.780 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T23:19:46.780Z,1712013586.780 [Default:CheckIn:C.Wait] Stopped 2024-04-01T23:19:46.780Z,1712013586.780 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T23:19:46.780Z,1712013586.780 [Default:CheckIn:D] Running Loop=1 2024-04-01T23:19:47.172Z,1712013587.172 [Default:CheckIn:D] Stopped 2024-04-01T23:19:47.172Z,1712013587.172 [Default:CheckIn:E] Running Loop=1 2024-04-01T23:19:47.569Z,1712013587.569 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.191268 min 2024-04-01T23:19:47.569Z,1712013587.569 [Default:CheckIn:E] Stopped 2024-04-01T23:19:47.570Z,1712013587.570 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T23:19:47.570Z,1712013587.570 [Default:CheckIn] Stopped 2024-04-01T23:19:47.570Z,1712013587.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T23:19:47.570Z,1712013587.570 [Default:CheckIn](INFO): Running loop #7 2024-04-01T23:19:47.570Z,1712013587.570 [Default:CheckIn] Running Loop=7 2024-04-01T23:19:47.570Z,1712013587.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T23:19:47.570Z,1712013587.570 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T23:19:54.819Z,1712013594.819 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:20:08.556Z,1712013608.556 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:20:22.699Z,1712013622.699 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:20:36.431Z,1712013636.431 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:20:50.571Z,1712013650.571 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:21:04.308Z,1712013664.308 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:21:24.587Z,1712013684.587 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2024-04-01T23:21:24.587Z,1712013684.587 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:21:24.598Z,1712013684.598 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:21:24.971Z,1712013684.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:21:24.971Z,1712013684.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2024-04-01T23:24:25.527Z,1712013865.527 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2024-04-01T23:24:25.527Z,1712013865.527 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:24:25.546Z,1712013865.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:24:25.959Z,1712013865.959 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:24:25.959Z,1712013865.959 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2024-04-01T23:24:47.737Z,1712013887.737 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T23:19:47.6Z 2024-04-01T23:24:47.737Z,1712013887.737 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T23:24:47.737Z,1712013887.737 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T23:24:54.997Z,1712013894.997 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240401T221151/Courier0022.lzma 2024-04-01T23:24:56.000Z,1712013896.000 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0022.lzma.bak 2024-04-01T23:24:56.001Z,1712013896.001 [DataOverHttps](INFO): SBD MOMSN=19371156 2024-04-01T23:25:11.889Z,1712013911.889 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240401T221151/Express0023.lzma 2024-04-01T23:25:12.891Z,1712013912.891 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0023.lzma.bak 2024-04-01T23:25:12.892Z,1712013912.892 [DataOverHttps](INFO): SBD MOMSN=19371158 2024-04-01T23:25:14.836Z,1712013914.836 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T23:25:14.836Z,1712013914.836 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T23:25:14.836Z,1712013914.836 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T23:26:13.803Z,1712013973.803 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-01T23:26:13.810Z,1712013973.810 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2024-04-01T23:26:13.813Z,1712013973.813 [BPC1](INFO): Received data from all battery sticks. 2024-04-01T23:27:26.551Z,1712014046.551 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25 2024-04-01T23:27:26.551Z,1712014046.551 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:27:26.562Z,1712014046.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:27:26.969Z,1712014046.969 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:27:26.969Z,1712014046.969 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25 2024-04-01T23:28:36.433Z,1712014116.433 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T23:28:36.433Z,1712014116.433 [NAL9602] Data Fault, FailCount= 2 2024-04-01T23:28:36.433Z,1712014116.433 [NAL9602](ERROR): Data Fault 2024-04-01T23:28:36.451Z,1712014116.451 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T23:28:36.816Z,1712014116.816 [NAL9602](INFO): Powering down 2024-04-01T23:28:37.640Z,1712014117.640 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T23:28:37.640Z,1712014117.640 [NAL9602] No Fault, FailCount= 2 2024-04-01T23:29:07.120Z,1712014147.120 [NAL9602](INFO): Powering up NAL9602 2024-04-01T23:29:18.028Z,1712014158.028 [NAL9602](INFO): NAL9602 initialized 2024-04-01T23:30:15.428Z,1712014215.428 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T23:30:15.428Z,1712014215.428 [Default:CheckIn:C.Wait] Stopped 2024-04-01T23:30:15.428Z,1712014215.428 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T23:30:15.428Z,1712014215.428 [Default:CheckIn:D] Running Loop=1 2024-04-01T23:30:15.837Z,1712014215.837 [Default:CheckIn:D] Stopped 2024-04-01T23:30:15.837Z,1712014215.837 [Default:CheckIn:E] Running Loop=1 2024-04-01T23:30:16.212Z,1712014216.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.669019 min 2024-04-01T23:30:16.212Z,1712014216.212 [Default:CheckIn:E] Stopped 2024-04-01T23:30:16.212Z,1712014216.212 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T23:30:16.212Z,1712014216.212 [Default:CheckIn] Stopped 2024-04-01T23:30:16.212Z,1712014216.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T23:30:16.212Z,1712014216.212 [Default:CheckIn](INFO): Running loop #8 2024-04-01T23:30:16.212Z,1712014216.212 [Default:CheckIn] Running Loop=8 2024-04-01T23:30:16.213Z,1712014216.213 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T23:30:16.213Z,1712014216.213 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T23:30:27.543Z,1712014227.543 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26 2024-04-01T23:30:27.543Z,1712014227.543 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:30:27.554Z,1712014227.554 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:30:27.952Z,1712014227.952 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:30:27.952Z,1712014227.952 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26 2024-04-01T23:33:28.531Z,1712014408.531 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27 2024-04-01T23:33:28.531Z,1712014408.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:33:28.542Z,1712014408.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:33:28.947Z,1712014408.947 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:33:28.947Z,1712014408.947 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27 2024-04-01T23:35:16.382Z,1712014516.382 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T23:30:16.2Z 2024-04-01T23:35:16.382Z,1712014516.382 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T23:35:16.382Z,1712014516.382 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T23:35:23.317Z,1712014523.317 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20240401T221151/Courier0025.lzma 2024-04-01T23:35:24.319Z,1712014524.319 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0025.lzma.bak 2024-04-01T23:35:24.320Z,1712014524.320 [DataOverHttps](INFO): SBD MOMSN=19371174 2024-04-01T23:35:40.117Z,1712014540.117 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20240401T221151/Express0026.lzma 2024-04-01T23:35:41.121Z,1712014541.121 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0026.lzma.bak 2024-04-01T23:35:41.121Z,1712014541.121 [DataOverHttps](INFO): SBD MOMSN=19371176 2024-04-01T23:35:43.066Z,1712014543.066 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T23:35:43.066Z,1712014543.066 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T23:35:43.067Z,1712014543.067 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T23:36:29.511Z,1712014589.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28 2024-04-01T23:36:29.511Z,1712014589.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:36:29.522Z,1712014589.522 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:36:29.925Z,1712014589.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:36:29.925Z,1712014589.925 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28 2024-04-01T23:39:20.811Z,1712014760.811 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T23:39:20.811Z,1712014760.811 [NAL9602] Data Fault, FailCount= 3 2024-04-01T23:39:20.812Z,1712014760.812 [NAL9602](ERROR): Data Fault 2024-04-01T23:39:20.879Z,1712014760.879 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T23:39:21.215Z,1712014761.215 [NAL9602](INFO): Powering down 2024-04-01T23:39:22.051Z,1712014762.051 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T23:39:22.051Z,1712014762.051 [NAL9602] No Fault, FailCount= 3 2024-04-01T23:39:30.518Z,1712014770.518 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29 2024-04-01T23:39:30.518Z,1712014770.518 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:39:30.553Z,1712014770.553 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:39:30.932Z,1712014770.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:39:30.932Z,1712014770.932 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29 2024-04-01T23:39:51.520Z,1712014791.520 [NAL9602](INFO): Powering up NAL9602 2024-04-01T23:40:02.432Z,1712014802.432 [NAL9602](INFO): NAL9602 initialized 2024-04-01T23:40:43.654Z,1712014843.654 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T23:40:43.654Z,1712014843.654 [Default:CheckIn:C.Wait] Stopped 2024-04-01T23:40:43.654Z,1712014843.654 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T23:40:43.655Z,1712014843.655 [Default:CheckIn:D] Running Loop=1 2024-04-01T23:40:44.068Z,1712014844.068 [Default:CheckIn:D] Stopped 2024-04-01T23:40:44.068Z,1712014844.068 [Default:CheckIn:E] Running Loop=1 2024-04-01T23:40:44.479Z,1712014844.479 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.139535 min 2024-04-01T23:40:44.479Z,1712014844.479 [Default:CheckIn:E] Stopped 2024-04-01T23:40:44.479Z,1712014844.479 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T23:40:44.479Z,1712014844.479 [Default:CheckIn] Stopped 2024-04-01T23:40:44.479Z,1712014844.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T23:40:44.480Z,1712014844.480 [Default:CheckIn](INFO): Running loop #9 2024-04-01T23:40:44.480Z,1712014844.480 [Default:CheckIn] Running Loop=9 2024-04-01T23:40:44.480Z,1712014844.480 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T23:40:44.480Z,1712014844.480 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T23:41:53.126Z,1712014913.126 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2024-04-01T23:42:31.561Z,1712014951.561 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30 2024-04-01T23:42:31.561Z,1712014951.561 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:42:31.572Z,1712014951.572 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:42:32.025Z,1712014952.025 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:42:32.025Z,1712014952.025 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30 2024-04-01T23:45:32.554Z,1712015132.554 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31 2024-04-01T23:45:32.554Z,1712015132.554 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:45:32.566Z,1712015132.566 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:45:32.948Z,1712015132.948 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:45:32.948Z,1712015132.948 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31 2024-04-01T23:45:44.651Z,1712015144.651 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T23:40:44.5Z 2024-04-01T23:45:44.651Z,1712015144.651 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T23:45:44.651Z,1712015144.651 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T23:45:52.189Z,1712015152.189 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240401T221151/Courier0028.lzma 2024-04-01T23:45:53.191Z,1712015153.191 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0028.lzma.bak 2024-04-01T23:45:53.192Z,1712015153.192 [DataOverHttps](INFO): SBD MOMSN=19371192 2024-04-01T23:46:08.849Z,1712015168.849 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240401T221151/Express0029.lzma 2024-04-01T23:46:09.851Z,1712015169.851 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0029.lzma.bak 2024-04-01T23:46:09.852Z,1712015169.852 [DataOverHttps](INFO): SBD MOMSN=19371194 2024-04-01T23:46:11.722Z,1712015171.722 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T23:46:11.722Z,1712015171.722 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T23:46:11.722Z,1712015171.722 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T23:48:33.544Z,1712015313.544 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32 2024-04-01T23:48:33.544Z,1712015313.544 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:48:33.555Z,1712015313.555 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:48:33.952Z,1712015313.952 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:48:33.952Z,1712015313.952 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32 2024-04-01T23:50:05.216Z,1712015405.216 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-01T23:50:05.216Z,1712015405.216 [NAL9602] Data Fault, FailCount= 4 2024-04-01T23:50:05.216Z,1712015405.216 [NAL9602](ERROR): Data Fault 2024-04-01T23:50:05.233Z,1712015405.233 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-01T23:50:05.620Z,1712015405.620 [NAL9602](INFO): Powering down 2024-04-01T23:50:06.444Z,1712015406.444 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-01T23:50:06.444Z,1712015406.444 [NAL9602] No Fault, FailCount= 4 2024-04-01T23:50:35.943Z,1712015435.943 [NAL9602](INFO): Powering up NAL9602 2024-04-01T23:50:46.839Z,1712015446.839 [NAL9602](INFO): NAL9602 initialized 2024-04-01T23:51:12.308Z,1712015472.308 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-01T23:51:12.308Z,1712015472.308 [Default:CheckIn:C.Wait] Stopped 2024-04-01T23:51:12.308Z,1712015472.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-01T23:51:12.308Z,1712015472.308 [Default:CheckIn:D] Running Loop=1 2024-04-01T23:51:12.715Z,1712015472.715 [Default:CheckIn:D] Stopped 2024-04-01T23:51:12.716Z,1712015472.716 [Default:CheckIn:E] Running Loop=1 2024-04-01T23:51:13.106Z,1712015473.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.616992 min 2024-04-01T23:51:13.106Z,1712015473.106 [Default:CheckIn:E] Stopped 2024-04-01T23:51:13.106Z,1712015473.106 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-01T23:51:13.106Z,1712015473.106 [Default:CheckIn] Stopped 2024-04-01T23:51:13.106Z,1712015473.106 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-01T23:51:13.107Z,1712015473.107 [Default:CheckIn](INFO): Running loop #10 2024-04-01T23:51:13.107Z,1712015473.107 [Default:CheckIn] Running Loop=10 2024-04-01T23:51:13.107Z,1712015473.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-01T23:51:13.107Z,1712015473.107 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-01T23:51:34.534Z,1712015494.534 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33 2024-04-01T23:51:34.534Z,1712015494.534 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:51:34.573Z,1712015494.573 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:51:34.947Z,1712015494.947 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:51:34.947Z,1712015494.947 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33 2024-04-01T23:54:35.503Z,1712015675.503 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34 2024-04-01T23:54:35.503Z,1712015675.503 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:54:35.514Z,1712015675.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:54:35.918Z,1712015675.918 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:54:35.918Z,1712015675.918 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34 2024-04-01T23:56:13.273Z,1712015773.273 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-01T23:51:13.1Z 2024-04-01T23:56:13.273Z,1712015773.273 [Default:CheckIn:Read_GPS] Stopped 2024-04-01T23:56:13.273Z,1712015773.273 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-01T23:56:20.085Z,1712015780.085 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240401T221151/Courier0031.lzma 2024-04-01T23:56:21.087Z,1712015781.087 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0031.lzma.bak 2024-04-01T23:56:21.088Z,1712015781.088 [DataOverHttps](INFO): SBD MOMSN=19371210 2024-04-01T23:56:38.362Z,1712015798.362 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240401T221151/Express0032.lzma 2024-04-01T23:56:39.363Z,1712015799.363 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0032.lzma.bak 2024-04-01T23:56:39.364Z,1712015799.364 [DataOverHttps](INFO): SBD MOMSN=19371212 2024-04-01T23:56:41.190Z,1712015801.190 [Default:CheckIn:Read_Iridium] Stopped 2024-04-01T23:56:41.190Z,1712015801.190 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-01T23:56:41.190Z,1712015801.190 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-01T23:57:36.531Z,1712015856.531 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35 2024-04-01T23:57:36.532Z,1712015856.532 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-01T23:57:36.542Z,1712015856.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-01T23:57:36.964Z,1712015856.964 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-01T23:57:36.964Z,1712015856.964 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35 2024-04-02T00:00:37.550Z,1712016037.550 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36 2024-04-02T00:00:37.550Z,1712016037.550 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:00:37.566Z,1712016037.566 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:00:37.932Z,1712016037.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:00:37.932Z,1712016037.932 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36 2024-04-02T00:00:48.824Z,1712016048.824 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-02T00:00:48.824Z,1712016048.824 [NAL9602] Data Fault, FailCount= 5 2024-04-02T00:00:48.824Z,1712016048.824 [NAL9602](ERROR): Data Fault 2024-04-02T00:00:48.876Z,1712016048.876 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-02T00:00:48.876Z,1712016048.876 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-04-02T00:00:49.236Z,1712016049.236 [NAL9602](INFO): Powering down 2024-04-02T00:00:49.269Z,1712016049.269 [CBIT](INFO): Critical error at 20240402T000048 2024-04-02T00:01:41.752Z,1712016101.752 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-02T00:01:41.752Z,1712016101.752 [Default:CheckIn:C.Wait] Stopped 2024-04-02T00:01:41.752Z,1712016101.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-02T00:01:41.752Z,1712016101.752 [Default:CheckIn:D] Running Loop=1 2024-04-02T00:01:42.176Z,1712016102.176 [Default:CheckIn:D] Stopped 2024-04-02T00:01:42.176Z,1712016102.176 [Default:CheckIn:E] Running Loop=1 2024-04-02T00:01:42.563Z,1712016102.563 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.108000 min 2024-04-02T00:01:42.563Z,1712016102.563 [Default:CheckIn:E] Stopped 2024-04-02T00:01:42.564Z,1712016102.564 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-02T00:01:42.564Z,1712016102.564 [Default:CheckIn] Stopped 2024-04-02T00:01:42.564Z,1712016102.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-02T00:01:42.564Z,1712016102.564 [Default:CheckIn](INFO): Running loop #11 2024-04-02T00:01:42.564Z,1712016102.564 [Default:CheckIn] Running Loop=11 2024-04-02T00:01:42.564Z,1712016102.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-02T00:01:42.564Z,1712016102.564 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-02T00:02:48.838Z,1712016168.838 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-02T00:02:48.838Z,1712016168.838 [NAL9602] No Fault, FailCount= 5 2024-04-02T00:02:49.216Z,1712016169.216 [NAL9602](INFO): Powering up NAL9602 2024-04-02T00:03:00.124Z,1712016180.124 [NAL9602](INFO): NAL9602 initialized 2024-04-02T00:03:07.470Z,1712016187.470 [DVL_micro](ERROR): DVL uart error: serial timeout 2024-04-02T00:03:07.470Z,1712016187.470 [DVL_micro] Communications Fault, FailCount= 1 2024-04-02T00:03:07.470Z,1712016187.470 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:03:07.470Z,1712016187.470 [DVL_micro](ERROR): Failed to parse: 2024-04-02T00:03:07.509Z,1712016187.509 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:03:07.891Z,1712016187.891 [DVL_micro](INFO): Powering down 2024-04-02T00:03:08.635Z,1712016188.635 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:03:08.635Z,1712016188.635 [DVL_micro] No Fault, FailCount= 1 2024-04-02T00:03:38.521Z,1712016218.521 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37 2024-04-02T00:03:38.521Z,1712016218.521 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:03:38.532Z,1712016218.532 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:03:38.952Z,1712016218.952 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:03:38.952Z,1712016218.952 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37 2024-04-02T00:04:54.059Z,1712016294.059 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-04-02T00:04:54.059Z,1712016294.059 [DVL_micro] Communications Fault, FailCount= 1 2024-04-02T00:04:54.059Z,1712016294.059 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:04:54.078Z,1712016294.078 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:04:54.546Z,1712016294.546 [DVL_micro](INFO): Powering down 2024-04-02T00:04:55.322Z,1712016295.322 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:04:55.322Z,1712016295.322 [DVL_micro] No Fault, FailCount= 1 2024-04-02T00:05:11.834Z,1712016311.834 [DVL_micro](ERROR): Failed to parse: :BI,+00000,+00000,+00000,+00000,I 2024-04-02T00:06:37.477Z,1712016397.477 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+21.6,089.0,000 2024-04-02T00:06:37.478Z,1712016397.478 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+21.6,089.0,000 2024-04-02T00:06:39.551Z,1712016399.551 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38 2024-04-02T00:06:39.551Z,1712016399.551 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:06:39.562Z,1712016399.562 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:06:39.940Z,1712016399.940 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:06:39.940Z,1712016399.940 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38 2024-04-02T00:06:42.771Z,1712016402.771 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-02T00:01:42.6Z 2024-04-02T00:06:42.771Z,1712016402.771 [Default:CheckIn:Read_GPS] Stopped 2024-04-02T00:06:42.771Z,1712016402.771 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-02T00:06:49.929Z,1712016409.929 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20240401T221151/Courier0034.lzma 2024-04-02T00:06:50.931Z,1712016410.931 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0034.lzma.bak 2024-04-02T00:06:50.932Z,1712016410.932 [DataOverHttps](INFO): SBD MOMSN=19371228 2024-04-02T00:07:07.841Z,1712016427.841 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240401T221151/Express0035.lzma 2024-04-02T00:07:08.846Z,1712016428.846 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0035.lzma.bak 2024-04-02T00:07:08.847Z,1712016428.847 [DataOverHttps](INFO): SBD MOMSN=19371231 2024-04-02T00:07:11.036Z,1712016431.036 [Default:CheckIn:Read_Iridium] Stopped 2024-04-02T00:07:11.036Z,1712016431.036 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-02T00:07:11.036Z,1712016431.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-02T00:07:26.363Z,1712016446.363 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-04-02T00:07:26.363Z,1712016446.363 [DVL_micro] Communications Fault, FailCount= 1 2024-04-02T00:07:26.363Z,1712016446.363 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:07:26.381Z,1712016446.381 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:07:26.854Z,1712016446.854 [DVL_micro](INFO): Powering down 2024-04-02T00:07:27.593Z,1712016447.593 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:07:27.593Z,1712016447.593 [DVL_micro] No Fault, FailCount= 1 2024-04-02T00:09:29.991Z,1712016569.991 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,+.00,+00000000.00,9999.99,000.00 2024-04-02T00:09:29.991Z,1712016569.991 [DVL_micro](ERROR): Failed to parse: :BI,+.00,+00000000.00,9999.99,000.00 2024-04-02T00:09:40.520Z,1712016580.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39 2024-04-02T00:09:40.520Z,1712016580.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:09:40.531Z,1712016580.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:09:40.932Z,1712016580.932 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:09:40.932Z,1712016580.932 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39 2024-04-02T00:09:44.931Z,1712016584.931 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-04-02T00:09:44.931Z,1712016584.931 [DVL_micro] Communications Fault, FailCount= 1 2024-04-02T00:09:44.931Z,1712016584.931 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:09:45.000Z,1712016585.000 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:09:45.430Z,1712016585.430 [DVL_micro](INFO): Powering down 2024-04-02T00:09:46.190Z,1712016586.190 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:09:46.190Z,1712016586.190 [DVL_micro] No Fault, FailCount= 1 2024-04-02T00:12:11.608Z,1712016731.608 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-02T00:12:11.608Z,1712016731.608 [Default:CheckIn:C.Wait] Stopped 2024-04-02T00:12:11.608Z,1712016731.608 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-02T00:12:11.609Z,1712016731.609 [Default:CheckIn:D] Running Loop=1 2024-04-02T00:12:12.052Z,1712016732.052 [Default:CheckIn:D] Stopped 2024-04-02T00:12:12.052Z,1712016732.052 [Default:CheckIn:E] Running Loop=1 2024-04-02T00:12:12.457Z,1712016732.457 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.605485 min 2024-04-02T00:12:12.457Z,1712016732.457 [Default:CheckIn:E] Stopped 2024-04-02T00:12:12.457Z,1712016732.457 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-02T00:12:12.457Z,1712016732.457 [Default:CheckIn] Stopped 2024-04-02T00:12:12.457Z,1712016732.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-02T00:12:12.457Z,1712016732.457 [Default:CheckIn](INFO): Running loop #12 2024-04-02T00:12:12.457Z,1712016732.457 [Default:CheckIn] Running Loop=12 2024-04-02T00:12:12.462Z,1712016732.462 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-02T00:12:12.462Z,1712016732.462 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-02T00:12:27.343Z,1712016747.343 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-04-02T00:12:27.343Z,1712016747.343 [DVL_micro] Communications Fault, FailCount= 1 2024-04-02T00:12:27.343Z,1712016747.343 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:12:27.381Z,1712016747.381 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:12:27.842Z,1712016747.842 [DVL_micro](INFO): Powering down 2024-04-02T00:12:28.589Z,1712016748.589 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:12:28.589Z,1712016748.589 [DVL_micro] No Fault, FailCount= 1 2024-04-02T00:12:41.518Z,1712016761.518 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40 2024-04-02T00:12:41.518Z,1712016761.518 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:12:41.561Z,1712016761.561 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:12:41.935Z,1712016761.935 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:12:41.935Z,1712016761.935 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40 2024-04-02T00:13:02.082Z,1712016782.082 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-02T00:13:02.082Z,1712016782.082 [NAL9602] Data Fault, FailCount= 1 2024-04-02T00:13:02.082Z,1712016782.082 [NAL9602](ERROR): Data Fault 2024-04-02T00:13:02.105Z,1712016782.105 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-02T00:13:02.507Z,1712016782.507 [NAL9602](INFO): Powering down 2024-04-02T00:13:03.344Z,1712016783.344 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-02T00:13:03.344Z,1712016783.344 [NAL9602] No Fault, FailCount= 1 2024-04-02T00:13:32.792Z,1712016812.792 [NAL9602](INFO): Powering up NAL9602 2024-04-02T00:13:43.697Z,1712016823.697 [NAL9602](INFO): NAL9602 initialized 2024-04-02T00:15:42.523Z,1712016942.523 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 41 2024-04-02T00:15:42.523Z,1712016942.523 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:15:42.534Z,1712016942.534 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:15:42.920Z,1712016942.920 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:15:42.920Z,1712016942.920 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 41 2024-04-02T00:17:08.786Z,1712017028.786 [CommandExec](IMPORTANT): got command failComponent 2024-04-02T00:17:08.786Z,1712017028.786 [CommandExec](IMPORTANT): Failed components: 2024-04-02T00:17:08.787Z,1712017028.787 [CommandExec](IMPORTANT): No failed Components. 2024-04-02T00:17:12.599Z,1712017032.599 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-02T00:12:12.5Z 2024-04-02T00:17:12.599Z,1712017032.599 [Default:CheckIn:Read_GPS] Stopped 2024-04-02T00:17:12.599Z,1712017032.599 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-02T00:17:19.897Z,1712017039.897 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240401T221151/Courier0037.lzma 2024-04-02T00:17:20.899Z,1712017040.899 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Courier0037.lzma.bak 2024-04-02T00:17:20.900Z,1712017040.900 [DataOverHttps](INFO): SBD MOMSN=19371247 2024-04-02T00:17:36.878Z,1712017056.878 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20240401T221151/Express0038.lzma 2024-04-02T00:17:37.879Z,1712017057.879 [DataOverHttps](INFO): Moved sent file to Logs/20240401T221151/Express0038.lzma.bak 2024-04-02T00:17:37.880Z,1712017057.880 [DataOverHttps](INFO): SBD MOMSN=19371249 2024-04-02T00:17:39.696Z,1712017059.696 [Default:CheckIn:Read_Iridium] Stopped 2024-04-02T00:17:39.696Z,1712017059.696 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-02T00:17:39.696Z,1712017059.696 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-02T00:17:58.628Z,1712017078.628 [DVL_micro](ERROR): No DVL communication! Re-initializing 2024-04-02T00:17:58.628Z,1712017078.628 [DVL_micro] Communications Fault, FailCount= 1 2024-04-02T00:17:58.628Z,1712017078.628 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:17:58.647Z,1712017078.647 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:17:59.114Z,1712017079.114 [DVL_micro](INFO): Powering down 2024-04-02T00:17:59.869Z,1712017079.869 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:17:59.869Z,1712017079.869 [DVL_micro] No Fault, FailCount= 1 2024-04-02T00:18:17.638Z,1712017097.638 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-04-02T00:18:17.638Z,1712017097.638 [DVL_micro] Communications Fault, FailCount= 2 2024-04-02T00:18:17.638Z,1712017097.638 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:18:17.718Z,1712017097.718 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:18:18.106Z,1712017098.106 [DVL_micro](INFO): Powering down 2024-04-02T00:18:18.898Z,1712017098.898 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:18:18.899Z,1712017098.899 [DVL_micro] No Fault, FailCount= 2 2024-04-02T00:18:36.597Z,1712017116.597 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-04-02T00:18:36.597Z,1712017116.597 [DVL_micro] Communications Fault, FailCount= 3 2024-04-02T00:18:36.597Z,1712017116.597 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:18:36.617Z,1712017116.617 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:18:36.617Z,1712017116.617 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-04-02T00:18:37.094Z,1712017117.094 [DVL_micro](INFO): Powering down 2024-04-02T00:18:37.116Z,1712017117.116 [CBIT](INFO): Critical error at 20240402T001836 2024-04-02T00:18:43.515Z,1712017123.515 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 42 2024-04-02T00:18:43.515Z,1712017123.515 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:18:43.526Z,1712017123.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:18:43.892Z,1712017123.892 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:18:43.892Z,1712017123.892 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 42 2024-04-02T00:21:36.840Z,1712017296.840 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:21:36.840Z,1712017296.840 [DVL_micro] No Fault, FailCount= 3 2024-04-02T00:21:44.504Z,1712017304.504 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 43 2024-04-02T00:21:44.504Z,1712017304.504 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-02T00:21:44.515Z,1712017304.515 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-02T00:21:44.884Z,1712017304.884 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-02T00:21:44.884Z,1712017304.884 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 43 2024-04-02T00:21:54.559Z,1712017314.559 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-04-02T00:21:54.559Z,1712017314.559 [DVL_micro] Communications Fault, FailCount= 1 2024-04-02T00:21:54.559Z,1712017314.559 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:21:54.585Z,1712017314.585 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:21:55.066Z,1712017315.066 [DVL_micro](INFO): Powering down 2024-04-02T00:21:55.824Z,1712017315.824 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:21:55.825Z,1712017315.825 [DVL_micro] No Fault, FailCount= 1 2024-04-02T00:21:57.530Z,1712017317.530 [CommandExec](IMPORTANT): got command failComponent 2024-04-02T00:21:57.530Z,1712017317.530 [CommandExec](IMPORTANT): Failed components: 2024-04-02T00:21:57.530Z,1712017317.530 [CommandExec](IMPORTANT): No failed Components. 2024-04-02T00:22:11.127Z,1712017331.127 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2024-04-02T00:22:11.127Z,1712017331.127 [DVL_micro] Data Fault, FailCount= 2 2024-04-02T00:22:11.127Z,1712017331.127 [DVL_micro](ERROR): Data Fault 2024-04-02T00:22:11.192Z,1712017331.192 [CBIT](ERROR): Data Fault in component: DVL_micro 2024-04-02T00:22:11.646Z,1712017331.646 [DVL_micro](INFO): Powering down 2024-04-02T00:22:12.357Z,1712017332.357 [CBIT](INFO): Clearing failed state for component DVL_micro 2024-04-02T00:22:12.357Z,1712017332.357 [DVL_micro] No Fault, FailCount= 2 2024-04-02T00:22:30.115Z,1712017350.115 [DVL_micro](FAULT): Failed to initialize within timeout. 2024-04-02T00:22:30.115Z,1712017350.115 [DVL_micro] Communications Fault, FailCount= 3 2024-04-02T00:22:30.115Z,1712017350.115 [DVL_micro](ERROR): Communications Fault 2024-04-02T00:22:30.134Z,1712017350.134 [CBIT](ERROR): Communications Fault in component: DVL_micro 2024-04-02T00:22:30.134Z,1712017350.134 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2024-04-02T00:22:30.618Z,1712017350.618 [DVL_micro](INFO): Powering down 2024-04-02T00:22:30.651Z,1712017350.651 [CBIT](INFO): Critical error at 20240402T002230 2024-04-02T00:22:40.236Z,1712017360.236 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-02T00:22:40.236Z,1712017360.236 [Default:CheckIn:C.Wait] Stopped 2024-04-02T00:22:40.236Z,1712017360.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-02T00:22:40.236Z,1712017360.236 [Default:CheckIn:D] Running Loop=1 2024-04-02T00:22:40.624Z,1712017360.624 [Default:CheckIn:D] Stopped 2024-04-02T00:22:40.624Z,1712017360.624 [Default:CheckIn:E] Running Loop=1 2024-04-02T00:22:41.027Z,1712017361.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.082137 min 2024-04-02T00:22:41.027Z,1712017361.027 [Default:CheckIn:E] Stopped 2024-04-02T00:22:41.028Z,1712017361.028 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-02T00:22:41.028Z,1712017361.028 [Default:CheckIn] Stopped 2024-04-02T00:22:41.028Z,1712017361.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-02T00:22:41.028Z,1712017361.028 [Default:CheckIn](INFO): Running loop #13 2024-04-02T00:22:41.028Z,1712017361.028 [Default:CheckIn] Running Loop=13 2024-04-02T00:22:41.028Z,1712017361.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-02T00:22:41.028Z,1712017361.028 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-02T00:22:45.355Z,1712017365.355 [CommandExec](IMPORTANT): got command failComponent 2024-04-02T00:22:45.355Z,1712017365.355 [CommandExec](IMPORTANT): Failed components: 2024-04-02T00:22:45.355Z,1712017365.355 [CommandExec](IMPORTANT): DVL_micro: Communications Fault 2024-04-02T00:22:48.800Z,1712017368.800 [CommandExec](IMPORTANT): got command failComponent none DVL_micro 2024-04-02T00:22:48.800Z,1712017368.800 [DVL_micro] No Fault, FailCount= 3 2024-04-02T00:22:48.800Z,1712017368.800 [CommandExec](IMPORTANT): DVL_micro failureMode is No Fault 2024-04-02T00:23:04.479Z,1712017384.479 [DVL_micro](INFO): Failure count cleared after critical for DVL_micro 2024-04-02T00:23:13.922Z,1712017393.922 [CommandExec](IMPORTANT): got command quit 2024-04-02T00:23:14.926Z,1712017394.926 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:14.926Z,1712017394.926 [CommandExec](INFO): Uninitializing the command executive. 2024-04-02T00:23:14.926Z,1712017394.926 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-02T00:23:14.927Z,1712017394.927 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:14.945Z,1712017394.945 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-02T00:23:14.946Z,1712017394.946 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-02T00:23:14.946Z,1712017394.946 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:14.947Z,1712017394.947 [NavChartDb](INFO): Join timeout helper Thread ID is 1450 2024-04-02T00:23:15.090Z,1712017395.090 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:15.090Z,1712017395.090 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.105Z,1712017395.105 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-02T00:23:15.106Z,1712017395.106 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.106Z,1712017395.106 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1451 2024-04-02T00:23:15.390Z,1712017395.390 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:15.390Z,1712017395.390 [WetLabsBB2FL](INFO): Powering down 2024-04-02T00:23:15.391Z,1712017395.391 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.398Z,1712017395.398 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-02T00:23:15.398Z,1712017395.398 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.398Z,1712017395.398 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1452 2024-04-02T00:23:15.570Z,1712017395.570 [CTD_Seabird](INFO): Powering down 2024-04-02T00:23:15.582Z,1712017395.582 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:15.583Z,1712017395.583 [CTD_Seabird](INFO): Powering down 2024-04-02T00:23:15.594Z,1712017395.594 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.602Z,1712017395.602 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-02T00:23:15.602Z,1712017395.602 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.602Z,1712017395.602 [DAT](INFO): Join timeout helper Thread ID is 1453 2024-04-02T00:23:15.754Z,1712017395.754 [DAT](INFO): Powering down 2024-04-02T00:23:15.826Z,1712017395.826 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:15.827Z,1712017395.827 [DAT](INFO): Powering down 2024-04-02T00:23:15.827Z,1712017395.827 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.859Z,1712017395.859 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-02T00:23:15.860Z,1712017395.860 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.860Z,1712017395.860 [Radio_Surface](INFO): Join timeout helper Thread ID is 1454 2024-04-02T00:23:15.898Z,1712017395.898 [Radio_Surface](INFO): Powering down 2024-04-02T00:23:15.899Z,1712017395.899 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:15.899Z,1712017395.899 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.918Z,1712017395.918 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-04-02T00:23:15.918Z,1712017395.918 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:15.919Z,1712017395.919 [Onboard](INFO): Join timeout helper Thread ID is 1455 2024-04-02T00:23:16.302Z,1712017396.302 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-04-02T00:23:18.699Z,1712017398.699 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:18.699Z,1712017398.699 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:18.702Z,1712017398.702 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-04-02T00:23:18.702Z,1712017398.702 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:18.703Z,1712017398.703 [DataOverHttps](INFO): Join timeout helper Thread ID is 1456 2024-04-02T00:23:19.314Z,1712017399.314 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:19.314Z,1712017399.314 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.327Z,1712017399.327 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-04-02T00:23:19.327Z,1712017399.327 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.328Z,1712017399.328 [BackseatComponent](INFO): Join timeout helper Thread ID is 1457 2024-04-02T00:23:19.514Z,1712017399.514 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:19.514Z,1712017399.514 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.518Z,1712017399.518 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-04-02T00:23:19.518Z,1712017399.518 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.519Z,1712017399.519 [logger](INFO): Join timeout helper Thread ID is 1458 2024-04-02T00:23:19.530Z,1712017399.530 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:19.530Z,1712017399.530 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.538Z,1712017399.538 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-04-02T00:23:19.538Z,1712017399.538 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.539Z,1712017399.539 [CommandLine](INFO): Join timeout helper Thread ID is 1459 2024-04-02T00:23:19.598Z,1712017399.598 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:19.598Z,1712017399.598 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.618Z,1712017399.618 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-04-02T00:23:19.618Z,1712017399.618 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.619Z,1712017399.619 [CommandExec](INFO): Join timeout helper Thread ID is 1460 2024-04-02T00:23:19.620Z,1712017399.620 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-04-02T00:23:19.620Z,1712017399.620 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:19.620Z,1712017399.620 [controlThread](INFO): Join timeout helper Thread ID is 1461 2024-04-02T00:23:19.790Z,1712017399.790 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-02T00:23:19.790Z,1712017399.790 [controlThread](DEBUG): Uninitializing ControlThread 2024-04-02T00:23:19.790Z,1712017399.790 [AHRS_M2](INFO): Powering down 2024-04-02T00:23:19.958Z,1712017399.958 [DVL_micro](INFO): Powering down 2024-04-02T00:23:19.959Z,1712017399.959 [NAL9602](INFO): Powering down 2024-04-02T00:23:19.960Z,1712017399.960 [Sonardyne_Nano](INFO): Powering down 2024-04-02T00:23:20.080Z,1712017400.080 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-04-02T00:23:20.080Z,1712017400.080 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-04-02T00:23:20.081Z,1712017400.081 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-04-02T00:23:20.081Z,1712017400.081 [MissionManager](INFO): Uninitializing Mission Default 2024-04-02T00:23:20.082Z,1712017400.082 [Default] Stopped 2024-04-02T00:23:20.082Z,1712017400.082 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-02T00:23:20.082Z,1712017400.082 [Default:B.GoToSurface] Stopped 2024-04-02T00:23:20.082Z,1712017400.082 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-02T00:23:20.082Z,1712017400.082 [Default:CheckIn] Stopped 2024-04-02T00:23:20.082Z,1712017400.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-02T00:23:20.082Z,1712017400.082 [Default:CheckIn:Read_GPS] Stopped 2024-04-02T00:23:20.085Z,1712017400.085 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-04-02T00:23:20.085Z,1712017400.085 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-04-02T00:23:20.086Z,1712017400.086 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-04-02T00:23:20.086Z,1712017400.086 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-04-02T00:23:20.086Z,1712017400.086 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-04-02T00:23:20.086Z,1712017400.086 [BuoyancyServo](INFO): Powering down 2024-04-02T00:23:20.098Z,1712017400.098 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-04-02T00:23:20.098Z,1712017400.098 [ElevatorServo](INFO): Powering down 2024-04-02T00:23:20.099Z,1712017400.099 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-04-02T00:23:20.099Z,1712017400.099 [MassServo](INFO): Powering down 2024-04-02T00:23:20.100Z,1712017400.100 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-04-02T00:23:20.100Z,1712017400.100 [RudderServo](INFO): Powering down 2024-04-02T00:23:20.101Z,1712017400.101 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-04-02T00:23:20.101Z,1712017400.101 [ThrusterHE](INFO): Powering down 2024-04-02T00:23:20.102Z,1712017400.102 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-04-02T00:23:20.102Z,1712017400.102 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-04-02T00:23:20.102Z,1712017400.102 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-04-02T00:23:20.102Z,1712017400.102 [CBIT](DEBUG): Powering off loads. 2024-04-02T00:23:20.113Z,1712017400.113 [CBIT](DEBUG): Disabling WDT. 2024-04-02T00:23:20.125Z,1712017400.125 [CBIT](DEBUG): Opening all GF detection circuits. 2024-04-02T00:23:20.126Z,1712017400.126 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.174Z,1712017400.174 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.179Z,1712017400.179 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.181Z,1712017400.181 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.188Z,1712017400.188 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.231Z,1712017400.231 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.283Z,1712017400.283 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.289Z,1712017400.289 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.311Z,1712017400.311 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-02T00:23:20.374Z,1712017400.374 [logger ThreadHandler](INFO): Thread cancelled.