2025-03-18T18:59:30.693Z,1742324370.693 [Supervisor](DEBUG): Initializing supervisor. 2025-03-18T18:59:30.697Z,1742324370.697 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-03-18T18:59:30.698Z,1742324370.698 [SyncHandler](INFO): Protected caller Thread ID is 2539 2025-03-18T18:59:30.698Z,1742324370.698 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-03-18T18:59:30.699Z,1742324370.699 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-03-18T18:59:30.700Z,1742324370.700 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2540 2025-03-18T18:59:30.704Z,1742324370.704 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-03-18T18:59:30.723Z,1742324370.723 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-03-18T18:59:30.723Z,1742324370.723 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-03-18T18:59:30.724Z,1742324370.724 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2541 2025-03-18T18:59:30.728Z,1742324370.728 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-03-18T18:59:30.729Z,1742324370.729 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-03-18T18:59:30.729Z,1742324370.729 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2542 2025-03-18T18:59:30.732Z,1742324370.732 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-03-18T18:59:30.733Z,1742324370.733 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-03-18T18:59:30.733Z,1742324370.733 [logger ThreadHandler](INFO): Protected caller Thread ID is 2543 2025-03-18T18:59:30.737Z,1742324370.737 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-03-18T18:59:30.737Z,1742324370.737 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-03-18T18:59:30.739Z,1742324370.739 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-03-18T18:59:31.086Z,1742324371.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-03-18T18:59:31.086Z,1742324371.086 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-03-18T18:59:31.177Z,1742324371.177 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-03-18T18:59:31.295Z,1742324371.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-03-18T18:59:31.295Z,1742324371.295 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-03-18T18:59:31.436Z,1742324371.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-03-18T18:59:31.436Z,1742324371.436 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-03-18T18:59:31.792Z,1742324371.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-03-18T18:59:31.792Z,1742324371.792 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-03-18T18:59:31.900Z,1742324371.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-03-18T18:59:31.900Z,1742324371.900 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-03-18T18:59:32.404Z,1742324372.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-03-18T18:59:32.404Z,1742324372.404 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-03-18T18:59:32.642Z,1742324372.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-03-18T18:59:32.643Z,1742324372.643 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-03-18T18:59:32.733Z,1742324372.733 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-03-18T18:59:32.985Z,1742324372.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-03-18T18:59:32.985Z,1742324372.985 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-03-18T18:59:33.215Z,1742324373.215 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-03-18T18:59:33.215Z,1742324373.215 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-03-18T18:59:33.749Z,1742324373.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-03-18T18:59:33.750Z,1742324373.750 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-03-18T18:59:33.946Z,1742324373.946 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-03-18T18:59:33.947Z,1742324373.947 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-03-18T18:59:34.242Z,1742324374.242 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-03-18T18:59:34.243Z,1742324374.243 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-03-18T18:59:35.107Z,1742324375.107 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-03-18T18:59:35.108Z,1742324375.108 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-03-18T18:59:35.687Z,1742324375.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-03-18T18:59:35.689Z,1742324375.689 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-03-18T18:59:35.689Z,1742324375.689 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-03-18T18:59:35.806Z,1742324375.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-03-18T18:59:35.947Z,1742324375.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-03-18T18:59:36.041Z,1742324376.041 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-03-18T18:59:36.159Z,1742324376.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-03-18T18:59:36.257Z,1742324376.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-03-18T18:59:36.526Z,1742324376.526 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-03-18T18:59:36.526Z,1742324376.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-03-18T18:59:36.647Z,1742324376.647 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-03-18T18:59:36.755Z,1742324376.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-03-18T18:59:36.907Z,1742324376.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-03-18T18:59:37.005Z,1742324377.005 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 2025-03-18T18:59:37.006Z,1742324377.006 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-03-18T18:59:37.007Z,1742324377.007 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-03-18T18:59:37.143Z,1742324377.143 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-03-18T18:59:37.143Z,1742324377.143 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-03-18T18:59:37.198Z,1742324377.198 [VerticalControl](DEBUG): Construct VerticalControl. 2025-03-18T18:59:37.259Z,1742324377.259 [VerticalControl] Loaded 2025-03-18T18:59:37.260Z,1742324377.260 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-03-18T18:59:37.262Z,1742324377.262 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-03-18T18:59:37.309Z,1742324377.309 [HorizontalControl] Loaded 2025-03-18T18:59:37.309Z,1742324377.309 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-03-18T18:59:37.312Z,1742324377.312 [SpeedControl](DEBUG): Construct SpeedControl. 2025-03-18T18:59:37.315Z,1742324377.315 [SpeedControl] Loaded 2025-03-18T18:59:37.315Z,1742324377.315 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-03-18T18:59:37.318Z,1742324377.318 [LoopControl](DEBUG): Construct LoopControl. 2025-03-18T18:59:37.318Z,1742324377.318 [LoopControl] Loaded 2025-03-18T18:59:37.319Z,1742324377.319 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-03-18T18:59:37.319Z,1742324377.319 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-03-18T18:59:37.319Z,1742324377.319 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-03-18T18:59:37.380Z,1742324377.380 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-03-18T18:59:37.381Z,1742324377.381 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-03-18T18:59:37.860Z,1742324377.860 [AHRS_M2] Loaded 2025-03-18T18:59:37.861Z,1742324377.861 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-03-18T18:59:37.899Z,1742324377.899 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC4 2025-03-18T18:59:37.899Z,1742324377.899 [BackseatComponent] Loaded 2025-03-18T18:59:37.900Z,1742324377.900 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-03-18T18:59:37.901Z,1742324377.901 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-03-18T18:59:37.901Z,1742324377.901 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2623 2025-03-18T18:59:37.904Z,1742324377.904 [LcmUniversalReporter] Loaded 2025-03-18T18:59:37.904Z,1742324377.904 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-03-18T18:59:38.700Z,1742324378.700 [BPC1] Loaded 2025-03-18T18:59:38.700Z,1742324378.700 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-03-18T18:59:38.796Z,1742324378.796 [DAT] Loaded 2025-03-18T18:59:38.796Z,1742324378.796 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-03-18T18:59:38.797Z,1742324378.797 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-03-18T18:59:38.797Z,1742324378.797 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2624 2025-03-18T18:59:38.869Z,1742324378.869 [DataOverHttps] Loaded 2025-03-18T18:59:38.870Z,1742324378.870 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-03-18T18:59:38.871Z,1742324378.871 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-03-18T18:59:38.871Z,1742324378.871 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2625 2025-03-18T18:59:38.894Z,1742324378.894 [Depth_Keller] Loaded 2025-03-18T18:59:38.894Z,1742324378.894 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-03-18T18:59:38.899Z,1742324378.899 [DropWeight] Loaded 2025-03-18T18:59:38.899Z,1742324378.899 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-03-18T18:59:38.964Z,1742324378.964 [NAL9602] Loaded 2025-03-18T18:59:38.964Z,1742324378.964 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-03-18T18:59:38.993Z,1742324378.993 [Onboard] Loaded 2025-03-18T18:59:38.994Z,1742324378.994 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-03-18T18:59:38.995Z,1742324378.995 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-03-18T18:59:38.995Z,1742324378.995 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2626 2025-03-18T18:59:39.009Z,1742324379.009 [Power24vConverter] Loaded 2025-03-18T18:59:39.010Z,1742324379.010 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-03-18T18:59:39.025Z,1742324379.025 [Radio_Surface] Loaded 2025-03-18T18:59:39.026Z,1742324379.026 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-03-18T18:59:39.027Z,1742324379.027 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0 2025-03-18T18:59:39.027Z,1742324379.027 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2627 2025-03-18T18:59:39.043Z,1742324379.043 [Sonardyne_Nano] Loaded 2025-03-18T18:59:39.043Z,1742324379.043 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-03-18T18:59:39.067Z,1742324379.067 [Waterlinked] Loaded 2025-03-18T18:59:39.067Z,1742324379.067 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2025-03-18T18:59:39.068Z,1742324379.068 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-03-18T18:59:39.068Z,1742324379.068 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-03-18T18:59:39.079Z,1742324379.079 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-03-18T18:59:39.079Z,1742324379.079 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-03-18T18:59:39.109Z,1742324379.109 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-03-18T18:59:39.109Z,1742324379.109 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-03-18T18:59:39.273Z,1742324379.273 [SBIT](DEBUG): Construct Startup Built In Test. 2025-03-18T18:59:39.283Z,1742324379.283 [SBIT] Loaded 2025-03-18T18:59:39.283Z,1742324379.283 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-03-18T18:59:39.286Z,1742324379.286 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-03-18T18:59:39.298Z,1742324379.298 [IBIT] Loaded 2025-03-18T18:59:39.299Z,1742324379.299 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-03-18T18:59:39.304Z,1742324379.304 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-03-18T18:59:39.382Z,1742324379.382 [CBIT] Loaded 2025-03-18T18:59:39.383Z,1742324379.383 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-03-18T18:59:39.415Z,1742324379.415 [GFScanner] Loaded 2025-03-18T18:59:39.415Z,1742324379.415 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-03-18T18:59:39.415Z,1742324379.415 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-03-18T18:59:39.416Z,1742324379.416 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-03-18T18:59:39.767Z,1742324379.767 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-03-18T18:59:39.767Z,1742324379.767 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-03-18T18:59:39.819Z,1742324379.819 [DepthRateCalculator] Loaded 2025-03-18T18:59:39.819Z,1742324379.819 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-03-18T18:59:39.824Z,1742324379.824 [PitchRateCalculator] Loaded 2025-03-18T18:59:39.825Z,1742324379.825 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-03-18T18:59:39.835Z,1742324379.835 [SpeedCalculator] Loaded 2025-03-18T18:59:39.835Z,1742324379.835 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-03-18T18:59:39.840Z,1742324379.840 [YawRateCalculator] Loaded 2025-03-18T18:59:39.840Z,1742324379.840 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-03-18T18:59:39.859Z,1742324379.859 [ElevatorOffsetCalculator] Loaded 2025-03-18T18:59:39.859Z,1742324379.859 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-03-18T18:59:39.859Z,1742324379.859 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-03-18T18:59:39.860Z,1742324379.860 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-03-18T18:59:40.039Z,1742324380.039 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-03-18T18:59:40.040Z,1742324380.040 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-03-18T18:59:40.154Z,1742324380.154 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-03-18T18:59:40.154Z,1742324380.154 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-03-18T18:59:40.168Z,1742324380.168 [NavChart] Loaded 2025-03-18T18:59:40.168Z,1742324380.168 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-03-18T18:59:40.174Z,1742324380.174 [UniversalFixResidualReporter] Loaded 2025-03-18T18:59:40.174Z,1742324380.174 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-03-18T18:59:40.174Z,1742324380.174 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-03-18T18:59:40.175Z,1742324380.175 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-03-18T18:59:40.313Z,1742324380.313 [BuoyancyServo] Loaded 2025-03-18T18:59:40.314Z,1742324380.314 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-03-18T18:59:40.337Z,1742324380.337 [ElevatorServo] Loaded 2025-03-18T18:59:40.337Z,1742324380.337 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-03-18T18:59:40.359Z,1742324380.359 [MassServo] Loaded 2025-03-18T18:59:40.359Z,1742324380.359 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-03-18T18:59:40.380Z,1742324380.380 [RudderServo] Loaded 2025-03-18T18:59:40.380Z,1742324380.380 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-03-18T18:59:40.397Z,1742324380.397 [ThrusterHE] Loaded 2025-03-18T18:59:40.397Z,1742324380.397 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-03-18T18:59:40.398Z,1742324380.398 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-03-18T18:59:40.398Z,1742324380.398 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-03-18T18:59:40.566Z,1742324380.566 [CTD_Seabird] Loaded 2025-03-18T18:59:40.567Z,1742324380.567 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-03-18T18:59:40.568Z,1742324380.568 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C054E0 2025-03-18T18:59:40.568Z,1742324380.568 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2628 2025-03-18T18:59:40.604Z,1742324380.604 [ESPComponent] Loaded 2025-03-18T18:59:40.605Z,1742324380.605 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-03-18T18:59:40.627Z,1742324380.627 [PAR_Licor] Loaded 2025-03-18T18:59:40.627Z,1742324380.627 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-03-18T18:59:40.672Z,1742324380.672 [WetLabsBB2FL] Loaded 2025-03-18T18:59:40.673Z,1742324380.673 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-03-18T18:59:40.674Z,1742324380.674 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-03-18T18:59:40.674Z,1742324380.674 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2629 2025-03-18T18:59:40.675Z,1742324380.675 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-03-18T18:59:40.692Z,1742324380.692 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-03-18T18:59:40.695Z,1742324380.695 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-03-18T18:59:40.709Z,1742324380.709 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-03-18T18:59:40.710Z,1742324380.710 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-03-18T18:59:40.710Z,1742324380.710 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2630 2025-03-18T18:59:40.715Z,1742324380.715 [Supervisor](INFO): Main Thread ID is 2538 2025-03-18T18:59:40.715Z,1742324380.715 [Supervisor](DEBUG): Running supervisor. 2025-03-18T18:59:40.716Z,1742324380.716 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2631 2025-03-18T18:59:40.716Z,1742324380.716 [CommandExec](INFO): Initializing the command executive. 2025-03-18T18:59:40.718Z,1742324380.718 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2632 2025-03-18T18:59:40.720Z,1742324380.720 [controlThread ThreadHandler](INFO): Handler Thread ID is 2633 2025-03-18T18:59:40.720Z,1742324380.720 [controlThread](DEBUG): Initializing ControlThread 2025-03-18T18:59:40.721Z,1742324380.721 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-03-18T18:59:40.723Z,1742324380.723 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-03-18T18:59:40.723Z,1742324380.723 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-03-18T18:59:40.724Z,1742324380.724 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-03-18T18:59:40.729Z,1742324380.729 [SBIT](INFO): Initialize SBIT Component. 2025-03-18T18:59:40.729Z,1742324380.729 [SBIT](IMPORTANT): git: 2025-03-12_C 2025-03-18T18:59:40.729Z,1742324380.729 [SBIT](INFO): git hash: c930cdb2e5c1f1ff3673ee566a0d82f2333fbba8 2025-03-18T18:59:40.730Z,1742324380.730 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-03-18T18:59:40.731Z,1742324380.731 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2025-03-18T18:59:40.732Z,1742324380.732 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2025-03-18T18:59:40.732Z,1742324380.732 [IBIT](INFO): Initialize IBIT Component. 2025-03-18T18:59:40.733Z,1742324380.733 [CBIT](DEBUG): Initialize CBIT Component. 2025-03-18T18:59:40.734Z,1742324380.734 [logger ThreadHandler](INFO): Handler Thread ID is 2634 2025-03-18T18:59:40.746Z,1742324380.746 [CBIT](DEBUG): Initialized mux pins. 2025-03-18T18:59:40.747Z,1742324380.747 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-03-18T18:59:40.747Z,1742324380.747 [CBIT](DEBUG): Initializing the watchdog timer. 2025-03-18T18:59:40.755Z,1742324380.755 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2635 2025-03-18T18:59:40.767Z,1742324380.767 [DAT ThreadHandler](INFO): Handler Thread ID is 2636 2025-03-18T18:59:40.768Z,1742324380.768 [DAT](INFO): Powering up 2025-03-18T18:59:40.768Z,1742324380.768 [DAT](DEBUG): Initializing DAT. 2025-03-18T18:59:40.771Z,1742324380.771 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-03-18T18:59:40.771Z,1742324380.771 [CBIT](DEBUG): Initializing heartbeat. 2025-03-18T18:59:40.773Z,1742324380.773 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2638 2025-03-18T18:59:40.774Z,1742324380.774 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-03-18T18:59:40.787Z,1742324380.787 [Onboard ThreadHandler](INFO): Handler Thread ID is 2639 2025-03-18T18:59:40.804Z,1742324380.804 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2640 2025-03-18T18:59:40.820Z,1742324380.820 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2641 2025-03-18T18:59:40.821Z,1742324380.821 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-03-18T18:59:40.824Z,1742324380.824 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2643 2025-03-18T18:59:40.827Z,1742324380.827 [WetLabsBB2FL](INFO): Powering up 2025-03-18T18:59:40.828Z,1742324380.828 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2645 2025-03-18T18:59:40.831Z,1742324380.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-03-18T18:59:40.831Z,1742324380.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-03-18T18:59:40.831Z,1742324380.831 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-03-18T18:59:40.831Z,1742324380.831 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-03-18T18:59:40.832Z,1742324380.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-03-18T18:59:40.832Z,1742324380.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-03-18T18:59:40.832Z,1742324380.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-03-18T18:59:40.832Z,1742324380.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-03-18T18:59:40.842Z,1742324380.842 [CBIT](DEBUG): Deactivating emergency mode. 2025-03-18T18:59:40.882Z,1742324380.882 [CBIT](DEBUG): Backplane powered. 2025-03-18T18:59:40.883Z,1742324380.883 [GFScanner](DEBUG): Initializing GFScanner 2025-03-18T18:59:40.883Z,1742324380.883 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-03-18T18:59:40.884Z,1742324380.884 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-03-18T18:59:40.884Z,1742324380.884 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-03-18T18:59:40.884Z,1742324380.884 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-03-18T18:59:40.885Z,1742324380.885 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-03-18T18:59:40.886Z,1742324380.886 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-03-18T18:59:40.886Z,1742324380.886 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-03-18T18:59:40.891Z,1742324380.891 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-03-18T18:59:40.892Z,1742324380.892 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-03-18T18:59:40.892Z,1742324380.892 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-03-18T18:59:40.893Z,1742324380.893 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-03-18T18:59:40.963Z,1742324380.963 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-03-18T18:59:40.984Z,1742324380.984 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-18T18:59:41.048Z,1742324381.048 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2025-03-18T18:59:41.048Z,1742324381.048 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-03-18T18:59:41.049Z,1742324381.049 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-03-18T18:59:41.050Z,1742324381.050 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-03-18T18:59:41.223Z,1742324381.223 [Radio_Surface](INFO): Powering up 2025-03-18T18:59:41.361Z,1742324381.361 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-03-18T18:59:41.383Z,1742324381.383 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-03-18T18:59:41.384Z,1742324381.384 [Default:A.Wait](DEBUG): Construct Wait. 2025-03-18T18:59:41.386Z,1742324381.386 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-18T18:59:41.423Z,1742324381.423 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-03-18T18:59:41.426Z,1742324381.426 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-03-18T18:59:41.448Z,1742324381.448 [Default:E.Execute](DEBUG): Construct Execute. 2025-03-18T18:59:41.467Z,1742324381.467 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2025-03-18T18:59:41.472Z,1742324381.472 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-03-18T18:59:41.496Z,1742324381.496 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-03-18T18:59:41.556Z,1742324381.556 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2025-03-18T18:59:41.557Z,1742324381.557 [Power24vConverter](INFO): Powering up. 2025-03-18T18:59:41.558Z,1742324381.558 [Sonardyne_Nano](INFO): Initializing. 2025-03-18T18:59:41.589Z,1742324381.589 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-03-18T18:59:41.629Z,1742324381.629 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:59:41.635Z,1742324381.635 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-03-18T18:59:41.636Z,1742324381.636 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:59:41.643Z,1742324381.643 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-18T18:59:41.644Z,1742324381.644 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:59:41.651Z,1742324381.651 [MassServo](DEBUG): Initializing MassServo. 2025-03-18T18:59:41.652Z,1742324381.652 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-18T18:59:41.659Z,1742324381.659 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-18T18:59:41.660Z,1742324381.660 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-18T18:59:41.667Z,1742324381.667 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-18T18:59:41.669Z,1742324381.669 [CBIT](FAULT): Main Battery Failure 2025-03-18T18:59:41.921Z,1742324381.921 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-03-18T18:59:41.921Z,1742324381.921 [DropWeight] Hardware Fault, FailCount= 1 2025-03-18T18:59:41.921Z,1742324381.921 [DropWeight](ERROR): Hardware Fault 2025-03-18T18:59:41.958Z,1742324381.958 [CommandExec](FAULT): Scheduling is paused 2025-03-18T18:59:41.967Z,1742324381.967 [CBIT](INFO): Critical error at 20250318T185941 2025-03-18T18:59:41.967Z,1742324381.967 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-03-18T18:59:41.969Z,1742324381.969 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-03-18T18:59:41.970Z,1742324381.970 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-03-18T18:59:42.309Z,1742324382.309 [CBIT](INFO): Critical error at 20250318T185941 2025-03-18T18:59:42.935Z,1742324382.935 [WetLabsBB2FL](INFO): Powering down 2025-03-18T18:59:43.501Z,1742324383.501 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-03-18T18:59:44.803Z,1742324384.803 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-03-18T18:59:53.131Z,1742324393.131 [DAT](INFO): DAT read: 2025-03-18T18:59:53.132Z,1742324393.132 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-03-18T18:59:54.791Z,1742324394.791 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004725 2025-03-18T18:59:54.896Z,1742324394.896 [DAT](INFO): DAT read: MF Frequency Band 2025-03-18T18:59:54.897Z,1742324394.897 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-03-18T18:59:54.898Z,1742324394.898 [DAT](INFO): DAT read: Mar 18 2025 18:56:20 2025-03-18T18:59:55.904Z,1742324395.904 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-03-18T18:59:55.905Z,1742324395.905 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-03-18T18:59:55.905Z,1742324395.905 [DAT](INFO): commRate: 800 2025-03-18T18:59:57.971Z,1742324397.971 [DAT](INFO): entering command mode 2025-03-18T18:59:58.171Z,1742324398.171 [DAT](INFO): DAT read: 2025-03-18T18:59:58.172Z,1742324398.172 [DAT](INFO): DAT read: user:1> 2025-03-18T18:59:58.172Z,1742324398.172 [DAT](INFO): setting verbose to 3 2025-03-18T18:59:58.423Z,1742324398.423 [DAT](INFO): DAT read: user:1> 2025-03-18T18:59:58.424Z,1742324398.424 [DAT](INFO): DAT read: Verbose | 3 2025-03-18T18:59:58.425Z,1742324398.425 [DAT](INFO): set verbose to 3 2025-03-18T18:59:58.425Z,1742324398.425 [DAT](INFO): setting DatVerbose to 27440 2025-03-18T18:59:58.675Z,1742324398.675 [DAT](INFO): DAT read: user:2> 2025-03-18T18:59:58.676Z,1742324398.676 [DAT](INFO): DAT read: DatVerbose | 27440 2025-03-18T18:59:58.677Z,1742324398.677 [DAT](INFO): set DatVerbose to 27440 2025-03-18T18:59:58.677Z,1742324398.677 [DAT](INFO): setting transmit power to 8 2025-03-18T18:59:58.927Z,1742324398.927 [DAT](INFO): DAT read: user:3> 2025-03-18T18:59:58.929Z,1742324398.929 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-03-18T18:59:58.929Z,1742324398.929 [DAT](INFO): set transmit power to 8 2025-03-18T18:59:58.929Z,1742324398.929 [DAT](INFO): setting local address to 1 2025-03-18T18:59:59.180Z,1742324399.180 [DAT](INFO): DAT read: user:4> 2025-03-18T18:59:59.180Z,1742324399.180 [DAT](INFO): DAT read: LocalAddr | 1 2025-03-18T18:59:59.181Z,1742324399.181 [DAT](INFO): set local address to 1 2025-03-18T18:59:59.184Z,1742324399.184 [DAT](INFO): Setting time to: 18:59:59 And date to:3/18/2025 2025-03-18T18:59:59.443Z,1742324399.443 [DAT](INFO): DAT read: user:5> 2025-03-18T18:59:59.444Z,1742324399.444 [DAT](INFO): DAT read: Tue Mar 18, 2025 18:59:59 2025-03-18T18:59:59.445Z,1742324399.445 [DAT](INFO): Local DAT time set to Tue Mar 18, 2025 18:59:59 2025-03-18T19:00:08.992Z,1742324408.992 [NAL9602](INFO): Powering up NAL9602 2025-03-18T19:00:18.679Z,1742324418.679 [Waterlinked](INFO): Powering down 2025-03-18T19:00:29.920Z,1742324429.920 [NAL9602](ERROR): NAL9602 initialization error. 2025-03-18T19:00:29.920Z,1742324429.920 [NAL9602] Communications Fault, FailCount= 1 2025-03-18T19:00:29.920Z,1742324429.920 [NAL9602](ERROR): Communications Fault 2025-03-18T19:00:29.977Z,1742324429.977 [CBIT](ERROR): Communications Fault in component: NAL9602 2025-03-18T19:00:30.324Z,1742324430.324 [NAL9602](INFO): Powering down 2025-03-18T19:00:31.152Z,1742324431.152 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-18T19:00:31.152Z,1742324431.152 [NAL9602] No Fault, FailCount= 1 2025-03-18T19:00:50.304Z,1742324450.304 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA1 2025-03-18T19:00:50.512Z,1742324450.512 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA2 2025-03-18T19:00:50.820Z,1742324450.820 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA3 2025-03-18T19:00:51.120Z,1742324451.120 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA4 2025-03-18T19:00:51.329Z,1742324451.329 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA5 2025-03-18T19:00:51.608Z,1742324451.608 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA6 2025-03-18T19:00:51.816Z,1742324451.816 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA7 2025-03-18T19:00:52.057Z,1742324452.057 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA1 2025-03-18T19:00:52.233Z,1742324452.233 [SBIT](IMPORTANT): Beginning Startup BIT 2025-03-18T19:00:52.308Z,1742324452.308 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA2 2025-03-18T19:00:52.518Z,1742324452.518 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA3 2025-03-18T19:00:52.788Z,1742324452.788 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA4 2025-03-18T19:00:52.951Z,1742324452.951 [ElevatorServo](ERROR): getPosition uart error serial timeout 2025-03-18T19:00:52.951Z,1742324452.951 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout 2025-03-18T19:00:52.951Z,1742324452.951 [ElevatorServo] Communications Fault, FailCount= 1 2025-03-18T19:00:52.952Z,1742324452.952 [ElevatorServo](ERROR): Communications Fault 2025-03-18T19:00:52.963Z,1742324452.963 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2025-03-18T19:00:52.996Z,1742324452.996 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA5 2025-03-18T19:00:53.121Z,1742324453.121 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-18T19:00:53.121Z,1742324453.121 [ElevatorServo](INFO): Powering down 2025-03-18T19:00:53.272Z,1742324453.272 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA6 2025-03-18T19:00:53.581Z,1742324453.581 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA7 2025-03-18T19:00:53.800Z,1742324453.800 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA1 2025-03-18T19:00:53.868Z,1742324453.868 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-18T19:00:53.869Z,1742324453.869 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-18T19:00:54.024Z,1742324454.024 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-03-18T19:00:54.025Z,1742324454.025 [ElevatorServo] No Fault, FailCount= 1 2025-03-18T19:00:54.060Z,1742324454.060 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA2 2025-03-18T19:00:54.373Z,1742324454.373 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA3 2025-03-18T19:00:54.547Z,1742324454.547 [MassServo](FAULT): Mass Shifter uart error: serial timeout 2025-03-18T19:00:54.547Z,1742324454.547 [MassServo] Communications Fault, FailCount= 1 2025-03-18T19:00:54.547Z,1742324454.547 [MassServo](ERROR): Communications Fault 2025-03-18T19:00:54.592Z,1742324454.592 [CommandExec](IMPORTANT): got command ! echo i > /dev/loadA4 2025-03-18T19:00:54.855Z,1742324454.855 [RudderServo](ERROR): getPosition uart error serial timeout 2025-03-18T19:00:54.855Z,1742324454.855 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2025-03-18T19:00:54.855Z,1742324454.855 [RudderServo] Communications Fault, FailCount= 1 2025-03-18T19:00:54.855Z,1742324454.855 [RudderServo](ERROR): Communications Fault 2025-03-18T19:00:54.862Z,1742324454.862 [CBIT](ERROR): Communications Fault in component: MassServo 2025-03-18T19:00:54.862Z,1742324454.862 [CBIT](ERROR): Communications Fault in component: RudderServo 2025-03-18T19:00:55.287Z,1742324455.287 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2025-03-18T19:00:55.287Z,1742324455.287 [ElevatorServo](FAULT): Elevator failed to initialize 2025-03-18T19:00:55.287Z,1742324455.287 [ElevatorServo] Communications Fault, FailCount= 2 2025-03-18T19:00:55.287Z,1742324455.287 [ElevatorServo](ERROR): Communications Fault 2025-03-18T19:00:55.288Z,1742324455.288 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-03-18T19:00:55.288Z,1742324455.288 [MassServo](INFO): Powering down 2025-03-18T19:00:55.289Z,1742324455.289 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-03-18T19:00:55.289Z,1742324455.289 [RudderServo](INFO): Powering down 2025-03-18T19:00:55.295Z,1742324455.295 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2025-03-18T19:00:55.427Z,1742324455.427 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-03-18T19:00:55.427Z,1742324455.427 [ElevatorServo](INFO): Powering down 2025-03-18T19:00:55.751Z,1742324455.751 [MassServo](DEBUG): Initializing EZServoServo. 2025-03-18T19:00:55.869Z,1742324455.869 [MassServo](DEBUG): Initializing MassServo. 2025-03-18T19:00:55.870Z,1742324455.870 [RudderServo](DEBUG): Initializing EZServoServo. 2025-03-18T19:00:55.991Z,1742324455.991 [RudderServo](DEBUG): Initializing RudderServo. 2025-03-18T19:00:55.996Z,1742324455.996 [CBIT](INFO): Clearing failed state for component MassServo 2025-03-18T19:00:55.996Z,1742324455.996 [MassServo] No Fault, FailCount= 1 2025-03-18T19:00:55.997Z,1742324455.997 [CBIT](INFO): Clearing failed state for component RudderServo 2025-03-18T19:00:55.997Z,1742324455.997 [RudderServo] No Fault, FailCount= 1 2025-03-18T19:00:56.161Z,1742324456.161 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-03-18T19:00:56.280Z,1742324456.280 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-03-18T19:00:56.286Z,1742324456.286 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-03-18T19:00:56.286Z,1742324456.286 [ElevatorServo] No Fault, FailCount= 2 2025-03-18T19:01:00.560Z,1742324460.560 [NAL9602](INFO): Powering up NAL9602 2025-03-18T19:01:05.471Z,1742324465.471 [SBIT](FAULT): Mass: EXPECTED:-0.006600 ACTUAL:-0.012971 2025-03-18T19:01:11.472Z,1742324471.472 [NAL9602](INFO): NAL9602 initialized 2025-03-18T19:01:32.270Z,1742324492.270 [SBIT](FAULT): Mass: EXPECTED:-0.016000 ACTUAL:-0.009877 2025-03-18T19:01:32.270Z,1742324492.270 [SBIT](FAULT): Control surface position failure. 2025-03-18T19:01:46.250Z,1742324506.250 [SBIT](CRITICAL): SBIT FAILED 2025-03-18T19:01:46.250Z,1742324506.250 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-03-18T19:01:46.255Z,1742324506.255 [CBIT](INFO): Critical error at 20250318T190146 2025-03-18T19:01:46.634Z,1742324506.634 [MissionManager](IMPORTANT): Started mission Startup 2025-03-18T19:01:46.655Z,1742324506.655 [Startup] Running Loop=1 2025-03-18T19:01:46.655Z,1742324506.655 [Startup](DEBUG): Aggregate::initialize Startup 2025-03-18T19:01:46.655Z,1742324506.655 [Startup:A.GoToSurface] Running Loop=1 2025-03-18T19:01:46.655Z,1742324506.655 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-18T19:01:46.656Z,1742324506.656 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-18T19:01:46.656Z,1742324506.656 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-18T19:01:46.656Z,1742324506.656 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-18T19:01:46.657Z,1742324506.657 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-18T19:01:46.657Z,1742324506.657 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-18T19:01:46.657Z,1742324506.657 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-18T19:01:46.663Z,1742324506.663 [Startup:StartupSatComms] Running Loop=1 2025-03-18T19:01:46.663Z,1742324506.663 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-03-18T19:01:46.663Z,1742324506.663 [Startup:StartupSatComms:A] Running Loop=1 2025-03-18T19:01:47.044Z,1742324507.044 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-03-18T19:02:41.163Z,1742324561.163 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-03-18T19:02:41.163Z,1742324561.163 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:02:41.174Z,1742324561.174 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:02:41.577Z,1742324561.577 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T19:02:41.577Z,1742324561.577 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-03-18T19:02:46.836Z,1742324566.836 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-18T19:01:46.7Z 2025-03-18T19:02:46.836Z,1742324566.836 [Startup:StartupSatComms:A] Stopped 2025-03-18T19:02:46.836Z,1742324566.836 [Startup:StartupSatComms:B] Running Loop=1 2025-03-18T19:02:47.248Z,1742324567.248 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-18T19:02:48.967Z,1742324568.967 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20250318T185315/Courier0000.lzma 2025-03-18T19:02:49.720Z,1742324569.720 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185315/Courier0000.lzma.bak 2025-03-18T19:02:49.721Z,1742324569.721 [DataOverHttps](INFO): SBD MOMSN=24372071 2025-03-18T19:03:05.162Z,1742324585.162 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250318T185315/Courier0004.lzma 2025-03-18T19:03:06.164Z,1742324586.164 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185315/Courier0004.lzma.bak 2025-03-18T19:03:06.165Z,1742324586.165 [DataOverHttps](INFO): SBD MOMSN=24372078 2025-03-18T19:03:21.619Z,1742324601.619 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20250318T185930/Courier0000.lzma 2025-03-18T19:03:22.620Z,1742324602.620 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185930/Courier0000.lzma.bak 2025-03-18T19:03:22.620Z,1742324602.620 [DataOverHttps](INFO): SBD MOMSN=24372080 2025-03-18T19:03:38.126Z,1742324618.126 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250318T183004/Express0008.lzma 2025-03-18T19:03:39.128Z,1742324619.128 [DataOverHttps](INFO): Moved sent file to Logs/20250318T183004/Express0008.lzma.bak 2025-03-18T19:03:39.128Z,1742324619.128 [DataOverHttps](INFO): SBD MOMSN=24372086 2025-03-18T19:03:47.128Z,1742324627.128 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-18T19:02:46.8Z 2025-03-18T19:03:47.128Z,1742324627.128 [Startup:StartupSatComms:B] Stopped 2025-03-18T19:03:47.128Z,1742324627.128 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-03-18T19:03:47.128Z,1742324627.128 [Startup:StartupSatComms] Stopped 2025-03-18T19:03:47.128Z,1742324627.128 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-03-18T19:03:47.129Z,1742324627.129 [Startup](INFO): Completed Startup 2025-03-18T19:03:47.129Z,1742324627.129 [MissionManager](INFO): Startup is completed. 2025-03-18T19:03:47.129Z,1742324627.129 [MissionManager](INFO): Uninitializing Mission Startup 2025-03-18T19:03:47.129Z,1742324627.129 [Startup] Stopped 2025-03-18T19:03:47.129Z,1742324627.129 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-03-18T19:03:47.130Z,1742324627.130 [Startup:A.GoToSurface] Stopped 2025-03-18T19:03:47.130Z,1742324627.130 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-18T19:03:47.527Z,1742324627.527 [MissionManager](IMPORTANT): Started mission Default 2025-03-18T19:03:47.527Z,1742324627.527 [Default] Running Loop=1 2025-03-18T19:03:47.527Z,1742324627.527 [Default](DEBUG): Aggregate::initialize Default 2025-03-18T19:03:47.527Z,1742324627.527 [Default:B.GoToSurface] Running Loop=1 2025-03-18T19:03:47.527Z,1742324627.527 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-18T19:03:47.528Z,1742324627.528 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-18T19:03:47.528Z,1742324627.528 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-18T19:03:47.528Z,1742324627.528 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-18T19:03:47.528Z,1742324627.528 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-18T19:03:47.529Z,1742324627.529 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-18T19:03:47.529Z,1742324627.529 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-18T19:03:47.529Z,1742324627.529 [Default:A.Wait] Running Loop=1 2025-03-18T19:03:47.529Z,1742324627.529 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-18T19:03:54.560Z,1742324634.560 [DataOverHttps](INFO): Sending 1090 bytes from file Logs/20250318T184420/Express0001.lzma 2025-03-18T19:03:55.560Z,1742324635.560 [DataOverHttps](INFO): Moved sent file to Logs/20250318T184420/Express0001.lzma.bak 2025-03-18T19:03:55.560Z,1742324635.560 [DataOverHttps](INFO): SBD MOMSN=24372088 2025-03-18T19:04:00.857Z,1742324640.857 [Default:A.Wait](INFO): Done Waiting. 2025-03-18T19:04:00.857Z,1742324640.857 [Default:A.Wait] Stopped 2025-03-18T19:04:00.857Z,1742324640.857 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-18T19:04:01.261Z,1742324641.261 [Default:CheckIn] Running Loop=1 2025-03-18T19:04:01.261Z,1742324641.261 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-18T19:04:01.261Z,1742324641.261 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-18T19:04:01.672Z,1742324641.672 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-18T19:04:41.644Z,1742324681.644 [BPC1](FAULT): Failed to initialize 2025-03-18T19:04:41.644Z,1742324681.644 [BPC1] Communications Fault, FailCount= 1 2025-03-18T19:04:41.645Z,1742324681.645 [BPC1](ERROR): Communications Fault 2025-03-18T19:04:41.695Z,1742324681.695 [CBIT](ERROR): Communications Fault in component: BPC1 2025-03-18T19:05:42.264Z,1742324742.264 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-03-18T19:05:42.264Z,1742324742.264 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:05:42.275Z,1742324742.275 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:05:42.682Z,1742324742.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T19:05:42.682Z,1742324742.682 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-03-18T19:06:14.572Z,1742324774.572 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-18T19:08:43.254Z,1742324923.254 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-03-18T19:08:43.254Z,1742324923.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:08:43.264Z,1742324923.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:08:43.686Z,1742324923.686 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T19:08:43.686Z,1742324923.686 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-03-18T19:09:01.444Z,1742324941.444 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-18T19:04:01.3Z 2025-03-18T19:09:01.444Z,1742324941.444 [Default:CheckIn:Read_GPS] Stopped 2025-03-18T19:09:01.444Z,1742324941.444 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-18T19:09:01.833Z,1742324941.833 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-18T19:09:08.150Z,1742324948.150 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250318T185930/Courier0004.lzma 2025-03-18T19:09:09.152Z,1742324949.152 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185930/Courier0004.lzma.bak 2025-03-18T19:09:09.152Z,1742324949.152 [DataOverHttps](INFO): SBD MOMSN=24372122 2025-03-18T19:09:24.606Z,1742324964.606 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250318T184420/Express0005.lzma 2025-03-18T19:09:25.608Z,1742324965.608 [DataOverHttps](INFO): Moved sent file to Logs/20250318T184420/Express0005.lzma.bak 2025-03-18T19:09:25.609Z,1742324965.609 [DataOverHttps](INFO): SBD MOMSN=24372124 2025-03-18T19:09:41.058Z,1742324981.058 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250318T185054/Express0001.lzma 2025-03-18T19:09:42.060Z,1742324982.060 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185054/Express0001.lzma.bak 2025-03-18T19:09:42.061Z,1742324982.061 [DataOverHttps](INFO): SBD MOMSN=24372126 2025-03-18T19:09:57.673Z,1742324997.673 [DataOverHttps](INFO): Sending 1413 bytes from file Logs/20250318T185315/Express0001.lzma 2025-03-18T19:09:58.672Z,1742324998.672 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185315/Express0001.lzma.bak 2025-03-18T19:09:58.673Z,1742324998.673 [DataOverHttps](INFO): SBD MOMSN=24372128 2025-03-18T19:10:14.174Z,1742325014.174 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250318T185315/Express0005.lzma 2025-03-18T19:10:15.176Z,1742325015.176 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185315/Express0005.lzma.bak 2025-03-18T19:10:15.176Z,1742325015.176 [DataOverHttps](INFO): SBD MOMSN=24372170 2025-03-18T19:10:30.673Z,1742325030.673 [DataOverHttps](INFO): Sending 904 bytes from file Logs/20250318T185930/Express0001.lzma 2025-03-18T19:10:31.673Z,1742325031.673 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185930/Express0001.lzma.bak 2025-03-18T19:10:31.673Z,1742325031.673 [DataOverHttps](INFO): SBD MOMSN=24372172 2025-03-18T19:10:47.167Z,1742325047.167 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20250318T185930/Express0005.lzma 2025-03-18T19:10:48.168Z,1742325048.168 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185930/Express0005.lzma.bak 2025-03-18T19:10:48.169Z,1742325048.169 [DataOverHttps](INFO): SBD MOMSN=24372210 2025-03-18T19:10:49.729Z,1742325049.729 [Default:CheckIn:Read_Iridium] Stopped 2025-03-18T19:10:49.729Z,1742325049.729 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-18T19:10:49.729Z,1742325049.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-18T19:11:14.341Z,1742325074.341 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-18T19:11:14.341Z,1742325074.341 [NAL9602] Data Fault, FailCount= 2 2025-03-18T19:11:14.341Z,1742325074.341 [NAL9602](ERROR): Data Fault 2025-03-18T19:11:14.359Z,1742325074.359 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-18T19:11:14.740Z,1742325074.740 [NAL9602](INFO): Powering down 2025-03-18T19:11:15.148Z,1742325075.148 [NAL9602](FAULT): LCB fault: Hardware Overcurrent Shutdown. 2025-03-18T19:11:15.148Z,1742325075.148 [NAL9602] Hardware Fault, FailCount= 2 2025-03-18T19:11:15.148Z,1742325075.148 [NAL9602](ERROR): Hardware Fault 2025-03-18T19:11:15.567Z,1742325075.567 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-18T19:11:15.567Z,1742325075.567 [NAL9602] No Fault, FailCount= 2 2025-03-18T19:11:44.243Z,1742325104.243 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-03-18T19:11:44.243Z,1742325104.243 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:11:44.254Z,1742325104.254 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:11:44.681Z,1742325104.681 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T19:11:44.681Z,1742325104.681 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-03-18T19:11:45.038Z,1742325105.038 [NAL9602](INFO): Powering up NAL9602 2025-03-18T19:11:55.948Z,1742325115.948 [NAL9602](INFO): NAL9602 initialized 2025-03-18T19:14:42.031Z,1742325282.031 [CBIT](INFO): Clearing failed state for component DropWeight 2025-03-18T19:14:42.031Z,1742325282.031 [DropWeight] No Fault, FailCount= 1 2025-03-18T19:14:42.845Z,1742325282.845 [CBIT](INFO): Clearing failed state for component BPC1 2025-03-18T19:14:42.845Z,1742325282.845 [BPC1] No Fault, FailCount= 1 2025-03-18T19:14:45.252Z,1742325285.252 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-03-18T19:14:45.252Z,1742325285.252 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:14:45.263Z,1742325285.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:14:45.669Z,1742325285.669 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T19:14:45.669Z,1742325285.669 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-03-18T19:15:50.304Z,1742325350.304 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-03-18T19:15:50.304Z,1742325350.304 [Default:CheckIn:C.Wait] Stopped 2025-03-18T19:15:50.304Z,1742325350.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-03-18T19:15:50.304Z,1742325350.304 [Default:CheckIn:D] Running Loop=1 2025-03-18T19:15:50.696Z,1742325350.696 [Default:CheckIn:D] Stopped 2025-03-18T19:15:50.696Z,1742325350.696 [Default:CheckIn:E] Running Loop=1 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.052821 min 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn:E] Stopped 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn] Stopped 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn](INFO): Running loop #2 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn] Running Loop=2 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-18T19:15:51.089Z,1742325351.089 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-18T19:17:46.258Z,1742325466.258 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-03-18T19:17:46.258Z,1742325466.258 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:17:46.269Z,1742325466.269 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:17:46.653Z,1742325466.653 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T19:17:46.653Z,1742325466.653 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-03-18T19:19:43.377Z,1742325583.377 [BPC1](FAULT): Failed to initialize 2025-03-18T19:19:43.377Z,1742325583.377 [BPC1] Communications Fault, FailCount= 2 2025-03-18T19:19:43.377Z,1742325583.377 [BPC1](ERROR): Communications Fault 2025-03-18T19:19:43.427Z,1742325583.427 [CBIT](ERROR): Communications Fault in component: BPC1 2025-03-18T19:20:47.236Z,1742325647.236 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-03-18T19:20:47.236Z,1742325647.236 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:20:47.247Z,1742325647.247 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:20:47.665Z,1742325647.665 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-03-18T19:20:47.665Z,1742325647.665 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-03-18T19:20:51.261Z,1742325651.261 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-18T19:15:51.1Z 2025-03-18T19:20:51.261Z,1742325651.261 [Default:CheckIn:Read_GPS] Stopped 2025-03-18T19:20:51.261Z,1742325651.261 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-18T19:20:58.678Z,1742325658.678 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250318T185930/Courier0007.lzma 2025-03-18T19:20:59.681Z,1742325659.681 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185930/Courier0007.lzma.bak 2025-03-18T19:20:59.681Z,1742325659.681 [DataOverHttps](INFO): SBD MOMSN=24372247 2025-03-18T19:21:15.195Z,1742325675.195 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20250318T185930/Express0008.lzma 2025-03-18T19:21:16.196Z,1742325676.196 [DataOverHttps](INFO): Moved sent file to Logs/20250318T185930/Express0008.lzma.bak 2025-03-18T19:21:16.197Z,1742325676.197 [DataOverHttps](INFO): SBD MOMSN=24372249 2025-03-18T19:21:17.533Z,1742325677.533 [Default:CheckIn:Read_Iridium] Stopped 2025-03-18T19:21:17.533Z,1742325677.533 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-18T19:21:17.533Z,1742325677.533 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-18T19:21:59.165Z,1742325719.165 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-03-18T19:21:59.165Z,1742325719.165 [NAL9602] Data Fault, FailCount= 3 2025-03-18T19:21:59.165Z,1742325719.165 [NAL9602](ERROR): Data Fault 2025-03-18T19:21:59.184Z,1742325719.184 [CBIT](ERROR): Data Fault in component: NAL9602 2025-03-18T19:21:59.560Z,1742325719.560 [NAL9602](INFO): Powering down 2025-03-18T19:22:00.390Z,1742325720.390 [CBIT](INFO): Clearing failed state for component NAL9602 2025-03-18T19:22:00.390Z,1742325720.390 [NAL9602] No Fault, FailCount= 3 2025-03-18T19:22:29.864Z,1742325749.864 [NAL9602](INFO): Powering up NAL9602 2025-03-18T19:22:40.768Z,1742325760.768 [NAL9602](INFO): NAL9602 initialized 2025-03-18T19:23:48.247Z,1742325828.247 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-03-18T19:23:48.247Z,1742325828.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-03-18T19:23:48.258Z,1742325828.258 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-03-18T19:23:48.697Z,1742325828.697 [CBIT](INFO): Clearing failed st