2025-04-09T16:31:37.690Z,1744216297.690 [Supervisor](DEBUG): Initializing supervisor. 2025-04-09T16:31:37.695Z,1744216297.695 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-04-09T16:31:37.695Z,1744216297.695 [SyncHandler](INFO): Protected caller Thread ID is 1003 2025-04-09T16:31:37.696Z,1744216297.696 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-04-09T16:31:37.697Z,1744216297.697 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-04-09T16:31:37.697Z,1744216297.697 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1004 2025-04-09T16:31:37.701Z,1744216297.701 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-04-09T16:31:37.721Z,1744216297.721 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-04-09T16:31:37.722Z,1744216297.722 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-04-09T16:31:37.723Z,1744216297.723 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1005 2025-04-09T16:31:37.727Z,1744216297.727 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-04-09T16:31:37.728Z,1744216297.728 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-04-09T16:31:37.728Z,1744216297.728 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1006 2025-04-09T16:31:37.731Z,1744216297.731 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-04-09T16:31:37.732Z,1744216297.732 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-04-09T16:31:37.732Z,1744216297.732 [logger ThreadHandler](INFO): Protected caller Thread ID is 1007 2025-04-09T16:31:37.736Z,1744216297.736 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-04-09T16:31:37.736Z,1744216297.736 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-04-09T16:31:37.738Z,1744216297.738 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-04-09T16:31:37.846Z,1744216297.846 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-04-09T16:31:37.846Z,1744216297.846 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-04-09T16:31:38.087Z,1744216298.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-04-09T16:31:38.088Z,1744216298.088 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-04-09T16:31:38.370Z,1744216298.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-04-09T16:31:38.371Z,1744216298.371 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-04-09T16:31:38.489Z,1744216298.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-04-09T16:31:38.490Z,1744216298.490 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-04-09T16:31:39.286Z,1744216299.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-04-09T16:31:39.286Z,1744216299.286 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-04-09T16:31:39.863Z,1744216299.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-04-09T16:31:39.863Z,1744216299.863 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-04-09T16:31:40.607Z,1744216300.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-04-09T16:31:40.608Z,1744216300.608 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-04-09T16:31:41.050Z,1744216301.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-04-09T16:31:41.050Z,1744216301.050 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-04-09T16:31:41.419Z,1744216301.419 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-04-09T16:31:41.420Z,1744216301.420 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-04-09T16:31:41.816Z,1744216301.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-04-09T16:31:41.817Z,1744216301.817 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-04-09T16:31:42.058Z,1744216302.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-04-09T16:31:42.058Z,1744216302.058 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-04-09T16:31:42.211Z,1744216302.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-04-09T16:31:42.212Z,1744216302.212 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-04-09T16:31:42.305Z,1744216302.305 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-04-09T16:31:42.396Z,1744216302.396 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-04-09T16:31:42.603Z,1744216302.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-04-09T16:31:42.604Z,1744216302.604 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-04-09T16:31:42.863Z,1744216302.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-04-09T16:31:42.865Z,1744216302.865 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2025-04-09T16:31:42.866Z,1744216302.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2025-04-09T16:31:42.961Z,1744216302.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2025-04-09T16:31:43.240Z,1744216303.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-04-09T16:31:43.241Z,1744216303.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2025-04-09T16:31:43.336Z,1744216303.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2025-04-09T16:31:43.518Z,1744216303.518 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2025-04-09T16:31:43.630Z,1744216303.630 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2025-04-09T16:31:43.752Z,1744216303.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2025-04-09T16:31:43.877Z,1744216303.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2025-04-09T16:31:43.982Z,1744216303.982 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2025-04-09T16:31:44.155Z,1744216304.155 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2025-04-09T16:31:44.264Z,1744216304.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg 2025-04-09T16:31:44.359Z,1744216304.359 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2025-04-09T16:31:44.468Z,1744216304.468 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2025-04-09T16:31:44.566Z,1744216304.566 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2025-04-09T16:31:44.567Z,1744216304.567 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-04-09T16:31:44.574Z,1744216304.574 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-04-09T16:31:44.758Z,1744216304.758 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-04-09T16:31:44.758Z,1744216304.758 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-04-09T16:31:44.925Z,1744216304.925 [BuoyancyServo] Loaded 2025-04-09T16:31:44.926Z,1744216304.926 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-04-09T16:31:44.950Z,1744216304.950 [ElevatorServo] Loaded 2025-04-09T16:31:44.950Z,1744216304.950 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-04-09T16:31:44.972Z,1744216304.972 [MassServo] Loaded 2025-04-09T16:31:44.972Z,1744216304.972 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-04-09T16:31:44.994Z,1744216304.994 [RudderServo] Loaded 2025-04-09T16:31:44.995Z,1744216304.995 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-04-09T16:31:45.013Z,1744216305.013 [ThrusterHE] Loaded 2025-04-09T16:31:45.013Z,1744216305.013 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-04-09T16:31:45.014Z,1744216305.014 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-04-09T16:31:45.014Z,1744216305.014 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-04-09T16:31:45.390Z,1744216305.390 [SBIT](DEBUG): Construct Startup Built In Test. 2025-04-09T16:31:45.437Z,1744216305.437 [SBIT] Loaded 2025-04-09T16:31:45.437Z,1744216305.437 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-04-09T16:31:45.440Z,1744216305.440 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-04-09T16:31:45.461Z,1744216305.461 [IBIT] Loaded 2025-04-09T16:31:45.474Z,1744216305.474 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-04-09T16:31:45.479Z,1744216305.479 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-04-09T16:31:45.965Z,1744216305.965 [CBIT] Loaded 2025-04-09T16:31:45.966Z,1744216305.966 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-04-09T16:31:46.001Z,1744216306.001 [GFScanner] Loaded 2025-04-09T16:31:46.001Z,1744216306.001 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-04-09T16:31:46.002Z,1744216306.002 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-04-09T16:31:46.002Z,1744216306.002 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-04-09T16:31:46.169Z,1744216306.169 [CTD_Seabird] Loaded 2025-04-09T16:31:46.169Z,1744216306.169 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-04-09T16:31:46.170Z,1744216306.170 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406834E0 2025-04-09T16:31:46.171Z,1744216306.171 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1095 2025-04-09T16:31:46.206Z,1744216306.206 [ESPComponent] Loaded 2025-04-09T16:31:46.206Z,1744216306.206 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-04-09T16:31:46.228Z,1744216306.228 [PAR_Licor] Loaded 2025-04-09T16:31:46.228Z,1744216306.228 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-04-09T16:31:46.273Z,1744216306.273 [WetLabsBB2FL] Loaded 2025-04-09T16:31:46.274Z,1744216306.274 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-04-09T16:31:46.275Z,1744216306.275 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B34E0 2025-04-09T16:31:46.275Z,1744216306.275 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1096 2025-04-09T16:31:46.276Z,1744216306.276 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-04-09T16:31:46.276Z,1744216306.276 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-04-09T16:31:46.329Z,1744216306.329 [DepthRateCalculator] Loaded 2025-04-09T16:31:46.329Z,1744216306.329 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-04-09T16:31:46.334Z,1744216306.334 [PitchRateCalculator] Loaded 2025-04-09T16:31:46.334Z,1744216306.334 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-04-09T16:31:46.345Z,1744216306.345 [SpeedCalculator] Loaded 2025-04-09T16:31:46.345Z,1744216306.345 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-04-09T16:31:46.350Z,1744216306.350 [YawRateCalculator] Loaded 2025-04-09T16:31:46.350Z,1744216306.350 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-04-09T16:31:46.370Z,1744216306.370 [ElevatorOffsetCalculator] Loaded 2025-04-09T16:31:46.370Z,1744216306.370 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-04-09T16:31:46.371Z,1744216306.371 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-04-09T16:31:46.371Z,1744216306.371 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-04-09T16:31:46.509Z,1744216306.509 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-04-09T16:31:46.509Z,1744216306.509 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-04-09T16:31:46.539Z,1744216306.539 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-04-09T16:31:46.539Z,1744216306.539 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-04-09T16:31:46.653Z,1744216306.653 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-04-09T16:31:46.654Z,1744216306.654 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-04-09T16:31:46.668Z,1744216306.668 [NavChart] Loaded 2025-04-09T16:31:46.668Z,1744216306.668 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-04-09T16:31:46.674Z,1744216306.674 [UniversalFixResidualReporter] Loaded 2025-04-09T16:31:46.674Z,1744216306.674 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-04-09T16:31:46.675Z,1744216306.675 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-04-09T16:31:46.675Z,1744216306.675 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-04-09T16:31:47.162Z,1744216307.162 [AHRS_M2] Loaded 2025-04-09T16:31:47.162Z,1744216307.162 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-04-09T16:31:47.200Z,1744216307.200 [BackseatComponent] Loaded 2025-04-09T16:31:47.200Z,1744216307.200 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-04-09T16:31:47.201Z,1744216307.201 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0 2025-04-09T16:31:47.202Z,1744216307.202 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1097 2025-04-09T16:31:47.205Z,1744216307.205 [LcmUniversalReporter] Loaded 2025-04-09T16:31:47.205Z,1744216307.205 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-04-09T16:31:47.946Z,1744216307.946 [BPC1] Loaded 2025-04-09T16:31:47.946Z,1744216307.946 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-04-09T16:31:48.043Z,1744216308.043 [DAT] Loaded 2025-04-09T16:31:48.043Z,1744216308.043 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-04-09T16:31:48.044Z,1744216308.044 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0 2025-04-09T16:31:48.045Z,1744216308.045 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1098 2025-04-09T16:31:48.118Z,1744216308.118 [DataOverHttps] Loaded 2025-04-09T16:31:48.119Z,1744216308.119 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-04-09T16:31:48.120Z,1744216308.120 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0 2025-04-09T16:31:48.120Z,1744216308.120 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1099 2025-04-09T16:31:48.143Z,1744216308.143 [Depth_Keller] Loaded 2025-04-09T16:31:48.144Z,1744216308.144 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-04-09T16:31:48.149Z,1744216308.149 [DropWeight] Loaded 2025-04-09T16:31:48.149Z,1744216308.149 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-04-09T16:31:48.190Z,1744216308.190 [DVL_micro] Loaded 2025-04-09T16:31:48.190Z,1744216308.190 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2025-04-09T16:31:48.252Z,1744216308.252 [NAL9602] Loaded 2025-04-09T16:31:48.252Z,1744216308.252 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-04-09T16:31:48.278Z,1744216308.278 [Onboard] Loaded 2025-04-09T16:31:48.279Z,1744216308.279 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-04-09T16:31:48.280Z,1744216308.280 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0 2025-04-09T16:31:48.280Z,1744216308.280 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1100 2025-04-09T16:31:48.286Z,1744216308.286 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2025-04-09T16:31:48.300Z,1744216308.300 [PowerOnly] Loaded 2025-04-09T16:31:48.300Z,1744216308.300 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2025-04-09T16:31:48.314Z,1744216308.314 [Power24vConverter] Loaded 2025-04-09T16:31:48.314Z,1744216308.314 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-04-09T16:31:48.330Z,1744216308.330 [Radio_Surface] Loaded 2025-04-09T16:31:48.331Z,1744216308.331 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-04-09T16:31:48.332Z,1744216308.332 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0 2025-04-09T16:31:48.332Z,1744216308.332 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1101 2025-04-09T16:31:48.348Z,1744216308.348 [Sonardyne_Nano] Loaded 2025-04-09T16:31:48.348Z,1744216308.348 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-04-09T16:31:48.349Z,1744216308.349 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-04-09T16:31:48.349Z,1744216308.349 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-04-09T16:31:48.410Z,1744216308.410 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-04-09T16:31:48.411Z,1744216308.411 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-04-09T16:31:48.467Z,1744216308.467 [VerticalControl](DEBUG): Construct VerticalControl. 2025-04-09T16:31:48.519Z,1744216308.519 [VerticalControl] Loaded 2025-04-09T16:31:48.519Z,1744216308.519 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-04-09T16:31:48.522Z,1744216308.522 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-04-09T16:31:48.566Z,1744216308.566 [HorizontalControl] Loaded 2025-04-09T16:31:48.566Z,1744216308.566 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-04-09T16:31:48.569Z,1744216308.569 [SpeedControl](DEBUG): Construct SpeedControl. 2025-04-09T16:31:48.570Z,1744216308.570 [SpeedControl] Loaded 2025-04-09T16:31:48.571Z,1744216308.571 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-04-09T16:31:48.574Z,1744216308.574 [LoopControl](DEBUG): Construct LoopControl. 2025-04-09T16:31:48.574Z,1744216308.574 [LoopControl] Loaded 2025-04-09T16:31:48.574Z,1744216308.574 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-04-09T16:31:48.575Z,1744216308.575 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-04-09T16:31:48.575Z,1744216308.575 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-04-09T16:31:48.586Z,1744216308.586 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-04-09T16:31:48.586Z,1744216308.586 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-04-09T16:31:48.946Z,1744216308.946 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-04-09T16:31:48.952Z,1744216308.952 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-04-09T16:31:48.955Z,1744216308.955 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-04-09T16:31:48.966Z,1744216308.966 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-04-09T16:31:48.967Z,1744216308.967 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0 2025-04-09T16:31:48.968Z,1744216308.968 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1102 2025-04-09T16:31:48.972Z,1744216308.972 [Supervisor](INFO): Main Thread ID is 823 2025-04-09T16:31:48.972Z,1744216308.972 [Supervisor](DEBUG): Running supervisor. 2025-04-09T16:31:48.973Z,1744216308.973 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1103 2025-04-09T16:31:48.973Z,1744216308.973 [CommandExec](INFO): Initializing the command executive. 2025-04-09T16:31:48.975Z,1744216308.975 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1104 2025-04-09T16:31:48.978Z,1744216308.978 [controlThread ThreadHandler](INFO): Handler Thread ID is 1105 2025-04-09T16:31:48.979Z,1744216308.979 [controlThread](DEBUG): Initializing ControlThread 2025-04-09T16:31:48.980Z,1744216308.980 [SBIT](INFO): Initialize SBIT Component. 2025-04-09T16:31:48.981Z,1744216308.981 [SBIT](IMPORTANT): git: 2025-03-26-1-g1815535cc 2025-04-09T16:31:48.981Z,1744216308.981 [SBIT](INFO): git hash: 1815535cca105e464bdb7831f6233b5043f0283f 2025-04-09T16:31:48.981Z,1744216308.981 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-04-09T16:31:48.983Z,1744216308.983 [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-04-09T16:31:48.984Z,1744216308.984 [SBIT](INFO): Beginning SBIT in 69.000000 seconds. 2025-04-09T16:31:48.984Z,1744216308.984 [IBIT](INFO): Initialize IBIT Component. 2025-04-09T16:31:48.985Z,1744216308.985 [CBIT](DEBUG): Initialize CBIT Component. 2025-04-09T16:31:48.986Z,1744216308.986 [logger ThreadHandler](INFO): Handler Thread ID is 1106 2025-04-09T16:31:48.996Z,1744216308.996 [CBIT](DEBUG): Initialized mux pins. 2025-04-09T16:31:48.996Z,1744216308.996 [CBIT](DEBUG): Initializing the watchdog timer. 2025-04-09T16:31:49.004Z,1744216309.004 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1107 2025-04-09T16:31:49.005Z,1744216309.005 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-04-09T16:31:49.009Z,1744216309.009 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1109 2025-04-09T16:31:49.012Z,1744216309.012 [WetLabsBB2FL](INFO): Powering up 2025-04-09T16:31:49.014Z,1744216309.014 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1111 2025-04-09T16:31:49.020Z,1744216309.020 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-04-09T16:31:49.020Z,1744216309.020 [CBIT](DEBUG): Initializing heartbeat. 2025-04-09T16:31:49.021Z,1744216309.021 [DAT ThreadHandler](INFO): Handler Thread ID is 1112 2025-04-09T16:31:49.022Z,1744216309.022 [DAT](INFO): Powering up 2025-04-09T16:31:49.022Z,1744216309.022 [DAT](DEBUG): Initializing DAT. 2025-04-09T16:31:49.025Z,1744216309.025 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1114 2025-04-09T16:31:49.026Z,1744216309.026 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-04-09T16:31:49.037Z,1744216309.037 [Onboard ThreadHandler](INFO): Handler Thread ID is 1115 2025-04-09T16:31:49.054Z,1744216309.054 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1116 2025-04-09T16:31:49.072Z,1744216309.072 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1117 2025-04-09T16:31:49.074Z,1744216309.074 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-04-09T16:31:49.074Z,1744216309.074 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-04-09T16:31:49.075Z,1744216309.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-04-09T16:31:49.075Z,1744216309.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-04-09T16:31:49.075Z,1744216309.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-04-09T16:31:49.075Z,1744216309.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-04-09T16:31:49.075Z,1744216309.075 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-04-09T16:31:49.075Z,1744216309.075 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-04-09T16:31:49.092Z,1744216309.092 [CBIT](DEBUG): Deactivating emergency mode. 2025-04-09T16:31:49.132Z,1744216309.132 [CBIT](DEBUG): Backplane powered. 2025-04-09T16:31:49.132Z,1744216309.132 [GFScanner](DEBUG): Initializing GFScanner 2025-04-09T16:31:49.132Z,1744216309.132 [GFScanner](DEBUG): Deactivating GF circuits. 2025-04-09T16:31:49.140Z,1744216309.140 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-04-09T16:31:49.141Z,1744216309.141 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-04-09T16:31:49.141Z,1744216309.141 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-04-09T16:31:49.141Z,1744216309.141 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-04-09T16:31:49.142Z,1744216309.142 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-04-09T16:31:49.143Z,1744216309.143 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-04-09T16:31:49.143Z,1744216309.143 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-04-09T16:31:49.147Z,1744216309.147 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-04-09T16:31:49.153Z,1744216309.153 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-04-09T16:31:49.153Z,1744216309.153 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-04-09T16:31:49.154Z,1744216309.154 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-04-09T16:31:49.154Z,1744216309.154 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-04-09T16:31:49.155Z,1744216309.155 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-04-09T16:31:49.155Z,1744216309.155 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-04-09T16:31:49.161Z,1744216309.161 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-04-09T16:31:49.234Z,1744216309.234 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-04-09T16:31:49.239Z,1744216309.239 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-09T16:31:49.277Z,1744216309.277 [DAT](INFO): Using elevation to construct direction to contact in vehicle frame. 2025-04-09T16:31:49.300Z,1744216309.300 [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-04-09T16:31:49.301Z,1744216309.301 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-04-09T16:31:49.301Z,1744216309.301 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-04-09T16:31:49.302Z,1744216309.302 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-04-09T16:31:49.460Z,1744216309.460 [Radio_Surface](INFO): Powering up 2025-04-09T16:31:49.658Z,1744216309.658 [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-04-09T16:31:49.676Z,1744216309.676 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-04-09T16:31:49.677Z,1744216309.677 [Default:A.Wait](DEBUG): Construct Wait. 2025-04-09T16:31:49.679Z,1744216309.679 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-09T16:31:49.724Z,1744216309.724 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-09T16:31:49.727Z,1744216309.727 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-09T16:31:49.749Z,1744216309.749 [Default:E.Execute](DEBUG): Construct Execute. 2025-04-09T16:31:49.752Z,1744216309.752 [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-04-09T16:31:49.764Z,1744216309.764 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-09T16:31:49.774Z,1744216309.774 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-04-09T16:31:49.840Z,1744216309.840 [Depth_Keller](INFO): Initializing. 2025-04-09T16:31:49.846Z,1744216309.846 [Power24vConverter](INFO): Powering up. 2025-04-09T16:31:49.846Z,1744216309.846 [Sonardyne_Nano](INFO): Initializing. 2025-04-09T16:31:49.872Z,1744216309.872 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-09T16:31:49.883Z,1744216309.883 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-09T16:31:49.888Z,1744216309.888 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-09T16:31:49.889Z,1744216309.889 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-09T16:31:49.896Z,1744216309.896 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-09T16:31:49.897Z,1744216309.897 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-09T16:31:49.904Z,1744216309.904 [MassServo](DEBUG): Initializing MassServo. 2025-04-09T16:31:49.905Z,1744216309.905 [RudderServo](DEBUG): Initializing EZServoServo. 2025-04-09T16:31:49.912Z,1744216309.912 [RudderServo](DEBUG): Initializing RudderServo. 2025-04-09T16:31:49.913Z,1744216309.913 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-09T16:31:49.920Z,1744216309.920 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-09T16:31:50.237Z,1744216310.237 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m 2025-04-09T16:31:50.238Z,1744216310.238 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-09T16:31:50.238Z,1744216310.238 [DropWeight] Hardware Fault, FailCount= 1 2025-04-09T16:31:50.238Z,1744216310.238 [DropWeight](ERROR): Hardware Fault 2025-04-09T16:31:50.241Z,1744216310.241 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-09T16:31:50.265Z,1744216310.265 [CommandExec](FAULT): Scheduling is paused 2025-04-09T16:31:50.266Z,1744216310.266 [CBIT](INFO): Critical error at 20250409T163150 2025-04-09T16:31:50.266Z,1744216310.266 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-04-09T16:31:50.277Z,1744216310.277 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-09T16:31:50.277Z,1744216310.277 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-09T16:31:50.624Z,1744216310.624 [CBIT](INFO): Critical error at 20250409T163150 2025-04-09T16:31:51.624Z,1744216311.624 [WetLabsBB2FL](INFO): Powering down 2025-04-09T16:31:51.799Z,1744216311.799 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-04-09T16:31:53.081Z,1744216313.081 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2025-04-09T16:32:01.120Z,1744216321.120 [DAT](INFO): DAT read: 2025-04-09T16:32:01.122Z,1744216321.122 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-04-09T16:32:02.885Z,1744216322.885 [DAT](INFO): DAT read: MF Frequency Band 2025-04-09T16:32:02.886Z,1744216322.886 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-04-09T16:32:02.887Z,1744216322.887 [DAT](INFO): DAT read: Apr 9 2025 16:31:57 2025-04-09T16:32:03.895Z,1744216323.895 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-04-09T16:32:03.896Z,1744216323.896 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2025-04-09T16:32:03.896Z,1744216323.896 [DAT](INFO): commRate: 1200 2025-04-09T16:32:05.960Z,1744216325.960 [DAT](INFO): entering command mode 2025-04-09T16:32:06.161Z,1744216326.161 [DAT](INFO): DAT read: 2025-04-09T16:32:06.161Z,1744216326.161 [DAT](INFO): DAT read: user:1> 2025-04-09T16:32:06.161Z,1744216326.161 [DAT](INFO): setting verbose to 3 2025-04-09T16:32:06.413Z,1744216326.413 [DAT](INFO): DAT read: user:1> 2025-04-09T16:32:06.414Z,1744216326.414 [DAT](INFO): DAT read: Verbose | 3 2025-04-09T16:32:06.414Z,1744216326.414 [DAT](INFO): set verbose to 3 2025-04-09T16:32:06.414Z,1744216326.414 [DAT](INFO): setting DatVerbose to 27440 2025-04-09T16:32:06.665Z,1744216326.665 [DAT](INFO): DAT read: user:2> 2025-04-09T16:32:06.666Z,1744216326.666 [DAT](INFO): DAT read: DatVerbose | 27440 2025-04-09T16:32:06.666Z,1744216326.666 [DAT](INFO): set DatVerbose to 27440 2025-04-09T16:32:06.666Z,1744216326.666 [DAT](INFO): setting transmit power to 8 2025-04-09T16:32:06.917Z,1744216326.917 [DAT](INFO): DAT read: user:3> 2025-04-09T16:32:06.917Z,1744216326.917 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-04-09T16:32:06.918Z,1744216326.918 [DAT](INFO): set transmit power to 8 2025-04-09T16:32:06.918Z,1744216326.918 [DAT](INFO): setting local address to 5 2025-04-09T16:32:07.169Z,1744216327.169 [DAT](INFO): DAT read: user:4> 2025-04-09T16:32:07.170Z,1744216327.170 [DAT](INFO): DAT read: LocalAddr | 5 2025-04-09T16:32:07.170Z,1744216327.170 [DAT](INFO): set local address to 5 2025-04-09T16:32:07.171Z,1744216327.171 [DAT](INFO): Setting time to: 16:32:7 And date to:4/9/2025 2025-04-09T16:32:07.421Z,1744216327.421 [DAT](INFO): DAT read: user:5> 2025-04-09T16:32:07.422Z,1744216327.422 [DAT](INFO): DAT read: Wed Apr 9, 2025 16:32:07 2025-04-09T16:32:07.422Z,1744216327.422 [DAT](INFO): Local DAT time set to Wed Apr 9, 2025 16:32:07 2025-04-09T16:32:18.475Z,1744216338.475 [NAL9602](INFO): Powering up NAL9602 2025-04-09T16:32:29.376Z,1744216349.376 [NAL9602](INFO): NAL9602 initialized 2025-04-09T16:32:58.481Z,1744216378.481 [SBIT](IMPORTANT): Beginning Startup BIT 2025-04-09T16:32:58.486Z,1744216378.486 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-09T16:33:03.132Z,1744216383.132 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.059915 CHAN A1 (24V): 0.001022 CHAN A2 (12V): -0.004960 CHAN A3 (5V): -0.002564 CHAN B0 (3.3V): -0.001145 CHAN B1 (3.15aV): -0.000987 CHAN B2 (3.15bV): -0.001240 CHAN B3 (GND): -0.000577 OPEN: 0.006291 Full Scale: +/- 1 mA 2025-04-09T16:33:52.285Z,1744216432.285 [SBIT](IMPORTANT): SBIT PASSED 2025-04-09T16:33:52.286Z,1744216432.286 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-04-09T16:33:52.286Z,1744216432.286 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count; 2025-04-09T16:33:52.287Z,1744216432.287 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool; 2025-04-09T16:33:52.287Z,1744216432.287 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool; 2025-04-09T16:33:52.287Z,1744216432.287 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2025-04-09T16:33:52.287Z,1744216432.287 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=209.04 cubic_centimeter; 2025-04-09T16:33:52.287Z,1744216432.287 [SBIT](IMPORTANT): VerticalControl.massDefault=12.4082 millimeter; 2025-04-09T16:33:52.677Z,1744216432.677 [MissionManager](IMPORTANT): Started mission Startup 2025-04-09T16:33:52.678Z,1744216432.678 [Startup] Running Loop=1 2025-04-09T16:33:52.678Z,1744216432.678 [Startup](DEBUG): Aggregate::initialize Startup 2025-04-09T16:33:52.678Z,1744216432.678 [Startup:A.GoToSurface] Running Loop=1 2025-04-09T16:33:52.678Z,1744216432.678 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-09T16:33:52.678Z,1744216432.678 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-09T16:33:52.679Z,1744216432.679 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-09T16:33:52.679Z,1744216432.679 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-09T16:33:52.679Z,1744216432.679 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-09T16:33:52.680Z,1744216432.680 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-09T16:33:52.680Z,1744216432.680 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-09T16:33:52.682Z,1744216432.682 [Startup:StartupSatComms] Running Loop=1 2025-04-09T16:33:52.682Z,1744216432.682 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-04-09T16:33:52.682Z,1744216432.682 [Startup:StartupSatComms:A] Running Loop=1 2025-04-09T16:33:53.098Z,1744216433.098 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-04-09T16:34:01.003Z,1744216441.003 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005049 2025-04-09T16:34:49.247Z,1744216489.247 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-04-09T16:34:49.247Z,1744216489.247 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:34:49.283Z,1744216489.283 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:34:49.677Z,1744216489.677 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:34:49.677Z,1744216489.677 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-04-09T16:34:52.855Z,1744216492.855 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-09T16:33:52.7Z 2025-04-09T16:34:52.855Z,1744216492.855 [Startup:StartupSatComms:A] Stopped 2025-04-09T16:34:52.855Z,1744216492.855 [Startup:StartupSatComms:B] Running Loop=1 2025-04-09T16:34:53.257Z,1744216493.257 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-09T16:35:00.271Z,1744216500.271 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T162200/Courier0007.lzma 2025-04-09T16:35:01.274Z,1744216501.274 [DataOverHttps](INFO): Moved sent file to Logs/20250409T162200/Courier0007.lzma.bak 2025-04-09T16:35:01.274Z,1744216501.274 [DataOverHttps](INFO): SBD MOMSN=24596763 2025-04-09T16:35:17.072Z,1744216517.072 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20250409T163137/Courier0000.lzma 2025-04-09T16:35:18.074Z,1744216518.074 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0000.lzma.bak 2025-04-09T16:35:18.074Z,1744216518.074 [DataOverHttps](INFO): SBD MOMSN=24596765 2025-04-09T16:35:18.861Z,1744216518.861 [CommandExec](IMPORTANT): got command strobe off 2025-04-09T16:35:18.861Z,1744216518.861 [CommandExec](IMPORTANT): Deactivating strobe 2025-04-09T16:35:33.855Z,1744216533.855 [DataOverHttps](INFO): Sending 91 bytes from file Logs/20250409T162200/Express0008.lzma 2025-04-09T16:35:34.858Z,1744216534.858 [DataOverHttps](INFO): Moved sent file to Logs/20250409T162200/Express0008.lzma.bak 2025-04-09T16:35:34.858Z,1744216534.858 [DataOverHttps](INFO): SBD MOMSN=24596770 2025-04-09T16:35:53.033Z,1744216553.033 [DataOverHttps](INFO): Sending 862 bytes from file Logs/20250409T163137/Express0001.lzma 2025-04-09T16:35:53.091Z,1744216553.091 [Startup:StartupSatComms:B](INFO): Timed out from 2025-04-09T16:34:52.9Z 2025-04-09T16:35:53.091Z,1744216553.091 [Startup:StartupSatComms:B] Stopped 2025-04-09T16:35:53.091Z,1744216553.091 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-04-09T16:35:53.091Z,1744216553.091 [Startup:StartupSatComms] Stopped 2025-04-09T16:35:53.091Z,1744216553.091 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-04-09T16:35:53.093Z,1744216553.093 [Startup](INFO): Completed Startup 2025-04-09T16:35:53.093Z,1744216553.093 [MissionManager](INFO): Startup is completed. 2025-04-09T16:35:53.093Z,1744216553.093 [MissionManager](INFO): Uninitializing Mission Startup 2025-04-09T16:35:53.093Z,1744216553.093 [Startup] Stopped 2025-04-09T16:35:53.093Z,1744216553.093 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-04-09T16:35:53.093Z,1744216553.093 [Startup:A.GoToSurface] Stopped 2025-04-09T16:35:53.093Z,1744216553.093 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-09T16:35:53.481Z,1744216553.481 [MissionManager](IMPORTANT): Started mission Default 2025-04-09T16:35:53.481Z,1744216553.481 [Default] Running Loop=1 2025-04-09T16:35:53.481Z,1744216553.481 [Default](DEBUG): Aggregate::initialize Default 2025-04-09T16:35:53.482Z,1744216553.482 [Default:B.GoToSurface] Running Loop=1 2025-04-09T16:35:53.482Z,1744216553.482 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-09T16:35:53.482Z,1744216553.482 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-09T16:35:53.482Z,1744216553.482 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-09T16:35:53.482Z,1744216553.482 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-09T16:35:53.483Z,1744216553.483 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-09T16:35:53.483Z,1744216553.483 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-09T16:35:53.483Z,1744216553.483 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-09T16:35:53.484Z,1744216553.484 [Default:A.Wait] Running Loop=1 2025-04-09T16:35:53.484Z,1744216553.484 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-09T16:35:54.036Z,1744216554.036 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0001.lzma.bak 2025-04-09T16:35:54.036Z,1744216554.036 [DataOverHttps](INFO): SBD MOMSN=24596773 2025-04-09T16:36:06.787Z,1744216566.787 [Default:A.Wait](INFO): Done Waiting. 2025-04-09T16:36:06.787Z,1744216566.787 [Default:A.Wait] Stopped 2025-04-09T16:36:06.787Z,1744216566.787 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T16:36:07.253Z,1744216567.253 [Default:CheckIn] Running Loop=1 2025-04-09T16:36:07.253Z,1744216567.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T16:36:07.253Z,1744216567.253 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T16:36:07.586Z,1744216567.586 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-09T16:37:32.429Z,1744216652.429 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-09T16:37:50.216Z,1744216670.216 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-04-09T16:37:50.216Z,1744216670.216 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:37:50.227Z,1744216670.227 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:37:50.622Z,1744216670.622 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:37:50.622Z,1744216670.622 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-04-09T16:39:26.761Z,1744216766.761 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-04-09T16:39:26.780Z,1744216766.780 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T16:40:51.203Z,1744216851.203 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-04-09T16:40:51.203Z,1744216851.203 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:40:51.245Z,1744216851.245 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:40:51.616Z,1744216851.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:40:51.616Z,1744216851.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-04-09T16:41:07.387Z,1744216867.387 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T16:36:07.3Z 2025-04-09T16:41:07.387Z,1744216867.387 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T16:41:07.387Z,1744216867.387 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T16:41:07.778Z,1744216867.778 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-09T16:41:15.040Z,1744216875.040 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250409T163137/Courier0004.lzma 2025-04-09T16:41:16.042Z,1744216876.042 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0004.lzma.bak 2025-04-09T16:41:16.042Z,1744216876.042 [DataOverHttps](INFO): SBD MOMSN=24596883 2025-04-09T16:41:31.952Z,1744216891.952 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20250409T163137/Express0005.lzma 2025-04-09T16:41:32.954Z,1744216892.954 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0005.lzma.bak 2025-04-09T16:41:32.954Z,1744216892.954 [DataOverHttps](INFO): SBD MOMSN=24596885 2025-04-09T16:41:34.052Z,1744216894.052 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T16:41:34.052Z,1744216894.052 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T16:41:34.052Z,1744216894.052 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T16:42:28.567Z,1744216948.567 [DVL_micro](ERROR): Failed to parse: :WI,-00904,-00122,-00900,+00000,A 2025-04-09T16:42:33.002Z,1744216953.002 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T16:42:33.002Z,1744216953.002 [NAL9602] Data Fault, FailCount= 1 2025-04-09T16:42:33.002Z,1744216953.002 [NAL9602](ERROR): Data Fault 2025-04-09T16:42:33.019Z,1744216953.019 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T16:42:33.402Z,1744216953.402 [NAL9602](INFO): Powering down 2025-04-09T16:42:34.222Z,1744216954.222 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T16:42:34.222Z,1744216954.222 [NAL9602] No Fault, FailCount= 1 2025-04-09T16:43:03.700Z,1744216983.700 [NAL9602](INFO): Powering up NAL9602 2025-04-09T16:43:14.622Z,1744216994.622 [NAL9602](INFO): NAL9602 initialized 2025-04-09T16:43:52.190Z,1744217032.190 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-04-09T16:43:52.190Z,1744217032.190 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:43:52.200Z,1744217032.200 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:43:52.657Z,1744217032.657 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:43:52.658Z,1744217032.658 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-04-09T16:46:34.600Z,1744217194.600 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T16:46:34.601Z,1744217194.601 [Default:CheckIn:C.Wait] Stopped 2025-04-09T16:46:34.601Z,1744217194.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T16:46:34.601Z,1744217194.601 [Default:CheckIn:D] Running Loop=1 2025-04-09T16:46:35.009Z,1744217195.009 [Default:CheckIn:D] Stopped 2025-04-09T16:46:35.009Z,1744217195.009 [Default:CheckIn:E] Running Loop=1 2025-04-09T16:46:35.402Z,1744217195.402 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.692125 min 2025-04-09T16:46:35.402Z,1744217195.402 [Default:CheckIn:E] Stopped 2025-04-09T16:46:35.402Z,1744217195.402 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T16:46:35.403Z,1744217195.403 [Default:CheckIn] Stopped 2025-04-09T16:46:35.403Z,1744217195.403 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T16:46:35.403Z,1744217195.403 [Default:CheckIn](INFO): Running loop #2 2025-04-09T16:46:35.403Z,1744217195.403 [Default:CheckIn] Running Loop=2 2025-04-09T16:46:35.403Z,1744217195.403 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T16:46:35.403Z,1744217195.403 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T16:46:50.362Z,1744217210.362 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-09T16:46:50.362Z,1744217210.362 [DropWeight] No Fault, FailCount= 1 2025-04-09T16:46:53.181Z,1744217213.181 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-04-09T16:46:53.181Z,1744217213.181 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:46:53.222Z,1744217213.222 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:46:53.595Z,1744217213.595 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:46:53.595Z,1744217213.595 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-04-09T16:49:54.203Z,1744217394.203 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-04-09T16:49:54.203Z,1744217394.203 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:49:54.214Z,1744217394.214 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:49:54.610Z,1744217394.610 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:49:54.610Z,1744217394.610 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-04-09T16:50:50.730Z,1744217450.730 [DVL_micro](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BI,-01021,-00000.00 2025-04-09T16:50:50.731Z,1744217450.731 [DVL_micro](ERROR): Failed to parse: :BI,-01021,-00000.00 2025-04-09T16:51:35.610Z,1744217495.610 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T16:46:35.4Z 2025-04-09T16:51:35.610Z,1744217495.610 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T16:51:35.610Z,1744217495.610 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T16:51:42.555Z,1744217502.555 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0007.lzma 2025-04-09T16:51:43.558Z,1744217503.558 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0007.lzma.bak 2025-04-09T16:51:43.558Z,1744217503.558 [DataOverHttps](INFO): SBD MOMSN=24597035 2025-04-09T16:51:59.328Z,1744217519.328 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20250409T163137/Express0008.lzma 2025-04-09T16:52:00.330Z,1744217520.330 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0008.lzma.bak 2025-04-09T16:52:00.330Z,1744217520.330 [DataOverHttps](INFO): SBD MOMSN=24597037 2025-04-09T16:52:01.446Z,1744217521.446 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T16:52:01.446Z,1744217521.446 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T16:52:01.446Z,1744217521.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T16:52:55.178Z,1744217575.178 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-04-09T16:52:55.178Z,1744217575.178 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:52:55.188Z,1744217575.188 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:52:55.605Z,1744217575.605 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:52:55.605Z,1744217575.605 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-04-09T16:53:15.766Z,1744217595.766 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T16:53:15.766Z,1744217595.766 [NAL9602] Data Fault, FailCount= 2 2025-04-09T16:53:15.766Z,1744217595.766 [NAL9602](ERROR): Data Fault 2025-04-09T16:53:15.782Z,1744217595.782 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T16:53:16.170Z,1744217596.170 [NAL9602](INFO): Powering down 2025-04-09T16:53:16.986Z,1744217596.986 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T16:53:16.986Z,1744217596.986 [NAL9602] No Fault, FailCount= 2 2025-04-09T16:53:24.777Z,1744217604.777 [CommandExec](IMPORTANT): got command burn on 2025-04-09T16:53:24.777Z,1744217604.777 [CommandExec](IMPORTANT): Activating dropweight wire 2025-04-09T16:53:25.865Z,1744217605.865 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-09T16:53:25.865Z,1744217605.865 [DropWeight] Hardware Fault, FailCount= 1 2025-04-09T16:53:25.865Z,1744217605.865 [DropWeight](ERROR): Hardware Fault 2025-04-09T16:53:25.928Z,1744217605.928 [CBIT](INFO): Critical error at 20250409T165325 2025-04-09T16:53:25.931Z,1744217605.931 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-09T16:53:25.931Z,1744217605.931 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-09T16:53:26.303Z,1744217606.303 [CBIT](INFO): Critical error at 20250409T165325 2025-04-09T16:53:46.476Z,1744217626.476 [NAL9602](INFO): Powering up NAL9602 2025-04-09T16:53:57.378Z,1744217637.378 [NAL9602](INFO): NAL9602 initialized 2025-04-09T16:54:11.377Z,1744217651.377 [CommandExec](IMPORTANT): got command burn off 2025-04-09T16:54:11.377Z,1744217651.377 [CommandExec](IMPORTANT): Deactivating dropweight wire 2025-04-09T16:55:56.205Z,1744217756.205 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-04-09T16:55:56.205Z,1744217756.205 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:55:56.215Z,1744217756.215 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:55:56.621Z,1744217756.621 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:55:56.621Z,1744217756.621 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2025-04-09T16:57:02.034Z,1744217822.034 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T16:57:02.034Z,1744217822.034 [Default:CheckIn:C.Wait] Stopped 2025-04-09T16:57:02.034Z,1744217822.034 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T16:57:02.034Z,1744217822.034 [Default:CheckIn:D] Running Loop=1 2025-04-09T16:57:02.441Z,1744217822.441 [Default:CheckIn:D] Stopped 2025-04-09T16:57:02.441Z,1744217822.441 [Default:CheckIn:E] Running Loop=1 2025-04-09T16:57:02.840Z,1744217822.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.149323 min 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn:E] Stopped 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn] Stopped 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn](INFO): Running loop #3 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn] Running Loop=3 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T16:57:02.841Z,1744217822.841 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T16:58:57.173Z,1744217937.173 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2025-04-09T16:58:57.173Z,1744217937.173 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T16:58:57.211Z,1744217937.211 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T16:58:57.616Z,1744217937.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T16:58:57.616Z,1744217937.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2025-04-09T17:01:58.165Z,1744218118.165 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2025-04-09T17:01:58.165Z,1744218118.165 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:01:58.175Z,1744218118.175 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:01:58.597Z,1744218118.597 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:01:58.597Z,1744218118.597 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2025-04-09T17:02:03.044Z,1744218123.044 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T16:57:02.8Z 2025-04-09T17:02:03.044Z,1744218123.044 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T17:02:03.045Z,1744218123.045 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T17:02:10.724Z,1744218130.724 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20250409T163137/Courier0010.lzma 2025-04-09T17:02:11.726Z,1744218131.726 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0010.lzma.bak 2025-04-09T17:02:11.726Z,1744218131.726 [DataOverHttps](INFO): SBD MOMSN=24597157 2025-04-09T17:02:27.640Z,1744218147.640 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20250409T163137/Express0011.lzma 2025-04-09T17:02:28.642Z,1744218148.642 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0011.lzma.bak 2025-04-09T17:02:28.642Z,1744218148.642 [DataOverHttps](INFO): SBD MOMSN=24597161 2025-04-09T17:02:29.688Z,1744218149.688 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T17:02:29.688Z,1744218149.688 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T17:02:29.689Z,1744218149.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T17:04:00.983Z,1744218240.983 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T17:04:00.983Z,1744218240.983 [NAL9602] Data Fault, FailCount= 3 2025-04-09T17:04:00.983Z,1744218240.983 [NAL9602](ERROR): Data Fault 2025-04-09T17:04:01.006Z,1744218241.006 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T17:04:01.378Z,1744218241.378 [NAL9602](INFO): Powering down 2025-04-09T17:04:02.206Z,1744218242.206 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T17:04:02.207Z,1744218242.207 [NAL9602] No Fault, FailCount= 3 2025-04-09T17:04:31.678Z,1744218271.678 [NAL9602](INFO): Powering up NAL9602 2025-04-09T17:04:42.596Z,1744218282.596 [NAL9602](INFO): NAL9602 initialized 2025-04-09T17:04:59.160Z,1744218299.160 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2025-04-09T17:04:59.161Z,1744218299.161 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:04:59.175Z,1744218299.175 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:04:59.585Z,1744218299.585 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:04:59.585Z,1744218299.585 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2025-04-09T17:06:46.273Z,1744218406.273 [CommandExec](IMPORTANT): got command get PowerOnly.loadControl 2025-04-09T17:06:46.274Z,1744218406.274 [CommandExec](IMPORTANT): PowerOnly.loadControl /dev/loadB1 2025-04-09T17:07:30.271Z,1744218450.271 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T17:07:30.271Z,1744218450.271 [Default:CheckIn:C.Wait] Stopped 2025-04-09T17:07:30.272Z,1744218450.272 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T17:07:30.272Z,1744218450.272 [Default:CheckIn:D] Running Loop=1 2025-04-09T17:07:30.658Z,1744218450.658 [Default:CheckIn:D] Stopped 2025-04-09T17:07:30.658Z,1744218450.658 [Default:CheckIn:E] Running Loop=1 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.619607 min 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn:E] Stopped 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn] Stopped 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn](INFO): Running loop #4 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn] Running Loop=4 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T17:07:31.063Z,1744218451.063 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T17:08:00.168Z,1744218480.168 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2025-04-09T17:08:00.168Z,1744218480.168 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:08:00.198Z,1744218480.198 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:08:00.585Z,1744218480.585 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:08:00.585Z,1744218480.585 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2025-04-09T17:08:26.020Z,1744218506.020 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-09T17:08:26.021Z,1744218506.021 [DropWeight] No Fault, FailCount= 1 2025-04-09T17:11:01.164Z,1744218661.164 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2025-04-09T17:11:01.164Z,1744218661.164 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:11:01.175Z,1744218661.175 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:11:01.585Z,1744218661.585 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:11:01.585Z,1744218661.585 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2025-04-09T17:12:31.260Z,1744218751.260 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T17:07:31.1Z 2025-04-09T17:12:31.260Z,1744218751.260 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T17:12:31.260Z,1744218751.260 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T17:12:38.945Z,1744218758.945 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0013.lzma 2025-04-09T17:12:39.946Z,1744218759.946 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0013.lzma.bak 2025-04-09T17:12:39.946Z,1744218759.946 [DataOverHttps](INFO): SBD MOMSN=24597275 2025-04-09T17:12:55.784Z,1744218775.784 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250409T163137/Express0014.lzma 2025-04-09T17:12:56.786Z,1744218776.786 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0014.lzma.bak 2025-04-09T17:12:56.786Z,1744218776.786 [DataOverHttps](INFO): SBD MOMSN=24597277 2025-04-09T17:12:57.925Z,1744218777.925 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T17:12:57.925Z,1744218777.925 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T17:12:57.925Z,1744218777.925 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T17:14:02.152Z,1744218842.152 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2025-04-09T17:14:02.152Z,1744218842.152 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:14:02.167Z,1744218842.167 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:14:02.575Z,1744218842.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:14:02.575Z,1744218842.575 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2025-04-09T17:14:45.758Z,1744218885.758 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T17:14:45.758Z,1744218885.758 [NAL9602] Data Fault, FailCount= 4 2025-04-09T17:14:45.759Z,1744218885.759 [NAL9602](ERROR): Data Fault 2025-04-09T17:14:45.779Z,1744218885.779 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T17:14:46.214Z,1744218886.214 [NAL9602](INFO): Powering down 2025-04-09T17:14:46.991Z,1744218886.991 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T17:14:46.991Z,1744218886.991 [NAL9602] No Fault, FailCount= 4 2025-04-09T17:15:16.474Z,1744218916.474 [NAL9602](INFO): Powering up NAL9602 2025-04-09T17:15:27.376Z,1744218927.376 [NAL9602](INFO): NAL9602 initialized 2025-04-09T17:17:03.159Z,1744219023.159 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2025-04-09T17:17:03.159Z,1744219023.159 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:17:03.175Z,1744219023.175 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:17:03.586Z,1744219023.586 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:17:03.586Z,1744219023.586 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2025-04-09T17:17:58.478Z,1744219078.478 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T17:17:58.479Z,1744219078.479 [Default:CheckIn:C.Wait] Stopped 2025-04-09T17:17:58.479Z,1744219078.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T17:17:58.479Z,1744219078.479 [Default:CheckIn:D] Running Loop=1 2025-04-09T17:17:58.909Z,1744219078.909 [Default:CheckIn:D] Stopped 2025-04-09T17:17:58.909Z,1744219078.909 [Default:CheckIn:E] Running Loop=1 2025-04-09T17:17:59.311Z,1744219079.311 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.090458 min 2025-04-09T17:17:59.311Z,1744219079.311 [Default:CheckIn:E] Stopped 2025-04-09T17:17:59.311Z,1744219079.311 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T17:17:59.311Z,1744219079.311 [Default:CheckIn] Stopped 2025-04-09T17:17:59.311Z,1744219079.311 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T17:17:59.312Z,1744219079.312 [Default:CheckIn](INFO): Running loop #5 2025-04-09T17:17:59.312Z,1744219079.312 [Default:CheckIn] Running Loop=5 2025-04-09T17:17:59.312Z,1744219079.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T17:17:59.312Z,1744219079.312 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T17:20:04.127Z,1744219204.127 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2025-04-09T17:20:04.127Z,1744219204.127 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:20:04.138Z,1744219204.138 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:20:04.531Z,1744219204.531 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:20:04.548Z,1744219204.548 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2025-04-09T17:22:59.474Z,1744219379.474 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T17:17:59.3Z 2025-04-09T17:22:59.474Z,1744219379.474 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T17:22:59.474Z,1744219379.474 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T17:23:05.144Z,1744219385.144 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2025-04-09T17:23:05.144Z,1744219385.144 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:23:05.166Z,1744219385.166 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:23:05.563Z,1744219385.563 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:23:05.563Z,1744219385.563 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2025-04-09T17:23:07.111Z,1744219387.111 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0016.lzma 2025-04-09T17:23:08.114Z,1744219388.114 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0016.lzma.bak 2025-04-09T17:23:08.114Z,1744219388.114 [DataOverHttps](INFO): SBD MOMSN=24597404 2025-04-09T17:23:24.008Z,1744219404.008 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T163137/Express0017.lzma 2025-04-09T17:23:25.010Z,1744219405.010 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0017.lzma.bak 2025-04-09T17:23:25.010Z,1744219405.010 [DataOverHttps](INFO): SBD MOMSN=24597406 2025-04-09T17:23:26.137Z,1744219406.137 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T17:23:26.137Z,1744219406.137 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T17:23:26.137Z,1744219406.137 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T17:25:30.546Z,1744219530.546 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T17:25:30.546Z,1744219530.546 [NAL9602] Data Fault, FailCount= 5 2025-04-09T17:25:30.546Z,1744219530.546 [NAL9602](ERROR): Data Fault 2025-04-09T17:25:30.608Z,1744219530.608 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T17:25:30.609Z,1744219530.609 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-09T17:25:30.955Z,1744219530.955 [NAL9602](INFO): Powering down 2025-04-09T17:25:30.990Z,1744219530.990 [CBIT](INFO): Critical error at 20250409T172530 2025-04-09T17:26:06.116Z,1744219566.116 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2025-04-09T17:26:06.116Z,1744219566.116 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:26:06.127Z,1744219566.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:26:06.531Z,1744219566.531 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:26:06.531Z,1744219566.531 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2025-04-09T17:27:30.548Z,1744219650.548 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T17:27:30.548Z,1744219650.548 [NAL9602] No Fault, FailCount= 5 2025-04-09T17:27:30.944Z,1744219650.944 [NAL9602](INFO): Powering up NAL9602 2025-04-09T17:27:41.860Z,1744219661.860 [NAL9602](INFO): NAL9602 initialized 2025-04-09T17:28:26.745Z,1744219706.745 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T17:28:26.745Z,1744219706.745 [Default:CheckIn:C.Wait] Stopped 2025-04-09T17:28:26.745Z,1744219706.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T17:28:26.745Z,1744219706.745 [Default:CheckIn:D] Running Loop=1 2025-04-09T17:28:27.122Z,1744219707.122 [Default:CheckIn:D] Stopped 2025-04-09T17:28:27.122Z,1744219707.122 [Default:CheckIn:E] Running Loop=1 2025-04-09T17:28:27.509Z,1744219707.509 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.560669 min 2025-04-09T17:28:27.509Z,1744219707.509 [Default:CheckIn:E] Stopped 2025-04-09T17:28:27.509Z,1744219707.509 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T17:28:27.509Z,1744219707.509 [Default:CheckIn] Stopped 2025-04-09T17:28:27.509Z,1744219707.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T17:28:27.509Z,1744219707.509 [Default:CheckIn](INFO): Running loop #6 2025-04-09T17:28:27.510Z,1744219707.510 [Default:CheckIn] Running Loop=6 2025-04-09T17:28:27.510Z,1744219707.510 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T17:28:27.510Z,1744219707.510 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T17:29:07.106Z,1744219747.106 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19 2025-04-09T17:29:07.106Z,1744219747.106 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:29:07.118Z,1744219747.118 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:29:07.504Z,1744219747.504 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:29:07.504Z,1744219747.504 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19 2025-04-09T17:32:08.100Z,1744219928.100 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20 2025-04-09T17:32:08.100Z,1744219928.100 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:32:08.119Z,1744219928.119 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:32:08.517Z,1744219928.517 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:32:08.517Z,1744219928.517 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20 2025-04-09T17:33:27.678Z,1744220007.678 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T17:28:27.5Z 2025-04-09T17:33:27.678Z,1744220007.678 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T17:33:27.678Z,1744220007.678 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T17:33:35.641Z,1744220015.641 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250409T163137/Courier0019.lzma 2025-04-09T17:33:36.642Z,1744220016.642 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0019.lzma.bak 2025-04-09T17:33:36.642Z,1744220016.642 [DataOverHttps](INFO): SBD MOMSN=24597485 2025-04-09T17:33:52.416Z,1744220032.416 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T163137/Express0020.lzma 2025-04-09T17:33:53.418Z,1744220033.418 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0020.lzma.bak 2025-04-09T17:33:53.418Z,1744220033.418 [DataOverHttps](INFO): SBD MOMSN=24597489 2025-04-09T17:33:54.742Z,1744220034.742 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T17:33:54.742Z,1744220034.742 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T17:33:54.742Z,1744220034.742 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T17:35:09.103Z,1744220109.103 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21 2025-04-09T17:35:09.103Z,1744220109.103 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:35:09.151Z,1744220109.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:35:09.529Z,1744220109.529 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:35:09.529Z,1744220109.529 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21 2025-04-09T17:37:45.022Z,1744220265.022 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T17:37:45.022Z,1744220265.022 [NAL9602] Data Fault, FailCount= 1 2025-04-09T17:37:45.022Z,1744220265.022 [NAL9602](ERROR): Data Fault 2025-04-09T17:37:45.039Z,1744220265.039 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T17:37:45.427Z,1744220265.427 [NAL9602](INFO): Powering down 2025-04-09T17:37:46.243Z,1744220266.243 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T17:37:46.243Z,1744220266.243 [NAL9602] No Fault, FailCount= 1 2025-04-09T17:38:10.077Z,1744220290.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22 2025-04-09T17:38:10.077Z,1744220290.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:38:10.087Z,1744220290.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:38:10.497Z,1744220290.497 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:38:10.497Z,1744220290.497 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22 2025-04-09T17:38:15.721Z,1744220295.721 [NAL9602](INFO): Powering up NAL9602 2025-04-09T17:38:26.623Z,1744220306.623 [NAL9602](INFO): NAL9602 initialized 2025-04-09T17:38:55.333Z,1744220335.333 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T17:38:55.333Z,1744220335.333 [Default:CheckIn:C.Wait] Stopped 2025-04-09T17:38:55.333Z,1744220335.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T17:38:55.333Z,1744220335.333 [Default:CheckIn:D] Running Loop=1 2025-04-09T17:38:55.737Z,1744220335.737 [Default:CheckIn:D] Stopped 2025-04-09T17:38:55.737Z,1744220335.737 [Default:CheckIn:E] Running Loop=1 2025-04-09T17:38:56.132Z,1744220336.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.037598 min 2025-04-09T17:38:56.132Z,1744220336.132 [Default:CheckIn:E] Stopped 2025-04-09T17:38:56.133Z,1744220336.133 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T17:38:56.133Z,1744220336.133 [Default:CheckIn] Stopped 2025-04-09T17:38:56.133Z,1744220336.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T17:38:56.133Z,1744220336.133 [Default:CheckIn](INFO): Running loop #7 2025-04-09T17:38:56.133Z,1744220336.133 [Default:CheckIn] Running Loop=7 2025-04-09T17:38:56.133Z,1744220336.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T17:38:56.133Z,1744220336.133 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T17:41:11.086Z,1744220471.086 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23 2025-04-09T17:41:11.086Z,1744220471.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:41:11.097Z,1744220471.097 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:41:11.503Z,1744220471.503 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:41:11.503Z,1744220471.503 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23 2025-04-09T17:43:56.365Z,1744220636.365 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T17:38:56.1Z 2025-04-09T17:43:56.365Z,1744220636.365 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T17:43:56.365Z,1744220636.365 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T17:44:03.604Z,1744220643.604 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0022.lzma 2025-04-09T17:44:04.606Z,1744220644.606 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0022.lzma.bak 2025-04-09T17:44:04.606Z,1744220644.606 [DataOverHttps](INFO): SBD MOMSN=24597576 2025-04-09T17:44:12.080Z,1744220652.080 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24 2025-04-09T17:44:12.080Z,1744220652.080 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:44:12.091Z,1744220652.091 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:44:12.479Z,1744220652.479 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:44:12.479Z,1744220652.479 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24 2025-04-09T17:44:20.588Z,1744220660.588 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T163137/Express0023.lzma 2025-04-09T17:44:21.582Z,1744220661.582 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0023.lzma.bak 2025-04-09T17:44:21.582Z,1744220661.582 [DataOverHttps](INFO): SBD MOMSN=24597579 2025-04-09T17:44:23.030Z,1744220663.030 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T17:44:23.030Z,1744220663.030 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T17:44:23.030Z,1744220663.030 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T17:47:03.752Z,1744220823.752 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-04-09T17:47:03.755Z,1744220823.755 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T17:47:13.050Z,1744220833.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25 2025-04-09T17:47:13.050Z,1744220833.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:47:13.078Z,1744220833.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:47:13.457Z,1744220833.457 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:47:13.457Z,1744220833.457 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25 2025-04-09T17:48:29.798Z,1744220909.798 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T17:48:29.798Z,1744220909.798 [NAL9602] Data Fault, FailCount= 2 2025-04-09T17:48:29.798Z,1744220909.798 [NAL9602](ERROR): Data Fault 2025-04-09T17:48:29.871Z,1744220909.871 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T17:48:30.232Z,1744220910.232 [NAL9602](INFO): Powering down 2025-04-09T17:48:31.069Z,1744220911.069 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T17:48:31.069Z,1744220911.069 [NAL9602] No Fault, FailCount= 2 2025-04-09T17:49:00.518Z,1744220940.518 [NAL9602](INFO): Powering up NAL9602 2025-04-09T17:49:11.440Z,1744220951.440 [NAL9602](INFO): NAL9602 initialized 2025-04-09T17:49:23.550Z,1744220963.550 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T17:49:23.550Z,1744220963.550 [Default:CheckIn:C.Wait] Stopped 2025-04-09T17:49:23.550Z,1744220963.550 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T17:49:23.550Z,1744220963.550 [Default:CheckIn:D] Running Loop=1 2025-04-09T17:49:23.977Z,1744220963.977 [Default:CheckIn:D] Stopped 2025-04-09T17:49:23.977Z,1744220963.977 [Default:CheckIn:E] Running Loop=1 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.508268 min 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn:E] Stopped 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn] Stopped 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn](INFO): Running loop #8 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn] Running Loop=8 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T17:49:24.390Z,1744220964.390 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T17:50:14.100Z,1744221014.100 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26 2025-04-09T17:50:14.100Z,1744221014.100 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:50:14.111Z,1744221014.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:50:14.513Z,1744221014.513 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:50:14.513Z,1744221014.513 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26 2025-04-09T17:53:15.117Z,1744221195.117 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27 2025-04-09T17:53:15.117Z,1744221195.117 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:53:15.128Z,1744221195.128 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:53:15.505Z,1744221195.505 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:53:15.505Z,1744221195.505 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27 2025-04-09T17:54:24.598Z,1744221264.598 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T17:49:24.4Z 2025-04-09T17:54:24.598Z,1744221264.598 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T17:54:24.598Z,1744221264.598 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T17:54:31.540Z,1744221271.540 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20250409T163137/Courier0025.lzma 2025-04-09T17:54:32.543Z,1744221272.543 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0025.lzma.bak 2025-04-09T17:54:32.543Z,1744221272.543 [DataOverHttps](INFO): SBD MOMSN=24597686 2025-04-09T17:54:49.392Z,1744221289.392 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250409T163137/Express0026.lzma 2025-04-09T17:54:50.394Z,1744221290.394 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0026.lzma.bak 2025-04-09T17:54:50.394Z,1744221290.394 [DataOverHttps](INFO): SBD MOMSN=24597688 2025-04-09T17:54:51.673Z,1744221291.673 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T17:54:51.673Z,1744221291.673 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T17:54:51.673Z,1744221291.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T17:56:16.091Z,1744221376.091 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28 2025-04-09T17:56:16.092Z,1744221376.092 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:56:16.102Z,1744221376.102 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:56:16.526Z,1744221376.526 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:56:16.527Z,1744221376.527 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28 2025-04-09T17:59:14.646Z,1744221554.646 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T17:59:14.646Z,1744221554.646 [NAL9602] Data Fault, FailCount= 3 2025-04-09T17:59:14.646Z,1744221554.646 [NAL9602](ERROR): Data Fault 2025-04-09T17:59:14.662Z,1744221554.662 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T17:59:15.066Z,1744221555.066 [NAL9602](INFO): Powering down 2025-04-09T17:59:15.870Z,1744221555.870 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T17:59:15.870Z,1744221555.870 [NAL9602] No Fault, FailCount= 3 2025-04-09T17:59:17.072Z,1744221557.072 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29 2025-04-09T17:59:17.072Z,1744221557.072 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T17:59:17.106Z,1744221557.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T17:59:17.487Z,1744221557.487 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T17:59:17.487Z,1744221557.487 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29 2025-04-09T17:59:45.346Z,1744221585.346 [NAL9602](INFO): Powering up NAL9602 2025-04-09T17:59:52.221Z,1744221592.221 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T17:59:52.221Z,1744221592.221 [Default:CheckIn:C.Wait] Stopped 2025-04-09T17:59:52.221Z,1744221592.221 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T17:59:52.221Z,1744221592.221 [Default:CheckIn:D] Running Loop=1 2025-04-09T17:59:52.629Z,1744221592.629 [Default:CheckIn:D] Stopped 2025-04-09T17:59:52.629Z,1744221592.629 [Default:CheckIn:E] Running Loop=1 2025-04-09T17:59:53.027Z,1744221593.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.985791 min 2025-04-09T17:59:53.027Z,1744221593.027 [Default:CheckIn:E] Stopped 2025-04-09T17:59:53.028Z,1744221593.028 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T17:59:53.028Z,1744221593.028 [Default:CheckIn] Stopped 2025-04-09T17:59:53.028Z,1744221593.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T17:59:53.028Z,1744221593.028 [Default:CheckIn](INFO): Running loop #9 2025-04-09T17:59:53.028Z,1744221593.028 [Default:CheckIn] Running Loop=9 2025-04-09T17:59:53.028Z,1744221593.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T17:59:53.029Z,1744221593.029 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T17:59:56.260Z,1744221596.260 [NAL9602](INFO): NAL9602 initialized 2025-04-09T18:02:18.124Z,1744221738.124 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30 2025-04-09T18:02:18.125Z,1744221738.125 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:02:18.135Z,1744221738.135 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:02:18.541Z,1744221738.541 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:02:18.542Z,1744221738.542 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30 2025-04-09T18:04:53.309Z,1744221893.309 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T17:59:53.0Z 2025-04-09T18:04:53.310Z,1744221893.310 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:04:53.310Z,1744221893.310 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:05:01.075Z,1744221901.075 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0028.lzma 2025-04-09T18:05:02.078Z,1744221902.078 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0028.lzma.bak 2025-04-09T18:05:02.078Z,1744221902.078 [DataOverHttps](INFO): SBD MOMSN=24597768 2025-04-09T18:05:17.860Z,1744221917.860 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250409T163137/Express0029.lzma 2025-04-09T18:05:18.862Z,1744221918.862 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0029.lzma.bak 2025-04-09T18:05:18.862Z,1744221918.862 [DataOverHttps](INFO): SBD MOMSN=24597770 2025-04-09T18:05:19.137Z,1744221919.137 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31 2025-04-09T18:05:19.137Z,1744221919.137 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:05:19.167Z,1744221919.167 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:05:19.555Z,1744221919.555 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:05:19.556Z,1744221919.556 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31 2025-04-09T18:05:19.947Z,1744221919.947 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:05:19.947Z,1744221919.947 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:05:19.947Z,1744221919.947 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:08:20.132Z,1744222100.132 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32 2025-04-09T18:08:20.132Z,1744222100.132 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:08:20.143Z,1744222100.143 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:08:20.544Z,1744222100.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:08:20.544Z,1744222100.544 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32 2025-04-09T18:09:57.110Z,1744222197.110 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T18:09:57.110Z,1744222197.110 [NAL9602] Data Fault, FailCount= 4 2025-04-09T18:09:57.110Z,1744222197.110 [NAL9602](ERROR): Data Fault 2025-04-09T18:09:57.139Z,1744222197.139 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T18:09:57.510Z,1744222197.510 [NAL9602](INFO): Powering down 2025-04-09T18:09:58.369Z,1744222198.369 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T18:09:58.369Z,1744222198.369 [NAL9602] No Fault, FailCount= 4 2025-04-09T18:10:20.541Z,1744222220.541 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:10:20.541Z,1744222220.541 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:10:20.541Z,1744222220.541 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:10:20.541Z,1744222220.541 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:10:20.942Z,1744222220.942 [Default:CheckIn:D] Stopped 2025-04-09T18:10:20.942Z,1744222220.942 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:10:21.361Z,1744222221.361 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.457666 min 2025-04-09T18:10:21.361Z,1744222221.361 [Default:CheckIn:E] Stopped 2025-04-09T18:10:21.361Z,1744222221.361 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:10:21.361Z,1744222221.361 [Default:CheckIn] Stopped 2025-04-09T18:10:21.361Z,1744222221.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:10:21.362Z,1744222221.362 [Default:CheckIn](INFO): Running loop #10 2025-04-09T18:10:21.362Z,1744222221.362 [Default:CheckIn] Running Loop=10 2025-04-09T18:10:21.362Z,1744222221.362 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:10:21.362Z,1744222221.362 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:10:27.818Z,1744222227.818 [NAL9602](INFO): Powering up NAL9602 2025-04-09T18:10:38.715Z,1744222238.715 [NAL9602](INFO): NAL9602 initialized 2025-04-09T18:11:21.155Z,1744222281.155 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33 2025-04-09T18:11:21.155Z,1744222281.155 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:11:21.165Z,1744222281.165 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:11:21.563Z,1744222281.563 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:11:21.563Z,1744222281.563 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33 2025-04-09T18:14:22.172Z,1744222462.172 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34 2025-04-09T18:14:22.172Z,1744222462.172 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:14:22.183Z,1744222462.183 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:14:22.609Z,1744222462.609 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:14:22.609Z,1744222462.609 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34 2025-04-09T18:15:21.582Z,1744222521.582 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T18:10:21.4Z 2025-04-09T18:15:21.582Z,1744222521.582 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:15:21.582Z,1744222521.582 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:15:29.255Z,1744222529.255 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0031.lzma 2025-04-09T18:15:30.258Z,1744222530.258 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0031.lzma.bak 2025-04-09T18:15:30.259Z,1744222530.259 [DataOverHttps](INFO): SBD MOMSN=24597939 2025-04-09T18:15:46.537Z,1744222546.537 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250409T163137/Express0032.lzma 2025-04-09T18:15:47.538Z,1744222547.538 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0032.lzma.bak 2025-04-09T18:15:47.538Z,1744222547.538 [DataOverHttps](INFO): SBD MOMSN=24597941 2025-04-09T18:15:48.633Z,1744222548.633 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:15:48.633Z,1744222548.633 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:15:48.633Z,1744222548.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:17:23.182Z,1744222643.182 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35 2025-04-09T18:17:23.182Z,1744222643.182 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:17:23.224Z,1744222643.224 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:17:23.594Z,1744222643.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:17:23.594Z,1744222643.594 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35 2025-04-09T18:20:24.188Z,1744222824.188 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36 2025-04-09T18:20:24.188Z,1744222824.188 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:20:24.198Z,1744222824.198 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:20:24.607Z,1744222824.607 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:20:24.607Z,1744222824.607 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36 2025-04-09T18:20:41.926Z,1744222841.926 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T18:20:41.926Z,1744222841.926 [NAL9602] Data Fault, FailCount= 5 2025-04-09T18:20:41.926Z,1744222841.926 [NAL9602](ERROR): Data Fault 2025-04-09T18:20:41.944Z,1744222841.944 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T18:20:41.944Z,1744222841.944 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-09T18:20:42.333Z,1744222842.333 [NAL9602](INFO): Powering down 2025-04-09T18:20:42.347Z,1744222842.347 [CBIT](INFO): Critical error at 20250409T182041 2025-04-09T18:20:49.219Z,1744222849.219 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:20:49.219Z,1744222849.219 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:20:49.221Z,1744222849.221 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:20:49.221Z,1744222849.221 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:20:49.637Z,1744222849.637 [Default:CheckIn:D] Stopped 2025-04-09T18:20:49.637Z,1744222849.637 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.935921 min 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn:E] Stopped 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn] Stopped 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn](INFO): Running loop #11 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn] Running Loop=11 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:20:50.014Z,1744222850.014 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:22:41.934Z,1744222961.934 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T18:22:41.935Z,1744222961.935 [NAL9602] No Fault, FailCount= 5 2025-04-09T18:22:42.322Z,1744222962.322 [NAL9602](INFO): Powering up NAL9602 2025-04-09T18:22:53.232Z,1744222973.232 [NAL9602](INFO): NAL9602 initialized 2025-04-09T18:23:25.178Z,1744223005.178 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37 2025-04-09T18:23:25.178Z,1744223005.178 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:23:25.189Z,1744223005.189 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:23:25.592Z,1744223005.592 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:23:25.592Z,1744223005.592 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37 2025-04-09T18:25:50.222Z,1744223150.222 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T18:20:50.0Z 2025-04-09T18:25:50.222Z,1744223150.222 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:25:50.222Z,1744223150.222 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:25:57.691Z,1744223157.691 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250409T163137/Courier0034.lzma 2025-04-09T18:25:58.694Z,1744223158.694 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0034.lzma.bak 2025-04-09T18:25:58.694Z,1744223158.694 [DataOverHttps](INFO): SBD MOMSN=24598101 2025-04-09T18:26:14.416Z,1744223174.416 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250409T163137/Express0035.lzma 2025-04-09T18:26:15.418Z,1744223175.418 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0035.lzma.bak 2025-04-09T18:26:15.418Z,1744223175.418 [DataOverHttps](INFO): SBD MOMSN=24598109 2025-04-09T18:26:16.501Z,1744223176.501 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:26:16.501Z,1744223176.501 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:26:16.501Z,1744223176.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:26:26.177Z,1744223186.177 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38 2025-04-09T18:26:26.177Z,1744223186.177 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:26:26.187Z,1744223186.187 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:26:26.634Z,1744223186.634 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:26:26.634Z,1744223186.634 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38 2025-04-09T18:28:13.284Z,1744223293.284 [DVL_micro](ERROR): only read 1 of 4 data items for beam range. Device response is::RD,+99 2025-04-09T18:28:13.285Z,1744223293.285 [DVL_micro](ERROR): Failed to parse: :RD,+99 2025-04-09T18:29:27.185Z,1744223367.185 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39 2025-04-09T18:29:27.185Z,1744223367.185 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:29:27.196Z,1744223367.196 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:29:27.606Z,1744223367.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:29:27.606Z,1744223367.606 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39 2025-04-09T18:31:17.063Z,1744223477.063 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:31:17.063Z,1744223477.063 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:31:17.064Z,1744223477.064 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:31:17.064Z,1744223477.064 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:31:17.478Z,1744223477.478 [Default:CheckIn:D] Stopped 2025-04-09T18:31:17.478Z,1744223477.478 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:31:17.896Z,1744223477.896 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.399943 min 2025-04-09T18:31:17.896Z,1744223477.896 [Default:CheckIn:E] Stopped 2025-04-09T18:31:17.896Z,1744223477.896 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:31:17.896Z,1744223477.896 [Default:CheckIn] Stopped 2025-04-09T18:31:17.897Z,1744223477.897 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:31:17.897Z,1744223477.897 [Default:CheckIn](INFO): Running loop #12 2025-04-09T18:31:17.897Z,1744223477.897 [Default:CheckIn] Running Loop=12 2025-04-09T18:31:17.897Z,1744223477.897 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:31:17.897Z,1744223477.897 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:32:28.178Z,1744223548.178 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40 2025-04-09T18:32:28.178Z,1744223548.178 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:32:28.204Z,1744223548.204 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:32:28.605Z,1744223548.605 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:32:28.605Z,1744223548.605 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40 2025-04-09T18:32:54.427Z,1744223574.427 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T18:32:54.427Z,1744223574.427 [NAL9602] Data Fault, FailCount= 1 2025-04-09T18:32:54.427Z,1744223574.427 [NAL9602](ERROR): Data Fault 2025-04-09T18:32:54.444Z,1744223574.444 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T18:32:54.826Z,1744223574.826 [NAL9602](INFO): Powering down 2025-04-09T18:32:55.683Z,1744223575.683 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T18:32:55.683Z,1744223575.683 [NAL9602] No Fault, FailCount= 1 2025-04-09T18:33:25.144Z,1744223605.144 [NAL9602](INFO): Powering up NAL9602 2025-04-09T18:33:36.039Z,1744223616.039 [NAL9602](INFO): NAL9602 initialized 2025-04-09T18:35:29.182Z,1744223729.182 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 41 2025-04-09T18:35:29.182Z,1744223729.182 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:35:29.201Z,1744223729.201 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:35:29.643Z,1744223729.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:35:29.643Z,1744223729.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 41 2025-04-09T18:36:18.050Z,1744223778.050 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T18:31:17.9Z 2025-04-09T18:36:18.050Z,1744223778.050 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:36:18.050Z,1744223778.050 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:36:25.431Z,1744223785.431 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0037.lzma 2025-04-09T18:36:26.434Z,1744223786.434 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0037.lzma.bak 2025-04-09T18:36:26.434Z,1744223786.434 [DataOverHttps](INFO): SBD MOMSN=24598217 2025-04-09T18:36:44.052Z,1744223804.052 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T163137/Express0038.lzma 2025-04-09T18:36:45.055Z,1744223805.055 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0038.lzma.bak 2025-04-09T18:36:45.055Z,1744223805.055 [DataOverHttps](INFO): SBD MOMSN=24598219 2025-04-09T18:36:46.365Z,1744223806.365 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:36:46.365Z,1744223806.365 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:36:46.365Z,1744223806.365 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:38:30.160Z,1744223910.160 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 42 2025-04-09T18:38:30.160Z,1744223910.160 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:38:30.191Z,1744223910.191 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:38:30.605Z,1744223910.605 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:38:30.605Z,1744223910.605 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 42 2025-04-09T18:41:31.197Z,1744224091.197 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 43 2025-04-09T18:41:31.197Z,1744224091.197 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:41:31.208Z,1744224091.208 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:41:31.578Z,1744224091.578 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:41:31.578Z,1744224091.578 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 43 2025-04-09T18:41:46.953Z,1744224106.953 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:41:46.953Z,1744224106.953 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:41:46.953Z,1744224106.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:41:46.953Z,1744224106.953 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:41:47.330Z,1744224107.330 [Default:CheckIn:D] Stopped 2025-04-09T18:41:47.330Z,1744224107.330 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:41:47.719Z,1744224107.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.897469 min 2025-04-09T18:41:47.719Z,1744224107.719 [Default:CheckIn:E] Stopped 2025-04-09T18:41:47.719Z,1744224107.719 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:41:47.720Z,1744224107.720 [Default:CheckIn] Stopped 2025-04-09T18:41:47.720Z,1744224107.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:41:47.720Z,1744224107.720 [Default:CheckIn](INFO): Running loop #13 2025-04-09T18:41:47.720Z,1744224107.720 [Default:CheckIn] Running Loop=13 2025-04-09T18:41:47.720Z,1744224107.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:41:47.720Z,1744224107.720 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:43:38.814Z,1744224218.814 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T18:43:38.814Z,1744224218.814 [NAL9602] Data Fault, FailCount= 2 2025-04-09T18:43:38.814Z,1744224218.814 [NAL9602](ERROR): Data Fault 2025-04-09T18:43:38.831Z,1744224218.831 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T18:43:39.215Z,1744224219.215 [NAL9602](INFO): Powering down 2025-04-09T18:43:40.038Z,1744224220.038 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T18:43:40.038Z,1744224220.038 [NAL9602] No Fault, FailCount= 2 2025-04-09T18:44:09.528Z,1744224249.528 [NAL9602](INFO): Powering up NAL9602 2025-04-09T18:44:20.437Z,1744224260.437 [NAL9602](INFO): NAL9602 initialized 2025-04-09T18:44:32.141Z,1744224272.141 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 44 2025-04-09T18:44:32.141Z,1744224272.141 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:44:32.160Z,1744224272.160 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:44:32.582Z,1744224272.582 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:44:32.582Z,1744224272.582 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 44 2025-04-09T18:46:47.925Z,1744224407.925 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T18:41:47.7Z 2025-04-09T18:46:47.926Z,1744224407.926 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:46:47.926Z,1744224407.926 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:46:55.016Z,1744224415.016 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0040.lzma 2025-04-09T18:46:56.031Z,1744224416.031 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0040.lzma.bak 2025-04-09T18:46:56.031Z,1744224416.031 [DataOverHttps](INFO): SBD MOMSN=24598315 2025-04-09T18:47:12.056Z,1744224432.056 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T163137/Express0041.lzma 2025-04-09T18:47:13.058Z,1744224433.058 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0041.lzma.bak 2025-04-09T18:47:13.058Z,1744224433.058 [DataOverHttps](INFO): SBD MOMSN=24598317 2025-04-09T18:47:14.218Z,1744224434.218 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:47:14.218Z,1744224434.218 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:47:14.218Z,1744224434.218 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:47:33.176Z,1744224453.176 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 45 2025-04-09T18:47:33.176Z,1744224453.176 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:47:33.187Z,1744224453.187 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:47:33.591Z,1744224453.591 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:47:33.591Z,1744224453.591 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 45 2025-04-09T18:50:34.166Z,1744224634.166 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 46 2025-04-09T18:50:34.166Z,1744224634.166 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:50:34.206Z,1744224634.206 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:50:34.573Z,1744224634.573 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:50:34.573Z,1744224634.573 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 46 2025-04-09T18:52:14.783Z,1744224734.783 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:52:14.783Z,1744224734.783 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:52:14.784Z,1744224734.784 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:52:14.784Z,1744224734.784 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:52:15.249Z,1744224735.249 [Default:CheckIn:D] Stopped 2025-04-09T18:52:15.249Z,1744224735.249 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:52:15.627Z,1744224735.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.362785 min 2025-04-09T18:52:15.628Z,1744224735.628 [Default:CheckIn:E] Stopped 2025-04-09T18:52:15.628Z,1744224735.628 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:52:15.628Z,1744224735.628 [Default:CheckIn] Stopped 2025-04-09T18:52:15.628Z,1744224735.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:52:15.628Z,1744224735.628 [Default:CheckIn](INFO): Running loop #14 2025-04-09T18:52:15.628Z,1744224735.628 [Default:CheckIn] Running Loop=14 2025-04-09T18:52:15.628Z,1744224735.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:52:15.629Z,1744224735.629 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:53:35.207Z,1744224815.207 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 47 2025-04-09T18:53:35.207Z,1744224815.207 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:53:35.219Z,1744224815.219 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:53:35.611Z,1744224815.611 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:53:35.611Z,1744224815.611 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 47 2025-04-09T18:54:16.783Z,1744224856.783 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-04-09T18:54:22.862Z,1744224862.862 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T18:54:22.862Z,1744224862.862 [NAL9602] Data Fault, FailCount= 3 2025-04-09T18:54:22.862Z,1744224862.862 [NAL9602](ERROR): Data Fault 2025-04-09T18:54:22.879Z,1744224862.879 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T18:54:23.246Z,1744224863.246 [NAL9602](INFO): Powering down 2025-04-09T18:54:24.082Z,1744224864.082 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T18:54:24.083Z,1744224864.083 [NAL9602] No Fault, FailCount= 3 2025-04-09T18:54:42.641Z,1744224882.641 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-04-09T18:54:42.643Z,1744224882.643 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T18:54:53.550Z,1744224893.550 [NAL9602](INFO): Powering up NAL9602 2025-04-09T18:55:04.466Z,1744224904.466 [NAL9602](INFO): NAL9602 initialized 2025-04-09T18:56:36.179Z,1744224996.179 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 48 2025-04-09T18:56:36.179Z,1744224996.179 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:56:36.190Z,1744224996.190 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:56:36.604Z,1744224996.604 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:56:36.604Z,1744224996.604 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 48 2025-04-09T18:57:15.758Z,1744225035.758 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T18:52:15.6Z 2025-04-09T18:57:15.758Z,1744225035.758 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:57:15.759Z,1744225035.759 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:57:23.283Z,1744225043.283 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20250409T163137/Courier0043.lzma 2025-04-09T18:57:24.286Z,1744225044.286 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0043.lzma.bak 2025-04-09T18:57:24.286Z,1744225044.286 [DataOverHttps](INFO): SBD MOMSN=24598415 2025-04-09T18:57:40.064Z,1744225060.064 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T163137/Express0044.lzma 2025-04-09T18:57:41.066Z,1744225061.066 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0044.lzma.bak 2025-04-09T18:57:41.066Z,1744225061.066 [DataOverHttps](INFO): SBD MOMSN=24598417 2025-04-09T18:57:42.465Z,1744225062.465 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:57:42.465Z,1744225062.465 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:57:42.466Z,1744225062.466 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:59:37.196Z,1744225177.196 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 49 2025-04-09T18:59:37.196Z,1744225177.196 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T18:59:37.207Z,1744225177.207 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T18:59:37.627Z,1744225177.627 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T18:59:37.628Z,1744225177.628 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 49 2025-04-09T19:02:38.221Z,1744225358.221 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 50 2025-04-09T19:02:38.221Z,1744225358.221 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T19:02:38.231Z,1744225358.231 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T19:02:38.638Z,1744225358.638 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T19:02:38.638Z,1744225358.638 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 50 2025-04-09T19:02:43.070Z,1744225363.070 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:02:43.070Z,1744225363.070 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:02:43.070Z,1744225363.070 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:02:43.071Z,1744225363.071 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:02:43.485Z,1744225363.485 [Default:CheckIn:D] Stopped 2025-04-09T19:02:43.485Z,1744225363.485 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:02:43.860Z,1744225363.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.833398 min 2025-04-09T19:02:43.860Z,1744225363.860 [Default:CheckIn:E] Stopped 2025-04-09T19:02:43.860Z,1744225363.860 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:02:43.860Z,1744225363.860 [Default:CheckIn] Stopped 2025-04-09T19:02:43.860Z,1744225363.860 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:02:43.860Z,1744225363.860 [Default:CheckIn](INFO): Running loop #15 2025-04-09T19:02:43.860Z,1744225363.860 [Default:CheckIn] Running Loop=15 2025-04-09T19:02:43.861Z,1744225363.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:02:43.861Z,1744225363.861 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:05:05.657Z,1744225505.657 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T19:05:05.657Z,1744225505.657 [NAL9602] Data Fault, FailCount= 4 2025-04-09T19:05:05.657Z,1744225505.657 [NAL9602](ERROR): Data Fault 2025-04-09T19:05:05.717Z,1744225505.717 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T19:05:06.064Z,1744225506.064 [NAL9602](INFO): Powering down 2025-04-09T19:05:06.891Z,1744225506.891 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T19:05:06.891Z,1744225506.891 [NAL9602] No Fault, FailCount= 4 2025-04-09T19:05:36.370Z,1744225536.370 [NAL9602](INFO): Powering up NAL9602 2025-04-09T19:05:39.210Z,1744225539.210 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 51 2025-04-09T19:05:39.210Z,1744225539.210 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T19:05:39.238Z,1744225539.238 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T19:05:39.646Z,1744225539.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T19:05:39.646Z,1744225539.646 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 51 2025-04-09T19:05:47.290Z,1744225547.290 [NAL9602](INFO): NAL9602 initialized 2025-04-09T19:07:05.667Z,1744225625.667 [DVL_micro](ERROR): Failed to parse: :SA,-02.44,-03.29,158.7 2025-04-09T19:07:44.059Z,1744225664.059 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T19:02:43.9Z 2025-04-09T19:07:44.059Z,1744225664.059 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:07:44.059Z,1744225664.059 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:07:51.695Z,1744225671.695 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250409T163137/Courier0046.lzma 2025-04-09T19:07:52.698Z,1744225672.698 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0046.lzma.bak 2025-04-09T19:07:52.698Z,1744225672.698 [DataOverHttps](INFO): SBD MOMSN=24598550 2025-04-09T19:08:08.576Z,1744225688.576 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250409T163137/Express0047.lzma 2025-04-09T19:08:09.578Z,1744225689.578 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0047.lzma.bak 2025-04-09T19:08:09.578Z,1744225689.578 [DataOverHttps](INFO): SBD MOMSN=24598552 2025-04-09T19:08:10.747Z,1744225690.747 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:08:10.747Z,1744225690.747 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:08:10.747Z,1744225690.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:08:40.223Z,1744225720.223 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 52 2025-04-09T19:08:40.224Z,1744225720.224 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T19:08:40.235Z,1744225720.235 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T19:08:40.657Z,1744225720.657 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T19:08:40.657Z,1744225720.657 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 52 2025-04-09T19:11:41.226Z,1744225901.226 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 53 2025-04-09T19:11:41.226Z,1744225901.226 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T19:11:41.236Z,1744225901.236 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T19:11:41.655Z,1744225901.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T19:11:41.655Z,1744225901.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 53 2025-04-09T19:13:11.326Z,1744225991.326 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:13:11.327Z,1744225991.327 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:13:11.327Z,1744225991.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:13:11.327Z,1744225991.327 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:13:11.742Z,1744225991.742 [Default:CheckIn:D] Stopped 2025-04-09T19:13:11.742Z,1744225991.742 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:13:12.169Z,1744225992.169 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.304329 min 2025-04-09T19:13:12.169Z,1744225992.169 [Default:CheckIn:E] Stopped 2025-04-09T19:13:12.169Z,1744225992.169 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:13:12.170Z,1744225992.170 [Default:CheckIn] Stopped 2025-04-09T19:13:12.170Z,1744225992.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:13:12.170Z,1744225992.170 [Default:CheckIn](INFO): Running loop #16 2025-04-09T19:13:12.170Z,1744225992.170 [Default:CheckIn] Running Loop=16 2025-04-09T19:13:12.170Z,1744225992.170 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:13:12.170Z,1744225992.170 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:14:42.243Z,1744226082.243 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 54 2025-04-09T19:14:42.243Z,1744226082.243 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T19:14:42.270Z,1744226082.270 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T19:14:42.638Z,1744226082.638 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T19:14:42.638Z,1744226082.638 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 54 2025-04-09T19:15:49.679Z,1744226149.679 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-09T19:15:49.679Z,1744226149.679 [NAL9602] Data Fault, FailCount= 5 2025-04-09T19:15:49.679Z,1744226149.679 [NAL9602](ERROR): Data Fault 2025-04-09T19:15:49.696Z,1744226149.696 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-09T19:15:49.696Z,1744226149.696 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-09T19:15:50.089Z,1744226150.089 [NAL9602](INFO): Powering down 2025-04-09T19:15:50.121Z,1744226150.121 [CBIT](INFO): Critical error at 20250409T191549 2025-04-09T19:17:43.207Z,1744226263.207 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 55 2025-04-09T19:17:43.208Z,1744226263.208 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-09T19:17:43.222Z,1744226263.222 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-09T19:17:43.632Z,1744226263.632 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-09T19:17:43.632Z,1744226263.632 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 55 2025-04-09T19:17:49.683Z,1744226269.683 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-09T19:17:49.683Z,1744226269.683 [NAL9602] No Fault, FailCount= 5 2025-04-09T19:17:50.074Z,1744226270.074 [NAL9602](INFO): Powering up NAL9602 2025-04-09T19:18:00.979Z,1744226280.979 [NAL9602](INFO): NAL9602 initialized 2025-04-09T19:18:12.325Z,1744226292.325 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-09T19:13:12.2Z 2025-04-09T19:18:12.325Z,1744226292.325 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:18:12.325Z,1744226292.325 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:18:20.135Z,1744226300.135 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250409T163137/Courier0049.lzma 2025-04-09T19:18:21.138Z,1744226301.138 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0049.lzma.bak 2025-04-09T19:18:21.138Z,1744226301.138 [DataOverHttps](INFO): SBD MOMSN=24598646 2025-04-09T19:18:37.905Z,1744226317.905 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T163137/Express0050.lzma 2025-04-09T19:18:38.906Z,1744226318.906 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0050.lzma.bak 2025-04-09T19:18:38.906Z,1744226318.906 [DataOverHttps](INFO): SBD MOMSN=24598649 2025-04-09T19:18:40.173Z,1744226320.173 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:18:40.173Z,1744226320.173 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:18:40.174Z,1744226320.174 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:19:17.974Z,1744226357.974 [DataOverHttps](IMPORTANT): SBD MTMSN=20250409T191917 2025-04-09T19:19:25.480Z,1744226365.480 [DataOverHttps](INFO): Received command: maintain control verticalcontrol.buoyancyaction 100 cc 2025-04-09T19:19:26.658Z,1744226366.658 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter 2025-04-09T19:19:26.667Z,1744226366.667 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread. 2025-04-09T19:19:26.677Z,1744226366.677 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-09T19:20:33.288Z,1744226433.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192030.00,A,3648.16853,N,12147.28589,W,0.603,273.94,090425,,,A*7E 2025-04-09T19:20:33.291Z,1744226433.291 [NAL9602](INFO): GPS fix at 20250409T192030: (36.802809, -121.788098) 2025-04-09T19:21:05.616Z,1744226465.616 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:21:29.649Z,1744226489.649 [DataOverHttps](IMPORTANT): SBD MTMSN=20250409T192129 2025-04-09T19:21:38.882Z,1744226498.882 [DataOverHttps](INFO): Received command: maintain clear 2025-04-09T19:21:38.922Z,1744226498.922 [CommandExec](IMPORTANT): got command maintain clear 2025-04-09T19:21:39.186Z,1744226499.186 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-09T19:23:40.783Z,1744226620.783 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:23:40.783Z,1744226620.783 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:23:40.783Z,1744226620.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:23:40.783Z,1744226620.783 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:23:41.199Z,1744226621.199 [Default:CheckIn:D] Stopped 2025-04-09T19:23:41.199Z,1744226621.199 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.795296 min 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn:E] Stopped 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn] Stopped 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn](INFO): Running loop #17 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn] Running Loop=17 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:23:41.590Z,1744226621.590 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:23:43.606Z,1744226623.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192343.00,A,3648.17261,N,12147.27926,W,0.330,177.68,090425,,,A*74 2025-04-09T19:23:43.608Z,1744226623.608 [NAL9602](INFO): GPS fix at 20250409T192343: (36.802877, -121.787988) 2025-04-09T19:23:43.618Z,1744226623.618 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:23:43.618Z,1744226623.618 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:23:51.036Z,1744226631.036 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250409T163137/Courier0052.lzma 2025-04-09T19:23:52.038Z,1744226632.038 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0052.lzma.bak 2025-04-09T19:23:52.038Z,1744226632.038 [DataOverHttps](INFO): SBD MOMSN=24598702 2025-04-09T19:24:08.325Z,1744226648.325 [DataOverHttps](INFO): Sending 551 bytes from file Logs/20250409T163137/Express0053.lzma 2025-04-09T19:24:09.327Z,1744226649.327 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0053.lzma.bak 2025-04-09T19:24:09.327Z,1744226649.327 [DataOverHttps](INFO): SBD MOMSN=24598705 2025-04-09T19:24:25.719Z,1744226665.719 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250409T163137/Express0056.lzma 2025-04-09T19:24:26.722Z,1744226666.722 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0056.lzma.bak 2025-04-09T19:24:26.722Z,1744226666.722 [DataOverHttps](INFO): SBD MOMSN=24598713 2025-04-09T19:24:28.083Z,1744226668.083 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:24:28.100Z,1744226668.100 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:24:28.100Z,1744226668.100 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:28:52.751Z,1744226932.751 [NAL9602](INFO): SBD MO Status=0, MOMSN=19221, MT Status=0, MTMSN=0 2025-04-09T19:28:52.751Z,1744226932.751 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:28:52.751Z,1744226932.751 [NAL9602](INFO): Failure count cleared after critical for NAL9602 2025-04-09T19:29:23.417Z,1744226963.417 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:29:28.683Z,1744226968.683 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:29:28.683Z,1744226968.683 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:29:28.683Z,1744226968.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:29:28.683Z,1744226968.683 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:29:29.081Z,1744226969.081 [Default:CheckIn:D] Stopped 2025-04-09T19:29:29.081Z,1744226969.081 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:29:29.491Z,1744226969.491 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 173.593327 min 2025-04-09T19:29:29.491Z,1744226969.491 [Default:CheckIn:E] Stopped 2025-04-09T19:29:29.492Z,1744226969.492 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:29:29.492Z,1744226969.492 [Default:CheckIn] Stopped 2025-04-09T19:29:29.492Z,1744226969.492 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:29:29.492Z,1744226969.492 [Default:CheckIn](INFO): Running loop #18 2025-04-09T19:29:29.492Z,1744226969.492 [Default:CheckIn] Running Loop=18 2025-04-09T19:29:29.492Z,1744226969.492 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:29:29.493Z,1744226969.493 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:29:31.538Z,1744226971.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192931.00,A,3648.15008,N,12147.25577,W,2.391,198.88,090425,,,A*78 2025-04-09T19:29:31.545Z,1744226971.545 [NAL9602](INFO): GPS fix at 20250409T192931: (36.802501, -121.787596) 2025-04-09T19:29:31.583Z,1744226971.583 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:29:31.583Z,1744226971.583 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:29:41.979Z,1744226981.979 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250409T163137/Courier0058.lzma 2025-04-09T19:29:42.984Z,1744226982.984 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0058.lzma.bak 2025-04-09T19:29:42.984Z,1744226982.984 [DataOverHttps](INFO): SBD MOMSN=24598756 2025-04-09T19:29:59.108Z,1744226999.108 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250409T163137/Express0059.lzma 2025-04-09T19:30:00.111Z,1744227000.111 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0059.lzma.bak 2025-04-09T19:30:00.111Z,1744227000.111 [DataOverHttps](INFO): SBD MOMSN=24598759 2025-04-09T19:30:01.502Z,1744227001.502 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:30:01.502Z,1744227001.502 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:30:01.502Z,1744227001.502 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:30:02.702Z,1744227002.702 [NAL9602](INFO): SBD MO Status=2, MOMSN=19222, MT Status=2, MTMSN=0 2025-04-09T19:30:02.702Z,1744227002.702 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T19:30:30.983Z,1744227030.983 [NAL9602](INFO): SBD MO Status=0, MOMSN=19222, MT Status=0, MTMSN=0 2025-04-09T19:30:30.983Z,1744227030.983 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:31:01.685Z,1744227061.685 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:31:09.977Z,1744227069.977 [DataOverHttps](IMPORTANT): SBD MTMSN=20250409T193109 2025-04-09T19:31:17.532Z,1744227077.532 [DataOverHttps](INFO): Received command: ibit 2025-04-09T19:31:17.625Z,1744227077.625 [CommandExec](IMPORTANT): got command ibit 2025-04-09T19:31:17.877Z,1744227077.877 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-04-09T19:31:17.877Z,1744227077.877 [IBIT](IMPORTANT): Beginning control surface checks. 2025-04-09T19:31:17.884Z,1744227077.884 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-09T19:31:19.466Z,1744227079.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193119.00,A,3648.14164,N,12147.22768,W,0.058,43.82,090425,,,D*46 2025-04-09T19:31:19.468Z,1744227079.468 [NAL9602](INFO): GPS fix at 20250409T193119: (36.802361, -121.787128) 2025-04-09T19:31:22.492Z,1744227082.492 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.075721 CHAN A1 (24V): 0.118274 CHAN A2 (12V): 0.010624 CHAN A3 (5V): -0.031885 CHAN B0 (3.3V): -0.027455 CHAN B1 (3.15aV): -0.021919 CHAN B2 (3.15bV): -0.016952 CHAN B3 (GND): -0.032457 OPEN: 0.007898 Full Scale: +/- 1 mA 2025-04-09T19:31:35.627Z,1744227095.627 [NAL9602](INFO): SBD MO Status=0, MOMSN=19223, MT Status=0, MTMSN=0 2025-04-09T19:31:35.628Z,1744227095.628 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:31:36.838Z,1744227096.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193136.00,A,3648.14124,N,12147.22680,W,0.078,43.82,090425,,,D*4A 2025-04-09T19:31:36.841Z,1744227096.841 [NAL9602](INFO): GPS fix at 20250409T193136: (36.802354, -121.787113) 2025-04-09T19:31:39.270Z,1744227099.270 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193139.00,A,3648.14128,N,12147.22668,W,0.058,43.82,090425,,,D*4D 2025-04-09T19:31:39.273Z,1744227099.273 [NAL9602](INFO): GPS fix at 20250409T193139: (36.802355, -121.787111) 2025-04-09T19:31:42.502Z,1744227102.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193142.00,A,3648.14141,N,12147.22663,W,0.097,43.82,090425,,,D*46 2025-04-09T19:31:42.504Z,1744227102.504 [NAL9602](INFO): GPS fix at 20250409T193142: (36.802357, -121.787110) 2025-04-09T19:31:45.323Z,1744227105.323 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193145.00,A,3648.14155,N,12147.22661,W,0.039,43.82,090425,,,D*42 2025-04-09T19:31:45.329Z,1744227105.329 [NAL9602](INFO): GPS fix at 20250409T193145: (36.802359, -121.787110) 2025-04-09T19:31:48.552Z,1744227108.552 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193148.00,A,3648.14171,N,12147.22659,W,0.019,43.82,090425,,,D*40 2025-04-09T19:31:48.555Z,1744227108.555 [NAL9602](INFO): GPS fix at 20250409T193148: (36.802362, -121.787110) 2025-04-09T19:31:51.381Z,1744227111.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193151.00,A,3648.14191,N,12147.22642,W,0.019,43.82,090425,,,D*4C 2025-04-09T19:31:51.384Z,1744227111.384 [NAL9602](INFO): GPS fix at 20250409T193151: (36.802365, -121.787107) 2025-04-09T19:31:54.210Z,1744227114.210 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193154.00,A,3648.14215,N,12147.22619,W,0.019,43.82,090425,,,D*48 2025-04-09T19:31:54.212Z,1744227114.212 [NAL9602](INFO): GPS fix at 20250409T193154: (36.802369, -121.787103) 2025-04-09T19:31:57.443Z,1744227117.443 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193157.00,A,3648.14239,N,12147.22618,W,0.039,43.82,090425,,,D*46 2025-04-09T19:31:57.445Z,1744227117.445 [NAL9602](INFO): GPS fix at 20250409T193157: (36.802373, -121.787103) 2025-04-09T19:32:00.273Z,1744227120.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193200.00,A,3648.14258,N,12147.22610,W,0.019,43.82,090425,,,D*4A 2025-04-09T19:32:00.275Z,1744227120.275 [NAL9602](INFO): GPS fix at 20250409T193200: (36.802376, -121.787102) 2025-04-09T19:32:03.514Z,1744227123.514 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193203.00,A,3648.14280,N,12147.22592,W,0.078,43.82,090425,,,D*42 2025-04-09T19:32:03.516Z,1744227123.516 [NAL9602](INFO): GPS fix at 20250409T193203: (36.802380, -121.787099) 2025-04-09T19:32:04.324Z,1744227124.324 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802380 Longitude: -121.787102 2025-04-09T19:32:04.732Z,1744227124.732 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 15.461000 2025-04-09T19:32:04.733Z,1744227124.733 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2025-04-09T19:32:04.733Z,1744227124.733 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2025-04-09T19:32:05.143Z,1744227125.143 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-04-09T19:32:05.143Z,1744227125.143 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-04-09T19:32:05.143Z,1744227125.143 [IBIT](IMPORTANT): Pressure:10.405140 PSI 2025-04-09T19:32:05.144Z,1744227125.144 [IBIT](IMPORTANT): Humidity:4.285191 % 2025-04-09T19:32:05.535Z,1744227125.535 [IBIT](IMPORTANT): Vehicle Pitch:-2.899554 degrees 2025-04-09T19:32:05.535Z,1744227125.535 [IBIT](IMPORTANT): Vehicle Roll:0.490872 degrees 2025-04-09T19:32:05.535Z,1744227125.535 [IBIT](IMPORTANT): Vehicle Heading:20.866398 degrees 2025-04-09T19:32:05.968Z,1744227125.968 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-04-09T19:32:05.968Z,1744227125.968 [IBIT](IMPORTANT): buoyancyNeutral: 209.039993 cc 2025-04-09T19:32:05.969Z,1744227125.969 [IBIT](IMPORTANT): massDefault: 1.240820 cm 2025-04-09T19:32:05.969Z,1744227125.969 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2025-04-09T19:32:05.969Z,1744227125.969 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-04-09T19:32:05.969Z,1744227125.969 [IBIT](IMPORTANT): IBIT PASSED 2025-04-09T19:32:35.822Z,1744227155.822 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:35:02.077Z,1744227302.077 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:35:02.077Z,1744227302.077 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:35:02.077Z,1744227302.077 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:35:02.077Z,1744227302.077 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:35:02.492Z,1744227302.492 [Default:CheckIn:D] Stopped 2025-04-09T19:35:02.492Z,1744227302.492 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:35:02.884Z,1744227302.884 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.150179 min 2025-04-09T19:35:02.884Z,1744227302.884 [Default:CheckIn:E] Stopped 2025-04-09T19:35:02.884Z,1744227302.884 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:35:02.884Z,1744227302.884 [Default:CheckIn] Stopped 2025-04-09T19:35:02.884Z,1744227302.884 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:35:02.884Z,1744227302.884 [Default:CheckIn](INFO): Running loop #19 2025-04-09T19:35:02.884Z,1744227302.884 [Default:CheckIn] Running Loop=19 2025-04-09T19:35:02.885Z,1744227302.885 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:35:02.885Z,1744227302.885 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:35:04.910Z,1744227304.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193504.00,A,3648.15179,N,12147.22476,W,0.428,37.38,090425,,,D*4E 2025-04-09T19:35:04.912Z,1744227304.912 [NAL9602](INFO): GPS fix at 20250409T193504: (36.802530, -121.787079) 2025-04-09T19:35:04.923Z,1744227304.923 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:35:04.923Z,1744227304.923 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:35:12.719Z,1744227312.719 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0061.lzma 2025-04-09T19:35:13.718Z,1744227313.718 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0061.lzma.bak 2025-04-09T19:35:13.718Z,1744227313.718 [DataOverHttps](INFO): SBD MOMSN=24598811 2025-04-09T19:35:18.294Z,1744227318.294 [NAL9602](INFO): SBD MO Status=0, MOMSN=19224, MT Status=0, MTMSN=0 2025-04-09T19:35:18.294Z,1744227318.294 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:35:29.702Z,1744227329.702 [DataOverHttps](INFO): Sending 1005 bytes from file Logs/20250409T163137/Express0062.lzma 2025-04-09T19:35:30.703Z,1744227330.703 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0062.lzma.bak 2025-04-09T19:35:30.703Z,1744227330.703 [DataOverHttps](INFO): SBD MOMSN=24598820 2025-04-09T19:35:46.953Z,1744227346.953 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250409T163137/Express0065.lzma 2025-04-09T19:35:47.954Z,1744227347.954 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0065.lzma.bak 2025-04-09T19:35:47.954Z,1744227347.954 [DataOverHttps](INFO): SBD MOMSN=24598883 2025-04-09T19:35:48.927Z,1744227348.927 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:35:49.346Z,1744227349.346 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:35:49.346Z,1744227349.346 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:35:49.346Z,1744227349.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:40:49.942Z,1744227649.942 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:40:49.942Z,1744227649.942 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:40:49.942Z,1744227649.942 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:40:49.942Z,1744227649.942 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:40:50.346Z,1744227650.346 [Default:CheckIn:D] Stopped 2025-04-09T19:40:50.346Z,1744227650.346 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 184.947738 min 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn:E] Stopped 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn] Stopped 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn](INFO): Running loop #20 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn] Running Loop=20 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:40:50.739Z,1744227650.739 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:40:52.748Z,1744227652.748 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194052.00,A,3648.15337,N,12147.22157,W,0.019,37.38,090425,,,D*47 2025-04-09T19:40:52.750Z,1744227652.750 [NAL9602](INFO): GPS fix at 20250409T194052: (36.802556, -121.787026) 2025-04-09T19:40:52.761Z,1744227652.761 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:40:52.761Z,1744227652.761 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:41:00.392Z,1744227660.392 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0067.lzma 2025-04-09T19:41:01.394Z,1744227661.394 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0067.lzma.bak 2025-04-09T19:41:01.394Z,1744227661.394 [DataOverHttps](INFO): SBD MOMSN=24598898 2025-04-09T19:41:17.389Z,1744227677.389 [NAL9602](INFO): SBD MO Status=2, MOMSN=19225, MT Status=2, MTMSN=0 2025-04-09T19:41:17.389Z,1744227677.389 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T19:41:17.480Z,1744227677.480 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250409T163137/Express0068.lzma 2025-04-09T19:41:18.483Z,1744227678.483 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0068.lzma.bak 2025-04-09T19:41:18.483Z,1744227678.483 [DataOverHttps](INFO): SBD MOMSN=24598901 2025-04-09T19:41:19.834Z,1744227679.834 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:41:19.834Z,1744227679.834 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:41:19.834Z,1744227679.834 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:41:52.956Z,1744227712.956 [NAL9602](INFO): SBD MO Status=0, MOMSN=19225, MT Status=0, MTMSN=0 2025-04-09T19:41:52.957Z,1744227712.957 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:42:23.698Z,1744227743.698 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:46:20.419Z,1744227980.419 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:46:20.419Z,1744227980.419 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:46:20.419Z,1744227980.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:46:20.419Z,1744227980.419 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:46:20.828Z,1744227980.828 [Default:CheckIn:D] Stopped 2025-04-09T19:46:20.829Z,1744227980.829 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:46:21.251Z,1744227981.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.455778 min 2025-04-09T19:46:21.251Z,1744227981.251 [Default:CheckIn:E] Stopped 2025-04-09T19:46:21.252Z,1744227981.252 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:46:21.252Z,1744227981.252 [Default:CheckIn] Stopped 2025-04-09T19:46:21.252Z,1744227981.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:46:21.252Z,1744227981.252 [Default:CheckIn](INFO): Running loop #21 2025-04-09T19:46:21.252Z,1744227981.252 [Default:CheckIn] Running Loop=21 2025-04-09T19:46:21.252Z,1744227981.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:46:21.252Z,1744227981.252 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:46:23.242Z,1744227983.242 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194623.00,A,3648.15236,N,12147.22140,W,0.058,37.38,090425,,,D*44 2025-04-09T19:46:23.244Z,1744227983.244 [NAL9602](INFO): GPS fix at 20250409T194623: (36.802539, -121.787023) 2025-04-09T19:46:23.254Z,1744227983.254 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:46:23.254Z,1744227983.254 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:46:31.087Z,1744227991.087 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250409T163137/Courier0070.lzma 2025-04-09T19:46:32.090Z,1744227992.090 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0070.lzma.bak 2025-04-09T19:46:32.090Z,1744227992.090 [DataOverHttps](INFO): SBD MOMSN=24598981 2025-04-09T19:46:39.404Z,1744227999.404 [NAL9602](INFO): SBD MO Status=0, MOMSN=19226, MT Status=0, MTMSN=0 2025-04-09T19:46:39.404Z,1744227999.404 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:46:48.004Z,1744228008.004 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20250409T163137/Express0071.lzma 2025-04-09T19:46:49.006Z,1744228009.006 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0071.lzma.bak 2025-04-09T19:46:49.006Z,1744228009.006 [DataOverHttps](INFO): SBD MOMSN=24598984 2025-04-09T19:46:50.330Z,1744228010.330 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:46:50.330Z,1744228010.330 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:46:50.330Z,1744228010.330 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:47:10.110Z,1744228030.110 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:48:11.920Z,1744228091.920 [DVL_micro](ERROR): Failed to parse: :WI,+01207,+01326,-00980,+00000,A 2025-04-09T19:51:50.934Z,1744228310.934 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:51:50.934Z,1744228310.934 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:51:50.934Z,1744228310.934 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:51:50.934Z,1744228310.934 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:51:51.322Z,1744228311.322 [Default:CheckIn:D] Stopped 2025-04-09T19:51:51.322Z,1744228311.322 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.963997 min 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn:E] Stopped 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn] Stopped 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn](INFO): Running loop #22 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn] Running Loop=22 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:51:51.729Z,1744228311.729 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:51:53.744Z,1744228313.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195153.00,A,3648.15243,N,12147.22148,W,0.000,37.38,090425,,,D*42 2025-04-09T19:51:53.746Z,1744228313.746 [NAL9602](INFO): GPS fix at 20250409T195153: (36.802540, -121.787025) 2025-04-09T19:51:53.757Z,1744228313.757 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:51:53.757Z,1744228313.757 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:52:01.140Z,1744228321.140 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250409T163137/Courier0073.lzma 2025-04-09T19:52:02.142Z,1744228322.142 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0073.lzma.bak 2025-04-09T19:52:02.142Z,1744228322.142 [DataOverHttps](INFO): SBD MOMSN=24599002 2025-04-09T19:52:17.183Z,1744228337.183 [NAL9602](INFO): SBD MO Status=0, MOMSN=19227, MT Status=0, MTMSN=0 2025-04-09T19:52:17.183Z,1744228337.183 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:52:18.096Z,1744228338.096 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250409T163137/Express0074.lzma 2025-04-09T19:52:19.098Z,1744228339.098 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0074.lzma.bak 2025-04-09T19:52:19.099Z,1744228339.099 [DataOverHttps](INFO): SBD MOMSN=24599005 2025-04-09T19:52:20.418Z,1744228340.418 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:52:20.418Z,1744228340.418 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:52:20.419Z,1744228340.419 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:52:47.892Z,1744228367.892 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:57:21.000Z,1744228641.000 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:57:21.000Z,1744228641.000 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:57:21.000Z,1744228641.000 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:57:21.000Z,1744228641.000 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:57:21.420Z,1744228641.420 [Default:CheckIn:D] Stopped 2025-04-09T19:57:21.420Z,1744228641.420 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:57:21.807Z,1744228641.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.465641 min 2025-04-09T19:57:21.807Z,1744228641.807 [Default:CheckIn:E] Stopped 2025-04-09T19:57:21.807Z,1744228641.807 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:57:21.807Z,1744228641.807 [Default:CheckIn] Stopped 2025-04-09T19:57:21.807Z,1744228641.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:57:21.807Z,1744228641.807 [Default:CheckIn](INFO): Running loop #23 2025-04-09T19:57:21.808Z,1744228641.808 [Default:CheckIn] Running Loop=23 2025-04-09T19:57:21.808Z,1744228641.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:57:21.808Z,1744228641.808 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:57:23.819Z,1744228643.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195723.00,A,3648.15256,N,12147.22023,W,0.058,37.38,090425,,,D*46 2025-04-09T19:57:23.823Z,1744228643.823 [NAL9602](INFO): GPS fix at 20250409T195723: (36.802543, -121.787004) 2025-04-09T19:57:23.839Z,1744228643.839 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:57:23.839Z,1744228643.839 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:57:31.204Z,1744228651.204 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250409T163137/Courier0076.lzma 2025-04-09T19:57:32.206Z,1744228652.206 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0076.lzma.bak 2025-04-09T19:57:32.206Z,1744228652.206 [DataOverHttps](INFO): SBD MOMSN=24599082 2025-04-09T19:57:48.188Z,1744228668.188 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250409T163137/Express0077.lzma 2025-04-09T19:57:49.190Z,1744228669.190 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0077.lzma.bak 2025-04-09T19:57:49.190Z,1744228669.190 [DataOverHttps](INFO): SBD MOMSN=24599085 2025-04-09T19:57:50.532Z,1744228670.532 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:57:50.532Z,1744228670.532 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:57:50.532Z,1744228670.532 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:57:51.330Z,1744228671.330 [NAL9602](INFO): SBD MO Status=2, MOMSN=19228, MT Status=2, MTMSN=0 2025-04-09T19:57:51.331Z,1744228671.331 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T19:58:23.758Z,1744228703.758 [NAL9602](INFO): SBD MO Status=0, MOMSN=19228, MT Status=0, MTMSN=0 2025-04-09T19:58:23.758Z,1744228703.758 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:58:54.349Z,1744228734.349 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:02:20.813Z,1744228940.813 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-04-09T20:02:20.815Z,1744228940.815 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T20:02:51.116Z,1744228971.116 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:02:51.116Z,1744228971.116 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:02:51.116Z,1744228971.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:02:51.116Z,1744228971.116 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:02:51.533Z,1744228971.533 [Default:CheckIn:D] Stopped 2025-04-09T20:02:51.533Z,1744228971.533 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:02:51.922Z,1744228971.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 206.967513 min 2025-04-09T20:02:51.922Z,1744228971.922 [Default:CheckIn:E] Stopped 2025-04-09T20:02:51.922Z,1744228971.922 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:02:51.922Z,1744228971.922 [Default:CheckIn] Stopped 2025-04-09T20:02:51.922Z,1744228971.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:02:51.922Z,1744228971.922 [Default:CheckIn](INFO): Running loop #24 2025-04-09T20:02:51.922Z,1744228971.922 [Default:CheckIn] Running Loop=24 2025-04-09T20:02:51.923Z,1744228971.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:02:51.923Z,1744228971.923 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:02:53.930Z,1744228973.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200253.00,A,3648.15273,N,12147.22083,W,0.039,37.38,090425,,,D*41 2025-04-09T20:02:53.933Z,1744228973.933 [NAL9602](INFO): GPS fix at 20250409T200253: (36.802546, -121.787014) 2025-04-09T20:02:53.942Z,1744228973.942 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:02:53.942Z,1744228973.942 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:03:01.779Z,1744228981.779 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20250409T163137/Courier0079.lzma 2025-04-09T20:03:02.782Z,1744228982.782 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0079.lzma.bak 2025-04-09T20:03:02.782Z,1744228982.782 [DataOverHttps](INFO): SBD MOMSN=24599104 2025-04-09T20:03:14.139Z,1744228994.139 [NAL9602](INFO): SBD MO Status=0, MOMSN=19229, MT Status=0, MTMSN=0 2025-04-09T20:03:14.139Z,1744228994.139 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:03:18.932Z,1744228998.932 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20250409T163137/Express0080.lzma 2025-04-09T20:03:19.934Z,1744228999.934 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0080.lzma.bak 2025-04-09T20:03:19.935Z,1744228999.935 [DataOverHttps](INFO): SBD MOMSN=24599107 2025-04-09T20:03:21.007Z,1744229001.007 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:03:21.007Z,1744229001.007 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:03:21.008Z,1744229001.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:03:44.833Z,1744229024.833 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:08:21.623Z,1744229301.623 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:08:21.623Z,1744229301.623 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:08:21.623Z,1744229301.623 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:08:21.623Z,1744229301.623 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:08:22.015Z,1744229302.015 [Default:CheckIn:D] Stopped 2025-04-09T20:08:22.016Z,1744229302.016 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:08:22.425Z,1744229302.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.475570 min 2025-04-09T20:08:22.425Z,1744229302.425 [Default:CheckIn:E] Stopped 2025-04-09T20:08:22.425Z,1744229302.425 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:08:22.425Z,1744229302.425 [Default:CheckIn] Stopped 2025-04-09T20:08:22.426Z,1744229302.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:08:22.426Z,1744229302.426 [Default:CheckIn](INFO): Running loop #25 2025-04-09T20:08:22.426Z,1744229302.426 [Default:CheckIn] Running Loop=25 2025-04-09T20:08:22.426Z,1744229302.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:08:22.426Z,1744229302.426 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:08:24.461Z,1744229304.461 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200824.00,A,3648.15240,N,12147.22130,W,0.039,0.00,090425,,,D*7D 2025-04-09T20:08:24.463Z,1744229304.463 [NAL9602](INFO): GPS fix at 20250409T200824: (36.802540, -121.787022) 2025-04-09T20:08:24.485Z,1744229304.485 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:08:24.485Z,1744229304.485 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:08:31.568Z,1744229311.568 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0082.lzma 2025-04-09T20:08:32.570Z,1744229312.570 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0082.lzma.bak 2025-04-09T20:08:32.570Z,1744229312.570 [DataOverHttps](INFO): SBD MOMSN=24599157 2025-04-09T20:08:48.572Z,1744229328.572 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20250409T163137/Express0083.lzma 2025-04-09T20:08:49.574Z,1744229329.574 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0083.lzma.bak 2025-04-09T20:08:49.574Z,1744229329.574 [DataOverHttps](INFO): SBD MOMSN=24599160 2025-04-09T20:08:50.715Z,1744229330.715 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:08:50.715Z,1744229330.715 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:08:50.715Z,1744229330.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:08:58.771Z,1744229338.771 [NAL9602](INFO): SBD MO Status=0, MOMSN=19230, MT Status=0, MTMSN=0 2025-04-09T20:08:58.771Z,1744229338.771 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:09:29.484Z,1744229369.484 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:13:51.303Z,1744229631.303 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:13:51.303Z,1744229631.303 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:13:51.304Z,1744229631.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:13:51.304Z,1744229631.304 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:13:51.708Z,1744229631.708 [Default:CheckIn:D] Stopped 2025-04-09T20:13:51.708Z,1744229631.708 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:13:52.116Z,1744229632.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.970443 min 2025-04-09T20:13:52.116Z,1744229632.116 [Default:CheckIn:E] Stopped 2025-04-09T20:13:52.116Z,1744229632.116 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:13:52.116Z,1744229632.116 [Default:CheckIn] Stopped 2025-04-09T20:13:52.116Z,1744229632.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:13:52.117Z,1744229632.117 [Default:CheckIn](INFO): Running loop #26 2025-04-09T20:13:52.117Z,1744229632.117 [Default:CheckIn] Running Loop=26 2025-04-09T20:13:52.117Z,1744229632.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:13:52.117Z,1744229632.117 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:13:54.122Z,1744229634.122 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201354.00,A,3648.15380,N,12147.22097,W,0.058,0.00,090425,,,D*76 2025-04-09T20:13:54.124Z,1744229634.124 [NAL9602](INFO): GPS fix at 20250409T201354: (36.802563, -121.787016) 2025-04-09T20:13:54.154Z,1744229634.154 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:13:54.154Z,1744229634.154 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:14:01.739Z,1744229641.739 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250409T163137/Courier0085.lzma 2025-04-09T20:14:02.742Z,1744229642.742 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0085.lzma.bak 2025-04-09T20:14:02.742Z,1744229642.742 [DataOverHttps](INFO): SBD MOMSN=24599179 2025-04-09T20:14:09.882Z,1744229649.882 [NAL9602](INFO): SBD MO Status=0, MOMSN=19231, MT Status=0, MTMSN=0 2025-04-09T20:14:09.882Z,1744229649.882 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:14:18.784Z,1744229658.784 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20250409T163137/Express0086.lzma 2025-04-09T20:14:19.786Z,1744229659.786 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0086.lzma.bak 2025-04-09T20:14:19.786Z,1744229659.786 [DataOverHttps](INFO): SBD MOMSN=24599182 2025-04-09T20:14:20.818Z,1744229660.818 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:14:20.818Z,1744229660.818 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:14:20.818Z,1744229660.818 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:14:40.581Z,1744229680.581 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:19:21.433Z,1744229961.433 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:19:21.433Z,1744229961.433 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:19:21.433Z,1744229961.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:19:21.433Z,1744229961.433 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:19:21.827Z,1744229961.827 [Default:CheckIn:D] Stopped 2025-04-09T20:19:21.827Z,1744229961.827 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:19:22.226Z,1744229962.226 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.472428 min 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn:E] Stopped 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn] Stopped 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn](INFO): Running loop #27 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn] Running Loop=27 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:19:22.227Z,1744229962.227 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:19:24.251Z,1744229964.251 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201924.00,A,3648.15380,N,12147.21941,W,0.019,0.00,090425,,,D*7F 2025-04-09T20:19:24.254Z,1744229964.254 [NAL9602](INFO): GPS fix at 20250409T201924: (36.802563, -121.786990) 2025-04-09T20:19:24.288Z,1744229964.288 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:19:24.288Z,1744229964.288 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:19:31.576Z,1744229971.576 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0088.lzma 2025-04-09T20:19:32.570Z,1744229972.570 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0088.lzma.bak 2025-04-09T20:19:32.571Z,1744229972.571 [DataOverHttps](INFO): SBD MOMSN=24599252 2025-04-09T20:19:33.937Z,1744229973.937 [NAL9602](INFO): SBD MO Status=0, MOMSN=19232, MT Status=0, MTMSN=0 2025-04-09T20:19:33.937Z,1744229973.937 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:19:48.580Z,1744229988.580 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20250409T163137/Express0089.lzma 2025-04-09T20:19:49.582Z,1744229989.582 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0089.lzma.bak 2025-04-09T20:19:49.582Z,1744229989.582 [DataOverHttps](INFO): SBD MOMSN=24599255 2025-04-09T20:19:50.911Z,1744229990.911 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:19:50.911Z,1744229990.911 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:19:50.911Z,1744229990.911 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:20:04.648Z,1744230004.648 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:24:51.509Z,1744230291.509 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:24:51.509Z,1744230291.509 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:24:51.509Z,1744230291.509 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:24:51.509Z,1744230291.509 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:24:51.911Z,1744230291.911 [Default:CheckIn:D] Stopped 2025-04-09T20:24:51.911Z,1744230291.911 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:24:52.329Z,1744230292.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.973828 min 2025-04-09T20:24:52.329Z,1744230292.329 [Default:CheckIn:E] Stopped 2025-04-09T20:24:52.330Z,1744230292.330 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:24:52.330Z,1744230292.330 [Default:CheckIn] Stopped 2025-04-09T20:24:52.330Z,1744230292.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:24:52.330Z,1744230292.330 [Default:CheckIn](INFO): Running loop #28 2025-04-09T20:24:52.330Z,1744230292.330 [Default:CheckIn] Running Loop=28 2025-04-09T20:24:52.330Z,1744230292.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:24:52.330Z,1744230292.330 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:24:54.326Z,1744230294.326 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202454.00,A,3648.15309,N,12147.22121,W,0.019,3.77,090425,,,D*79 2025-04-09T20:24:54.328Z,1744230294.328 [NAL9602](INFO): GPS fix at 20250409T202454: (36.802551, -121.787020) 2025-04-09T20:24:54.339Z,1744230294.339 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:24:54.339Z,1744230294.339 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:25:02.036Z,1744230302.036 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250409T163137/Courier0091.lzma 2025-04-09T20:25:03.038Z,1744230303.038 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0091.lzma.bak 2025-04-09T20:25:03.038Z,1744230303.038 [DataOverHttps](INFO): SBD MOMSN=24599304 2025-04-09T20:25:19.060Z,1744230319.060 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250409T163137/Express0092.lzma 2025-04-09T20:25:20.063Z,1744230320.063 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0092.lzma.bak 2025-04-09T20:25:20.064Z,1744230320.064 [DataOverHttps](INFO): SBD MOMSN=24599307 2025-04-09T20:25:21.421Z,1744230321.421 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:25:21.421Z,1744230321.421 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:25:21.421Z,1744230321.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:25:58.569Z,1744230358.569 [NAL9602](INFO): SBD MO Status=2, MOMSN=19233, MT Status=2, MTMSN=0 2025-04-09T20:25:58.570Z,1744230358.570 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T20:26:32.908Z,1744230392.908 [NAL9602](INFO): SBD MO Status=0, MOMSN=19233, MT Status=0, MTMSN=0 2025-04-09T20:26:32.908Z,1744230392.908 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:27:03.614Z,1744230423.614 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:30:22.001Z,1744230622.001 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:30:22.001Z,1744230622.001 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:30:22.002Z,1744230622.002 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:30:22.002Z,1744230622.002 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:30:22.393Z,1744230622.393 [Default:CheckIn:D] Stopped 2025-04-09T20:30:22.393Z,1744230622.393 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 234.481868 min 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn:E] Stopped 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn] Stopped 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn](INFO): Running loop #29 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn] Running Loop=29 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:30:22.799Z,1744230622.799 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:30:24.813Z,1744230624.813 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203024.00,A,3648.15338,N,12147.22088,W,0.136,3.77,090425,,,D*77 2025-04-09T20:30:24.815Z,1744230624.815 [NAL9602](INFO): GPS fix at 20250409T203024: (36.802556, -121.787015) 2025-04-09T20:30:24.826Z,1744230624.826 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:30:24.826Z,1744230624.826 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:30:33.188Z,1744230633.188 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0094.lzma 2025-04-09T20:30:34.190Z,1744230634.190 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0094.lzma.bak 2025-04-09T20:30:34.190Z,1744230634.190 [DataOverHttps](INFO): SBD MOMSN=24599400 2025-04-09T20:30:43.395Z,1744230643.395 [NAL9602](INFO): SBD MO Status=2, MOMSN=19234, MT Status=2, MTMSN=0 2025-04-09T20:30:43.395Z,1744230643.395 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T20:30:50.292Z,1744230650.292 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250409T163137/Express0095.lzma 2025-04-09T20:30:51.294Z,1744230651.294 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0095.lzma.bak 2025-04-09T20:30:51.294Z,1744230651.294 [DataOverHttps](INFO): SBD MOMSN=24599403 2025-04-09T20:30:52.335Z,1744230652.335 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:30:52.335Z,1744230652.335 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:30:52.335Z,1744230652.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:31:09.256Z,1744230669.256 [NAL9602](INFO): SBD MO Status=2, MOMSN=19234, MT Status=2, MTMSN=0 2025-04-09T20:31:09.256Z,1744230669.256 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T20:31:32.719Z,1744230692.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=19234, MT Status=0, MTMSN=0 2025-04-09T20:31:32.719Z,1744230692.719 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:32:00.969Z,1744230720.969 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-09T20:32:03.389Z,1744230723.389 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:35:52.930Z,1744230952.930 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:35:52.930Z,1744230952.930 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:35:52.930Z,1744230952.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:35:52.930Z,1744230952.930 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:35:53.342Z,1744230953.342 [Default:CheckIn:D] Stopped 2025-04-09T20:35:53.342Z,1744230953.342 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:35:53.735Z,1744230953.735 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 239.997673 min 2025-04-09T20:35:53.735Z,1744230953.735 [Default:CheckIn:E] Stopped 2025-04-09T20:35:53.735Z,1744230953.735 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:35:53.735Z,1744230953.735 [Default:CheckIn] Stopped 2025-04-09T20:35:53.735Z,1744230953.735 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:35:53.735Z,1744230953.735 [Default:CheckIn](INFO): Running loop #30 2025-04-09T20:35:53.735Z,1744230953.735 [Default:CheckIn] Running Loop=30 2025-04-09T20:35:53.736Z,1744230953.736 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:35:53.736Z,1744230953.736 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:35:55.747Z,1744230955.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203555.00,A,3648.33985,N,12147.11889,W,3.790,4.19,090425,,,D*73 2025-04-09T20:35:55.750Z,1744230955.750 [NAL9602](INFO): GPS fix at 20250409T203555: (36.805664, -121.785315) 2025-04-09T20:35:55.759Z,1744230955.759 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:35:55.759Z,1744230955.759 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:36:03.128Z,1744230963.128 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0097.lzma 2025-04-09T20:36:04.131Z,1744230964.131 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0097.lzma.bak 2025-04-09T20:36:04.131Z,1744230964.131 [DataOverHttps](INFO): SBD MOMSN=24599416 2025-04-09T20:36:10.710Z,1744230970.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=19235, MT Status=0, MTMSN=0 2025-04-09T20:36:10.710Z,1744230970.710 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:36:20.228Z,1744230980.228 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250409T163137/Express0098.lzma 2025-04-09T20:36:21.238Z,1744230981.238 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0098.lzma.bak 2025-04-09T20:36:21.238Z,1744230981.238 [DataOverHttps](INFO): SBD MOMSN=24599419 2025-04-09T20:36:22.418Z,1744230982.418 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:36:22.418Z,1744230982.418 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:36:22.418Z,1744230982.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:36:41.395Z,1744231001.395 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:38:27.247Z,1744231107.247 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00060,+00082,-0,+9999.99 2025-04-09T20:38:27.248Z,1744231107.248 [DVL_micro](ERROR): Failed to parse: :BI,-00060,+00082,-0,+9999.99 2025-04-09T20:41:23.072Z,1744231283.072 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:41:23.072Z,1744231283.072 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:41:23.072Z,1744231283.072 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:41:23.073Z,1744231283.073 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:41:23.469Z,1744231283.469 [Default:CheckIn:D] Stopped 2025-04-09T20:41:23.469Z,1744231283.469 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:41:23.879Z,1744231283.879 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 245.499788 min 2025-04-09T20:41:23.879Z,1744231283.879 [Default:CheckIn:E] Stopped 2025-04-09T20:41:23.884Z,1744231283.884 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:41:23.884Z,1744231283.884 [Default:CheckIn] Stopped 2025-04-09T20:41:23.884Z,1744231283.884 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:41:23.884Z,1744231283.884 [Default:CheckIn](INFO): Running loop #31 2025-04-09T20:41:23.884Z,1744231283.884 [Default:CheckIn] Running Loop=31 2025-04-09T20:41:23.884Z,1744231283.884 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:41:23.884Z,1744231283.884 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:41:25.882Z,1744231285.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204125.00,A,3648.33081,N,12147.61537,W,14.462,267.64,090425,,,A*45 2025-04-09T20:41:25.884Z,1744231285.884 [NAL9602](INFO): GPS fix at 20250409T204125: (36.805514, -121.793589) 2025-04-09T20:41:25.910Z,1744231285.910 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:41:25.911Z,1744231285.911 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:41:34.899Z,1744231294.899 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0100.lzma 2025-04-09T20:41:35.906Z,1744231295.906 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0100.lzma.bak 2025-04-09T20:41:35.906Z,1744231295.906 [DataOverHttps](INFO): SBD MOMSN=24599553 2025-04-09T20:41:43.679Z,1744231303.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=19236, MT Status=0, MTMSN=0 2025-04-09T20:41:43.680Z,1744231303.680 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:41:52.908Z,1744231312.908 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20250409T163137/Express0101.lzma 2025-04-09T20:41:58.930Z,1744231318.930 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0101.lzma.bak 2025-04-09T20:41:58.930Z,1744231318.930 [DataOverHttps](INFO): SBD MOMSN=24599567 2025-04-09T20:42:00.247Z,1744231320.247 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:42:00.247Z,1744231320.247 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:42:00.247Z,1744231320.247 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:42:14.375Z,1744231334.375 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:45:52.943Z,1744231552.943 [DVL_micro](ERROR): Failed to parse: :BI,+00018,+00273,-00230,+00000,I 2025-04-09T20:47:00.814Z,1744231620.814 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:47:00.814Z,1744231620.814 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:47:00.814Z,1744231620.814 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:47:00.815Z,1744231620.815 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:47:01.224Z,1744231621.224 [Default:CheckIn:D] Stopped 2025-04-09T20:47:01.224Z,1744231621.224 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 251.129036 min 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn:E] Stopped 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn] Stopped 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn](INFO): Running loop #32 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn] Running Loop=32 2025-04-09T20:47:01.622Z,1744231621.622 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:47:01.623Z,1744231621.623 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:47:03.635Z,1744231623.635 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204703.00,A,3648.09280,N,12149.48971,W,15.803,242.73,090425,,,A*4D 2025-04-09T20:47:03.637Z,1744231623.637 [NAL9602](INFO): GPS fix at 20250409T204703: (36.801547, -121.824828) 2025-04-09T20:47:03.647Z,1744231623.647 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:47:03.647Z,1744231623.647 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:47:12.580Z,1744231632.580 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250409T163137/Courier0103.lzma 2025-04-09T20:47:13.582Z,1744231633.582 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0103.lzma.bak 2025-04-09T20:47:13.582Z,1744231633.582 [DataOverHttps](INFO): SBD MOMSN=24599580 2025-04-09T20:47:18.597Z,1744231638.597 [NAL9602](INFO): SBD MO Status=0, MOMSN=19237, MT Status=0, MTMSN=0 2025-04-09T20:47:18.597Z,1744231638.597 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:47:29.732Z,1744231649.732 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250409T163137/Express0104.lzma 2025-04-09T20:47:30.734Z,1744231650.734 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0104.lzma.bak 2025-04-09T20:47:30.734Z,1744231650.734 [DataOverHttps](INFO): SBD MOMSN=24599588 2025-04-09T20:47:31.927Z,1744231651.927 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:47:31.927Z,1744231651.927 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:47:31.927Z,1744231651.927 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:47:49.292Z,1744231669.292 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:52:32.533Z,1744231952.533 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:52:32.533Z,1744231952.533 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:52:32.533Z,1744231952.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:52:32.533Z,1744231952.533 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:52:32.947Z,1744231952.947 [Default:CheckIn:D] Stopped 2025-04-09T20:52:32.947Z,1744231952.947 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 256.657764 min 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn:E] Stopped 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn] Stopped 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn](INFO): Running loop #33 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn] Running Loop=33 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:52:33.358Z,1744231953.358 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:52:35.361Z,1744231955.361 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205235.00,A,3647.61268,N,12150.84171,W,9.564,239.12,090425,,,A*71 2025-04-09T20:52:35.363Z,1744231955.363 [NAL9602](INFO): GPS fix at 20250409T205235: (36.793545, -121.847362) 2025-04-09T20:52:35.397Z,1744231955.397 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:52:35.397Z,1744231955.397 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:52:49.067Z,1744231969.067 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T163137/Courier0106.lzma 2025-04-09T20:52:50.070Z,1744231970.070 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Courier0106.lzma.bak 2025-04-09T20:52:50.070Z,1744231970.070 [DataOverHttps](INFO): SBD MOMSN=24599679 2025-04-09T20:53:07.932Z,1744231987.932 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250409T163137/Express0107.lzma 2025-04-09T20:53:08.934Z,1744231988.934 [DataOverHttps](INFO): Moved sent file to Logs/20250409T163137/Express0107.lzma.bak 2025-04-09T20:53:08.934Z,1744231988.934 [DataOverHttps](INFO): SBD MOMSN=24599683 2025-04-09T20:53:10.109Z,1744231990.109 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:53:10.110Z,1744231990.110 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:53:10.110Z,1744231990.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:53:22.625Z,1744232002.625 [NAL9602](INFO): SBD MO Status=2, MOMSN=19238, MT Status=2, MTMSN=0 2025-04-09T20:53:22.626Z,1744232002.626 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T20:53:42.421Z,1744232022.421 [NAL9602](INFO): SBD MO