2024-03-15T21:35:16.727Z,1710538516.727 [Supervisor](DEBUG): Initializing supervisor. 2024-03-15T21:35:16.732Z,1710538516.732 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-03-15T21:35:16.732Z,1710538516.732 [SyncHandler](INFO): Protected caller Thread ID is 6598 2024-03-15T21:35:16.733Z,1710538516.733 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-03-15T21:35:16.734Z,1710538516.734 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-03-15T21:35:16.735Z,1710538516.735 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6599 2024-03-15T21:35:16.739Z,1710538516.739 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-03-15T21:35:16.757Z,1710538516.757 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-03-15T21:35:16.758Z,1710538516.758 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-03-15T21:35:16.758Z,1710538516.758 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6600 2024-03-15T21:35:16.762Z,1710538516.762 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-03-15T21:35:16.763Z,1710538516.763 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-03-15T21:35:16.764Z,1710538516.764 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6601 2024-03-15T21:35:16.766Z,1710538516.766 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-03-15T21:35:16.767Z,1710538516.767 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-03-15T21:35:16.767Z,1710538516.767 [logger ThreadHandler](INFO): Protected caller Thread ID is 6602 2024-03-15T21:35:16.771Z,1710538516.771 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-03-15T21:35:16.771Z,1710538516.771 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-03-15T21:35:16.773Z,1710538516.773 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-03-15T21:35:17.050Z,1710538517.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-03-15T21:35:17.051Z,1710538517.051 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-03-15T21:35:17.134Z,1710538517.134 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-03-15T21:35:17.657Z,1710538517.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-03-15T21:35:17.658Z,1710538517.658 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-03-15T21:35:18.010Z,1710538518.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-03-15T21:35:18.011Z,1710538518.011 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-03-15T21:35:18.110Z,1710538518.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-03-15T21:35:18.110Z,1710538518.110 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-03-15T21:35:18.535Z,1710538518.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-03-15T21:35:18.536Z,1710538518.536 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-03-15T21:35:18.766Z,1710538518.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-03-15T21:35:18.766Z,1710538518.766 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-03-15T21:35:19.309Z,1710538519.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-03-15T21:35:19.310Z,1710538519.310 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-03-15T21:35:19.453Z,1710538519.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-03-15T21:35:19.454Z,1710538519.454 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-03-15T21:35:19.535Z,1710538519.535 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-03-15T21:35:20.381Z,1710538520.381 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-03-15T21:35:20.382Z,1710538520.382 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-03-15T21:35:20.777Z,1710538520.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-03-15T21:35:20.778Z,1710538520.778 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-03-15T21:35:21.003Z,1710538521.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-03-15T21:35:21.003Z,1710538521.003 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-03-15T21:35:21.365Z,1710538521.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-03-15T21:35:21.366Z,1710538521.366 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-03-15T21:35:21.771Z,1710538521.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-03-15T21:35:21.772Z,1710538521.772 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-03-15T21:35:22.172Z,1710538522.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-03-15T21:35:22.174Z,1710538522.174 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-03-15T21:35:22.175Z,1710538522.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-03-15T21:35:22.271Z,1710538522.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-03-15T21:35:22.360Z,1710538522.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-03-15T21:35:22.467Z,1710538522.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-03-15T21:35:22.554Z,1710538522.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-03-15T21:35:22.662Z,1710538522.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-03-15T21:35:22.770Z,1710538522.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-03-15T21:35:22.889Z,1710538522.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-03-15T21:35:23.050Z,1710538523.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-03-15T21:35:23.198Z,1710538523.198 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-03-15T21:35:23.315Z,1710538523.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-03-15T21:35:23.589Z,1710538523.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-03-15T21:35:23.591Z,1710538523.591 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-03-15T21:35:23.591Z,1710538523.591 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-03-15T21:35:23.602Z,1710538523.602 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-03-15T21:35:23.656Z,1710538523.656 [VerticalControl](DEBUG): Construct VerticalControl. 2024-03-15T21:35:23.718Z,1710538523.718 [VerticalControl] Loaded 2024-03-15T21:35:23.718Z,1710538523.718 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-03-15T21:35:23.721Z,1710538523.721 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-03-15T21:35:23.763Z,1710538523.763 [HorizontalControl] Loaded 2024-03-15T21:35:23.763Z,1710538523.763 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-03-15T21:35:23.765Z,1710538523.765 [SpeedControl](DEBUG): Construct SpeedControl. 2024-03-15T21:35:23.768Z,1710538523.768 [SpeedControl] Loaded 2024-03-15T21:35:23.769Z,1710538523.769 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-03-15T21:35:23.771Z,1710538523.771 [LoopControl](DEBUG): Construct LoopControl. 2024-03-15T21:35:23.772Z,1710538523.772 [LoopControl] Loaded 2024-03-15T21:35:23.772Z,1710538523.772 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-03-15T21:35:23.773Z,1710538523.773 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-03-15T21:35:23.773Z,1710538523.773 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-03-15T21:35:23.784Z,1710538523.784 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-03-15T21:35:23.784Z,1710538523.784 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-03-15T21:35:23.940Z,1710538523.940 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-03-15T21:35:23.941Z,1710538523.941 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-03-15T21:35:24.104Z,1710538524.104 [BuoyancyServo] Loaded 2024-03-15T21:35:24.104Z,1710538524.104 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-03-15T21:35:24.124Z,1710538524.124 [ElevatorServo] Loaded 2024-03-15T21:35:24.125Z,1710538524.125 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-03-15T21:35:24.144Z,1710538524.144 [MassServo] Loaded 2024-03-15T21:35:24.144Z,1710538524.144 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-03-15T21:35:24.163Z,1710538524.163 [RudderServo] Loaded 2024-03-15T21:35:24.163Z,1710538524.163 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-03-15T21:35:24.178Z,1710538524.178 [ThrusterHE] Loaded 2024-03-15T21:35:24.178Z,1710538524.178 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-03-15T21:35:24.179Z,1710538524.179 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-03-15T21:35:24.179Z,1710538524.179 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-03-15T21:35:24.297Z,1710538524.297 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-03-15T21:35:24.297Z,1710538524.297 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-03-15T21:35:24.311Z,1710538524.311 [NavChart] Loaded 2024-03-15T21:35:24.311Z,1710538524.311 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-03-15T21:35:24.317Z,1710538524.317 [UniversalFixResidualReporter] Loaded 2024-03-15T21:35:24.317Z,1710538524.317 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-03-15T21:35:24.318Z,1710538524.318 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-03-15T21:35:24.318Z,1710538524.318 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-03-15T21:35:24.378Z,1710538524.378 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-03-15T21:35:24.378Z,1710538524.378 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-03-15T21:35:24.825Z,1710538524.825 [AHRS_M2] Loaded 2024-03-15T21:35:24.825Z,1710538524.825 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-03-15T21:35:25.677Z,1710538525.677 [BPC1] Loaded 2024-03-15T21:35:25.677Z,1710538525.677 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-03-15T21:35:25.753Z,1710538525.753 [DataOverHttps] Loaded 2024-03-15T21:35:25.753Z,1710538525.753 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-03-15T21:35:25.755Z,1710538525.755 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-03-15T21:35:25.755Z,1710538525.755 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6684 2024-03-15T21:35:25.776Z,1710538525.776 [Depth_Keller] Loaded 2024-03-15T21:35:25.776Z,1710538525.776 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-03-15T21:35:25.781Z,1710538525.781 [DropWeight] Loaded 2024-03-15T21:35:25.782Z,1710538525.782 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-03-15T21:35:25.846Z,1710538525.846 [NAL9602] Loaded 2024-03-15T21:35:25.846Z,1710538525.846 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-03-15T21:35:25.877Z,1710538525.877 [Onboard] Loaded 2024-03-15T21:35:25.877Z,1710538525.877 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-03-15T21:35:25.878Z,1710538525.878 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0 2024-03-15T21:35:25.879Z,1710538525.879 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6685 2024-03-15T21:35:25.886Z,1710538525.886 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1 2024-03-15T21:35:25.898Z,1710538525.898 [PowerOnly] Loaded 2024-03-15T21:35:25.898Z,1710538525.898 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-03-15T21:35:25.910Z,1710538525.910 [Power24vConverter] Loaded 2024-03-15T21:35:25.911Z,1710538525.911 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-03-15T21:35:25.924Z,1710538525.924 [Radio_Surface] Loaded 2024-03-15T21:35:25.925Z,1710538525.925 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-03-15T21:35:25.926Z,1710538525.926 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0 2024-03-15T21:35:25.926Z,1710538525.926 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6686 2024-03-15T21:35:25.927Z,1710538525.927 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-03-15T21:35:25.928Z,1710538525.928 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-03-15T21:35:25.980Z,1710538525.980 [DepthRateCalculator] Loaded 2024-03-15T21:35:25.981Z,1710538525.981 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-03-15T21:35:25.986Z,1710538525.986 [PitchRateCalculator] Loaded 2024-03-15T21:35:25.986Z,1710538525.986 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-03-15T21:35:25.995Z,1710538525.995 [SpeedCalculator] Loaded 2024-03-15T21:35:25.995Z,1710538525.995 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-03-15T21:35:25.000Z,1710538526.000 [YawRateCalculator] Loaded 2024-03-15T21:35:26.000Z,1710538526.000 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-03-15T21:35:26.020Z,1710538526.020 [ElevatorOffsetCalculator] Loaded 2024-03-15T21:35:26.020Z,1710538526.020 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-03-15T21:35:26.020Z,1710538526.020 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-03-15T21:35:26.021Z,1710538526.021 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-03-15T21:35:26.521Z,1710538526.521 [CANONSampler] Loaded 2024-03-15T21:35:26.521Z,1710538526.521 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-03-15T21:35:26.625Z,1710538526.625 [CTD_Seabird] Loaded 2024-03-15T21:35:26.626Z,1710538526.626 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-03-15T21:35:26.627Z,1710538526.627 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0 2024-03-15T21:35:26.627Z,1710538526.627 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6687 2024-03-15T21:35:26.648Z,1710538526.648 [PAR_Licor] Loaded 2024-03-15T21:35:26.648Z,1710538526.648 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-03-15T21:35:26.694Z,1710538526.694 [WetLabsBB2FL] Loaded 2024-03-15T21:35:26.695Z,1710538526.695 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-03-15T21:35:26.696Z,1710538526.696 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0 2024-03-15T21:35:26.696Z,1710538526.696 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6688 2024-03-15T21:35:26.697Z,1710538526.697 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-03-15T21:35:26.697Z,1710538526.697 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-03-15T21:35:26.725Z,1710538526.725 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-03-15T21:35:26.725Z,1710538526.725 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-03-15T21:35:27.083Z,1710538527.083 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-03-15T21:35:27.083Z,1710538527.083 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-03-15T21:35:27.232Z,1710538527.232 [SBIT](DEBUG): Construct Startup Built In Test. 2024-03-15T21:35:27.241Z,1710538527.241 [SBIT] Loaded 2024-03-15T21:35:27.242Z,1710538527.242 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-03-15T21:35:27.245Z,1710538527.245 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-03-15T21:35:27.259Z,1710538527.259 [IBIT] Loaded 2024-03-15T21:35:27.259Z,1710538527.259 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-03-15T21:35:27.265Z,1710538527.265 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-03-15T21:35:27.371Z,1710538527.371 [CBIT] Loaded 2024-03-15T21:35:27.371Z,1710538527.371 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-03-15T21:35:27.371Z,1710538527.371 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-03-15T21:35:27.372Z,1710538527.372 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-03-15T21:35:27.491Z,1710538527.491 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-03-15T21:35:27.497Z,1710538527.497 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-03-15T21:35:27.500Z,1710538527.500 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-03-15T21:35:27.511Z,1710538527.511 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-03-15T21:35:27.512Z,1710538527.512 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0 2024-03-15T21:35:27.513Z,1710538527.513 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6689 2024-03-15T21:35:27.517Z,1710538527.517 [Supervisor](INFO): Main Thread ID is 6310 2024-03-15T21:35:27.517Z,1710538527.517 [Supervisor](DEBUG): Running supervisor. 2024-03-15T21:35:27.518Z,1710538527.518 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6690 2024-03-15T21:35:27.518Z,1710538527.518 [CommandExec](INFO): Initializing the command executive. 2024-03-15T21:35:27.520Z,1710538527.520 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6691 2024-03-15T21:35:27.522Z,1710538527.522 [controlThread ThreadHandler](INFO): Handler Thread ID is 6692 2024-03-15T21:35:27.523Z,1710538527.523 [controlThread](DEBUG): Initializing ControlThread 2024-03-15T21:35:27.524Z,1710538527.524 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-03-15T21:35:27.525Z,1710538527.525 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-03-15T21:35:27.526Z,1710538527.526 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-03-15T21:35:27.526Z,1710538527.526 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-03-15T21:35:27.528Z,1710538527.528 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-03-15T21:35:27.529Z,1710538527.529 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-03-15T21:35:27.534Z,1710538527.534 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-03-15T21:35:27.534Z,1710538527.534 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-03-15T21:35:27.535Z,1710538527.535 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-03-15T21:35:27.535Z,1710538527.535 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-03-15T21:35:27.535Z,1710538527.535 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-03-15T21:35:27.540Z,1710538527.540 [SBIT](INFO): Initialize SBIT Component. 2024-03-15T21:35:27.541Z,1710538527.541 [SBIT](IMPORTANT): git: 2024-02-14 2024-03-15T21:35:27.541Z,1710538527.541 [SBIT](INFO): git hash: 8fc4e848ca047b5de9ae8a7c02f55958c6301ab2 2024-03-15T21:35:27.541Z,1710538527.541 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-03-15T21:35:27.542Z,1710538527.542 [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-03-15T21:35:27.544Z,1710538527.544 [SBIT](INFO): Beginning SBIT in 83.000000 seconds. 2024-03-15T21:35:27.544Z,1710538527.544 [IBIT](INFO): Initialize IBIT Component. 2024-03-15T21:35:27.545Z,1710538527.545 [CBIT](DEBUG): Initialize CBIT Component. 2024-03-15T21:35:27.546Z,1710538527.546 [logger ThreadHandler](INFO): Handler Thread ID is 6693 2024-03-15T21:35:27.556Z,1710538527.556 [CBIT](DEBUG): Initialized mux pins. 2024-03-15T21:35:27.556Z,1710538527.556 [CBIT](DEBUG): Initializing the watchdog timer. 2024-03-15T21:35:27.565Z,1710538527.565 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6694 2024-03-15T21:35:27.566Z,1710538527.566 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-03-15T21:35:27.577Z,1710538527.577 [Onboard ThreadHandler](INFO): Handler Thread ID is 6695 2024-03-15T21:35:27.581Z,1710538527.581 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-03-15T21:35:27.581Z,1710538527.581 [CBIT](DEBUG): Initializing heartbeat. 2024-03-15T21:35:27.597Z,1710538527.597 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6696 2024-03-15T21:35:27.617Z,1710538527.617 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6697 2024-03-15T21:35:27.617Z,1710538527.617 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-03-15T21:35:27.622Z,1710538527.622 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6699 2024-03-15T21:35:27.629Z,1710538527.629 [WetLabsBB2FL](INFO): Powering up 2024-03-15T21:35:27.630Z,1710538527.630 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6701 2024-03-15T21:35:27.633Z,1710538527.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-03-15T21:35:27.633Z,1710538527.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-03-15T21:35:27.633Z,1710538527.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-03-15T21:35:27.633Z,1710538527.633 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-03-15T21:35:27.634Z,1710538527.634 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-03-15T21:35:27.634Z,1710538527.634 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-03-15T21:35:27.634Z,1710538527.634 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-03-15T21:35:27.634Z,1710538527.634 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-03-15T21:35:27.652Z,1710538527.652 [CBIT](DEBUG): Deactivating GF circuits. 2024-03-15T21:35:27.652Z,1710538527.652 [CBIT](DEBUG): Deactivating emergency mode. 2024-03-15T21:35:27.692Z,1710538527.692 [CBIT](DEBUG): Backplane powered. 2024-03-15T21:35:27.693Z,1710538527.693 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-03-15T21:35:27.694Z,1710538527.694 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-03-15T21:35:27.705Z,1710538527.705 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-15T21:35:27.740Z,1710538527.740 [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-03-15T21:35:27.741Z,1710538527.741 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-03-15T21:35:27.741Z,1710538527.741 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-03-15T21:35:27.814Z,1710538527.814 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-03-15T21:35:27.816Z,1710538527.816 [Default:A.Wait](DEBUG): Construct Wait. 2024-03-15T21:35:27.818Z,1710538527.818 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-15T21:35:27.861Z,1710538527.861 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-03-15T21:35:27.864Z,1710538527.864 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-03-15T21:35:27.880Z,1710538527.880 [Default:E.Execute](DEBUG): Construct Execute. 2024-03-15T21:35:27.894Z,1710538527.894 [MissionManager](DEBUG): 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-03-15T21:35:27.899Z,1710538527.899 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,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,Reporter,LogSplitter, 2024-03-15T21:35:27.929Z,1710538527.929 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-15T21:35:27.995Z,1710538527.995 [PowerOnly](INFO): Powering up loadControl 2024-03-15T21:35:28.004Z,1710538528.004 [Radio_Surface](INFO): Powering up 2024-03-15T21:35:28.013Z,1710538528.013 [Power24vConverter](INFO): Powering up. 2024-03-15T21:35:28.014Z,1710538528.014 [CANONSampler](INFO): Powering down 2024-03-15T21:35:28.066Z,1710538528.066 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-03-15T21:35:28.073Z,1710538528.073 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-03-15T21:35:28.074Z,1710538528.074 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-15T21:35:28.080Z,1710538528.080 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-15T21:35:28.081Z,1710538528.081 [MassServo](DEBUG): Initializing EZServoServo. 2024-03-15T21:35:28.088Z,1710538528.088 [MassServo](DEBUG): Initializing MassServo. 2024-03-15T21:35:28.089Z,1710538528.089 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-15T21:35:28.100Z,1710538528.100 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-15T21:35:28.101Z,1710538528.101 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-03-15T21:35:28.108Z,1710538528.108 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-03-15T21:35:29.732Z,1710538529.732 [WetLabsBB2FL](INFO): Powering down 2024-03-15T21:35:33.750Z,1710538533.750 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-03-15T21:35:56.190Z,1710538556.190 [NAL9602](INFO): Powering up NAL9602 2024-03-15T21:36:07.098Z,1710538567.098 [NAL9602](INFO): NAL9602 initialized 2024-03-15T21:36:34.565Z,1710538594.565 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:36:47.493Z,1710538607.493 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:36:51.165Z,1710538611.165 [SBIT](IMPORTANT): Beginning Startup BIT 2024-03-15T21:36:51.169Z,1710538611.169 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-15T21:37:00.017Z,1710538620.017 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:37:02.573Z,1710538622.573 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-15T21:37:02.970Z,1710538622.970 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-15T21:37:13.753Z,1710538633.753 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:37:13.862Z,1710538633.862 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-15T21:37:14.233Z,1710538634.233 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-15T21:37:25.095Z,1710538645.095 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-15T21:37:25.095Z,1710538645.095 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule. 2024-03-15T21:37:26.681Z,1710538646.681 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:37:40.014Z,1710538660.014 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:37:45.301Z,1710538665.301 [SBIT](IMPORTANT): SBIT PASSED 2024-03-15T21:37:45.302Z,1710538665.302 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-03-15T21:37:45.302Z,1710538665.302 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2024-03-15T21:37:45.302Z,1710538665.302 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): PowerOnly.sampleTime1=0 second; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): PowerOnly.simulateHardware=0 bool; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=319.031769 cubic_centimeter; 2024-03-15T21:37:45.303Z,1710538665.303 [SBIT](IMPORTANT): VerticalControl.massDefault=-15.950069 millimeter; 2024-03-15T21:37:45.691Z,1710538665.691 [MissionManager](IMPORTANT): Started mission Startup 2024-03-15T21:37:45.691Z,1710538665.691 [Startup] Running Loop=1 2024-03-15T21:37:45.691Z,1710538665.691 [Startup](DEBUG): Aggregate::initialize Startup 2024-03-15T21:37:45.691Z,1710538665.691 [Startup:A.GoToSurface] Running Loop=1 2024-03-15T21:37:45.691Z,1710538665.691 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-15T21:37:45.692Z,1710538665.692 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-15T21:37:45.693Z,1710538665.693 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-15T21:37:45.693Z,1710538665.693 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-15T21:37:45.693Z,1710538665.693 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-15T21:37:45.694Z,1710538665.694 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-15T21:37:45.695Z,1710538665.695 [Startup:StartupSatComms] Running Loop=1 2024-03-15T21:37:45.695Z,1710538665.695 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-03-15T21:37:45.695Z,1710538665.695 [Startup:StartupSatComms:A] Running Loop=1 2024-03-15T21:37:46.109Z,1710538666.109 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-03-15T21:37:52.941Z,1710538672.941 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:38:06.273Z,1710538686.273 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:38:10.551Z,1710538690.551 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004851 2024-03-15T21:38:18.797Z,1710538698.797 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:38:27.710Z,1710538707.710 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-03-15T21:38:27.710Z,1710538707.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:38:27.760Z,1710538707.760 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:38:28.114Z,1710538708.114 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:38:28.115Z,1710538708.115 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-03-15T21:38:31.725Z,1710538711.725 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:38:44.652Z,1710538724.652 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:38:45.902Z,1710538725.902 [Startup:StartupSatComms:A](INFO): Timed out from 2024-03-15T21:37:45.7Z 2024-03-15T21:38:45.903Z,1710538725.903 [Startup:StartupSatComms:A] Stopped 2024-03-15T21:38:45.903Z,1710538725.903 [Startup:StartupSatComms:B] Running Loop=1 2024-03-15T21:38:46.322Z,1710538726.322 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-03-15T21:38:53.536Z,1710538733.536 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240315T212633/Courier0004.lzma 2024-03-15T21:38:54.538Z,1710538734.538 [DataOverHttps](INFO): Moved sent file to Logs/20240315T212633/Courier0004.lzma.bak 2024-03-15T21:38:54.538Z,1710538734.538 [DataOverHttps](INFO): SBD MOMSN=19322348 2024-03-15T21:38:58.085Z,1710538738.085 [CommandExec](IMPORTANT): got command failComponent 2024-03-15T21:38:58.085Z,1710538738.085 [CommandExec](IMPORTANT): Failed components: 2024-03-15T21:38:58.085Z,1710538738.085 [CommandExec](IMPORTANT): No failed Components. 2024-03-15T21:39:10.197Z,1710538750.197 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240315T213516/Courier0000.lzma 2024-03-15T21:39:11.190Z,1710538751.190 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0000.lzma.bak 2024-03-15T21:39:11.190Z,1710538751.190 [DataOverHttps](INFO): SBD MOMSN=19322350 2024-03-15T21:39:15.719Z,1710538755.719 [CommandExec](IMPORTANT): got command configSet list 2024-03-15T21:39:15.720Z,1710538755.720 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): PowerOnly.sampleTime1=0 second; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): PowerOnly.simulateHardware=0 bool; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2024-03-15T21:39:15.725Z,1710538755.725 [CommandExec](IMPORTANT): ThrusterServo.currLimit=50 percent; 2024-03-15T21:39:15.726Z,1710538755.726 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=319.031769 cubic_centimeter; 2024-03-15T21:39:15.726Z,1710538755.726 [CommandExec](IMPORTANT): VerticalControl.massDefault=-15.950069 millimeter; 2024-03-15T21:39:27.001Z,1710538767.001 [DataOverHttps](INFO): Sending 564 bytes from file Logs/20240315T212633/Express0005.lzma 2024-03-15T21:39:28.002Z,1710538768.002 [DataOverHttps](INFO): Moved sent file to Logs/20240315T212633/Express0005.lzma.bak 2024-03-15T21:39:28.003Z,1710538768.003 [DataOverHttps](INFO): SBD MOMSN=19322353 2024-03-15T21:39:43.705Z,1710538783.705 [DataOverHttps](INFO): Sending 822 bytes from file Logs/20240315T213516/Express0001.lzma 2024-03-15T21:39:44.706Z,1710538784.706 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0001.lzma.bak 2024-03-15T21:39:44.706Z,1710538784.706 [DataOverHttps](INFO): SBD MOMSN=19322373 2024-03-15T21:39:46.103Z,1710538786.103 [Startup:StartupSatComms:B](INFO): Timed out from 2024-03-15T21:38:45.9Z 2024-03-15T21:39:46.103Z,1710538786.103 [Startup:StartupSatComms:B] Stopped 2024-03-15T21:39:46.103Z,1710538786.103 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-03-15T21:39:46.103Z,1710538786.103 [Startup:StartupSatComms] Stopped 2024-03-15T21:39:46.103Z,1710538786.103 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-03-15T21:39:46.104Z,1710538786.104 [Startup](INFO): Completed Startup 2024-03-15T21:39:46.104Z,1710538786.104 [MissionManager](INFO): Startup is completed. 2024-03-15T21:39:46.104Z,1710538786.104 [MissionManager](INFO): Uninitializing Mission Startup 2024-03-15T21:39:46.104Z,1710538786.104 [Startup] Stopped 2024-03-15T21:39:46.104Z,1710538786.104 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-03-15T21:39:46.104Z,1710538786.104 [Startup:A.GoToSurface] Stopped 2024-03-15T21:39:46.105Z,1710538786.105 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-15T21:39:46.490Z,1710538786.490 [MissionManager](IMPORTANT): Started mission Default 2024-03-15T21:39:46.490Z,1710538786.490 [Default] Running Loop=1 2024-03-15T21:39:46.490Z,1710538786.490 [Default](DEBUG): Aggregate::initialize Default 2024-03-15T21:39:46.491Z,1710538786.491 [Default:B.GoToSurface] Running Loop=1 2024-03-15T21:39:46.491Z,1710538786.491 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-15T21:39:46.491Z,1710538786.491 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-15T21:39:46.491Z,1710538786.491 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-15T21:39:46.491Z,1710538786.491 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-15T21:39:46.492Z,1710538786.492 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-15T21:39:46.492Z,1710538786.492 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-15T21:39:46.493Z,1710538786.493 [Default:A.Wait] Running Loop=1 2024-03-15T21:39:46.493Z,1710538786.493 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-03-15T21:39:59.843Z,1710538799.843 [Default:A.Wait](INFO): Done Waiting. 2024-03-15T21:39:59.843Z,1710538799.843 [Default:A.Wait] Stopped 2024-03-15T21:39:59.843Z,1710538799.843 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T21:40:00.251Z,1710538800.251 [Default:CheckIn] Running Loop=1 2024-03-15T21:40:00.251Z,1710538800.251 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T21:40:00.251Z,1710538800.251 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T21:40:00.647Z,1710538800.647 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-03-15T21:40:12.509Z,1710538812.509 [CommandExec](IMPORTANT): got command strobe off 2024-03-15T21:40:12.510Z,1710538812.510 [CommandExec](IMPORTANT): Deactivating strobe 2024-03-15T21:41:10.514Z,1710538870.514 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-15T21:41:18.181Z,1710538878.181 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-15T21:41:25.857Z,1710538885.857 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T21:41:25.880Z,1710538885.880 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-15T21:41:25.883Z,1710538885.883 [BPC1](INFO): Received data from all battery sticks. 2024-03-15T21:41:28.701Z,1710538888.701 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-03-15T21:41:28.701Z,1710538888.701 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:41:28.712Z,1710538888.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:41:29.116Z,1710538889.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:41:29.116Z,1710538889.116 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-03-15T21:44:29.699Z,1710539069.699 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-03-15T21:44:29.699Z,1710539069.699 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:44:29.710Z,1710539069.710 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:44:30.136Z,1710539070.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:44:30.136Z,1710539070.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-03-15T21:45:00.395Z,1710539100.395 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T21:40:00.3Z 2024-03-15T21:45:00.395Z,1710539100.395 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T21:45:00.395Z,1710539100.395 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T21:45:00.805Z,1710539100.805 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-03-15T21:45:07.256Z,1710539107.256 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240315T213516/Courier0004.lzma 2024-03-15T21:45:08.258Z,1710539108.258 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0004.lzma.bak 2024-03-15T21:45:08.258Z,1710539108.258 [DataOverHttps](INFO): SBD MOMSN=19322405 2024-03-15T21:45:23.713Z,1710539123.713 [DataOverHttps](INFO): Sending 590 bytes from file Logs/20240315T213516/Express0005.lzma 2024-03-15T21:45:24.714Z,1710539124.714 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0005.lzma.bak 2024-03-15T21:45:24.714Z,1710539124.714 [DataOverHttps](INFO): SBD MOMSN=19322407 2024-03-15T21:45:25.866Z,1710539125.866 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T21:45:25.866Z,1710539125.866 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T21:45:25.866Z,1710539125.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T21:46:09.470Z,1710539169.470 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T21:46:09.470Z,1710539169.470 [NAL9602] Data Fault, FailCount= 1 2024-03-15T21:46:09.470Z,1710539169.470 [NAL9602](ERROR): Data Fault 2024-03-15T21:46:09.488Z,1710539169.488 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T21:46:09.882Z,1710539169.882 [NAL9602](INFO): Powering down 2024-03-15T21:46:10.702Z,1710539170.702 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T21:46:10.702Z,1710539170.702 [NAL9602] No Fault, FailCount= 1 2024-03-15T21:46:40.170Z,1710539200.170 [NAL9602](INFO): Powering up NAL9602 2024-03-15T21:46:51.086Z,1710539211.086 [NAL9602](INFO): NAL9602 initialized 2024-03-15T21:47:30.720Z,1710539250.720 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-03-15T21:47:30.720Z,1710539250.720 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:47:30.735Z,1710539250.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:47:31.111Z,1710539251.111 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:47:31.111Z,1710539251.111 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-03-15T21:50:26.429Z,1710539426.429 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T21:50:26.429Z,1710539426.429 [Default:CheckIn:C.Wait] Stopped 2024-03-15T21:50:26.430Z,1710539426.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T21:50:26.430Z,1710539426.430 [Default:CheckIn:D] Running Loop=1 2024-03-15T21:50:26.844Z,1710539426.844 [Default:CheckIn:D] Stopped 2024-03-15T21:50:26.844Z,1710539426.844 [Default:CheckIn:E] Running Loop=1 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672563 min 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn:E] Stopped 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn] Stopped 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn](INFO): Running loop #2 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn] Running Loop=2 2024-03-15T21:50:27.258Z,1710539427.258 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T21:50:27.259Z,1710539427.259 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T21:50:31.688Z,1710539431.688 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-03-15T21:50:31.689Z,1710539431.689 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:50:31.703Z,1710539431.703 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:50:32.138Z,1710539432.138 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:50:32.138Z,1710539432.138 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-03-15T21:53:32.675Z,1710539612.675 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-03-15T21:53:32.675Z,1710539612.675 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:53:32.685Z,1710539612.685 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:53:33.121Z,1710539613.121 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:53:33.121Z,1710539613.121 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-03-15T21:55:27.437Z,1710539727.437 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T21:50:27.3Z 2024-03-15T21:55:27.437Z,1710539727.437 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T21:55:27.437Z,1710539727.437 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T21:55:34.647Z,1710539734.647 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0007.lzma 2024-03-15T21:55:35.650Z,1710539735.650 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0007.lzma.bak 2024-03-15T21:55:35.650Z,1710539735.650 [DataOverHttps](INFO): SBD MOMSN=19322443 2024-03-15T21:55:50.267Z,1710539750.267 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20240315T213516/Express0008.lzma 2024-03-15T21:55:51.050Z,1710539751.050 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0008.lzma.bak 2024-03-15T21:55:51.050Z,1710539751.050 [DataOverHttps](INFO): SBD MOMSN=19322445 2024-03-15T21:55:52.459Z,1710539752.459 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T21:55:52.459Z,1710539752.459 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T21:55:52.459Z,1710539752.459 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T21:56:33.662Z,1710539793.662 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-03-15T21:56:33.662Z,1710539793.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:56:33.705Z,1710539793.705 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:56:34.074Z,1710539794.074 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:56:34.074Z,1710539794.074 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-03-15T21:56:52.238Z,1710539812.238 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T21:56:52.238Z,1710539812.238 [NAL9602] Data Fault, FailCount= 2 2024-03-15T21:56:52.238Z,1710539812.238 [NAL9602](ERROR): Data Fault 2024-03-15T21:56:52.255Z,1710539812.255 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T21:56:52.653Z,1710539812.653 [NAL9602](INFO): Powering down 2024-03-15T21:56:53.490Z,1710539813.490 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T21:56:53.490Z,1710539813.490 [NAL9602] No Fault, FailCount= 2 2024-03-15T21:57:22.942Z,1710539842.942 [NAL9602](INFO): Powering up NAL9602 2024-03-15T21:57:33.850Z,1710539853.850 [NAL9602](INFO): NAL9602 initialized 2024-03-15T21:59:34.667Z,1710539974.667 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-03-15T21:59:34.668Z,1710539974.668 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T21:59:34.681Z,1710539974.681 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T21:59:35.094Z,1710539975.094 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T21:59:35.094Z,1710539975.094 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-03-15T22:00:53.071Z,1710540053.071 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T22:00:53.071Z,1710540053.071 [Default:CheckIn:C.Wait] Stopped 2024-03-15T22:00:53.071Z,1710540053.071 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T22:00:53.071Z,1710540053.071 [Default:CheckIn:D] Running Loop=1 2024-03-15T22:00:53.459Z,1710540053.459 [Default:CheckIn:D] Stopped 2024-03-15T22:00:53.459Z,1710540053.459 [Default:CheckIn:E] Running Loop=1 2024-03-15T22:00:53.879Z,1710540053.879 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.116148 min 2024-03-15T22:00:53.879Z,1710540053.879 [Default:CheckIn:E] Stopped 2024-03-15T22:00:53.879Z,1710540053.879 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T22:00:53.879Z,1710540053.879 [Default:CheckIn] Stopped 2024-03-15T22:00:53.879Z,1710540053.879 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T22:00:53.879Z,1710540053.879 [Default:CheckIn](INFO): Running loop #3 2024-03-15T22:00:53.879Z,1710540053.879 [Default:CheckIn] Running Loop=3 2024-03-15T22:00:53.880Z,1710540053.880 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T22:00:53.880Z,1710540053.880 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T22:02:35.662Z,1710540155.662 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2024-03-15T22:02:35.662Z,1710540155.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:02:35.673Z,1710540155.673 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:02:36.083Z,1710540156.083 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:02:36.083Z,1710540156.083 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2024-03-15T22:05:36.673Z,1710540336.673 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2024-03-15T22:05:36.673Z,1710540336.673 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:05:36.715Z,1710540336.715 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:05:37.080Z,1710540337.080 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:05:37.080Z,1710540337.080 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2024-03-15T22:05:54.035Z,1710540354.035 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T22:00:53.9Z 2024-03-15T22:05:54.035Z,1710540354.035 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T22:05:54.035Z,1710540354.035 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T22:06:01.247Z,1710540361.247 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0010.lzma 2024-03-15T22:06:02.250Z,1710540362.250 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0010.lzma.bak 2024-03-15T22:06:02.250Z,1710540362.250 [DataOverHttps](INFO): SBD MOMSN=19322461 2024-03-15T22:06:17.628Z,1710540377.628 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240315T213516/Express0011.lzma 2024-03-15T22:06:18.630Z,1710540378.630 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0011.lzma.bak 2024-03-15T22:06:18.630Z,1710540378.630 [DataOverHttps](INFO): SBD MOMSN=19322463 2024-03-15T22:06:19.886Z,1710540379.886 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T22:06:19.886Z,1710540379.886 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T22:06:19.886Z,1710540379.886 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T22:07:36.634Z,1710540456.634 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T22:07:36.634Z,1710540456.634 [NAL9602] Data Fault, FailCount= 3 2024-03-15T22:07:36.634Z,1710540456.634 [NAL9602](ERROR): Data Fault 2024-03-15T22:07:36.651Z,1710540456.651 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T22:07:37.033Z,1710540457.033 [NAL9602](INFO): Powering down 2024-03-15T22:07:37.858Z,1710540457.858 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T22:07:37.858Z,1710540457.858 [NAL9602] No Fault, FailCount= 3 2024-03-15T22:08:07.338Z,1710540487.338 [NAL9602](INFO): Powering up NAL9602 2024-03-15T22:08:18.250Z,1710540498.250 [NAL9602](INFO): NAL9602 initialized 2024-03-15T22:08:37.654Z,1710540517.654 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2024-03-15T22:08:37.654Z,1710540517.654 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:08:37.665Z,1710540517.665 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:08:38.086Z,1710540518.086 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:08:38.086Z,1710540518.086 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2024-03-15T22:11:20.458Z,1710540680.458 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T22:11:20.458Z,1710540680.458 [Default:CheckIn:C.Wait] Stopped 2024-03-15T22:11:20.458Z,1710540680.458 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T22:11:20.458Z,1710540680.458 [Default:CheckIn:D] Running Loop=1 2024-03-15T22:11:20.862Z,1710540680.862 [Default:CheckIn:D] Stopped 2024-03-15T22:11:20.862Z,1710540680.862 [Default:CheckIn:E] Running Loop=1 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.572862 min 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn:E] Stopped 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn] Stopped 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn](INFO): Running loop #4 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn] Running Loop=4 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T22:11:21.287Z,1710540681.287 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T22:11:38.637Z,1710540698.637 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2024-03-15T22:11:38.637Z,1710540698.637 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:11:38.664Z,1710540698.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:11:39.078Z,1710540699.078 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:11:39.079Z,1710540699.079 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2024-03-15T22:14:39.671Z,1710540879.671 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2024-03-15T22:14:39.671Z,1710540879.671 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:14:39.709Z,1710540879.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:14:40.067Z,1710540880.067 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:14:40.067Z,1710540880.067 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2024-03-15T22:16:21.467Z,1710540981.467 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T22:11:21.3Z 2024-03-15T22:16:21.467Z,1710540981.467 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T22:16:21.467Z,1710540981.467 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T22:16:28.235Z,1710540988.235 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0013.lzma 2024-03-15T22:16:29.238Z,1710540989.238 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0013.lzma.bak 2024-03-15T22:16:29.238Z,1710540989.238 [DataOverHttps](INFO): SBD MOMSN=19322481 2024-03-15T22:16:44.644Z,1710541004.644 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240315T213516/Express0014.lzma 2024-03-15T22:16:45.646Z,1710541005.646 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0014.lzma.bak 2024-03-15T22:16:45.646Z,1710541005.646 [DataOverHttps](INFO): SBD MOMSN=19322483 2024-03-15T22:16:46.946Z,1710541006.946 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T22:16:46.946Z,1710541006.946 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T22:16:46.946Z,1710541006.946 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T22:17:40.649Z,1710541060.649 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2024-03-15T22:17:40.649Z,1710541060.649 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:17:40.659Z,1710541060.659 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:17:41.064Z,1710541061.064 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:17:41.064Z,1710541061.064 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2024-03-15T22:18:21.442Z,1710541101.442 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T22:18:21.442Z,1710541101.442 [NAL9602] Data Fault, FailCount= 4 2024-03-15T22:18:21.442Z,1710541101.442 [NAL9602](ERROR): Data Fault 2024-03-15T22:18:21.524Z,1710541101.524 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T22:18:21.845Z,1710541101.845 [NAL9602](INFO): Powering down 2024-03-15T22:18:22.717Z,1710541102.717 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T22:18:22.717Z,1710541102.717 [NAL9602] No Fault, FailCount= 4 2024-03-15T22:18:52.150Z,1710541132.150 [NAL9602](INFO): Powering up NAL9602 2024-03-15T22:19:03.058Z,1710541143.058 [NAL9602](INFO): NAL9602 initialized 2024-03-15T22:20:41.641Z,1710541241.641 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2024-03-15T22:20:41.641Z,1710541241.641 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:20:41.651Z,1710541241.651 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:20:42.063Z,1710541242.063 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:20:42.063Z,1710541242.063 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2024-03-15T22:21:47.498Z,1710541307.498 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T22:21:47.498Z,1710541307.498 [Default:CheckIn:C.Wait] Stopped 2024-03-15T22:21:47.498Z,1710541307.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T22:21:47.499Z,1710541307.499 [Default:CheckIn:D] Running Loop=1 2024-03-15T22:21:47.907Z,1710541307.907 [Default:CheckIn:D] Stopped 2024-03-15T22:21:47.907Z,1710541307.907 [Default:CheckIn:E] Running Loop=1 2024-03-15T22:21:48.315Z,1710541308.315 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.023600 min 2024-03-15T22:21:48.315Z,1710541308.315 [Default:CheckIn:E] Stopped 2024-03-15T22:21:48.315Z,1710541308.315 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T22:21:48.315Z,1710541308.315 [Default:CheckIn] Stopped 2024-03-15T22:21:48.315Z,1710541308.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T22:21:48.315Z,1710541308.315 [Default:CheckIn](INFO): Running loop #5 2024-03-15T22:21:48.316Z,1710541308.316 [Default:CheckIn] Running Loop=5 2024-03-15T22:21:48.316Z,1710541308.316 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T22:21:48.316Z,1710541308.316 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T22:23:42.630Z,1710541422.630 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2024-03-15T22:23:42.630Z,1710541422.630 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:23:42.641Z,1710541422.641 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:23:43.046Z,1710541423.046 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:23:43.046Z,1710541423.046 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2024-03-15T22:26:43.639Z,1710541603.639 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2024-03-15T22:26:43.639Z,1710541603.639 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:26:43.650Z,1710541603.650 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:26:44.062Z,1710541604.062 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:26:44.062Z,1710541604.062 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2024-03-15T22:26:48.482Z,1710541608.482 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T22:21:48.3Z 2024-03-15T22:26:48.483Z,1710541608.483 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T22:26:48.483Z,1710541608.483 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T22:26:55.211Z,1710541615.211 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0016.lzma 2024-03-15T22:26:56.214Z,1710541616.214 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0016.lzma.bak 2024-03-15T22:26:56.214Z,1710541616.214 [DataOverHttps](INFO): SBD MOMSN=19322509 2024-03-15T22:27:11.776Z,1710541631.776 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240315T213516/Express0017.lzma 2024-03-15T22:27:12.778Z,1710541632.778 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0017.lzma.bak 2024-03-15T22:27:12.778Z,1710541632.778 [DataOverHttps](INFO): SBD MOMSN=19322511 2024-03-15T22:27:13.936Z,1710541633.936 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T22:27:13.936Z,1710541633.936 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T22:27:13.936Z,1710541633.936 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T22:29:05.424Z,1710541745.424 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T22:29:05.424Z,1710541745.424 [NAL9602] Data Fault, FailCount= 5 2024-03-15T22:29:05.424Z,1710541745.424 [NAL9602](ERROR): Data Fault 2024-03-15T22:29:05.447Z,1710541745.447 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T22:29:05.448Z,1710541745.448 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-03-15T22:29:05.822Z,1710541745.822 [NAL9602](INFO): Powering down 2024-03-15T22:29:05.867Z,1710541745.867 [CommandExec](FAULT): Scheduling is paused 2024-03-15T22:29:05.868Z,1710541745.868 [CBIT](INFO): Critical error at 20240315T222905 2024-03-15T22:29:05.872Z,1710541745.872 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-03-15T22:29:44.617Z,1710541784.617 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2024-03-15T22:29:44.617Z,1710541784.617 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:29:44.628Z,1710541784.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:29:45.034Z,1710541785.034 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:29:45.034Z,1710541785.034 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2024-03-15T22:31:05.454Z,1710541865.454 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T22:31:05.454Z,1710541865.454 [NAL9602] No Fault, FailCount= 5 2024-03-15T22:31:05.816Z,1710541865.816 [NAL9602](INFO): Powering up NAL9602 2024-03-15T22:31:16.722Z,1710541876.722 [NAL9602](INFO): NAL9602 initialized 2024-03-15T22:32:14.516Z,1710541934.516 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T22:32:14.516Z,1710541934.516 [Default:CheckIn:C.Wait] Stopped 2024-03-15T22:32:14.516Z,1710541934.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T22:32:14.516Z,1710541934.516 [Default:CheckIn:D] Running Loop=1 2024-03-15T22:32:14.906Z,1710541934.906 [Default:CheckIn:D] Stopped 2024-03-15T22:32:14.906Z,1710541934.906 [Default:CheckIn:E] Running Loop=1 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.473596 min 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn:E] Stopped 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn] Stopped 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn](INFO): Running loop #6 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn] Running Loop=6 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T22:32:15.307Z,1710541935.307 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T22:32:45.610Z,1710541965.610 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2024-03-15T22:32:45.610Z,1710541965.610 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:32:45.621Z,1710541965.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:32:46.040Z,1710541966.040 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:32:46.040Z,1710541966.040 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2024-03-15T22:35:46.606Z,1710542146.606 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2024-03-15T22:35:46.606Z,1710542146.606 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:35:46.616Z,1710542146.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:35:46.617Z,1710542146.617 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:37:15.506Z,1710542235.506 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T22:32:15.3Z 2024-03-15T22:37:15.506Z,1710542235.506 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T22:37:15.506Z,1710542235.506 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T22:37:23.168Z,1710542243.168 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240315T213516/Courier0019.lzma 2024-03-15T22:37:24.170Z,1710542244.170 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0019.lzma.bak 2024-03-15T22:37:24.170Z,1710542244.170 [DataOverHttps](INFO): SBD MOMSN=19322526 2024-03-15T22:37:39.580Z,1710542259.580 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20240315T213516/Express0020.lzma 2024-03-15T22:37:40.582Z,1710542260.582 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0020.lzma.bak 2024-03-15T22:37:40.582Z,1710542260.582 [DataOverHttps](INFO): SBD MOMSN=19322532 2024-03-15T22:37:41.751Z,1710542261.751 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T22:37:41.751Z,1710542261.751 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T22:37:41.751Z,1710542261.751 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T22:41:18.274Z,1710542478.274 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T22:41:18.274Z,1710542478.274 [NAL9602] Data Fault, FailCount= 1 2024-03-15T22:41:18.274Z,1710542478.274 [NAL9602](ERROR): Data Fault 2024-03-15T22:41:18.349Z,1710542478.349 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T22:41:18.677Z,1710542478.677 [NAL9602](INFO): Powering down 2024-03-15T22:41:19.517Z,1710542479.517 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T22:41:19.517Z,1710542479.517 [NAL9602] No Fault, FailCount= 1 2024-03-15T22:41:48.977Z,1710542508.977 [NAL9602](INFO): Powering up NAL9602 2024-03-15T22:41:59.890Z,1710542519.890 [NAL9602](INFO): NAL9602 initialized 2024-03-15T22:42:33.010Z,1710542553.010 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:42:42.340Z,1710542562.340 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T22:42:42.341Z,1710542562.341 [Default:CheckIn:C.Wait] Stopped 2024-03-15T22:42:42.341Z,1710542562.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T22:42:42.341Z,1710542562.341 [Default:CheckIn:D] Running Loop=1 2024-03-15T22:42:42.747Z,1710542562.747 [Default:CheckIn:D] Stopped 2024-03-15T22:42:42.747Z,1710542562.747 [Default:CheckIn:E] Running Loop=1 2024-03-15T22:42:43.126Z,1710542563.126 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.937610 min 2024-03-15T22:42:43.126Z,1710542563.126 [Default:CheckIn:E] Stopped 2024-03-15T22:42:43.126Z,1710542563.126 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T22:42:43.126Z,1710542563.126 [Default:CheckIn] Stopped 2024-03-15T22:42:43.126Z,1710542563.126 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T22:42:43.126Z,1710542563.126 [Default:CheckIn](INFO): Running loop #7 2024-03-15T22:42:43.127Z,1710542563.127 [Default:CheckIn] Running Loop=7 2024-03-15T22:42:43.127Z,1710542563.127 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T22:42:43.127Z,1710542563.127 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T22:42:45.937Z,1710542565.937 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:42:58.865Z,1710542578.865 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:43:12.198Z,1710542592.198 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:43:24.721Z,1710542604.721 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:43:36.841Z,1710542616.841 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:43:50.173Z,1710542630.173 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:46:20.465Z,1710542780.465 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-15T22:46:24.505Z,1710542784.505 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-15T22:46:24.511Z,1710542784.511 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-15T22:46:24.530Z,1710542784.530 [BPC1](INFO): Received data from all battery sticks. 2024-03-15T22:47:43.299Z,1710542863.299 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T22:42:43.1Z 2024-03-15T22:47:43.299Z,1710542863.299 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T22:47:43.299Z,1710542863.299 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T22:47:50.807Z,1710542870.807 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240315T213516/Courier0022.lzma 2024-03-15T22:47:51.810Z,1710542871.810 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0022.lzma.bak 2024-03-15T22:47:51.810Z,1710542871.810 [DataOverHttps](INFO): SBD MOMSN=19322544 2024-03-15T22:48:07.408Z,1710542887.408 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240315T213516/Express0023.lzma 2024-03-15T22:48:08.410Z,1710542888.410 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0023.lzma.bak 2024-03-15T22:48:08.410Z,1710542888.410 [DataOverHttps](INFO): SBD MOMSN=19322552 2024-03-15T22:48:09.581Z,1710542889.581 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T22:48:09.581Z,1710542889.581 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T22:48:09.582Z,1710542889.582 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T22:50:46.758Z,1710543046.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:50:46.758Z,1710543046.758 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2024-03-15T22:52:03.470Z,1710543123.470 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T22:52:03.470Z,1710543123.470 [NAL9602] Data Fault, FailCount= 2 2024-03-15T22:52:03.470Z,1710543123.470 [NAL9602](ERROR): Data Fault 2024-03-15T22:52:03.486Z,1710543123.486 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T22:52:03.875Z,1710543123.875 [NAL9602](INFO): Powering down 2024-03-15T22:52:04.718Z,1710543124.718 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T22:52:04.718Z,1710543124.718 [NAL9602] No Fault, FailCount= 2 2024-03-15T22:52:34.174Z,1710543154.174 [NAL9602](INFO): Powering up NAL9602 2024-03-15T22:52:45.083Z,1710543165.083 [NAL9602](INFO): NAL9602 initialized 2024-03-15T22:53:10.158Z,1710543190.158 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T22:53:10.158Z,1710543190.158 [Default:CheckIn:C.Wait] Stopped 2024-03-15T22:53:10.158Z,1710543190.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T22:53:10.158Z,1710543190.158 [Default:CheckIn:D] Running Loop=1 2024-03-15T22:53:10.573Z,1710543190.573 [Default:CheckIn:D] Stopped 2024-03-15T22:53:10.573Z,1710543190.573 [Default:CheckIn:E] Running Loop=1 2024-03-15T22:53:10.943Z,1710543190.943 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.401375 min 2024-03-15T22:53:10.943Z,1710543190.943 [Default:CheckIn:E] Stopped 2024-03-15T22:53:10.943Z,1710543190.943 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T22:53:10.943Z,1710543190.943 [Default:CheckIn] Stopped 2024-03-15T22:53:10.943Z,1710543190.943 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T22:53:10.944Z,1710543190.944 [Default:CheckIn](INFO): Running loop #8 2024-03-15T22:53:10.944Z,1710543190.944 [Default:CheckIn] Running Loop=8 2024-03-15T22:53:10.944Z,1710543190.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T22:53:10.944Z,1710543190.944 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T22:53:47.327Z,1710543227.327 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-03-15T22:53:47.327Z,1710543227.327 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:53:47.341Z,1710543227.341 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:53:47.765Z,1710543227.765 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:53:47.765Z,1710543227.765 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-03-15T22:56:48.320Z,1710543408.320 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-03-15T22:56:48.320Z,1710543408.320 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:56:48.367Z,1710543408.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:56:48.739Z,1710543408.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:56:48.739Z,1710543408.739 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-03-15T22:58:11.139Z,1710543491.139 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T22:53:10.9Z 2024-03-15T22:58:11.139Z,1710543491.139 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T22:58:11.139Z,1710543491.139 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T22:58:18.244Z,1710543498.244 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0025.lzma 2024-03-15T22:58:19.246Z,1710543499.246 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0025.lzma.bak 2024-03-15T22:58:19.246Z,1710543499.246 [DataOverHttps](INFO): SBD MOMSN=19322564 2024-03-15T22:58:34.672Z,1710543514.672 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240315T213516/Express0026.lzma 2024-03-15T22:58:35.674Z,1710543515.674 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0026.lzma.bak 2024-03-15T22:58:35.674Z,1710543515.674 [DataOverHttps](INFO): SBD MOMSN=19322566 2024-03-15T22:58:36.980Z,1710543516.980 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T22:58:36.980Z,1710543516.980 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T22:58:36.980Z,1710543516.980 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T22:59:49.306Z,1710543589.306 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-03-15T22:59:49.306Z,1710543589.306 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T22:59:49.332Z,1710543589.332 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T22:59:49.706Z,1710543589.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T22:59:49.706Z,1710543589.706 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-03-15T23:02:46.274Z,1710543766.274 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T23:02:46.274Z,1710543766.274 [NAL9602] Data Fault, FailCount= 3 2024-03-15T23:02:46.274Z,1710543766.274 [NAL9602](ERROR): Data Fault 2024-03-15T23:02:46.320Z,1710543766.320 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T23:02:46.673Z,1710543766.673 [NAL9602](INFO): Powering down 2024-03-15T23:02:47.515Z,1710543767.515 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T23:02:47.515Z,1710543767.515 [NAL9602] No Fault, FailCount= 3 2024-03-15T23:02:50.322Z,1710543770.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-03-15T23:02:50.322Z,1710543770.322 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:02:50.333Z,1710543770.333 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:02:50.747Z,1710543770.747 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:02:50.747Z,1710543770.747 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-03-15T23:03:16.978Z,1710543796.978 [NAL9602](INFO): Powering up NAL9602 2024-03-15T23:03:27.882Z,1710543807.882 [NAL9602](INFO): NAL9602 initialized 2024-03-15T23:03:37.586Z,1710543817.586 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T23:03:37.586Z,1710543817.586 [Default:CheckIn:C.Wait] Stopped 2024-03-15T23:03:37.587Z,1710543817.587 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T23:03:37.587Z,1710543817.587 [Default:CheckIn:D] Running Loop=1 2024-03-15T23:03:38.001Z,1710543818.001 [Default:CheckIn:D] Stopped 2024-03-15T23:03:38.001Z,1710543818.001 [Default:CheckIn:E] Running Loop=1 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.858512 min 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn:E] Stopped 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn] Stopped 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn](INFO): Running loop #9 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn] Running Loop=9 2024-03-15T23:03:38.395Z,1710543818.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T23:03:38.396Z,1710543818.396 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T23:05:51.316Z,1710543951.316 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-03-15T23:05:51.316Z,1710543951.316 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:05:51.326Z,1710543951.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:05:51.736Z,1710543951.736 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:05:51.736Z,1710543951.736 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-03-15T23:08:38.567Z,1710544118.567 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T23:03:38.4Z 2024-03-15T23:08:38.567Z,1710544118.567 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T23:08:38.567Z,1710544118.567 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T23:08:45.840Z,1710544125.840 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0028.lzma 2024-03-15T23:08:46.842Z,1710544126.842 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0028.lzma.bak 2024-03-15T23:08:46.842Z,1710544126.842 [DataOverHttps](INFO): SBD MOMSN=19322582 2024-03-15T23:08:52.321Z,1710544132.321 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-03-15T23:08:52.321Z,1710544132.321 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:08:52.373Z,1710544132.373 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:08:52.733Z,1710544132.733 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:08:52.733Z,1710544132.733 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-03-15T23:09:01.641Z,1710544141.641 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20240315T213516/Express0029.lzma 2024-03-15T23:09:02.642Z,1710544142.642 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0029.lzma.bak 2024-03-15T23:09:02.642Z,1710544142.642 [DataOverHttps](INFO): SBD MOMSN=19322584 2024-03-15T23:09:04.037Z,1710544144.037 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T23:09:04.037Z,1710544144.037 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T23:09:04.038Z,1710544144.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T23:11:53.349Z,1710544313.349 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-03-15T23:11:53.349Z,1710544313.349 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:11:53.360Z,1710544313.360 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:11:53.769Z,1710544313.769 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:11:53.769Z,1710544313.769 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-03-15T23:13:30.694Z,1710544410.694 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T23:13:30.694Z,1710544410.694 [NAL9602] Data Fault, FailCount= 4 2024-03-15T23:13:30.694Z,1710544410.694 [NAL9602](ERROR): Data Fault 2024-03-15T23:13:30.738Z,1710544410.738 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T23:13:31.102Z,1710544411.102 [NAL9602](INFO): Powering down 2024-03-15T23:13:31.942Z,1710544411.942 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T23:13:31.942Z,1710544411.942 [NAL9602] No Fault, FailCount= 4 2024-03-15T23:14:01.406Z,1710544441.406 [NAL9602](INFO): Powering up NAL9602 2024-03-15T23:14:04.645Z,1710544444.645 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T23:14:04.645Z,1710544444.645 [Default:CheckIn:C.Wait] Stopped 2024-03-15T23:14:04.645Z,1710544444.645 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T23:14:04.646Z,1710544444.646 [Default:CheckIn:D] Running Loop=1 2024-03-15T23:14:05.050Z,1710544445.050 [Default:CheckIn:D] Stopped 2024-03-15T23:14:05.050Z,1710544445.050 [Default:CheckIn:E] Running Loop=1 2024-03-15T23:14:05.450Z,1710544445.450 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.309334 min 2024-03-15T23:14:05.450Z,1710544445.450 [Default:CheckIn:E] Stopped 2024-03-15T23:14:05.450Z,1710544445.450 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T23:14:05.450Z,1710544445.450 [Default:CheckIn] Stopped 2024-03-15T23:14:05.450Z,1710544445.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T23:14:05.450Z,1710544445.450 [Default:CheckIn](INFO): Running loop #10 2024-03-15T23:14:05.451Z,1710544445.451 [Default:CheckIn] Running Loop=10 2024-03-15T23:14:05.451Z,1710544445.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T23:14:05.451Z,1710544445.451 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T23:14:12.306Z,1710544452.306 [NAL9602](INFO): NAL9602 initialized 2024-03-15T23:14:54.336Z,1710544494.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-03-15T23:14:54.336Z,1710544494.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:14:54.346Z,1710544494.346 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:14:54.756Z,1710544494.756 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:14:54.756Z,1710544494.756 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-03-15T23:17:55.325Z,1710544675.325 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2024-03-15T23:17:55.325Z,1710544675.325 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:17:55.364Z,1710544675.364 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:17:55.738Z,1710544675.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:17:55.738Z,1710544675.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2024-03-15T23:19:05.643Z,1710544745.643 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T23:14:05.5Z 2024-03-15T23:19:05.643Z,1710544745.643 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T23:19:05.643Z,1710544745.643 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T23:19:12.807Z,1710544752.807 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0031.lzma 2024-03-15T23:19:13.806Z,1710544753.806 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0031.lzma.bak 2024-03-15T23:19:13.806Z,1710544753.806 [DataOverHttps](INFO): SBD MOMSN=19322602 2024-03-15T23:19:29.464Z,1710544769.464 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240315T213516/Express0032.lzma 2024-03-15T23:19:30.466Z,1710544770.466 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0032.lzma.bak 2024-03-15T23:19:30.466Z,1710544770.466 [DataOverHttps](INFO): SBD MOMSN=19322604 2024-03-15T23:19:31.884Z,1710544771.884 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T23:19:31.884Z,1710544771.884 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T23:19:31.884Z,1710544771.884 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T23:20:56.399Z,1710544856.399 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2024-03-15T23:20:56.399Z,1710544856.399 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:20:56.410Z,1710544856.410 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:20:56.812Z,1710544856.812 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:20:56.812Z,1710544856.812 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2024-03-15T23:23:57.401Z,1710545037.401 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2024-03-15T23:23:57.401Z,1710545037.401 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:23:57.411Z,1710545037.411 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:23:57.820Z,1710545037.820 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:23:57.820Z,1710545037.820 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2024-03-15T23:24:15.147Z,1710545055.147 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T23:24:15.147Z,1710545055.147 [NAL9602] Data Fault, FailCount= 5 2024-03-15T23:24:15.147Z,1710545055.147 [NAL9602](ERROR): Data Fault 2024-03-15T23:24:15.172Z,1710545055.172 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T23:24:15.172Z,1710545055.172 [CBIT](CRITICAL): Data Fault in component: NAL9602 2024-03-15T23:24:15.545Z,1710545055.545 [NAL9602](INFO): Powering down 2024-03-15T23:24:15.586Z,1710545055.586 [CBIT](INFO): Critical error at 20240315T232415 2024-03-15T23:24:32.525Z,1710545072.525 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T23:24:32.525Z,1710545072.525 [Default:CheckIn:C.Wait] Stopped 2024-03-15T23:24:32.525Z,1710545072.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T23:24:32.525Z,1710545072.525 [Default:CheckIn:D] Running Loop=1 2024-03-15T23:24:32.930Z,1710545072.930 [Default:CheckIn:D] Stopped 2024-03-15T23:24:32.930Z,1710545072.930 [Default:CheckIn:E] Running Loop=1 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.773983 min 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn:E] Stopped 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn] Stopped 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn](INFO): Running loop #11 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn] Running Loop=11 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T23:24:33.339Z,1710545073.339 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T23:26:15.169Z,1710545175.169 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T23:26:15.169Z,1710545175.169 [NAL9602] No Fault, FailCount= 5 2024-03-15T23:26:15.538Z,1710545175.538 [NAL9602](INFO): Powering up NAL9602 2024-03-15T23:26:26.448Z,1710545186.448 [NAL9602](INFO): NAL9602 initialized 2024-03-15T23:26:58.384Z,1710545218.384 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2024-03-15T23:26:58.384Z,1710545218.384 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:26:58.395Z,1710545218.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:26:58.782Z,1710545218.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:26:58.782Z,1710545218.782 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2024-03-15T23:29:33.549Z,1710545373.549 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T23:24:33.3Z 2024-03-15T23:29:33.549Z,1710545373.549 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T23:29:33.549Z,1710545373.549 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T23:29:40.764Z,1710545380.764 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20240315T213516/Courier0034.lzma 2024-03-15T23:29:41.766Z,1710545381.766 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0034.lzma.bak 2024-03-15T23:29:41.766Z,1710545381.766 [DataOverHttps](INFO): SBD MOMSN=19322622 2024-03-15T23:29:57.200Z,1710545397.200 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240315T213516/Express0035.lzma 2024-03-15T23:29:58.210Z,1710545398.210 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0035.lzma.bak 2024-03-15T23:29:58.210Z,1710545398.210 [DataOverHttps](INFO): SBD MOMSN=19322625 2024-03-15T23:29:59.377Z,1710545399.377 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2024-03-15T23:29:59.377Z,1710545399.377 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:29:59.378Z,1710545399.378 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T23:29:59.378Z,1710545399.378 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T23:29:59.378Z,1710545399.378 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T23:29:59.387Z,1710545399.387 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:29:59.784Z,1710545399.784 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:29:59.784Z,1710545399.784 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2024-03-15T23:33:00.415Z,1710545580.415 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2024-03-15T23:33:00.416Z,1710545580.416 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:33:00.426Z,1710545580.426 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:33:00.832Z,1710545580.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:33:00.832Z,1710545580.832 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2024-03-15T23:34:59.991Z,1710545699.991 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T23:34:59.991Z,1710545699.991 [Default:CheckIn:C.Wait] Stopped 2024-03-15T23:34:59.991Z,1710545699.991 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T23:34:59.991Z,1710545699.991 [Default:CheckIn:D] Running Loop=1 2024-03-15T23:35:00.405Z,1710545700.405 [Default:CheckIn:D] Stopped 2024-03-15T23:35:00.405Z,1710545700.405 [Default:CheckIn:E] Running Loop=1 2024-03-15T23:35:00.797Z,1710545700.797 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.231901 min 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn:E] Stopped 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn] Stopped 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn](INFO): Running loop #12 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn] Running Loop=12 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T23:35:00.798Z,1710545700.798 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T23:36:01.419Z,1710545761.419 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2024-03-15T23:36:01.419Z,1710545761.419 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:36:01.429Z,1710545761.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:36:01.850Z,1710545761.850 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:36:01.851Z,1710545761.851 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2024-03-15T23:36:28.450Z,1710545788.450 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T23:36:28.450Z,1710545788.450 [NAL9602] Data Fault, FailCount= 1 2024-03-15T23:36:28.450Z,1710545788.450 [NAL9602](ERROR): Data Fault 2024-03-15T23:36:28.468Z,1710545788.468 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T23:36:28.864Z,1710545788.864 [NAL9602](INFO): Powering down 2024-03-15T23:36:29.682Z,1710545789.682 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T23:36:29.682Z,1710545789.682 [NAL9602] No Fault, FailCount= 1 2024-03-15T23:36:59.158Z,1710545819.158 [NAL9602](INFO): Powering up NAL9602 2024-03-15T23:37:10.072Z,1710545830.072 [NAL9602](INFO): NAL9602 initialized 2024-03-15T23:39:02.405Z,1710545942.405 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2024-03-15T23:39:02.405Z,1710545942.405 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:39:02.416Z,1710545942.416 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:39:02.824Z,1710545942.824 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:39:02.824Z,1710545942.824 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2024-03-15T23:40:00.995Z,1710546000.995 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-15T23:35:00.8Z 2024-03-15T23:40:00.995Z,1710546000.995 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T23:40:00.995Z,1710546000.995 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-15T23:40:07.980Z,1710546007.980 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240315T213516/Courier0037.lzma 2024-03-15T23:40:08.982Z,1710546008.982 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Courier0037.lzma.bak 2024-03-15T23:40:08.982Z,1710546008.982 [DataOverHttps](INFO): SBD MOMSN=19322641 2024-03-15T23:40:24.424Z,1710546024.424 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240315T213516/Express0038.lzma 2024-03-15T23:40:25.426Z,1710546025.426 [DataOverHttps](INFO): Moved sent file to Logs/20240315T213516/Express0038.lzma.bak 2024-03-15T23:40:25.426Z,1710546025.426 [DataOverHttps](INFO): SBD MOMSN=19322643 2024-03-15T23:40:26.838Z,1710546026.838 [Default:CheckIn:Read_Iridium] Stopped 2024-03-15T23:40:26.838Z,1710546026.838 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-15T23:40:26.838Z,1710546026.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-15T23:42:03.378Z,1710546123.378 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2024-03-15T23:42:03.378Z,1710546123.378 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:42:03.389Z,1710546123.389 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:42:03.801Z,1710546123.801 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:42:03.801Z,1710546123.801 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2024-03-15T23:45:04.430Z,1710546304.430 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2024-03-15T23:45:04.430Z,1710546304.430 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-15T23:45:04.441Z,1710546304.441 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-15T23:45:04.826Z,1710546304.826 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-15T23:45:04.826Z,1710546304.826 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2024-03-15T23:45:27.447Z,1710546327.447 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-15T23:45:27.447Z,1710546327.447 [Default:CheckIn:C.Wait] Stopped 2024-03-15T23:45:27.447Z,1710546327.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-15T23:45:27.447Z,1710546327.447 [Default:CheckIn:D] Running Loop=1 2024-03-15T23:45:27.854Z,1710546327.854 [Default:CheckIn:D] Stopped 2024-03-15T23:45:27.854Z,1710546327.854 [Default:CheckIn:E] Running Loop=1 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.689396 min 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn:E] Stopped 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn] Stopped 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn](INFO): Running loop #13 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn] Running Loop=13 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-15T23:45:28.251Z,1710546328.251 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-15T23:47:13.670Z,1710546433.670 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-15T23:47:13.670Z,1710546433.670 [NAL9602] Data Fault, FailCount= 2 2024-03-15T23:47:13.670Z,1710546433.670 [NAL9602](ERROR): Data Fault 2024-03-15T23:47:13.707Z,1710546433.707 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-15T23:47:14.070Z,1710546434.070 [NAL9602](INFO): Powering down 2024-03-15T23:47:14.912Z,1710546434.912 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-15T23:47:14.912Z,1710546434.912 [NAL9602] No Fault, FailCount= 2 2024-03-15T23:47:22.241Z,1710546442.241 [CommandExec](IMPORTANT): got command quit 2024-03-15T23:47:23.244Z,1710546443.244 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:23.244Z,1710546443.244 [CommandExec](INFO): Uninitializing the command executive. 2024-03-15T23:47:23.244Z,1710546443.244 [CommandExec](INFO): Uninitializing the command scheduler. 2024-03-15T23:47:23.245Z,1710546443.245 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:23.320Z,1710546443.320 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-03-15T23:47:23.320Z,1710546443.320 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-03-15T23:47:23.320Z,1710546443.320 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:23.321Z,1710546443.321 [NavChartDb](INFO): Join timeout helper Thread ID is 6797 2024-03-15T23:47:23.492Z,1710546443.492 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:23.493Z,1710546443.493 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:23.508Z,1710546443.508 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-03-15T23:47:23.508Z,1710546443.508 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:23.509Z,1710546443.509 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6798 2024-03-15T23:47:23.868Z,1710546443.868 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:23.869Z,1710546443.869 [WetLabsBB2FL](INFO): Powering down 2024-03-15T23:47:23.869Z,1710546443.869 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:23.884Z,1710546443.884 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-03-15T23:47:23.884Z,1710546443.884 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:23.885Z,1710546443.885 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6799 2024-03-15T23:47:24.552Z,1710546444.552 [CTD_Seabird](INFO): Powering down 2024-03-15T23:47:24.572Z,1710546444.572 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:24.573Z,1710546444.573 [CTD_Seabird](INFO): Powering down 2024-03-15T23:47:24.584Z,1710546444.584 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:24.592Z,1710546444.592 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-03-15T23:47:24.593Z,1710546444.593 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:24.594Z,1710546444.594 [Radio_Surface](INFO): Join timeout helper Thread ID is 6800 2024-03-15T23:47:24.704Z,1710546444.704 [Radio_Surface](INFO): Powering down 2024-03-15T23:47:24.706Z,1710546444.706 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:24.706Z,1710546444.706 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:24.712Z,1710546444.712 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-03-15T23:47:24.712Z,1710546444.712 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:24.713Z,1710546444.713 [Onboard](INFO): Join timeout helper Thread ID is 6801 2024-03-15T23:47:24.861Z,1710546444.861 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-03-15T23:47:29.196Z,1710546449.196 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:29.197Z,1710546449.197 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.215Z,1710546449.215 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-03-15T23:47:29.215Z,1710546449.215 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.215Z,1710546449.215 [DataOverHttps](INFO): Join timeout helper Thread ID is 6802 2024-03-15T23:47:29.880Z,1710546449.880 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:29.881Z,1710546449.881 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.889Z,1710546449.889 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-03-15T23:47:29.889Z,1710546449.889 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.889Z,1710546449.889 [logger](INFO): Join timeout helper Thread ID is 6803 2024-03-15T23:47:29.892Z,1710546449.892 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:29.893Z,1710546449.893 [logger ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.909Z,1710546449.909 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-03-15T23:47:29.909Z,1710546449.909 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.909Z,1710546449.909 [CommandLine](INFO): Join timeout helper Thread ID is 6804 2024-03-15T23:47:29.960Z,1710546449.960 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:29.961Z,1710546449.961 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.969Z,1710546449.969 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-03-15T23:47:29.969Z,1710546449.969 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.969Z,1710546449.969 [CommandExec](INFO): Join timeout helper Thread ID is 6805 2024-03-15T23:47:29.971Z,1710546449.971 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-03-15T23:47:29.971Z,1710546449.971 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:29.971Z,1710546449.971 [controlThread](INFO): Join timeout helper Thread ID is 6806 2024-03-15T23:47:30.216Z,1710546450.216 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-03-15T23:47:30.217Z,1710546450.217 [controlThread](DEBUG): Uninitializing ControlThread 2024-03-15T23:47:30.217Z,1710546450.217 [AHRS_M2](INFO): Powering down 2024-03-15T23:47:30.289Z,1710546450.289 [NAL9602](INFO): Powering down 2024-03-15T23:47:30.292Z,1710546450.292 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-03-15T23:47:30.293Z,1710546450.293 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-03-15T23:47:30.293Z,1710546450.293 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-03-15T23:47:30.294Z,1710546450.294 [MissionManager](INFO): Uninitializing Mission Default 2024-03-15T23:47:30.294Z,1710546450.294 [Default] Stopped 2024-03-15T23:47:30.294Z,1710546450.294 [Default](DEBUG): Aggregate::uninitialize Default 2024-03-15T23:47:30.294Z,1710546450.294 [Default:B.GoToSurface] Stopped 2024-03-15T23:47:30.294Z,1710546450.294 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-15T23:47:30.294Z,1710546450.294 [Default:CheckIn] Stopped 2024-03-15T23:47:30.294Z,1710546450.294 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-15T23:47:30.294Z,1710546450.294 [Default:CheckIn:Read_GPS] Stopped 2024-03-15T23:47:30.297Z,1710546450.297 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-03-15T23:47:30.297Z,1710546450.297 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-03-15T23:47:30.298Z,1710546450.298 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-03-15T23:47:30.298Z,1710546450.298 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-03-15T23:47:30.298Z,1710546450.298 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-03-15T23:47:30.298Z,1710546450.298 [BuoyancyServo](INFO): Powering down 2024-03-15T23:47:30.312Z,1710546450.312 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-03-15T23:47:30.312Z,1710546450.312 [ElevatorServo](INFO): Powering down 2024-03-15T23:47:30.313Z,1710546450.313 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-03-15T23:47:30.313Z,1710546450.313 [MassServo](INFO): Powering down 2024-03-15T23:47:30.314Z,1710546450.314 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-03-15T23:47:30.314Z,1710546450.314 [RudderServo](INFO): Powering down 2024-03-15T23:47:30.315Z,1710546450.315 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-03-15T23:47:30.315Z,1710546450.315 [ThrusterHE](INFO): Powering down 2024-03-15T23:47:30.316Z,1710546450.316 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-03-15T23:47:30.316Z,1710546450.316 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-03-15T23:47:30.317Z,1710546450.317 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-03-15T23:47:30.317Z,1710546450.317 [CBIT](DEBUG): Powering off loads. 2024-03-15T23:47:30.328Z,1710546450.328 [CBIT](DEBUG): Disabling WDT. 2024-03-15T23:47:30.340Z,1710546450.340 [CBIT](DEBUG): Opening all GF detection circuits. 2024-03-15T23:47:30.341Z,1710546450.341 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:30.390Z,1710546450.390 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:30.393Z,1710546450.393 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:30.399Z,1710546450.399 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:30.472Z,1710546450.472 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:30.476Z,1710546450.476 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:30.521Z,1710546450.521 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-03-15T23:47:30.585Z,1710546450.585 [logger ThreadHandler](INFO): Thread cancelled.