2025-06-26T18:19:00.402Z,1750961940.402 [Supervisor](DEBUG): Initializing supervisor. 2025-06-26T18:19:00.407Z,1750961940.407 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-06-26T18:19:00.408Z,1750961940.408 [SyncHandler](INFO): Protected caller Thread ID is 3991 2025-06-26T18:19:00.408Z,1750961940.408 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-06-26T18:19:00.410Z,1750961940.410 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-06-26T18:19:00.410Z,1750961940.410 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3992 2025-06-26T18:19:00.414Z,1750961940.414 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-06-26T18:19:00.435Z,1750961940.435 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-06-26T18:19:00.436Z,1750961940.436 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-06-26T18:19:00.436Z,1750961940.436 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3993 2025-06-26T18:19:00.440Z,1750961940.440 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-06-26T18:19:00.441Z,1750961940.441 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-06-26T18:19:00.442Z,1750961940.442 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3994 2025-06-26T18:19:00.444Z,1750961940.444 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-06-26T18:19:00.445Z,1750961940.445 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-06-26T18:19:00.445Z,1750961940.445 [logger ThreadHandler](INFO): Protected caller Thread ID is 3995 2025-06-26T18:19:00.449Z,1750961940.449 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-06-26T18:19:00.449Z,1750961940.449 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-06-26T18:19:00.451Z,1750961940.451 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-06-26T18:19:00.668Z,1750961940.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-06-26T18:19:00.669Z,1750961940.669 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-06-26T18:19:00.785Z,1750961940.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-06-26T18:19:00.786Z,1750961940.786 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-06-26T18:19:00.891Z,1750961940.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-06-26T18:19:00.891Z,1750961940.891 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-06-26T18:19:01.382Z,1750961941.382 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-06-26T18:19:01.383Z,1750961941.383 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-06-26T18:19:01.741Z,1750961941.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-06-26T18:19:01.742Z,1750961941.742 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-06-26T18:19:02.086Z,1750961942.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-06-26T18:19:02.087Z,1750961942.087 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-06-26T18:19:02.275Z,1750961942.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-06-26T18:19:02.276Z,1750961942.276 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-06-26T18:19:02.366Z,1750961942.366 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-06-26T18:19:02.467Z,1750961942.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-06-26T18:19:02.467Z,1750961942.467 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-06-26T18:19:02.935Z,1750961942.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-06-26T18:19:02.935Z,1750961942.935 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-06-26T18:19:03.221Z,1750961943.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-06-26T18:19:03.222Z,1750961943.222 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-06-26T18:19:03.776Z,1750961943.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-06-26T18:19:03.777Z,1750961943.777 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-06-26T18:19:04.021Z,1750961944.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-06-26T18:19:04.021Z,1750961944.021 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-06-26T18:19:04.283Z,1750961944.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-06-26T18:19:04.284Z,1750961944.284 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-06-26T18:19:04.436Z,1750961944.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-06-26T18:19:04.436Z,1750961944.436 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-06-26T18:19:06.081Z,1750961946.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-06-26T18:19:06.083Z,1750961946.083 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2025-06-26T18:19:06.084Z,1750961946.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2025-06-26T18:19:06.179Z,1750961946.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2025-06-26T18:19:06.315Z,1750961946.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2025-06-26T18:19:06.594Z,1750961946.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-06-26T18:19:06.595Z,1750961946.595 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2025-06-26T18:19:06.729Z,1750961946.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2025-06-26T18:19:06.836Z,1750961946.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2025-06-26T18:19:06.939Z,1750961946.939 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2025-06-26T18:19:07.057Z,1750961947.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2025-06-26T18:19:07.152Z,1750961947.152 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2025-06-26T18:19:07.249Z,1750961947.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2025-06-26T18:19:07.350Z,1750961947.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2025-06-26T18:19:07.529Z,1750961947.529 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2025-06-26T18:19:07.529Z,1750961947.529 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-06-26T18:19:07.537Z,1750961947.537 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-06-26T18:19:07.590Z,1750961947.590 [DepthRateCalculator] Loaded 2025-06-26T18:19:07.590Z,1750961947.590 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-06-26T18:19:07.596Z,1750961947.596 [PitchRateCalculator] Loaded 2025-06-26T18:19:07.596Z,1750961947.596 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-06-26T18:19:07.606Z,1750961947.606 [SpeedCalculator] Loaded 2025-06-26T18:19:07.607Z,1750961947.607 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-06-26T18:19:07.611Z,1750961947.611 [YawRateCalculator] Loaded 2025-06-26T18:19:07.612Z,1750961947.612 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-06-26T18:19:07.634Z,1750961947.634 [ElevatorOffsetCalculator] Loaded 2025-06-26T18:19:07.634Z,1750961947.634 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-06-26T18:19:07.635Z,1750961947.635 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-06-26T18:19:07.635Z,1750961947.635 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-06-26T18:19:07.665Z,1750961947.665 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-06-26T18:19:07.665Z,1750961947.665 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-06-26T18:19:07.676Z,1750961947.676 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-06-26T18:19:07.676Z,1750961947.676 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-06-26T18:19:08.181Z,1750961948.181 [AHRS_M2] Loaded 2025-06-26T18:19:08.181Z,1750961948.181 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-06-26T18:19:08.220Z,1750961948.220 [BackseatComponent] Loaded 2025-06-26T18:19:08.220Z,1750961948.220 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-06-26T18:19:08.221Z,1750961948.221 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0 2025-06-26T18:19:08.222Z,1750961948.222 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 4077 2025-06-26T18:19:08.225Z,1750961948.225 [LcmUniversalReporter] Loaded 2025-06-26T18:19:08.225Z,1750961948.225 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-06-26T18:19:09.045Z,1750961949.045 [BPC1] Loaded 2025-06-26T18:19:09.045Z,1750961949.045 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-06-26T18:19:09.143Z,1750961949.143 [DAT] Loaded 2025-06-26T18:19:09.143Z,1750961949.143 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-06-26T18:19:09.144Z,1750961949.144 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0 2025-06-26T18:19:09.145Z,1750961949.145 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4078 2025-06-26T18:19:09.219Z,1750961949.219 [DataOverHttps] Loaded 2025-06-26T18:19:09.220Z,1750961949.220 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-06-26T18:19:09.221Z,1750961949.221 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0 2025-06-26T18:19:09.221Z,1750961949.221 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4079 2025-06-26T18:19:09.244Z,1750961949.244 [Depth_Keller] Loaded 2025-06-26T18:19:09.244Z,1750961949.244 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-06-26T18:19:09.249Z,1750961949.249 [DropWeight] Loaded 2025-06-26T18:19:09.250Z,1750961949.250 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-06-26T18:19:09.271Z,1750961949.271 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7 2025-06-26T18:19:09.271Z,1750961949.271 [MultiRay] Loaded 2025-06-26T18:19:09.271Z,1750961949.271 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2025-06-26T18:19:09.336Z,1750961949.336 [NAL9602] Loaded 2025-06-26T18:19:09.336Z,1750961949.336 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-06-26T18:19:09.367Z,1750961949.367 [Onboard] Loaded 2025-06-26T18:19:09.367Z,1750961949.367 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-06-26T18:19:09.368Z,1750961949.368 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0 2025-06-26T18:19:09.368Z,1750961949.368 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4080 2025-06-26T18:19:09.374Z,1750961949.374 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2025-06-26T18:19:09.387Z,1750961949.387 [PowerOnly] Loaded 2025-06-26T18:19:09.388Z,1750961949.388 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2025-06-26T18:19:09.402Z,1750961949.402 [Power24vConverter] Loaded 2025-06-26T18:19:09.402Z,1750961949.402 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-06-26T18:19:09.419Z,1750961949.419 [Radio_Surface] Loaded 2025-06-26T18:19:09.419Z,1750961949.419 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-06-26T18:19:09.420Z,1750961949.420 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0 2025-06-26T18:19:09.421Z,1750961949.421 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4081 2025-06-26T18:19:09.422Z,1750961949.422 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-06-26T18:19:09.422Z,1750961949.422 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-06-26T18:19:09.536Z,1750961949.536 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-06-26T18:19:09.536Z,1750961949.536 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-06-26T18:19:09.551Z,1750961949.551 [NavChart] Loaded 2025-06-26T18:19:09.551Z,1750961949.551 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-06-26T18:19:09.556Z,1750961949.556 [UniversalFixResidualReporter] Loaded 2025-06-26T18:19:09.557Z,1750961949.557 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-06-26T18:19:09.557Z,1750961949.557 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-06-26T18:19:09.558Z,1750961949.558 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-06-26T18:19:09.719Z,1750961949.719 [SBIT](DEBUG): Construct Startup Built In Test. 2025-06-26T18:19:09.733Z,1750961949.733 [SBIT] Loaded 2025-06-26T18:19:09.733Z,1750961949.733 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-06-26T18:19:09.736Z,1750961949.736 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-06-26T18:19:09.749Z,1750961949.749 [IBIT] Loaded 2025-06-26T18:19:09.749Z,1750961949.749 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-06-26T18:19:09.755Z,1750961949.755 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-06-26T18:19:09.836Z,1750961949.836 [CBIT] Loaded 2025-06-26T18:19:09.836Z,1750961949.836 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-06-26T18:19:09.868Z,1750961949.868 [GFScanner] Loaded 2025-06-26T18:19:09.869Z,1750961949.869 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-06-26T18:19:09.869Z,1750961949.869 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-06-26T18:19:09.870Z,1750961949.870 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-06-26T18:19:10.044Z,1750961950.044 [CTD_Seabird] Loaded 2025-06-26T18:19:10.045Z,1750961950.045 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-06-26T18:19:10.046Z,1750961950.046 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4B4E0 2025-06-26T18:19:10.046Z,1750961950.046 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4082 2025-06-26T18:19:10.069Z,1750961950.069 [PAR_Licor] Loaded 2025-06-26T18:19:10.069Z,1750961950.069 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-06-26T18:19:10.115Z,1750961950.115 [WetLabsBB2FL] Loaded 2025-06-26T18:19:10.115Z,1750961950.115 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-06-26T18:19:10.116Z,1750961950.116 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7B4E0 2025-06-26T18:19:10.116Z,1750961950.116 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4083 2025-06-26T18:19:10.117Z,1750961950.117 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-06-26T18:19:10.117Z,1750961950.117 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-06-26T18:19:10.258Z,1750961950.258 [BuoyancyServo] Loaded 2025-06-26T18:19:10.258Z,1750961950.258 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-06-26T18:19:10.283Z,1750961950.283 [ElevatorServo] Loaded 2025-06-26T18:19:10.283Z,1750961950.283 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-06-26T18:19:10.307Z,1750961950.307 [MassServo] Loaded 2025-06-26T18:19:10.307Z,1750961950.307 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-06-26T18:19:10.330Z,1750961950.330 [RudderServo] Loaded 2025-06-26T18:19:10.330Z,1750961950.330 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-06-26T18:19:10.348Z,1750961950.348 [ThrusterHE] Loaded 2025-06-26T18:19:10.349Z,1750961950.349 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-06-26T18:19:10.349Z,1750961950.349 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-06-26T18:19:10.350Z,1750961950.350 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-06-26T18:19:10.403Z,1750961950.403 [VerticalControl](DEBUG): Construct VerticalControl. 2025-06-26T18:19:10.455Z,1750961950.455 [VerticalControl] Loaded 2025-06-26T18:19:10.456Z,1750961950.456 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-06-26T18:19:10.459Z,1750961950.459 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-06-26T18:19:10.504Z,1750961950.504 [HorizontalControl] Loaded 2025-06-26T18:19:10.504Z,1750961950.504 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-06-26T18:19:10.506Z,1750961950.506 [SpeedControl](DEBUG): Construct SpeedControl. 2025-06-26T18:19:10.508Z,1750961950.508 [SpeedControl] Loaded 2025-06-26T18:19:10.508Z,1750961950.508 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-06-26T18:19:10.511Z,1750961950.511 [LoopControl](DEBUG): Construct LoopControl. 2025-06-26T18:19:10.512Z,1750961950.512 [LoopControl] Loaded 2025-06-26T18:19:10.512Z,1750961950.512 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-06-26T18:19:10.512Z,1750961950.512 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-06-26T18:19:10.513Z,1750961950.513 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-06-26T18:19:10.647Z,1750961950.647 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-06-26T18:19:10.648Z,1750961950.648 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-06-26T18:19:10.828Z,1750961950.828 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-06-26T18:19:10.828Z,1750961950.828 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-06-26T18:19:11.174Z,1750961951.174 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-06-26T18:19:11.174Z,1750961951.174 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-06-26T18:19:11.387Z,1750961951.387 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-06-26T18:19:11.445Z,1750961951.445 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-06-26T18:19:11.448Z,1750961951.448 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-06-26T18:19:11.554Z,1750961951.554 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-06-26T18:19:11.555Z,1750961951.555 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0 2025-06-26T18:19:11.556Z,1750961951.556 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4084 2025-06-26T18:19:11.560Z,1750961951.560 [Supervisor](INFO): Main Thread ID is 3842 2025-06-26T18:19:11.560Z,1750961951.560 [Supervisor](DEBUG): Running supervisor. 2025-06-26T18:19:11.561Z,1750961951.561 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4085 2025-06-26T18:19:11.562Z,1750961951.562 [CommandExec](INFO): Initializing the command executive. 2025-06-26T18:19:11.567Z,1750961951.567 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4086 2025-06-26T18:19:11.569Z,1750961951.569 [controlThread ThreadHandler](INFO): Handler Thread ID is 4087 2025-06-26T18:19:11.569Z,1750961951.569 [controlThread](DEBUG): Initializing ControlThread 2025-06-26T18:19:11.570Z,1750961951.570 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-06-26T18:19:11.571Z,1750961951.571 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-06-26T18:19:11.571Z,1750961951.571 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-06-26T18:19:11.571Z,1750961951.571 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-06-26T18:19:11.572Z,1750961951.572 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-06-26T18:19:11.576Z,1750961951.576 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-06-26T18:19:11.576Z,1750961951.576 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-06-26T18:19:11.577Z,1750961951.577 [SBIT](INFO): Initialize SBIT Component. 2025-06-26T18:19:11.577Z,1750961951.577 [SBIT](IMPORTANT): git: 2025-06-23 2025-06-26T18:19:11.577Z,1750961951.577 [SBIT](INFO): git hash: 4f30d88d07a5bb26241f3dcd8e36105bcba949e8 2025-06-26T18:19:11.578Z,1750961951.578 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-06-26T18:19:11.579Z,1750961951.579 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-06-26T18:19:11.580Z,1750961951.580 [SBIT](INFO): Beginning SBIT in 121.000000 seconds. 2025-06-26T18:19:11.580Z,1750961951.580 [IBIT](INFO): Initialize IBIT Component. 2025-06-26T18:19:11.581Z,1750961951.581 [CBIT](DEBUG): Initialize CBIT Component. 2025-06-26T18:19:11.584Z,1750961951.584 [logger ThreadHandler](INFO): Handler Thread ID is 4088 2025-06-26T18:19:11.594Z,1750961951.594 [CBIT](DEBUG): Initialized mux pins. 2025-06-26T18:19:11.594Z,1750961951.594 [CBIT](DEBUG): Initializing the watchdog timer. 2025-06-26T18:19:11.603Z,1750961951.603 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 4089 2025-06-26T18:19:11.618Z,1750961951.618 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-06-26T18:19:11.618Z,1750961951.618 [CBIT](DEBUG): Initializing heartbeat. 2025-06-26T18:19:11.619Z,1750961951.619 [DAT ThreadHandler](INFO): Handler Thread ID is 4090 2025-06-26T18:19:11.620Z,1750961951.620 [DAT](INFO): Powering up 2025-06-26T18:19:11.620Z,1750961951.620 [DAT](DEBUG): Initializing DAT. 2025-06-26T18:19:11.624Z,1750961951.624 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4092 2025-06-26T18:19:11.625Z,1750961951.625 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-06-26T18:19:11.648Z,1750961951.648 [Onboard ThreadHandler](INFO): Handler Thread ID is 4093 2025-06-26T18:19:11.668Z,1750961951.668 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4094 2025-06-26T18:19:11.683Z,1750961951.683 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4095 2025-06-26T18:19:11.683Z,1750961951.683 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-06-26T18:19:11.687Z,1750961951.687 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4097 2025-06-26T18:19:11.690Z,1750961951.690 [CBIT](DEBUG): Deactivating emergency mode. 2025-06-26T18:19:11.691Z,1750961951.691 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4099 2025-06-26T18:19:11.693Z,1750961951.693 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-06-26T18:19:11.693Z,1750961951.693 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-06-26T18:19:11.694Z,1750961951.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-06-26T18:19:11.694Z,1750961951.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-06-26T18:19:11.694Z,1750961951.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-06-26T18:19:11.694Z,1750961951.694 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-06-26T18:19:11.694Z,1750961951.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-06-26T18:19:11.695Z,1750961951.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-06-26T18:19:11.698Z,1750961951.698 [WetLabsBB2FL](INFO): Powering up 2025-06-26T18:19:11.730Z,1750961951.730 [CBIT](DEBUG): Backplane powered. 2025-06-26T18:19:11.730Z,1750961951.730 [GFScanner](DEBUG): Initializing GFScanner 2025-06-26T18:19:11.731Z,1750961951.731 [GFScanner](DEBUG): Deactivating GF circuits. 2025-06-26T18:19:11.740Z,1750961951.740 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-06-26T18:19:11.741Z,1750961951.741 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-06-26T18:19:11.742Z,1750961951.742 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-06-26T18:19:11.743Z,1750961951.743 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-06-26T18:19:11.743Z,1750961951.743 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-06-26T18:19:11.744Z,1750961951.744 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-06-26T18:19:11.744Z,1750961951.744 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-06-26T18:19:11.745Z,1750961951.745 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-06-26T18:19:11.803Z,1750961951.803 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-06-26T18:19:11.820Z,1750961951.820 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-26T18:19:11.844Z,1750961951.844 [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-06-26T18:19:11.845Z,1750961951.845 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-06-26T18:19:11.845Z,1750961951.845 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-06-26T18:19:11.846Z,1750961951.846 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-06-26T18:19:12.057Z,1750961952.057 [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-06-26T18:19:12.070Z,1750961952.070 [Radio_Surface](INFO): Powering up 2025-06-26T18:19:12.073Z,1750961952.073 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-06-26T18:19:12.083Z,1750961952.083 [Default:A.Wait](DEBUG): Construct Wait. 2025-06-26T18:19:12.085Z,1750961952.085 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-26T18:19:12.111Z,1750961952.111 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-06-26T18:19:12.117Z,1750961952.117 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-06-26T18:19:12.139Z,1750961952.139 [Default:E.Execute](DEBUG): Construct Execute. 2025-06-26T18:19:12.142Z,1750961952.142 [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-06-26T18:19:12.147Z,1750961952.147 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-06-26T18:19:12.166Z,1750961952.166 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-06-26T18:19:12.227Z,1750961952.227 [Depth_Keller](INFO): Initializing. 2025-06-26T18:19:12.228Z,1750961952.228 [MultiRay](INFO): Powering up MultiRay Lights 2025-06-26T18:19:12.228Z,1750961952.228 [MultiRay](INFO): Powering up secondary power supply. 2025-06-26T18:19:12.264Z,1750961952.264 [Power24vConverter](INFO): Powering up. 2025-06-26T18:19:12.286Z,1750961952.286 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-06-26T18:19:12.309Z,1750961952.309 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-06-26T18:19:12.314Z,1750961952.314 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-06-26T18:19:12.315Z,1750961952.315 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-06-26T18:19:12.323Z,1750961952.323 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-06-26T18:19:12.323Z,1750961952.323 [MassServo](DEBUG): Initializing EZServoServo. 2025-06-26T18:19:12.331Z,1750961952.331 [MassServo](DEBUG): Initializing MassServo. 2025-06-26T18:19:12.331Z,1750961952.331 [RudderServo](DEBUG): Initializing EZServoServo. 2025-06-26T18:19:12.338Z,1750961952.338 [RudderServo](DEBUG): Initializing RudderServo. 2025-06-26T18:19:12.339Z,1750961952.339 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-06-26T18:19:12.354Z,1750961952.354 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-06-26T18:19:13.372Z,1750961953.372 [MultiRay](INFO): Powering down 2025-06-26T18:19:13.490Z,1750961953.490 [MultiRay](INFO): Powering down LCB2 2025-06-26T18:19:14.302Z,1750961954.302 [WetLabsBB2FL](INFO): Powering down 2025-06-26T18:19:18.731Z,1750961958.731 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-06-26T18:19:23.211Z,1750961963.211 [DAT](INFO): DAT read: 2025-06-26T18:19:23.212Z,1750961963.212 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-06-26T18:19:24.727Z,1750961964.727 [DAT](INFO): DAT read: MF Frequency Band 2025-06-26T18:19:24.728Z,1750961964.728 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-06-26T18:19:24.729Z,1750961964.729 [DAT](INFO): DAT read: Jun 26 2025 18:19:19 2025-06-26T18:19:25.987Z,1750961965.987 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-06-26T18:19:25.989Z,1750961965.989 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-06-26T18:19:25.989Z,1750961965.989 [DAT](INFO): commRate: 800 2025-06-26T18:19:28.062Z,1750961968.062 [DAT](INFO): entering command mode 2025-06-26T18:19:28.263Z,1750961968.263 [DAT](INFO): DAT read: 2025-06-26T18:19:28.263Z,1750961968.263 [DAT](INFO): DAT read: user:1> 2025-06-26T18:19:28.264Z,1750961968.264 [DAT](INFO): setting verbose to 3 2025-06-26T18:19:28.515Z,1750961968.515 [DAT](INFO): DAT read: user:1> 2025-06-26T18:19:28.516Z,1750961968.516 [DAT](INFO): DAT read: Verbose | 3 2025-06-26T18:19:28.516Z,1750961968.516 [DAT](INFO): set verbose to 3 2025-06-26T18:19:28.517Z,1750961968.517 [DAT](INFO): setting DatVerbose to 27440 2025-06-26T18:19:28.767Z,1750961968.767 [DAT](INFO): DAT read: user:2> 2025-06-26T18:19:28.768Z,1750961968.768 [DAT](INFO): DAT read: DatVerbose | 27440 2025-06-26T18:19:28.768Z,1750961968.768 [DAT](INFO): set DatVerbose to 27440 2025-06-26T18:19:28.768Z,1750961968.768 [DAT](INFO): setting transmit power to 8 2025-06-26T18:19:29.019Z,1750961969.019 [DAT](INFO): DAT read: user:3> 2025-06-26T18:19:29.020Z,1750961969.020 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-06-26T18:19:29.020Z,1750961969.020 [DAT](INFO): set transmit power to 8 2025-06-26T18:19:29.021Z,1750961969.021 [DAT](INFO): setting local address to 9 2025-06-26T18:19:29.271Z,1750961969.271 [DAT](INFO): DAT read: user:4> 2025-06-26T18:19:29.272Z,1750961969.272 [DAT](INFO): DAT read: LocalAddr | 9 2025-06-26T18:19:29.272Z,1750961969.272 [DAT](INFO): set local address to 9 2025-06-26T18:19:29.273Z,1750961969.273 [DAT](INFO): Setting time to: 18:19:29 And date to:6/26/2025 2025-06-26T18:19:29.523Z,1750961969.523 [DAT](INFO): DAT read: user:5> 2025-06-26T18:19:29.524Z,1750961969.524 [DAT](INFO): DAT read: Thu Jun 26, 2025 18:19:29 2025-06-26T18:19:29.524Z,1750961969.524 [DAT](INFO): Local DAT time set to Thu Jun 26, 2025 18:19:29 2025-06-26T18:19:39.728Z,1750961979.728 [NAL9602](INFO): Powering up NAL9602 2025-06-26T18:19:50.637Z,1750961990.637 [NAL9602](INFO): NAL9602 initialized 2025-06-26T18:20:10.123Z,1750962010.123 [CommandExec](IMPORTANT): got command strobe off 2025-06-26T18:20:10.124Z,1750962010.124 [CommandExec](IMPORTANT): Deactivating strobe 2025-06-26T18:21:09.491Z,1750962069.491 [CommandExec](IMPORTANT): got command burn on 2025-06-26T18:21:09.492Z,1750962069.492 [CommandExec](IMPORTANT): Activating dropweight wire 2025-06-26T18:21:13.061Z,1750962073.061 [SBIT](IMPORTANT): Beginning Startup BIT 2025-06-26T18:21:13.065Z,1750962073.065 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-26T18:21:17.703Z,1750962077.703 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006432 CHAN A1 (24V): 0.002120 CHAN A2 (12V): -0.005769 CHAN A3 (5V): -0.002823 CHAN B0 (3.3V): -0.001349 CHAN B1 (3.15aV): -0.001075 CHAN B2 (3.15bV): -0.001982 CHAN B3 (GND): -0.000778 OPEN: 0.004587 Full Scale: +/- 1 mA 2025-06-26T18:21:41.159Z,1750962101.159 [CommandExec](IMPORTANT): got command burn off 2025-06-26T18:21:41.159Z,1750962101.159 [CommandExec](IMPORTANT): Deactivating dropweight wire 2025-06-26T18:22:06.431Z,1750962126.431 [SBIT](IMPORTANT): SBIT PASSED 2025-06-26T18:22:06.431Z,1750962126.431 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-06-26T18:22:06.432Z,1750962126.432 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool; 2025-06-26T18:22:06.432Z,1750962126.432 [SBIT](IMPORTANT): MultiRay.brightnessWhite=88 none; 2025-06-26T18:22:06.432Z,1750962126.432 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool; 2025-06-26T18:22:06.433Z,1750962126.433 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2025-06-26T18:22:06.433Z,1750962126.433 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2025-06-26T18:22:06.435Z,1750962126.435 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=215.486556 cubic_centimeter; 2025-06-26T18:22:06.435Z,1750962126.435 [SBIT](IMPORTANT): VerticalControl.massDefault=25.322496 millimeter; 2025-06-26T18:22:06.845Z,1750962126.845 [MissionManager](IMPORTANT): Started mission Startup 2025-06-26T18:22:06.845Z,1750962126.845 [Startup] Running Loop=1 2025-06-26T18:22:06.845Z,1750962126.845 [Startup](DEBUG): Aggregate::initialize Startup 2025-06-26T18:22:06.845Z,1750962126.845 [Startup:A.GoToSurface] Running Loop=1 2025-06-26T18:22:06.845Z,1750962126.845 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-26T18:22:06.846Z,1750962126.846 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-26T18:22:06.846Z,1750962126.846 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-26T18:22:06.847Z,1750962126.847 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-26T18:22:06.847Z,1750962126.847 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-26T18:22:06.847Z,1750962126.847 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-26T18:22:06.848Z,1750962126.848 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-26T18:22:06.853Z,1750962126.853 [Startup:StartupSatComms] Running Loop=1 2025-06-26T18:22:06.853Z,1750962126.853 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-06-26T18:22:06.853Z,1750962126.853 [Startup:StartupSatComms:A] Running Loop=1 2025-06-26T18:22:07.237Z,1750962127.237 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-06-26T18:22:11.683Z,1750962131.683 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-06-26T18:22:11.683Z,1750962131.683 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-06-26T18:22:11.822Z,1750962131.822 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-06-26T18:22:12.238Z,1750962132.238 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-06-26T18:22:12.238Z,1750962132.238 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-06-26T18:22:37.819Z,1750962157.819 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-06-26T18:23:00.249Z,1750962180.249 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004920 2025-06-26T18:23:07.033Z,1750962187.033 [Startup:StartupSatComms:A](INFO): Timed out from 2025-06-26T18:22:06.9Z 2025-06-26T18:23:07.033Z,1750962187.033 [Startup:StartupSatComms:A] Stopped 2025-06-26T18:23:07.033Z,1750962187.033 [Startup:StartupSatComms:B] Running Loop=1 2025-06-26T18:23:07.433Z,1750962187.433 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-26T18:23:14.738Z,1750962194.738 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250624T192950/Courier0144.lzma 2025-06-26T18:23:25.781Z,1750962205.781 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0144.lzma.bak 2025-06-26T18:23:25.781Z,1750962205.781 [DataOverHttps](INFO): SBD MOMSN=25248705 2025-06-26T18:23:54.398Z,1750962234.398 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250626T181451/Courier0000.lzma 2025-06-26T18:24:05.441Z,1750962245.441 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181451/Courier0000.lzma.bak 2025-06-26T18:24:05.441Z,1750962245.441 [DataOverHttps](INFO): SBD MOMSN=25248711 2025-06-26T18:24:07.309Z,1750962247.309 [Startup:StartupSatComms:B](INFO): Timed out from 2025-06-26T18:23:07.0Z 2025-06-26T18:24:07.309Z,1750962247.309 [Startup:StartupSatComms:B] Stopped 2025-06-26T18:24:07.309Z,1750962247.309 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-06-26T18:24:07.309Z,1750962247.309 [Startup:StartupSatComms] Stopped 2025-06-26T18:24:07.309Z,1750962247.309 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-06-26T18:24:07.310Z,1750962247.310 [Startup](INFO): Completed Startup 2025-06-26T18:24:07.310Z,1750962247.310 [MissionManager](INFO): Startup is completed. 2025-06-26T18:24:07.310Z,1750962247.310 [MissionManager](INFO): Uninitializing Mission Startup 2025-06-26T18:24:07.311Z,1750962247.311 [Startup] Stopped 2025-06-26T18:24:07.311Z,1750962247.311 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-06-26T18:24:07.311Z,1750962247.311 [Startup:A.GoToSurface] Stopped 2025-06-26T18:24:07.311Z,1750962247.311 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-26T18:24:07.689Z,1750962247.689 [MissionManager](IMPORTANT): Started mission Default 2025-06-26T18:24:07.690Z,1750962247.690 [Default] Running Loop=1 2025-06-26T18:24:07.690Z,1750962247.690 [Default](DEBUG): Aggregate::initialize Default 2025-06-26T18:24:07.690Z,1750962247.690 [Default:B.GoToSurface] Running Loop=1 2025-06-26T18:24:07.690Z,1750962247.690 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-26T18:24:07.690Z,1750962247.690 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-26T18:24:07.691Z,1750962247.691 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-26T18:24:07.691Z,1750962247.691 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-26T18:24:07.691Z,1750962247.691 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-26T18:24:07.692Z,1750962247.692 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-26T18:24:07.692Z,1750962247.692 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-26T18:24:07.692Z,1750962247.692 [Default:A.Wait] Running Loop=1 2025-06-26T18:24:07.692Z,1750962247.692 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:24:21.057Z,1750962261.057 [Default:A.Wait](INFO): Done Waiting. 2025-06-26T18:24:21.057Z,1750962261.057 [Default:A.Wait] Stopped 2025-06-26T18:24:21.057Z,1750962261.057 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:24:21.445Z,1750962261.445 [Default:CheckIn] Running Loop=1 2025-06-26T18:24:21.445Z,1750962261.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T18:24:21.445Z,1750962261.445 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T18:24:21.853Z,1750962261.853 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-26T18:24:48.610Z,1750962288.610 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250626T181900/Courier0000.lzma 2025-06-26T18:24:53.736Z,1750962293.736 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-26T18:24:59.652Z,1750962299.652 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0000.lzma.bak 2025-06-26T18:24:59.652Z,1750962299.652 [DataOverHttps](INFO): SBD MOMSN=25248716 2025-06-26T18:25:12.739Z,1750962312.739 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-06-26T18:25:12.739Z,1750962312.739 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-06-26T18:25:12.749Z,1750962312.749 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-06-26T18:25:13.165Z,1750962313.165 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-06-26T18:25:13.166Z,1750962313.166 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-06-26T18:25:35.196Z,1750962335.196 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T182534 2025-06-26T18:25:43.059Z,1750962343.059 [DataOverHttps](INFO): Received command: run Engineering/multiray_test.tl 2025-06-26T18:25:43.190Z,1750962343.190 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/multiray_test.tl 2025-06-26T18:25:43.191Z,1750962343.191 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/multiray_test.tl 2025-06-26T18:25:43.191Z,1750962343.191 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/multiray_test.tl 2025-06-26T18:25:43.195Z,1750962343.195 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/multiray_test.tl 2025-06-26T18:25:43.634Z,1750962343.634 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/multiray_test.tl translated into: 5 5 0 1 2 2025-06-26T18:25:43.649Z,1750962343.649 [MissionManager](INFO): DefineArg multiray_test.MissionTimeout = 5.000000 min 2025-06-26T18:25:43.652Z,1750962343.652 [MissionManager](INFO): DefineArg multiray_test.LightDuration = 5.000000 s 2025-06-26T18:25:43.655Z,1750962343.655 [MissionManager](INFO): DefineArg multiray_test.LightMode0 = 0.000000 count 2025-06-26T18:25:43.658Z,1750962343.658 [MissionManager](INFO): DefineArg multiray_test.LightMode1 = 1.000000 count 2025-06-26T18:25:43.729Z,1750962343.729 [MissionManager](INFO): DefineArg multiray_test.LightMode2 = 2.000000 count 2025-06-26T18:25:43.748Z,1750962343.748 [MissionManager](INFO): DefineArg multiray_test.EnabledMultiray = 1 bool 2025-06-26T18:25:43.757Z,1750962343.757 [multiray_test:C.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.776Z,1750962343.776 [multiray_test:E.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.780Z,1750962343.780 [multiray_test:G.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.816Z,1750962343.816 [multiray_test:I.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.852Z,1750962343.852 [multiray_test:K.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.895Z,1750962343.895 [multiray_test:M.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.914Z,1750962343.914 [multiray_test:O.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.946Z,1750962343.946 [multiray_test:Q.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.950Z,1750962343.950 [multiray_test:R.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.961Z,1750962343.961 [multiray_test:T.Wait](DEBUG): Construct Wait. 2025-06-26T18:25:43.983Z,1750962343.983 [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 multiray_test { """ This mission is designed to run in the lab only. Cycles through MultiRay light modes """ arguments { MissionTimeout = 5 minute """ How long to let the mission run. """ LightDuration = 5 second """ How long to wait before changing light modes """ LightMode0 = 0 count """ Light mode = 0 """ LightMode1 = 1 count """ Light mode = 1 """ LightMode2 = 2 count """ Light mode = 2 """ EnabledMultiray = Sensor:MultiRay.loadAtStartup """ Automatically set to true if the Multiray is enabled. """ } timeout duration=MissionTimeout readData strategy="MinError" { while ( EnabledMultiray ) Sensor:MultiRay.lightModeLog } # # # assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode1 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode2 behavior Guidance:Wait { run in sequence set duration = LightDuration } assign in sequence Sensor:MultiRay.lightModeCommand = LightMode0 behavior Guidance:Wait { run in sequence set duration = LightDuration } } 2025-06-26T18:25:43.985Z,1750962343.985 [CommandExec](IMPORTANT): Running ./Missions/Engineering/multiray_test.tl 2025-06-26T18:25:44.263Z,1750962344.263 [Default] Stopped 2025-06-26T18:25:44.263Z,1750962344.263 [Default](DEBUG): Aggregate::uninitialize Default 2025-06-26T18:25:44.263Z,1750962344.263 [Default:B.GoToSurface] Stopped 2025-06-26T18:25:44.263Z,1750962344.263 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-26T18:25:44.263Z,1750962344.263 [Default:CheckIn] Stopped 2025-06-26T18:25:44.263Z,1750962344.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T18:25:44.264Z,1750962344.264 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T18:25:44.264Z,1750962344.264 [MissionManager](IMPORTANT): Started mission multiray_test 2025-06-26T18:25:44.264Z,1750962344.264 [multiray_test] Running Loop=1 2025-06-26T18:25:44.264Z,1750962344.264 [multiray_test](DEBUG): Aggregate::initialize multiray_test 2025-06-26T18:25:44.264Z,1750962344.264 [multiray_test:A] Running Loop=1 2025-06-26T18:25:44.264Z,1750962344.264 [multiray_test:B] Running Loop=1 2025-06-26T18:25:44.265Z,1750962344.265 [multiray_test:B] Stopped 2025-06-26T18:25:44.265Z,1750962344.265 [multiray_test:C.Wait] Running Loop=1 2025-06-26T18:25:44.265Z,1750962344.265 [multiray_test:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:25:44.265Z,1750962344.265 [multiray_test:A] Running Loop=1 2025-06-26T18:25:44.266Z,1750962344.266 [multiray_test:A](DEBUG): Initialize ReadDataComponent to sense MultiRay.lightModeLog 2025-06-26T18:25:45.852Z,1750962345.852 [MultiRay](IMPORTANT): MultiRay all lights OFF 2025-06-26T18:25:46.255Z,1750962346.255 [MultiRay](INFO): Powering down 2025-06-26T18:25:46.375Z,1750962346.375 [MultiRay](INFO): Powering down LCB2 2025-06-26T18:25:49.978Z,1750962349.978 [multiray_test:C.Wait](INFO): Done Waiting. 2025-06-26T18:25:49.978Z,1750962349.978 [multiray_test:C.Wait] Stopped 2025-06-26T18:25:49.978Z,1750962349.978 [multiray_test:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:25:49.979Z,1750962349.979 [multiray_test:D] Running Loop=1 2025-06-26T18:25:50.353Z,1750962350.353 [multiray_test:D] Stopped 2025-06-26T18:25:50.354Z,1750962350.354 [multiray_test:E.Wait] Running Loop=1 2025-06-26T18:25:50.354Z,1750962350.354 [multiray_test:E.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:25:52.728Z,1750962352.728 [MultiRay](IMPORTANT): Multiray red lights ON 2025-06-26T18:25:55.976Z,1750962355.976 [multiray_test:E.Wait](INFO): Done Waiting. 2025-06-26T18:25:55.976Z,1750962355.976 [multiray_test:E.Wait] Stopped 2025-06-26T18:25:55.976Z,1750962355.976 [multiray_test:E.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:25:55.976Z,1750962355.976 [multiray_test:F] Running Loop=1 2025-06-26T18:25:56.372Z,1750962356.372 [multiray_test:F] Stopped 2025-06-26T18:25:56.373Z,1750962356.373 [multiray_test:G.Wait] Running Loop=1 2025-06-26T18:25:56.373Z,1750962356.373 [multiray_test:G.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:25:57.976Z,1750962357.976 [MultiRay](IMPORTANT): MultiRay white lights ON 2025-06-26T18:26:02.039Z,1750962362.039 [multiray_test:G.Wait](INFO): Done Waiting. 2025-06-26T18:26:02.040Z,1750962362.040 [multiray_test:G.Wait] Stopped 2025-06-26T18:26:02.040Z,1750962362.040 [multiray_test:G.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:26:02.040Z,1750962362.040 [multiray_test:H] Running Loop=1 2025-06-26T18:26:02.440Z,1750962362.440 [multiray_test:H] Stopped 2025-06-26T18:26:02.440Z,1750962362.440 [multiray_test:I.Wait] Running Loop=1 2025-06-26T18:26:02.440Z,1750962362.440 [multiray_test:I.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:26:03.234Z,1750962363.234 [MultiRay](IMPORTANT): MultiRay all lights OFF 2025-06-26T18:26:03.717Z,1750962363.717 [MultiRay](INFO): Powering down 2025-06-26T18:26:03.795Z,1750962363.795 [CommandExec](IMPORTANT): got command stop 2025-06-26T18:26:03.795Z,1750962363.795 [CommandExec](IMPORTANT): Scheduling is paused 2025-06-26T18:26:03.795Z,1750962363.795 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-06-26T18:26:03.841Z,1750962363.841 [MultiRay](INFO): Powering down LCB2 2025-06-26T18:26:03.985Z,1750962363.985 [MissionManager](INFO): MissionManager is completed. 2025-06-26T18:26:03.985Z,1750962363.985 [MissionManager](INFO): Uninitializing Mission multiray_test 2025-06-26T18:26:03.985Z,1750962363.985 [multiray_test] Stopped 2025-06-26T18:26:03.985Z,1750962363.985 [multiray_test](DEBUG): Aggregate::uninitialize multiray_test 2025-06-26T18:26:03.985Z,1750962363.985 [multiray_test:A] Stopped 2025-06-26T18:26:03.985Z,1750962363.985 [multiray_test:I.Wait] Stopped 2025-06-26T18:26:03.985Z,1750962363.985 [multiray_test:I.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:26:04.167Z,1750962364.167 [MissionManager](IMPORTANT): Started mission Default 2025-06-26T18:26:04.167Z,1750962364.167 [Default] Running Loop=1 2025-06-26T18:26:04.167Z,1750962364.167 [Default](DEBUG): Aggregate::initialize Default 2025-06-26T18:26:04.167Z,1750962364.167 [Default:B.GoToSurface] Running Loop=1 2025-06-26T18:26:04.167Z,1750962364.167 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-26T18:26:04.168Z,1750962364.168 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-26T18:26:04.168Z,1750962364.168 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-26T18:26:04.168Z,1750962364.168 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-26T18:26:04.168Z,1750962364.168 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-26T18:26:04.169Z,1750962364.169 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-26T18:26:04.169Z,1750962364.169 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-26T18:26:04.169Z,1750962364.169 [Default:A.Wait] Running Loop=1 2025-06-26T18:26:04.169Z,1750962364.169 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:26:17.264Z,1750962377.264 [Default:A.Wait](INFO): Done Waiting. 2025-06-26T18:26:17.264Z,1750962377.264 [Default:A.Wait] Stopped 2025-06-26T18:26:17.264Z,1750962377.264 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:26:17.443Z,1750962377.443 [Default:CheckIn] Running Loop=1 2025-06-26T18:26:17.443Z,1750962377.443 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T18:26:17.443Z,1750962377.443 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T18:26:19.736Z,1750962379.736 [CommandExec](IMPORTANT): got command schedule resume 2025-06-26T18:26:19.736Z,1750962379.736 [CommandExec](IMPORTANT): Scheduling is resumed 2025-06-26T18:26:25.462Z,1750962385.462 [CommandExec](IMPORTANT): got command schedule clear 2025-06-26T18:26:25.462Z,1750962385.462 [CommandExec](IMPORTANT): Cleared 0 scheduled commands. 2025-06-26T18:26:28.476Z,1750962388.476 [CommandExec](IMPORTANT): got command schedule resume 2025-06-26T18:26:29.839Z,1750962389.839 [CommandExec](IMPORTANT): got command failComponent 2025-06-26T18:26:29.839Z,1750962389.839 [CommandExec](IMPORTANT): Failed components: 2025-06-26T18:26:29.839Z,1750962389.839 [CommandExec](IMPORTANT): No failed Components. 2025-06-26T18:27:25.299Z,1750962445.299 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-06-26T18:27:25.323Z,1750962445.323 [BPC1](INFO): Received data from all battery sticks. 2025-06-26T18:28:13.807Z,1750962493.807 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-06-26T18:28:13.807Z,1750962493.807 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-06-26T18:28:13.818Z,1750962493.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-06-26T18:28:14.209Z,1750962494.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-06-26T18:28:14.209Z,1750962494.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-06-26T18:29:51.604Z,1750962591.604 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-06-26T18:29:51.604Z,1750962591.604 [NAL9602] Data Fault, FailCount= 1 2025-06-26T18:29:51.604Z,1750962591.604 [NAL9602](ERROR): Data Fault 2025-06-26T18:29:51.712Z,1750962591.712 [CBIT](ERROR): Data Fault in component: NAL9602 2025-06-26T18:29:52.012Z,1750962592.012 [NAL9602](INFO): Powering down 2025-06-26T18:29:52.913Z,1750962592.913 [CBIT](INFO): Clearing failed state for component NAL9602 2025-06-26T18:29:52.913Z,1750962592.913 [NAL9602] No Fault, FailCount= 1 2025-06-26T18:30:22.300Z,1750962622.300 [NAL9602](INFO): Powering up NAL9602 2025-06-26T18:30:33.208Z,1750962633.208 [NAL9602](INFO): NAL9602 initialized 2025-06-26T18:31:14.827Z,1750962674.827 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-06-26T18:31:14.827Z,1750962674.827 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-06-26T18:31:14.837Z,1750962674.837 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-06-26T18:31:15.257Z,1750962675.257 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-06-26T18:31:15.257Z,1750962675.257 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-06-26T18:31:17.660Z,1750962677.660 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-26T18:26:17.4Z 2025-06-26T18:31:17.660Z,1750962677.660 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T18:31:17.660Z,1750962677.660 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T18:31:18.055Z,1750962678.055 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-26T18:31:24.623Z,1750962684.623 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250626T181900/Courier0004.lzma 2025-06-26T18:31:35.680Z,1750962695.680 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0004.lzma.bak 2025-06-26T18:31:35.680Z,1750962695.680 [DataOverHttps](INFO): SBD MOMSN=25248727 2025-06-26T18:32:01.967Z,1750962721.967 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250624T192950/Express0145.lzma 2025-06-26T18:32:04.113Z,1750962724.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183202.00,A,3648.15571,N,12147.22550,W,0.097,0.00,260625,,,A*72 2025-06-26T18:32:04.116Z,1750962724.116 [NAL9602](INFO): GPS fix at 20250626T183202: (36.802595, -121.787092) 2025-06-26T18:32:13.013Z,1750962733.013 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0145.lzma.bak 2025-06-26T18:32:13.013Z,1750962733.013 [DataOverHttps](INFO): SBD MOMSN=25248735 2025-06-26T18:32:31.412Z,1750962751.412 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T183230 2025-06-26T18:32:36.443Z,1750962756.443 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T18:32:38.840Z,1750962758.840 [DataOverHttps](INFO): Sending 941 bytes from file Logs/20250626T181451/Express0001.lzma 2025-06-26T18:32:38.844Z,1750962758.844 [DataOverHttps](INFO): Received command: failc 2025-06-26T18:32:38.928Z,1750962758.928 [CommandExec](IMPORTANT): got command failComponent 2025-06-26T18:32:38.928Z,1750962758.928 [CommandExec](IMPORTANT): Failed components: 2025-06-26T18:32:38.928Z,1750962758.928 [CommandExec](IMPORTANT): No failed Components. 2025-06-26T18:32:49.881Z,1750962769.881 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181451/Express0001.lzma.bak 2025-06-26T18:32:49.881Z,1750962769.881 [DataOverHttps](IMPORTANT): SBD MOMSN=25248741, MTMSN=20250626T183248 2025-06-26T18:32:57.210Z,1750962777.210 [DataOverHttps](INFO): Received command: gfscan 2025-06-26T18:32:57.287Z,1750962777.287 [CommandExec](IMPORTANT): got command gfscan 2025-06-26T18:32:57.465Z,1750962777.465 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-26T18:33:01.911Z,1750962781.911 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006540 CHAN A1 (24V): 0.001152 CHAN A2 (12V): -0.005531 CHAN A3 (5V): -0.002767 CHAN B0 (3.3V): -0.001164 CHAN B1 (3.15aV): -0.001338 CHAN B2 (3.15bV): -0.001482 CHAN B3 (GND): -0.000742 OPEN: 0.005024 Full Scale: +/- 1 mA 2025-06-26T18:33:08.252Z,1750962788.252 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T183307 2025-06-26T18:33:15.686Z,1750962795.686 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20250626T181900/Courier0010.lzma 2025-06-26T18:33:15.689Z,1750962795.689 [DataOverHttps](INFO): Received command: ibit 2025-06-26T18:33:15.751Z,1750962795.751 [CommandExec](IMPORTANT): got command ibit 2025-06-26T18:33:16.045Z,1750962796.045 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-06-26T18:33:16.045Z,1750962796.045 [IBIT](IMPORTANT): Beginning control surface checks. 2025-06-26T18:33:16.049Z,1750962796.049 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-26T18:33:17.707Z,1750962797.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183315.00,A,3648.15515,N,12147.22511,W,0.097,0.00,260625,,,A*72 2025-06-26T18:33:17.709Z,1750962797.709 [NAL9602](INFO): GPS fix at 20250626T183315: (36.802586, -121.787085) 2025-06-26T18:33:20.663Z,1750962800.663 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007170 CHAN A1 (24V): 0.001101 CHAN A2 (12V): -0.005363 CHAN A3 (5V): -0.002861 CHAN B0 (3.3V): -0.001346 CHAN B1 (3.15aV): -0.000996 CHAN B2 (3.15bV): -0.001694 CHAN B3 (GND): -0.000150 OPEN: 0.004714 Full Scale: +/- 1 mA 2025-06-26T18:33:26.736Z,1750962806.736 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0010.lzma.bak 2025-06-26T18:33:26.736Z,1750962806.736 [DataOverHttps](INFO): SBD MOMSN=25248776 2025-06-26T18:33:54.858Z,1750962834.858 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250626T181900/Courier0013.lzma 2025-06-26T18:34:05.900Z,1750962845.900 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0013.lzma.bak 2025-06-26T18:34:05.900Z,1750962845.900 [DataOverHttps](INFO): SBD MOMSN=25248780 2025-06-26T18:34:31.698Z,1750962871.698 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250626T181900/Courier0016.lzma 2025-06-26T18:34:42.740Z,1750962882.740 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0016.lzma.bak 2025-06-26T18:34:42.740Z,1750962882.740 [DataOverHttps](INFO): SBD MOMSN=25248782 2025-06-26T18:35:07.200Z,1750962907.200 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2025-06-26T18:35:07.579Z,1750962907.579 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.690001 2025-06-26T18:35:07.580Z,1750962907.580 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2025-06-26T18:35:07.580Z,1750962907.580 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2025-06-26T18:35:08.024Z,1750962908.024 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-06-26T18:35:08.024Z,1750962908.024 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-06-26T18:35:08.024Z,1750962908.024 [IBIT](IMPORTANT): Pressure:9.877444 PSI 2025-06-26T18:35:08.025Z,1750962908.025 [IBIT](IMPORTANT): Humidity:11.817849 % 2025-06-26T18:35:08.393Z,1750962908.393 [IBIT](IMPORTANT): Vehicle Pitch:-0.224188 degrees 2025-06-26T18:35:08.393Z,1750962908.393 [IBIT](IMPORTANT): Vehicle Roll:-1.147859 degrees 2025-06-26T18:35:08.394Z,1750962908.394 [IBIT](IMPORTANT): Vehicle Heading:191.299713 degrees 2025-06-26T18:35:08.516Z,1750962908.516 [DataOverHttps](INFO): Sending 902 bytes from file Logs/20250626T181900/Express0001.lzma 2025-06-26T18:35:08.808Z,1750962908.808 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-06-26T18:35:08.808Z,1750962908.808 [IBIT](IMPORTANT): buoyancyNeutral: 215.486557 cc 2025-06-26T18:35:08.808Z,1750962908.808 [IBIT](IMPORTANT): massDefault: 2.532250 cm 2025-06-26T18:35:08.809Z,1750962908.809 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2025-06-26T18:35:08.809Z,1750962908.809 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-06-26T18:35:08.809Z,1750962908.809 [IBIT](IMPORTANT): IBIT FAILED 2025-06-26T18:35:19.556Z,1750962919.556 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0001.lzma.bak 2025-06-26T18:35:19.556Z,1750962919.556 [DataOverHttps](INFO): SBD MOMSN=25248784 2025-06-26T18:35:48.359Z,1750962948.359 [DataOverHttps](INFO): Sending 539 bytes from file Logs/20250626T181900/Express0005.lzma 2025-06-26T18:35:59.400Z,1750962959.400 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0005.lzma.bak 2025-06-26T18:35:59.400Z,1750962959.400 [DataOverHttps](INFO): SBD MOMSN=25248809 2025-06-26T18:36:25.282Z,1750962985.282 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250626T181900/Express0008.lzma 2025-06-26T18:36:36.324Z,1750962996.324 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0008.lzma.bak 2025-06-26T18:36:36.324Z,1750962996.324 [DataOverHttps](INFO): SBD MOMSN=25248833 2025-06-26T18:36:50.223Z,1750963010.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-26T18:36:50.223Z,1750963010.223 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:37:02.147Z,1750963022.147 [DataOverHttps](INFO): Sending 370 bytes from file Logs/20250626T181900/Express0011.lzma 2025-06-26T18:37:13.188Z,1750963033.188 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0011.lzma.bak 2025-06-26T18:37:13.188Z,1750963033.188 [DataOverHttps](INFO): SBD MOMSN=25248841 2025-06-26T18:37:35.465Z,1750963055.465 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-26T18:37:35.465Z,1750963055.465 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:37:39.126Z,1750963059.126 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250626T181900/Express0014.lzma 2025-06-26T18:37:50.168Z,1750963070.168 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0014.lzma.bak 2025-06-26T18:37:50.168Z,1750963070.168 [DataOverHttps](INFO): SBD MOMSN=25248847 2025-06-26T18:38:16.127Z,1750963096.127 [DataOverHttps](INFO): Sending 373 bytes from file Logs/20250626T181900/Express0017.lzma 2025-06-26T18:38:20.304Z,1750963100.304 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-26T18:38:27.168Z,1750963107.168 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0017.lzma.bak 2025-06-26T18:38:27.168Z,1750963107.168 [DataOverHttps](INFO): SBD MOMSN=25248852 2025-06-26T18:38:28.408Z,1750963108.408 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T18:38:28.408Z,1750963108.408 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T18:38:28.408Z,1750963108.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:38:51.011Z,1750963131.011 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T18:43:29.012Z,1750963409.012 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T18:43:29.012Z,1750963409.012 [Default:CheckIn:C.Wait] Stopped 2025-06-26T18:43:29.013Z,1750963409.013 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:43:29.013Z,1750963409.013 [Default:CheckIn:D] Running Loop=1 2025-06-26T18:43:29.421Z,1750963409.421 [Default:CheckIn:D] Stopped 2025-06-26T18:43:29.421Z,1750963409.421 [Default:CheckIn:E] Running Loop=1 2025-06-26T18:43:29.821Z,1750963409.821 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.420903 min 2025-06-26T18:43:29.821Z,1750963409.821 [Default:CheckIn:E] Stopped 2025-06-26T18:43:29.821Z,1750963409.821 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T18:43:29.821Z,1750963409.821 [Default:CheckIn] Stopped 2025-06-26T18:43:29.821Z,1750963409.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T18:43:29.821Z,1750963409.821 [Default:CheckIn](INFO): Running loop #2 2025-06-26T18:43:29.821Z,1750963409.821 [Default:CheckIn] Running Loop=2 2025-06-26T18:43:29.822Z,1750963409.822 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T18:43:29.822Z,1750963409.822 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T18:43:31.833Z,1750963411.833 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184330.00,A,3648.15431,N,12147.22484,W,0.078,0.00,260625,,,A*79 2025-06-26T18:43:31.836Z,1750963411.836 [NAL9602](INFO): GPS fix at 20250626T184330: (36.802572, -121.787081) 2025-06-26T18:43:31.845Z,1750963411.845 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T18:43:31.845Z,1750963411.845 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T18:43:39.574Z,1750963419.574 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0019.lzma 2025-06-26T18:43:50.636Z,1750963430.636 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0019.lzma.bak 2025-06-26T18:43:50.636Z,1750963430.636 [DataOverHttps](INFO): SBD MOMSN=25248867 2025-06-26T18:44:02.545Z,1750963442.545 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-26T18:44:02.626Z,1750963442.626 [NAL9602](ERROR): received: +CSQ:0 OK734, 2, 0, 0, 0 OK 2025-06-26T18:44:16.583Z,1750963456.583 [DataOverHttps](INFO): Sending 786 bytes from file Logs/20250626T181900/Express0020.lzma 2025-06-26T18:44:27.624Z,1750963467.624 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0020.lzma.bak 2025-06-26T18:44:27.624Z,1750963467.624 [DataOverHttps](INFO): SBD MOMSN=25248870 2025-06-26T18:44:28.812Z,1750963468.812 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T18:44:28.813Z,1750963468.813 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T18:44:28.813Z,1750963468.813 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T18:45:41.518Z,1750963541.518 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-26T18:45:41.518Z,1750963541.518 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:46:18.333Z,1750963578.333 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-26T18:46:18.333Z,1750963578.333 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:46:44.191Z,1750963604.191 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-26T18:46:44.191Z,1750963604.191 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:47:31.862Z,1750963651.862 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-26T18:47:31.878Z,1750963651.878 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:47:43.689Z,1750963663.689 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T184742 2025-06-26T18:47:53.886Z,1750963673.886 [DataOverHttps](INFO): Received command: report touch NAL9602.sigQuality 2025-06-26T18:47:55.107Z,1750963675.107 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality 2025-06-26T18:47:59.424Z,1750963679.424 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:07.917Z,1750963687.917 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:09.525Z,1750963689.525 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:18.008Z,1750963698.008 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:20.424Z,1750963700.424 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:24.904Z,1750963704.904 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:28.909Z,1750963708.909 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:34.525Z,1750963714.525 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-26T18:49:05.228Z,1750963745.228 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T18:49:05.251Z,1750963745.251 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:49:13.336Z,1750963753.336 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T184912 2025-06-26T18:49:20.738Z,1750963760.738 [DataOverHttps](INFO): Received command: ! echo 0 > /dev/loadB6 2025-06-26T18:49:20.808Z,1750963760.808 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadB6 2025-06-26T18:49:29.477Z,1750963769.477 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T18:49:29.478Z,1750963769.478 [Default:CheckIn:C.Wait] Stopped 2025-06-26T18:49:29.478Z,1750963769.478 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T18:49:29.478Z,1750963769.478 [Default:CheckIn:D] Running Loop=1 2025-06-26T18:49:29.891Z,1750963769.891 [Default:CheckIn:D] Stopped 2025-06-26T18:49:29.891Z,1750963769.891 [Default:CheckIn:E] Running Loop=1 2025-06-26T18:49:30.299Z,1750963770.299 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.428729 min 2025-06-26T18:49:30.299Z,1750963770.299 [Default:CheckIn:E] Stopped 2025-06-26T18:49:30.299Z,1750963770.299 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T18:49:30.299Z,1750963770.299 [Default:CheckIn] Stopped 2025-06-26T18:49:30.299Z,1750963770.299 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T18:49:30.300Z,1750963770.300 [Default:CheckIn](INFO): Running loop #3 2025-06-26T18:49:30.300Z,1750963770.300 [Default:CheckIn] Running Loop=3 2025-06-26T18:49:30.300Z,1750963770.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T18:49:30.300Z,1750963770.300 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T18:49:32.302Z,1750963772.302 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184931.00,A,3648.15398,N,12147.22418,W,0.097,0.00,260625,,,A*72 2025-06-26T18:49:32.304Z,1750963772.304 [NAL9602](INFO): GPS fix at 20250626T184931: (36.802566, -121.787070) 2025-06-26T18:49:32.338Z,1750963772.338 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T18:49:32.338Z,1750963772.338 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T18:49:44.835Z,1750963784.835 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-06-26T18:50:03.004Z,1750963803.004 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-26T18:50:03.082Z,1750963803.082 [NAL9602](ERROR): received: +CSQ:0 OK734, 2, 0, 0, 0 OK 2025-06-26T18:50:09.505Z,1750963809.505 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:50:11.537Z,1750963811.537 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:50:13.963Z,1750963813.963 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.627346 2025-06-26T18:50:18.004Z,1750963818.004 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:20.436Z,1750963820.436 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:26.877Z,1750963826.877 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:30.928Z,1750963830.928 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:35.364Z,1750963835.364 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:37.384Z,1750963837.384 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:43.852Z,1750963843.852 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:45.087Z,1750963845.087 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:50:47.496Z,1750963847.496 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:51.524Z,1750963851.524 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:54.756Z,1750963854.756 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:51:02.840Z,1750963862.840 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:51:11.328Z,1750963871.328 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:51:20.208Z,1750963880.208 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:51:20.227Z,1750963880.227 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:51:28.688Z,1750963888.688 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:51:29.900Z,1750963889.900 [Reporter](INFO): NAL9602.sigQuality 4 count 2025-06-26T18:51:41.985Z,1750963901.985 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19734, MT Status=1, MTMSN=1217 2025-06-26T18:51:41.985Z,1750963901.985 [NAL9602](INFO): Data available in MT queue 2025-06-26T18:51:42.479Z,1750963902.479 [NAL9602](INFO): Received command: ibit 2025-06-26T18:51:42.523Z,1750963902.523 [CommandExec](IMPORTANT): got command ibit 2025-06-26T18:51:42.812Z,1750963902.812 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-06-26T18:51:42.812Z,1750963902.812 [IBIT](IMPORTANT): Beginning control surface checks. 2025-06-26T18:51:42.816Z,1750963902.816 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-26T18:51:44.004Z,1750963904.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185143.00,A,3648.15294,N,12147.22410,W,0.330,0.00,260625,,,A*75 2025-06-26T18:51:44.006Z,1750963904.006 [NAL9602](INFO): GPS fix at 20250626T185143: (36.802549, -121.787068) 2025-06-26T18:51:46.623Z,1750963906.623 [Reporter](INFO): NAL9602.sigQuality 4 count 2025-06-26T18:51:49.546Z,1750963909.546 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007301 CHAN A1 (24V): 0.000710 CHAN A2 (12V): -0.005331 CHAN A3 (5V): -0.002580 CHAN B0 (3.3V): -0.001363 CHAN B1 (3.15aV): -0.001043 CHAN B2 (3.15bV): -0.001344 CHAN B3 (GND): -0.000341 OPEN: 0.004544 Full Scale: +/- 1 mA 2025-06-26T18:51:55.366Z,1750963915.366 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:52:09.377Z,1750963929.377 [NAL9602](INFO): SBD MO Status=1, MOMSN=19735, MT Status=0, MTMSN=0 2025-06-26T18:52:09.433Z,1750963929.433 [NAL9602](INFO): Sent 74 bytes from file Logs/20250626T181900/Courier0022.lzma 2025-06-26T18:52:09.433Z,1750963929.433 [NAL9602](INFO): Packets left to send: 0 2025-06-26T18:52:22.085Z,1750963942.085 [NAL9602](INFO): SBD MO Status=1, MOMSN=19736, MT Status=0, MTMSN=0 2025-06-26T18:52:22.138Z,1750963942.138 [NAL9602](INFO): Sent 332 bytes from file Logs/20250626T181900/Express0023.lzma 2025-06-26T18:52:22.139Z,1750963942.139 [NAL9602](INFO): Packets left to send: 1 2025-06-26T18:52:29.031Z,1750963949.031 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 4 Latitude: 36.802547 Longitude: -121.787071 2025-06-26T18:52:29.417Z,1750963949.417 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.690001 2025-06-26T18:52:29.417Z,1750963949.417 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2025-06-26T18:52:29.417Z,1750963949.417 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2025-06-26T18:52:29.836Z,1750963949.836 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-06-26T18:52:29.836Z,1750963949.836 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-06-26T18:52:29.836Z,1750963949.836 [IBIT](IMPORTANT): Pressure:9.885340 PSI 2025-06-26T18:52:29.837Z,1750963949.837 [IBIT](IMPORTANT): Humidity:11.530949 % 2025-06-26T18:52:30.247Z,1750963950.247 [IBIT](IMPORTANT): Vehicle Pitch:-0.231994 degrees 2025-06-26T18:52:30.247Z,1750963950.247 [IBIT](IMPORTANT): Vehicle Roll:-1.165159 degrees 2025-06-26T18:52:30.247Z,1750963950.247 [IBIT](IMPORTANT): Vehicle Heading:191.323624 degrees 2025-06-26T18:52:30.527Z,1750963950.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:52:30.628Z,1750963950.628 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-06-26T18:52:30.629Z,1750963950.629 [IBIT](IMPORTANT): buoyancyNeutral: 215.486557 cc 2025-06-26T18:52:30.629Z,1750963950.629 [IBIT](IMPORTANT): massDefault: 2.532250 cm 2025-06-26T18:52:30.629Z,1750963950.629 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2025-06-26T18:52:30.629Z,1750963950.629 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-06-26T18:52:30.630Z,1750963950.630 [IBIT](IMPORTANT): IBIT PASSED 2025-06-26T18:52:40.311Z,1750963960.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=19737, MT Status=2, MTMSN=0 2025-06-26T18:52:40.312Z,1750963960.312 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:52:44.812Z,1750963964.812 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:52:54.892Z,1750963974.892 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:53:05.666Z,1750963985.666 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:53:09.849Z,1750963989.849 [NAL9602](INFO): SBD MO Status=2, MOMSN=19737, MT Status=2, MTMSN=0 2025-06-26T18:53:09.849Z,1750963989.849 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:53:14.329Z,1750963994.329 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:21.994Z,1750964001.994 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:32.113Z,1750964012.113 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:53:40.808Z,1750964020.808 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:53:46.254Z,1750964026.254 [NAL9602](INFO): SBD MO Status=2, MOMSN=19737, MT Status=2, MTMSN=0 2025-06-26T18:53:46.255Z,1750964026.255 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:53:50.736Z,1750964030.736 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:52.760Z,1750964032.760 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:57.213Z,1750964037.213 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:59.228Z,1750964039.228 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:54:03.260Z,1750964043.260 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:54:10.944Z,1750964050.944 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:54:15.958Z,1750964055.958 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:54:30.738Z,1750964070.738 [NAL9602](INFO): SBD MO Status=2, MOMSN=19737, MT Status=2, MTMSN=0 2025-06-26T18:54:30.739Z,1750964070.739 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:54:35.226Z,1750964075.226 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:54:38.476Z,1750964078.476 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:54:51.099Z,1750964091.099 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:54:56.641Z,1750964096.641 [NAL9602](INFO): SBD MO Status=2, MOMSN=19737, MT Status=2, MTMSN=0 2025-06-26T18:54:56.641Z,1750964096.641 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:55:01.136Z,1750964101.136 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:55:11.244Z,1750964111.244 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T18:55:26.239Z,1750964126.239 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:55:31.446Z,1750964131.446 [NAL9602](INFO): SBD MO Status=2, MOMSN=19737, MT Status=2, MTMSN=0 2025-06-26T18:55:31.446Z,1750964131.446 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:55:35.525Z,1750964135.525 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:55:38.760Z,1750964138.760 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:55:50.477Z,1750964150.477 [NAL9602](INFO): SBD MO Status=1, MOMSN=19737, MT Status=0, MTMSN=0 2025-06-26T18:55:50.543Z,1750964150.543 [NAL9602](INFO): Sent 7 bytes from file Logs/20250626T181900/Express0023.lzma 2025-06-26T18:55:50.543Z,1750964150.543 [NAL9602](INFO): Packets left to send: 0 2025-06-26T18:56:01.379Z,1750964161.379 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:56:05.832Z,1750964165.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=19738, MT Status=2, MTMSN=0 2025-06-26T18:56:05.832Z,1750964165.832 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:56:10.325Z,1750964170.325 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T18:56:22.797Z,1750964182.797 [NAL9602](INFO): SBD MO Status=2, MOMSN=19738, MT Status=2, MTMSN=0 2025-06-26T18:56:22.797Z,1750964182.797 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:56:27.285Z,1750964187.285 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:27.970Z,1750964187.970 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:56:30.520Z,1750964190.520 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:30.943Z,1750964190.943 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:56:36.519Z,1750964196.519 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:56:38.994Z,1750964198.994 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:39.439Z,1750964199.439 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:56:46.277Z,1750964206.277 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:46.708Z,1750964206.708 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:56:55.161Z,1750964215.161 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:55.592Z,1750964215.592 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:57:00.820Z,1750964220.820 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:01.250Z,1750964221.250 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:57:05.264Z,1750964225.264 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:05.693Z,1750964225.693 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:57:11.675Z,1750964231.675 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:57:13.750Z,1750964233.750 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:14.179Z,1750964234.179 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:57:22.229Z,1750964242.229 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:22.660Z,1750964242.660 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:57:31.111Z,1750964251.111 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:31.553Z,1750964251.553 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T18:57:39.608Z,1750964259.608 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T18:57:46.827Z,1750964266.827 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:57:51.685Z,1750964271.685 [NAL9602](INFO): SBD MO Status=2, MOMSN=19738, MT Status=2, MTMSN=0 2025-06-26T18:57:51.685Z,1750964271.685 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:57:55.756Z,1750964275.756 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T18:58:00.969Z,1750964280.969 [NAL9602](INFO): SBD MO Status=0, MOMSN=19738, MT Status=0, MTMSN=0 2025-06-26T18:58:05.454Z,1750964285.454 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T18:58:21.970Z,1750964301.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:58:22.043Z,1750964302.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=19739, MT Status=2, MTMSN=0 2025-06-26T18:58:22.043Z,1750964302.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:58:26.541Z,1750964306.541 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T18:58:32.597Z,1750964312.597 [NAL9602](INFO): SBD MO Status=1, MOMSN=19739, MT Status=0, MTMSN=0 2025-06-26T18:58:32.650Z,1750964312.650 [NAL9602](INFO): Sent 75 bytes from file Logs/20250626T181900/Courier0025.lzma 2025-06-26T18:58:32.651Z,1750964312.651 [NAL9602](INFO): Packets left to send: 0 2025-06-26T18:58:43.961Z,1750964323.961 [NAL9602](INFO): SBD MO Status=1, MOMSN=19740, MT Status=0, MTMSN=0 2025-06-26T18:58:44.015Z,1750964324.015 [NAL9602](INFO): Sent 332 bytes from file Logs/20250626T181900/Express0027.lzma 2025-06-26T18:58:44.015Z,1750964324.015 [NAL9602](INFO): Packets left to send: 2 2025-06-26T18:58:57.111Z,1750964337.111 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:59:03.012Z,1750964343.012 [NAL9602](INFO): SBD MO Status=1, MOMSN=19741, MT Status=0, MTMSN=0 2025-06-26T18:59:03.074Z,1750964343.074 [NAL9602](INFO): Sent 332 bytes from file Logs/20250626T181900/Express0027.lzma 2025-06-26T18:59:03.075Z,1750964343.075 [NAL9602](INFO): Packets left to send: 1 2025-06-26T18:59:20.019Z,1750964360.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=19742, MT Status=2, MTMSN=0 2025-06-26T18:59:20.019Z,1750964360.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T18:59:24.524Z,1750964364.524 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:59:32.258Z,1750964372.258 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T18:59:38.252Z,1750964378.252 [NAL9602](INFO): SBD MO Status=1, MOMSN=19742, MT Status=0, MTMSN=0 2025-06-26T18:59:38.308Z,1750964378.308 [NAL9602](INFO): Sent 100 bytes from file Logs/20250626T181900/Express0027.lzma 2025-06-26T18:59:38.308Z,1750964378.308 [NAL9602](INFO): Packets left to send: 0 2025-06-26T18:59:47.542Z,1750964387.542 [NAL9602](INFO): SBD MO Status=0, MOMSN=19743, MT Status=0, MTMSN=0 2025-06-26T18:59:47.632Z,1750964387.632 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T18:59:47.633Z,1750964387.633 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T18:59:47.633Z,1750964387.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:00:07.399Z,1750964407.399 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:00:18.244Z,1750964418.244 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:00:18.265Z,1750964418.265 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:00:42.539Z,1750964442.539 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:01:17.703Z,1750964477.703 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:01:52.842Z,1750964512.842 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:02:27.982Z,1750964547.982 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:03:03.126Z,1750964583.126 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:03:38.266Z,1750964618.266 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:04:13.407Z,1750964653.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:04:48.134Z,1750964688.134 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T19:04:48.134Z,1750964688.134 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:04:48.134Z,1750964688.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:04:48.135Z,1750964688.135 [Default:CheckIn:D] Running Loop=1 2025-06-26T19:04:48.530Z,1750964688.530 [Default:CheckIn:D] Stopped 2025-06-26T19:04:48.531Z,1750964688.531 [Default:CheckIn:E] Running Loop=1 2025-06-26T19:04:48.563Z,1750964688.563 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:04:48.968Z,1750964688.968 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.739388 min 2025-06-26T19:04:48.968Z,1750964688.968 [Default:CheckIn:E] Stopped 2025-06-26T19:04:48.968Z,1750964688.968 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T19:04:48.968Z,1750964688.968 [Default:CheckIn] Stopped 2025-06-26T19:04:48.969Z,1750964688.969 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:04:48.969Z,1750964688.969 [Default:CheckIn](INFO): Running loop #4 2025-06-26T19:04:48.969Z,1750964688.969 [Default:CheckIn] Running Loop=4 2025-06-26T19:04:48.969Z,1750964688.969 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:04:48.969Z,1750964688.969 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:04:50.947Z,1750964690.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190450.00,A,3648.15228,N,12147.22343,W,0.214,0.00,260625,,,A*77 2025-06-26T19:04:50.949Z,1750964690.949 [NAL9602](INFO): GPS fix at 20250626T190450: (36.802538, -121.787057) 2025-06-26T19:04:50.959Z,1750964690.959 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:04:50.959Z,1750964690.959 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:04:58.677Z,1750964698.677 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T19:05:09.581Z,1750964709.581 [NAL9602](INFO): SBD MO Status=1, MOMSN=19744, MT Status=0, MTMSN=0 2025-06-26T19:05:09.647Z,1750964709.647 [NAL9602](INFO): Sent 74 bytes from file Logs/20250626T181900/Courier0031.lzma 2025-06-26T19:05:09.647Z,1750964709.647 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:05:19.333Z,1750964719.333 [NAL9602](INFO): SBD MO Status=1, MOMSN=19745, MT Status=0, MTMSN=0 2025-06-26T19:05:19.394Z,1750964719.394 [NAL9602](INFO): Sent 149 bytes from file Logs/20250626T181900/Express0032.lzma 2025-06-26T19:05:19.395Z,1750964719.395 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:05:23.710Z,1750964723.710 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:05:34.282Z,1750964734.282 [NAL9602](INFO): SBD MO Status=2, MOMSN=19746, MT Status=2, MTMSN=0 2025-06-26T19:05:34.282Z,1750964734.282 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:05:38.772Z,1750964738.772 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:05:39.203Z,1750964739.203 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T19:05:42.003Z,1750964742.003 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:05:42.437Z,1750964742.437 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-06-26T19:05:50.493Z,1750964750.493 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T19:05:58.850Z,1750964758.850 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:06:09.041Z,1750964769.041 [NAL9602](INFO): SBD MO Status=2, MOMSN=19746, MT Status=2, MTMSN=0 2025-06-26T19:06:09.041Z,1750964769.041 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:06:13.524Z,1750964773.524 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T19:06:18.729Z,1750964778.729 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19746, MT Status=1, MTMSN=1218 2025-06-26T19:06:19.271Z,1750964779.271 [NAL9602](INFO): Received command: ! grep -i nal9602.sigquality /mnt/mmc/LRAUV/Logs/latest/syslog 2025-06-26T19:06:19.287Z,1750964779.287 [CommandExec](IMPORTANT): got command ! grep -i nal9602.sigquality /mnt/mmc/LRAUV/Logs/latest/syslog 2025-06-26T19:06:19.808Z,1750964779.808 [CommandExec](IMPORTANT): 2025-06-26T18:47:53.886Z,1750963673.886 [DataOverHttps](INFO): Received command: report touch NAL9602.sigQuality 2025-06-26T18:47:55.107Z,1750963675.107 [CommandExec](IMPORTANT): got command report touch NAL9602.sigQuality 2025-06-26T18:47:59.424Z,1750963679.424 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:07.917Z,1750963687.917 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:09.525Z,1750963689.525 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:18.008Z,1750963698.008 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:20.424Z,1750963700.424 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:24.904Z,1750963704.904 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:48:28.909Z,1750963708.909 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:49:05.251Z,1750963745.251 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:09.505Z,1750963809.505 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:50:11.537Z,1750963811.537 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:50:18.004Z,1750963818.004 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:20.436Z,1750963820.436 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:26.877Z,1750963826.877 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:30.928Z,1750963830.928 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:35.364Z,1750963835.364 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:37.384Z,1750963837.384 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:43.852Z,1750963843.852 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:47.496Z,1750963847.496 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:51.524Z,1750963851.524 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:50:54.756Z,1750963854.756 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:51:02.840Z,1750963862.840 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:51:11.328Z,1750963871.328 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:51:20.208Z,1750963880.208 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:51:28.688Z,1750963888.688 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:51:29.900Z,1750963889.900 [Reporter](INFO): NAL9602.sigQuality 4 count 2025-06-26T18:51:46.623Z,1750963906.623 [Reporter](INFO): NAL9602.sigQuality 4 count 2025-06-26T18:52:44.812Z,1750963964.812 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:52:54.892Z,1750963974.892 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:53:14.329Z,1750963994.329 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:21.994Z,1750964001.994 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:32.113Z,1750964012.113 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:53:50.736Z,1750964030.736 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:52.760Z,1750964032.760 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:57.213Z,1750964037.213 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:53:59.228Z,1750964039.228 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:54:03.260Z,1750964043.260 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:54:10.944Z,1750964050.944 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:54:35.226Z,1750964075.226 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:54:38.476Z,1750964078.476 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:55:01.136Z,1750964101.136 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:55:11.244Z,1750964111.244 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T18:55:35.525Z,1750964135.525 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:55:38.760Z,1750964138.760 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T18:56:10.325Z,1750964170.325 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T18:56:27.285Z,1750964187.285 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:30.520Z,1750964190.520 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:38.994Z,1750964198.994 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:46.277Z,1750964206.277 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:56:55.161Z,1750964215.161 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:00.820Z,1750964220.820 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:05.264Z,1750964225.264 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:13.750Z,1750964233.750 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:22.229Z,1750964242.229 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:31.111Z,1750964251.111 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T18:57:39.608Z,1750964259.608 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T18:57:55.756Z,1750964275.756 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T18:58:05.454Z,1750964285.454 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T18:58:26.541Z,1750964306.541 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T18:59:24.524Z,1750964364.524 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T19:00:18.265Z,1750964418.265 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:04:58.677Z,1750964698.677 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T19:05:38.772Z,1750964738.772 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:05:42.003Z,1750964742.003 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:06:23.625Z,1750964783.625 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T19:06:27.610Z,1750964787.610 [NAL9602](INFO): SBD MO Status=0, MOMSN=19747, MT Status=0, MTMSN=0 2025-06-26T19:06:32.100Z,1750964792.100 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T19:06:33.990Z,1750964793.990 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:06:51.486Z,1750964811.486 [NAL9602](INFO): SBD MO Status=2, MOMSN=19748, MT Status=2, MTMSN=0 2025-06-26T19:06:51.487Z,1750964811.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:06:55.980Z,1750964815.980 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T19:07:09.130Z,1750964829.130 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:07:09.301Z,1750964829.301 [NAL9602](INFO): SBD MO Status=2, MOMSN=19748, MT Status=2, MTMSN=0 2025-06-26T19:07:09.301Z,1750964829.301 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:07:13.788Z,1750964833.788 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:07:17.019Z,1750964837.019 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:07:25.508Z,1750964845.508 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T19:07:38.030Z,1750964858.030 [NAL9602](INFO): SBD MO Status=1, MOMSN=19748, MT Status=0, MTMSN=0 2025-06-26T19:07:38.078Z,1750964858.078 [NAL9602](INFO): Sent 38 bytes from file Logs/20250626T181900/Courier0034.lzma 2025-06-26T19:07:38.079Z,1750964858.079 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:07:44.274Z,1750964864.274 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:07:59.889Z,1750964879.889 [NAL9602](INFO): SBD MO Status=1, MOMSN=19749, MT Status=0, MTMSN=0 2025-06-26T19:07:59.958Z,1750964879.958 [NAL9602](INFO): Sent 332 bytes from file Logs/20250626T181900/Express0035.lzma 2025-06-26T19:07:59.959Z,1750964879.959 [NAL9602](INFO): Packets left to send: 2 2025-06-26T19:08:19.414Z,1750964899.414 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:08:29.841Z,1750964909.841 [NAL9602](INFO): SBD MO Status=2, MOMSN=19750, MT Status=2, MTMSN=0 2025-06-26T19:08:29.841Z,1750964909.841 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:08:34.336Z,1750964914.336 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:08:37.565Z,1750964917.565 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:08:46.052Z,1750964926.052 [Reporter](INFO): NAL9602.sigQuality 4 count 2025-06-26T19:08:54.554Z,1750964934.554 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:09:02.193Z,1750964942.193 [NAL9602](INFO): SBD MO Status=1, MOMSN=19750, MT Status=0, MTMSN=0 2025-06-26T19:09:02.242Z,1750964942.242 [NAL9602](INFO): Sent 332 bytes from file Logs/20250626T181900/Express0035.lzma 2025-06-26T19:09:02.243Z,1750964942.243 [NAL9602](INFO): Packets left to send: 1 2025-06-26T19:09:14.359Z,1750964954.359 [NAL9602](INFO): SBD MO Status=1, MOMSN=19751, MT Status=0, MTMSN=0 2025-06-26T19:09:14.412Z,1750964954.412 [NAL9602](INFO): Sent 262 bytes from file Logs/20250626T181900/Express0035.lzma 2025-06-26T19:09:14.412Z,1750964954.412 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:09:23.652Z,1750964963.652 [NAL9602](INFO): SBD MO Status=0, MOMSN=19752, MT Status=0, MTMSN=0 2025-06-26T19:09:23.732Z,1750964963.732 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:09:23.732Z,1750964963.732 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:09:23.732Z,1750964963.732 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:09:29.695Z,1750964969.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:09:54.355Z,1750964994.355 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:09:54.377Z,1750964994.377 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:10:04.835Z,1750965004.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:10:39.978Z,1750965039.978 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:11:15.118Z,1750965075.118 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:11:50.262Z,1750965110.262 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:12:25.402Z,1750965145.402 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:13:00.558Z,1750965180.558 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:13:35.698Z,1750965215.698 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:14:10.854Z,1750965250.854 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:14:24.240Z,1750965264.240 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T19:14:24.241Z,1750965264.241 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:14:24.241Z,1750965264.241 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:14:24.241Z,1750965264.241 [Default:CheckIn:D] Running Loop=1 2025-06-26T19:14:24.648Z,1750965264.648 [Default:CheckIn:D] Stopped 2025-06-26T19:14:24.648Z,1750965264.648 [Default:CheckIn:E] Running Loop=1 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.341349 min 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn:E] Stopped 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn] Stopped 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn](INFO): Running loop #5 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn] Running Loop=5 2025-06-26T19:14:25.045Z,1750965265.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:14:25.046Z,1750965265.046 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:14:27.061Z,1750965267.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191426.00,A,3648.14867,N,12147.22133,W,0.175,337.66,260625,,,A*71 2025-06-26T19:14:27.064Z,1750965267.064 [NAL9602](INFO): GPS fix at 20250626T191426: (36.802478, -121.787022) 2025-06-26T19:14:27.073Z,1750965267.073 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:14:27.073Z,1750965267.073 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:14:37.229Z,1750965277.229 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:14:40.036Z,1750965280.036 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:14:44.513Z,1750965284.513 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:14:45.994Z,1750965285.994 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:14:50.524Z,1750965290.524 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:14:54.981Z,1750965294.981 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:15:03.075Z,1750965303.075 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T19:15:13.569Z,1750965313.569 [NAL9602](INFO): SBD MO Status=2, MOMSN=19753, MT Status=2, MTMSN=0 2025-06-26T19:15:13.569Z,1750965313.569 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:15:18.081Z,1750965318.081 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T19:15:21.134Z,1750965321.134 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:15:39.053Z,1750965339.053 [NAL9602](INFO): SBD MO Status=2, MOMSN=19753, MT Status=2, MTMSN=0 2025-06-26T19:15:39.053Z,1750965339.053 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:15:43.555Z,1750965343.555 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:15:47.592Z,1750965347.592 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T19:15:56.286Z,1750965356.286 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:16:06.169Z,1750965366.169 [NAL9602](INFO): SBD MO Status=2, MOMSN=19753, MT Status=2, MTMSN=0 2025-06-26T19:16:06.169Z,1750965366.169 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:16:10.660Z,1750965370.660 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:16:19.574Z,1750965379.574 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:16:21.962Z,1750965381.962 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:16:26.404Z,1750965386.404 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:16:31.442Z,1750965391.442 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:16:33.687Z,1750965393.687 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:16:40.960Z,1750965400.960 [Reporter](INFO): NAL9602.sigQuality 1 count 2025-06-26T19:17:01.967Z,1750965421.967 [NAL9602](INFO): SBD MO Status=2, MOMSN=19753, MT Status=2, MTMSN=0 2025-06-26T19:17:01.967Z,1750965421.967 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:17:06.440Z,1750965426.440 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:17:06.582Z,1750965426.582 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:17:15.744Z,1750965435.744 [Reporter](INFO): NAL9602.sigQuality 2 count 2025-06-26T19:17:36.352Z,1750965456.352 [NAL9602](INFO): SBD MO Status=2, MOMSN=19753, MT Status=2, MTMSN=0 2025-06-26T19:17:36.352Z,1750965456.352 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T19:17:40.857Z,1750965460.857 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T19:17:41.723Z,1750965461.723 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:18:01.854Z,1750965481.854 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=19753, MT Status=1, MTMSN=1219 2025-06-26T19:18:01.907Z,1750965481.907 [NAL9602](INFO): Sent 74 bytes from file Logs/20250626T181900/Courier0037.lzma 2025-06-26T19:18:01.907Z,1750965481.907 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:18:02.367Z,1750965482.367 [NAL9602](INFO): Received command: ! ! echo 1 > /dev/loadB6 2025-06-26T19:18:02.413Z,1750965482.413 [CommandExec](IMPORTANT): got command ! ! echo 1 > /dev/loadB6 2025-06-26T19:18:06.349Z,1750965486.349 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T19:18:16.863Z,1750965496.863 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:18:21.705Z,1750965501.705 [NAL9602](INFO): SBD MO Status=1, MOMSN=19754, MT Status=0, MTMSN=0 2025-06-26T19:18:21.758Z,1750965501.758 [NAL9602](INFO): Sent 149 bytes from file Logs/20250626T181900/Express0038.lzma 2025-06-26T19:18:21.759Z,1750965501.759 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:18:27.762Z,1750965507.762 [NAL9602](INFO): SBD MO Status=0, MOMSN=19755, MT Status=0, MTMSN=0 2025-06-26T19:18:32.253Z,1750965512.253 [Reporter](INFO): NAL9602.sigQuality 5 count 2025-06-26T19:18:47.608Z,1750965527.608 [NAL9602](INFO): SBD MO Status=1, MOMSN=19756, MT Status=0, MTMSN=0 2025-06-26T19:18:47.718Z,1750965527.718 [NAL9602](INFO): Sent 38 bytes from file Logs/20250626T181900/Courier0040.lzma 2025-06-26T19:18:47.719Z,1750965527.719 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:18:52.006Z,1750965532.006 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:19:01.781Z,1750965541.781 [NAL9602](INFO): SBD MO Status=1, MOMSN=19757, MT Status=0, MTMSN=0 2025-06-26T19:19:01.830Z,1750965541.830 [NAL9602](INFO): Sent 158 bytes from file Logs/20250626T181900/Express0041.lzma 2025-06-26T19:19:01.831Z,1750965541.831 [NAL9602](INFO): Packets left to send: 0 2025-06-26T19:19:12.289Z,1750965552.289 [NAL9602](INFO): SBD MO Status=0, MOMSN=19758, MT Status=0, MTMSN=0 2025-06-26T19:19:12.366Z,1750965552.366 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:19:12.366Z,1750965552.366 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:19:12.366Z,1750965552.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:19:27.146Z,1750965567.146 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:19:42.993Z,1750965582.993 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:19:43.020Z,1750965583.020 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:20:02.287Z,1750965602.287 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:20:37.434Z,1750965637.434 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:21:12.582Z,1750965672.582 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-06-26T19:21:35.131Z,1750965695.131 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003910 2025-06-26T19:24:12.873Z,1750965852.873 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T19:24:12.874Z,1750965852.874 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:24:12.874Z,1750965852.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:24:12.874Z,1750965852.874 [Default:CheckIn:D] Running Loop=1 2025-06-26T19:24:13.289Z,1750965853.289 [Default:CheckIn:D] Stopped 2025-06-26T19:24:13.289Z,1750965853.289 [Default:CheckIn:E] Running Loop=1 2025-06-26T19:24:13.681Z,1750965853.681 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.152026 min 2025-06-26T19:24:13.681Z,1750965853.681 [Default:CheckIn:E] Stopped 2025-06-26T19:24:13.681Z,1750965853.681 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T19:24:13.681Z,1750965853.681 [Default:CheckIn] Stopped 2025-06-26T19:24:13.681Z,1750965853.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:24:13.681Z,1750965853.681 [Default:CheckIn](INFO): Running loop #6 2025-06-26T19:24:13.681Z,1750965853.681 [Default:CheckIn] Running Loop=6 2025-06-26T19:24:13.682Z,1750965853.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:24:13.682Z,1750965853.682 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:24:15.697Z,1750965855.697 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192415.00,A,3648.15415,N,12147.22427,W,0.117,334.02,260625,,,A*7F 2025-06-26T19:24:15.700Z,1750965855.700 [NAL9602](INFO): GPS fix at 20250626T192415: (36.802569, -121.787071) 2025-06-26T19:24:15.709Z,1750965855.709 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:24:15.709Z,1750965855.709 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:24:22.366Z,1750965862.366 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0043.lzma 2025-06-26T19:24:24.625Z,1750965864.625 [Reporter](INFO): NAL9602.sigQuality 3 count 2025-06-26T19:24:33.408Z,1750965873.408 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0043.lzma.bak 2025-06-26T19:24:33.408Z,1750965873.408 [DataOverHttps](INFO): SBD MOMSN=25249037 2025-06-26T19:24:34.286Z,1750965874.286 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19759, MT Status=1, MTMSN=1220 2025-06-26T19:24:34.286Z,1750965874.286 [NAL9602](INFO): Data available in MT queue 2025-06-26T19:24:34.779Z,1750965874.779 [NAL9602](INFO): Received command: ibit 2025-06-26T19:24:34.815Z,1750965874.815 [CommandExec](IMPORTANT): got command ibit 2025-06-26T19:24:34.826Z,1750965874.826 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-06-26T19:24:34.826Z,1750965874.826 [IBIT](IMPORTANT): Beginning control surface checks. 2025-06-26T19:24:34.830Z,1750965874.830 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-26T19:24:35.894Z,1750965875.894 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192435.00,A,3648.15466,N,12147.22454,W,0.097,334.02,260625,,,A*74 2025-06-26T19:24:35.903Z,1750965875.903 [NAL9602](INFO): GPS fix at 20250626T192435: (36.802578, -121.787076) 2025-06-26T19:24:39.131Z,1750965879.131 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192438.00,A,3648.15468,N,12147.22448,W,0.097,334.02,260625,,,A*7A 2025-06-26T19:24:39.134Z,1750965879.134 [NAL9602](INFO): GPS fix at 20250626T192438: (36.802578, -121.787075) 2025-06-26T19:24:39.318Z,1750965879.318 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.007969 CHAN A1 (24V): 0.000893 CHAN A2 (12V): -0.005685 CHAN A3 (5V): -0.002828 CHAN B0 (3.3V): -0.001531 CHAN B1 (3.15aV): -0.001259 CHAN B2 (3.15bV): -0.001882 CHAN B3 (GND): -0.000530 OPEN: 0.004662 Full Scale: +/- 1 mA 2025-06-26T19:24:41.957Z,1750965881.957 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192441.00,A,3648.15477,N,12147.22439,W,0.078,334.02,260625,,,A*7D 2025-06-26T19:24:41.960Z,1750965881.960 [NAL9602](INFO): GPS fix at 20250626T192441: (36.802580, -121.787073) 2025-06-26T19:24:44.789Z,1750965884.789 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192444.00,A,3648.15482,N,12147.22430,W,0.058,334.02,260625,,,A*79 2025-06-26T19:24:44.792Z,1750965884.792 [NAL9602](INFO): GPS fix at 20250626T192444: (36.802580, -121.787072) 2025-06-26T19:24:48.021Z,1750965888.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192447.00,A,3648.15480,N,12147.22415,W,0.175,334.02,260625,,,A*71 2025-06-26T19:24:48.024Z,1750965888.024 [NAL9602](INFO): GPS fix at 20250626T192447: (36.802580, -121.787069) 2025-06-26T19:24:50.852Z,1750965890.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192450.00,A,3648.15485,N,12147.22405,W,0.175,334.02,260625,,,A*73 2025-06-26T19:24:50.855Z,1750965890.855 [NAL9602](INFO): GPS fix at 20250626T192450: (36.802581, -121.787068) 2025-06-26T19:24:54.074Z,1750965894.074 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192453.00,A,3648.15484,N,12147.22396,W,0.214,334.02,260625,,,A*78 2025-06-26T19:24:54.084Z,1750965894.084 [NAL9602](INFO): GPS fix at 20250626T192453: (36.802581, -121.787066) 2025-06-26T19:24:56.905Z,1750965896.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192456.00,A,3648.15488,N,12147.22391,W,0.156,334.02,260625,,,A*73 2025-06-26T19:24:56.908Z,1750965896.908 [NAL9602](INFO): GPS fix at 20250626T192456: (36.802581, -121.787065) 2025-06-26T19:24:59.854Z,1750965899.854 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250626T181900/Express0044.lzma 2025-06-26T19:25:00.133Z,1750965900.133 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192459.00,A,3648.15482,N,12147.22420,W,0.117,334.02,260625,,,A*7E 2025-06-26T19:25:00.137Z,1750965900.137 [NAL9602](INFO): GPS fix at 20250626T192459: (36.802580, -121.787070) 2025-06-26T19:25:02.962Z,1750965902.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192502.00,A,3648.15502,N,12147.22426,W,0.253,334.02,260625,,,A*7D 2025-06-26T19:25:02.972Z,1750965902.972 [NAL9602](INFO): GPS fix at 20250626T192502: (36.802584, -121.787071) 2025-06-26T19:25:05.791Z,1750965905.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192505.00,A,3648.15499,N,12147.22434,W,0.253,334.02,260625,,,A*7A 2025-06-26T19:25:05.793Z,1750965905.793 [NAL9602](INFO): GPS fix at 20250626T192505: (36.802583, -121.787072) 2025-06-26T19:25:09.024Z,1750965909.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192508.00,A,3648.15492,N,12147.22444,W,0.058,334.02,260625,,,A*72 2025-06-26T19:25:09.027Z,1750965909.027 [NAL9602](INFO): GPS fix at 20250626T192508: (36.802582, -121.787074) 2025-06-26T19:25:10.896Z,1750965910.896 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0044.lzma.bak 2025-06-26T19:25:10.896Z,1750965910.896 [DataOverHttps](INFO): SBD MOMSN=25249047 2025-06-26T19:25:11.853Z,1750965911.853 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192511.00,A,3648.15492,N,12147.22454,W,0.058,334.02,260625,,,A*7B 2025-06-26T19:25:11.856Z,1750965911.856 [NAL9602](INFO): GPS fix at 20250626T192511: (36.802582, -121.787076) 2025-06-26T19:25:15.085Z,1750965915.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192514.00,A,3648.15503,N,12147.22459,W,0.039,334.02,260625,,,A*7D 2025-06-26T19:25:15.088Z,1750965915.088 [NAL9602](INFO): GPS fix at 20250626T192514: (36.802584, -121.787076) 2025-06-26T19:25:17.917Z,1750965917.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192517.00,A,3648.15508,N,12147.22466,W,0.039,334.02,260625,,,A*79 2025-06-26T19:25:17.920Z,1750965917.920 [NAL9602](INFO): GPS fix at 20250626T192517: (36.802585, -121.787078) 2025-06-26T19:25:20.788Z,1750965920.788 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802586 Longitude: -121.787079 2025-06-26T19:25:21.148Z,1750965921.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192520.00,A,3648.15499,N,12147.22472,W,0.078,334.02,260625,,,A*74 2025-06-26T19:25:21.150Z,1750965921.150 [NAL9602](INFO): GPS fix at 20250626T192520: (36.802583, -121.787079) 2025-06-26T19:25:21.166Z,1750965921.166 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.690001 2025-06-26T19:25:21.166Z,1750965921.166 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2025-06-26T19:25:21.167Z,1750965921.167 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2025-06-26T19:25:21.592Z,1750965921.592 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-06-26T19:25:21.592Z,1750965921.592 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-06-26T19:25:21.592Z,1750965921.592 [IBIT](IMPORTANT): Pressure:9.869547 PSI 2025-06-26T19:25:21.593Z,1750965921.593 [IBIT](IMPORTANT): Humidity:11.659138 % 2025-06-26T19:25:21.987Z,1750965921.987 [IBIT](IMPORTANT): Vehicle Pitch:-0.238559 degrees 2025-06-26T19:25:21.987Z,1750965921.987 [IBIT](IMPORTANT): Vehicle Roll:-1.167152 degrees 2025-06-26T19:25:21.988Z,1750965921.988 [IBIT](IMPORTANT): Vehicle Heading:191.367920 degrees 2025-06-26T19:25:22.370Z,1750965922.370 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-06-26T19:25:22.371Z,1750965922.371 [IBIT](IMPORTANT): buoyancyNeutral: 215.486557 cc 2025-06-26T19:25:22.371Z,1750965922.371 [IBIT](IMPORTANT): massDefault: 2.532250 cm 2025-06-26T19:25:22.371Z,1750965922.371 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2025-06-26T19:25:22.371Z,1750965922.371 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-06-26T19:25:22.371Z,1750965922.371 [IBIT](IMPORTANT): IBIT PASSED 2025-06-26T19:25:22.781Z,1750965922.781 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:25:22.781Z,1750965922.781 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:25:22.781Z,1750965922.781 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:25:53.460Z,1750965953.460 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:25:53.513Z,1750965953.513 [Reporter](INFO): NAL9602.sigQuality 0 count 2025-06-26T19:27:33.599Z,1750966053.599 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T192733 2025-06-26T19:27:40.934Z,1750966060.934 [DataOverHttps](INFO): Received command: report clear 2025-06-26T19:27:40.948Z,1750966060.948 [CommandExec](IMPORTANT): got command report clear 2025-06-26T19:30:23.343Z,1750966223.343 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T19:30:23.343Z,1750966223.343 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:30:23.343Z,1750966223.343 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:30:23.344Z,1750966223.344 [Default:CheckIn:D] Running Loop=1 2025-06-26T19:30:23.753Z,1750966223.753 [Default:CheckIn:D] Stopped 2025-06-26T19:30:23.753Z,1750966223.753 [Default:CheckIn:E] Running Loop=1 2025-06-26T19:30:24.173Z,1750966224.173 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.326424 min 2025-06-26T19:30:24.173Z,1750966224.173 [Default:CheckIn:E] Stopped 2025-06-26T19:30:24.173Z,1750966224.173 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T19:30:24.174Z,1750966224.174 [Default:CheckIn] Stopped 2025-06-26T19:30:24.174Z,1750966224.174 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:30:24.174Z,1750966224.174 [Default:CheckIn](INFO): Running loop #7 2025-06-26T19:30:24.174Z,1750966224.174 [Default:CheckIn] Running Loop=7 2025-06-26T19:30:24.174Z,1750966224.174 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:30:24.174Z,1750966224.174 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:30:26.167Z,1750966226.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193025.00,A,3648.15337,N,12147.22475,W,0.078,334.02,260625,,,A*71 2025-06-26T19:30:26.169Z,1750966226.169 [NAL9602](INFO): GPS fix at 20250626T193025: (36.802556, -121.787079) 2025-06-26T19:30:26.179Z,1750966226.179 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:30:26.179Z,1750966226.179 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:30:33.598Z,1750966233.598 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0046.lzma 2025-06-26T19:30:44.640Z,1750966244.640 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0046.lzma.bak 2025-06-26T19:30:44.640Z,1750966244.640 [DataOverHttps](INFO): SBD MOMSN=25249058 2025-06-26T19:30:50.815Z,1750966250.815 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=19760, MT Status=1, MTMSN=1221 2025-06-26T19:30:50.815Z,1750966250.815 [NAL9602](INFO): Data available in MT queue 2025-06-26T19:30:51.319Z,1750966251.319 [NAL9602](INFO): Received command: ! echo 1 > /dev/loadB6 2025-06-26T19:30:51.364Z,1750966251.364 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB6 2025-06-26T19:31:10.681Z,1750966270.681 [DataOverHttps](INFO): Sending 1083 bytes from file Logs/20250626T181900/Express0047.lzma 2025-06-26T19:31:21.720Z,1750966281.720 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0047.lzma.bak 2025-06-26T19:31:21.720Z,1750966281.720 [DataOverHttps](INFO): SBD MOMSN=25249061 2025-06-26T19:31:21.912Z,1750966281.912 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:31:48.130Z,1750966308.130 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250626T181900/Express0050.lzma 2025-06-26T19:31:59.172Z,1750966319.172 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0050.lzma.bak 2025-06-26T19:31:59.172Z,1750966319.172 [DataOverHttps](INFO): SBD MOMSN=25249107 2025-06-26T19:32:00.313Z,1750966320.313 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:32:00.313Z,1750966320.313 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:32:00.313Z,1750966320.313 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:34:51.213Z,1750966491.213 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-06-26T19:34:51.235Z,1750966491.235 [BPC1](INFO): Received data from all battery sticks. 2025-06-26T19:37:00.905Z,1750966620.905 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T19:37:00.905Z,1750966620.905 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:37:00.905Z,1750966620.905 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:37:00.906Z,1750966620.906 [Default:CheckIn:D] Running Loop=1 2025-06-26T19:37:01.311Z,1750966621.311 [Default:CheckIn:D] Stopped 2025-06-26T19:37:01.311Z,1750966621.311 [Default:CheckIn:E] Running Loop=1 2025-06-26T19:37:01.713Z,1750966621.713 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.952393 min 2025-06-26T19:37:01.714Z,1750966621.714 [Default:CheckIn:E] Stopped 2025-06-26T19:37:01.714Z,1750966621.714 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T19:37:01.714Z,1750966621.714 [Default:CheckIn] Stopped 2025-06-26T19:37:01.714Z,1750966621.714 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:37:01.714Z,1750966621.714 [Default:CheckIn](INFO): Running loop #8 2025-06-26T19:37:01.714Z,1750966621.714 [Default:CheckIn] Running Loop=8 2025-06-26T19:37:01.715Z,1750966621.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:37:01.715Z,1750966621.715 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:37:03.722Z,1750966623.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193703.00,A,3648.15249,N,12147.22278,W,0.117,334.02,260625,,,A*79 2025-06-26T19:37:03.732Z,1750966623.732 [NAL9602](INFO): GPS fix at 20250626T193703: (36.802541, -121.787046) 2025-06-26T19:37:03.742Z,1750966623.742 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:37:03.742Z,1750966623.742 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:37:11.542Z,1750966631.542 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250626T181900/Courier0052.lzma 2025-06-26T19:37:17.863Z,1750966637.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=19761, MT Status=0, MTMSN=0 2025-06-26T19:37:17.863Z,1750966637.863 [NAL9602](INFO): No messages in MT queue 2025-06-26T19:37:22.584Z,1750966642.584 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0052.lzma.bak 2025-06-26T19:37:22.584Z,1750966642.584 [DataOverHttps](INFO): SBD MOMSN=25249115 2025-06-26T19:37:48.603Z,1750966668.603 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:37:49.879Z,1750966669.879 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20250626T181900/Express0053.lzma 2025-06-26T19:38:00.928Z,1750966680.928 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0053.lzma.bak 2025-06-26T19:38:00.928Z,1750966680.928 [DataOverHttps](INFO): SBD MOMSN=25249119 2025-06-26T19:38:02.340Z,1750966682.340 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:38:02.341Z,1750966682.341 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:38:02.341Z,1750966682.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:41:16.748Z,1750966876.748 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T194115 2025-06-26T19:41:24.107Z,1750966884.107 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 1 h;set transit.NeedCommsTime 20 min;set transit.Latitude 36.73703 degree;set transit.Longitude -122.02094 degree;set transit.Depth 10 m;set transit.Speed 1.3 m/s" 3dvzv 1 2 2025-06-26T19:41:24.215Z,1750966884.215 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 1 h;set transit.NeedCommsTime 20 min;set transit.Latitude 36.73703 degree;set transit.Longitude -122.02094 degree;set transit.Depth 10 m;set transit.Speed 1.3 m/s" 3dvzv 1 2.000000 2025-06-26T19:41:24.215Z,1750966884.215 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3dvzv 2025-06-26T19:41:24.216Z,1750966884.216 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='3dvzv'): "load Transport/transit.tl;set transit.MissionTimeout 1 h;set transit.NeedCommsTime 20 min;set transit.Latitude 36.73703 degree;set transit.Longitude -122.02094 degree;set transit.Depth 10 m;set transit.Speed 1.3 m/s", ASAP 2025-06-26T19:41:35.148Z,1750966895.148 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T194134 2025-06-26T19:41:42.514Z,1750966902.514 [DataOverHttps](INFO): Received command: sched asap "set transit.MaxDepth 20 m;run" 3dvzv 2 2 2025-06-26T19:41:42.573Z,1750966902.573 [CommandExec](IMPORTANT): got command schedule asap "set transit.MaxDepth 20 m;run" 3dvzv 2 2.000000 2025-06-26T19:41:42.574Z,1750966902.574 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3dvzv 2025-06-26T19:41:42.575Z,1750966902.575 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='3dvzv'): "set transit.MaxDepth 20 m;run", ASAP 2025-06-26T19:41:42.688Z,1750966902.688 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2025-06-26T19:41:42.688Z,1750966902.688 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2025-06-26T19:41:42.689Z,1750966902.689 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl 2025-06-26T19:41:42.692Z,1750966902.692 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl 2025-06-26T19:41:43.414Z,1750966903.414 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into: 60 30 NaN NaN 0 0 10 1 20 1 7 1 2025-06-26T19:41:43.420Z,1750966903.420 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2025-06-26T19:41:43.424Z,1750966903.424 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2025-06-26T19:41:43.427Z,1750966903.427 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2025-06-26T19:41:43.429Z,1750966903.429 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2025-06-26T19:41:43.433Z,1750966903.433 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2025-06-26T19:41:43.460Z,1750966903.460 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2025-06-26T19:41:43.467Z,1750966903.467 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2025-06-26T19:41:43.470Z,1750966903.470 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2025-06-26T19:41:43.477Z,1750966903.477 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2025-06-26T19:41:43.489Z,1750966903.489 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2025-06-26T19:41:43.500Z,1750966903.500 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2025-06-26T19:41:43.511Z,1750966903.511 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2025-06-26T19:41:43.514Z,1750966903.514 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2025-06-26T19:41:43.519Z,1750966903.519 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-06-26T19:41:43.519Z,1750966903.519 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-06-26T19:41:43.520Z,1750966903.520 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-06-26T19:41:43.946Z,1750966903.946 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-06-26T19:41:43.952Z,1750966903.952 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h 2025-06-26T19:41:43.955Z,1750966903.955 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2025-06-26T19:41:43.958Z,1750966903.958 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-06-26T19:41:43.961Z,1750966903.961 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2025-06-26T19:41:43.964Z,1750966903.964 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-06-26T19:41:43.967Z,1750966903.967 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2025-06-26T19:41:43.971Z,1750966903.971 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2025-06-26T19:41:43.977Z,1750966903.977 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2025-06-26T19:41:43.984Z,1750966903.984 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-26T19:41:44.082Z,1750966904.082 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-06-26T19:41:44.094Z,1750966904.094 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-06-26T19:41:44.095Z,1750966904.095 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-06-26T19:41:44.313Z,1750966904.313 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-06-26T19:41:44.331Z,1750966904.331 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2025-06-26T19:41:44.341Z,1750966904.341 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-06-26T19:41:44.344Z,1750966904.344 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2025-06-26T19:41:44.348Z,1750966904.348 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-06-26T19:41:44.349Z,1750966904.349 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-06-26T19:41:44.366Z,1750966904.366 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-06-26T19:41:44.391Z,1750966904.391 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-06-26T19:41:44.415Z,1750966904.415 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-06-26T19:41:44.416Z,1750966904.416 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-06-26T19:41:44.417Z,1750966904.417 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-06-26T19:41:44.483Z,1750966904.483 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-06-26T19:41:44.488Z,1750966904.488 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool 2025-06-26T19:41:44.507Z,1750966904.507 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-06-26T19:41:44.510Z,1750966904.510 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-06-26T19:41:44.514Z,1750966904.514 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-06-26T19:41:44.515Z,1750966904.515 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-06-26T19:41:44.760Z,1750966904.760 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-06-26T19:41:44.789Z,1750966904.789 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool 2025-06-26T19:41:44.792Z,1750966904.792 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool 2025-06-26T19:41:44.811Z,1750966904.811 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool 2025-06-26T19:41:44.814Z,1750966904.814 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool 2025-06-26T19:41:44.821Z,1750966904.821 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 1 bool 2025-06-26T19:41:44.837Z,1750966904.837 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-06-26T19:41:44.843Z,1750966904.843 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-26T19:41:44.851Z,1750966904.851 [transit:MassHold.Pitch](DEBUG): Construct. 2025-06-26T19:41:44.872Z,1750966904.872 [transit:Transit:A.Pitch](DEBUG): Construct. 2025-06-26T19:41:45.011Z,1750966905.011 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2025-06-26T19:41:45.035Z,1750966905.035 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-06-26T19:41:45.083Z,1750966905.083 [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 transit { """ Vehicle dives immediately with no comms/GPS and transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" priorityHere=false refId="NeedComms" } } 2025-06-26T19:41:45.083Z,1750966905.083 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit 2025-06-26T19:42:06.514Z,1750966926.514 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 1 hour 2025-06-26T19:42:06.515Z,1750966926.515 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 20 minute 2025-06-26T19:42:06.516Z,1750966926.516 [CommandExec](IMPORTANT): got command set transit.Latitude 36.73703 degree 2025-06-26T19:42:06.517Z,1750966926.517 [CommandExec](IMPORTANT): got command set transit.Longitude -122.02094 degree 2025-06-26T19:42:06.517Z,1750966926.517 [CommandExec](IMPORTANT): got command set transit.Depth 10 meter 2025-06-26T19:42:06.610Z,1750966926.610 [CommandExec](IMPORTANT): got command set transit.Speed 1.3 meter_per_second 2025-06-26T19:42:08.666Z,1750966928.666 [CommandExec](IMPORTANT): got command set transit.MaxDepth 20 meter 2025-06-26T19:42:08.667Z,1750966928.667 [CommandExec](IMPORTANT): got command run 2025-06-26T19:42:08.669Z,1750966928.669 [CommandExec](IMPORTANT): Running 2025-06-26T19:42:08.969Z,1750966928.969 [Default] Stopped 2025-06-26T19:42:08.970Z,1750966928.970 [Default](DEBUG): Aggregate::uninitialize Default 2025-06-26T19:42:08.970Z,1750966928.970 [Default:B.GoToSurface] Stopped 2025-06-26T19:42:08.970Z,1750966928.970 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-26T19:42:08.970Z,1750966928.970 [Default:CheckIn] Stopped 2025-06-26T19:42:08.970Z,1750966928.970 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:42:08.970Z,1750966928.970 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:42:08.970Z,1750966928.970 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:42:08.970Z,1750966928.970 [MissionManager](IMPORTANT): Started mission transit 2025-06-26T19:42:08.971Z,1750966928.971 [transit] Running Loop=1 2025-06-26T19:42:08.971Z,1750966928.971 [transit](DEBUG): Aggregate::initialize transit 2025-06-26T19:42:08.971Z,1750966928.971 [transit:StandardEnvelopes] Running Loop=1 2025-06-26T19:42:08.971Z,1750966928.971 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2025-06-26T19:42:08.971Z,1750966928.971 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-26T19:42:08.971Z,1750966928.971 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-06-26T19:42:08.971Z,1750966928.971 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-26T19:42:08.971Z,1750966928.971 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-06-26T19:42:08.972Z,1750966928.972 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-26T19:42:08.972Z,1750966928.972 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-06-26T19:42:08.972Z,1750966928.972 [transit:BackseatDriver] Running Loop=1 2025-06-26T19:42:08.972Z,1750966928.972 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver 2025-06-26T19:42:08.972Z,1750966928.972 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-06-26T19:42:08.972Z,1750966928.972 [transit:PowerOnly] Running Loop=1 2025-06-26T19:42:08.972Z,1750966928.972 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly 2025-06-26T19:42:08.972Z,1750966928.972 [transit:PowerOnly:A] Running Loop=1 2025-06-26T19:42:08.972Z,1750966928.972 [transit:PowerOnly:B] Running Loop=1 2025-06-26T19:42:08.973Z,1750966928.973 [transit:PowerOnly:C] Running Loop=1 2025-06-26T19:42:08.973Z,1750966928.973 [transit:PowerOnly:D] Running Loop=1 2025-06-26T19:42:08.973Z,1750966928.973 [transit:PowerOnly:E.Wait] Running Loop=1 2025-06-26T19:42:08.973Z,1750966928.973 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:42:08.973Z,1750966928.973 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-26T19:42:08.973Z,1750966928.973 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-26T19:42:08.973Z,1750966928.973 [transit:MassHold.Pitch] Running Loop=1 2025-06-26T19:42:08.973Z,1750966928.973 [transit:MassHold.Pitch](DEBUG): Initialize. 2025-06-26T19:42:08.973Z,1750966928.973 [transit:B] Running Loop=1 2025-06-26T19:42:08.974Z,1750966928.974 [transit:MassHold.Pitch] Running Loop=1 2025-06-26T19:42:08.975Z,1750966928.975 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-26T19:42:08.975Z,1750966928.975 [transit:PowerOnly] Running Loop=1 2025-06-26T19:42:08.975Z,1750966928.975 [transit:PowerOnly:D] Stopped 2025-06-26T19:42:08.975Z,1750966928.975 [transit:PowerOnly:C] Stopped 2025-06-26T19:42:08.975Z,1750966928.975 [transit:PowerOnly:B] Stopped 2025-06-26T19:42:08.975Z,1750966928.975 [transit:PowerOnly:A] Stopped 2025-06-26T19:42:08.975Z,1750966928.975 [transit:BackseatDriver] Running Loop=1 2025-06-26T19:42:08.976Z,1750966928.976 [transit:StandardEnvelopes] Running Loop=1 2025-06-26T19:42:08.976Z,1750966928.976 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-26T19:42:08.976Z,1750966928.976 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-26T19:42:08.977Z,1750966928.977 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-26T19:42:08.978Z,1750966928.978 [transit:B] Stopped 2025-06-26T19:42:08.978Z,1750966928.978 [transit:C] Running Loop=1 2025-06-26T19:42:09.386Z,1750966929.386 [transit:C] Stopped 2025-06-26T19:42:09.386Z,1750966929.386 [transit:E] Running Loop=1 2025-06-26T19:42:09.779Z,1750966929.779 [transit:E] Stopped 2025-06-26T19:42:09.779Z,1750966929.779 [transit:F] Running Loop=1 2025-06-26T19:42:10.186Z,1750966930.186 [transit:F] Stopped 2025-06-26T19:42:10.186Z,1750966930.186 [transit:G] Running Loop=1 2025-06-26T19:42:10.594Z,1750966930.594 [transit:G] Stopped 2025-06-26T19:42:10.594Z,1750966930.594 [transit:Transit] Running Loop=1 2025-06-26T19:42:10.594Z,1750966930.594 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2025-06-26T19:42:10.595Z,1750966930.595 [transit:Transit:A.Pitch] Running Loop=1 2025-06-26T19:42:10.595Z,1750966930.595 [transit:Transit:A.Pitch](DEBUG): Initialize. 2025-06-26T19:42:10.595Z,1750966930.595 [transit:Transit:B.SetSpeed] Running Loop=1 2025-06-26T19:42:10.595Z,1750966930.595 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2025-06-26T19:42:10.595Z,1750966930.595 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2025-06-26T19:42:10.595Z,1750966930.595 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-06-26T19:42:10.597Z,1750966930.597 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.737030,-122.020943 2025-06-26T19:42:10.598Z,1750966930.598 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-06-26T19:42:10.851Z,1750966930.851 [CommandExec](IMPORTANT): got command stop 2025-06-26T19:42:10.851Z,1750966930.851 [CommandExec](IMPORTANT): Scheduling is paused 2025-06-26T19:42:10.851Z,1750966930.851 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-06-26T19:42:11.003Z,1750966931.003 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.737030,-122.020943 2025-06-26T19:42:11.004Z,1750966931.004 [transit:Transit:B.SetSpeed] Running Loop=1 2025-06-26T19:42:11.004Z,1750966931.004 [transit:Transit:A.Pitch] Running Loop=1 2025-06-26T19:42:11.006Z,1750966931.006 [MissionManager](INFO): MissionManager is completed. 2025-06-26T19:42:11.007Z,1750966931.007 [MissionManager](INFO): Uninitializing Mission transit 2025-06-26T19:42:11.007Z,1750966931.007 [transit] Stopped 2025-06-26T19:42:11.007Z,1750966931.007 [transit](DEBUG): Aggregate::uninitialize transit 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes] Stopped 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-06-26T19:42:11.007Z,1750966931.007 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-06-26T19:42:11.007Z,1750966931.007 [transit:BackseatDriver] Stopped 2025-06-26T19:42:11.007Z,1750966931.007 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2025-06-26T19:42:11.008Z,1750966931.008 [transit:BackseatDriver:A.BackseatDriver] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:PowerOnly] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2025-06-26T19:42:11.008Z,1750966931.008 [transit:PowerOnly:E.Wait] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:42:11.008Z,1750966931.008 [transit:BuoyancyHold.Buoyancy] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-26T19:42:11.008Z,1750966931.008 [transit:MassHold.Pitch] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:Transit] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2025-06-26T19:42:11.008Z,1750966931.008 [transit:Transit:A.Pitch] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:Transit:B.SetSpeed] Stopped 2025-06-26T19:42:11.008Z,1750966931.008 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2025-06-26T19:42:11.009Z,1750966931.009 [transit:Transit:Wpt1.Waypoint] Stopped 2025-06-26T19:42:11.009Z,1750966931.009 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-06-26T19:42:11.398Z,1750966931.398 [MissionManager](IMPORTANT): Started mission Default 2025-06-26T19:42:11.398Z,1750966931.398 [Default] Running Loop=1 2025-06-26T19:42:11.398Z,1750966931.398 [Default](DEBUG): Aggregate::initialize Default 2025-06-26T19:42:11.398Z,1750966931.398 [Default:B.GoToSurface] Running Loop=1 2025-06-26T19:42:11.398Z,1750966931.398 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-26T19:42:11.399Z,1750966931.399 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-26T19:42:11.399Z,1750966931.399 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-26T19:42:11.399Z,1750966931.399 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-26T19:42:11.400Z,1750966931.400 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-26T19:42:11.400Z,1750966931.400 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-26T19:42:11.400Z,1750966931.400 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-26T19:42:11.401Z,1750966931.401 [Default:A.Wait] Running Loop=1 2025-06-26T19:42:11.401Z,1750966931.401 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:42:24.737Z,1750966944.737 [Default:A.Wait](INFO): Done Waiting. 2025-06-26T19:42:24.737Z,1750966944.737 [Default:A.Wait] Stopped 2025-06-26T19:42:24.737Z,1750966944.737 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:42:25.133Z,1750966945.133 [Default:CheckIn] Running Loop=1 2025-06-26T19:42:25.133Z,1750966945.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:42:25.133Z,1750966945.133 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:42:27.143Z,1750966947.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194226.00,A,3648.15229,N,12147.22154,W,0.097,0.00,260625,,,A*78 2025-06-26T19:42:27.145Z,1750966947.145 [NAL9602](INFO): GPS fix at 20250626T194226: (36.802538, -121.787026) 2025-06-26T19:42:27.155Z,1750966947.155 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:42:27.155Z,1750966947.155 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:42:28.504Z,1750966948.504 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T194228 2025-06-26T19:42:35.586Z,1750966955.586 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0055.lzma 2025-06-26T19:42:35.588Z,1750966955.588 [DataOverHttps](INFO): Received command: schedule clear;schedule resume 2025-06-26T19:42:35.677Z,1750966955.677 [CommandExec](IMPORTANT): got command schedule clear 2025-06-26T19:42:35.678Z,1750966955.678 [CommandExec](IMPORTANT): Cleared 0 scheduled commands. 2025-06-26T19:42:35.678Z,1750966955.678 [CommandExec](IMPORTANT): got command schedule resume 2025-06-26T19:42:35.678Z,1750966955.678 [CommandExec](IMPORTANT): Scheduling is resumed 2025-06-26T19:42:36.841Z,1750966956.841 [NAL9602](INFO): SBD MO Status=0, MOMSN=19762, MT Status=0, MTMSN=0 2025-06-26T19:42:36.841Z,1750966956.841 [NAL9602](INFO): No messages in MT queue 2025-06-26T19:42:46.628Z,1750966966.628 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0055.lzma.bak 2025-06-26T19:42:46.628Z,1750966966.628 [DataOverHttps](INFO): SBD MOMSN=25249134 2025-06-26T19:43:07.541Z,1750966987.541 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:43:12.478Z,1750966992.478 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250626T181900/Courier0061.lzma 2025-06-26T19:43:23.520Z,1750967003.520 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0061.lzma.bak 2025-06-26T19:43:23.520Z,1750967003.520 [DataOverHttps](INFO): SBD MOMSN=25249138 2025-06-26T19:43:50.803Z,1750967030.803 [DataOverHttps](INFO): Sending 663 bytes from file Logs/20250626T181900/Express0056.lzma 2025-06-26T19:44:01.845Z,1750967041.845 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0056.lzma.bak 2025-06-26T19:44:01.845Z,1750967041.845 [DataOverHttps](INFO): SBD MOMSN=25249141 2025-06-26T19:44:27.854Z,1750967067.854 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250626T181900/Express0059.lzma 2025-06-26T19:44:38.896Z,1750967078.896 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0059.lzma.bak 2025-06-26T19:44:38.896Z,1750967078.896 [DataOverHttps](INFO): SBD MOMSN=25249167 2025-06-26T19:45:04.790Z,1750967104.790 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20250626T181900/Express0062.lzma 2025-06-26T19:45:15.832Z,1750967115.832 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0062.lzma.bak 2025-06-26T19:45:15.832Z,1750967115.832 [DataOverHttps](INFO): SBD MOMSN=25249169 2025-06-26T19:45:17.238Z,1750967117.238 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:45:17.239Z,1750967117.239 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:45:17.239Z,1750967117.239 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:50:17.886Z,1750967417.886 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T19:50:17.886Z,1750967417.886 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:50:17.886Z,1750967417.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:50:17.887Z,1750967417.887 [Default:CheckIn:D] Running Loop=1 2025-06-26T19:50:18.292Z,1750967418.292 [Default:CheckIn:D] Stopped 2025-06-26T19:50:18.292Z,1750967418.292 [Default:CheckIn:E] Running Loop=1 2025-06-26T19:50:18.704Z,1750967418.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.114892 min 2025-06-26T19:50:18.704Z,1750967418.704 [Default:CheckIn:E] Stopped 2025-06-26T19:50:18.704Z,1750967418.704 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T19:50:18.704Z,1750967418.704 [Default:CheckIn] Stopped 2025-06-26T19:50:18.704Z,1750967418.704 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:50:18.704Z,1750967418.704 [Default:CheckIn](INFO): Running loop #2 2025-06-26T19:50:18.705Z,1750967418.705 [Default:CheckIn] Running Loop=2 2025-06-26T19:50:18.705Z,1750967418.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:50:18.705Z,1750967418.705 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:50:20.710Z,1750967420.710 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195020.00,A,3648.14967,N,12147.21392,W,0.058,0.00,260625,,,A*75 2025-06-26T19:50:20.712Z,1750967420.712 [NAL9602](INFO): GPS fix at 20250626T195020: (36.802495, -121.786899) 2025-06-26T19:50:20.745Z,1750967420.745 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:50:20.745Z,1750967420.745 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:50:27.887Z,1750967427.887 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250626T181900/Courier0064.lzma 2025-06-26T19:50:38.928Z,1750967438.928 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0064.lzma.bak 2025-06-26T19:50:38.928Z,1750967438.928 [DataOverHttps](INFO): SBD MOMSN=25249182 2025-06-26T19:50:51.019Z,1750967451.019 [NAL9602](INFO): SBD MO Status=0, MOMSN=19763, MT Status=0, MTMSN=0 2025-06-26T19:50:51.019Z,1750967451.019 [NAL9602](INFO): No messages in MT queue 2025-06-26T19:51:04.722Z,1750967464.722 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250626T181900/Express0065.lzma 2025-06-26T19:51:15.764Z,1750967475.764 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0065.lzma.bak 2025-06-26T19:51:15.764Z,1750967475.764 [DataOverHttps](INFO): SBD MOMSN=25249185 2025-06-26T19:51:16.874Z,1750967476.874 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:51:16.874Z,1750967476.874 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:51:16.874Z,1750967476.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T19:51:21.715Z,1750967481.715 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:56:17.498Z,1750967777.498 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T19:56:17.498Z,1750967777.498 [Default:CheckIn:C.Wait] Stopped 2025-06-26T19:56:17.498Z,1750967777.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T19:56:17.499Z,1750967777.499 [Default:CheckIn:D] Running Loop=1 2025-06-26T19:56:17.889Z,1750967777.889 [Default:CheckIn:D] Stopped 2025-06-26T19:56:17.889Z,1750967777.889 [Default:CheckIn:E] Running Loop=1 2025-06-26T19:56:18.289Z,1750967778.289 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.108178 min 2025-06-26T19:56:18.289Z,1750967778.289 [Default:CheckIn:E] Stopped 2025-06-26T19:56:18.289Z,1750967778.289 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T19:56:18.289Z,1750967778.289 [Default:CheckIn] Stopped 2025-06-26T19:56:18.289Z,1750967778.289 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T19:56:18.290Z,1750967778.290 [Default:CheckIn](INFO): Running loop #3 2025-06-26T19:56:18.290Z,1750967778.290 [Default:CheckIn] Running Loop=3 2025-06-26T19:56:18.290Z,1750967778.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T19:56:18.290Z,1750967778.290 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T19:56:20.301Z,1750967780.301 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195619.00,A,3648.15287,N,12147.22130,W,0.214,0.00,260625,,,A*7E 2025-06-26T19:56:20.312Z,1750967780.312 [NAL9602](INFO): GPS fix at 20250626T195619: (36.802548, -121.787022) 2025-06-26T19:56:20.322Z,1750967780.322 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T19:56:20.322Z,1750967780.322 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T19:56:27.574Z,1750967787.574 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0067.lzma 2025-06-26T19:56:34.040Z,1750967794.040 [NAL9602](INFO): SBD MO Status=0, MOMSN=19764, MT Status=0, MTMSN=0 2025-06-26T19:56:34.040Z,1750967794.040 [NAL9602](INFO): No messages in MT queue 2025-06-26T19:56:38.616Z,1750967798.616 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0067.lzma.bak 2025-06-26T19:56:38.616Z,1750967798.616 [DataOverHttps](INFO): SBD MOMSN=25249195 2025-06-26T19:57:04.478Z,1750967824.478 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250626T181900/Express0068.lzma 2025-06-26T19:57:04.736Z,1750967824.736 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T19:57:15.520Z,1750967835.520 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0068.lzma.bak 2025-06-26T19:57:15.520Z,1750967835.520 [DataOverHttps](INFO): SBD MOMSN=25249204 2025-06-26T19:57:16.887Z,1750967836.887 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T19:57:16.887Z,1750967836.887 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T19:57:16.887Z,1750967836.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T20:02:17.558Z,1750968137.558 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T20:02:17.559Z,1750968137.559 [Default:CheckIn:C.Wait] Stopped 2025-06-26T20:02:17.559Z,1750968137.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T20:02:17.559Z,1750968137.559 [Default:CheckIn:D] Running Loop=1 2025-06-26T20:02:17.893Z,1750968137.893 [Default:CheckIn:D] Stopped 2025-06-26T20:02:17.893Z,1750968137.893 [Default:CheckIn:E] Running Loop=1 2025-06-26T20:02:18.294Z,1750968138.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.108242 min 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn:E] Stopped 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn] Stopped 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn](INFO): Running loop #4 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn] Running Loop=4 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T20:02:18.295Z,1750968138.295 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T20:02:20.310Z,1750968140.310 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200219.00,A,3648.15236,N,12147.22087,W,0.019,332.95,260625,,,A*73 2025-06-26T20:02:20.312Z,1750968140.312 [NAL9602](INFO): GPS fix at 20250626T200219: (36.802539, -121.787014) 2025-06-26T20:02:20.323Z,1750968140.323 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T20:02:20.323Z,1750968140.323 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T20:02:27.162Z,1750968147.162 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0070.lzma 2025-06-26T20:02:30.003Z,1750968150.003 [NAL9602](INFO): SBD MO Status=0, MOMSN=19765, MT Status=0, MTMSN=0 2025-06-26T20:02:30.003Z,1750968150.003 [NAL9602](INFO): No messages in MT queue 2025-06-26T20:02:38.204Z,1750968158.204 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0070.lzma.bak 2025-06-26T20:02:38.204Z,1750968158.204 [DataOverHttps](INFO): SBD MOMSN=25249214 2025-06-26T20:03:00.707Z,1750968180.707 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T20:03:04.328Z,1750968184.328 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250626T181900/Express0071.lzma 2025-06-26T20:03:15.364Z,1750968195.364 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0071.lzma.bak 2025-06-26T20:03:15.364Z,1750968195.364 [DataOverHttps](INFO): SBD MOMSN=25249218 2025-06-26T20:03:16.472Z,1750968196.472 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T20:03:16.472Z,1750968196.472 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T20:03:16.473Z,1750968196.473 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T20:08:17.121Z,1750968497.121 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T20:08:17.121Z,1750968497.121 [Default:CheckIn:C.Wait] Stopped 2025-06-26T20:08:17.122Z,1750968497.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T20:08:17.122Z,1750968497.122 [Default:CheckIn:D] Running Loop=1 2025-06-26T20:08:17.537Z,1750968497.537 [Default:CheckIn:D] Stopped 2025-06-26T20:08:17.537Z,1750968497.537 [Default:CheckIn:E] Running Loop=1 2025-06-26T20:08:17.911Z,1750968497.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.102319 min 2025-06-26T20:08:17.911Z,1750968497.911 [Default:CheckIn:E] Stopped 2025-06-26T20:08:17.911Z,1750968497.911 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T20:08:17.911Z,1750968497.911 [Default:CheckIn] Stopped 2025-06-26T20:08:17.912Z,1750968497.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T20:08:17.912Z,1750968497.912 [Default:CheckIn](INFO): Running loop #5 2025-06-26T20:08:17.912Z,1750968497.912 [Default:CheckIn] Running Loop=5 2025-06-26T20:08:17.912Z,1750968497.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T20:08:17.912Z,1750968497.912 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T20:08:19.925Z,1750968499.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200819.00,A,3648.47480,N,12147.27860,W,20.683,230.89,260625,,,A*48 2025-06-26T20:08:19.935Z,1750968499.935 [NAL9602](INFO): GPS fix at 20250626T200819: (36.807913, -121.787977) 2025-06-26T20:08:19.945Z,1750968499.945 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T20:08:19.945Z,1750968499.945 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T20:08:26.866Z,1750968506.866 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0073.lzma 2025-06-26T20:08:37.908Z,1750968517.908 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0073.lzma.bak 2025-06-26T20:08:37.908Z,1750968517.908 [DataOverHttps](INFO): SBD MOMSN=25249227 2025-06-26T20:08:40.528Z,1750968520.528 [NAL9602](INFO): SBD MO Status=0, MOMSN=19766, MT Status=0, MTMSN=0 2025-06-26T20:08:40.528Z,1750968520.528 [NAL9602](INFO): No messages in MT queue 2025-06-26T20:09:03.910Z,1750968543.910 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20250626T181900/Express0074.lzma 2025-06-26T20:09:11.230Z,1750968551.230 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T20:09:14.952Z,1750968554.952 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0074.lzma.bak 2025-06-26T20:09:14.952Z,1750968554.952 [DataOverHttps](INFO): SBD MOMSN=25249231 2025-06-26T20:09:16.092Z,1750968556.092 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T20:09:16.093Z,1750968556.093 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T20:09:16.093Z,1750968556.093 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T20:14:16.700Z,1750968856.700 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-26T20:14:16.700Z,1750968856.700 [Default:CheckIn:C.Wait] Stopped 2025-06-26T20:14:16.701Z,1750968856.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-26T20:14:16.701Z,1750968856.701 [Default:CheckIn:D] Running Loop=1 2025-06-26T20:14:17.112Z,1750968857.112 [Default:CheckIn:D] Stopped 2025-06-26T20:14:17.112Z,1750968857.112 [Default:CheckIn:E] Running Loop=1 2025-06-26T20:14:17.511Z,1750968857.511 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.095235 min 2025-06-26T20:14:17.511Z,1750968857.511 [Default:CheckIn:E] Stopped 2025-06-26T20:14:17.511Z,1750968857.511 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-26T20:14:17.511Z,1750968857.511 [Default:CheckIn] Stopped 2025-06-26T20:14:17.511Z,1750968857.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-26T20:14:17.512Z,1750968857.512 [Default:CheckIn](INFO): Running loop #6 2025-06-26T20:14:17.512Z,1750968857.512 [Default:CheckIn] Running Loop=6 2025-06-26T20:14:17.512Z,1750968857.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-26T20:14:17.512Z,1750968857.512 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-26T20:14:19.522Z,1750968859.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201418.00,A,3647.76023,N,12148.78787,W,16.600,187.71,260625,,,D*44 2025-06-26T20:14:19.524Z,1750968859.524 [NAL9602](INFO): GPS fix at 20250626T201418: (36.796004, -121.813131) 2025-06-26T20:14:19.535Z,1750968859.535 [Default:CheckIn:Read_GPS] Stopped 2025-06-26T20:14:19.535Z,1750968859.535 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-26T20:14:30.022Z,1750968870.022 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250626T181900/Courier0076.lzma 2025-06-26T20:14:41.060Z,1750968881.060 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Courier0076.lzma.bak 2025-06-26T20:14:41.060Z,1750968881.060 [DataOverHttps](INFO): SBD MOMSN=25249240 2025-06-26T20:15:00.333Z,1750968900.333 [NAL9602](INFO): SBD MO Status=2, MOMSN=19767, MT Status=2, MTMSN=0 2025-06-26T20:15:00.333Z,1750968900.333 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T20:15:06.931Z,1750968906.931 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250626T181900/Express0077.lzma 2025-06-26T20:15:17.768Z,1750968917.768 [NAL9602](INFO): SBD MO Status=2, MOMSN=19767, MT Status=2, MTMSN=0 2025-06-26T20:15:17.768Z,1750968917.768 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T20:15:17.972Z,1750968917.972 [DataOverHttps](INFO): Moved sent file to Logs/20250626T181900/Express0077.lzma.bak 2025-06-26T20:15:17.972Z,1750968917.972 [DataOverHttps](INFO): SBD MOMSN=25249243 2025-06-26T20:15:19.330Z,1750968919.330 [Default:CheckIn:Read_Iridium] Stopped 2025-06-26T20:15:19.330Z,1750968919.330 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-26T20:15:19.330Z,1750968919.330 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-26T20:15:36.281Z,1750968936.281 [NAL9602](INFO): SBD MO Status=2, MOMSN=19767, MT Status=2, MTMSN=0 2025-06-26T20:15:36.281Z,1750968936.281 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T20:16:00.941Z,1750968960.941 [NAL9602](INFO): SBD MO Status=2, MOMSN=19767, MT Status=2, MTMSN=0 2025-06-26T20:16:00.941Z,1750968960.941 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T20:16:25.973Z,1750968985.973 [NAL9602](INFO): SBD MO Status=2, MOMSN=19767, MT Status=2, MTMSN=0 2025-06-26T20:16:25.973Z,1750968985.973 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-26T20:16:36.074Z,1750968996.074 [NAL9602](INFO): SBD MO Status=0, MOMSN=19767, MT Status=0, MTMSN=0 2025-06-26T20:16:36.074Z,1750968996.074 [NAL9602](INFO): No messages in MT queue 2025-06-26T20:17:06.783Z,1750969026.783 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-26T20:18:45.520Z,1750969125.520 [DAT](INFO): DAT read: user:6>Rx Time:20:18:44.7865 2025-06-26T20:18:45.520Z,1750969125.520 [DAT](INFO): Rx dataTimestamp_ set to:1750969125.520002 2025-06-26T20:18:45.521Z,1750969125.521 [DAT](INFO): DAT read: 2025-06-26T20:18:45.521Z,1750969125.521 [DAT](INFO): DAT read: $Packet for address 0 2025-06-26T20:18:45.522Z,1750969125.522 [DAT](INFO): received a packet notification 2025-06-26T20:19:09.275Z,1750969149.275 [DataOverHttps](IMPORTANT): SBD MTMSN=20250626T201908 2025-06-26T20:19:16.638Z,1750969156.638 [DataOverHttps](INFO): Received command: restart logs