2025-01-07T17:30:32.310Z,1736271032.310 [Supervisor](DEBUG): Initializing supervisor.
2025-01-07T17:30:32.314Z,1736271032.314 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-01-07T17:30:32.315Z,1736271032.315 [SyncHandler](INFO): Protected caller Thread ID is 1006
2025-01-07T17:30:32.315Z,1736271032.315 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-01-07T17:30:32.316Z,1736271032.316 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-01-07T17:30:32.316Z,1736271032.316 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1007
2025-01-07T17:30:32.320Z,1736271032.320 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-01-07T17:30:32.338Z,1736271032.338 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-01-07T17:30:32.339Z,1736271032.339 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-01-07T17:30:32.340Z,1736271032.340 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1008
2025-01-07T17:30:32.344Z,1736271032.344 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-01-07T17:30:32.345Z,1736271032.345 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-01-07T17:30:32.345Z,1736271032.345 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1009
2025-01-07T17:30:32.347Z,1736271032.347 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-01-07T17:30:32.348Z,1736271032.348 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-01-07T17:30:32.349Z,1736271032.349 [logger ThreadHandler](INFO): Protected caller Thread ID is 1010
2025-01-07T17:30:32.352Z,1736271032.352 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-01-07T17:30:32.353Z,1736271032.353 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-01-07T17:30:32.357Z,1736271032.357 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-01-07T17:30:32.635Z,1736271032.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-01-07T17:30:32.635Z,1736271032.635 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-01-07T17:30:32.724Z,1736271032.724 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-01-07T17:30:33.243Z,1736271033.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-01-07T17:30:33.244Z,1736271033.244 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-01-07T17:30:33.586Z,1736271033.586 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-01-07T17:30:33.587Z,1736271033.587 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-01-07T17:30:33.691Z,1736271033.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-01-07T17:30:33.692Z,1736271033.692 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-01-07T17:30:34.101Z,1736271034.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-01-07T17:30:34.102Z,1736271034.102 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-01-07T17:30:34.321Z,1736271034.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-01-07T17:30:34.322Z,1736271034.322 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-01-07T17:30:34.846Z,1736271034.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-01-07T17:30:34.846Z,1736271034.846 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-01-07T17:30:34.991Z,1736271034.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-01-07T17:30:34.992Z,1736271034.992 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-01-07T17:30:35.080Z,1736271035.080 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-01-07T17:30:35.911Z,1736271035.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-01-07T17:30:35.911Z,1736271035.911 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-01-07T17:30:36.307Z,1736271036.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-01-07T17:30:36.308Z,1736271036.308 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-01-07T17:30:36.497Z,1736271036.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-01-07T17:30:36.498Z,1736271036.498 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-01-07T17:30:36.618Z,1736271036.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-01-07T17:30:36.618Z,1736271036.618 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-01-07T17:30:36.851Z,1736271036.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-01-07T17:30:36.852Z,1736271036.852 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-01-07T17:30:37.107Z,1736271037.107 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-01-07T17:30:37.109Z,1736271037.109 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2025-01-07T17:30:37.110Z,1736271037.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2025-01-07T17:30:37.205Z,1736271037.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2025-01-07T17:30:37.314Z,1736271037.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2025-01-07T17:30:37.453Z,1736271037.453 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2025-01-07T17:30:37.767Z,1736271037.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2025-01-07T17:30:38.020Z,1736271038.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2025-01-07T17:30:38.338Z,1736271038.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2025-01-07T17:30:38.508Z,1736271038.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2025-01-07T17:30:38.830Z,1736271038.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2025-01-07T17:30:38.952Z,1736271038.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2025-01-07T17:30:39.229Z,1736271039.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-01-07T17:30:39.231Z,1736271039.231 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2025-01-07T17:30:39.231Z,1736271039.231 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-01-07T17:30:39.240Z,1736271039.240 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-01-07T17:30:39.294Z,1736271039.294 [VerticalControl](DEBUG): Construct VerticalControl.
2025-01-07T17:30:39.356Z,1736271039.356 [VerticalControl] Loaded
2025-01-07T17:30:39.356Z,1736271039.356 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-01-07T17:30:39.359Z,1736271039.359 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-01-07T17:30:39.407Z,1736271039.407 [HorizontalControl] Loaded
2025-01-07T17:30:39.407Z,1736271039.407 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-01-07T17:30:39.409Z,1736271039.409 [SpeedControl](DEBUG): Construct SpeedControl.
2025-01-07T17:30:39.412Z,1736271039.412 [SpeedControl] Loaded
2025-01-07T17:30:39.413Z,1736271039.413 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-01-07T17:30:39.415Z,1736271039.415 [LoopControl](DEBUG): Construct LoopControl.
2025-01-07T17:30:39.416Z,1736271039.416 [LoopControl] Loaded
2025-01-07T17:30:39.416Z,1736271039.416 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-01-07T17:30:39.416Z,1736271039.416 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-01-07T17:30:39.417Z,1736271039.417 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-01-07T17:30:39.427Z,1736271039.427 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-01-07T17:30:39.428Z,1736271039.428 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-01-07T17:30:39.602Z,1736271039.602 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-01-07T17:30:39.603Z,1736271039.603 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-01-07T17:30:39.762Z,1736271039.762 [BuoyancyServo] Loaded
2025-01-07T17:30:39.762Z,1736271039.762 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-01-07T17:30:39.784Z,1736271039.784 [ElevatorServo] Loaded
2025-01-07T17:30:39.785Z,1736271039.785 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-01-07T17:30:39.806Z,1736271039.806 [MassServo] Loaded
2025-01-07T17:30:39.806Z,1736271039.806 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-01-07T17:30:39.827Z,1736271039.827 [RudderServo] Loaded
2025-01-07T17:30:39.827Z,1736271039.827 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-01-07T17:30:39.844Z,1736271039.844 [ThrusterHE] Loaded
2025-01-07T17:30:39.844Z,1736271039.844 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-01-07T17:30:39.845Z,1736271039.845 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-01-07T17:30:39.845Z,1736271039.845 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-01-07T17:30:39.960Z,1736271039.960 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-01-07T17:30:39.960Z,1736271039.960 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-01-07T17:30:39.974Z,1736271039.974 [NavChart] Loaded
2025-01-07T17:30:39.974Z,1736271039.974 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-01-07T17:30:39.980Z,1736271039.980 [UniversalFixResidualReporter] Loaded
2025-01-07T17:30:39.980Z,1736271039.980 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-01-07T17:30:39.981Z,1736271039.981 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-01-07T17:30:39.981Z,1736271039.981 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-01-07T17:30:40.039Z,1736271040.039 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-01-07T17:30:40.040Z,1736271040.040 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-01-07T17:30:40.501Z,1736271040.501 [AHRS_M2] Loaded
2025-01-07T17:30:40.501Z,1736271040.501 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-01-07T17:30:41.335Z,1736271041.335 [BPC1] Loaded
2025-01-07T17:30:41.336Z,1736271041.336 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-01-07T17:30:41.409Z,1736271041.409 [DataOverHttps] Loaded
2025-01-07T17:30:41.409Z,1736271041.409 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-01-07T17:30:41.411Z,1736271041.411 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409464E0
2025-01-07T17:30:41.411Z,1736271041.411 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1089
2025-01-07T17:30:41.434Z,1736271041.434 [Depth_Keller] Loaded
2025-01-07T17:30:41.434Z,1736271041.434 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-01-07T17:30:41.439Z,1736271041.439 [DropWeight] Loaded
2025-01-07T17:30:41.440Z,1736271041.440 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-01-07T17:30:41.504Z,1736271041.504 [NAL9602] Loaded
2025-01-07T17:30:41.504Z,1736271041.504 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-01-07T17:30:41.536Z,1736271041.536 [Onboard] Loaded
2025-01-07T17:30:41.537Z,1736271041.537 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-01-07T17:30:41.577Z,1736271041.577 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409764E0
2025-01-07T17:30:41.577Z,1736271041.577 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1090
2025-01-07T17:30:41.605Z,1736271041.605 [Power24vConverter] Loaded
2025-01-07T17:30:41.605Z,1736271041.605 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-01-07T17:30:41.630Z,1736271041.630 [Radio_Surface] Loaded
2025-01-07T17:30:41.630Z,1736271041.630 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-01-07T17:30:41.631Z,1736271041.631 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A64E0
2025-01-07T17:30:41.631Z,1736271041.631 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1091
2025-01-07T17:30:41.661Z,1736271041.661 [Sonardyne_Nano] Loaded
2025-01-07T17:30:41.662Z,1736271041.662 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-01-07T17:30:41.662Z,1736271041.662 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-01-07T17:30:41.663Z,1736271041.663 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-01-07T17:30:41.715Z,1736271041.715 [DepthRateCalculator] Loaded
2025-01-07T17:30:41.715Z,1736271041.715 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-01-07T17:30:41.720Z,1736271041.720 [PitchRateCalculator] Loaded
2025-01-07T17:30:41.720Z,1736271041.720 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-01-07T17:30:41.731Z,1736271041.731 [SpeedCalculator] Loaded
2025-01-07T17:30:41.731Z,1736271041.731 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-01-07T17:30:41.750Z,1736271041.750 [YawRateCalculator] Loaded
2025-01-07T17:30:41.750Z,1736271041.750 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-01-07T17:30:41.828Z,1736271041.828 [ElevatorOffsetCalculator] Loaded
2025-01-07T17:30:41.828Z,1736271041.828 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-01-07T17:30:41.829Z,1736271041.829 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-01-07T17:30:41.829Z,1736271041.829 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-01-07T17:30:42.268Z,1736271042.268 [CANONSampler] Loaded
2025-01-07T17:30:42.268Z,1736271042.268 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-01-07T17:30:42.331Z,1736271042.331 [CTD_Seabird] Loaded
2025-01-07T17:30:42.332Z,1736271042.332 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-01-07T17:30:42.333Z,1736271042.333 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A624E0
2025-01-07T17:30:42.333Z,1736271042.333 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1092
2025-01-07T17:30:42.356Z,1736271042.356 [PAR_Licor] Loaded
2025-01-07T17:30:42.356Z,1736271042.356 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-01-07T17:30:42.402Z,1736271042.402 [WetLabsBB2FL] Loaded
2025-01-07T17:30:42.402Z,1736271042.402 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-01-07T17:30:42.403Z,1736271042.403 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A924E0
2025-01-07T17:30:42.404Z,1736271042.404 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1093
2025-01-07T17:30:42.405Z,1736271042.405 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-01-07T17:30:42.405Z,1736271042.405 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-01-07T17:30:42.431Z,1736271042.431 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-01-07T17:30:42.432Z,1736271042.432 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-01-07T17:30:42.963Z,1736271042.963 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-01-07T17:30:42.963Z,1736271042.963 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-01-07T17:30:43.108Z,1736271043.108 [SBIT](DEBUG): Construct Startup Built In Test.
2025-01-07T17:30:43.117Z,1736271043.117 [SBIT] Loaded
2025-01-07T17:30:43.117Z,1736271043.117 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-01-07T17:30:43.120Z,1736271043.120 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-01-07T17:30:43.132Z,1736271043.132 [IBIT] Loaded
2025-01-07T17:30:43.133Z,1736271043.133 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-01-07T17:30:43.139Z,1736271043.139 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-01-07T17:30:43.247Z,1736271043.247 [CBIT] Loaded
2025-01-07T17:30:43.247Z,1736271043.247 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-01-07T17:30:43.248Z,1736271043.248 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-01-07T17:30:43.248Z,1736271043.248 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-01-07T17:30:43.383Z,1736271043.383 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-01-07T17:30:43.390Z,1736271043.390 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-01-07T17:30:43.393Z,1736271043.393 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-01-07T17:30:43.404Z,1736271043.404 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-01-07T17:30:43.405Z,1736271043.405 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF14E0
2025-01-07T17:30:43.406Z,1736271043.406 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1094
2025-01-07T17:30:43.410Z,1736271043.410 [Supervisor](INFO): Main Thread ID is 829
2025-01-07T17:30:43.410Z,1736271043.410 [Supervisor](DEBUG): Running supervisor.
2025-01-07T17:30:43.411Z,1736271043.411 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1095
2025-01-07T17:30:43.411Z,1736271043.411 [CommandExec](INFO): Initializing the command executive.
2025-01-07T17:30:43.413Z,1736271043.413 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1096
2025-01-07T17:30:43.415Z,1736271043.415 [controlThread ThreadHandler](INFO): Handler Thread ID is 1097
2025-01-07T17:30:43.416Z,1736271043.416 [controlThread](DEBUG): Initializing ControlThread
2025-01-07T17:30:43.416Z,1736271043.416 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-01-07T17:30:43.418Z,1736271043.418 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-01-07T17:30:43.419Z,1736271043.419 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-01-07T17:30:43.419Z,1736271043.419 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-01-07T17:30:43.421Z,1736271043.421 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-01-07T17:30:43.421Z,1736271043.421 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-01-07T17:30:43.428Z,1736271043.428 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-01-07T17:30:43.428Z,1736271043.428 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-01-07T17:30:43.429Z,1736271043.429 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-01-07T17:30:43.429Z,1736271043.429 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-01-07T17:30:43.429Z,1736271043.429 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-01-07T17:30:43.434Z,1736271043.434 [SBIT](INFO): Initialize SBIT Component.
2025-01-07T17:30:43.435Z,1736271043.435 [SBIT](IMPORTANT): git: 2025-01-07
2025-01-07T17:30:43.435Z,1736271043.435 [SBIT](INFO): git hash: f0eb87dde8dcff876b8e11720932f17a67afa618
2025-01-07T17:30:43.435Z,1736271043.435 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-01-07T17:30:43.436Z,1736271043.436 [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-01-07T17:30:43.438Z,1736271043.438 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2025-01-07T17:30:43.438Z,1736271043.438 [IBIT](INFO): Initialize IBIT Component.
2025-01-07T17:30:43.439Z,1736271043.439 [CBIT](DEBUG): Initialize CBIT Component.
2025-01-07T17:30:43.440Z,1736271043.440 [logger ThreadHandler](INFO): Handler Thread ID is 1098
2025-01-07T17:30:43.450Z,1736271043.450 [CBIT](DEBUG): Initialized mux pins.
2025-01-07T17:30:43.450Z,1736271043.450 [CBIT](DEBUG): Initializing the watchdog timer.
2025-01-07T17:30:43.459Z,1736271043.459 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1099
2025-01-07T17:30:43.460Z,1736271043.460 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-01-07T17:30:43.471Z,1736271043.471 [Onboard ThreadHandler](INFO): Handler Thread ID is 1100
2025-01-07T17:30:43.475Z,1736271043.475 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-01-07T17:30:43.475Z,1736271043.475 [CBIT](DEBUG): Initializing heartbeat.
2025-01-07T17:30:43.491Z,1736271043.491 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1101
2025-01-07T17:30:43.503Z,1736271043.503 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1102
2025-01-07T17:30:43.503Z,1736271043.503 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-01-07T17:30:43.508Z,1736271043.508 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1104
2025-01-07T17:30:43.511Z,1736271043.511 [WetLabsBB2FL](INFO): Powering up
2025-01-07T17:30:43.512Z,1736271043.512 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1106
2025-01-07T17:30:43.516Z,1736271043.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-01-07T17:30:43.516Z,1736271043.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-01-07T17:30:43.516Z,1736271043.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-01-07T17:30:43.516Z,1736271043.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-01-07T17:30:43.516Z,1736271043.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-01-07T17:30:43.516Z,1736271043.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-01-07T17:30:43.517Z,1736271043.517 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-01-07T17:30:43.517Z,1736271043.517 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-01-07T17:30:43.546Z,1736271043.546 [CBIT](DEBUG): Deactivating GF circuits.
2025-01-07T17:30:43.546Z,1736271043.546 [CBIT](DEBUG): Deactivating emergency mode.
2025-01-07T17:30:43.586Z,1736271043.586 [CBIT](DEBUG): Backplane powered.
2025-01-07T17:30:43.587Z,1736271043.587 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-01-07T17:30:43.588Z,1736271043.588 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-01-07T17:30:43.588Z,1736271043.588 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-01-07T17:30:43.589Z,1736271043.589 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-01-07T17:30:43.643Z,1736271043.643 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-01-07T17:30:43.647Z,1736271043.647 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-01-07T17:30:43.692Z,1736271043.692 [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-01-07T17:30:43.692Z,1736271043.692 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-01-07T17:30:43.693Z,1736271043.693 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-01-07T17:30:43.694Z,1736271043.694 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-01-07T17:30:43.894Z,1736271043.894 [Radio_Surface](INFO): Powering up
2025-01-07T17:30:44.024Z,1736271044.024 [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-01-07T17:30:44.047Z,1736271044.047 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-01-07T17:30:44.048Z,1736271044.048 [Default:A.Wait](DEBUG): Construct Wait.
2025-01-07T17:30:44.056Z,1736271044.056 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-01-07T17:30:44.115Z,1736271044.115 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-01-07T17:30:44.118Z,1736271044.118 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-01-07T17:30:44.140Z,1736271044.140 [Default:E.Execute](DEBUG): Construct Execute.
2025-01-07T17:30:44.143Z,1736271044.143 [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-01-07T17:30:44.148Z,1736271044.148 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2025-01-07T17:30:44.170Z,1736271044.170 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-01-07T17:30:44.241Z,1736271044.241 [Power24vConverter](INFO): Powering up.
2025-01-07T17:30:44.241Z,1736271044.241 [Sonardyne_Nano](INFO): Initializing.
2025-01-07T17:30:44.254Z,1736271044.254 [CANONSampler](INFO): Powering down
2025-01-07T17:30:44.316Z,1736271044.316 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-01-07T17:30:44.322Z,1736271044.322 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-01-07T17:30:44.323Z,1736271044.323 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-01-07T17:30:44.330Z,1736271044.330 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-01-07T17:30:44.331Z,1736271044.331 [MassServo](DEBUG): Initializing EZServoServo.
2025-01-07T17:30:44.338Z,1736271044.338 [MassServo](DEBUG): Initializing MassServo.
2025-01-07T17:30:44.339Z,1736271044.339 [RudderServo](DEBUG): Initializing EZServoServo.
2025-01-07T17:30:44.347Z,1736271044.347 [RudderServo](DEBUG): Initializing RudderServo.
2025-01-07T17:30:44.348Z,1736271044.348 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-01-07T17:30:44.354Z,1736271044.354 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-01-07T17:30:46.122Z,1736271046.122 [WetLabsBB2FL](INFO): Powering down
2025-01-07T17:30:46.177Z,1736271046.177 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-01-07T17:30:47.462Z,1736271047.462 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2025-01-07T17:30:50.183Z,1736271050.183 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-01-07T17:31:11.680Z,1736271071.680 [NAL9602](INFO): Powering up NAL9602
2025-01-07T17:31:22.584Z,1736271082.584 [NAL9602](INFO): NAL9602 initialized
2025-01-07T17:31:26.600Z,1736271086.600 [CommandExec](IMPORTANT): got command strobe off
2025-01-07T17:31:26.600Z,1736271086.600 [CommandExec](IMPORTANT): Deactivating strobe
2025-01-07T17:31:33.112Z,1736271093.112 [SBIT](IMPORTANT): Beginning Startup BIT
2025-01-07T17:31:33.144Z,1736271093.144 [CBIT](IMPORTANT): Beginning ground fault scan
2025-01-07T17:31:39.022Z,1736271099.022 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-01-07T17:31:39.023Z,1736271099.023 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 91.000000 %
2025-01-07T17:31:44.026Z,1736271104.026 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002627
CHAN A1 (24V): 0.000668
CHAN A2 (12V): -0.001976
CHAN A3 (5V): -0.001413
CHAN B0 (3.3V): -0.000536
CHAN B1 (3.15aV): -0.000176
CHAN B2 (3.15bV): -0.000123
CHAN B3 (GND): 0.000114
OPEN: -0.000289
Full Scale: +/- 1 mA
2025-01-07T17:31:49.531Z,1736271109.531 [CommandExec](IMPORTANT): got command failComponent
2025-01-07T17:31:49.531Z,1736271109.531 [CommandExec](IMPORTANT): Failed components:
2025-01-07T17:31:49.531Z,1736271109.531 [CommandExec](IMPORTANT): No failed Components.
2025-01-07T17:32:27.296Z,1736271147.296 [SBIT](IMPORTANT): SBIT PASSED
2025-01-07T17:32:27.297Z,1736271147.297 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-01-07T17:32:27.297Z,1736271147.297 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;
2025-01-07T17:32:27.297Z,1736271147.297 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2025-01-07T17:32:27.298Z,1736271147.298 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2025-01-07T17:32:27.298Z,1736271147.298 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-01-07T17:32:27.298Z,1736271147.298 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2025-01-07T17:32:27.298Z,1736271147.298 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-01-07T17:32:27.298Z,1736271147.298 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=292.731903 cubic_centimeter;
2025-01-07T17:32:27.298Z,1736271147.298 [SBIT](IMPORTANT): VerticalControl.massDefault=7.465234 millimeter;
2025-01-07T17:32:27.708Z,1736271147.708 [MissionManager](IMPORTANT): Started mission Startup
2025-01-07T17:32:27.708Z,1736271147.708 [Startup] Running Loop=1
2025-01-07T17:32:27.708Z,1736271147.708 [Startup](DEBUG): Aggregate::initialize Startup
2025-01-07T17:32:27.708Z,1736271147.708 [Startup:A.GoToSurface] Running Loop=1
2025-01-07T17:32:27.708Z,1736271147.708 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-07T17:32:27.709Z,1736271147.709 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-01-07T17:32:27.709Z,1736271147.709 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-01-07T17:32:27.710Z,1736271147.710 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-01-07T17:32:27.710Z,1736271147.710 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-01-07T17:32:27.711Z,1736271147.711 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-07T17:32:27.711Z,1736271147.711 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-07T17:32:27.713Z,1736271147.713 [Startup:StartupSatComms] Running Loop=1
2025-01-07T17:32:27.713Z,1736271147.713 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-01-07T17:32:27.713Z,1736271147.713 [Startup:StartupSatComms:A] Running Loop=1
2025-01-07T17:32:28.093Z,1736271148.093 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-01-07T17:33:25.316Z,1736271205.316 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004706
2025-01-07T17:33:27.888Z,1736271207.888 [Startup:StartupSatComms:A](INFO): Timed out from 2025-01-07T17:32:27.7Z
2025-01-07T17:33:27.888Z,1736271207.888 [Startup:StartupSatComms:A] Stopped
2025-01-07T17:33:27.888Z,1736271207.888 [Startup:StartupSatComms:B] Running Loop=1
2025-01-07T17:33:28.285Z,1736271208.285 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-01-07T17:33:28.687Z,1736271208.687 [Startup:StartupSatComms:B] Stopped
2025-01-07T17:33:28.688Z,1736271208.688 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-01-07T17:33:28.688Z,1736271208.688 [Startup:StartupSatComms] Stopped
2025-01-07T17:33:28.688Z,1736271208.688 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-01-07T17:33:28.689Z,1736271208.689 [Startup](INFO): Completed Startup
2025-01-07T17:33:28.689Z,1736271208.689 [MissionManager](INFO): Startup is completed.
2025-01-07T17:33:28.689Z,1736271208.689 [MissionManager](INFO): Uninitializing Mission Startup
2025-01-07T17:33:28.689Z,1736271208.689 [Startup] Stopped
2025-01-07T17:33:28.689Z,1736271208.689 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-01-07T17:33:28.689Z,1736271208.689 [Startup:A.GoToSurface] Stopped
2025-01-07T17:33:28.689Z,1736271208.689 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-01-07T17:33:29.096Z,1736271209.096 [MissionManager](IMPORTANT): Started mission Default
2025-01-07T17:33:29.096Z,1736271209.096 [Default] Running Loop=1
2025-01-07T17:33:29.096Z,1736271209.096 [Default](DEBUG): Aggregate::initialize Default
2025-01-07T17:33:29.096Z,1736271209.096 [Default:B.GoToSurface] Running Loop=1
2025-01-07T17:33:29.096Z,1736271209.096 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-01-07T17:33:29.097Z,1736271209.097 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-01-07T17:33:29.097Z,1736271209.097 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-01-07T17:33:29.097Z,1736271209.097 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-01-07T17:33:29.098Z,1736271209.098 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-01-07T17:33:29.098Z,1736271209.098 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-01-07T17:33:29.098Z,1736271209.098 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-01-07T17:33:29.099Z,1736271209.099 [Default:A.Wait] Running Loop=1
2025-01-07T17:33:29.099Z,1736271209.099 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-01-07T17:33:42.449Z,1736271222.449 [Default:A.Wait](INFO): Done Waiting.
2025-01-07T17:33:42.449Z,1736271222.449 [Default:A.Wait] Stopped
2025-01-07T17:33:42.449Z,1736271222.449 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T17:33:42.837Z,1736271222.837 [Default:CheckIn] Running Loop=1
2025-01-07T17:33:42.837Z,1736271222.837 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T17:33:42.837Z,1736271222.837 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T17:33:43.243Z,1736271223.243 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-01-07T17:33:43.669Z,1736271223.669 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-01-07T17:33:43.669Z,1736271223.669 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:33:43.680Z,1736271223.680 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:33:44.052Z,1736271224.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:33:44.052Z,1736271224.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-01-07T17:36:26.456Z,1736271386.456 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-01-07T17:36:44.644Z,1736271404.644 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-01-07T17:36:44.644Z,1736271404.644 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:36:44.686Z,1736271404.686 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:36:45.056Z,1736271405.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:36:45.056Z,1736271405.056 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-01-07T17:36:47.470Z,1736271407.470 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:37:00.802Z,1736271420.802 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:37:14.527Z,1736271434.527 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:37:28.663Z,1736271448.663 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:37:40.919Z,1736271460.919 [CommandExec](IMPORTANT): got command burn on
2025-01-07T17:37:40.919Z,1736271460.919 [CommandExec](IMPORTANT): Activating dropweight wire
2025-01-07T17:37:41.995Z,1736271461.995 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:37:55.731Z,1736271475.731 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:38:08.852Z,1736271488.852 [DataOverHttps](IMPORTANT): SBD MTMSN=20250107T173807
2025-01-07T17:38:09.872Z,1736271489.872 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:38:16.082Z,1736271496.082 [DataOverHttps](INFO): Received command: sched 20250107T1740 "burn off"
2025-01-07T17:38:16.110Z,1736271496.110 [CommandExec](IMPORTANT): got command schedule 20250107T174000 "burn off"
2025-01-07T17:38:16.111Z,1736271496.111 [CommandExec](IMPORTANT): Scheduled #1: "burn off", AT TIME: 2025-01-07T17:40:00Z
2025-01-07T17:38:23.203Z,1736271503.203 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:38:36.939Z,1736271516.939 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:38:43.052Z,1736271523.052 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-01-07T17:33:42.8Z
2025-01-07T17:38:43.052Z,1736271523.052 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T17:38:43.052Z,1736271523.052 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T17:38:43.449Z,1736271523.449 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-01-07T17:38:45.816Z,1736271525.816 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250107T173032/Courier0000.lzma
2025-01-07T17:38:46.648Z,1736271526.648 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0000.lzma.bak
2025-01-07T17:38:46.648Z,1736271526.648 [DataOverHttps](INFO): SBD MOMSN=23882353
2025-01-07T17:38:51.127Z,1736271531.127 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:39:02.198Z,1736271542.198 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250107T173032/Courier0004.lzma
2025-01-07T17:39:03.200Z,1736271543.200 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0004.lzma.bak
2025-01-07T17:39:03.200Z,1736271543.200 [DataOverHttps](INFO): SBD MOMSN=23882356
2025-01-07T17:39:05.267Z,1736271545.267 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-01-07T17:39:18.629Z,1736271558.629 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250107T173032/Courier0007.lzma
2025-01-07T17:39:19.632Z,1736271559.632 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0007.lzma.bak
2025-01-07T17:39:19.632Z,1736271559.632 [DataOverHttps](INFO): SBD MOMSN=23882358
2025-01-07T17:39:35.271Z,1736271575.271 [DataOverHttps](INFO): Sending 986 bytes from file Logs/20250107T173032/Express0001.lzma
2025-01-07T17:39:36.272Z,1736271576.272 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0001.lzma.bak
2025-01-07T17:39:36.272Z,1736271576.272 [DataOverHttps](INFO): SBD MOMSN=23882360
2025-01-07T17:39:45.683Z,1736271585.683 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-01-07T17:39:45.683Z,1736271585.683 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:39:45.694Z,1736271585.694 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:39:46.097Z,1736271586.097 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:39:46.097Z,1736271586.097 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-01-07T17:39:51.799Z,1736271591.799 [DataOverHttps](INFO): Sending 350 bytes from file Logs/20250107T173032/Express0005.lzma
2025-01-07T17:39:52.792Z,1736271592.792 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0005.lzma.bak
2025-01-07T17:39:52.792Z,1736271592.792 [DataOverHttps](INFO): SBD MOMSN=23882388
2025-01-07T17:40:00.087Z,1736271600.087 [CommandExec](IMPORTANT): got command burn off
2025-01-07T17:40:00.088Z,1736271600.088 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-01-07T17:40:07.162Z,1736271607.162 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250107T173032/Express0008.lzma
2025-01-07T17:40:08.164Z,1736271608.164 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0008.lzma.bak
2025-01-07T17:40:08.164Z,1736271608.164 [DataOverHttps](INFO): SBD MOMSN=23882397
2025-01-07T17:40:09.520Z,1736271609.520 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T17:40:09.520Z,1736271609.520 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T17:40:09.521Z,1736271609.521 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T17:40:45.060Z,1736271645.060 [Power24vConverter](INFO): Powering down.
2025-01-07T17:41:25.476Z,1736271685.476 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-01-07T17:41:25.476Z,1736271685.476 [NAL9602] Data Fault, FailCount= 1
2025-01-07T17:41:25.476Z,1736271685.476 [NAL9602](ERROR): Data Fault
2025-01-07T17:41:25.493Z,1736271685.493 [CBIT](ERROR): Data Fault in component: NAL9602
2025-01-07T17:41:25.871Z,1736271685.871 [NAL9602](INFO): Powering down
2025-01-07T17:41:26.708Z,1736271686.708 [CBIT](INFO): Clearing failed state for component NAL9602
2025-01-07T17:41:26.709Z,1736271686.709 [NAL9602] No Fault, FailCount= 1
2025-01-07T17:41:56.168Z,1736271716.168 [NAL9602](INFO): Powering up NAL9602
2025-01-07T17:42:07.076Z,1736271727.076 [NAL9602](INFO): NAL9602 initialized
2025-01-07T17:42:17.576Z,1736271737.576 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-01-07T17:42:46.676Z,1736271766.676 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-01-07T17:42:46.676Z,1736271766.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:42:46.687Z,1736271766.687 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:42:47.096Z,1736271767.096 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:42:47.096Z,1736271767.096 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-01-07T17:45:10.096Z,1736271910.096 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T17:45:10.097Z,1736271910.097 [Default:CheckIn:C.Wait] Stopped
2025-01-07T17:45:10.097Z,1736271910.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T17:45:10.097Z,1736271910.097 [Default:CheckIn:D] Running Loop=1
2025-01-07T17:45:10.500Z,1736271910.500 [Default:CheckIn:D] Stopped
2025-01-07T17:45:10.500Z,1736271910.500 [Default:CheckIn:E] Running Loop=1
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.690057 min
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn:E] Stopped
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn] Stopped
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn](INFO): Running loop #2
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn] Running Loop=2
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T17:45:10.925Z,1736271910.925 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T17:45:12.294Z,1736271912.294 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:45:43.418Z,1736271943.418 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:45:47.666Z,1736271947.666 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-01-07T17:45:47.666Z,1736271947.666 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:45:47.700Z,1736271947.700 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:45:48.112Z,1736271948.112 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:45:48.112Z,1736271948.112 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-01-07T17:46:14.554Z,1736271974.554 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:46:22.430Z,1736271982.430 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:46:36.979Z,1736271996.979 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:46:50.335Z,1736272010.335 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:47:03.695Z,1736272023.695 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:47:17.028Z,1736272037.028 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:47:30.764Z,1736272050.764 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:47:44.095Z,1736272064.095 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:47:57.427Z,1736272077.427 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:48:10.759Z,1736272090.759 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:48:24.127Z,1736272104.127 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:48:37.463Z,1736272117.463 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:48:48.787Z,1736272128.787 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-01-07T17:48:48.787Z,1736272128.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:48:48.798Z,1736272128.798 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:48:49.215Z,1736272129.215 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:48:49.215Z,1736272129.215 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-01-07T17:50:11.249Z,1736272211.249 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-01-07T17:45:10.9Z
2025-01-07T17:50:11.249Z,1736272211.249 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T17:50:11.249Z,1736272211.249 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T17:50:19.286Z,1736272219.286 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250107T173032/Courier0010.lzma
2025-01-07T17:50:20.288Z,1736272220.288 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0010.lzma.bak
2025-01-07T17:50:20.288Z,1736272220.288 [DataOverHttps](INFO): SBD MOMSN=23882418
2025-01-07T17:50:37.886Z,1736272237.886 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20250107T173032/Express0011.lzma
2025-01-07T17:50:38.888Z,1736272238.888 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0011.lzma.bak
2025-01-07T17:50:38.888Z,1736272238.888 [DataOverHttps](INFO): SBD MOMSN=23882420
2025-01-07T17:50:39.986Z,1736272239.986 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T17:50:39.986Z,1736272239.986 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T17:50:39.000Z,1736272240.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T17:51:49.888Z,1736272309.888 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-01-07T17:51:49.888Z,1736272309.888 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:51:49.899Z,1736272309.899 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:51:50.300Z,1736272310.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:51:50.300Z,1736272310.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-01-07T17:52:10.072Z,1736272330.072 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-01-07T17:52:10.072Z,1736272330.072 [NAL9602] Data Fault, FailCount= 2
2025-01-07T17:52:10.072Z,1736272330.072 [NAL9602](ERROR): Data Fault
2025-01-07T17:52:10.089Z,1736272330.089 [CBIT](ERROR): Data Fault in component: NAL9602
2025-01-07T17:52:10.476Z,1736272330.476 [NAL9602](INFO): Powering down
2025-01-07T17:52:11.288Z,1736272331.288 [CBIT](INFO): Clearing failed state for component NAL9602
2025-01-07T17:52:11.288Z,1736272331.288 [NAL9602] No Fault, FailCount= 2
2025-01-07T17:52:40.757Z,1736272360.757 [NAL9602](INFO): Powering up NAL9602
2025-01-07T17:52:51.696Z,1736272371.696 [NAL9602](INFO): NAL9602 initialized
2025-01-07T17:54:50.973Z,1736272490.973 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-01-07T17:54:50.973Z,1736272490.973 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:54:51.045Z,1736272491.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:54:51.369Z,1736272491.369 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:54:51.369Z,1736272491.369 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-01-07T17:55:40.601Z,1736272540.601 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T17:55:40.601Z,1736272540.601 [Default:CheckIn:C.Wait] Stopped
2025-01-07T17:55:40.601Z,1736272540.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T17:55:40.601Z,1736272540.601 [Default:CheckIn:D] Running Loop=1
2025-01-07T17:55:40.984Z,1736272540.984 [Default:CheckIn:D] Stopped
2025-01-07T17:55:40.984Z,1736272540.984 [Default:CheckIn:E] Running Loop=1
2025-01-07T17:55:41.384Z,1736272541.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.198132 min
2025-01-07T17:55:41.384Z,1736272541.384 [Default:CheckIn:E] Stopped
2025-01-07T17:55:41.384Z,1736272541.384 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T17:55:41.385Z,1736272541.385 [Default:CheckIn] Stopped
2025-01-07T17:55:41.385Z,1736272541.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T17:55:41.385Z,1736272541.385 [Default:CheckIn](INFO): Running loop #3
2025-01-07T17:55:41.385Z,1736272541.385 [Default:CheckIn] Running Loop=3
2025-01-07T17:55:41.385Z,1736272541.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T17:55:41.385Z,1736272541.385 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T17:55:56.319Z,1736272556.319 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:56:09.651Z,1736272569.651 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:56:23.387Z,1736272583.387 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:56:36.720Z,1736272596.720 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:56:44.222Z,1736272604.222 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:56:50.051Z,1736272610.051 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:57:03.787Z,1736272623.787 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:57:15.346Z,1736272635.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:57:16.715Z,1736272636.715 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:57:30.047Z,1736272650.047 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:57:43.783Z,1736272663.783 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:57:46.470Z,1736272666.470 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:57:51.891Z,1736272671.891 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-01-07T17:57:51.891Z,1736272671.891 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T17:57:51.927Z,1736272671.927 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T17:57:52.312Z,1736272672.312 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T17:57:52.312Z,1736272672.312 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-01-07T17:57:58.327Z,1736272678.327 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T17:58:12.887Z,1736272692.887 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-01-07T17:58:17.602Z,1736272697.602 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:58:48.738Z,1736272728.738 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T17:58:51.751Z,1736272731.751 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.751526
2025-01-07T17:59:49.987Z,1736272789.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T18:00:21.131Z,1736272821.131 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T18:00:41.604Z,1736272841.604 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-01-07T17:55:41.4Z
2025-01-07T18:00:41.604Z,1736272841.604 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:00:41.604Z,1736272841.604 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:00:52.254Z,1736272852.254 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T18:00:52.907Z,1736272852.907 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-01-07T18:00:52.907Z,1736272852.907 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T18:00:52.941Z,1736272852.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T18:00:53.300Z,1736272853.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T18:00:53.300Z,1736272853.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-01-07T18:01:23.378Z,1736272883.378 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T18:01:44.584Z,1736272904.584 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4.
2025-01-07T18:01:44.587Z,1736272904.587 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-01-07T18:01:54.502Z,1736272914.502 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T18:02:25.626Z,1736272945.626 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T18:02:42.874Z,1736272962.874 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007109
2025-01-07T18:02:50.170Z,1736272970.170 [NAL9602](INFO): SBD MO Status=1, MOMSN=61250, MT Status=0, MTMSN=0
2025-01-07T18:02:50.223Z,1736272970.223 [NAL9602](INFO): Sent 39 bytes from file Logs/20250107T173032/Courier0013.lzma
2025-01-07T18:02:50.223Z,1736272970.223 [NAL9602](INFO): Packets left to send: 0
2025-01-07T18:02:51.658Z,1736272971.658 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250107T173032/Express0014.lzma
2025-01-07T18:02:52.660Z,1736272972.660 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0014.lzma.bak
2025-01-07T18:02:52.660Z,1736272972.660 [DataOverHttps](INFO): SBD MOMSN=23882444
2025-01-07T18:02:53.744Z,1736272973.744 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-01-07T18:02:53.744Z,1736272973.744 [NAL9602] Data Fault, FailCount= 3
2025-01-07T18:02:53.744Z,1736272973.744 [NAL9602](ERROR): Data Fault
2025-01-07T18:02:53.777Z,1736272973.777 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:02:53.777Z,1736272973.777 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:02:53.777Z,1736272973.777 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:02:53.795Z,1736272973.795 [CBIT](ERROR): Data Fault in component: NAL9602
2025-01-07T18:02:54.147Z,1736272974.147 [NAL9602](INFO): Powering down
2025-01-07T18:02:54.995Z,1736272974.995 [CBIT](INFO): Clearing failed state for component NAL9602
2025-01-07T18:02:54.995Z,1736272974.995 [NAL9602] No Fault, FailCount= 3
2025-01-07T18:03:24.456Z,1736273004.456 [NAL9602](INFO): Powering up NAL9602
2025-01-07T18:03:35.364Z,1736273015.364 [NAL9602](INFO): NAL9602 initialized
2025-01-07T18:03:53.951Z,1736273033.951 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-01-07T18:03:53.951Z,1736273033.951 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T18:03:53.962Z,1736273033.962 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T18:03:54.400Z,1736273034.400 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T18:03:54.400Z,1736273034.400 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-01-07T18:06:54.971Z,1736273214.971 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-01-07T18:06:54.971Z,1736273214.971 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-01-07T18:06:54.992Z,1736273214.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-01-07T18:06:55.419Z,1736273215.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-01-07T18:06:55.419Z,1736273215.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-01-07T18:07:28.882Z,1736273248.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180727.00,A,3648.15991,N,12147.28229,W,0.019,0.00,070125,,,A*70
2025-01-07T18:07:28.893Z,1736273248.893 [NAL9602](INFO): GPS fix at 20250107T180727: (36.802665, -121.788038)
2025-01-07T18:07:54.350Z,1736273274.350 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:07:54.354Z,1736273274.354 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:07:54.354Z,1736273274.354 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:07:54.354Z,1736273274.354 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:07:54.759Z,1736273274.759 [Default:CheckIn:D] Stopped
2025-01-07T18:07:54.759Z,1736273274.759 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.427710 min
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn:E] Stopped
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn] Stopped
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn](INFO): Running loop #4
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn] Running Loop=4
2025-01-07T18:07:55.163Z,1736273275.163 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:07:55.164Z,1736273275.164 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:07:57.174Z,1736273277.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180755.00,A,3648.16381,N,12147.28398,W,0.253,0.00,070125,,,A*7A
2025-01-07T18:07:57.177Z,1736273277.177 [NAL9602](INFO): GPS fix at 20250107T180755: (36.802730, -121.788066)
2025-01-07T18:07:57.193Z,1736273277.193 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:07:57.193Z,1736273277.193 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:08:03.550Z,1736273283.550 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20250107T173032/Courier0016.lzma
2025-01-07T18:08:04.552Z,1736273284.552 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0016.lzma.bak
2025-01-07T18:08:04.552Z,1736273284.552 [DataOverHttps](INFO): SBD MOMSN=23882456
2025-01-07T18:08:22.470Z,1736273302.470 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20250107T173032/Express0017.lzma
2025-01-07T18:08:23.472Z,1736273303.472 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0017.lzma.bak
2025-01-07T18:08:23.472Z,1736273303.472 [DataOverHttps](INFO): SBD MOMSN=23882459
2025-01-07T18:08:24.693Z,1736273304.693 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:08:24.693Z,1736273304.693 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:08:24.693Z,1736273304.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:08:29.511Z,1736273309.511 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T18:13:06.543Z,1736273586.543 [CommandExec](IMPORTANT): got command failComponent
2025-01-07T18:13:06.544Z,1736273586.544 [CommandExec](IMPORTANT): Failed components:
2025-01-07T18:13:06.544Z,1736273586.544 [CommandExec](IMPORTANT): No failed Components.
2025-01-07T18:13:09.251Z,1736273589.251 [CommandExec](IMPORTANT): got command gfscan
2025-01-07T18:13:09.519Z,1736273589.519 [CBIT](IMPORTANT): Beginning ground fault scan
2025-01-07T18:13:12.332Z,1736273592.332 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2025-01-07T18:13:20.418Z,1736273600.418 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002759
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002113
CHAN A3 (5V): -0.001504
CHAN B0 (3.3V): -0.000258
CHAN B1 (3.15aV): -0.000169
CHAN B2 (3.15bV): -0.000089
CHAN B3 (GND): 0.000206
OPEN: -0.000653
Full Scale: +/- 1 mA
2025-01-07T18:13:25.268Z,1736273605.268 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:13:25.269Z,1736273605.269 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:13:25.269Z,1736273605.269 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:13:25.269Z,1736273605.269 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:13:25.661Z,1736273605.661 [Default:CheckIn:D] Stopped
2025-01-07T18:13:25.662Z,1736273605.662 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:13:26.062Z,1736273606.062 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.942753 min
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn:E] Stopped
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn] Stopped
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn](INFO): Running loop #5
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn] Running Loop=5
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:13:26.063Z,1736273606.063 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:13:28.073Z,1736273608.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181327.00,A,3648.15961,N,12147.28592,W,0.622,185.91,070125,,,A*77
2025-01-07T18:13:28.075Z,1736273608.075 [NAL9602](INFO): GPS fix at 20250107T181327: (36.802660, -121.788099)
2025-01-07T18:13:28.114Z,1736273608.114 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:13:28.114Z,1736273608.114 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:13:35.097Z,1736273615.097 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0019.lzma
2025-01-07T18:13:36.100Z,1736273616.100 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0019.lzma.bak
2025-01-07T18:13:36.101Z,1736273616.101 [DataOverHttps](INFO): SBD MOMSN=23882475
2025-01-07T18:13:51.902Z,1736273631.902 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20250107T173032/Express0020.lzma
2025-01-07T18:13:52.904Z,1736273632.904 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0020.lzma.bak
2025-01-07T18:13:52.904Z,1736273632.904 [DataOverHttps](INFO): SBD MOMSN=23882478
2025-01-07T18:13:53.939Z,1736273633.939 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:13:53.939Z,1736273633.939 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:13:53.939Z,1736273633.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:18:30.264Z,1736273910.264 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-01-07T18:18:54.545Z,1736273934.545 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:18:54.545Z,1736273934.545 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:18:54.545Z,1736273934.545 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:18:54.546Z,1736273934.546 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:18:54.926Z,1736273934.926 [Default:CheckIn:D] Stopped
2025-01-07T18:18:54.926Z,1736273934.926 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:18:55.347Z,1736273935.347 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.430489 min
2025-01-07T18:18:55.347Z,1736273935.347 [Default:CheckIn:E] Stopped
2025-01-07T18:18:55.348Z,1736273935.348 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:18:55.348Z,1736273935.348 [Default:CheckIn] Stopped
2025-01-07T18:18:55.348Z,1736273935.348 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:18:55.348Z,1736273935.348 [Default:CheckIn](INFO): Running loop #6
2025-01-07T18:18:55.348Z,1736273935.348 [Default:CheckIn] Running Loop=6
2025-01-07T18:18:55.348Z,1736273935.348 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:18:55.348Z,1736273935.348 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:18:57.333Z,1736273937.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181856.00,A,3648.16561,N,12147.28403,W,0.486,100.11,070125,,,A*75
2025-01-07T18:18:57.339Z,1736273937.339 [NAL9602](INFO): GPS fix at 20250107T181856: (36.802760, -121.788067)
2025-01-07T18:18:57.352Z,1736273937.352 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:18:57.352Z,1736273937.352 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:19:04.641Z,1736273944.641 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250107T173032/Courier0022.lzma
2025-01-07T18:19:05.644Z,1736273945.644 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0022.lzma.bak
2025-01-07T18:19:05.644Z,1736273945.644 [DataOverHttps](INFO): SBD MOMSN=23882494
2025-01-07T18:19:21.334Z,1736273961.334 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250107T173032/Express0023.lzma
2025-01-07T18:19:22.336Z,1736273962.336 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0023.lzma.bak
2025-01-07T18:19:22.336Z,1736273962.336 [DataOverHttps](INFO): SBD MOMSN=23882497
2025-01-07T18:19:23.600Z,1736273963.600 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:19:23.600Z,1736273963.600 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:19:23.600Z,1736273963.600 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:19:29.251Z,1736273969.251 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T18:21:41.454Z,1736274101.454 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 292 cubic_centimeter
2025-01-07T18:21:41.466Z,1736274101.466 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2025-01-07T18:21:41.804Z,1736274101.804 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2025-01-07T18:23:06.797Z,1736274186.797 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2025-01-07T18:23:06.798Z,1736274186.798 [CommandExec](IMPORTANT): platform_buoyancy_position 301.224823 cc
2025-01-07T18:23:24.671Z,1736274204.671 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter
2025-01-07T18:23:39.880Z,1736274219.880 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position
2025-01-07T18:23:40.200Z,1736274220.200 [Reporter](INFO): platform_buoyancy_position 127.172028 cc
2025-01-07T18:23:40.609Z,1736274220.609 [Reporter](INFO): platform_buoyancy_position 122.874405 cc
2025-01-07T18:23:41.025Z,1736274221.025 [Reporter](INFO): platform_buoyancy_position 116.562309 cc
2025-01-07T18:23:41.420Z,1736274221.420 [Reporter](INFO): platform_buoyancy_position 111.996117 cc
2025-01-07T18:23:41.917Z,1736274221.917 [Reporter](INFO): platform_buoyancy_position 107.698509 cc
2025-01-07T18:24:24.192Z,1736274264.192 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:24:24.193Z,1736274264.193 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:24:24.193Z,1736274264.193 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:24:24.193Z,1736274264.193 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:24:24.598Z,1736274264.598 [Default:CheckIn:D] Stopped
2025-01-07T18:24:24.598Z,1736274264.598 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.925024 min
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn:E] Stopped
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn] Stopped
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn](INFO): Running loop #7
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn] Running Loop=7
2025-01-07T18:24:25.003Z,1736274265.003 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:24:25.004Z,1736274265.004 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:24:27.009Z,1736274267.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182426.00,A,3648.16374,N,12147.28743,W,0.156,188.88,070125,,,A*70
2025-01-07T18:24:27.011Z,1736274267.011 [NAL9602](INFO): GPS fix at 20250107T182426: (36.802729, -121.788124)
2025-01-07T18:24:27.023Z,1736274267.023 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:24:27.023Z,1736274267.023 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:24:33.910Z,1736274273.910 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0025.lzma
2025-01-07T18:24:34.912Z,1736274274.912 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0025.lzma.bak
2025-01-07T18:24:34.912Z,1736274274.912 [DataOverHttps](INFO): SBD MOMSN=23882516
2025-01-07T18:24:53.460Z,1736274293.460 [DataOverHttps](INFO): Sending 277 bytes from file Logs/20250107T173032/Express0026.lzma
2025-01-07T18:24:54.460Z,1736274294.460 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0026.lzma.bak
2025-01-07T18:24:54.460Z,1736274294.460 [DataOverHttps](INFO): SBD MOMSN=23882519
2025-01-07T18:24:55.742Z,1736274295.742 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:24:55.750Z,1736274295.750 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:24:55.750Z,1736274295.750 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:24:57.752Z,1736274297.752 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-01-07T18:24:57.830Z,1736274297.830 [NAL9602](ERROR): received:
+CSQ:0
OK250, 0, 0, 0, 0
OK
2025-01-07T18:25:23.011Z,1736274323.011 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 40 cubic_centimeter
2025-01-07T18:25:25.397Z,1736274325.397 [Reporter](INFO): platform_buoyancy_position 96.954506 cc
2025-01-07T18:25:26.084Z,1736274326.084 [Reporter](INFO): platform_buoyancy_position 94.940010 cc
2025-01-07T18:25:26.504Z,1736274326.504 [Reporter](INFO): platform_buoyancy_position 92.656914 cc
2025-01-07T18:25:26.916Z,1736274326.916 [Reporter](INFO): platform_buoyancy_position 87.956413 cc
2025-01-07T18:25:27.312Z,1736274327.312 [Reporter](INFO): platform_buoyancy_position 81.912910 cc
2025-01-07T18:25:27.701Z,1736274327.701 [Reporter](INFO): platform_buoyancy_position 77.615303 cc
2025-01-07T18:25:28.120Z,1736274328.120 [Reporter](INFO): platform_buoyancy_position 73.317711 cc
2025-01-07T18:25:28.540Z,1736274328.540 [Reporter](INFO): platform_buoyancy_position 66.871315 cc
2025-01-07T18:25:28.945Z,1736274328.945 [Reporter](INFO): platform_buoyancy_position 62.573708 cc
2025-01-07T18:25:29.368Z,1736274329.368 [Reporter](INFO): platform_buoyancy_position 56.127312 cc
2025-01-07T18:25:29.878Z,1736274329.878 [Reporter](INFO): platform_buoyancy_position 49.815208 cc
2025-01-07T18:27:10.440Z,1736274430.440 [CommandExec](IMPORTANT): got command maintain clear
2025-01-07T18:27:10.724Z,1736274430.724 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2025-01-07T18:27:13.251Z,1736274433.251 [Reporter](INFO): platform_buoyancy_position 41.354301 cc
2025-01-07T18:27:13.568Z,1736274433.568 [Reporter](INFO): platform_buoyancy_position 41.220009 cc
2025-01-07T18:27:13.956Z,1736274433.956 [Reporter](INFO): platform_buoyancy_position 43.368813 cc
2025-01-07T18:27:14.363Z,1736274434.363 [Reporter](INFO): platform_buoyancy_position 45.517601 cc
2025-01-07T18:27:14.780Z,1736274434.780 [Reporter](INFO): platform_buoyancy_position 49.815208 cc
2025-01-07T18:27:15.182Z,1736274435.182 [Reporter](INFO): platform_buoyancy_position 54.112801 cc
2025-01-07T18:27:15.601Z,1736274435.601 [Reporter](INFO): platform_buoyancy_position 60.424904 cc
2025-01-07T18:27:16.008Z,1736274436.008 [Reporter](INFO): platform_buoyancy_position 66.065514 cc
2025-01-07T18:27:16.424Z,1736274436.424 [Reporter](INFO): platform_buoyancy_position 71.168907 cc
2025-01-07T18:27:16.815Z,1736274436.815 [Reporter](INFO): platform_buoyancy_position 75.466515 cc
2025-01-07T18:27:17.188Z,1736274437.188 [Reporter](INFO): platform_buoyancy_position 81.375710 cc
2025-01-07T18:27:17.592Z,1736274437.592 [Reporter](INFO): platform_buoyancy_position 86.210503 cc
2025-01-07T18:27:18.005Z,1736274438.005 [Reporter](INFO): platform_buoyancy_position 92.656914 cc
2025-01-07T18:27:18.420Z,1736274438.420 [Reporter](INFO): platform_buoyancy_position 96.954506 cc
2025-01-07T18:27:18.792Z,1736274438.792 [Reporter](INFO): platform_buoyancy_position 101.655006 cc
2025-01-07T18:27:19.207Z,1736274439.207 [Reporter](INFO): platform_buoyancy_position 107.832817 cc
2025-01-07T18:27:19.620Z,1736274439.620 [Reporter](INFO): platform_buoyancy_position 113.473412 cc
2025-01-07T18:27:20.020Z,1736274440.020 [Reporter](INFO): platform_buoyancy_position 118.576805 cc
2025-01-07T18:27:20.408Z,1736274440.408 [Reporter](INFO): platform_buoyancy_position 123.411621 cc
2025-01-07T18:27:20.852Z,1736274440.852 [Reporter](INFO): platform_buoyancy_position 129.186523 cc
2025-01-07T18:27:21.240Z,1736274441.240 [Reporter](INFO): platform_buoyancy_position 133.618408 cc
2025-01-07T18:27:21.656Z,1736274441.656 [Reporter](INFO): platform_buoyancy_position 139.930511 cc
2025-01-07T18:27:22.068Z,1736274442.068 [Reporter](INFO): platform_buoyancy_position 144.496704 cc
2025-01-07T18:27:22.433Z,1736274442.433 [Reporter](INFO): platform_buoyancy_position 150.674530 cc
2025-01-07T18:27:22.856Z,1736274442.856 [Reporter](INFO): platform_buoyancy_position 154.972107 cc
2025-01-07T18:27:23.272Z,1736274443.272 [Reporter](INFO): platform_buoyancy_position 161.418518 cc
2025-01-07T18:27:23.640Z,1736274443.640 [Reporter](INFO): platform_buoyancy_position 165.850433 cc
2025-01-07T18:27:24.052Z,1736274444.052 [Reporter](INFO): platform_buoyancy_position 170.282318 cc
2025-01-07T18:27:24.472Z,1736274444.472 [Reporter](INFO): platform_buoyancy_position 176.594421 cc
2025-01-07T18:27:24.868Z,1736274444.868 [Reporter](INFO): platform_buoyancy_position 181.429230 cc
2025-01-07T18:27:25.264Z,1736274445.264 [Reporter](INFO): platform_buoyancy_position 187.204132 cc
2025-01-07T18:27:25.672Z,1736274445.672 [Reporter](INFO): platform_buoyancy_position 191.904633 cc
2025-01-07T18:27:26.091Z,1736274446.091 [Reporter](INFO): platform_buoyancy_position 198.082428 cc
2025-01-07T18:27:26.504Z,1736274446.504 [Reporter](INFO): platform_buoyancy_position 202.380035 cc
2025-01-07T18:27:26.923Z,1736274446.923 [Reporter](INFO): platform_buoyancy_position 208.826416 cc
2025-01-07T18:27:27.312Z,1736274447.312 [Reporter](INFO): platform_buoyancy_position 213.124023 cc
2025-01-07T18:27:27.696Z,1736274447.696 [Reporter](INFO): platform_buoyancy_position 219.570435 cc
2025-01-07T18:27:28.112Z,1736274448.112 [Reporter](INFO): platform_buoyancy_position 223.868042 cc
2025-01-07T18:27:28.508Z,1736274448.508 [Reporter](INFO): platform_buoyancy_position 230.314423 cc
2025-01-07T18:27:28.904Z,1736274448.904 [Reporter](INFO): platform_buoyancy_position 234.477722 cc
2025-01-07T18:27:29.317Z,1736274449.317 [Reporter](INFO): platform_buoyancy_position 240.924133 cc
2025-01-07T18:27:29.712Z,1736274449.712 [Reporter](INFO): platform_buoyancy_position 245.356033 cc
2025-01-07T18:27:30.185Z,1736274450.185 [Reporter](INFO): platform_buoyancy_position 251.668121 cc
2025-01-07T18:27:30.516Z,1736274450.516 [Reporter](INFO): platform_buoyancy_position 255.965714 cc
2025-01-07T18:27:30.949Z,1736274450.949 [Reporter](INFO): platform_buoyancy_position 262.412140 cc
2025-01-07T18:27:31.392Z,1736274451.392 [Reporter](INFO): platform_buoyancy_position 266.844025 cc
2025-01-07T18:27:31.800Z,1736274451.800 [Reporter](INFO): platform_buoyancy_position 273.156128 cc
2025-01-07T18:27:32.203Z,1736274452.203 [Reporter](INFO): platform_buoyancy_position 277.588043 cc
2025-01-07T18:27:32.611Z,1736274452.611 [Reporter](INFO): platform_buoyancy_position 283.900146 cc
2025-01-07T18:27:32.992Z,1736274452.992 [Reporter](INFO): platform_buoyancy_position 288.197723 cc
2025-01-07T18:27:33.392Z,1736274453.392 [Reporter](INFO): platform_buoyancy_position 292.495331 cc
2025-01-07T18:27:33.799Z,1736274453.799 [Reporter](INFO): platform_buoyancy_position 299.076019 cc
2025-01-07T18:27:34.184Z,1736274454.184 [Reporter](INFO): platform_buoyancy_position 303.373627 cc
2025-01-07T18:27:34.620Z,1736274454.620 [Reporter](INFO): platform_buoyancy_position 309.820038 cc
2025-01-07T18:27:35.016Z,1736274455.016 [Reporter](INFO): platform_buoyancy_position 314.117645 cc
2025-01-07T18:27:35.436Z,1736274455.436 [Reporter](INFO): platform_buoyancy_position 318.415222 cc
2025-01-07T18:27:35.824Z,1736274455.824 [Reporter](INFO): platform_buoyancy_position 324.861633 cc
2025-01-07T18:27:36.199Z,1736274456.199 [Reporter](INFO): platform_buoyancy_position 329.159241 cc
2025-01-07T18:27:36.609Z,1736274456.609 [Reporter](INFO): platform_buoyancy_position 335.471344 cc
2025-01-07T18:27:37.004Z,1736274457.004 [Reporter](INFO): platform_buoyancy_position 340.037537 cc
2025-01-07T18:27:37.408Z,1736274457.408 [Reporter](INFO): platform_buoyancy_position 346.215332 cc
2025-01-07T18:27:37.816Z,1736274457.816 [Reporter](INFO): platform_buoyancy_position 351.318726 cc
2025-01-07T18:27:38.188Z,1736274458.188 [Reporter](INFO): platform_buoyancy_position 356.959351 cc
2025-01-07T18:27:38.612Z,1736274458.612 [Reporter](INFO): platform_buoyancy_position 361.256927 cc
2025-01-07T18:27:39.027Z,1736274459.027 [Reporter](INFO): platform_buoyancy_position 367.837646 cc
2025-01-07T18:27:39.396Z,1736274459.396 [Reporter](INFO): platform_buoyancy_position 372.135254 cc
2025-01-07T18:27:39.808Z,1736274459.808 [Reporter](INFO): platform_buoyancy_position 376.970032 cc
2025-01-07T18:27:40.232Z,1736274460.232 [Reporter](INFO): platform_buoyancy_position 383.282135 cc
2025-01-07T18:27:40.624Z,1736274460.624 [Reporter](INFO): platform_buoyancy_position 389.191345 cc
2025-01-07T18:27:41.020Z,1736274461.020 [Reporter](INFO): platform_buoyancy_position 393.757538 cc
2025-01-07T18:27:41.476Z,1736274461.476 [Reporter](INFO): platform_buoyancy_position 399.935333 cc
2025-01-07T18:27:41.855Z,1736274461.855 [Reporter](INFO): platform_buoyancy_position 404.232941 cc
2025-01-07T18:27:42.272Z,1736274462.272 [Reporter](INFO): platform_buoyancy_position 409.202026 cc
2025-01-07T18:27:42.669Z,1736274462.669 [Reporter](INFO): platform_buoyancy_position 415.245514 cc
2025-01-07T18:27:43.048Z,1736274463.048 [Reporter](INFO): platform_buoyancy_position 419.408844 cc
2025-01-07T18:27:43.464Z,1736274463.464 [Reporter](INFO): platform_buoyancy_position 425.720917 cc
2025-01-07T18:27:43.878Z,1736274463.878 [Reporter](INFO): platform_buoyancy_position 430.152863 cc
2025-01-07T18:27:44.256Z,1736274464.256 [Reporter](INFO): platform_buoyancy_position 436.464935 cc
2025-01-07T18:27:44.668Z,1736274464.668 [Reporter](INFO): platform_buoyancy_position 440.762543 cc
2025-01-07T18:27:45.086Z,1736274465.086 [Reporter](INFO): platform_buoyancy_position 445.194427 cc
2025-01-07T18:27:45.456Z,1736274465.456 [Reporter](INFO): platform_buoyancy_position 451.506561 cc
2025-01-07T18:27:45.868Z,1736274465.868 [Reporter](INFO): platform_buoyancy_position 455.804169 cc
2025-01-07T18:27:46.288Z,1736274466.288 [Reporter](INFO): platform_buoyancy_position 462.384857 cc
2025-01-07T18:27:46.704Z,1736274466.704 [Reporter](INFO): platform_buoyancy_position 468.562653 cc
2025-01-07T18:27:47.120Z,1736274467.120 [Reporter](INFO): platform_buoyancy_position 472.994537 cc
2025-01-07T18:27:47.528Z,1736274467.528 [Reporter](INFO): platform_buoyancy_position 479.440948 cc
2025-01-07T18:27:47.904Z,1736274467.904 [Reporter](INFO): platform_buoyancy_position 483.738556 cc
2025-01-07T18:27:48.305Z,1736274468.305 [Reporter](INFO): platform_buoyancy_position 488.170441 cc
2025-01-07T18:27:48.696Z,1736274468.696 [Reporter](INFO): platform_buoyancy_position 494.616852 cc
2025-01-07T18:27:49.104Z,1736274469.104 [Reporter](INFO): platform_buoyancy_position 498.914459 cc
2025-01-07T18:27:49.516Z,1736274469.516 [Reporter](INFO): platform_buoyancy_position 505.360901 cc
2025-01-07T18:27:49.921Z,1736274469.921 [Reporter](INFO): platform_buoyancy_position 509.658478 cc
2025-01-07T18:27:50.312Z,1736274470.312 [Reporter](INFO): platform_buoyancy_position 515.970520 cc
2025-01-07T18:27:50.716Z,1736274470.716 [Reporter](INFO): platform_buoyancy_position 520.536743 cc
2025-01-07T18:27:51.136Z,1736274471.136 [Reporter](INFO): platform_buoyancy_position 526.714539 cc
2025-01-07T18:27:51.559Z,1736274471.559 [Reporter](INFO): platform_buoyancy_position 531.146423 cc
2025-01-07T18:27:51.976Z,1736274471.976 [Reporter](INFO): platform_buoyancy_position 537.592834 cc
2025-01-07T18:27:52.332Z,1736274472.332 [Reporter](INFO): platform_buoyancy_position 541.890442 cc
2025-01-07T18:27:52.751Z,1736274472.751 [Reporter](INFO): platform_buoyancy_position 548.202515 cc
2025-01-07T18:27:53.165Z,1736274473.165 [Reporter](INFO): platform_buoyancy_position 552.634399 cc
2025-01-07T18:27:53.566Z,1736274473.566 [Reporter](INFO): platform_buoyancy_position 558.946533 cc
2025-01-07T18:27:53.992Z,1736274473.992 [Reporter](INFO): platform_buoyancy_position 563.378418 cc
2025-01-07T18:27:54.376Z,1736274474.376 [Reporter](INFO): platform_buoyancy_position 569.824829 cc
2025-01-07T18:27:54.780Z,1736274474.780 [Reporter](INFO): platform_buoyancy_position 573.988098 cc
2025-01-07T18:27:55.180Z,1736274475.180 [Reporter](INFO): platform_buoyancy_position 579.360107 cc
2025-01-07T18:27:55.560Z,1736274475.560 [Reporter](INFO): platform_buoyancy_position 584.866394 cc
2025-01-07T18:27:55.988Z,1736274475.988 [Reporter](INFO): platform_buoyancy_position 589.164001 cc
2025-01-07T18:27:56.390Z,1736274476.390 [Reporter](INFO): platform_buoyancy_position 593.730225 cc
2025-01-07T18:27:56.776Z,1736274476.776 [Reporter](INFO): platform_buoyancy_position 599.908020 cc
2025-01-07T18:27:57.181Z,1736274477.181 [Reporter](INFO): platform_buoyancy_position 606.220154 cc
2025-01-07T18:27:57.600Z,1736274477.600 [Reporter](INFO): platform_buoyancy_position 610.652039 cc
2025-01-07T18:27:58.007Z,1736274478.007 [Reporter](INFO): platform_buoyancy_position 616.964111 cc
2025-01-07T18:27:58.396Z,1736274478.396 [Reporter](INFO): platform_buoyancy_position 621.395996 cc
2025-01-07T18:27:58.796Z,1736274478.796 [Reporter](INFO): platform_buoyancy_position 626.230835 cc
2025-01-07T18:27:59.216Z,1736274479.216 [Reporter](INFO): platform_buoyancy_position 632.005737 cc
2025-01-07T18:27:59.628Z,1736274479.628 [Reporter](INFO): platform_buoyancy_position 637.780640 cc
2025-01-07T18:28:00.030Z,1736274480.030 [Reporter](INFO): platform_buoyancy_position 642.749756 cc
2025-01-07T18:28:00.420Z,1736274480.420 [Reporter](INFO): platform_buoyancy_position 647.315918 cc
2025-01-07T18:28:00.825Z,1736274480.825 [Reporter](INFO): platform_buoyancy_position 653.628052 cc
2025-01-07T18:28:01.252Z,1736274481.252 [Reporter](INFO): platform_buoyancy_position 659.940125 cc
2025-01-07T18:28:01.656Z,1736274481.656 [Reporter](INFO): platform_buoyancy_position 664.237732 cc
2025-01-07T18:28:02.088Z,1736274482.088 [Reporter](INFO): platform_buoyancy_position 670.684143 cc
2025-01-07T18:28:02.494Z,1736274482.494 [Reporter](INFO): platform_buoyancy_position 675.116028 cc
2025-01-07T18:28:02.907Z,1736274482.907 [Reporter](INFO): platform_buoyancy_position 679.547913 cc
2025-01-07T18:28:03.276Z,1736274483.276 [Reporter](INFO): platform_buoyancy_position 685.860046 cc
2025-01-07T18:28:03.684Z,1736274483.684 [Reporter](INFO): platform_buoyancy_position 690.023315 cc
2025-01-07T18:28:04.065Z,1736274484.065 [Reporter](INFO): platform_buoyancy_position 694.858154 cc
2025-01-07T18:28:04.476Z,1736274484.476 [Reporter](INFO): platform_buoyancy_position 700.767334 cc
2025-01-07T18:28:04.852Z,1736274484.852 [Reporter](INFO): platform_buoyancy_position 705.199219 cc
2025-01-07T18:28:05.283Z,1736274485.283 [Reporter](INFO): platform_buoyancy_position 711.511353 cc
2025-01-07T18:28:05.692Z,1736274485.692 [Reporter](INFO): platform_buoyancy_position 716.211853 cc
2025-01-07T18:28:06.088Z,1736274486.088 [Reporter](INFO): platform_buoyancy_position 722.255310 cc
2025-01-07T18:28:06.476Z,1736274486.476 [Reporter](INFO): platform_buoyancy_position 728.701721 cc
2025-01-07T18:28:06.896Z,1736274486.896 [Reporter](INFO): platform_buoyancy_position 733.133606 cc
2025-01-07T18:28:07.308Z,1736274487.308 [Reporter](INFO): platform_buoyancy_position 737.431213 cc
2025-01-07T18:28:07.680Z,1736274487.680 [Reporter](INFO): platform_buoyancy_position 743.743347 cc
2025-01-07T18:28:08.104Z,1736274488.104 [Reporter](INFO): platform_buoyancy_position 748.175232 cc
2025-01-07T18:28:08.504Z,1736274488.504 [Reporter](INFO): platform_buoyancy_position 754.487366 cc
2025-01-07T18:28:08.900Z,1736274488.900 [Reporter](INFO): platform_buoyancy_position 758.784912 cc
2025-01-07T18:28:09.324Z,1736274489.324 [Reporter](INFO): platform_buoyancy_position 765.097046 cc
2025-01-07T18:28:09.722Z,1736274489.722 [Reporter](INFO): platform_buoyancy_position 769.663208 cc
2025-01-07T18:28:10.100Z,1736274490.100 [Reporter](INFO): platform_buoyancy_position 773.826538 cc
2025-01-07T18:28:10.529Z,1736274490.529 [Reporter](INFO): platform_buoyancy_position 780.272949 cc
2025-01-07T18:28:10.949Z,1736274490.949 [Reporter](INFO): platform_buoyancy_position 786.719360 cc
2025-01-07T18:28:11.359Z,1736274491.359 [Reporter](INFO): platform_buoyancy_position 791.151245 cc
2025-01-07T18:28:11.740Z,1736274491.740 [Reporter](INFO): platform_buoyancy_position 797.463318 cc
2025-01-07T18:28:12.147Z,1736274492.147 [Reporter](INFO): platform_buoyancy_position 801.760925 cc
2025-01-07T18:28:12.556Z,1736274492.556 [Reporter](INFO): platform_buoyancy_position 806.327148 cc
2025-01-07T18:28:12.948Z,1736274492.948 [Reporter](INFO): platform_buoyancy_position 812.504944 cc
2025-01-07T18:28:13.336Z,1736274493.336 [Reporter](INFO): platform_buoyancy_position 817.071167 cc
2025-01-07T18:28:13.752Z,1736274493.752 [Reporter](INFO): platform_buoyancy_position 823.248962 cc
2025-01-07T18:28:14.164Z,1736274494.164 [Reporter](INFO): platform_buoyancy_position 827.546570 cc
2025-01-07T18:28:14.570Z,1736274494.570 [Reporter](INFO): platform_buoyancy_position 833.992920 cc
2025-01-07T18:28:14.960Z,1736274494.960 [Reporter](INFO): platform_buoyancy_position 838.424866 cc
2025-01-07T18:28:15.376Z,1736274495.376 [Reporter](INFO): platform_buoyancy_position 844.736938 cc
2025-01-07T18:28:15.796Z,1736274495.796 [Reporter](INFO): platform_buoyancy_position 849.034546 cc
2025-01-07T18:28:16.208Z,1736274496.208 [Reporter](INFO): platform_buoyancy_position 855.480957 cc
2025-01-07T18:28:16.588Z,1736274496.588 [Reporter](INFO): platform_buoyancy_position 859.778564 cc
2025-01-07T18:28:17.041Z,1736274497.041 [Reporter](INFO): platform_buoyancy_position 864.210449 cc
2025-01-07T18:28:17.412Z,1736274497.412 [Reporter](INFO): platform_buoyancy_position 870.522522 cc
2025-01-07T18:28:17.796Z,1736274497.796 [Reporter](INFO): platform_buoyancy_position 874.954468 cc
2025-01-07T18:28:18.208Z,1736274498.208 [Reporter](INFO): platform_buoyancy_position 881.400818 cc
2025-01-07T18:28:18.620Z,1736274498.620 [Reporter](INFO): platform_buoyancy_position 885.698425 cc
2025-01-07T18:28:19.004Z,1736274499.004 [Reporter](INFO): platform_buoyancy_position 892.010559 cc
2025-01-07T18:28:19.419Z,1736274499.419 [Reporter](INFO): platform_buoyancy_position 896.308167 cc
2025-01-07T18:28:19.832Z,1736274499.832 [Reporter](INFO): platform_buoyancy_position 902.754517 cc
2025-01-07T18:28:20.228Z,1736274500.228 [Reporter](INFO): platform_buoyancy_position 907.186462 cc
2025-01-07T18:28:20.624Z,1736274500.624 [Reporter](INFO): platform_buoyancy_position 913.498535 cc
2025-01-07T18:28:21.047Z,1736274501.047 [Reporter](INFO): platform_buoyancy_position 918.064758 cc
2025-01-07T18:28:21.489Z,1736274501.489 [Reporter](INFO): platform_buoyancy_position 924.376831 cc
2025-01-07T18:28:21.856Z,1736274501.856 [Reporter](INFO): platform_buoyancy_position 928.674438 cc
2025-01-07T18:28:22.256Z,1736274502.256 [Reporter](INFO): platform_buoyancy_position 934.852295 cc
2025-01-07T18:28:22.637Z,1736274502.637 [Reporter](INFO): platform_buoyancy_position 939.284180 cc
2025-01-07T18:28:23.148Z,1736274503.148 [Reporter](INFO): platform_buoyancy_position 943.581787 cc
2025-01-07T18:28:23.544Z,1736274503.544 [Reporter](INFO): platform_buoyancy_position 947.879395 cc
2025-01-07T18:29:10.512Z,1736274550.512 [CommandExec](IMPORTANT): got command report clear
2025-01-07T18:29:18.087Z,1736274558.087 [CommandLine](INFO): End of History
2025-01-07T18:29:18.092Z,1736274558.092 [CommandLine](INFO): End of History
2025-01-07T18:29:18.097Z,1736274558.097 [CommandLine](INFO): End of History
2025-01-07T18:29:18.102Z,1736274558.102 [CommandLine](INFO): End of History
2025-01-07T18:29:18.108Z,1736274558.108 [CommandLine](INFO): End of History
2025-01-07T18:29:18.113Z,1736274558.113 [CommandLine](INFO): End of History
2025-01-07T18:29:18.117Z,1736274558.117 [CommandLine](INFO): End of History
2025-01-07T18:29:18.146Z,1736274558.146 [CommandLine](INFO): End of History
2025-01-07T18:29:18.148Z,1736274558.148 [CommandLine](INFO): End of History
2025-01-07T18:29:29.244Z,1736274569.244 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-01-07T18:29:40.572Z,1736274580.572 [CommandExec](IMPORTANT): got command maintain clear
2025-01-07T18:29:56.322Z,1736274596.322 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:29:56.322Z,1736274596.322 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:29:56.322Z,1736274596.322 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:29:56.322Z,1736274596.322 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:29:56.726Z,1736274596.726 [Default:CheckIn:D] Stopped
2025-01-07T18:29:56.726Z,1736274596.726 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:29:57.132Z,1736274597.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.460494 min
2025-01-07T18:29:57.132Z,1736274597.132 [Default:CheckIn:E] Stopped
2025-01-07T18:29:57.132Z,1736274597.132 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:29:57.132Z,1736274597.132 [Default:CheckIn] Stopped
2025-01-07T18:29:57.132Z,1736274597.132 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:29:57.132Z,1736274597.132 [Default:CheckIn](INFO): Running loop #8
2025-01-07T18:29:57.132Z,1736274597.132 [Default:CheckIn] Running Loop=8
2025-01-07T18:29:57.133Z,1736274597.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:29:57.133Z,1736274597.133 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:29:59.145Z,1736274599.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182958.00,A,3648.16401,N,12147.28281,W,0.583,188.88,070125,,,A*76
2025-01-07T18:29:59.155Z,1736274599.155 [NAL9602](INFO): GPS fix at 20250107T182958: (36.802734, -121.788047)
2025-01-07T18:29:59.166Z,1736274599.166 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:29:59.166Z,1736274599.166 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:30:06.774Z,1736274606.774 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0028.lzma
2025-01-07T18:30:07.776Z,1736274607.776 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0028.lzma.bak
2025-01-07T18:30:07.776Z,1736274607.776 [DataOverHttps](INFO): SBD MOMSN=23882534
2025-01-07T18:30:26.091Z,1736274626.091 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20250107T173032/Express0029.lzma
2025-01-07T18:30:27.108Z,1736274627.108 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0029.lzma.bak
2025-01-07T18:30:27.108Z,1736274627.108 [DataOverHttps](INFO): SBD MOMSN=23882539
2025-01-07T18:30:28.319Z,1736274628.319 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:30:28.319Z,1736274628.319 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:30:28.319Z,1736274628.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:30:31.516Z,1736274631.516 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T18:30:49.309Z,1736274649.309 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-01-07T18:35:28.896Z,1736274928.896 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:35:28.896Z,1736274928.896 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:35:28.896Z,1736274928.896 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:35:28.896Z,1736274928.896 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:35:29.309Z,1736274929.309 [Default:CheckIn:D] Stopped
2025-01-07T18:35:29.309Z,1736274929.309 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:35:29.721Z,1736274929.721 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.003552 min
2025-01-07T18:35:29.721Z,1736274929.721 [Default:CheckIn:E] Stopped
2025-01-07T18:35:29.721Z,1736274929.721 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:35:29.721Z,1736274929.721 [Default:CheckIn] Stopped
2025-01-07T18:35:29.721Z,1736274929.721 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:35:29.721Z,1736274929.721 [Default:CheckIn](INFO): Running loop #9
2025-01-07T18:35:29.721Z,1736274929.721 [Default:CheckIn] Running Loop=9
2025-01-07T18:35:29.722Z,1736274929.722 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:35:29.722Z,1736274929.722 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:35:31.721Z,1736274931.721 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183531.00,A,3648.16909,N,12147.28115,W,0.525,0.00,070125,,,A*72
2025-01-07T18:35:31.723Z,1736274931.723 [NAL9602](INFO): GPS fix at 20250107T183531: (36.802818, -121.788019)
2025-01-07T18:35:31.759Z,1736274931.759 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:35:31.759Z,1736274931.759 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:35:39.289Z,1736274939.289 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0031.lzma
2025-01-07T18:35:40.292Z,1736274940.292 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0031.lzma.bak
2025-01-07T18:35:40.292Z,1736274940.292 [DataOverHttps](INFO): SBD MOMSN=23882556
2025-01-07T18:35:56.062Z,1736274956.062 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250107T173032/Express0032.lzma
2025-01-07T18:35:57.064Z,1736274957.064 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0032.lzma.bak
2025-01-07T18:35:57.064Z,1736274957.064 [DataOverHttps](INFO): SBD MOMSN=23882559
2025-01-07T18:35:58.398Z,1736274958.398 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:35:58.398Z,1736274958.398 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:35:58.398Z,1736274958.398 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:36:02.420Z,1736274962.420 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-01-07T18:36:02.498Z,1736274962.498 [NAL9602](ERROR): received:
+CSQ:0
OK250, 0, 0, 0, 0
OK
2025-01-07T18:40:34.315Z,1736275234.315 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-01-07T18:40:58.971Z,1736275258.971 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:40:58.971Z,1736275258.971 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:40:58.972Z,1736275258.972 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:40:58.972Z,1736275258.972 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:40:59.380Z,1736275259.380 [Default:CheckIn:D] Stopped
2025-01-07T18:40:59.380Z,1736275259.380 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.504732 min
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn:E] Stopped
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn] Stopped
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn](INFO): Running loop #10
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn] Running Loop=10
2025-01-07T18:40:59.801Z,1736275259.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:40:59.802Z,1736275259.802 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:41:01.790Z,1736275261.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184101.00,A,3648.17388,N,12147.28052,W,0.622,294.47,070125,,,A*7A
2025-01-07T18:41:01.792Z,1736275261.792 [NAL9602](INFO): GPS fix at 20250107T184101: (36.802898, -121.788009)
2025-01-07T18:41:01.803Z,1736275261.803 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:41:01.803Z,1736275261.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:41:09.486Z,1736275269.486 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0034.lzma
2025-01-07T18:41:10.488Z,1736275270.488 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0034.lzma.bak
2025-01-07T18:41:10.488Z,1736275270.488 [DataOverHttps](INFO): SBD MOMSN=23882570
2025-01-07T18:41:26.130Z,1736275286.130 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250107T173032/Express0035.lzma
2025-01-07T18:41:27.132Z,1736275287.132 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0035.lzma.bak
2025-01-07T18:41:27.132Z,1736275287.132 [DataOverHttps](INFO): SBD MOMSN=23882573
2025-01-07T18:41:28.462Z,1736275288.462 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:41:28.462Z,1736275288.462 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:41:28.462Z,1736275288.462 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:41:34.509Z,1736275294.509 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T18:46:29.052Z,1736275589.052 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:46:29.052Z,1736275589.052 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:46:29.052Z,1736275589.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:46:29.052Z,1736275589.052 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:46:29.465Z,1736275589.465 [Default:CheckIn:D] Stopped
2025-01-07T18:46:29.465Z,1736275589.465 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:46:29.853Z,1736275589.853 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.006152 min
2025-01-07T18:46:29.853Z,1736275589.853 [Default:CheckIn:E] Stopped
2025-01-07T18:46:29.853Z,1736275589.853 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:46:29.853Z,1736275589.853 [Default:CheckIn] Stopped
2025-01-07T18:46:29.854Z,1736275589.854 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:46:29.854Z,1736275589.854 [Default:CheckIn](INFO): Running loop #11
2025-01-07T18:46:29.854Z,1736275589.854 [Default:CheckIn] Running Loop=11
2025-01-07T18:46:29.854Z,1736275589.854 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:46:29.854Z,1736275589.854 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:46:31.869Z,1736275591.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184631.00,A,3648.13996,N,12147.24230,W,0.117,100.99,070125,,,A*79
2025-01-07T18:46:31.871Z,1736275591.871 [NAL9602](INFO): GPS fix at 20250107T184631: (36.802333, -121.787372)
2025-01-07T18:46:31.911Z,1736275591.911 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:46:31.911Z,1736275591.911 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:46:38.786Z,1736275598.786 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0037.lzma
2025-01-07T18:46:39.788Z,1736275599.788 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0037.lzma.bak
2025-01-07T18:46:39.788Z,1736275599.788 [DataOverHttps](INFO): SBD MOMSN=23882587
2025-01-07T18:46:47.217Z,1736275607.217 [NAL9602](INFO): SBD MO Status=0, MOMSN=61251, MT Status=0, MTMSN=0
2025-01-07T18:46:47.217Z,1736275607.217 [NAL9602](INFO): No messages in MT queue
2025-01-07T18:46:58.182Z,1736275618.182 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250107T173032/Express0038.lzma
2025-01-07T18:46:59.184Z,1736275619.184 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0038.lzma.bak
2025-01-07T18:46:59.184Z,1736275619.184 [DataOverHttps](INFO): SBD MOMSN=23882590
2025-01-07T18:47:00.228Z,1736275620.228 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:47:00.228Z,1736275620.228 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:47:00.228Z,1736275620.228 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:47:18.003Z,1736275638.003 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T18:48:38.395Z,1736275718.395 [DataOverHttps](IMPORTANT): SBD MTMSN=20250107T184837
2025-01-07T18:48:45.922Z,1736275725.922 [DataOverHttps](INFO): Received command: ibit
2025-01-07T18:48:45.975Z,1736275725.975 [CommandExec](IMPORTANT): got command ibit
2025-01-07T18:48:46.093Z,1736275726.093 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-01-07T18:48:46.093Z,1736275726.093 [IBIT](IMPORTANT): Beginning control surface checks.
2025-01-07T18:48:46.097Z,1736275726.097 [CBIT](IMPORTANT): Beginning ground fault scan
2025-01-07T18:48:46.923Z,1736275726.923 [DataOverHttps](IMPORTANT): SBD MTMSN=20250107T184846
2025-01-07T18:48:47.685Z,1736275727.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184846.00,A,3648.13791,N,12147.21223,W,0.019,44.18,070125,,,D*4B
2025-01-07T18:48:47.688Z,1736275727.688 [NAL9602](INFO): GPS fix at 20250107T184846: (36.802298, -121.786871)
2025-01-07T18:48:49.007Z,1736275729.007 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2025-01-07T18:48:57.283Z,1736275737.283 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2025-01-07T18:48:57.606Z,1736275737.606 [DataOverHttps](INFO): Received command: failc
2025-01-07T18:48:57.627Z,1736275737.627 [CommandExec](IMPORTANT): got command failComponent
2025-01-07T18:48:57.627Z,1736275737.627 [CommandExec](IMPORTANT): Failed components:
2025-01-07T18:48:57.627Z,1736275737.627 [CommandExec](IMPORTANT): No failed Components.
2025-01-07T18:48:57.732Z,1736275737.732 [CBIT](IMPORTANT): Beginning ground fault scan
2025-01-07T18:49:00.519Z,1736275740.519 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2025-01-07T18:49:08.566Z,1736275748.566 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002854
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002057
CHAN A3 (5V): -0.001684
CHAN B0 (3.3V): -0.000386
CHAN B1 (3.15aV): -0.000443
CHAN B2 (3.15bV): -0.000325
CHAN B3 (GND): 0.000171
OPEN: -0.000250
Full Scale: +/- 1 mA
2025-01-07T18:49:16.909Z,1736275756.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=61252, MT Status=0, MTMSN=0
2025-01-07T18:49:16.909Z,1736275756.909 [NAL9602](INFO): No messages in MT queue
2025-01-07T18:49:18.121Z,1736275758.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184917.00,A,3648.13809,N,12147.21128,W,0.019,44.18,070125,,,D*48
2025-01-07T18:49:18.123Z,1736275758.123 [NAL9602](INFO): GPS fix at 20250107T184917: (36.802301, -121.786855)
2025-01-07T18:49:20.949Z,1736275760.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184920.00,A,3648.13812,N,12147.21118,W,0.117,44.18,070125,,,D*4A
2025-01-07T18:49:20.951Z,1736275760.951 [NAL9602](INFO): GPS fix at 20250107T184920: (36.802302, -121.786853)
2025-01-07T18:49:24.177Z,1736275764.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184923.00,A,3648.13809,N,12147.21114,W,0.058,44.18,070125,,,D*45
2025-01-07T18:49:24.179Z,1736275764.179 [NAL9602](INFO): GPS fix at 20250107T184923: (36.802301, -121.786852)
2025-01-07T18:49:27.014Z,1736275767.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184926.00,A,3648.13803,N,12147.21117,W,0.019,44.18,070125,,,D*4C
2025-01-07T18:49:27.017Z,1736275767.017 [NAL9602](INFO): GPS fix at 20250107T184926: (36.802301, -121.786853)
2025-01-07T18:49:29.839Z,1736275769.839 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184929.00,A,3648.13796,N,12147.21125,W,0.019,44.18,070125,,,D*41
2025-01-07T18:49:29.841Z,1736275769.841 [NAL9602](INFO): GPS fix at 20250107T184929: (36.802299, -121.786854)
2025-01-07T18:49:32.287Z,1736275772.287 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802299 Longitude: -121.786858
2025-01-07T18:49:32.704Z,1736275772.704 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2025-01-07T18:49:32.704Z,1736275772.704 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-01-07T18:49:32.704Z,1736275772.704 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-01-07T18:49:33.069Z,1736275773.069 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184932.00,A,3648.13790,N,12147.21131,W,0.078,44.18,070125,,,D*4F
2025-01-07T18:49:33.071Z,1736275773.071 [NAL9602](INFO): GPS fix at 20250107T184932: (36.802298, -121.786855)
2025-01-07T18:49:33.088Z,1736275773.088 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-01-07T18:49:33.088Z,1736275773.088 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-01-07T18:49:33.089Z,1736275773.089 [IBIT](IMPORTANT): Pressure:9.375185 PSI
2025-01-07T18:49:33.089Z,1736275773.089 [IBIT](IMPORTANT): Humidity:17.348309 %
2025-01-07T18:49:33.501Z,1736275773.501 [IBIT](IMPORTANT): Vehicle Pitch:-0.724934 degrees
2025-01-07T18:49:33.501Z,1736275773.501 [IBIT](IMPORTANT): Vehicle Roll:-1.778566 degrees
2025-01-07T18:49:33.501Z,1736275773.501 [IBIT](IMPORTANT): Vehicle Heading:168.484619 degrees
2025-01-07T18:49:33.891Z,1736275773.891 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-01-07T18:49:33.892Z,1736275773.892 [IBIT](IMPORTANT): buoyancyNeutral: 292.731903 cc
2025-01-07T18:49:33.892Z,1736275773.892 [IBIT](IMPORTANT): massDefault: 0.746523 cm
2025-01-07T18:49:33.892Z,1736275773.892 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-01-07T18:49:33.892Z,1736275773.892 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-01-07T18:49:33.893Z,1736275773.893 [IBIT](IMPORTANT): IBIT PASSED
2025-01-07T18:50:05.382Z,1736275805.382 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T18:50:49.868Z,1736275849.868 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2109,HPR45,EXT,TILT
2025-01-07T18:52:01.043Z,1736275921.043 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:52:01.043Z,1736275921.043 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:52:01.043Z,1736275921.043 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:52:01.043Z,1736275921.043 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:52:01.424Z,1736275921.424 [Default:CheckIn:D] Stopped
2025-01-07T18:52:01.424Z,1736275921.424 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.538794 min
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn:E] Stopped
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn] Stopped
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn](INFO): Running loop #12
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn] Running Loop=12
2025-01-07T18:52:01.839Z,1736275921.839 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:52:01.840Z,1736275921.840 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:52:03.850Z,1736275923.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185203.00,A,3648.13705,N,12147.21180,W,0.039,44.18,070125,,,D*44
2025-01-07T18:52:03.853Z,1736275923.853 [NAL9602](INFO): GPS fix at 20250107T185203: (36.802284, -121.786863)
2025-01-07T18:52:03.863Z,1736275923.863 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:52:03.864Z,1736275923.864 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:52:11.825Z,1736275931.825 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0040.lzma
2025-01-07T18:52:12.828Z,1736275932.828 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0040.lzma.bak
2025-01-07T18:52:12.828Z,1736275932.828 [DataOverHttps](INFO): SBD MOMSN=23882608
2025-01-07T18:52:32.663Z,1736275952.663 [DataOverHttps](INFO): Sending 881 bytes from file Logs/20250107T173032/Express0041.lzma
2025-01-07T18:52:33.664Z,1736275953.664 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0041.lzma.bak
2025-01-07T18:52:33.664Z,1736275953.664 [DataOverHttps](INFO): SBD MOMSN=23882611
2025-01-07T18:52:51.754Z,1736275971.754 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250107T173032/Express0044.lzma
2025-01-07T18:52:52.756Z,1736275972.756 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0044.lzma.bak
2025-01-07T18:52:52.756Z,1736275972.756 [DataOverHttps](INFO): SBD MOMSN=23882648
2025-01-07T18:52:54.022Z,1736275974.022 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:52:54.022Z,1736275974.022 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:52:54.022Z,1736275974.022 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:52:58.897Z,1736275978.897 [NAL9602](INFO): SBD MO Status=0, MOMSN=61253, MT Status=0, MTMSN=0
2025-01-07T18:52:58.897Z,1736275978.897 [NAL9602](INFO): No messages in MT queue
2025-01-07T18:53:29.599Z,1736276009.599 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T18:57:54.637Z,1736276274.637 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T18:57:54.637Z,1736276274.637 [Default:CheckIn:C.Wait] Stopped
2025-01-07T18:57:54.638Z,1736276274.638 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T18:57:54.638Z,1736276274.638 [Default:CheckIn:D] Running Loop=1
2025-01-07T18:57:55.045Z,1736276275.045 [Default:CheckIn:D] Stopped
2025-01-07T18:57:55.045Z,1736276275.045 [Default:CheckIn:E] Running Loop=1
2025-01-07T18:57:55.449Z,1736276275.449 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.432487 min
2025-01-07T18:57:55.449Z,1736276275.449 [Default:CheckIn:E] Stopped
2025-01-07T18:57:55.449Z,1736276275.449 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T18:57:55.449Z,1736276275.449 [Default:CheckIn] Stopped
2025-01-07T18:57:55.449Z,1736276275.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T18:57:55.449Z,1736276275.449 [Default:CheckIn](INFO): Running loop #13
2025-01-07T18:57:55.449Z,1736276275.449 [Default:CheckIn] Running Loop=13
2025-01-07T18:57:55.450Z,1736276275.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T18:57:55.450Z,1736276275.450 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T18:57:57.457Z,1736276277.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185756.00,A,3648.13819,N,12147.21235,W,0.078,44.18,070125,,,D*4B
2025-01-07T18:57:57.459Z,1736276277.459 [NAL9602](INFO): GPS fix at 20250107T185756: (36.802303, -121.786873)
2025-01-07T18:57:57.492Z,1736276277.492 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T18:57:57.492Z,1736276277.492 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T18:58:04.767Z,1736276284.767 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250107T173032/Courier0046.lzma
2025-01-07T18:58:05.768Z,1736276285.768 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0046.lzma.bak
2025-01-07T18:58:05.768Z,1736276285.768 [DataOverHttps](INFO): SBD MOMSN=23882656
2025-01-07T18:58:18.069Z,1736276298.069 [NAL9602](INFO): SBD MO Status=0, MOMSN=61254, MT Status=0, MTMSN=0
2025-01-07T18:58:18.069Z,1736276298.069 [NAL9602](INFO): No messages in MT queue
2025-01-07T18:58:18.638Z,1736276298.638 [RudderServo](ERROR): getPosition uart error serial timeout
2025-01-07T18:58:18.638Z,1736276298.638 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2025-01-07T18:58:18.639Z,1736276298.639 [RudderServo] Communications Fault, FailCount= 1
2025-01-07T18:58:18.639Z,1736276298.639 [RudderServo](ERROR): Communications Fault
2025-01-07T18:58:18.659Z,1736276298.659 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-01-07T18:58:18.954Z,1736276298.954 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-01-07T18:58:18.954Z,1736276298.954 [RudderServo](INFO): Powering down
2025-01-07T18:58:19.773Z,1736276299.773 [RudderServo](DEBUG): Initializing EZServoServo.
2025-01-07T18:58:19.909Z,1736276299.909 [RudderServo](DEBUG): Initializing RudderServo.
2025-01-07T18:58:19.913Z,1736276299.913 [CBIT](INFO): Clearing failed state for component RudderServo
2025-01-07T18:58:19.913Z,1736276299.913 [RudderServo] No Fault, FailCount= 1
2025-01-07T18:58:24.942Z,1736276304.942 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250107T173032/Express0047.lzma
2025-01-07T18:58:25.945Z,1736276305.945 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0047.lzma.bak
2025-01-07T18:58:25.945Z,1736276305.945 [DataOverHttps](INFO): SBD MOMSN=23882659
2025-01-07T18:58:27.013Z,1736276307.013 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T18:58:27.013Z,1736276307.013 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T18:58:27.013Z,1736276307.013 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T18:58:48.800Z,1736276328.800 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:02:50.801Z,1736276570.801 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:03:02.923Z,1736276582.923 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:03:14.637Z,1736276594.637 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:03:26.756Z,1736276606.756 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:03:27.594Z,1736276607.594 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:03:27.594Z,1736276607.594 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:03:27.594Z,1736276607.594 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:03:27.594Z,1736276607.594 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:03:27.986Z,1736276607.986 [Default:CheckIn:D] Stopped
2025-01-07T19:03:27.986Z,1736276607.986 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.981494 min
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn:E] Stopped
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn] Stopped
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn](INFO): Running loop #14
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn] Running Loop=14
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:03:28.403Z,1736276608.403 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:03:30.397Z,1736276610.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190329.00,A,3648.13798,N,12147.21243,W,0.019,44.18,070125,,,D*43
2025-01-07T19:03:30.400Z,1736276610.400 [NAL9602](INFO): GPS fix at 20250107T190329: (36.802300, -121.786874)
2025-01-07T19:03:30.411Z,1736276610.411 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:03:30.411Z,1736276610.411 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:03:38.069Z,1736276618.069 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:03:38.425Z,1736276618.425 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0049.lzma
2025-01-07T19:03:39.429Z,1736276619.429 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0049.lzma.bak
2025-01-07T19:03:39.429Z,1736276619.429 [DataOverHttps](INFO): SBD MOMSN=23882678
2025-01-07T19:03:49.380Z,1736276629.380 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:03:55.733Z,1736276635.733 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250107T173032/Express0050.lzma
2025-01-07T19:03:56.732Z,1736276636.732 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0050.lzma.bak
2025-01-07T19:03:56.732Z,1736276636.732 [DataOverHttps](INFO): SBD MOMSN=23882685
2025-01-07T19:03:57.884Z,1736276637.884 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:03:57.884Z,1736276637.884 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:03:57.884Z,1736276637.884 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:04:00.697Z,1736276640.697 [NAL9602](INFO): SBD MO Status=2, MOMSN=61255, MT Status=2, MTMSN=0
2025-01-07T19:04:00.697Z,1736276640.697 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-07T19:04:01.502Z,1736276641.502 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:04:13.634Z,1736276653.634 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:04:21.321Z,1736276661.321 [NAL9602](INFO): SBD MO Status=2, MOMSN=61255, MT Status=2, MTMSN=0
2025-01-07T19:04:21.321Z,1736276661.321 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-07T19:04:38.281Z,1736276678.281 [NAL9602](INFO): SBD MO Status=0, MOMSN=61255, MT Status=0, MTMSN=0
2025-01-07T19:04:38.281Z,1736276678.281 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:05:08.979Z,1736276708.979 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:06:51.601Z,1736276811.601 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:07:04.933Z,1736276824.933 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:07:18.268Z,1736276838.268 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:07:31.194Z,1736276851.194 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:07:44.929Z,1736276864.929 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:07:58.666Z,1736276878.666 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:08:11.996Z,1736276891.996 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:08:25.329Z,1736276905.329 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:08:39.064Z,1736276919.064 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:08:53.207Z,1736276933.207 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-01-07T19:08:58.469Z,1736276938.469 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:08:58.469Z,1736276938.469 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:08:58.469Z,1736276938.469 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:08:58.469Z,1736276938.469 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:08:58.873Z,1736276938.873 [Default:CheckIn:D] Stopped
2025-01-07T19:08:58.873Z,1736276938.873 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:08:59.282Z,1736276939.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.496281 min
2025-01-07T19:08:59.282Z,1736276939.282 [Default:CheckIn:E] Stopped
2025-01-07T19:08:59.282Z,1736276939.282 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:08:59.282Z,1736276939.282 [Default:CheckIn] Stopped
2025-01-07T19:08:59.283Z,1736276939.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:08:59.283Z,1736276939.283 [Default:CheckIn](INFO): Running loop #15
2025-01-07T19:08:59.283Z,1736276939.283 [Default:CheckIn] Running Loop=15
2025-01-07T19:08:59.283Z,1736276939.283 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:08:59.283Z,1736276939.283 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:09:01.294Z,1736276941.294 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190900.00,A,3648.13772,N,12147.21141,W,0.039,44.18,070125,,,D*45
2025-01-07T19:09:01.297Z,1736276941.297 [NAL9602](INFO): GPS fix at 20250107T190900: (36.802295, -121.786857)
2025-01-07T19:09:01.315Z,1736276941.315 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:09:01.315Z,1736276941.315 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:09:07.766Z,1736276947.766 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-01-07T19:09:07.769Z,1736276947.769 [BPC1](INFO): Received data from all battery sticks.
2025-01-07T19:09:09.117Z,1736276949.117 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250107T173032/Courier0052.lzma
2025-01-07T19:09:10.120Z,1736276950.120 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0052.lzma.bak
2025-01-07T19:09:10.120Z,1736276950.120 [DataOverHttps](INFO): SBD MOMSN=23882690
2025-01-07T19:09:25.529Z,1736276965.529 [NAL9602](INFO): SBD MO Status=0, MOMSN=61256, MT Status=0, MTMSN=0
2025-01-07T19:09:25.529Z,1736276965.529 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:09:26.215Z,1736276966.215 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250107T173032/Express0053.lzma
2025-01-07T19:09:27.216Z,1736276967.216 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0053.lzma.bak
2025-01-07T19:09:27.216Z,1736276967.216 [DataOverHttps](INFO): SBD MOMSN=23882693
2025-01-07T19:09:28.369Z,1736276968.369 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:09:28.369Z,1736276968.369 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:09:28.369Z,1736276968.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:09:56.241Z,1736276996.241 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:14:29.012Z,1736277269.012 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:14:29.012Z,1736277269.012 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:14:29.012Z,1736277269.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:14:29.012Z,1736277269.012 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:14:29.417Z,1736277269.417 [Default:CheckIn:D] Stopped
2025-01-07T19:14:29.417Z,1736277269.417 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.005339 min
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn:E] Stopped
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn] Stopped
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn](INFO): Running loop #16
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn] Running Loop=16
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:14:29.831Z,1736277269.831 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:14:31.850Z,1736277271.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191431.00,A,3648.13705,N,12147.20693,W,0.253,67.67,070125,,,D*45
2025-01-07T19:14:31.852Z,1736277271.852 [NAL9602](INFO): GPS fix at 20250107T191431: (36.802284, -121.786782)
2025-01-07T19:14:31.863Z,1736277271.863 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:14:31.863Z,1736277271.863 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:14:39.442Z,1736277279.442 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250107T173032/Courier0055.lzma
2025-01-07T19:14:40.444Z,1736277280.444 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0055.lzma.bak
2025-01-07T19:14:40.445Z,1736277280.445 [DataOverHttps](INFO): SBD MOMSN=23882716
2025-01-07T19:14:53.685Z,1736277293.685 [NAL9602](INFO): SBD MO Status=0, MOMSN=61257, MT Status=0, MTMSN=0
2025-01-07T19:14:53.685Z,1736277293.685 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:14:59.134Z,1736277299.134 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250107T173032/Express0056.lzma
2025-01-07T19:15:00.136Z,1736277300.136 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0056.lzma.bak
2025-01-07T19:15:00.137Z,1736277300.137 [DataOverHttps](INFO): SBD MOMSN=23882723
2025-01-07T19:15:01.402Z,1736277301.402 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:15:01.402Z,1736277301.402 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:15:01.402Z,1736277301.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:15:24.420Z,1736277324.420 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:20:01.983Z,1736277601.983 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:20:01.983Z,1736277601.983 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:20:01.983Z,1736277601.983 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:20:01.983Z,1736277601.983 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:20:02.386Z,1736277602.386 [Default:CheckIn:D] Stopped
2025-01-07T19:20:02.386Z,1736277602.386 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:20:02.780Z,1736277602.780 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.554826 min
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn:E] Stopped
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn] Stopped
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn](INFO): Running loop #17
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn] Running Loop=17
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:20:02.781Z,1736277602.781 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:20:04.797Z,1736277604.797 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192004.00,A,3648.13645,N,12147.20585,W,0.039,67.67,070125,,,D*4B
2025-01-07T19:20:04.799Z,1736277604.799 [NAL9602](INFO): GPS fix at 20250107T192004: (36.802274, -121.786764)
2025-01-07T19:20:04.810Z,1736277604.810 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:20:04.810Z,1736277604.810 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:20:11.738Z,1736277611.738 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250107T173032/Courier0058.lzma
2025-01-07T19:20:12.745Z,1736277612.745 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0058.lzma.bak
2025-01-07T19:20:12.745Z,1736277612.745 [DataOverHttps](INFO): SBD MOMSN=23882727
2025-01-07T19:20:21.776Z,1736277621.776 [NAL9602](INFO): SBD MO Status=0, MOMSN=61258, MT Status=0, MTMSN=0
2025-01-07T19:20:21.777Z,1736277621.777 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:20:31.618Z,1736277631.618 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250107T173032/Express0059.lzma
2025-01-07T19:20:32.620Z,1736277632.620 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0059.lzma.bak
2025-01-07T19:20:32.620Z,1736277632.620 [DataOverHttps](INFO): SBD MOMSN=23882731
2025-01-07T19:20:33.930Z,1736277633.930 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:20:33.930Z,1736277633.930 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:20:33.931Z,1736277633.931 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:20:52.498Z,1736277652.498 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:25:34.526Z,1736277934.526 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:25:34.526Z,1736277934.526 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:25:34.526Z,1736277934.526 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:25:34.526Z,1736277934.526 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:25:34.917Z,1736277934.917 [Default:CheckIn:D] Stopped
2025-01-07T19:25:34.917Z,1736277934.917 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.097005 min
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn:E] Stopped
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn] Stopped
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn](INFO): Running loop #18
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn] Running Loop=18
2025-01-07T19:25:35.326Z,1736277935.326 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:25:35.327Z,1736277935.327 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:25:37.334Z,1736277937.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192536.00,A,3648.13576,N,12147.20509,W,0.019,67.67,070125,,,D*4A
2025-01-07T19:25:37.336Z,1736277937.336 [NAL9602](INFO): GPS fix at 20250107T192536: (36.802263, -121.786751)
2025-01-07T19:25:37.348Z,1736277937.348 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:25:37.348Z,1736277937.348 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:25:44.585Z,1736277944.585 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250107T173032/Courier0061.lzma
2025-01-07T19:25:45.588Z,1736277945.588 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0061.lzma.bak
2025-01-07T19:25:45.588Z,1736277945.588 [DataOverHttps](INFO): SBD MOMSN=23882749
2025-01-07T19:25:54.721Z,1736277954.721 [NAL9602](INFO): SBD MO Status=0, MOMSN=61259, MT Status=0, MTMSN=0
2025-01-07T19:25:54.721Z,1736277954.721 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:26:01.342Z,1736277961.342 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250107T173032/Express0062.lzma
2025-01-07T19:26:02.344Z,1736277962.344 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0062.lzma.bak
2025-01-07T19:26:02.344Z,1736277962.344 [DataOverHttps](INFO): SBD MOMSN=23882756
2025-01-07T19:26:03.612Z,1736277963.612 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:26:03.612Z,1736277963.612 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:26:03.612Z,1736277963.612 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:26:25.407Z,1736277985.407 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:31:04.218Z,1736278264.218 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:31:04.218Z,1736278264.218 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:31:04.218Z,1736278264.218 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:31:04.218Z,1736278264.218 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:31:04.612Z,1736278264.612 [Default:CheckIn:D] Stopped
2025-01-07T19:31:04.612Z,1736278264.612 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.591935 min
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn:E] Stopped
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn] Stopped
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn](INFO): Running loop #19
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn] Running Loop=19
2025-01-07T19:31:05.022Z,1736278265.022 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:31:05.023Z,1736278265.023 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:31:07.037Z,1736278267.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193106.00,A,3648.31073,N,12147.12083,W,3.188,2.76,070125,,,D*73
2025-01-07T19:31:07.039Z,1736278267.039 [NAL9602](INFO): GPS fix at 20250107T193106: (36.805179, -121.785347)
2025-01-07T19:31:07.050Z,1736278267.050 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:31:07.050Z,1736278267.050 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:31:14.066Z,1736278274.066 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0064.lzma
2025-01-07T19:31:15.068Z,1736278275.068 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0064.lzma.bak
2025-01-07T19:31:15.068Z,1736278275.068 [DataOverHttps](INFO): SBD MOMSN=23882762
2025-01-07T19:31:25.209Z,1736278285.209 [NAL9602](INFO): SBD MO Status=0, MOMSN=61260, MT Status=0, MTMSN=0
2025-01-07T19:31:25.209Z,1736278285.209 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:31:30.822Z,1736278290.822 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250107T173032/Express0065.lzma
2025-01-07T19:31:31.824Z,1736278291.824 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0065.lzma.bak
2025-01-07T19:31:31.824Z,1736278291.824 [DataOverHttps](INFO): SBD MOMSN=23882765
2025-01-07T19:31:32.904Z,1736278292.904 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:31:32.904Z,1736278292.904 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:31:32.904Z,1736278292.904 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:31:55.910Z,1736278315.910 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:36:33.484Z,1736278593.484 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:36:33.484Z,1736278593.484 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:36:33.484Z,1736278593.484 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:36:33.484Z,1736278593.484 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:36:33.881Z,1736278593.881 [Default:CheckIn:D] Stopped
2025-01-07T19:36:33.881Z,1736278593.881 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.079753 min
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn:E] Stopped
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn] Stopped
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn](INFO): Running loop #20
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn] Running Loop=20
2025-01-07T19:36:34.281Z,1736278594.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:36:34.282Z,1736278594.282 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:36:36.298Z,1736278596.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193635.00,A,3648.46206,N,12147.29523,W,4.529,244.71,070125,,,D*7C
2025-01-07T19:36:36.300Z,1736278596.300 [NAL9602](INFO): GPS fix at 20250107T193635: (36.807701, -121.788254)
2025-01-07T19:36:36.311Z,1736278596.311 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:36:36.311Z,1736278596.311 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:36:43.657Z,1736278603.657 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0067.lzma
2025-01-07T19:36:44.660Z,1736278604.660 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0067.lzma.bak
2025-01-07T19:36:44.660Z,1736278604.660 [DataOverHttps](INFO): SBD MOMSN=23882787
2025-01-07T19:37:02.205Z,1736278622.205 [NAL9602](INFO): SBD MO Status=0, MOMSN=61261, MT Status=0, MTMSN=0
2025-01-07T19:37:02.205Z,1736278622.205 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:37:03.374Z,1736278623.374 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250107T173032/Express0068.lzma
2025-01-07T19:37:04.376Z,1736278624.376 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0068.lzma.bak
2025-01-07T19:37:04.377Z,1736278624.377 [DataOverHttps](INFO): SBD MOMSN=23882790
2025-01-07T19:37:05.445Z,1736278625.445 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:37:05.445Z,1736278625.445 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:37:05.445Z,1736278625.445 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:37:32.906Z,1736278652.906 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:42:06.042Z,1736278926.042 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:42:06.042Z,1736278926.042 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:42:06.042Z,1736278926.042 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:42:06.043Z,1736278926.043 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:42:06.432Z,1736278926.432 [Default:CheckIn:D] Stopped
2025-01-07T19:42:06.433Z,1736278926.433 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:42:06.843Z,1736278926.843 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.622274 min
2025-01-07T19:42:06.843Z,1736278926.843 [Default:CheckIn:E] Stopped
2025-01-07T19:42:06.843Z,1736278926.843 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:42:06.843Z,1736278926.843 [Default:CheckIn] Stopped
2025-01-07T19:42:06.843Z,1736278926.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:42:06.844Z,1736278926.844 [Default:CheckIn](INFO): Running loop #21
2025-01-07T19:42:06.844Z,1736278926.844 [Default:CheckIn] Running Loop=21
2025-01-07T19:42:06.844Z,1736278926.844 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:42:06.844Z,1736278926.844 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:42:08.853Z,1736278928.853 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194208.00,A,3648.30750,N,12147.98841,W,10.030,249.95,070125,,,D*4A
2025-01-07T19:42:08.865Z,1736278928.865 [NAL9602](INFO): GPS fix at 20250107T194208: (36.805125, -121.799807)
2025-01-07T19:42:08.876Z,1736278928.876 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:42:08.876Z,1736278928.876 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:42:16.362Z,1736278936.362 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250107T173032/Courier0070.lzma
2025-01-07T19:42:17.364Z,1736278937.364 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0070.lzma.bak
2025-01-07T19:42:17.364Z,1736278937.364 [DataOverHttps](INFO): SBD MOMSN=23882795
2025-01-07T19:42:30.741Z,1736278950.741 [NAL9602](INFO): SBD MO Status=0, MOMSN=61262, MT Status=0, MTMSN=0
2025-01-07T19:42:30.741Z,1736278950.741 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:42:35.734Z,1736278955.734 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20250107T173032/Express0071.lzma
2025-01-07T19:42:36.736Z,1736278956.736 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0071.lzma.bak
2025-01-07T19:42:36.736Z,1736278956.736 [DataOverHttps](INFO): SBD MOMSN=23882798
2025-01-07T19:42:38.017Z,1736278958.017 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:42:38.017Z,1736278958.017 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:42:38.017Z,1736278958.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:43:01.443Z,1736278981.443 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:47:38.600Z,1736279258.600 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:47:38.600Z,1736279258.600 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:47:38.600Z,1736279258.600 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:47:38.600Z,1736279258.600 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:47:39.005Z,1736279259.005 [Default:CheckIn:D] Stopped
2025-01-07T19:47:39.006Z,1736279259.006 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:47:39.419Z,1736279259.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.165153 min
2025-01-07T19:47:39.419Z,1736279259.419 [Default:CheckIn:E] Stopped
2025-01-07T19:47:39.419Z,1736279259.419 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:47:39.419Z,1736279259.419 [Default:CheckIn] Stopped
2025-01-07T19:47:39.419Z,1736279259.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:47:39.419Z,1736279259.419 [Default:CheckIn](INFO): Running loop #22
2025-01-07T19:47:39.419Z,1736279259.419 [Default:CheckIn] Running Loop=22
2025-01-07T19:47:39.420Z,1736279259.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:47:39.420Z,1736279259.420 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:47:41.425Z,1736279261.425 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194740.00,A,3648.35963,N,12149.22671,W,11.721,261.90,070125,,,A*46
2025-01-07T19:47:41.427Z,1736279261.427 [NAL9602](INFO): GPS fix at 20250107T194740: (36.805994, -121.820445)
2025-01-07T19:47:41.466Z,1736279261.466 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:47:41.466Z,1736279261.466 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:47:42.239Z,1736279262.239 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:47:42.239Z,1736279262.239 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:47:42.239Z,1736279262.239 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:48:15.357Z,1736279295.357 [NAL9602](INFO): SBD MO Status=2, MOMSN=61263, MT Status=2, MTMSN=0
2025-01-07T19:48:15.357Z,1736279295.357 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-07T19:48:33.153Z,1736279313.153 [NAL9602](INFO): SBD MO Status=2, MOMSN=61263, MT Status=2, MTMSN=0
2025-01-07T19:48:33.153Z,1736279313.153 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-01-07T19:48:44.065Z,1736279324.065 [NAL9602](INFO): SBD MO Status=0, MOMSN=61263, MT Status=0, MTMSN=0
2025-01-07T19:48:44.065Z,1736279324.065 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:49:14.787Z,1736279354.787 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:52:42.930Z,1736279562.930 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:52:42.930Z,1736279562.930 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:52:42.930Z,1736279562.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:52:42.930Z,1736279562.930 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:52:43.340Z,1736279563.340 [Default:CheckIn:D] Stopped
2025-01-07T19:52:43.340Z,1736279563.340 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:52:43.765Z,1736279563.765 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 139.237402 min
2025-01-07T19:52:43.765Z,1736279563.765 [Default:CheckIn:E] Stopped
2025-01-07T19:52:43.765Z,1736279563.765 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:52:43.766Z,1736279563.766 [Default:CheckIn] Stopped
2025-01-07T19:52:43.766Z,1736279563.766 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:52:43.766Z,1736279563.766 [Default:CheckIn](INFO): Running loop #23
2025-01-07T19:52:43.766Z,1736279563.766 [Default:CheckIn] Running Loop=23
2025-01-07T19:52:43.766Z,1736279563.766 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:52:43.766Z,1736279563.766 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:52:45.748Z,1736279565.748 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195245.00,A,3648.13398,N,12150.38491,W,10.827,228.55,070125,,,D*4B
2025-01-07T19:52:45.751Z,1736279565.751 [NAL9602](INFO): GPS fix at 20250107T195245: (36.802233, -121.839748)
2025-01-07T19:52:45.761Z,1736279565.761 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:52:45.761Z,1736279565.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:52:55.518Z,1736279575.518 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250107T173032/Courier0073.lzma
2025-01-07T19:52:56.520Z,1736279576.520 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0073.lzma.bak
2025-01-07T19:52:56.521Z,1736279576.521 [DataOverHttps](INFO): SBD MOMSN=23882822
2025-01-07T19:52:59.944Z,1736279579.944 [NAL9602](INFO): SBD MO Status=0, MOMSN=61264, MT Status=0, MTMSN=0
2025-01-07T19:52:59.944Z,1736279579.944 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:53:12.418Z,1736279592.418 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0076.lzma
2025-01-07T19:53:13.420Z,1736279593.420 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0076.lzma.bak
2025-01-07T19:53:13.420Z,1736279593.420 [DataOverHttps](INFO): SBD MOMSN=23882825
2025-01-07T19:53:29.262Z,1736279609.262 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20250107T173032/Express0074.lzma
2025-01-07T19:53:30.264Z,1736279610.264 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0074.lzma.bak
2025-01-07T19:53:30.264Z,1736279610.264 [DataOverHttps](INFO): SBD MOMSN=23882828
2025-01-07T19:53:30.643Z,1736279610.643 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:53:45.846Z,1736279625.846 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20250107T173032/Express0077.lzma
2025-01-07T19:53:46.868Z,1736279626.868 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0077.lzma.bak
2025-01-07T19:53:46.868Z,1736279626.868 [DataOverHttps](INFO): SBD MOMSN=23882832
2025-01-07T19:53:48.022Z,1736279628.022 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T19:53:48.022Z,1736279628.022 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T19:53:48.022Z,1736279628.022 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T19:58:48.738Z,1736279928.738 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-01-07T19:58:48.738Z,1736279928.738 [Default:CheckIn:C.Wait] Stopped
2025-01-07T19:58:48.738Z,1736279928.738 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-01-07T19:58:48.738Z,1736279928.738 [Default:CheckIn:D] Running Loop=1
2025-01-07T19:58:49.141Z,1736279929.141 [Default:CheckIn:D] Stopped
2025-01-07T19:58:49.141Z,1736279929.141 [Default:CheckIn:E] Running Loop=1
2025-01-07T19:58:49.560Z,1736279929.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.334082 min
2025-01-07T19:58:49.560Z,1736279929.560 [Default:CheckIn:E] Stopped
2025-01-07T19:58:49.561Z,1736279929.561 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-01-07T19:58:49.561Z,1736279929.561 [Default:CheckIn] Stopped
2025-01-07T19:58:49.561Z,1736279929.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-01-07T19:58:49.561Z,1736279929.561 [Default:CheckIn](INFO): Running loop #24
2025-01-07T19:58:49.561Z,1736279929.561 [Default:CheckIn] Running Loop=24
2025-01-07T19:58:49.561Z,1736279929.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-01-07T19:58:49.561Z,1736279929.561 [Default:CheckIn:Read_GPS] Running Loop=1
2025-01-07T19:58:51.549Z,1736279931.549 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195850.00,A,3647.83664,N,12150.88967,W,0.700,92.76,070125,,,D*43
2025-01-07T19:58:51.551Z,1736279931.551 [NAL9602](INFO): GPS fix at 20250107T195850: (36.797277, -121.848161)
2025-01-07T19:58:51.562Z,1736279931.562 [Default:CheckIn:Read_GPS] Stopped
2025-01-07T19:58:51.562Z,1736279931.562 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-01-07T19:58:52.193Z,1736279932.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T19:59:07.304Z,1736279947.304 [NAL9602](INFO): SBD MO Status=0, MOMSN=61265, MT Status=0, MTMSN=0
2025-01-07T19:59:07.304Z,1736279947.304 [NAL9602](INFO): No messages in MT queue
2025-01-07T19:59:23.311Z,1736279963.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-01-07T19:59:38.011Z,1736279978.011 [NAL9602](INFO): Not Powering down - fast GPS
2025-01-07T19:59:40.642Z,1736279980.642 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250107T173032/Courier0079.lzma
2025-01-07T19:59:41.644Z,1736279981.644 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Courier0079.lzma.bak
2025-01-07T19:59:41.644Z,1736279981.644 [DataOverHttps](INFO): SBD MOMSN=23882856
2025-01-07T19:59:57.370Z,1736279997.370 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20250107T173032/Express0080.lzma
2025-01-07T20:00:10.421Z,1736280010.421 [DataOverHttps](INFO): Moved sent file to Logs/20250107T173032/Express0080.lzma.bak
2025-01-07T20:00:10.421Z,1736280010.421 [DataOverHttps](INFO): SBD MOMSN=23882859
2025-01-07T20:00:11.553Z,1736280011.553 [Default:CheckIn:Read_Iridium] Stopped
2025-01-07T20:00:11.553Z,1736280011.553 [Default:CheckIn:C.Wait] Running Loop=1
2025-01-07T20:00:11.553Z,1736280011.553 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-01-07T20:03:20.972Z,1736280200.972 [DataOverHttps](IMPORTANT): SBD MTMSN=20250107T200320
2025-01-07T20:03:28.278Z,1736280208.278 [DataOverHttps](INFO): Received command: restart logs