2024-04-22T21:06:02.658Z,1713819962.658 [Supervisor](DEBUG): Initializing supervisor. 2024-04-22T21:06:02.663Z,1713819962.663 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-22T21:06:02.664Z,1713819962.664 [SyncHandler](INFO): Protected caller Thread ID is 3970 2024-04-22T21:06:02.664Z,1713819962.664 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-22T21:06:02.665Z,1713819962.665 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-22T21:06:02.666Z,1713819962.666 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3971 2024-04-22T21:06:02.670Z,1713819962.670 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-22T21:06:02.687Z,1713819962.687 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-22T21:06:02.688Z,1713819962.688 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-22T21:06:02.689Z,1713819962.689 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3972 2024-04-22T21:06:02.693Z,1713819962.693 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-22T21:06:02.694Z,1713819962.694 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-22T21:06:02.694Z,1713819962.694 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3973 2024-04-22T21:06:02.696Z,1713819962.696 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-22T21:06:02.697Z,1713819962.697 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-22T21:06:02.698Z,1713819962.698 [logger ThreadHandler](INFO): Protected caller Thread ID is 3974 2024-04-22T21:06:02.701Z,1713819962.701 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-22T21:06:02.702Z,1713819962.702 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-22T21:06:02.703Z,1713819962.703 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-22T21:06:03.074Z,1713819963.074 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-22T21:06:03.075Z,1713819963.075 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-22T21:06:03.863Z,1713819963.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-22T21:06:03.863Z,1713819963.863 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-22T21:06:03.986Z,1713819963.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-22T21:06:03.986Z,1713819963.986 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-22T21:06:04.568Z,1713819964.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-22T21:06:04.569Z,1713819964.569 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-22T21:06:04.681Z,1713819964.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-22T21:06:04.681Z,1713819964.681 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-22T21:06:04.775Z,1713819964.775 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-22T21:06:05.221Z,1713819965.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-22T21:06:05.221Z,1713819965.221 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-22T21:06:05.470Z,1713819965.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-22T21:06:05.470Z,1713819965.470 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-22T21:06:05.622Z,1713819965.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-22T21:06:05.623Z,1713819965.623 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-22T21:06:05.899Z,1713819965.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-22T21:06:05.900Z,1713819965.900 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-22T21:06:06.484Z,1713819966.484 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-22T21:06:06.485Z,1713819966.485 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-22T21:06:06.943Z,1713819966.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-22T21:06:06.944Z,1713819966.944 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-22T21:06:07.180Z,1713819967.180 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-22T21:06:07.586Z,1713819967.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-22T21:06:07.586Z,1713819967.586 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-22T21:06:08.037Z,1713819968.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-22T21:06:08.038Z,1713819968.038 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-22T21:06:08.362Z,1713819968.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-22T21:06:08.364Z,1713819968.364 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2024-04-22T21:06:08.365Z,1713819968.365 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2024-04-22T21:06:08.489Z,1713819968.489 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2024-04-22T21:06:08.658Z,1713819968.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2024-04-22T21:06:08.757Z,1713819968.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2024-04-22T21:06:08.854Z,1713819968.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2024-04-22T21:06:08.979Z,1713819968.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2024-04-22T21:06:09.098Z,1713819969.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2024-04-22T21:06:09.380Z,1713819969.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-22T21:06:09.381Z,1713819969.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2024-04-22T21:06:09.532Z,1713819969.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2024-04-22T21:06:09.638Z,1713819969.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2024-04-22T21:06:09.745Z,1713819969.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2024-04-22T21:06:09.862Z,1713819969.862 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2024-04-22T21:06:09.863Z,1713819969.863 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-04-22T21:06:09.871Z,1713819969.871 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-22T21:06:09.992Z,1713819969.992 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-22T21:06:09.992Z,1713819969.992 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-22T21:06:10.003Z,1713819970.003 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-22T21:06:10.003Z,1713819970.003 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-22T21:06:10.055Z,1713819970.055 [DepthRateCalculator] Loaded 2024-04-22T21:06:10.055Z,1713819970.055 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-22T21:06:10.061Z,1713819970.061 [PitchRateCalculator] Loaded 2024-04-22T21:06:10.061Z,1713819970.061 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-22T21:06:10.071Z,1713819970.071 [SpeedCalculator] Loaded 2024-04-22T21:06:10.071Z,1713819970.071 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-22T21:06:10.076Z,1713819970.076 [YawRateCalculator] Loaded 2024-04-22T21:06:10.076Z,1713819970.076 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-22T21:06:10.097Z,1713819970.097 [ElevatorOffsetCalculator] Loaded 2024-04-22T21:06:10.098Z,1713819970.098 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-22T21:06:10.098Z,1713819970.098 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-22T21:06:10.098Z,1713819970.098 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-22T21:06:10.152Z,1713819970.152 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-22T21:06:10.207Z,1713819970.207 [VerticalControl] Loaded 2024-04-22T21:06:10.207Z,1713819970.207 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-22T21:06:10.210Z,1713819970.210 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-22T21:06:10.251Z,1713819970.251 [HorizontalControl] Loaded 2024-04-22T21:06:10.251Z,1713819970.251 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-22T21:06:10.254Z,1713819970.254 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-22T21:06:10.256Z,1713819970.256 [SpeedControl] Loaded 2024-04-22T21:06:10.256Z,1713819970.256 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-22T21:06:10.259Z,1713819970.259 [LoopControl](DEBUG): Construct LoopControl. 2024-04-22T21:06:10.260Z,1713819970.260 [LoopControl] Loaded 2024-04-22T21:06:10.260Z,1713819970.260 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-22T21:06:10.260Z,1713819970.260 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-22T21:06:10.261Z,1713819970.261 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-22T21:06:10.374Z,1713819970.374 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-22T21:06:10.374Z,1713819970.374 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-22T21:06:10.388Z,1713819970.388 [NavChart] Loaded 2024-04-22T21:06:10.388Z,1713819970.388 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-22T21:06:10.394Z,1713819970.394 [UniversalFixResidualReporter] Loaded 2024-04-22T21:06:10.394Z,1713819970.394 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-22T21:06:10.395Z,1713819970.395 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-22T21:06:10.395Z,1713819970.395 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-22T21:06:10.579Z,1713819970.579 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-22T21:06:10.579Z,1713819970.579 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-22T21:06:11.050Z,1713819971.050 [AHRS_M2] Loaded 2024-04-22T21:06:11.050Z,1713819971.050 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-22T21:06:11.086Z,1713819971.086 [BackseatComponent] Loaded 2024-04-22T21:06:11.086Z,1713819971.086 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-04-22T21:06:11.087Z,1713819971.087 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409204E0 2024-04-22T21:06:11.088Z,1713819971.088 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4056 2024-04-22T21:06:11.091Z,1713819971.091 [LcmUniversalReporter] Loaded 2024-04-22T21:06:11.091Z,1713819971.091 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-04-22T21:06:11.916Z,1713819971.916 [BPC1] Loaded 2024-04-22T21:06:11.916Z,1713819971.916 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-22T21:06:11.996Z,1713819971.996 [DataOverHttps] Loaded 2024-04-22T21:06:11.996Z,1713819971.996 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-22T21:06:11.997Z,1713819971.997 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409504E0 2024-04-22T21:06:11.997Z,1713819971.997 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4057 2024-04-22T21:06:12.018Z,1713819972.018 [Depth_Keller] Loaded 2024-04-22T21:06:12.018Z,1713819972.018 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-22T21:06:12.023Z,1713819972.023 [DropWeight] Loaded 2024-04-22T21:06:12.023Z,1713819972.023 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-22T21:06:12.062Z,1713819972.062 [DVL_micro] Loaded 2024-04-22T21:06:12.062Z,1713819972.062 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2024-04-22T21:06:12.124Z,1713819972.124 [NAL9602] Loaded 2024-04-22T21:06:12.125Z,1713819972.125 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-22T21:06:12.155Z,1713819972.155 [Onboard] Loaded 2024-04-22T21:06:12.155Z,1713819972.155 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-22T21:06:12.156Z,1713819972.156 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409804E0 2024-04-22T21:06:12.156Z,1713819972.156 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4058 2024-04-22T21:06:12.168Z,1713819972.168 [Power24vConverter] Loaded 2024-04-22T21:06:12.169Z,1713819972.169 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-22T21:06:12.182Z,1713819972.182 [Radio_Surface] Loaded 2024-04-22T21:06:12.182Z,1713819972.182 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-22T21:06:12.183Z,1713819972.183 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409B04E0 2024-04-22T21:06:12.184Z,1713819972.184 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4059 2024-04-22T21:06:12.197Z,1713819972.197 [Sonardyne_Nano] Loaded 2024-04-22T21:06:12.198Z,1713819972.198 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-04-22T21:06:12.290Z,1713819972.290 [DAT] Loaded 2024-04-22T21:06:12.290Z,1713819972.290 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-22T21:06:12.291Z,1713819972.291 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409E04E0 2024-04-22T21:06:12.292Z,1713819972.292 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4060 2024-04-22T21:06:12.292Z,1713819972.292 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-22T21:06:12.293Z,1713819972.293 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-22T21:06:12.818Z,1713819972.818 [BuoyancyServo] Loaded 2024-04-22T21:06:12.819Z,1713819972.819 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-22T21:06:12.935Z,1713819972.935 [ElevatorServo] Loaded 2024-04-22T21:06:12.935Z,1713819972.935 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-22T21:06:12.984Z,1713819972.984 [MassServo] Loaded 2024-04-22T21:06:12.984Z,1713819972.984 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-22T21:06:13.003Z,1713819973.003 [RudderServo] Loaded 2024-04-22T21:06:13.003Z,1713819973.003 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-22T21:06:13.018Z,1713819973.018 [ThrusterHE] Loaded 2024-04-22T21:06:13.018Z,1713819973.018 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-22T21:06:13.019Z,1713819973.019 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-22T21:06:13.019Z,1713819973.019 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-22T21:06:13.078Z,1713819973.078 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-22T21:06:13.078Z,1713819973.078 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-22T21:06:13.106Z,1713819973.106 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-22T21:06:13.106Z,1713819973.106 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-22T21:06:13.460Z,1713819973.460 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-22T21:06:13.461Z,1713819973.461 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-22T21:06:13.639Z,1713819973.639 [CTD_Seabird] Loaded 2024-04-22T21:06:13.640Z,1713819973.640 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-22T21:06:13.641Z,1713819973.641 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B964E0 2024-04-22T21:06:13.641Z,1713819973.641 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4061 2024-04-22T21:06:13.674Z,1713819973.674 [ESPComponent] Loaded 2024-04-22T21:06:13.674Z,1713819973.674 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-04-22T21:06:13.694Z,1713819973.694 [PAR_Licor] Loaded 2024-04-22T21:06:13.695Z,1713819973.695 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-22T21:06:13.739Z,1713819973.739 [WetLabsBB2FL] Loaded 2024-04-22T21:06:13.739Z,1713819973.739 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-22T21:06:13.740Z,1713819973.740 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC64E0 2024-04-22T21:06:13.740Z,1713819973.740 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4062 2024-04-22T21:06:13.741Z,1713819973.741 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-22T21:06:13.742Z,1713819973.742 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-22T21:06:13.890Z,1713819973.890 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-22T21:06:13.900Z,1713819973.900 [SBIT] Loaded 2024-04-22T21:06:13.900Z,1713819973.900 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-22T21:06:13.903Z,1713819973.903 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-22T21:06:13.915Z,1713819973.915 [IBIT] Loaded 2024-04-22T21:06:13.916Z,1713819973.916 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-22T21:06:13.922Z,1713819973.922 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-22T21:06:14.026Z,1713819974.026 [CBIT] Loaded 2024-04-22T21:06:14.027Z,1713819974.027 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-22T21:06:14.027Z,1713819974.027 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-22T21:06:14.033Z,1713819974.033 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-22T21:06:14.036Z,1713819974.036 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-22T21:06:14.047Z,1713819974.047 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-22T21:06:14.048Z,1713819974.048 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2024-04-22T21:06:14.049Z,1713819974.049 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4063 2024-04-22T21:06:14.053Z,1713819974.053 [Supervisor](INFO): Main Thread ID is 3810 2024-04-22T21:06:14.053Z,1713819974.053 [Supervisor](DEBUG): Running supervisor. 2024-04-22T21:06:14.054Z,1713819974.054 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4064 2024-04-22T21:06:14.054Z,1713819974.054 [CommandExec](INFO): Initializing the command executive. 2024-04-22T21:06:14.056Z,1713819974.056 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4065 2024-04-22T21:06:14.058Z,1713819974.058 [controlThread ThreadHandler](INFO): Handler Thread ID is 4066 2024-04-22T21:06:14.058Z,1713819974.058 [controlThread](DEBUG): Initializing ControlThread 2024-04-22T21:06:14.059Z,1713819974.059 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-22T21:06:14.060Z,1713819974.060 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-22T21:06:14.060Z,1713819974.060 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-22T21:06:14.060Z,1713819974.060 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-22T21:06:14.061Z,1713819974.061 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-22T21:06:14.061Z,1713819974.061 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-22T21:06:14.063Z,1713819974.063 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-22T21:06:14.063Z,1713819974.063 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-22T21:06:14.064Z,1713819974.064 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-22T21:06:14.065Z,1713819974.065 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-22T21:06:14.065Z,1713819974.065 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-22T21:06:14.073Z,1713819974.073 [SBIT](INFO): Initialize SBIT Component. 2024-04-22T21:06:14.073Z,1713819974.073 [SBIT](IMPORTANT): git: 2024-04-11 2024-04-22T21:06:14.074Z,1713819974.074 [SBIT](INFO): git hash: 8b14ac3cbe1df2c95d6505c0066306c41245f1d7 2024-04-22T21:06:14.074Z,1713819974.074 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-22T21:06:14.075Z,1713819974.075 [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-22T21:06:14.076Z,1713819974.076 [SBIT](INFO): Beginning SBIT in 148.000000 seconds. 2024-04-22T21:06:14.077Z,1713819974.077 [IBIT](INFO): Initialize IBIT Component. 2024-04-22T21:06:14.078Z,1713819974.078 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-22T21:06:14.078Z,1713819974.078 [logger ThreadHandler](INFO): Handler Thread ID is 4067 2024-04-22T21:06:14.089Z,1713819974.089 [CBIT](DEBUG): Initialized mux pins. 2024-04-22T21:06:14.089Z,1713819974.089 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-22T21:06:14.097Z,1713819974.097 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4068 2024-04-22T21:06:14.109Z,1713819974.109 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4069 2024-04-22T21:06:14.111Z,1713819974.111 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-22T21:06:14.113Z,1713819974.113 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-04-22T21:06:14.113Z,1713819974.113 [CBIT](DEBUG): Initializing heartbeat. 2024-04-22T21:06:14.121Z,1713819974.121 [Onboard ThreadHandler](INFO): Handler Thread ID is 4070 2024-04-22T21:06:14.139Z,1713819974.139 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4071 2024-04-22T21:06:14.158Z,1713819974.158 [DAT ThreadHandler](INFO): Handler Thread ID is 4072 2024-04-22T21:06:14.158Z,1713819974.158 [DAT](INFO): Powering up 2024-04-22T21:06:14.158Z,1713819974.158 [DAT](DEBUG): Initializing DAT. 2024-04-22T21:06:14.162Z,1713819974.162 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4074 2024-04-22T21:06:14.163Z,1713819974.163 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-22T21:06:14.166Z,1713819974.166 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4076 2024-04-22T21:06:14.169Z,1713819974.169 [WetLabsBB2FL](INFO): Powering up 2024-04-22T21:06:14.170Z,1713819974.170 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4078 2024-04-22T21:06:14.173Z,1713819974.173 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-04-22T21:06:14.173Z,1713819974.173 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-04-22T21:06:14.174Z,1713819974.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-04-22T21:06:14.174Z,1713819974.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-04-22T21:06:14.174Z,1713819974.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-04-22T21:06:14.174Z,1713819974.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-04-22T21:06:14.174Z,1713819974.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-04-22T21:06:14.174Z,1713819974.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-04-22T21:06:14.185Z,1713819974.185 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-22T21:06:14.185Z,1713819974.185 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-22T21:06:14.225Z,1713819974.225 [CBIT](DEBUG): Backplane powered. 2024-04-22T21:06:14.226Z,1713819974.226 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-22T21:06:14.226Z,1713819974.226 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-04-22T21:06:14.240Z,1713819974.240 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-22T21:06:14.277Z,1713819974.277 [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-22T21:06:14.277Z,1713819974.277 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-04-22T21:06:14.278Z,1713819974.278 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-04-22T21:06:14.349Z,1713819974.349 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-22T21:06:14.350Z,1713819974.350 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-22T21:06:14.352Z,1713819974.352 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-22T21:06:14.397Z,1713819974.397 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-22T21:06:14.411Z,1713819974.411 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-22T21:06:14.433Z,1713819974.433 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-22T21:06:14.436Z,1713819974.436 [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-22T21:06:14.457Z,1713819974.457 [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-22T21:06:14.475Z,1713819974.475 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-22T21:06:14.545Z,1713819974.545 [Radio_Surface](INFO): Powering up 2024-04-22T21:06:14.546Z,1713819974.546 [Power24vConverter](INFO): Powering up. 2024-04-22T21:06:14.547Z,1713819974.547 [Sonardyne_Nano](INFO): Initializing. 2024-04-22T21:06:14.630Z,1713819974.630 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-22T21:06:14.637Z,1713819974.637 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-22T21:06:14.638Z,1713819974.638 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-22T21:06:14.649Z,1713819974.649 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-22T21:06:14.650Z,1713819974.650 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-22T21:06:14.657Z,1713819974.657 [MassServo](DEBUG): Initializing MassServo. 2024-04-22T21:06:14.658Z,1713819974.658 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-22T21:06:14.665Z,1713819974.665 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-22T21:06:14.666Z,1713819974.666 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-22T21:06:14.677Z,1713819974.677 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-22T21:06:16.487Z,1713819976.487 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-04-22T21:06:16.781Z,1713819976.781 [WetLabsBB2FL](INFO): Powering down 2024-04-22T21:06:17.774Z,1713819977.774 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2024-04-22T21:06:20.230Z,1713819980.230 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-04-22T21:06:26.001Z,1713819986.001 [DAT](INFO): DAT read: 2024-04-22T21:06:26.002Z,1713819986.002 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-22T21:06:27.765Z,1713819987.765 [DAT](INFO): DAT read: MF Frequency Band 2024-04-22T21:06:27.767Z,1713819987.767 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-22T21:06:27.767Z,1713819987.767 [DAT](INFO): DAT read: Apr 22 2024 21:06:22 2024-04-22T21:06:28.774Z,1713819988.774 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-22T21:06:28.775Z,1713819988.775 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-22T21:06:28.775Z,1713819988.775 [DAT](INFO): commRate: 800 2024-04-22T21:06:30.841Z,1713819990.841 [DAT](INFO): entering command mode 2024-04-22T21:06:31.041Z,1713819991.041 [DAT](INFO): DAT read: 2024-04-22T21:06:31.042Z,1713819991.042 [DAT](INFO): DAT read: user:1> 2024-04-22T21:06:31.042Z,1713819991.042 [DAT](INFO): setting verbose to 3 2024-04-22T21:06:31.293Z,1713819991.293 [DAT](INFO): DAT read: user:1> 2024-04-22T21:06:31.294Z,1713819991.294 [DAT](INFO): DAT read: Verbose | 3 2024-04-22T21:06:31.295Z,1713819991.295 [DAT](INFO): set verbose to 3 2024-04-22T21:06:31.295Z,1713819991.295 [DAT](INFO): setting DatVerbose to 27440 2024-04-22T21:06:31.545Z,1713819991.545 [DAT](INFO): DAT read: user:2> 2024-04-22T21:06:31.546Z,1713819991.546 [DAT](INFO): DAT read: DatVerbose | 27440 2024-04-22T21:06:31.546Z,1713819991.546 [DAT](INFO): set DatVerbose to 27440 2024-04-22T21:06:31.547Z,1713819991.547 [DAT](INFO): setting transmit power to 8 2024-04-22T21:06:31.797Z,1713819991.797 [DAT](INFO): DAT read: user:3> 2024-04-22T21:06:31.798Z,1713819991.798 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-04-22T21:06:31.798Z,1713819991.798 [DAT](INFO): set transmit power to 8 2024-04-22T21:06:31.799Z,1713819991.799 [DAT](INFO): setting local address to 11 2024-04-22T21:06:32.049Z,1713819992.049 [DAT](INFO): DAT read: user:4> 2024-04-22T21:06:32.050Z,1713819992.050 [DAT](INFO): DAT read: LocalAddr | 11 2024-04-22T21:06:32.051Z,1713819992.051 [DAT](INFO): set local address to 11 2024-04-22T21:06:32.052Z,1713819992.052 [DAT](INFO): Setting time to: 21:6:32 And date to:4/22/2024 2024-04-22T21:06:32.302Z,1713819992.302 [DAT](INFO): DAT read: user:5> 2024-04-22T21:06:32.303Z,1713819992.303 [DAT](INFO): DAT read: Mon Apr 22, 2024 21:06:32 2024-04-22T21:06:32.304Z,1713819992.304 [DAT](INFO): Local DAT time set to Mon Apr 22, 2024 21:06:32 2024-04-22T21:06:42.347Z,1713820002.347 [NAL9602](INFO): Powering up NAL9602 2024-04-22T21:06:53.259Z,1713820013.259 [NAL9602](INFO): NAL9602 initialized 2024-04-22T21:07:26.105Z,1713820046.105 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T21:07:26.105Z,1713820046.105 [CommandExec](IMPORTANT): Failed components: 2024-04-22T21:07:26.106Z,1713820046.106 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T21:08:42.337Z,1713820122.337 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:08:42.783Z,1713820122.783 [SBIT](IMPORTANT): Beginning Startup BIT 2024-04-22T21:08:42.787Z,1713820122.787 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-22T21:08:44.786Z,1713820124.786 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-04-22T21:08:45.898Z,1713820125.898 [CommandExec](IMPORTANT): got command strobe off 2024-04-22T21:08:45.898Z,1713820125.898 [CommandExec](IMPORTANT): Deactivating strobe 2024-04-22T21:08:53.677Z,1713820133.677 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.015478 CHAN A1 (24V): -0.000693 CHAN A2 (12V): -0.000396 CHAN A3 (5V): 0.000443 CHAN B0 (3.3V): 0.000119 CHAN B1 (3.15aV): 0.000333 CHAN B2 (3.15bV): 0.000098 CHAN B3 (GND): 0.000396 OPEN: 0.004761 Full Scale: +/- 1 mA 2024-04-22T21:09:03.833Z,1713820143.833 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T21:09:03.833Z,1713820143.833 [CommandExec](IMPORTANT): Failed components: 2024-04-22T21:09:03.833Z,1713820143.833 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T21:09:14.339Z,1713820154.339 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-04-22T21:09:14.339Z,1713820154.339 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:09:14.358Z,1713820154.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:09:14.703Z,1713820154.703 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:09:14.703Z,1713820154.703 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-04-22T21:09:17.343Z,1713820157.343 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004680 2024-04-22T21:09:36.893Z,1713820176.893 [SBIT](IMPORTANT): SBIT PASSED 2024-04-22T21:09:36.893Z,1713820176.893 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-04-22T21:09:36.894Z,1713820176.894 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool; 2024-04-22T21:09:36.894Z,1713820176.894 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=12 count; 2024-04-22T21:09:36.894Z,1713820176.894 [SBIT](IMPORTANT): DVL_micro.baud=19200 bit_per_second; 2024-04-22T21:09:36.894Z,1713820176.894 [SBIT](IMPORTANT): Depth_Keller.offset=3.6 decibar; 2024-04-22T21:09:36.894Z,1713820176.894 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-04-22T21:09:36.894Z,1713820176.894 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2024-04-22T21:09:36.894Z,1713820176.894 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=410.582899 cubic_centimeter; 2024-04-22T21:09:36.895Z,1713820176.895 [SBIT](IMPORTANT): VerticalControl.massDefault=3.2 centimeter; 2024-04-22T21:09:37.315Z,1713820177.315 [MissionManager](IMPORTANT): Started mission Startup 2024-04-22T21:09:37.315Z,1713820177.315 [Startup] Running Loop=1 2024-04-22T21:09:37.315Z,1713820177.315 [Startup](DEBUG): Aggregate::initialize Startup 2024-04-22T21:09:37.315Z,1713820177.315 [Startup:A.GoToSurface] Running Loop=1 2024-04-22T21:09:37.315Z,1713820177.315 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-22T21:09:37.316Z,1713820177.316 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-22T21:09:37.316Z,1713820177.316 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-22T21:09:37.317Z,1713820177.317 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-22T21:09:37.317Z,1713820177.317 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-22T21:09:37.318Z,1713820177.318 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-22T21:09:37.319Z,1713820177.319 [Startup:StartupSatComms] Running Loop=1 2024-04-22T21:09:37.319Z,1713820177.319 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-04-22T21:09:37.319Z,1713820177.319 [Startup:StartupSatComms:A] Running Loop=1 2024-04-22T21:09:37.727Z,1713820177.727 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-04-22T21:10:37.483Z,1713820237.483 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-22T21:09:37.3Z 2024-04-22T21:10:37.483Z,1713820237.483 [Startup:StartupSatComms:A] Stopped 2024-04-22T21:10:37.483Z,1713820237.483 [Startup:StartupSatComms:B] Running Loop=1 2024-04-22T21:10:37.898Z,1713820237.898 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-22T21:10:45.060Z,1713820245.060 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240422T202600/Courier0013.lzma 2024-04-22T21:10:46.062Z,1713820246.062 [DataOverHttps](INFO): Moved sent file to Logs/20240422T202600/Courier0013.lzma.bak 2024-04-22T21:10:46.063Z,1713820246.063 [DataOverHttps](INFO): SBD MOMSN=19421587 2024-04-22T21:11:07.772Z,1713820267.772 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240422T210602/Courier0000.lzma 2024-04-22T21:11:08.774Z,1713820268.774 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Courier0000.lzma.bak 2024-04-22T21:11:08.775Z,1713820268.775 [DataOverHttps](INFO): SBD MOMSN=19421589 2024-04-22T21:11:31.285Z,1713820291.285 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20240422T202600/Express0014.lzma 2024-04-22T21:11:32.286Z,1713820292.286 [DataOverHttps](INFO): Moved sent file to Logs/20240422T202600/Express0014.lzma.bak 2024-04-22T21:11:32.286Z,1713820292.286 [DataOverHttps](INFO): SBD MOMSN=19421594 2024-04-22T21:11:37.767Z,1713820297.767 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-22T21:10:37.5Z 2024-04-22T21:11:37.767Z,1713820297.767 [Startup:StartupSatComms:B] Stopped 2024-04-22T21:11:37.767Z,1713820297.767 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-04-22T21:11:37.767Z,1713820297.767 [Startup:StartupSatComms] Stopped 2024-04-22T21:11:37.768Z,1713820297.768 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-04-22T21:11:37.773Z,1713820297.773 [Startup](INFO): Completed Startup 2024-04-22T21:11:37.773Z,1713820297.773 [MissionManager](INFO): Startup is completed. 2024-04-22T21:11:37.773Z,1713820297.773 [MissionManager](INFO): Uninitializing Mission Startup 2024-04-22T21:11:37.773Z,1713820297.773 [Startup] Stopped 2024-04-22T21:11:37.773Z,1713820297.773 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-04-22T21:11:37.773Z,1713820297.773 [Startup:A.GoToSurface] Stopped 2024-04-22T21:11:37.773Z,1713820297.773 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-22T21:11:38.178Z,1713820298.178 [MissionManager](IMPORTANT): Started mission Default 2024-04-22T21:11:38.179Z,1713820298.179 [Default] Running Loop=1 2024-04-22T21:11:38.179Z,1713820298.179 [Default](DEBUG): Aggregate::initialize Default 2024-04-22T21:11:38.180Z,1713820298.180 [Default:B.GoToSurface] Running Loop=1 2024-04-22T21:11:38.180Z,1713820298.180 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-22T21:11:38.185Z,1713820298.185 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-22T21:11:38.185Z,1713820298.185 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-22T21:11:38.185Z,1713820298.185 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-22T21:11:38.186Z,1713820298.186 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-22T21:11:38.186Z,1713820298.186 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-22T21:11:38.186Z,1713820298.186 [Default:A.Wait] Running Loop=1 2024-04-22T21:11:38.186Z,1713820298.186 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-04-22T21:11:51.022Z,1713820311.022 [DataOverHttps](INFO): Sending 1021 bytes from file Logs/20240422T210602/Express0001.lzma 2024-04-22T21:11:51.537Z,1713820311.537 [Default:A.Wait](INFO): Done Waiting. 2024-04-22T21:11:51.537Z,1713820311.537 [Default:A.Wait] Stopped 2024-04-22T21:11:51.537Z,1713820311.537 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-04-22T21:11:51.947Z,1713820311.947 [Default:CheckIn] Running Loop=1 2024-04-22T21:11:51.947Z,1713820311.947 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-22T21:11:51.947Z,1713820311.947 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-22T21:11:52.022Z,1713820312.022 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Express0001.lzma.bak 2024-04-22T21:11:52.022Z,1713820312.022 [DataOverHttps](INFO): SBD MOMSN=19421604 2024-04-22T21:11:52.296Z,1713820312.296 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-04-22T21:11:57.122Z,1713820317.122 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-22T21:12:15.337Z,1713820335.337 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-04-22T21:12:15.337Z,1713820335.337 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:12:15.347Z,1713820335.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:12:15.761Z,1713820335.761 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:12:15.761Z,1713820335.761 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-04-22T21:13:49.023Z,1713820429.023 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:14:03.585Z,1713820443.585 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:14:18.516Z,1713820458.516 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:14:33.058Z,1713820473.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:14:47.606Z,1713820487.606 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:15:02.554Z,1713820502.554 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:15:16.322Z,1713820516.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-04-22T21:15:16.322Z,1713820516.322 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:15:16.333Z,1713820516.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:15:16.788Z,1713820516.788 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:15:16.788Z,1713820516.788 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-04-22T21:15:17.099Z,1713820517.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:15:32.046Z,1713820532.046 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:15:37.613Z,1713820537.613 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T21:15:37.613Z,1713820537.613 [CommandExec](IMPORTANT): Failed components: 2024-04-22T21:15:37.613Z,1713820537.613 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T21:15:47.009Z,1713820547.009 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:16:01.944Z,1713820561.944 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:16:52.088Z,1713820612.088 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-22T21:11:51.9Z 2024-04-22T21:16:52.088Z,1713820612.088 [Default:CheckIn:Read_GPS] Stopped 2024-04-22T21:16:52.088Z,1713820612.088 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-22T21:16:52.489Z,1713820612.489 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-22T21:16:56.107Z,1713820616.107 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-22T21:16:56.107Z,1713820616.107 [NAL9602] Data Fault, FailCount= 1 2024-04-22T21:16:56.107Z,1713820616.107 [NAL9602](ERROR): Data Fault 2024-04-22T21:16:56.167Z,1713820616.167 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-22T21:16:56.510Z,1713820616.510 [NAL9602](INFO): Powering down 2024-04-22T21:16:57.368Z,1713820617.368 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-22T21:16:57.368Z,1713820617.368 [NAL9602] No Fault, FailCount= 1 2024-04-22T21:16:58.605Z,1713820618.605 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240422T210602/Courier0004.lzma 2024-04-22T21:16:59.598Z,1713820619.598 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Courier0004.lzma.bak 2024-04-22T21:16:59.598Z,1713820619.598 [DataOverHttps](INFO): SBD MOMSN=19421674 2024-04-22T21:17:16.884Z,1713820636.884 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20240422T210602/Express0005.lzma 2024-04-22T21:17:17.886Z,1713820637.886 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Express0005.lzma.bak 2024-04-22T21:17:17.886Z,1713820637.886 [DataOverHttps](INFO): SBD MOMSN=19421676 2024-04-22T21:17:20.763Z,1713820640.763 [Default:CheckIn:Read_Iridium] Stopped 2024-04-22T21:17:20.763Z,1713820640.763 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-22T21:17:20.763Z,1713820640.763 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-22T21:17:26.811Z,1713820646.811 [NAL9602](INFO): Powering up NAL9602 2024-04-22T21:17:37.723Z,1713820657.723 [NAL9602](INFO): NAL9602 initialized 2024-04-22T21:18:17.355Z,1713820697.355 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-04-22T21:18:17.355Z,1713820697.355 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:18:17.365Z,1713820697.365 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:18:17.739Z,1713820697.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:18:17.740Z,1713820697.740 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-04-22T21:19:12.727Z,1713820752.727 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,.99 2024-04-22T21:19:12.728Z,1713820752.728 [DVL_micro](ERROR): Failed to parse: :RD,.99 2024-04-22T21:21:18.422Z,1713820878.422 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-04-22T21:21:18.422Z,1713820878.422 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:21:18.433Z,1713820878.433 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:21:18.855Z,1713820878.855 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:21:18.855Z,1713820878.855 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-04-22T21:22:21.442Z,1713820941.442 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-22T21:22:21.442Z,1713820941.442 [Default:CheckIn:C.Wait] Stopped 2024-04-22T21:22:21.442Z,1713820941.442 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-22T21:22:21.442Z,1713820941.442 [Default:CheckIn:D] Running Loop=1 2024-04-22T21:22:21.849Z,1713820941.849 [Default:CheckIn:D] Stopped 2024-04-22T21:22:21.849Z,1713820941.849 [Default:CheckIn:E] Running Loop=1 2024-04-22T21:22:22.263Z,1713820942.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.727834 min 2024-04-22T21:22:22.263Z,1713820942.263 [Default:CheckIn:E] Stopped 2024-04-22T21:22:22.263Z,1713820942.263 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-22T21:22:22.263Z,1713820942.263 [Default:CheckIn] Stopped 2024-04-22T21:22:22.263Z,1713820942.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-22T21:22:22.263Z,1713820942.263 [Default:CheckIn](INFO): Running loop #2 2024-04-22T21:22:22.264Z,1713820942.264 [Default:CheckIn] Running Loop=2 2024-04-22T21:22:22.264Z,1713820942.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-22T21:22:22.264Z,1713820942.264 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-22T21:23:32.130Z,1713821012.130 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:23:37.787Z,1713821017.787 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:23:52.740Z,1713821032.740 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:24:07.683Z,1713821047.683 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:24:19.453Z,1713821059.453 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-04-22T21:24:19.453Z,1713821059.453 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:24:19.480Z,1713821059.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:24:19.843Z,1713821059.843 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:24:19.843Z,1713821059.843 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-04-22T21:24:22.645Z,1713821062.645 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:24:37.176Z,1713821077.176 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:24:51.719Z,1713821091.719 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:25:11.920Z,1713821111.920 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:25:12.969Z,1713821112.969 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T21:25:12.969Z,1713821112.969 [CommandExec](IMPORTANT): Failed components: 2024-04-22T21:25:12.969Z,1713821112.969 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T21:25:27.323Z,1713821127.323 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:25:33.332Z,1713821133.332 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:25:54.743Z,1713821154.743 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:27:20.426Z,1713821240.426 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-04-22T21:27:20.426Z,1713821240.426 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:27:20.438Z,1713821240.438 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:27:20.815Z,1713821240.815 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:27:20.815Z,1713821240.815 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-04-22T21:27:22.430Z,1713821242.430 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-22T21:22:22.3Z 2024-04-22T21:27:22.430Z,1713821242.430 [Default:CheckIn:Read_GPS] Stopped 2024-04-22T21:27:22.430Z,1713821242.430 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-22T21:27:29.704Z,1713821249.704 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240422T210602/Courier0007.lzma 2024-04-22T21:27:30.706Z,1713821250.706 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Courier0007.lzma.bak 2024-04-22T21:27:30.706Z,1713821250.706 [DataOverHttps](INFO): SBD MOMSN=19421729 2024-04-22T21:27:40.613Z,1713821260.613 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-22T21:27:40.613Z,1713821260.613 [NAL9602] Data Fault, FailCount= 2 2024-04-22T21:27:40.613Z,1713821260.613 [NAL9602](ERROR): Data Fault 2024-04-22T21:27:40.630Z,1713821260.630 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-22T21:27:41.002Z,1713821261.002 [NAL9602](INFO): Powering down 2024-04-22T21:27:41.824Z,1713821261.824 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-22T21:27:41.824Z,1713821261.824 [NAL9602] No Fault, FailCount= 2 2024-04-22T21:27:48.153Z,1713821268.153 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20240422T210602/Express0008.lzma 2024-04-22T21:27:49.154Z,1713821269.154 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Express0008.lzma.bak 2024-04-22T21:27:49.155Z,1713821269.155 [DataOverHttps](INFO): SBD MOMSN=19421731 2024-04-22T21:27:51.939Z,1713821271.939 [Default:CheckIn:Read_Iridium] Stopped 2024-04-22T21:27:51.939Z,1713821271.939 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-22T21:27:51.939Z,1713821271.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-22T21:28:11.307Z,1713821291.307 [NAL9602](INFO): Powering up NAL9602 2024-04-22T21:28:22.211Z,1713821302.211 [NAL9602](INFO): NAL9602 initialized 2024-04-22T21:28:34.383Z,1713821314.383 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:30:00.857Z,1713821400.857 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:30:21.418Z,1713821421.418 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-04-22T21:30:21.418Z,1713821421.418 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:30:21.429Z,1713821421.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:30:21.836Z,1713821421.836 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:30:21.836Z,1713821421.836 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-04-22T21:31:13.160Z,1713821473.160 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:31:41.844Z,1713821501.844 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:32:05.684Z,1713821525.684 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-04-22T21:32:10.607Z,1713821530.607 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:32:52.519Z,1713821572.519 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-22T21:32:52.519Z,1713821572.519 [Default:CheckIn:C.Wait] Stopped 2024-04-22T21:32:52.519Z,1713821572.519 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-22T21:32:52.519Z,1713821572.519 [Default:CheckIn:D] Running Loop=1 2024-04-22T21:32:52.941Z,1713821572.941 [Default:CheckIn:D] Stopped 2024-04-22T21:32:52.941Z,1713821572.941 [Default:CheckIn:E] Running Loop=1 2024-04-22T21:32:53.414Z,1713821573.414 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.246033 min 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn:E] Stopped 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn] Stopped 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn](INFO): Running loop #3 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn] Running Loop=3 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-22T21:32:53.415Z,1713821573.415 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-22T21:33:18.756Z,1713821598.756 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:33:22.438Z,1713821602.438 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2024-04-22T21:33:22.438Z,1713821602.438 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:33:22.489Z,1713821602.489 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:33:22.859Z,1713821602.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:33:22.859Z,1713821602.859 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2024-04-22T21:33:25.232Z,1713821605.232 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-04-22T21:33:38.597Z,1713821618.597 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:33:46.226Z,1713821626.226 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:34:07.640Z,1713821647.640 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:34:18.198Z,1713821658.198 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:34:29.050Z,1713821669.050 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:34:51.271Z,1713821691.271 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:34:57.404Z,1713821697.404 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-04-22T21:35:12.682Z,1713821712.682 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:35:34.096Z,1713821734.096 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-22T21:35:49.385Z,1713821749.385 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T21:35:49.385Z,1713821749.385 [CommandExec](IMPORTANT): Failed components: 2024-04-22T21:35:49.385Z,1713821749.385 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T21:36:23.418Z,1713821783.418 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2024-04-22T21:36:23.418Z,1713821783.418 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:36:23.429Z,1713821783.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:36:23.835Z,1713821783.835 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:36:23.835Z,1713821783.835 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2024-04-22T21:37:14.693Z,1713821834.693 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 4. 2024-04-22T21:37:14.697Z,1713821834.697 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2024-04-22T21:37:53.515Z,1713821873.515 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-22T21:32:53.4Z 2024-04-22T21:37:53.515Z,1713821873.515 [Default:CheckIn:Read_GPS] Stopped 2024-04-22T21:37:53.515Z,1713821873.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-22T21:38:01.308Z,1713821881.308 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240422T210602/Courier0010.lzma 2024-04-22T21:38:02.310Z,1713821882.310 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Courier0010.lzma.bak 2024-04-22T21:38:02.310Z,1713821882.310 [DataOverHttps](INFO): SBD MOMSN=19421796 2024-04-22T21:38:19.708Z,1713821899.708 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20240422T210602/Express0011.lzma 2024-04-22T21:38:20.710Z,1713821900.710 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Express0011.lzma.bak 2024-04-22T21:38:20.711Z,1713821900.711 [DataOverHttps](INFO): SBD MOMSN=19421798 2024-04-22T21:38:23.435Z,1713821903.435 [Default:CheckIn:Read_Iridium] Stopped 2024-04-22T21:38:23.436Z,1713821903.436 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-22T21:38:23.436Z,1713821903.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-22T21:38:24.611Z,1713821904.611 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-22T21:38:24.611Z,1713821904.611 [NAL9602] Data Fault, FailCount= 3 2024-04-22T21:38:24.611Z,1713821904.611 [NAL9602](ERROR): Data Fault 2024-04-22T21:38:24.691Z,1713821904.691 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-22T21:38:25.014Z,1713821905.014 [NAL9602](INFO): Powering down 2024-04-22T21:38:25.843Z,1713821905.843 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-22T21:38:25.843Z,1713821905.843 [NAL9602] No Fault, FailCount= 3 2024-04-22T21:38:55.319Z,1713821935.319 [NAL9602](INFO): Powering up NAL9602 2024-04-22T21:39:06.228Z,1713821946.228 [NAL9602](INFO): NAL9602 initialized 2024-04-22T21:39:24.437Z,1713821964.437 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2024-04-22T21:39:24.437Z,1713821964.437 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:39:24.447Z,1713821964.447 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:39:24.876Z,1713821964.876 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:39:24.876Z,1713821964.876 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2024-04-22T21:41:21.632Z,1713822081.632 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00 2024-04-22T21:42:25.480Z,1713822145.480 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2024-04-22T21:42:25.480Z,1713822145.480 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:42:25.491Z,1713822145.491 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:42:25.904Z,1713822145.904 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:42:25.904Z,1713822145.904 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2024-04-22T21:43:24.051Z,1713822204.051 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-22T21:43:24.051Z,1713822204.051 [Default:CheckIn:C.Wait] Stopped 2024-04-22T21:43:24.051Z,1713822204.051 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-22T21:43:24.051Z,1713822204.051 [Default:CheckIn:D] Running Loop=1 2024-04-22T21:43:24.465Z,1713822204.465 [Default:CheckIn:D] Stopped 2024-04-22T21:43:24.465Z,1713822204.465 [Default:CheckIn:E] Running Loop=1 2024-04-22T21:43:24.863Z,1713822204.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.771442 min 2024-04-22T21:43:24.863Z,1713822204.863 [Default:CheckIn:E] Stopped 2024-04-22T21:43:24.863Z,1713822204.863 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-22T21:43:24.863Z,1713822204.863 [Default:CheckIn] Stopped 2024-04-22T21:43:24.863Z,1713822204.863 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-22T21:43:24.864Z,1713822204.864 [Default:CheckIn](INFO): Running loop #4 2024-04-22T21:43:24.864Z,1713822204.864 [Default:CheckIn] Running Loop=4 2024-04-22T21:43:24.864Z,1713822204.864 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-22T21:43:24.864Z,1713822204.864 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-22T21:45:26.476Z,1713822326.476 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2024-04-22T21:45:26.476Z,1713822326.476 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:45:26.487Z,1713822326.487 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:45:26.879Z,1713822326.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:45:26.879Z,1713822326.879 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2024-04-22T21:48:25.090Z,1713822505.090 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-22T21:43:24.9Z 2024-04-22T21:48:25.090Z,1713822505.090 [Default:CheckIn:Read_GPS] Stopped 2024-04-22T21:48:25.090Z,1713822505.090 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-22T21:48:27.481Z,1713822507.481 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2024-04-22T21:48:27.481Z,1713822507.481 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:48:27.496Z,1713822507.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:48:27.899Z,1713822507.899 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:48:27.899Z,1713822507.899 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2024-04-22T21:48:32.762Z,1713822512.762 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240422T210602/Courier0013.lzma 2024-04-22T21:48:33.762Z,1713822513.762 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Courier0013.lzma.bak 2024-04-22T21:48:33.762Z,1713822513.762 [DataOverHttps](INFO): SBD MOMSN=19421813 2024-04-22T21:48:51.184Z,1713822531.184 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240422T210602/Express0014.lzma 2024-04-22T21:48:52.186Z,1713822532.186 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Express0014.lzma.bak 2024-04-22T21:48:52.187Z,1713822532.187 [DataOverHttps](INFO): SBD MOMSN=19421815 2024-04-22T21:48:54.950Z,1713822534.950 [Default:CheckIn:Read_Iridium] Stopped 2024-04-22T21:48:54.950Z,1713822534.950 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-22T21:48:54.950Z,1713822534.950 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-22T21:49:07.038Z,1713822547.038 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-22T21:49:07.038Z,1713822547.038 [NAL9602] Data Fault, FailCount= 4 2024-04-22T21:49:07.038Z,1713822547.038 [NAL9602](ERROR): Data Fault 2024-04-22T21:49:07.118Z,1713822547.118 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-22T21:49:07.451Z,1713822547.451 [NAL9602](INFO): Powering down 2024-04-22T21:49:08.295Z,1713822548.295 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-22T21:49:08.296Z,1713822548.296 [NAL9602] No Fault, FailCount= 4 2024-04-22T21:49:37.747Z,1713822577.747 [NAL9602](INFO): Powering up NAL9602 2024-04-22T21:49:48.651Z,1713822588.651 [NAL9602](INFO): NAL9602 initialized 2024-04-22T21:51:16.982Z,1713822676.982 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T21:51:16.982Z,1713822676.982 [CommandExec](IMPORTANT): Failed components: 2024-04-22T21:51:16.982Z,1713822676.982 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T21:51:28.534Z,1713822688.534 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2024-04-22T21:51:28.534Z,1713822688.534 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:51:28.569Z,1713822688.569 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:51:28.938Z,1713822688.938 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:51:28.939Z,1713822688.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2024-04-22T21:52:20.741Z,1713822740.741 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T21:52:20.741Z,1713822740.741 [CommandExec](IMPORTANT): Failed components: 2024-04-22T21:52:20.742Z,1713822740.742 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T21:53:55.579Z,1713822835.579 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-22T21:53:55.579Z,1713822835.579 [Default:CheckIn:C.Wait] Stopped 2024-04-22T21:53:55.579Z,1713822835.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-22T21:53:55.579Z,1713822835.579 [Default:CheckIn:D] Running Loop=1 2024-04-22T21:53:55.973Z,1713822835.973 [Default:CheckIn:D] Stopped 2024-04-22T21:53:55.973Z,1713822835.973 [Default:CheckIn:E] Running Loop=1 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.296574 min 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn:E] Stopped 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn] Stopped 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn](INFO): Running loop #5 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn] Running Loop=5 2024-04-22T21:53:56.378Z,1713822836.378 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-22T21:53:56.379Z,1713822836.379 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-22T21:54:29.502Z,1713822869.502 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2024-04-22T21:54:29.502Z,1713822869.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:54:29.513Z,1713822869.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:54:29.919Z,1713822869.919 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:54:29.919Z,1713822869.919 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2024-04-22T21:57:30.504Z,1713823050.504 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2024-04-22T21:57:30.504Z,1713823050.504 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T21:57:30.515Z,1713823050.515 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T21:57:30.922Z,1713823050.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T21:57:30.922Z,1713823050.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2024-04-22T21:58:56.543Z,1713823136.543 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-22T21:53:56.4Z 2024-04-22T21:58:56.543Z,1713823136.543 [Default:CheckIn:Read_GPS] Stopped 2024-04-22T21:58:56.543Z,1713823136.543 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-22T21:59:03.520Z,1713823143.520 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240422T210602/Courier0016.lzma 2024-04-22T21:59:04.522Z,1713823144.522 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Courier0016.lzma.bak 2024-04-22T21:59:04.523Z,1713823144.523 [DataOverHttps](INFO): SBD MOMSN=19421821 2024-04-22T21:59:22.154Z,1713823162.154 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20240422T210602/Express0017.lzma 2024-04-22T21:59:23.082Z,1713823163.082 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Express0017.lzma.bak 2024-04-22T21:59:23.083Z,1713823163.083 [DataOverHttps](INFO): SBD MOMSN=19421823 2024-04-22T21:59:26.046Z,1713823166.046 [Default:CheckIn:Read_Iridium] Stopped 2024-04-22T21:59:26.046Z,1713823166.046 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-22T21:59:26.047Z,1713823166.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-22T21:59:49.863Z,1713823189.863 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-22T21:59:49.863Z,1713823189.863 [NAL9602] Data Fault, FailCount= 5 2024-04-22T21:59:49.863Z,1713823189.863 [NAL9602](ERROR): Data Fault 2024-04-22T21:59:49.881Z,1713823189.881 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-22T21:59:49.881Z,1713823189.881 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-04-22T21:59:50.266Z,1713823190.266 [NAL9602](INFO): Powering down 2024-04-22T21:59:50.282Z,1713823190.282 [CommandExec](FAULT): Scheduling is paused 2024-04-22T21:59:50.282Z,1713823190.282 [CBIT](INFO): Critical error at 20240422T215949 2024-04-22T21:59:50.283Z,1713823190.283 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-04-22T22:00:31.481Z,1713823231.481 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2024-04-22T22:00:31.481Z,1713823231.481 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T22:00:31.514Z,1713823231.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T22:00:31.928Z,1713823231.928 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T22:00:31.928Z,1713823231.928 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2024-04-22T22:01:49.934Z,1713823309.934 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-22T22:01:49.934Z,1713823309.934 [NAL9602] No Fault, FailCount= 5 2024-04-22T22:01:50.282Z,1713823310.282 [NAL9602](INFO): Powering up NAL9602 2024-04-22T22:02:01.186Z,1713823321.186 [NAL9602](INFO): NAL9602 initialized 2024-04-22T22:03:32.505Z,1713823412.505 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2024-04-22T22:03:32.505Z,1713823412.505 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T22:03:32.516Z,1713823412.516 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T22:03:32.971Z,1713823412.971 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T22:03:32.971Z,1713823412.971 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2024-04-22T22:04:26.666Z,1713823466.666 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-22T22:04:26.666Z,1713823466.666 [Default:CheckIn:C.Wait] Stopped 2024-04-22T22:04:26.666Z,1713823466.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-22T22:04:26.666Z,1713823466.666 [Default:CheckIn:D] Running Loop=1 2024-04-22T22:04:27.059Z,1713823467.059 [Default:CheckIn:D] Stopped 2024-04-22T22:04:27.059Z,1713823467.059 [Default:CheckIn:E] Running Loop=1 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.814673 min 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn:E] Stopped 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn] Stopped 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn](INFO): Running loop #6 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn] Running Loop=6 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-22T22:04:27.447Z,1713823467.447 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-22T22:06:33.501Z,1713823593.501 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2024-04-22T22:06:33.501Z,1713823593.501 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T22:06:33.511Z,1713823593.511 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T22:06:33.923Z,1713823593.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T22:06:33.923Z,1713823593.923 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2024-04-22T22:09:27.637Z,1713823767.637 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-22T22:04:27.4Z 2024-04-22T22:09:27.637Z,1713823767.637 [Default:CheckIn:Read_GPS] Stopped 2024-04-22T22:09:27.637Z,1713823767.637 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-22T22:09:34.486Z,1713823774.486 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2024-04-22T22:09:34.486Z,1713823774.486 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T22:09:34.517Z,1713823774.517 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T22:09:34.899Z,1713823774.899 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T22:09:34.899Z,1713823774.899 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2024-04-22T22:09:35.012Z,1713823775.012 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240422T210602/Courier0019.lzma 2024-04-22T22:09:37.419Z,1713823777.419 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Courier0019.lzma.bak 2024-04-22T22:09:37.419Z,1713823777.419 [DataOverHttps](INFO): SBD MOMSN=19421835 2024-04-22T22:09:53.824Z,1713823793.824 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20240422T210602/Express0020.lzma 2024-04-22T22:09:54.826Z,1713823794.826 [DataOverHttps](INFO): Moved sent file to Logs/20240422T210602/Express0020.lzma.bak 2024-04-22T22:09:54.827Z,1713823794.827 [DataOverHttps](INFO): SBD MOMSN=19421838 2024-04-22T22:09:57.941Z,1713823797.941 [Default:CheckIn:Read_Iridium] Stopped 2024-04-22T22:09:57.942Z,1713823797.942 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-22T22:09:57.942Z,1713823797.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-22T22:12:03.170Z,1713823923.170 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-22T22:12:03.171Z,1713823923.171 [NAL9602] Data Fault, FailCount= 1 2024-04-22T22:12:03.171Z,1713823923.171 [NAL9602](ERROR): Data Fault 2024-04-22T22:12:03.188Z,1713823923.188 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-22T22:12:03.574Z,1713823923.574 [NAL9602](INFO): Powering down 2024-04-22T22:12:04.412Z,1713823924.412 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-22T22:12:04.412Z,1713823924.412 [NAL9602] No Fault, FailCount= 1 2024-04-22T22:12:33.871Z,1713823953.871 [NAL9602](INFO): Powering up NAL9602 2024-04-22T22:12:35.497Z,1713823955.497 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2024-04-22T22:12:35.497Z,1713823955.497 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-22T22:12:35.531Z,1713823955.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-22T22:12:35.918Z,1713823955.918 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-22T22:12:35.918Z,1713823955.918 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2024-04-22T22:12:44.783Z,1713823964.783 [NAL9602](INFO): NAL9602 initialized 2024-04-22T22:13:23.453Z,1713824003.453 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T22:13:23.453Z,1713824003.453 [CommandExec](IMPORTANT): Failed components: 2024-04-22T22:13:23.454Z,1713824003.454 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T22:13:37.535Z,1713824017.535 [CommandExec](IMPORTANT): got command failComponent 2024-04-22T22:13:37.535Z,1713824017.535 [CommandExec](IMPORTANT): Failed components: 2024-04-22T22:13:37.535Z,1713824017.535 [CommandExec](IMPORTANT): No failed Components. 2024-04-22T22:13:57.631Z,1713824037.631 [CommandExec](IMPORTANT): got command restart application 2024-04-22T22:13:58.633Z,1713824038.633 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:13:58.633Z,1713824038.633 [CommandExec](INFO): Uninitializing the command executive. 2024-04-22T22:13:58.633Z,1713824038.633 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-22T22:13:58.633Z,1713824038.633 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:58.788Z,1713824038.788 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-22T22:13:58.789Z,1713824038.789 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-22T22:13:58.789Z,1713824038.789 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:58.789Z,1713824038.789 [NavChartDb](INFO): Join timeout helper Thread ID is 4143 2024-04-22T22:13:58.857Z,1713824038.857 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:13:58.857Z,1713824038.857 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:58.873Z,1713824038.873 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-22T22:13:58.873Z,1713824038.873 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:58.873Z,1713824038.873 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4144 2024-04-22T22:13:58.953Z,1713824038.953 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:13:58.953Z,1713824038.953 [WetLabsBB2FL](INFO): Powering down 2024-04-22T22:13:58.954Z,1713824038.954 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:58.969Z,1713824038.969 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-22T22:13:58.969Z,1713824038.969 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:58.969Z,1713824038.969 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4145 2024-04-22T22:13:59.033Z,1713824039.033 [CTD_Seabird](INFO): Powering down 2024-04-22T22:13:59.045Z,1713824039.045 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:13:59.045Z,1713824039.045 [CTD_Seabird](INFO): Powering down 2024-04-22T22:13:59.057Z,1713824039.057 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:59.057Z,1713824039.057 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-22T22:13:59.057Z,1713824039.057 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:59.057Z,1713824039.057 [DAT](INFO): Join timeout helper Thread ID is 4146 2024-04-22T22:13:59.169Z,1713824039.169 [DAT](INFO): Powering down 2024-04-22T22:13:59.241Z,1713824039.241 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:13:59.241Z,1713824039.241 [DAT](INFO): Powering down 2024-04-22T22:13:59.242Z,1713824039.242 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:59.256Z,1713824039.256 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-22T22:13:59.257Z,1713824039.257 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:59.257Z,1713824039.257 [Radio_Surface](INFO): Join timeout helper Thread ID is 4147 2024-04-22T22:13:59.261Z,1713824039.261 [Radio_Surface](INFO): Powering down 2024-04-22T22:13:59.262Z,1713824039.262 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:13:59.262Z,1713824039.262 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:59.278Z,1713824039.278 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-04-22T22:13:59.278Z,1713824039.278 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:13:59.278Z,1713824039.278 [Onboard](INFO): Join timeout helper Thread ID is 4148 2024-04-22T22:13:59.421Z,1713824039.421 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-04-22T22:14:02.529Z,1713824042.529 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:14:02.529Z,1713824042.529 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:02.541Z,1713824042.541 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-04-22T22:14:02.541Z,1713824042.541 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:02.542Z,1713824042.542 [DataOverHttps](INFO): Join timeout helper Thread ID is 4149 2024-04-22T22:14:03.437Z,1713824043.437 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:14:03.437Z,1713824043.437 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.457Z,1713824043.457 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-04-22T22:14:03.457Z,1713824043.457 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.464Z,1713824043.464 [BackseatComponent](INFO): Join timeout helper Thread ID is 4150 2024-04-22T22:14:03.521Z,1713824043.521 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:14:03.521Z,1713824043.521 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.541Z,1713824043.541 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-04-22T22:14:03.541Z,1713824043.541 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.542Z,1713824043.542 [logger](INFO): Join timeout helper Thread ID is 4151 2024-04-22T22:14:03.557Z,1713824043.557 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:14:03.557Z,1713824043.557 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.561Z,1713824043.561 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-04-22T22:14:03.561Z,1713824043.561 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.562Z,1713824043.562 [CommandLine](INFO): Join timeout helper Thread ID is 4152 2024-04-22T22:14:03.629Z,1713824043.629 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:14:03.629Z,1713824043.629 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.641Z,1713824043.641 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-04-22T22:14:03.641Z,1713824043.641 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.642Z,1713824043.642 [CommandExec](INFO): Join timeout helper Thread ID is 4153 2024-04-22T22:14:03.643Z,1713824043.643 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-04-22T22:14:03.643Z,1713824043.643 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:03.643Z,1713824043.643 [controlThread](INFO): Join timeout helper Thread ID is 4154 2024-04-22T22:14:03.949Z,1713824043.949 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-22T22:14:03.949Z,1713824043.949 [controlThread](DEBUG): Uninitializing ControlThread 2024-04-22T22:14:03.949Z,1713824043.949 [AHRS_M2](INFO): Powering down 2024-04-22T22:14:04.117Z,1713824044.117 [DVL_micro](INFO): Powering down 2024-04-22T22:14:04.118Z,1713824044.118 [NAL9602](INFO): Powering down 2024-04-22T22:14:04.119Z,1713824044.119 [Sonardyne_Nano](INFO): Powering down 2024-04-22T22:14:04.239Z,1713824044.239 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-04-22T22:14:04.239Z,1713824044.239 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-04-22T22:14:04.240Z,1713824044.240 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-04-22T22:14:04.241Z,1713824044.241 [MissionManager](INFO): Uninitializing Mission Default 2024-04-22T22:14:04.241Z,1713824044.241 [Default] Stopped 2024-04-22T22:14:04.241Z,1713824044.241 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-22T22:14:04.241Z,1713824044.241 [Default:B.GoToSurface] Stopped 2024-04-22T22:14:04.241Z,1713824044.241 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-22T22:14:04.241Z,1713824044.241 [Default:CheckIn] Stopped 2024-04-22T22:14:04.241Z,1713824044.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-22T22:14:04.241Z,1713824044.241 [Default:CheckIn:C.Wait] Stopped 2024-04-22T22:14:04.241Z,1713824044.241 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-22T22:14:04.244Z,1713824044.244 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-04-22T22:14:04.244Z,1713824044.244 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-04-22T22:14:04.245Z,1713824044.245 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-04-22T22:14:04.245Z,1713824044.245 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-04-22T22:14:04.245Z,1713824044.245 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-04-22T22:14:04.245Z,1713824044.245 [BuoyancyServo](INFO): Powering down 2024-04-22T22:14:04.257Z,1713824044.257 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-04-22T22:14:04.257Z,1713824044.257 [ElevatorServo](INFO): Powering down 2024-04-22T22:14:04.258Z,1713824044.258 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-04-22T22:14:04.258Z,1713824044.258 [MassServo](INFO): Powering down 2024-04-22T22:14:04.259Z,1713824044.259 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-04-22T22:14:04.259Z,1713824044.259 [RudderServo](INFO): Powering down 2024-04-22T22:14:04.259Z,1713824044.259 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-04-22T22:14:04.260Z,1713824044.260 [ThrusterHE](INFO): Powering down 2024-04-22T22:14:04.261Z,1713824044.261 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-04-22T22:14:04.261Z,1713824044.261 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-04-22T22:14:04.261Z,1713824044.261 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-04-22T22:14:04.261Z,1713824044.261 [CBIT](DEBUG): Powering off loads. 2024-04-22T22:14:04.272Z,1713824044.272 [CBIT](DEBUG): Disabling WDT. 2024-04-22T22:14:04.284Z,1713824044.284 [CBIT](DEBUG): Opening all GF detection circuits. 2024-04-22T22:14:04.286Z,1713824044.286 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.338Z,1713824044.338 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.344Z,1713824044.344 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.346Z,1713824044.346 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.353Z,1713824044.353 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.397Z,1713824044.397 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.449Z,1713824044.449 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.455Z,1713824044.455 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.476Z,1713824044.476 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-22T22:14:04.525Z,1713824044.525 [logger ThreadHandler](INFO): Thread cancelled.