2024-04-23T23:46:11.917Z,1713915971.917 [Supervisor](DEBUG): Initializing supervisor. 2024-04-23T23:46:11.922Z,1713915971.922 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-23T23:46:11.922Z,1713915971.922 [SyncHandler](INFO): Protected caller Thread ID is 838 2024-04-23T23:46:11.923Z,1713915971.923 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-23T23:46:11.924Z,1713915971.924 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-23T23:46:11.924Z,1713915971.924 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839 2024-04-23T23:46:11.928Z,1713915971.928 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-23T23:46:11.947Z,1713915971.947 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-23T23:46:11.948Z,1713915971.948 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-23T23:46:11.948Z,1713915971.948 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840 2024-04-23T23:46:11.953Z,1713915971.953 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-23T23:46:11.954Z,1713915971.954 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-23T23:46:11.954Z,1713915971.954 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841 2024-04-23T23:46:11.956Z,1713915971.956 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-23T23:46:11.957Z,1713915971.957 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-23T23:46:11.958Z,1713915971.958 [logger ThreadHandler](INFO): Protected caller Thread ID is 842 2024-04-23T23:46:11.962Z,1713915971.962 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-23T23:46:11.962Z,1713915971.962 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-23T23:46:11.965Z,1713915971.965 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-23T23:46:12.193Z,1713915972.193 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-23T23:46:12.195Z,1713915972.195 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-23T23:46:12.304Z,1713915972.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-23T23:46:12.306Z,1713915972.306 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-23T23:46:12.403Z,1713915972.403 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-23T23:46:12.405Z,1713915972.405 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-23T23:46:12.934Z,1713915972.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-23T23:46:12.936Z,1713915972.936 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-23T23:46:13.303Z,1713915973.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-23T23:46:13.305Z,1713915973.305 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-23T23:46:13.652Z,1713915973.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-23T23:46:13.653Z,1713915973.653 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-23T23:46:13.848Z,1713915973.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-23T23:46:13.850Z,1713915973.850 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-23T23:46:13.931Z,1713915973.931 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-23T23:46:14.014Z,1713915974.014 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-23T23:46:14.438Z,1713915974.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-23T23:46:14.440Z,1713915974.440 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-23T23:46:14.728Z,1713915974.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-23T23:46:14.728Z,1713915974.728 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-23T23:46:15.521Z,1713915975.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-23T23:46:15.522Z,1713915975.522 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-23T23:46:16.182Z,1713915976.182 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-23T23:46:16.184Z,1713915976.184 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-23T23:46:16.433Z,1713915976.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-23T23:46:16.434Z,1713915976.434 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-23T23:46:16.574Z,1713915976.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-23T23:46:16.576Z,1713915976.576 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-23T23:46:17.429Z,1713915977.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-23T23:46:17.431Z,1713915977.431 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2024-04-23T23:46:17.434Z,1713915977.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2024-04-23T23:46:17.520Z,1713915977.520 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2024-04-23T23:46:17.648Z,1713915977.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2024-04-23T23:46:17.915Z,1713915977.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-23T23:46:17.916Z,1713915977.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2024-04-23T23:46:18.042Z,1713915978.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2024-04-23T23:46:18.144Z,1713915978.144 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2024-04-23T23:46:18.239Z,1713915978.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2024-04-23T23:46:18.347Z,1713915978.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2024-04-23T23:46:18.444Z,1713915978.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2024-04-23T23:46:18.530Z,1713915978.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2024-04-23T23:46:18.621Z,1713915978.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/workSite.cfg 2024-04-23T23:46:18.705Z,1713915978.705 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2024-04-23T23:46:18.872Z,1713915978.872 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-04-23T23:46:18.883Z,1713915978.883 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-23T23:46:18.955Z,1713915978.955 [DepthRateCalculator] Loaded 2024-04-23T23:46:18.955Z,1713915978.955 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-23T23:46:18.961Z,1713915978.961 [PitchRateCalculator] Loaded 2024-04-23T23:46:18.961Z,1713915978.961 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-23T23:46:18.972Z,1713915978.972 [SpeedCalculator] Loaded 2024-04-23T23:46:18.972Z,1713915978.972 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-23T23:46:18.977Z,1713915978.977 [YawRateCalculator] Loaded 2024-04-23T23:46:18.977Z,1713915978.977 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-23T23:46:18.999Z,1713915978.999 [ElevatorOffsetCalculator] Loaded 2024-04-23T23:46:18.999Z,1713915978.999 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-23T23:46:19.000Z,1713915979.000 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-23T23:46:19.002Z,1713915979.002 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-23T23:46:19.045Z,1713915979.045 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-23T23:46:19.047Z,1713915979.047 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-23T23:46:19.063Z,1713915979.063 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-23T23:46:19.063Z,1713915979.063 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-23T23:46:19.834Z,1713915979.834 [AHRS_M2] Loaded 2024-04-23T23:46:19.834Z,1713915979.834 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-23T23:46:20.084Z,1713915980.084 [BackseatComponent] Loaded 2024-04-23T23:46:20.084Z,1713915980.084 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-04-23T23:46:20.085Z,1713915980.085 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4084E4E0 2024-04-23T23:46:20.086Z,1713915980.086 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 928 2024-04-23T23:46:20.088Z,1713915980.088 [LcmUniversalReporter] Loaded 2024-04-23T23:46:20.089Z,1713915980.089 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-04-23T23:46:20.877Z,1713915980.877 [BPC1] Loaded 2024-04-23T23:46:20.877Z,1713915980.877 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-23T23:46:21.025Z,1713915981.025 [DataOverHttps] Loaded 2024-04-23T23:46:21.026Z,1713915981.026 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-23T23:46:21.027Z,1713915981.027 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087E4E0 2024-04-23T23:46:21.027Z,1713915981.027 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 929 2024-04-23T23:46:21.048Z,1713915981.048 [Depth_Keller] Loaded 2024-04-23T23:46:21.048Z,1713915981.048 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-23T23:46:21.053Z,1713915981.053 [DropWeight] Loaded 2024-04-23T23:46:21.053Z,1713915981.053 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-23T23:46:21.071Z,1713915981.071 [MultiRay] Loaded 2024-04-23T23:46:21.072Z,1713915981.072 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2024-04-23T23:46:21.133Z,1713915981.133 [NAL9602] Loaded 2024-04-23T23:46:21.133Z,1713915981.133 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-23T23:46:21.163Z,1713915981.163 [Onboard] Loaded 2024-04-23T23:46:21.163Z,1713915981.163 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-23T23:46:21.164Z,1713915981.164 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AE4E0 2024-04-23T23:46:21.164Z,1713915981.164 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 930 2024-04-23T23:46:21.170Z,1713915981.170 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2024-04-23T23:46:21.182Z,1713915981.182 [PowerOnly] Loaded 2024-04-23T23:46:21.182Z,1713915981.182 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-04-23T23:46:21.264Z,1713915981.264 [Power24vConverter] Loaded 2024-04-23T23:46:21.265Z,1713915981.265 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-23T23:46:21.290Z,1713915981.290 [Radio_Surface] Loaded 2024-04-23T23:46:21.291Z,1713915981.291 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-23T23:46:21.292Z,1713915981.292 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DE4E0 2024-04-23T23:46:21.300Z,1713915981.300 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 931 2024-04-23T23:46:21.604Z,1713915981.604 [DAT] Loaded 2024-04-23T23:46:21.605Z,1713915981.605 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-23T23:46:21.606Z,1713915981.606 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0 2024-04-23T23:46:21.606Z,1713915981.606 [DAT ThreadHandler](INFO): Protected caller Thread ID is 932 2024-04-23T23:46:21.606Z,1713915981.606 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-23T23:46:21.607Z,1713915981.607 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-23T23:46:22.060Z,1713915982.060 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-23T23:46:22.061Z,1713915982.061 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-23T23:46:22.102Z,1713915982.102 [DeadReckonUsingSpeedCalculator] Loaded 2024-04-23T23:46:22.103Z,1713915982.103 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-04-23T23:46:22.117Z,1713915982.117 [NavChart] Loaded 2024-04-23T23:46:22.117Z,1713915982.117 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-23T23:46:22.122Z,1713915982.122 [UniversalFixResidualReporter] Loaded 2024-04-23T23:46:22.123Z,1713915982.123 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-23T23:46:22.123Z,1713915982.123 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-23T23:46:22.125Z,1713915982.125 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-23T23:46:22.298Z,1713915982.298 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-23T23:46:22.313Z,1713915982.313 [SBIT] Loaded 2024-04-23T23:46:22.313Z,1713915982.313 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-23T23:46:22.316Z,1713915982.316 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-23T23:46:22.330Z,1713915982.330 [IBIT] Loaded 2024-04-23T23:46:22.330Z,1713915982.330 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-23T23:46:22.336Z,1713915982.336 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-23T23:46:22.441Z,1713915982.441 [CBIT] Loaded 2024-04-23T23:46:22.442Z,1713915982.442 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-23T23:46:22.442Z,1713915982.442 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-23T23:46:22.443Z,1713915982.443 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-23T23:46:22.663Z,1713915982.663 [CTD_Seabird] Loaded 2024-04-23T23:46:22.663Z,1713915982.663 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-23T23:46:22.664Z,1713915982.664 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A164E0 2024-04-23T23:46:22.665Z,1713915982.665 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 933 2024-04-23T23:46:22.685Z,1713915982.685 [PAR_Licor] Loaded 2024-04-23T23:46:22.685Z,1713915982.685 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-23T23:46:22.727Z,1713915982.727 [WetLabsBB2FL] Loaded 2024-04-23T23:46:22.727Z,1713915982.727 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-23T23:46:22.728Z,1713915982.728 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2024-04-23T23:46:22.729Z,1713915982.729 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 934 2024-04-23T23:46:22.730Z,1713915982.730 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-23T23:46:22.730Z,1713915982.730 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-23T23:46:22.894Z,1713915982.894 [BuoyancyServo] Loaded 2024-04-23T23:46:22.894Z,1713915982.894 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-23T23:46:22.916Z,1713915982.916 [ElevatorServo] Loaded 2024-04-23T23:46:22.916Z,1713915982.916 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-23T23:46:22.937Z,1713915982.937 [MassServo] Loaded 2024-04-23T23:46:22.938Z,1713915982.938 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-23T23:46:22.958Z,1713915982.958 [RudderServo] Loaded 2024-04-23T23:46:22.958Z,1713915982.958 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-23T23:46:22.974Z,1713915982.974 [ThrusterHE] Loaded 2024-04-23T23:46:22.974Z,1713915982.974 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-23T23:46:22.975Z,1713915982.975 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-23T23:46:22.977Z,1713915982.977 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-23T23:46:23.048Z,1713915983.048 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-23T23:46:23.100Z,1713915983.100 [VerticalControl] Loaded 2024-04-23T23:46:23.100Z,1713915983.100 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-23T23:46:23.103Z,1713915983.103 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-23T23:46:23.143Z,1713915983.143 [HorizontalControl] Loaded 2024-04-23T23:46:23.143Z,1713915983.143 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-23T23:46:23.146Z,1713915983.146 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-23T23:46:23.147Z,1713915983.147 [SpeedControl] Loaded 2024-04-23T23:46:23.148Z,1713915983.148 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-23T23:46:23.150Z,1713915983.150 [LoopControl](DEBUG): Construct LoopControl. 2024-04-23T23:46:23.151Z,1713915983.151 [LoopControl] Loaded 2024-04-23T23:46:23.151Z,1713915983.151 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-23T23:46:23.152Z,1713915983.152 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-23T23:46:23.152Z,1713915983.152 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-23T23:46:23.296Z,1713915983.296 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-23T23:46:23.297Z,1713915983.297 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-23T23:46:23.483Z,1713915983.483 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-23T23:46:23.483Z,1713915983.483 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-23T23:46:23.885Z,1713915983.885 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-23T23:46:23.885Z,1713915983.885 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-23T23:46:23.970Z,1713915983.970 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-23T23:46:23.977Z,1713915983.977 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-23T23:46:23.980Z,1713915983.980 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-23T23:46:23.991Z,1713915983.991 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-23T23:46:23.993Z,1713915983.993 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C234E0 2024-04-23T23:46:23.993Z,1713915983.993 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 935 2024-04-23T23:46:23.998Z,1713915983.998 [Supervisor](INFO): Main Thread ID is 830 2024-04-23T23:46:23.998Z,1713915983.998 [Supervisor](DEBUG): Running supervisor. 2024-04-23T23:46:23.998Z,1713915983.998 [CommandExec ThreadHandler](INFO): Handler Thread ID is 936 2024-04-23T23:46:23.999Z,1713915983.999 [CommandExec](INFO): Initializing the command executive. 2024-04-23T23:46:24.000Z,1713915984.000 [CommandLine ThreadHandler](INFO): Handler Thread ID is 937 2024-04-23T23:46:24.002Z,1713915984.002 [controlThread ThreadHandler](INFO): Handler Thread ID is 938 2024-04-23T23:46:24.003Z,1713915984.003 [controlThread](DEBUG): Initializing ControlThread 2024-04-23T23:46:24.004Z,1713915984.004 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-23T23:46:24.004Z,1713915984.004 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-23T23:46:24.005Z,1713915984.005 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-23T23:46:24.005Z,1713915984.005 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-23T23:46:24.005Z,1713915984.005 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-23T23:46:24.011Z,1713915984.011 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-23T23:46:24.011Z,1713915984.011 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-23T23:46:24.012Z,1713915984.012 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-23T23:46:24.012Z,1713915984.012 [SBIT](INFO): Initialize SBIT Component. 2024-04-23T23:46:24.013Z,1713915984.013 [SBIT](IMPORTANT): git: 2024-03-26 2024-04-23T23:46:24.013Z,1713915984.013 [SBIT](INFO): git hash: ad310a373d447f5044d653e05ec2c3eb1a52d830 2024-04-23T23:46:24.013Z,1713915984.013 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-23T23:46:24.014Z,1713915984.014 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-04-23T23:46:24.015Z,1713915984.015 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2024-04-23T23:46:24.016Z,1713915984.016 [IBIT](INFO): Initialize IBIT Component. 2024-04-23T23:46:24.017Z,1713915984.017 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-23T23:46:24.018Z,1713915984.018 [logger ThreadHandler](INFO): Handler Thread ID is 939 2024-04-23T23:46:24.028Z,1713915984.028 [CBIT](DEBUG): Initialized mux pins. 2024-04-23T23:46:24.028Z,1713915984.028 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-23T23:46:24.036Z,1713915984.036 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 940 2024-04-23T23:46:24.048Z,1713915984.048 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 941 2024-04-23T23:46:24.050Z,1713915984.050 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-23T23:46:24.052Z,1713915984.052 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-04-23T23:46:24.052Z,1713915984.052 [CBIT](DEBUG): Initializing heartbeat. 2024-04-23T23:46:24.060Z,1713915984.060 [Onboard ThreadHandler](INFO): Handler Thread ID is 942 2024-04-23T23:46:24.097Z,1713915984.097 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 943 2024-04-23T23:46:24.112Z,1713915984.112 [DAT ThreadHandler](INFO): Handler Thread ID is 944 2024-04-23T23:46:24.113Z,1713915984.113 [DAT](INFO): Powering up 2024-04-23T23:46:24.113Z,1713915984.113 [DAT](DEBUG): Initializing DAT. 2024-04-23T23:46:24.117Z,1713915984.117 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 946 2024-04-23T23:46:24.118Z,1713915984.118 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-23T23:46:24.121Z,1713915984.121 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 948 2024-04-23T23:46:24.124Z,1713915984.124 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-23T23:46:24.124Z,1713915984.124 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-23T23:46:24.125Z,1713915984.125 [WetLabsBB2FL](INFO): Powering up 2024-04-23T23:46:24.126Z,1713915984.126 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 950 2024-04-23T23:46:24.135Z,1713915984.135 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-04-23T23:46:24.135Z,1713915984.135 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-04-23T23:46:24.135Z,1713915984.135 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-04-23T23:46:24.136Z,1713915984.136 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-04-23T23:46:24.136Z,1713915984.136 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-04-23T23:46:24.136Z,1713915984.136 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-04-23T23:46:24.137Z,1713915984.137 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-04-23T23:46:24.137Z,1713915984.137 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-04-23T23:46:24.164Z,1713915984.164 [CBIT](DEBUG): Backplane powered. 2024-04-23T23:46:24.169Z,1713915984.169 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-23T23:46:24.171Z,1713915984.171 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-23T23:46:24.172Z,1713915984.172 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-23T23:46:24.172Z,1713915984.172 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-23T23:46:24.173Z,1713915984.173 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-04-23T23:46:24.174Z,1713915984.174 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-23T23:46:24.174Z,1713915984.174 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-04-23T23:46:24.179Z,1713915984.179 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-04-23T23:46:24.246Z,1713915984.246 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-04-23T23:46:24.250Z,1713915984.250 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-23T23:46:24.273Z,1713915984.273 [MissionManager](DEBUG): mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-04-23T23:46:24.274Z,1713915984.274 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2024-04-23T23:46:24.324Z,1713915984.324 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-23T23:46:24.325Z,1713915984.325 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-23T23:46:24.327Z,1713915984.327 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-23T23:46:24.355Z,1713915984.355 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-23T23:46:24.358Z,1713915984.358 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-23T23:46:24.377Z,1713915984.377 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-23T23:46:24.380Z,1713915984.380 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-04-23T23:46:24.397Z,1713915984.397 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-04-23T23:46:24.409Z,1713915984.409 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-23T23:46:24.500Z,1713915984.500 [Radio_Surface](INFO): Powering up 2024-04-23T23:46:24.529Z,1713915984.529 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1629.131348 decibar, 0.000000 m 2024-04-23T23:46:24.530Z,1713915984.530 [MultiRay](INFO): Powering up MultiRay Lights 2024-04-23T23:46:24.569Z,1713915984.569 [PowerOnly](INFO): Powering up loadControl 2024-04-23T23:46:24.588Z,1713915984.588 [Power24vConverter](INFO): Powering up. 2024-04-23T23:46:24.597Z,1713915984.597 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-04-23T23:46:24.614Z,1713915984.614 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-23T23:46:24.621Z,1713915984.621 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-23T23:46:24.622Z,1713915984.622 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-23T23:46:24.628Z,1713915984.628 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-23T23:46:24.629Z,1713915984.629 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-23T23:46:24.636Z,1713915984.636 [MassServo](DEBUG): Initializing MassServo. 2024-04-23T23:46:24.637Z,1713915984.637 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-23T23:46:24.645Z,1713915984.645 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-23T23:46:24.645Z,1713915984.645 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-23T23:46:24.652Z,1713915984.652 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-23T23:46:24.854Z,1713915984.854 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-23T23:46:24.854Z,1713915984.854 [DropWeight] Hardware Fault, FailCount= 1 2024-04-23T23:46:24.854Z,1713915984.854 [DropWeight](ERROR): Hardware Fault 2024-04-23T23:46:24.906Z,1713915984.906 [CommandExec](FAULT): Scheduling is paused 2024-04-23T23:46:24.907Z,1713915984.907 [CBIT](INFO): Critical error at 20240423T234624 2024-04-23T23:46:24.907Z,1713915984.907 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-04-23T23:46:24.914Z,1713915984.914 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-23T23:46:24.914Z,1713915984.914 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-23T23:46:25.234Z,1713915985.234 [CBIT](INFO): Critical error at 20240423T234624 2024-04-23T23:46:26.736Z,1713915986.736 [WetLabsBB2FL](INFO): Powering down 2024-04-23T23:46:30.249Z,1713915990.249 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-04-23T23:46:37.224Z,1713915997.224 [DAT](INFO): DAT read: 2024-04-23T23:46:37.226Z,1713915997.226 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-23T23:46:38.989Z,1713915998.989 [DAT](INFO): DAT read: MF Frequency Band 2024-04-23T23:46:38.990Z,1713915998.990 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-23T23:46:38.990Z,1713915998.990 [DAT](INFO): DAT read: Apr 23 2024 23:24:14 2024-04-23T23:46:40.250Z,1713916000.250 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-23T23:46:40.251Z,1713916000.251 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-23T23:46:40.251Z,1713916000.251 [DAT](INFO): commRate: 800 2024-04-23T23:46:42.316Z,1713916002.316 [DAT](INFO): entering command mode 2024-04-23T23:46:42.516Z,1713916002.516 [DAT](INFO): DAT read: 2024-04-23T23:46:42.517Z,1713916002.517 [DAT](INFO): DAT read: user:1> 2024-04-23T23:46:42.517Z,1713916002.517 [DAT](INFO): setting verbose to 3 2024-04-23T23:46:42.768Z,1713916002.768 [DAT](INFO): DAT read: user:1> 2024-04-23T23:46:42.769Z,1713916002.769 [DAT](INFO): DAT read: Verbose | 3 2024-04-23T23:46:42.770Z,1713916002.770 [DAT](INFO): set verbose to 3 2024-04-23T23:46:42.770Z,1713916002.770 [DAT](INFO): setting DatVerbose to 27440 2024-04-23T23:46:43.020Z,1713916003.020 [DAT](INFO): DAT read: user:2> 2024-04-23T23:46:43.021Z,1713916003.021 [DAT](INFO): DAT read: DatVerbose | 27440 2024-04-23T23:46:43.022Z,1713916003.022 [DAT](INFO): set DatVerbose to 27440 2024-04-23T23:46:43.022Z,1713916003.022 [DAT](INFO): setting transmit power to 8 2024-04-23T23:46:43.273Z,1713916003.273 [DAT](INFO): DAT read: user:3> 2024-04-23T23:46:43.274Z,1713916003.274 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-04-23T23:46:43.274Z,1713916003.274 [DAT](INFO): set transmit power to 8 2024-04-23T23:46:43.274Z,1713916003.274 [DAT](INFO): setting local address to 9 2024-04-23T23:46:43.524Z,1713916003.524 [DAT](INFO): DAT read: user:4> 2024-04-23T23:46:43.525Z,1713916003.525 [DAT](INFO): DAT read: LocalAddr | 9 2024-04-23T23:46:43.526Z,1713916003.526 [DAT](INFO): set local address to 9 2024-04-23T23:46:43.527Z,1713916003.527 [DAT](INFO): Setting time to: 23:46:43 And date to:4/23/2024 2024-04-23T23:46:43.776Z,1713916003.776 [DAT](INFO): DAT read: user:5> 2024-04-23T23:46:43.777Z,1713916003.777 [DAT](INFO): DAT read: Tue Apr 23, 2024 23:46:43 2024-04-23T23:46:43.778Z,1713916003.778 [DAT](INFO): Local DAT time set to Tue Apr 23, 2024 23:46:43 2024-04-23T23:46:48.710Z,1713916008.710 [SBIT](IMPORTANT): Beginning Startup BIT 2024-04-23T23:46:48.715Z,1713916008.715 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-23T23:46:51.530Z,1713916011.530 [NAL9602](INFO): Powering up NAL9602 2024-04-23T23:46:59.765Z,1713916019.765 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.015198 CHAN A1 (24V): 0.000324 CHAN A2 (12V): -0.005185 CHAN A3 (5V): -0.002652 CHAN B0 (3.3V): -0.001457 CHAN B1 (3.15aV): -0.001328 CHAN B2 (3.15bV): -0.001652 CHAN B3 (GND): -0.000151 OPEN: 0.006906 Full Scale: +/- 1 mA 2024-04-23T23:47:02.438Z,1713916022.438 [NAL9602](INFO): NAL9602 initialized 2024-04-23T23:47:42.463Z,1713916062.463 [SBIT](IMPORTANT): SBIT PASSED 2024-04-23T23:47:42.464Z,1713916062.464 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-04-23T23:47:42.465Z,1713916062.465 [SBIT](IMPORTANT): BuoyancyServo.limitLo=500 count; 2024-04-23T23:47:42.465Z,1713916062.465 [SBIT](IMPORTANT): BuoyancyServo.offsetVolume=0 cubic_centimeter; 2024-04-23T23:47:42.465Z,1713916062.465 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool; 2024-04-23T23:47:42.465Z,1713916062.465 [SBIT](IMPORTANT): PowerOnly.sampleTime1=0 second; 2024-04-23T23:47:42.465Z,1713916062.465 [SBIT](IMPORTANT): Vehicle.useTlLoader=1 bool; 2024-04-23T23:47:42.465Z,1713916062.465 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=325.783869 cubic_centimeter; 2024-04-23T23:47:42.465Z,1713916062.465 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.183276 millimeter; 2024-04-23T23:47:42.863Z,1713916062.863 [MissionManager](IMPORTANT): Started mission Startup 2024-04-23T23:47:42.863Z,1713916062.863 [Startup] Running Loop=1 2024-04-23T23:47:42.863Z,1713916062.863 [Startup](DEBUG): Aggregate::initialize Startup 2024-04-23T23:47:42.863Z,1713916062.863 [Startup:A.GoToSurface] Running Loop=1 2024-04-23T23:47:42.863Z,1713916062.863 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-23T23:47:42.864Z,1713916062.864 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-23T23:47:42.864Z,1713916062.864 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-23T23:47:42.865Z,1713916062.865 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-23T23:47:42.865Z,1713916062.865 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-23T23:47:42.865Z,1713916062.865 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-23T23:47:42.867Z,1713916062.867 [Startup:StartupSatComms] Running Loop=1 2024-04-23T23:47:42.867Z,1713916062.867 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-04-23T23:47:42.867Z,1713916062.867 [Startup:StartupSatComms:A] Running Loop=1 2024-04-23T23:47:43.275Z,1713916063.275 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-04-23T23:48:43.047Z,1713916123.047 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-23T23:47:42.9Z 2024-04-23T23:48:43.047Z,1713916123.047 [Startup:StartupSatComms:A] Stopped 2024-04-23T23:48:43.047Z,1713916123.047 [Startup:StartupSatComms:B] Running Loop=1 2024-04-23T23:48:43.450Z,1713916123.450 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-23T23:49:06.007Z,1713916146.007 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005123 2024-04-23T23:49:13.341Z,1713916153.341 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T225729/Courier0013.lzma 2024-04-23T23:49:14.342Z,1713916154.342 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Courier0013.lzma.bak 2024-04-23T23:49:14.342Z,1713916154.342 [DataOverHttps](INFO): SBD MOMSN=19424947 2024-04-23T23:49:24.176Z,1713916164.176 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-04-23T23:49:24.176Z,1713916164.176 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-23T23:49:24.178Z,1713916164.178 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-04-23T23:49:24.178Z,1713916164.178 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-23T23:49:24.208Z,1713916164.208 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-23T23:49:24.209Z,1713916164.209 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-23T23:49:24.589Z,1713916164.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-23T23:49:24.590Z,1713916164.590 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-04-23T23:49:24.590Z,1713916164.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-23T23:49:24.590Z,1713916164.590 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-04-23T23:49:24.985Z,1713916164.985 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-23T23:49:30.156Z,1713916170.156 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20240423T234611/Courier0000.lzma 2024-04-23T23:49:31.157Z,1713916171.157 [DataOverHttps](INFO): Moved sent file to Logs/20240423T234611/Courier0000.lzma.bak 2024-04-23T23:49:31.158Z,1713916171.158 [DataOverHttps](INFO): SBD MOMSN=19424949 2024-04-23T23:49:43.188Z,1713916183.188 [Startup:StartupSatComms:B](INFO): Timed out from 2024-04-23T23:48:43.0Z 2024-04-23T23:49:43.188Z,1713916183.188 [Startup:StartupSatComms:B] Stopped 2024-04-23T23:49:43.188Z,1713916183.188 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-04-23T23:49:43.188Z,1713916183.188 [Startup:StartupSatComms] Stopped 2024-04-23T23:49:43.188Z,1713916183.188 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-04-23T23:49:43.189Z,1713916183.189 [Startup](INFO): Completed Startup 2024-04-23T23:49:43.189Z,1713916183.189 [MissionManager](INFO): Startup is completed. 2024-04-23T23:49:43.189Z,1713916183.189 [MissionManager](INFO): Uninitializing Mission Startup 2024-04-23T23:49:43.189Z,1713916183.189 [Startup] Stopped 2024-04-23T23:49:43.190Z,1713916183.190 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-04-23T23:49:43.190Z,1713916183.190 [Startup:A.GoToSurface] Stopped 2024-04-23T23:49:43.190Z,1713916183.190 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-23T23:49:43.582Z,1713916183.582 [MissionManager](IMPORTANT): Started mission Default 2024-04-23T23:49:43.582Z,1713916183.582 [Default] Running Loop=1 2024-04-23T23:49:43.582Z,1713916183.582 [Default](DEBUG): Aggregate::initialize Default 2024-04-23T23:49:43.582Z,1713916183.582 [Default:B.GoToSurface] Running Loop=1 2024-04-23T23:49:43.582Z,1713916183.582 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-23T23:49:43.582Z,1713916183.582 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-23T23:49:43.583Z,1713916183.583 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-23T23:49:43.583Z,1713916183.583 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-23T23:49:43.583Z,1713916183.583 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-23T23:49:43.588Z,1713916183.588 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-23T23:49:43.588Z,1713916183.588 [Default:A.Wait] Running Loop=1 2024-04-23T23:49:43.588Z,1713916183.588 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-04-23T23:49:47.561Z,1713916187.561 [DataOverHttps](INFO): Sending 342 bytes from file Logs/20240423T225729/Express0014.lzma 2024-04-23T23:49:48.562Z,1713916188.562 [DataOverHttps](INFO): Moved sent file to Logs/20240423T225729/Express0014.lzma.bak 2024-04-23T23:49:48.562Z,1713916188.562 [DataOverHttps](INFO): SBD MOMSN=19424954 2024-04-23T23:49:56.928Z,1713916196.928 [Default:A.Wait](INFO): Done Waiting. 2024-04-23T23:49:56.928Z,1713916196.928 [Default:A.Wait] Stopped 2024-04-23T23:49:56.928Z,1713916196.928 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-04-23T23:49:57.317Z,1713916197.317 [Default:CheckIn] Running Loop=1 2024-04-23T23:49:57.317Z,1713916197.317 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-23T23:49:57.317Z,1713916197.317 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-23T23:49:57.735Z,1713916197.735 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-04-23T23:52:06.177Z,1713916326.177 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-23T23:52:25.223Z,1713916345.223 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-04-23T23:52:25.223Z,1713916345.223 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-23T23:52:25.229Z,1713916345.229 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2024-04-23T23:52:25.229Z,1713916345.229 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-23T23:52:25.274Z,1713916345.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-23T23:52:25.276Z,1713916345.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-23T23:52:25.650Z,1713916345.650 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-23T23:52:25.650Z,1713916345.650 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-04-23T23:52:25.650Z,1713916345.650 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-23T23:52:25.650Z,1713916345.650 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2024-04-23T23:52:26.080Z,1713916346.080 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-23T23:54:57.483Z,1713916497.483 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-23T23:49:57.3Z 2024-04-23T23:54:57.483Z,1713916497.483 [Default:CheckIn:Read_GPS] Stopped 2024-04-23T23:54:57.483Z,1713916497.483 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-23T23:54:57.888Z,1713916497.888 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-23T23:55:04.635Z,1713916504.635 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240423T234611/Courier0004.lzma 2024-04-23T23:55:05.638Z,1713916505.638 [DataOverHttps](INFO): Moved sent file to Logs/20240423T234611/Courier0004.lzma.bak 2024-04-23T23:55:05.638Z,1713916505.638 [DataOverHttps](INFO): SBD MOMSN=19424976 2024-04-23T23:55:21.185Z,1713916521.185 [DataOverHttps](INFO): Sending 823 bytes from file Logs/20240423T234611/Express0001.lzma 2024-04-23T23:55:22.186Z,1713916522.186 [DataOverHttps](INFO): Moved sent file to Logs/20240423T234611/Express0001.lzma.bak 2024-04-23T23:55:22.186Z,1713916522.186 [DataOverHttps](INFO): SBD MOMSN=19424978 2024-04-23T23:55:26.201Z,1713916526.201 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-04-23T23:55:26.201Z,1713916526.201 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-23T23:55:26.202Z,1713916526.202 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2024-04-23T23:55:26.202Z,1713916526.202 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-23T23:55:26.225Z,1713916526.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-23T23:55:26.225Z,1713916526.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-23T23:55:26.793Z,1713916526.793 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-23T23:55:26.794Z,1713916526.794 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-04-23T23:55:26.794Z,1713916526.794 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-23T23:55:26.794Z,1713916526.794 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2024-04-23T23:55:27.019Z,1713916527.019 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-23T23:55:38.043Z,1713916538.043 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240423T234611/Express0005.lzma 2024-04-23T23:55:39.046Z,1713916539.046 [DataOverHttps](INFO): Moved sent file to Logs/20240423T234611/Express0005.lzma.bak 2024-04-23T23:55:39.046Z,1713916539.046 [DataOverHttps](INFO): SBD MOMSN=19424998 2024-04-23T23:55:41.147Z,1713916541.147 [Default:CheckIn:Read_Iridium] Stopped 2024-04-23T23:55:41.147Z,1713916541.147 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-23T23:55:41.147Z,1713916541.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-23T23:57:03.517Z,1713916623.517 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-23T23:57:03.517Z,1713916623.517 [NAL9602] Data Fault, FailCount= 1 2024-04-23T23:57:03.517Z,1713916623.517 [NAL9602](ERROR): Data Fault 2024-04-23T23:57:03.555Z,1713916623.555 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-23T23:57:03.921Z,1713916623.921 [NAL9602](INFO): Powering down 2024-04-23T23:57:04.787Z,1713916624.787 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-23T23:57:04.787Z,1713916624.787 [NAL9602] No Fault, FailCount= 1 2024-04-23T23:57:09.929Z,1713916629.929 [CommandExec](IMPORTANT): got command restart system 2024-04-23T23:57:12.004Z,1713916632.004 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-23T23:57:12.004Z,1713916632.004 [CommandExec](INFO): Uninitializing the command executive. 2024-04-23T23:57:12.004Z,1713916632.004 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-23T23:57:12.005Z,1713916632.005 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:12.100Z,1713916632.100 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-23T23:57:12.100Z,1713916632.100 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-23T23:57:12.100Z,1713916632.100 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:12.101Z,1713916632.101 [NavChartDb](INFO): Join timeout helper Thread ID is 1005 2024-04-23T23:57:12.228Z,1713916632.228 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-23T23:57:12.228Z,1713916632.228 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:12.240Z,1713916632.240 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-23T23:57:12.240Z,1713916632.240 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:12.241Z,1713916632.241 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1006 2024-04-23T23:57:12.396Z,1713916632.396 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-23T23:57:12.396Z,1713916632.396 [WetLabsBB2FL](INFO): Powering down 2024-04-23T23:57:12.397Z,1713916632.397 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:12.408Z,1713916632.408 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-23T23:57:12.408Z,1713916632.408 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:12.408Z,1713916632.408 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1007 2024-04-23T23:57:13.072Z,1713916633.072 [CTD_Seabird](INFO): Powering down 2024-04-23T23:57:13.084Z,1713916633.084 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-23T23:57:13.084Z,1713916633.084 [CTD_Seabird](INFO): Powering down 2024-04-23T23:57:13.097Z,1713916633.097 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:13.115Z,1713916633.115 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-23T23:57:13.115Z,1713916633.115 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:13.116Z,1713916633.116 [DAT](INFO): Join timeout helper Thread ID is 1008 2024-04-23T23:57:13.256Z,1713916633.256 [DAT](INFO): Powering down 2024-04-23T23:57:13.329Z,1713916633.329 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-23T23:57:13.329Z,1713916633.329 [DAT](INFO): Powering down 2024-04-23T23:57:13.330Z,1713916633.330 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:13.340Z,1713916633.340 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-23T23:57:13.340Z,1713916633.340 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-23T23:57:13.340Z,1713916633.340 [Ra