2025-07-22T22:19:10.113Z,1753222750.113 [Supervisor](DEBUG): Initializing supervisor. 2025-07-22T22:19:10.141Z,1753222750.141 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-07-22T22:19:10.142Z,1753222750.142 [SyncHandler](INFO): Protected caller Thread ID is 577 2025-07-22T22:19:10.142Z,1753222750.142 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-07-22T22:19:10.143Z,1753222750.143 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-07-22T22:19:10.143Z,1753222750.143 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 578 2025-07-22T22:19:10.159Z,1753222750.159 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-07-22T22:19:10.179Z,1753222750.179 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-07-22T22:19:10.180Z,1753222750.180 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-07-22T22:19:10.180Z,1753222750.180 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 579 2025-07-22T22:19:10.185Z,1753222750.185 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-07-22T22:19:10.186Z,1753222750.186 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-07-22T22:19:10.186Z,1753222750.186 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 580 2025-07-22T22:19:10.188Z,1753222750.188 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-07-22T22:19:10.189Z,1753222750.189 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-07-22T22:19:10.190Z,1753222750.190 [logger ThreadHandler](INFO): Protected caller Thread ID is 581 2025-07-22T22:19:10.194Z,1753222750.194 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-07-22T22:19:10.194Z,1753222750.194 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-07-22T22:19:10.198Z,1753222750.198 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-07-22T22:19:10.647Z,1753222750.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-07-22T22:19:10.649Z,1753222750.649 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-07-22T22:19:10.965Z,1753222750.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-07-22T22:19:10.967Z,1753222750.967 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-07-22T22:19:11.469Z,1753222751.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-07-22T22:19:11.471Z,1753222751.471 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-07-22T22:19:11.717Z,1753222751.717 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-07-22T22:19:11.718Z,1753222751.718 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-07-22T22:19:11.952Z,1753222751.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-07-22T22:19:11.952Z,1753222751.952 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-07-22T22:19:12.750Z,1753222752.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-07-22T22:19:12.752Z,1753222752.752 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-07-22T22:19:13.104Z,1753222753.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-07-22T22:19:13.105Z,1753222753.105 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-07-22T22:19:13.228Z,1753222753.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-07-22T22:19:13.228Z,1753222753.228 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-07-22T22:19:13.320Z,1753222753.320 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-07-22T22:19:13.518Z,1753222753.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-07-22T22:19:13.519Z,1753222753.519 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-07-22T22:19:13.627Z,1753222753.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-07-22T22:19:13.629Z,1753222753.629 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-07-22T22:19:13.859Z,1753222753.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-07-22T22:19:13.860Z,1753222753.860 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-07-22T22:19:14.010Z,1753222754.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-07-22T22:19:14.010Z,1753222754.010 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-07-22T22:19:14.115Z,1753222754.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-07-22T22:19:14.116Z,1753222754.116 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-07-22T22:19:14.559Z,1753222754.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-07-22T22:19:14.560Z,1753222754.560 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-07-22T22:19:15.177Z,1753222755.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-07-22T22:19:15.180Z,1753222755.180 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/ 2025-07-22T22:19:15.472Z,1753222755.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg 2025-07-22T22:19:15.884Z,1753222755.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg 2025-07-22T22:19:16.356Z,1753222756.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-07-22T22:19:16.357Z,1753222756.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg 2025-07-22T22:19:16.472Z,1753222756.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg 2025-07-22T22:19:16.581Z,1753222756.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg 2025-07-22T22:19:16.703Z,1753222756.703 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg 2025-07-22T22:19:16.852Z,1753222756.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg 2025-07-22T22:19:16.964Z,1753222756.964 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg 2025-07-22T22:19:17.085Z,1753222757.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg 2025-07-22T22:19:17.181Z,1753222757.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg 2025-07-22T22:19:17.309Z,1753222757.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg 2025-07-22T22:19:17.407Z,1753222757.407 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-07-22T22:19:17.418Z,1753222757.418 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-07-22T22:19:17.434Z,1753222757.434 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-07-22T22:19:17.435Z,1753222757.435 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-07-22T22:19:17.578Z,1753222757.578 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-07-22T22:19:17.578Z,1753222757.578 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-07-22T22:19:17.618Z,1753222757.618 [DeadReckonWithRespectToSeafloor] Loaded 2025-07-22T22:19:17.619Z,1753222757.619 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2025-07-22T22:19:17.632Z,1753222757.632 [NavChart] Loaded 2025-07-22T22:19:17.632Z,1753222757.632 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-07-22T22:19:17.638Z,1753222757.638 [UniversalFixResidualReporter] Loaded 2025-07-22T22:19:17.638Z,1753222757.638 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-07-22T22:19:17.639Z,1753222757.639 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-07-22T22:19:17.640Z,1753222757.640 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-07-22T22:19:18.523Z,1753222758.523 [AHRS_M2] Loaded 2025-07-22T22:19:18.523Z,1753222758.523 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-07-22T22:19:18.783Z,1753222758.783 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC3 2025-07-22T22:19:18.784Z,1753222758.784 [BackseatComponent] Loaded 2025-07-22T22:19:18.784Z,1753222758.784 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-07-22T22:19:18.806Z,1753222758.806 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085D4E0 2025-07-22T22:19:18.806Z,1753222758.806 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 663 2025-07-22T22:19:18.809Z,1753222758.809 [LcmUniversalReporter] Loaded 2025-07-22T22:19:18.809Z,1753222758.809 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-07-22T22:19:19.649Z,1753222759.649 [BPC1] Loaded 2025-07-22T22:19:19.649Z,1753222759.649 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-07-22T22:19:19.748Z,1753222759.748 [DAT] Loaded 2025-07-22T22:19:19.748Z,1753222759.748 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-07-22T22:19:19.749Z,1753222759.749 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088D4E0 2025-07-22T22:19:19.749Z,1753222759.749 [DAT ThreadHandler](INFO): Protected caller Thread ID is 664 2025-07-22T22:19:20.048Z,1753222760.048 [DataOverHttps] Loaded 2025-07-22T22:19:20.049Z,1753222760.049 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-07-22T22:19:20.050Z,1753222760.050 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0 2025-07-22T22:19:20.050Z,1753222760.050 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 665 2025-07-22T22:19:20.073Z,1753222760.073 [DepthKeller33X] Loaded 2025-07-22T22:19:20.073Z,1753222760.073 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread. 2025-07-22T22:19:20.138Z,1753222760.138 [NAL9602] Loaded 2025-07-22T22:19:20.138Z,1753222760.138 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-07-22T22:19:20.168Z,1753222760.168 [Onboard] Loaded 2025-07-22T22:19:20.169Z,1753222760.169 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-07-22T22:19:20.170Z,1753222760.170 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0 2025-07-22T22:19:20.170Z,1753222760.170 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 666 2025-07-22T22:19:20.184Z,1753222760.184 [Power24vConverter] Loaded 2025-07-22T22:19:20.185Z,1753222760.185 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-07-22T22:19:20.202Z,1753222760.202 [Radio_Surface] Loaded 2025-07-22T22:19:20.202Z,1753222760.202 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-07-22T22:19:20.203Z,1753222760.203 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0 2025-07-22T22:19:20.203Z,1753222760.203 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 667 2025-07-22T22:19:20.250Z,1753222760.250 [RDI_Pathfinder] Loaded 2025-07-22T22:19:20.251Z,1753222760.251 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-07-22T22:19:20.266Z,1753222760.266 [Sonardyne_Nano] Loaded 2025-07-22T22:19:20.266Z,1753222760.266 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-07-22T22:19:20.267Z,1753222760.267 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-07-22T22:19:20.267Z,1753222760.267 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-07-22T22:19:20.769Z,1753222760.769 [DepthRateCalculator] Loaded 2025-07-22T22:19:20.769Z,1753222760.769 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-07-22T22:19:20.775Z,1753222760.775 [PitchRateCalculator] Loaded 2025-07-22T22:19:20.775Z,1753222760.775 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-07-22T22:19:20.784Z,1753222760.784 [SpeedCalculator] Loaded 2025-07-22T22:19:20.785Z,1753222760.785 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-07-22T22:19:20.789Z,1753222760.789 [YawRateCalculator] Loaded 2025-07-22T22:19:20.789Z,1753222760.789 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-07-22T22:19:20.812Z,1753222760.812 [ElevatorOffsetCalculator] Loaded 2025-07-22T22:19:20.812Z,1753222760.812 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-07-22T22:19:20.813Z,1753222760.813 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-07-22T22:19:20.814Z,1753222760.814 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-07-22T22:19:20.901Z,1753222760.901 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-07-22T22:19:20.901Z,1753222760.901 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-07-22T22:19:21.075Z,1753222761.075 [BuoyancyServo] Loaded 2025-07-22T22:19:21.076Z,1753222761.076 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-07-22T22:19:21.100Z,1753222761.100 [ElevatorServo] Loaded 2025-07-22T22:19:21.100Z,1753222761.100 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-07-22T22:19:21.127Z,1753222761.127 [MassServo] Loaded 2025-07-22T22:19:21.127Z,1753222761.127 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-07-22T22:19:21.150Z,1753222761.150 [RudderServo] Loaded 2025-07-22T22:19:21.150Z,1753222761.150 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-07-22T22:19:21.168Z,1753222761.168 [ThrusterHE] Loaded 2025-07-22T22:19:21.168Z,1753222761.168 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-07-22T22:19:21.169Z,1753222761.169 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-07-22T22:19:21.169Z,1753222761.169 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-07-22T22:19:21.400Z,1753222761.400 [CTD_Seabird] Loaded 2025-07-22T22:19:21.400Z,1753222761.400 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-07-22T22:19:21.421Z,1753222761.421 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A514E0 2025-07-22T22:19:21.421Z,1753222761.421 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 668 2025-07-22T22:19:21.443Z,1753222761.443 [RBRTridente] Loaded 2025-07-22T22:19:21.443Z,1753222761.443 [ComponentRegistry](DEBUG): Component "RBRTridente" handled in its own thread. 2025-07-22T22:19:21.444Z,1753222761.444 [RBRTridente ThreadHandler](DEBUG): Created PCaller Thread at 40A814E0 2025-07-22T22:19:21.444Z,1753222761.444 [RBRTridente ThreadHandler](INFO): Protected caller Thread ID is 669 2025-07-22T22:19:21.445Z,1753222761.445 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-07-22T22:19:21.446Z,1753222761.446 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-07-22T22:19:21.616Z,1753222761.616 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-07-22T22:19:21.617Z,1753222761.617 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-07-22T22:19:21.837Z,1753222761.837 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-07-22T22:19:21.838Z,1753222761.838 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-07-22T22:19:22.261Z,1753222762.261 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-07-22T22:19:22.262Z,1753222762.262 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-07-22T22:19:22.335Z,1753222762.335 [VerticalControl](DEBUG): Construct VerticalControl. 2025-07-22T22:19:22.389Z,1753222762.389 [VerticalControl] Loaded 2025-07-22T22:19:22.389Z,1753222762.389 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-07-22T22:19:22.392Z,1753222762.392 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-07-22T22:19:22.440Z,1753222762.440 [HorizontalControl] Loaded 2025-07-22T22:19:22.441Z,1753222762.441 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-07-22T22:19:22.443Z,1753222762.443 [SpeedControl](DEBUG): Construct SpeedControl. 2025-07-22T22:19:22.445Z,1753222762.445 [SpeedControl] Loaded 2025-07-22T22:19:22.445Z,1753222762.445 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-07-22T22:19:22.448Z,1753222762.448 [LoopControl](DEBUG): Construct LoopControl. 2025-07-22T22:19:22.448Z,1753222762.448 [LoopControl] Loaded 2025-07-22T22:19:22.449Z,1753222762.449 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-07-22T22:19:22.449Z,1753222762.449 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-07-22T22:19:22.450Z,1753222762.450 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-07-22T22:19:22.647Z,1753222762.647 [SBIT](DEBUG): Construct Startup Built In Test. 2025-07-22T22:19:22.656Z,1753222762.656 [SBIT] Loaded 2025-07-22T22:19:22.657Z,1753222762.657 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-07-22T22:19:22.660Z,1753222762.660 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-07-22T22:19:22.673Z,1753222762.673 [IBIT] Loaded 2025-07-22T22:19:22.673Z,1753222762.673 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-07-22T22:19:22.678Z,1753222762.678 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-07-22T22:19:22.760Z,1753222762.760 [CBIT] Loaded 2025-07-22T22:19:22.761Z,1753222762.761 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-07-22T22:19:22.794Z,1753222762.794 [GFScanner] Loaded 2025-07-22T22:19:22.794Z,1753222762.794 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-07-22T22:19:22.795Z,1753222762.795 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-07-22T22:19:22.795Z,1753222762.795 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-07-22T22:19:22.847Z,1753222762.847 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-07-22T22:19:22.853Z,1753222762.853 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-07-22T22:19:22.857Z,1753222762.857 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-07-22T22:19:22.868Z,1753222762.868 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-07-22T22:19:22.889Z,1753222762.889 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0 2025-07-22T22:19:22.889Z,1753222762.889 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 670 2025-07-22T22:19:22.893Z,1753222762.893 [Supervisor](INFO): Main Thread ID is 576 2025-07-22T22:19:22.894Z,1753222762.894 [Supervisor](DEBUG): Running supervisor. 2025-07-22T22:19:22.894Z,1753222762.894 [CommandExec ThreadHandler](INFO): Handler Thread ID is 671 2025-07-22T22:19:22.895Z,1753222762.895 [CommandExec](INFO): Initializing the command executive. 2025-07-22T22:19:22.897Z,1753222762.897 [CommandLine ThreadHandler](INFO): Handler Thread ID is 672 2025-07-22T22:19:22.899Z,1753222762.899 [controlThread ThreadHandler](INFO): Handler Thread ID is 673 2025-07-22T22:19:22.899Z,1753222762.899 [controlThread](DEBUG): Initializing ControlThread 2025-07-22T22:19:22.901Z,1753222762.901 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-07-22T22:19:22.902Z,1753222762.902 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-07-22T22:19:22.904Z,1753222762.904 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-07-22T22:19:22.904Z,1753222762.904 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-07-22T22:19:22.904Z,1753222762.904 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-07-22T22:19:22.905Z,1753222762.905 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-07-22T22:19:22.905Z,1753222762.905 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-07-22T22:19:22.907Z,1753222762.907 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-07-22T22:19:22.908Z,1753222762.908 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-07-22T22:19:22.909Z,1753222762.909 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-07-22T22:19:22.910Z,1753222762.910 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-07-22T22:19:22.910Z,1753222762.910 [SBIT](INFO): Initialize SBIT Component. 2025-07-22T22:19:22.910Z,1753222762.910 [SBIT](IMPORTANT): git: 2025-06-02-12-gf31d2d2cf 2025-07-22T22:19:22.911Z,1753222762.911 [SBIT](INFO): git hash: f31d2d2cff7b1a18a5fb2309356ea947ca1213be 2025-07-22T22:19:22.911Z,1753222762.911 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-07-22T22:19:22.912Z,1753222762.912 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Mon Nov 27 01:04:18 PST 2023 2025-07-22T22:19:22.913Z,1753222762.913 [SBIT](INFO): Beginning SBIT in 95.000000 seconds. 2025-07-22T22:19:22.914Z,1753222762.914 [IBIT](INFO): Initialize IBIT Component. 2025-07-22T22:19:22.915Z,1753222762.915 [CBIT](DEBUG): Initialize CBIT Component. 2025-07-22T22:19:22.916Z,1753222762.916 [logger ThreadHandler](INFO): Handler Thread ID is 674 2025-07-22T22:19:22.927Z,1753222762.927 [CBIT](DEBUG): Initialized mux pins. 2025-07-22T22:19:22.927Z,1753222762.927 [CBIT](DEBUG): Initializing the watchdog timer. 2025-07-22T22:19:22.936Z,1753222762.936 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 675 2025-07-22T22:19:22.951Z,1753222762.951 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2025-07-22T22:19:22.951Z,1753222762.951 [CBIT](DEBUG): Initializing heartbeat. 2025-07-22T22:19:22.952Z,1753222762.952 [DAT ThreadHandler](INFO): Handler Thread ID is 676 2025-07-22T22:19:22.953Z,1753222762.953 [DAT](INFO): Powering up 2025-07-22T22:19:22.953Z,1753222762.953 [DAT](DEBUG): Initializing DAT. 2025-07-22T22:19:22.956Z,1753222762.956 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 678 2025-07-22T22:19:22.958Z,1753222762.958 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-07-22T22:19:22.979Z,1753222762.979 [Onboard ThreadHandler](INFO): Handler Thread ID is 679 2025-07-22T22:19:22.995Z,1753222762.995 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 680 2025-07-22T22:19:23.009Z,1753222763.009 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 681 2025-07-22T22:19:23.010Z,1753222763.010 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-07-22T22:19:23.024Z,1753222763.024 [CBIT](DEBUG): Deactivating emergency mode. 2025-07-22T22:19:23.025Z,1753222763.025 [RBRTridente ThreadHandler](INFO): Handler Thread ID is 683 2025-07-22T22:19:23.026Z,1753222763.026 [RBRTridente](INFO): Start 2025-07-22T22:19:23.027Z,1753222763.027 [RBRTridente](INFO): Powering up 2025-07-22T22:19:23.028Z,1753222763.028 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 685 2025-07-22T22:19:23.037Z,1753222763.037 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-07-22T22:19:23.037Z,1753222763.037 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-07-22T22:19:23.037Z,1753222763.037 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-07-22T22:19:23.037Z,1753222763.037 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-07-22T22:19:23.037Z,1753222763.037 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-07-22T22:19:23.037Z,1753222763.037 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-07-22T22:19:23.038Z,1753222763.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-07-22T22:19:23.038Z,1753222763.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-07-22T22:19:23.067Z,1753222763.067 [CBIT](DEBUG): Backplane powered. 2025-07-22T22:19:23.067Z,1753222763.067 [GFScanner](DEBUG): Initializing GFScanner 2025-07-22T22:19:23.067Z,1753222763.067 [GFScanner](DEBUG): Deactivating GF circuits. 2025-07-22T22:19:23.072Z,1753222763.072 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-07-22T22:19:23.073Z,1753222763.073 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-07-22T22:19:23.073Z,1753222763.073 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-07-22T22:19:23.078Z,1753222763.078 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-07-22T22:19:23.150Z,1753222763.150 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-07-22T22:19:23.167Z,1753222763.167 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-22T22:19:23.225Z,1753222763.225 [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-07-22T22:19:23.225Z,1753222763.225 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-07-22T22:19:23.226Z,1753222763.226 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-07-22T22:19:23.229Z,1753222763.229 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-07-22T22:19:23.399Z,1753222763.399 [Radio_Surface](INFO): Powering up 2025-07-22T22:19:23.427Z,1753222763.427 [RBRTridente](INFO): Starting 2025-07-22T22:19:23.545Z,1753222763.545 [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-07-22T22:19:23.552Z,1753222763.552 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-07-22T22:19:23.553Z,1753222763.553 [Default:A.Wait](DEBUG): Construct Wait. 2025-07-22T22:19:23.575Z,1753222763.575 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-22T22:19:23.607Z,1753222763.607 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-07-22T22:19:23.610Z,1753222763.610 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-07-22T22:19:23.629Z,1753222763.629 [Default:E.Execute](DEBUG): Construct Execute. 2025-07-22T22:19:23.652Z,1753222763.652 [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-07-22T22:19:23.656Z,1753222763.656 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DepthKeller33X,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-07-22T22:19:23.680Z,1753222763.680 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-07-22T22:19:23.779Z,1753222763.779 [DepthKeller33X](INFO): Initializing. 2025-07-22T22:19:23.791Z,1753222763.791 [Power24vConverter](INFO): Powering up. 2025-07-22T22:19:23.792Z,1753222763.792 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-07-22T22:19:23.800Z,1753222763.800 [Sonardyne_Nano](INFO): Initializing. 2025-07-22T22:19:23.823Z,1753222763.823 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-22T22:19:23.831Z,1753222763.831 [RBRTridente](INFO): Stop 2025-07-22T22:19:23.873Z,1753222763.873 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-07-22T22:19:23.879Z,1753222763.879 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-07-22T22:19:23.880Z,1753222763.880 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-22T22:19:23.887Z,1753222763.887 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-22T22:19:23.888Z,1753222763.888 [MassServo](DEBUG): Initializing EZServoServo. 2025-07-22T22:19:23.895Z,1753222763.895 [MassServo](DEBUG): Initializing MassServo. 2025-07-22T22:19:23.896Z,1753222763.896 [RudderServo](DEBUG): Initializing EZServoServo. 2025-07-22T22:19:23.903Z,1753222763.903 [RBRTridente](INFO): Powering down 2025-07-22T22:19:23.905Z,1753222763.905 [RudderServo](DEBUG): Initializing RudderServo. 2025-07-22T22:19:23.906Z,1753222763.906 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-07-22T22:19:23.912Z,1753222763.912 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-07-22T22:19:23.917Z,1753222763.917 [CommandExec](FAULT): Scheduling is paused 2025-07-22T22:19:23.918Z,1753222763.918 [CBIT](INFO): Critical error at 20250722T221922 2025-07-22T22:19:23.918Z,1753222763.918 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-07-22T22:19:24.108Z,1753222764.108 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-22T22:19:24.235Z,1753222764.235 [RBRTridente](INFO): Stopping 2025-07-22T22:19:24.475Z,1753222764.475 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-22T22:19:24.875Z,1753222764.875 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-22T22:19:25.281Z,1753222765.281 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-22T22:19:25.704Z,1753222765.704 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-22T22:19:27.746Z,1753222767.746 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-07-22T22:19:28.210Z,1753222768.210 [Sonardyne_Nano](FAULT): Failed hardware self-test:>CKHW:FAIL;LBK 2025-07-22T22:19:28.210Z,1753222768.210 [Sonardyne_Nano] Hardware Fault, FailCount= 1 2025-07-22T22:19:28.210Z,1753222768.210 [Sonardyne_Nano](ERROR): Hardware Fault 2025-07-22T22:19:28.295Z,1753222768.295 [CBIT](ERROR): Hardware Fault in component: Sonardyne_Nano 2025-07-22T22:19:28.569Z,1753222768.569 [Sonardyne_Nano](INFO): Powering down 2025-07-22T22:19:29.446Z,1753222769.446 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-07-22T22:19:29.510Z,1753222769.510 [CBIT](INFO): Clearing failed state for component Sonardyne_Nano 2025-07-22T22:19:29.511Z,1753222769.511 [Sonardyne_Nano] No Fault, FailCount= 1 2025-07-22T22:19:29.813Z,1753222769.813 [Sonardyne_Nano](INFO): Initializing. 2025-07-22T22:19:31.841Z,1753222771.841 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-07-22T22:19:33.037Z,1753222773.037 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2025-07-22T22:19:34.300Z,1753222774.300 [DAT](INFO): DAT read: 2025-07-22T22:19:34.301Z,1753222774.301 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-07-22T22:19:35.817Z,1753222775.817 [DAT](INFO): DAT read: MF Frequency Band 2025-07-22T22:19:35.818Z,1753222775.818 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.0 2025-07-22T22:19:35.818Z,1753222775.818 [DAT](INFO): DAT read: Jan 1 2000 00:00:06 2025-07-22T22:19:36.825Z,1753222776.825 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-07-22T22:19:36.832Z,1753222776.832 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-07-22T22:19:36.832Z,1753222776.832 [DAT](INFO): commRate: 600 2025-07-22T22:19:38.289Z,1753222778.289 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004464 2025-07-22T22:19:38.891Z,1753222778.891 [DAT](INFO): entering command mode 2025-07-22T22:19:39.091Z,1753222779.091 [DAT](INFO): DAT read: 2025-07-22T22:19:39.092Z,1753222779.092 [DAT](INFO): DAT read: user:1> 2025-07-22T22:19:39.092Z,1753222779.092 [DAT](INFO): setting verbose to 3 2025-07-22T22:19:39.344Z,1753222779.344 [DAT](INFO): DAT read: user:1> 2025-07-22T22:19:39.345Z,1753222779.345 [DAT](INFO): DAT read: Verbose | 3 2025-07-22T22:19:39.345Z,1753222779.345 [DAT](INFO): set verbose to 3 2025-07-22T22:19:39.345Z,1753222779.345 [DAT](INFO): setting DatVerbose to 27440 2025-07-22T22:19:39.596Z,1753222779.596 [DAT](INFO): DAT read: user:2> 2025-07-22T22:19:39.597Z,1753222779.597 [DAT](INFO): DAT read: DatVerbose | 27440 2025-07-22T22:19:39.597Z,1753222779.597 [DAT](INFO): set DatVerbose to 27440 2025-07-22T22:19:39.597Z,1753222779.597 [DAT](INFO): setting transmit power to 8 2025-07-22T22:19:39.849Z,1753222779.849 [DAT](INFO): DAT read: user:3> 2025-07-22T22:19:39.851Z,1753222779.851 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-07-22T22:19:39.852Z,1753222779.852 [DAT](INFO): set transmit power to 8 2025-07-22T22:19:39.852Z,1753222779.852 [DAT](INFO): setting local address to 3 2025-07-22T22:19:40.100Z,1753222780.100 [DAT](INFO): DAT read: user:4> 2025-07-22T22:19:40.101Z,1753222780.101 [DAT](INFO): DAT read: LocalAddr | 3 2025-07-22T22:19:40.101Z,1753222780.101 [DAT](INFO): set local address to 3 2025-07-22T22:19:40.102Z,1753222780.102 [DAT](INFO): Setting time to: 22:19:40 And date to:7/22/2025 2025-07-22T22:19:40.352Z,1753222780.352 [DAT](INFO): DAT read: user:5> 2025-07-22T22:19:40.353Z,1753222780.353 [DAT](INFO): DAT read: Tue Jul 22, 2025 22:19:40 2025-07-22T22:19:40.353Z,1753222780.353 [DAT](INFO): Local DAT time set to Tue Jul 22, 2025 22:19:40 2025-07-22T22:19:50.381Z,1753222790.381 [NAL9602](INFO): Powering up NAL9602 2025-07-22T22:19:54.974Z,1753222794.974 [CommandExec](IMPORTANT): got command strobe off 2025-07-22T22:19:54.974Z,1753222794.974 [CommandExec](IMPORTANT): Deactivating strobe 2025-07-22T22:20:01.305Z,1753222801.305 [NAL9602](INFO): NAL9602 initialized 2025-07-22T22:20:08.937Z,1753222808.937 [CommandExec](IMPORTANT): got command strobe off 2025-07-22T22:20:08.938Z,1753222808.938 [CommandExec](IMPORTANT): Deactivating strobe 2025-07-22T22:20:58.414Z,1753222858.414 [SBIT](IMPORTANT): Beginning Startup BIT 2025-07-22T22:20:58.435Z,1753222858.435 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-22T22:21:00.495Z,1753222860.495 [ElevatorServo](FAULT): Overload Error 2025-07-22T22:21:00.495Z,1753222860.495 [ElevatorServo] Hardware Fault, FailCount= 1 2025-07-22T22:21:00.495Z,1753222860.495 [ElevatorServo](ERROR): Hardware Fault 2025-07-22T22:21:00.781Z,1753222860.781 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-07-22T22:21:00.921Z,1753222860.921 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-07-22T22:21:00.921Z,1753222860.921 [ElevatorServo](INFO): Powering down 2025-07-22T22:21:01.721Z,1753222861.721 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-22T22:21:01.839Z,1753222861.839 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-22T22:21:01.900Z,1753222861.900 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-07-22T22:21:01.900Z,1753222861.900 [ElevatorServo] No Fault, FailCount= 1 2025-07-22T22:21:03.131Z,1753222863.131 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.015457 CHAN A1 (24V): 0.000260 CHAN A2 (12V): 0.000068 CHAN A3 (5V): -0.011783 CHAN B0 (3.3V): -0.000487 CHAN B1 (3.15aV): -0.000568 CHAN B2 (3.15bV): -0.000706 CHAN B3 (GND): -0.000898 OPEN: 0.008184 Full Scale: +/- 1 mA 2025-07-22T22:21:06.747Z,1753222866.747 [ElevatorServo](FAULT): Overload Error 2025-07-22T22:21:06.747Z,1753222866.747 [ElevatorServo] Hardware Fault, FailCount= 2 2025-07-22T22:21:06.747Z,1753222866.747 [ElevatorServo](ERROR): Hardware Fault 2025-07-22T22:21:06.752Z,1753222866.752 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-07-22T22:21:06.968Z,1753222866.968 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-07-22T22:21:06.968Z,1753222866.968 [ElevatorServo](INFO): Powering down 2025-07-22T22:21:07.793Z,1753222867.793 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-22T22:21:07.912Z,1753222867.912 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-22T22:21:07.917Z,1753222867.917 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-07-22T22:21:07.917Z,1753222867.917 [ElevatorServo] No Fault, FailCount= 2 2025-07-22T22:21:11.646Z,1753222871.646 [SBIT](FAULT): Elevator: EXPECTED:15.000000 ACTUAL:17.654718 2025-07-22T22:21:13.051Z,1753222873.051 [ElevatorServo](FAULT): Overload Error 2025-07-22T22:21:13.051Z,1753222873.051 [ElevatorServo] Hardware Fault, FailCount= 3 2025-07-22T22:21:13.051Z,1753222873.051 [ElevatorServo](ERROR): Hardware Fault 2025-07-22T22:21:13.276Z,1753222873.276 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-07-22T22:21:13.276Z,1753222873.276 [CBIT](CRITICAL): Hardware Fault in component: ElevatorServo 2025-07-22T22:21:13.427Z,1753222873.427 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-07-22T22:21:13.427Z,1753222873.427 [ElevatorServo](INFO): Powering down 2025-07-22T22:21:13.702Z,1753222873.702 [CBIT](INFO): Critical error at 20250722T222113 2025-07-22T22:21:14.289Z,1753222874.289 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-22T22:21:14.409Z,1753222874.409 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-22T22:21:38.076Z,1753222898.076 [SBIT](ERROR): Could not read elevatorAngleReader_. 2025-07-22T22:21:38.077Z,1753222898.077 [SBIT](FAULT): Control surface position failure. 2025-07-22T22:21:52.005Z,1753222912.005 [SBIT](ERROR): Could not read elevatorAngleReader_. 2025-07-22T22:21:52.005Z,1753222912.005 [SBIT](FAULT): Control surface position failure. 2025-07-22T22:21:52.323Z,1753222912.323 [SBIT](CRITICAL): SBIT FAILED 2025-07-22T22:21:52.323Z,1753222912.323 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-07-22T22:21:52.324Z,1753222912.324 [SBIT](IMPORTANT): BPC1.loadAtStartup=1 bool; 2025-07-22T22:21:52.324Z,1753222912.324 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2025-07-22T22:21:52.324Z,1753222912.324 [SBIT](IMPORTANT): Express linearApproximation Onboard.Temperature 1.000000 degree; 2025-07-22T22:21:52.324Z,1753222912.324 [SBIT](IMPORTANT): Express linearApproximation platform_buoyancy_position 100.000000 cubic_centimeter; 2025-07-22T22:21:52.324Z,1753222912.324 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=1275 cubic_centimeter; 2025-07-22T22:21:52.325Z,1753222912.325 [SBIT](IMPORTANT): VerticalControl.dropWtOverrideDelay=20 minute; 2025-07-22T22:21:52.325Z,1753222912.325 [SBIT](IMPORTANT): VerticalControl.massDefault=-18.753875 millimeter; 2025-07-22T22:21:52.326Z,1753222912.326 [CBIT](INFO): Critical error at 20250722T222152 2025-07-22T22:21:52.674Z,1753222912.674 [MissionManager](IMPORTANT): Started mission Startup 2025-07-22T22:21:52.674Z,1753222912.674 [Startup] Running Loop=1 2025-07-22T22:21:52.674Z,1753222912.674 [Startup](DEBUG): Aggregate::initialize Startup 2025-07-22T22:21:52.675Z,1753222912.675 [Startup:A.GoToSurface] Running Loop=1 2025-07-22T22:21:52.675Z,1753222912.675 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-22T22:21:52.676Z,1753222912.676 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-22T22:21:52.677Z,1753222912.677 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-22T22:21:52.677Z,1753222912.677 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-22T22:21:52.678Z,1753222912.678 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-22T22:21:52.678Z,1753222912.678 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-22T22:21:52.678Z,1753222912.678 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-22T22:21:52.681Z,1753222912.681 [Startup:StartupSatComms] Running Loop=1 2025-07-22T22:21:52.681Z,1753222912.681 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-07-22T22:21:52.681Z,1753222912.681 [Startup:StartupSatComms:A] Running Loop=1 2025-07-22T22:21:53.046Z,1753222913.046 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-07-22T22:22:12.382Z,1753222932.382 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:22:22.910Z,1753222942.910 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-07-22T22:22:22.910Z,1753222942.910 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-22T22:22:22.911Z,1753222942.911 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-07-22T22:22:22.911Z,1753222942.911 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-07-22T22:22:22.921Z,1753222942.921 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-22T22:22:22.921Z,1753222942.921 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-07-22T22:22:23.334Z,1753222943.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-22T22:22:23.334Z,1753222943.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-07-22T22:22:23.335Z,1753222943.335 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-07-22T22:22:23.335Z,1753222943.335 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-07-22T22:22:27.733Z,1753222947.733 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:22:43.086Z,1753222963.086 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:22:52.816Z,1753222972.816 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-22T22:21:52.7Z 2025-07-22T22:22:52.816Z,1753222972.816 [Startup:StartupSatComms:A] Stopped 2025-07-22T22:22:52.816Z,1753222972.816 [Startup:StartupSatComms:B] Running Loop=1 2025-07-22T22:22:53.236Z,1753222973.236 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-22T22:22:57.849Z,1753222977.849 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:22:59.992Z,1753222979.992 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250613T220759/Courier0007.lzma 2025-07-22T22:23:00.993Z,1753222980.993 [DataOverHttps](INFO): Moved sent file to Logs/20250613T220759/Courier0007.lzma.bak 2025-07-22T22:23:00.993Z,1753222980.993 [DataOverHttps](INFO): SBD MOMSN=25570188 2025-07-22T22:23:08.360Z,1753222988.360 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00041823435108,3.7, 0.0,1526.2, 0 2025-07-22T22:23:12.793Z,1753222992.793 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:23:17.483Z,1753222997.483 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20250722T221910/Courier0000.lzma 2025-07-22T22:23:18.485Z,1753222998.485 [DataOverHttps](INFO): Moved sent file to Logs/20250722T221910/Courier0000.lzma.bak 2025-07-22T22:23:18.485Z,1753222998.485 [DataOverHttps](INFO): SBD MOMSN=25570190 2025-07-22T22:23:28.146Z,1753223008.146 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:23:36.906Z,1753223016.906 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20250613T220759/Express0008.lzma 2025-07-22T22:23:37.905Z,1753223017.905 [DataOverHttps](INFO): Moved sent file to Logs/20250613T220759/Express0008.lzma.bak 2025-07-22T22:23:37.905Z,1753223017.905 [DataOverHttps](INFO): SBD MOMSN=25570196 2025-07-22T22:23:43.497Z,1753223023.497 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:23:52.822Z,1753223032.822 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-22T22:22:52.8Z 2025-07-22T22:23:52.822Z,1753223032.822 [Startup:StartupSatComms:B] Stopped 2025-07-22T22:23:52.822Z,1753223032.822 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-07-22T22:23:52.822Z,1753223032.822 [Startup:StartupSatComms] Stopped 2025-07-22T22:23:52.822Z,1753223032.822 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-07-22T22:23:52.824Z,1753223032.824 [Startup](INFO): Completed Startup 2025-07-22T22:23:52.824Z,1753223032.824 [MissionManager](INFO): Startup is completed. 2025-07-22T22:23:52.825Z,1753223032.825 [MissionManager](INFO): Uninitializing Mission Startup 2025-07-22T22:23:52.825Z,1753223032.825 [Startup] Stopped 2025-07-22T22:23:52.825Z,1753223032.825 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-07-22T22:23:52.825Z,1753223032.825 [Startup:A.GoToSurface] Stopped 2025-07-22T22:23:52.825Z,1753223032.825 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-22T22:23:53.257Z,1753223033.257 [MissionManager](IMPORTANT): Started mission Default 2025-07-22T22:23:53.258Z,1753223033.258 [Default] Running Loop=1 2025-07-22T22:23:53.258Z,1753223033.258 [Default](DEBUG): Aggregate::initialize Default 2025-07-22T22:23:53.258Z,1753223033.258 [Default:B.GoToSurface] Running Loop=1 2025-07-22T22:23:53.258Z,1753223033.258 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-22T22:23:53.258Z,1753223033.258 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-22T22:23:53.259Z,1753223033.259 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-22T22:23:53.268Z,1753223033.268 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-22T22:23:53.268Z,1753223033.268 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-22T22:23:53.269Z,1753223033.269 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-22T22:23:53.269Z,1753223033.269 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-22T22:23:53.269Z,1753223033.269 [Default:A.Wait] Running Loop=1 2025-07-22T22:23:53.269Z,1753223033.269 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-07-22T22:23:55.675Z,1753223035.675 [DataOverHttps](INFO): Sending 1253 bytes from file Logs/20250722T221910/Express0001.lzma 2025-07-22T22:23:56.673Z,1753223036.673 [DataOverHttps](INFO): Moved sent file to Logs/20250722T221910/Express0001.lzma.bak 2025-07-22T22:23:56.673Z,1753223036.673 [DataOverHttps](INFO): SBD MOMSN=25570203 2025-07-22T22:23:58.446Z,1753223038.446 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:24:06.770Z,1753223046.770 [Default:A.Wait](INFO): Done Waiting. 2025-07-22T22:24:06.770Z,1753223046.770 [Default:A.Wait] Stopped 2025-07-22T22:24:06.770Z,1753223046.770 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-07-22T22:24:06.952Z,1753223046.952 [Default:CheckIn] Running Loop=1 2025-07-22T22:24:06.952Z,1753223046.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-22T22:24:06.953Z,1753223046.953 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-22T22:24:07.420Z,1753223047.420 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-07-22T22:24:13.799Z,1753223053.799 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-22T22:25:04.328Z,1753223104.328 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-22T22:25:23.726Z,1753223123.726 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-07-22T22:25:23.726Z,1753223123.726 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-22T22:25:23.728Z,1753223123.728 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2025-07-22T22:25:23.728Z,1753223123.728 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-07-22T22:25:23.739Z,1753223123.739 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-22T22:25:23.755Z,1753223123.755 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-07-22T22:25:24.140Z,1753223124.140 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-22T22:25:24.140Z,1753223124.140 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-07-22T22:25:24.141Z,1753223124.141 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-07-22T22:25:24.141Z,1753223124.141 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2025-07-22T22:27:02.679Z,1753223222.679 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2025-07-22T22:27:02.682Z,1753223222.682 [BPC1](INFO): Received data from all battery sticks. 2025-07-22T22:28:24.726Z,1753223304.726 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-07-22T22:28:24.726Z,1753223304.726 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-22T22:28:24.736Z,1753223304.736 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2025-07-22T22:28:24.736Z,1753223304.736 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-07-22T22:28:24.751Z,1753223304.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-22T22:28:24.752Z,1753223304.752 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-07-22T22:28:25.171Z,1753223305.171 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-22T22:28:25.171Z,1753223305.171 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-07-22T22:28:25.171Z,1753223305.171 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-07-22T22:28:25.172Z,1753223305.172 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2025-07-22T22:29:07.172Z,1753223347.172 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-22T22:24:06.0Z 2025-07-22T22:29:07.172Z,1753223347.172 [Default:CheckIn:Read_GPS] Stopped 2025-07-22T22:29:07.172Z,1753223347.172 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-22T22:29:07.544Z,1753223347.544 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-22T22:29:10.929Z,1753223350.929 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250722T221910/Courier0004.lzma 2025-07-22T22:29:21.321Z,1753223361.321 [DataOverHttps](INFO): Moved sent file to Logs/20250722T221910/Courier0004.lzma.bak 2025-07-22T22:29:21.321Z,1753223361.321 [DataOverHttps](INFO): SBD MOMSN=25570274 2025-07-22T22:29:48.816Z,1753223388.816 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20250722T221910/Express0005.lzma 2025-07-22T22:29:59.857Z,1753223399.857 [DataOverHttps](INFO): Moved sent file to Logs/20250722T221910/Express0005.lzma.bak 2025-07-22T22:29:59.857Z,1753223399.857 [DataOverHttps](INFO): SBD MOMSN=25570279 2025-07-22T22:30:02.150Z,1753223402.150 [Default:CheckIn:Read_Iridium] Stopped 2025-07-22T22:30:02.150Z,1753223402.150 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-22T22:30:02.151Z,1753223402.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-22T22:30:03.329Z,1753223403.329 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-07-22T22:30:03.329Z,1753223403.329 [NAL9602] Data Fault, FailCount= 1 2025-07-22T22:30:03.329Z,1753223403.329 [NAL9602](ERROR): Data Fault 2025-07-22T22:30:03.368Z,1753223403.368 [CBIT](ERROR): Data Fault in component: NAL9602 2025-07-22T22:30:03.736Z,1753223403.736 [NAL9602](INFO): Powering down 2025-07-22T22:30:04.649Z,1753223404.649 [CBIT](INFO): Clearing failed state for component NAL9602 2025-07-22T22:30:04.649Z,1753223404.649 [NAL9602] No Fault, FailCount= 1 2025-07-22T22:30:34.038Z,1753223434.038 [NAL9602](INFO): Powering up NAL9602 2025-07-22T22:30:44.960Z,1753223444.960 [NAL9602](INFO): NAL9602 initialized 2025-07-22T22:31:25.826Z,1753223485.826 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-07-22T22:31:25.826Z,1753223485.826 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-07-22T22:31:25.849Z,1753223485.849 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2025-07-22T22:31:25.849Z,1753223485.849 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-07-22T22:31:25.902Z,1753223485.902 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-07-22T22:31:25.902Z,1753223485.902 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-07-22T22:31:26.405Z,1753223486.405 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-07-22T22:31:26.405Z,1753223486.405 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-07-22T22:31:26.406Z,1753223486.406 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-07-22T22:31:26.406Z,1753223486.406 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2025-07-22T22:32:49.054Z,1753223569.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223245.99,A,3648.17582,N,12147.28589,W,0.019,0.00,220725,,,A*7B 2025-07-22T22:32:49.072Z,1753223569.072 [NAL9602](INFO): GPS fix at 20250722T223245: (36.802930, -121.788098) 2025-07-22T22:33:21.372Z,1753223601.372 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-22T22:34:26.857Z,1753223666.857 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-07-22T22:34:26.857Z,1753223666.857 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-07-22T22:34:26.868Z,1753223666.868 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-07-22T22:34:27.283Z,1753223667.283 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-07-22T22:34:27.283Z,1753223667.283 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-07-22T22:35:02.854Z,1753223702.854 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-22T22:35:02.855Z,1753223702.855 [Default:CheckIn:C.Wait] Stopped 2025-07-22T22:35:02.859Z,1753223702.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-22T22:35:02.859Z,1753223702.859 [Default:CheckIn:D] Running Loop=1 2025-07-22T22:35:03.319Z,1753223703.319 [Default:CheckIn:D] Stopped 2025-07-22T22:35:03.319Z,1753223703.319 [Default:CheckIn:E] Running Loop=1 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.167550 min 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn:E] Stopped 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn] Stopped 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn](INFO): Running loop #2 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn] Running Loop=2 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-22T22:35:03.684Z,1753223703.684 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-22T22:35:05.675Z,1753223705.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223504.00,A,3648.17845,N,12147.28452,W,0.078,0.00,220725,,,A*7F 2025-07-22T22:35:05.677Z,1753223705.677 [NAL9602](INFO): GPS fix at 20250722T223504: (36.802974, -121.788075) 2025-07-22T22:35:05.690Z,1753223705.690 [Default:CheckIn:Read_GPS] Stopped 2025-07-22T22:35:05.690Z,1753223705.690 [Default:CheckIn:Read_Iridium] Running Loop=1