2024-03-11T14:50:43.608Z,1710168643.608 [Supervisor](DEBUG): Initializing supervisor. 2024-03-11T14:50:43.613Z,1710168643.613 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-03-11T14:50:43.613Z,1710168643.613 [SyncHandler](INFO): Protected caller Thread ID is 837 2024-03-11T14:50:43.614Z,1710168643.614 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-03-11T14:50:43.615Z,1710168643.615 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-03-11T14:50:43.615Z,1710168643.615 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2024-03-11T14:50:43.619Z,1710168643.619 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-03-11T14:50:43.636Z,1710168643.636 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-03-11T14:50:43.637Z,1710168643.637 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-03-11T14:50:43.638Z,1710168643.638 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2024-03-11T14:50:43.642Z,1710168643.642 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-03-11T14:50:43.643Z,1710168643.643 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-03-11T14:50:43.643Z,1710168643.643 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2024-03-11T14:50:43.645Z,1710168643.645 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-03-11T14:50:43.646Z,1710168643.646 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-03-11T14:50:43.647Z,1710168643.647 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2024-03-11T14:50:43.650Z,1710168643.650 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-03-11T14:50:43.651Z,1710168643.651 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-03-11T14:50:43.655Z,1710168643.655 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-03-11T14:50:43.932Z,1710168643.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-03-11T14:50:43.934Z,1710168643.934 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-03-11T14:50:44.017Z,1710168644.017 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-03-11T14:50:44.524Z,1710168644.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-03-11T14:50:44.526Z,1710168644.526 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-03-11T14:50:44.863Z,1710168644.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-03-11T14:50:44.865Z,1710168644.865 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-03-11T14:50:44.963Z,1710168644.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-03-11T14:50:44.965Z,1710168644.965 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-03-11T14:50:45.376Z,1710168645.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-03-11T14:50:45.377Z,1710168645.377 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-03-11T14:50:45.599Z,1710168645.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-03-11T14:50:45.601Z,1710168645.601 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-03-11T14:50:46.131Z,1710168646.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-03-11T14:50:46.132Z,1710168646.132 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-03-11T14:50:46.275Z,1710168646.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-03-11T14:50:46.277Z,1710168646.277 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-03-11T14:50:46.809Z,1710168646.809 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-03-11T14:50:47.685Z,1710168647.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-03-11T14:50:47.686Z,1710168647.686 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-03-11T14:50:48.070Z,1710168648.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-03-11T14:50:48.070Z,1710168648.070 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-03-11T14:50:48.270Z,1710168648.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-03-11T14:50:48.270Z,1710168648.270 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-03-11T14:50:48.383Z,1710168648.383 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-03-11T14:50:48.385Z,1710168648.385 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-03-11T14:50:48.620Z,1710168648.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-03-11T14:50:48.621Z,1710168648.621 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-03-11T14:50:48.875Z,1710168648.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-03-11T14:50:48.877Z,1710168648.877 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-03-11T14:50:48.881Z,1710168648.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-03-11T14:50:48.976Z,1710168648.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-03-11T14:50:49.063Z,1710168649.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-03-11T14:50:49.167Z,1710168649.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-03-11T14:50:49.253Z,1710168649.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-03-11T14:50:49.357Z,1710168649.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-03-11T14:50:49.463Z,1710168649.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-03-11T14:50:49.579Z,1710168649.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-03-11T14:50:49.733Z,1710168649.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-03-11T14:50:49.877Z,1710168649.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-03-11T14:50:49.991Z,1710168649.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-03-11T14:50:50.264Z,1710168650.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-03-11T14:50:50.265Z,1710168650.265 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-03-11T14:50:50.280Z,1710168650.280 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-03-11T14:50:50.355Z,1710168650.355 [VerticalControl](DEBUG): Construct VerticalControl. 2024-03-11T14:50:50.431Z,1710168650.431 [VerticalControl] Loaded 2024-03-11T14:50:50.432Z,1710168650.432 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-03-11T14:50:50.435Z,1710168650.435 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-03-11T14:50:50.477Z,1710168650.477 [HorizontalControl] Loaded 2024-03-11T14:50:50.477Z,1710168650.477 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-03-11T14:50:50.480Z,1710168650.480 [SpeedControl](DEBUG): Construct SpeedControl. 2024-03-11T14:50:50.483Z,1710168650.483 [SpeedControl] Loaded 2024-03-11T14:50:50.483Z,1710168650.483 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-03-11T14:50:50.486Z,1710168650.486 [LoopControl](DEBUG): Construct LoopControl. 2024-03-11T14:50:50.486Z,1710168650.486 [LoopControl] Loaded 2024-03-11T14:50:50.487Z,1710168650.487 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-03-11T14:50:50.487Z,1710168650.487 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-03-11T14:50:50.488Z,1710168650.488 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-03-11T14:50:50.504Z,1710168650.504 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-03-11T14:50:50.506Z,1710168650.506 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-03-11T14:50:50.698Z,1710168650.698 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-03-11T14:50:50.699Z,1710168650.699 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-03-11T14:50:50.893Z,1710168650.893 [BuoyancyServo] Loaded 2024-03-11T14:50:50.893Z,1710168650.893 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-03-11T14:50:50.914Z,1710168650.914 [ElevatorServo] Loaded 2024-03-11T14:50:50.914Z,1710168650.914 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-03-11T14:50:50.933Z,1710168650.933 [MassServo] Loaded 2024-03-11T14:50:50.933Z,1710168650.933 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-03-11T14:50:50.952Z,1710168650.952 [RudderServo] Loaded 2024-03-11T14:50:50.952Z,1710168650.952 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-03-11T14:50:50.967Z,1710168650.967 [ThrusterHE] Loaded 2024-03-11T14:50:50.967Z,1710168650.967 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-03-11T14:50:50.968Z,1710168650.968 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-03-11T14:50:50.968Z,1710168650.968 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-03-11T14:50:51.117Z,1710168651.117 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-03-11T14:50:51.118Z,1710168651.118 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-03-11T14:50:51.132Z,1710168651.132 [NavChart] Loaded 2024-03-11T14:50:51.132Z,1710168651.132 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-03-11T14:50:51.138Z,1710168651.138 [UniversalFixResidualReporter] Loaded 2024-03-11T14:50:51.138Z,1710168651.138 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-03-11T14:50:51.139Z,1710168651.139 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-03-11T14:50:51.139Z,1710168651.139 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-03-11T14:50:51.227Z,1710168651.227 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-03-11T14:50:51.227Z,1710168651.227 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-03-11T14:50:52.804Z,1710168652.804 [AHRS_M2] Loaded 2024-03-11T14:50:52.804Z,1710168652.804 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-03-11T14:50:53.652Z,1710168653.652 [BPC1] Loaded 2024-03-11T14:50:53.652Z,1710168653.652 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-03-11T14:50:53.796Z,1710168653.796 [DataOverHttps] Loaded 2024-03-11T14:50:53.797Z,1710168653.797 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-03-11T14:50:53.798Z,1710168653.798 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-03-11T14:50:53.798Z,1710168653.798 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2024-03-11T14:50:53.819Z,1710168653.819 [Depth_Keller] Loaded 2024-03-11T14:50:53.820Z,1710168653.820 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-03-11T14:50:53.825Z,1710168653.825 [DropWeight] Loaded 2024-03-11T14:50:53.825Z,1710168653.825 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-03-11T14:50:53.888Z,1710168653.888 [NAL9602] Loaded 2024-03-11T14:50:53.889Z,1710168653.889 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-03-11T14:50:53.920Z,1710168653.920 [Onboard] Loaded 2024-03-11T14:50:53.920Z,1710168653.920 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-03-11T14:50:53.921Z,1710168653.921 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0 2024-03-11T14:50:53.921Z,1710168653.921 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2024-03-11T14:50:53.935Z,1710168653.935 [Power24vConverter] Loaded 2024-03-11T14:50:53.935Z,1710168653.935 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-03-11T14:50:53.949Z,1710168653.949 [Radio_Surface] Loaded 2024-03-11T14:50:53.949Z,1710168653.949 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-03-11T14:50:53.950Z,1710168653.950 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0 2024-03-11T14:50:53.950Z,1710168653.950 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2024-03-11T14:50:53.964Z,1710168653.964 [Sonardyne_Nano] Loaded 2024-03-11T14:50:53.965Z,1710168653.965 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-03-11T14:50:53.965Z,1710168653.965 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-03-11T14:50:53.966Z,1710168653.966 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-03-11T14:50:54.041Z,1710168654.041 [DepthRateCalculator] Loaded 2024-03-11T14:50:54.041Z,1710168654.041 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-03-11T14:50:54.046Z,1710168654.046 [PitchRateCalculator] Loaded 2024-03-11T14:50:54.047Z,1710168654.047 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-03-11T14:50:54.056Z,1710168654.056 [SpeedCalculator] Loaded 2024-03-11T14:50:54.056Z,1710168654.056 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-03-11T14:50:54.060Z,1710168654.060 [YawRateCalculator] Loaded 2024-03-11T14:50:54.061Z,1710168654.061 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-03-11T14:50:54.080Z,1710168654.080 [ElevatorOffsetCalculator] Loaded 2024-03-11T14:50:54.080Z,1710168654.080 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-03-11T14:50:54.081Z,1710168654.081 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-03-11T14:50:54.082Z,1710168654.082 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-03-11T14:50:54.275Z,1710168654.275 [CANONSampler] Loaded 2024-03-11T14:50:54.275Z,1710168654.275 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-03-11T14:50:54.337Z,1710168654.337 [CTD_Seabird] Loaded 2024-03-11T14:50:54.337Z,1710168654.337 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-03-11T14:50:54.338Z,1710168654.338 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0 2024-03-11T14:50:54.338Z,1710168654.338 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927 2024-03-11T14:50:54.359Z,1710168654.359 [PAR_Licor] Loaded 2024-03-11T14:50:54.359Z,1710168654.359 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-03-11T14:50:54.401Z,1710168654.401 [WetLabsBB2FL] Loaded 2024-03-11T14:50:54.401Z,1710168654.401 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-03-11T14:50:54.402Z,1710168654.402 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0 2024-03-11T14:50:54.402Z,1710168654.402 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928 2024-03-11T14:50:54.403Z,1710168654.403 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-03-11T14:50:54.404Z,1710168654.404 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-03-11T14:50:54.448Z,1710168654.448 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-03-11T14:50:54.448Z,1710168654.448 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-03-11T14:50:54.861Z,1710168654.861 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-03-11T14:50:54.862Z,1710168654.862 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-03-11T14:50:55.044Z,1710168655.044 [SBIT](DEBUG): Construct Startup Built In Test. 2024-03-11T14:50:55.053Z,1710168655.053 [SBIT] Loaded 2024-03-11T14:50:55.053Z,1710168655.053 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-03-11T14:50:55.056Z,1710168655.056 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-03-11T14:50:55.070Z,1710168655.070 [IBIT] Loaded 2024-03-11T14:50:55.070Z,1710168655.070 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-03-11T14:50:55.076Z,1710168655.076 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-03-11T14:50:55.184Z,1710168655.184 [CBIT] Loaded 2024-03-11T14:50:55.184Z,1710168655.184 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-03-11T14:50:55.184Z,1710168655.184 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-03-11T14:50:55.185Z,1710168655.185 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-03-11T14:50:55.332Z,1710168655.332 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-03-11T14:50:55.339Z,1710168655.339 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-03-11T14:50:55.342Z,1710168655.342 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-03-11T14:50:55.353Z,1710168655.353 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-03-11T14:50:55.354Z,1710168655.354 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0 2024-03-11T14:50:55.354Z,1710168655.354 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929 2024-03-11T14:50:55.359Z,1710168655.359 [Supervisor](INFO): Main Thread ID is 830 2024-03-11T14:50:55.359Z,1710168655.359 [Supervisor](DEBUG): Running supervisor. 2024-03-11T14:50:55.360Z,1710168655.360 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930 2024-03-11T14:50:55.360Z,1710168655.360 [CommandExec](INFO): Initializing the command executive. 2024-03-11T14:50:55.362Z,1710168655.362 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931 2024-03-11T14:50:55.364Z,1710168655.364 [controlThread ThreadHandler](INFO): Handler Thread ID is 932 2024-03-11T14:50:55.364Z,1710168655.364 [controlThread](DEBUG): Initializing ControlThread 2024-03-11T14:50:55.365Z,1710168655.365 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-03-11T14:50:55.367Z,1710168655.367 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-03-11T14:50:55.368Z,1710168655.368 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-03-11T14:50:55.368Z,1710168655.368 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-03-11T14:50:55.370Z,1710168655.370 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-03-11T14:50:55.370Z,1710168655.370 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-03-11T14:50:55.375Z,1710168655.375 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-03-11T14:50:55.375Z,1710168655.375 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-03-11T14:50:55.375Z,1710168655.375 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-03-11T14:50:55.376Z,1710168655.376 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-03-11T14:50:55.376Z,1710168655.376 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-03-11T14:50:55.382Z,1710168655.382 [SBIT](INFO): Initialize SBIT Component. 2024-03-11T14:50:55.382Z,1710168655.382 [SBIT](IMPORTANT): git: 2024-02-14 2024-03-11T14:50:55.382Z,1710168655.382 [SBIT](INFO): git hash: 8fc4e848ca047b5de9ae8a7c02f55958c6301ab2 2024-03-11T14:50:55.383Z,1710168655.383 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-03-11T14:50:55.384Z,1710168655.384 [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 2024-03-11T14:50:55.385Z,1710168655.385 [SBIT](INFO): Beginning SBIT in 68.000000 seconds. 2024-03-11T14:50:55.386Z,1710168655.386 [IBIT](INFO): Initialize IBIT Component. 2024-03-11T14:50:55.386Z,1710168655.386 [CBIT](DEBUG): Initialize CBIT Component. 2024-03-11T14:50:55.387Z,1710168655.387 [logger ThreadHandler](INFO): Handler Thread ID is 933 2024-03-11T14:50:55.397Z,1710168655.397 [CBIT](DEBUG): Initialized mux pins. 2024-03-11T14:50:55.397Z,1710168655.397 [CBIT](DEBUG): Initializing the watchdog timer. 2024-03-11T14:50:55.405Z,1710168655.405 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 934 2024-03-11T14:50:55.407Z,1710168655.407 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-03-11T14:50:55.417Z,1710168655.417 [Onboard ThreadHandler](INFO): Handler Thread ID is 935 2024-03-11T14:50:55.422Z,1710168655.422 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-03-11T14:50:55.422Z,1710168655.422 [CBIT](DEBUG): Initializing heartbeat. 2024-03-11T14:50:55.438Z,1710168655.438 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 936 2024-03-11T14:50:55.453Z,1710168655.453 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 937 2024-03-11T14:50:55.454Z,1710168655.454 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-03-11T14:50:55.459Z,1710168655.459 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 939 2024-03-11T14:50:55.462Z,1710168655.462 [WetLabsBB2FL](INFO): Powering up 2024-03-11T14:50:55.463Z,1710168655.463 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941 2024-03-11T14:50:55.473Z,1710168655.473 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-03-11T14:50:55.473Z,1710168655.473 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-03-11T14:50:55.473Z,1710168655.473 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-03-11T14:50:55.474Z,1710168655.474 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-03-11T14:50:55.474Z,1710168655.474 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-03-11T14:50:55.474Z,1710168655.474 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-03-11T14:50:55.474Z,1710168655.474 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-03-11T14:50:55.474Z,1710168655.474 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-03-11T14:50:55.493Z,1710168655.493 [CBIT](DEBUG): Deactivating GF circuits. 2024-03-11T14:50:55.493Z,1710168655.493 [CBIT](DEBUG): Deactivating emergency mode. 2024-03-11T14:50:55.533Z,1710168655.533 [CBIT](DEBUG): Backplane powered. 2024-03-11T14:50:55.534Z,1710168655.534 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-03-11T14:50:55.534Z,1710168655.534 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-03-11T14:50:55.576Z,1710168655.576 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-11T14:50:55.601Z,1710168655.601 [MissionManager](DEBUG): 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 } } } 2024-03-11T14:50:55.602Z,1710168655.602 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-03-11T14:50:55.602Z,1710168655.602 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-03-11T14:50:55.663Z,1710168655.663 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-03-11T14:50:55.664Z,1710168655.664 [Default:A.Wait](DEBUG): Construct Wait. 2024-03-11T14:50:55.667Z,1710168655.667 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-11T14:50:55.722Z,1710168655.722 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-03-11T14:50:55.724Z,1710168655.724 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-03-11T14:50:55.746Z,1710168655.746 [Default:E.Execute](DEBUG): Construct Execute. 2024-03-11T14:50:55.755Z,1710168655.755 [MissionManager](DEBUG): 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" } } 2024-03-11T14:50:55.760Z,1710168655.760 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-03-11T14:50:55.785Z,1710168655.785 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-11T14:50:55.841Z,1710168655.841 [Radio_Surface](INFO): Powering up 2024-03-11T14:50:55.942Z,1710168655.942 [Power24vConverter](INFO): Powering up. 2024-03-11T14:50:55.943Z,1710168655.943 [Sonardyne_Nano](INFO): Initializing. 2024-03-11T14:50:55.962Z,1710168655.962 [CANONSampler](INFO): Powering down 2024-03-11T14:50:56.020Z,1710168656.020 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-03-11T14:50:56.029Z,1710168656.029 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-03-11T14:50:56.030Z,1710168656.030 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-11T14:50:56.037Z,1710168656.037 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-11T14:50:56.038Z,1710168656.038 [MassServo](DEBUG): Initializing EZServoServo. 2024-03-11T14:50:56.045Z,1710168656.045 [MassServo](DEBUG): Initializing MassServo. 2024-03-11T14:50:56.046Z,1710168656.046 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-11T14:50:56.053Z,1710168656.053 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-11T14:50:56.054Z,1710168656.054 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-03-11T14:50:56.061Z,1710168656.061 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-03-11T14:50:57.585Z,1710168657.585 [WetLabsBB2FL](INFO): Powering down 2024-03-11T14:50:58.192Z,1710168658.192 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-03-11T14:50:59.440Z,1710168659.440 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109 2024-03-11T14:51:01.679Z,1710168661.679 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-03-11T14:51:24.051Z,1710168684.051 [NAL9602](INFO): Powering up NAL9602 2024-03-11T14:51:34.959Z,1710168694.959 [NAL9602](INFO): NAL9602 initialized 2024-03-11T14:52:02.746Z,1710168722.746 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:52:04.023Z,1710168724.023 [SBIT](IMPORTANT): Beginning Startup BIT 2024-03-11T14:52:04.027Z,1710168724.027 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-11T14:52:15.027Z,1710168735.027 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:52:15.053Z,1710168735.053 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-11T14:52:15.468Z,1710168735.468 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-11T14:52:26.446Z,1710168746.446 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-11T14:52:26.910Z,1710168746.910 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-11T14:52:27.949Z,1710168747.949 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:52:37.686Z,1710168757.686 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006408 CHAN A1 (24V): 0.000218 CHAN A2 (12V): -0.002323 CHAN A3 (5V): -0.001225 CHAN B0 (3.3V): 0.000272 CHAN B1 (3.15aV): 0.000077 CHAN B2 (3.15bV): -0.000111 CHAN B3 (GND): 0.000055 OPEN: -0.000651 Full Scale: +/- 1 mA 2024-03-11T14:52:40.878Z,1710168760.878 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:52:54.216Z,1710168774.216 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:52:57.899Z,1710168777.899 [SBIT](IMPORTANT): SBIT PASSED 2024-03-11T14:52:57.900Z,1710168777.900 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool; 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent; 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=275.678967 cubic_centimeter; 2024-03-11T14:52:57.901Z,1710168777.901 [SBIT](IMPORTANT): VerticalControl.massDefault=-12.054874 millimeter; 2024-03-11T14:52:58.298Z,1710168778.298 [MissionManager](IMPORTANT): Started mission Startup 2024-03-11T14:52:58.298Z,1710168778.298 [Startup] Running Loop=1 2024-03-11T14:52:58.298Z,1710168778.298 [Startup](DEBUG): Aggregate::initialize Startup 2024-03-11T14:52:58.298Z,1710168778.298 [Startup:A.GoToSurface] Running Loop=1 2024-03-11T14:52:58.299Z,1710168778.299 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-11T14:52:58.299Z,1710168778.299 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-11T14:52:58.299Z,1710168778.299 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-11T14:52:58.300Z,1710168778.300 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-11T14:52:58.300Z,1710168778.300 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-11T14:52:58.301Z,1710168778.301 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-11T14:52:58.302Z,1710168778.302 [Startup:StartupSatComms] Running Loop=1 2024-03-11T14:52:58.302Z,1710168778.302 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-03-11T14:52:58.302Z,1710168778.302 [Startup:StartupSatComms:A] Running Loop=1 2024-03-11T14:52:58.675Z,1710168778.675 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-03-11T14:53:07.541Z,1710168787.541 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:53:17.753Z,1710168797.753 [CommandExec](IMPORTANT): got command failComponent 2024-03-11T14:53:17.753Z,1710168797.753 [CommandExec](IMPORTANT): Failed components: 2024-03-11T14:53:17.754Z,1710168797.754 [CommandExec](IMPORTANT): No failed Components. 2024-03-11T14:53:20.469Z,1710168800.469 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:53:25.929Z,1710168805.929 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2024-03-11T14:53:25.930Z,1710168805.930 [CommandExec](IMPORTANT): Onboard.Humidity 12.971553 % 2024-03-11T14:53:27.631Z,1710168807.631 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004628 2024-03-11T14:53:34.475Z,1710168814.475 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2024-03-11T14:53:34.475Z,1710168814.475 [CommandExec](IMPORTANT): Onboard.Pressure 67522.382812 Pa 2024-03-11T14:53:41.647Z,1710168821.647 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-03-11T14:53:41.647Z,1710168821.647 [CommandExec](IMPORTANT): Onboard.Pressure 9.818375 psi 2024-03-11T14:53:55.633Z,1710168835.633 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-03-11T14:53:55.633Z,1710168835.633 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T14:53:55.643Z,1710168835.643 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T14:53:56.052Z,1710168836.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T14:53:56.052Z,1710168836.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-03-11T14:53:58.487Z,1710168838.487 [Startup:StartupSatComms:A](INFO): Timed out from 2024-03-11T14:52:58.3Z 2024-03-11T14:53:58.487Z,1710168838.487 [Startup:StartupSatComms:A] Stopped 2024-03-11T14:53:58.488Z,1710168838.488 [Startup:StartupSatComms:B] Running Loop=1 2024-03-11T14:53:58.887Z,1710168838.887 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-03-11T14:54:05.388Z,1710168845.388 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240311T145043/Courier0000.lzma 2024-03-11T14:54:05.737Z,1710168845.737 [CommandExec](IMPORTANT): got command 2024-03-11T14:54:05.737Z,1710168845.737 [CommandExec](FAULT): Incomplete syntax. Try: help 2024-03-11T14:54:06.390Z,1710168846.390 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0000.lzma.bak 2024-03-11T14:54:06.391Z,1710168846.391 [DataOverHttps](INFO): SBD MOMSN=19310504 2024-03-11T14:54:09.589Z,1710168849.589 [CommandExec](IMPORTANT): got command load 2024-03-11T14:54:09.589Z,1710168849.589 [CommandExec](FAULT): Incomplete syntax. Try: help load 2024-03-11T14:54:10.186Z,1710168850.186 [DataOverHttps](INFO): Sending 1041 bytes from file Logs/20240311T145043/Express0001.lzma 2024-03-11T14:54:11.186Z,1710168851.186 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0001.lzma.bak 2024-03-11T14:54:11.186Z,1710168851.186 [DataOverHttps](INFO): SBD MOMSN=19310507 2024-03-11T14:54:12.546Z,1710168852.546 [Startup:StartupSatComms:B] Stopped 2024-03-11T14:54:12.547Z,1710168852.547 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-03-11T14:54:12.547Z,1710168852.547 [Startup:StartupSatComms] Stopped 2024-03-11T14:54:12.547Z,1710168852.547 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-03-11T14:54:12.547Z,1710168852.547 [Startup](INFO): Completed Startup 2024-03-11T14:54:12.548Z,1710168852.548 [MissionManager](INFO): Startup is completed. 2024-03-11T14:54:12.548Z,1710168852.548 [MissionManager](INFO): Uninitializing Mission Startup 2024-03-11T14:54:12.548Z,1710168852.548 [Startup] Stopped 2024-03-11T14:54:12.548Z,1710168852.548 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-03-11T14:54:12.548Z,1710168852.548 [Startup:A.GoToSurface] Stopped 2024-03-11T14:54:12.548Z,1710168852.548 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-11T14:54:12.958Z,1710168852.958 [MissionManager](IMPORTANT): Started mission Default 2024-03-11T14:54:12.958Z,1710168852.958 [Default] Running Loop=1 2024-03-11T14:54:12.958Z,1710168852.958 [Default](DEBUG): Aggregate::initialize Default 2024-03-11T14:54:12.958Z,1710168852.958 [Default:B.GoToSurface] Running Loop=1 2024-03-11T14:54:12.958Z,1710168852.958 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-11T14:54:12.959Z,1710168852.959 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-11T14:54:12.959Z,1710168852.959 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-11T14:54:12.959Z,1710168852.959 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-11T14:54:12.959Z,1710168852.959 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-11T14:54:12.960Z,1710168852.960 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-11T14:54:12.960Z,1710168852.960 [Default:A.Wait] Running Loop=1 2024-03-11T14:54:12.960Z,1710168852.960 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-03-11T14:54:26.283Z,1710168866.283 [Default:A.Wait](INFO): Done Waiting. 2024-03-11T14:54:26.283Z,1710168866.283 [Default:A.Wait] Stopped 2024-03-11T14:54:26.283Z,1710168866.283 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T14:54:26.702Z,1710168866.702 [Default:CheckIn] Running Loop=1 2024-03-11T14:54:26.703Z,1710168866.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T14:54:26.703Z,1710168866.703 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T14:54:27.090Z,1710168867.090 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-03-11T14:55:40.609Z,1710168940.609 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T14:55:40.617Z,1710168940.617 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-03-11T14:55:40.620Z,1710168940.620 [BPC1](INFO): Received data from all battery sticks. 2024-03-11T14:56:38.790Z,1710168998.790 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-11T14:56:56.601Z,1710169016.601 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-03-11T14:56:56.601Z,1710169016.601 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T14:56:56.611Z,1710169016.611 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T14:56:57.014Z,1710169017.014 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T14:56:57.014Z,1710169017.014 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-03-11T14:59:26.851Z,1710169166.851 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-11T14:54:26.7Z 2024-03-11T14:59:26.851Z,1710169166.851 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T14:59:26.851Z,1710169166.851 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T14:59:27.263Z,1710169167.263 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-03-11T14:59:34.440Z,1710169174.440 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240311T145043/Courier0004.lzma 2024-03-11T14:59:35.442Z,1710169175.442 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0004.lzma.bak 2024-03-11T14:59:35.442Z,1710169175.442 [DataOverHttps](INFO): SBD MOMSN=19310540 2024-03-11T14:59:50.856Z,1710169190.856 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20240311T145043/Express0005.lzma 2024-03-11T14:59:51.858Z,1710169191.858 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0005.lzma.bak 2024-03-11T14:59:51.858Z,1710169191.858 [DataOverHttps](INFO): SBD MOMSN=19310542 2024-03-11T14:59:53.119Z,1710169193.119 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T14:59:53.119Z,1710169193.119 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T14:59:53.119Z,1710169193.119 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T14:59:57.566Z,1710169197.566 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-03-11T14:59:57.566Z,1710169197.566 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T14:59:57.619Z,1710169197.619 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T14:59:57.000Z,1710169198.000 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T14:59:57.000Z,1710169198.000 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-03-11T15:00:16.254Z,1710169216.254 [CommandExec](IMPORTANT): got command strobe off 2024-03-11T15:00:16.254Z,1710169216.254 [CommandExec](IMPORTANT): Deactivating strobe 2024-03-11T15:00:17.605Z,1710169217.605 [CommandExec](IMPORTANT): got command failComponent 2024-03-11T15:00:17.605Z,1710169217.605 [CommandExec](IMPORTANT): Failed components: 2024-03-11T15:00:17.605Z,1710169217.605 [CommandExec](IMPORTANT): No failed Components. 2024-03-11T15:00:24.125Z,1710169224.125 [CommandExec](IMPORTANT): got command get Onboard.Pressure 2024-03-11T15:00:24.125Z,1710169224.125 [CommandExec](IMPORTANT): Onboard.Pressure 67021.070312 Pa 2024-03-11T15:00:30.078Z,1710169230.078 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2024-03-11T15:00:30.079Z,1710169230.079 [CommandExec](IMPORTANT): Onboard.Humidity 12.995970 % 2024-03-11T15:00:56.941Z,1710169256.941 [Power24vConverter](INFO): Powering down. 2024-03-11T15:01:23.849Z,1710169283.849 [CommandExec](IMPORTANT): got command burn on 2024-03-11T15:01:23.849Z,1710169283.849 [CommandExec](IMPORTANT): Activating dropweight wire 2024-03-11T15:01:36.530Z,1710169296.530 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-11T15:01:36.530Z,1710169296.530 [NAL9602] Data Fault, FailCount= 1 2024-03-11T15:01:36.530Z,1710169296.530 [NAL9602](ERROR): Data Fault 2024-03-11T15:01:36.564Z,1710169296.564 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-11T15:01:36.934Z,1710169296.934 [NAL9602](INFO): Powering down 2024-03-11T15:01:37.777Z,1710169297.777 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-11T15:01:37.777Z,1710169297.777 [NAL9602] No Fault, FailCount= 1 2024-03-11T15:01:59.882Z,1710169319.882 [CommandExec](IMPORTANT): got command burn off 2024-03-11T15:01:59.882Z,1710169319.882 [CommandExec](IMPORTANT): Deactivating dropweight wire 2024-03-11T15:02:07.238Z,1710169327.238 [NAL9602](INFO): Powering up NAL9602 2024-03-11T15:02:18.148Z,1710169338.148 [NAL9602](INFO): NAL9602 initialized 2024-03-11T15:02:58.564Z,1710169378.564 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-03-11T15:02:58.564Z,1710169378.564 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T15:02:58.613Z,1710169378.613 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T15:02:59.017Z,1710169379.017 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T15:02:59.017Z,1710169379.017 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-03-11T15:04:53.690Z,1710169493.690 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:04:53.690Z,1710169493.690 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:04:53.690Z,1710169493.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:04:53.691Z,1710169493.691 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:04:54.099Z,1710169494.099 [Default:CheckIn:D] Stopped 2024-03-11T15:04:54.099Z,1710169494.099 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.685686 min 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn:E] Stopped 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn] Stopped 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn](INFO): Running loop #2 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn] Running Loop=2 2024-03-11T15:04:54.507Z,1710169494.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:04:54.508Z,1710169494.508 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:05:59.592Z,1710169559.592 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-03-11T15:05:59.592Z,1710169559.592 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T15:05:59.614Z,1710169559.614 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T15:06:00.010Z,1710169560.010 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T15:06:00.010Z,1710169560.010 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-03-11T15:09:00.537Z,1710169740.537 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-03-11T15:09:00.538Z,1710169740.538 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T15:09:00.548Z,1710169740.548 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T15:09:00.967Z,1710169740.967 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T15:09:00.967Z,1710169740.967 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-03-11T15:09:54.688Z,1710169794.688 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-11T15:04:54.5Z 2024-03-11T15:09:54.688Z,1710169794.688 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:09:54.688Z,1710169794.688 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:10:01.872Z,1710169801.872 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240311T145043/Courier0007.lzma 2024-03-11T15:10:02.874Z,1710169802.874 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0007.lzma.bak 2024-03-11T15:10:02.874Z,1710169802.874 [DataOverHttps](INFO): SBD MOMSN=19310550 2024-03-11T15:10:18.820Z,1710169818.820 [DataOverHttps](INFO): Sending 384 bytes from file Logs/20240311T145043/Express0008.lzma 2024-03-11T15:10:19.819Z,1710169819.819 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0008.lzma.bak 2024-03-11T15:10:19.819Z,1710169819.819 [DataOverHttps](INFO): SBD MOMSN=19310552 2024-03-11T15:10:20.950Z,1710169820.950 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:10:20.951Z,1710169820.951 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:10:20.951Z,1710169820.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:12:01.581Z,1710169921.581 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-03-11T15:12:01.581Z,1710169921.581 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T15:12:01.591Z,1710169921.591 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T15:12:02.004Z,1710169922.004 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T15:12:02.004Z,1710169922.004 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-03-11T15:12:19.319Z,1710169939.319 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-11T15:12:19.319Z,1710169939.319 [NAL9602] Data Fault, FailCount= 2 2024-03-11T15:12:19.319Z,1710169939.319 [NAL9602](ERROR): Data Fault 2024-03-11T15:12:19.345Z,1710169939.345 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-11T15:12:19.726Z,1710169939.726 [NAL9602](INFO): Powering down 2024-03-11T15:12:20.580Z,1710169940.580 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-11T15:12:20.581Z,1710169940.581 [NAL9602] No Fault, FailCount= 2 2024-03-11T15:12:50.035Z,1710169970.035 [NAL9602](INFO): Powering up NAL9602 2024-03-11T15:13:00.942Z,1710169980.942 [NAL9602](INFO): NAL9602 initialized 2024-03-11T15:15:02.557Z,1710170102.557 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-03-11T15:15:02.557Z,1710170102.557 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-11T15:15:02.568Z,1710170102.568 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-11T15:15:02.975Z,1710170102.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-11T15:15:02.975Z,1710170102.975 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-03-11T15:15:21.543Z,1710170121.543 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:15:21.543Z,1710170121.543 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:15:21.543Z,1710170121.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:15:21.543Z,1710170121.543 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:15:21.947Z,1710170121.947 [Default:CheckIn:D] Stopped 2024-03-11T15:15:21.947Z,1710170121.947 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.149807 min 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn:E] Stopped 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn] Stopped 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn](INFO): Running loop #3 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn] Running Loop=3 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:15:22.365Z,1710170122.365 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:17:55.867Z,1710170275.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,151754.00,A,3648.15708,N,12147.30592,W,0.661,0.00,110324,,,A*75 2024-03-11T15:17:55.870Z,1710170275.870 [NAL9602](INFO): GPS fix at 20240311T151754: (36.802618, -121.788432) 2024-03-11T15:17:55.883Z,1710170275.883 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:17:55.883Z,1710170275.883 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:18:05.764Z,1710170285.764 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240311T145043/Courier0010.lzma 2024-03-11T15:18:06.658Z,1710170286.658 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0010.lzma.bak 2024-03-11T15:18:06.658Z,1710170286.658 [DataOverHttps](INFO): SBD MOMSN=19310569 2024-03-11T15:18:26.333Z,1710170306.333 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20240311T145043/Express0011.lzma 2024-03-11T15:18:27.334Z,1710170307.334 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0011.lzma.bak 2024-03-11T15:18:27.334Z,1710170307.334 [DataOverHttps](INFO): SBD MOMSN=19310572 2024-03-11T15:18:28.225Z,1710170308.225 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:18:28.673Z,1710170308.673 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:18:28.673Z,1710170308.673 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:18:28.673Z,1710170308.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:23:29.231Z,1710170609.231 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:23:29.231Z,1710170609.231 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:23:29.231Z,1710170609.231 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:23:29.231Z,1710170609.231 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:23:29.635Z,1710170609.635 [Default:CheckIn:D] Stopped 2024-03-11T15:23:29.636Z,1710170609.636 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:23:30.040Z,1710170610.040 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.277956 min 2024-03-11T15:23:30.040Z,1710170610.040 [Default:CheckIn:E] Stopped 2024-03-11T15:23:30.040Z,1710170610.040 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:23:30.040Z,1710170610.040 [Default:CheckIn] Stopped 2024-03-11T15:23:30.040Z,1710170610.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:23:30.040Z,1710170610.040 [Default:CheckIn](INFO): Running loop #4 2024-03-11T15:23:30.040Z,1710170610.040 [Default:CheckIn] Running Loop=4 2024-03-11T15:23:30.041Z,1710170610.041 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:23:30.041Z,1710170610.041 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:23:32.051Z,1710170612.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152330.00,A,3648.14946,N,12147.22505,W,0.097,16.43,110324,,,D*42 2024-03-11T15:23:32.063Z,1710170612.063 [NAL9602](INFO): GPS fix at 20240311T152330: (36.802491, -121.787084) 2024-03-11T15:23:32.074Z,1710170612.074 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:23:32.074Z,1710170612.074 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:23:39.340Z,1710170619.340 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0013.lzma 2024-03-11T15:23:40.342Z,1710170620.342 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0013.lzma.bak 2024-03-11T15:23:40.342Z,1710170620.342 [DataOverHttps](INFO): SBD MOMSN=19310576 2024-03-11T15:23:51.443Z,1710170631.443 [NAL9602](INFO): SBD MO Status=2, MOMSN=47344, MT Status=2, MTMSN=0 2024-03-11T15:23:51.443Z,1710170631.443 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T15:23:56.312Z,1710170636.312 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240311T145043/Express0014.lzma 2024-03-11T15:23:57.314Z,1710170637.314 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0014.lzma.bak 2024-03-11T15:23:57.314Z,1710170637.314 [DataOverHttps](INFO): SBD MOMSN=19310579 2024-03-11T15:23:58.328Z,1710170638.328 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:23:58.328Z,1710170638.328 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:23:58.328Z,1710170638.328 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:24:09.623Z,1710170649.623 [NAL9602](INFO): SBD MO Status=2, MOMSN=47344, MT Status=2, MTMSN=0 2024-03-11T15:24:09.623Z,1710170649.623 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T15:24:34.679Z,1710170674.679 [NAL9602](INFO): SBD MO Status=2, MOMSN=47344, MT Status=2, MTMSN=0 2024-03-11T15:24:34.679Z,1710170674.679 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T15:24:43.965Z,1710170683.965 [NAL9602](INFO): SBD MO Status=0, MOMSN=47344, MT Status=0, MTMSN=0 2024-03-11T15:24:43.965Z,1710170683.965 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:25:14.669Z,1710170714.669 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:25:36.926Z,1710170736.926 [DataOverHttps](IMPORTANT): SBD MTMSN=20240311T152536 2024-03-11T15:25:44.529Z,1710170744.529 [DataOverHttps](INFO): Received command: ibit 2024-03-11T15:25:44.593Z,1710170744.593 [CommandExec](IMPORTANT): got command ibit 2024-03-11T15:25:44.607Z,1710170744.607 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-03-11T15:25:44.607Z,1710170744.607 [IBIT](IMPORTANT): Beginning control surface checks. 2024-03-11T15:25:44.615Z,1710170744.615 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-11T15:25:46.179Z,1710170746.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152544.00,A,3648.14929,N,12147.22491,W,0.078,16.43,110324,,,D*43 2024-03-11T15:25:46.181Z,1710170746.181 [NAL9602](INFO): GPS fix at 20240311T152544: (36.802488, -121.787082) 2024-03-11T15:25:47.489Z,1710170747.489 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-03-11T15:25:55.518Z,1710170755.518 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000947 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002117 CHAN A3 (5V): -0.001285 CHAN B0 (3.3V): -0.000157 CHAN B1 (3.15aV): 0.000007 CHAN B2 (3.15bV): -0.000281 CHAN B3 (GND): 0.000327 OPEN: -0.000464 Full Scale: +/- 1 mA 2024-03-11T15:26:01.939Z,1710170761.939 [NAL9602](INFO): SBD MO Status=0, MOMSN=47345, MT Status=0, MTMSN=0 2024-03-11T15:26:01.939Z,1710170761.939 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:26:03.146Z,1710170763.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152601.00,A,3648.14894,N,12147.22431,W,0.058,16.43,110324,,,D*4E 2024-03-11T15:26:03.148Z,1710170763.148 [NAL9602](INFO): GPS fix at 20240311T152601: (36.802482, -121.787072) 2024-03-11T15:26:05.976Z,1710170765.976 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152604.00,A,3648.14895,N,12147.22437,W,0.039,16.43,110324,,,D*4B 2024-03-11T15:26:05.978Z,1710170765.978 [NAL9602](INFO): GPS fix at 20240311T152604: (36.802482, -121.787073) 2024-03-11T15:26:08.807Z,1710170768.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152607.00,A,3648.14895,N,12147.22440,W,0.019,16.43,110324,,,D*4A 2024-03-11T15:26:08.810Z,1710170768.810 [NAL9602](INFO): GPS fix at 20240311T152607: (36.802482, -121.787073) 2024-03-11T15:26:12.036Z,1710170772.036 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152610.00,A,3648.14893,N,12147.22440,W,0.097,16.43,110324,,,D*4C 2024-03-11T15:26:12.038Z,1710170772.038 [NAL9602](INFO): GPS fix at 20240311T152610: (36.802482, -121.787073) 2024-03-11T15:26:14.868Z,1710170774.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152613.00,A,3648.14896,N,12147.22445,W,0.058,16.43,110324,,,D*4C 2024-03-11T15:26:14.870Z,1710170774.870 [NAL9602](INFO): GPS fix at 20240311T152613: (36.802483, -121.787074) 2024-03-11T15:26:18.095Z,1710170778.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152616.00,A,3648.14890,N,12147.22431,W,0.019,16.43,110324,,,D*49 2024-03-11T15:26:18.098Z,1710170778.098 [NAL9602](INFO): GPS fix at 20240311T152616: (36.802482, -121.787072) 2024-03-11T15:26:20.928Z,1710170780.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152619.00,A,3648.14877,N,12147.22404,W,0.136,16.43,110324,,,D*45 2024-03-11T15:26:20.938Z,1710170780.938 [NAL9602](INFO): GPS fix at 20240311T152619: (36.802479, -121.787067) 2024-03-11T15:26:23.751Z,1710170783.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152622.00,A,3648.14869,N,12147.22380,W,0.117,16.43,110324,,,D*4A 2024-03-11T15:26:23.754Z,1710170783.754 [NAL9602](INFO): GPS fix at 20240311T152622: (36.802478, -121.787063) 2024-03-11T15:26:26.988Z,1710170786.988 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152625.00,A,3648.14872,N,12147.22369,W,0.039,16.43,110324,,,D*4D 2024-03-11T15:26:26.990Z,1710170786.990 [NAL9602](INFO): GPS fix at 20240311T152625: (36.802479, -121.787061) 2024-03-11T15:26:29.815Z,1710170789.815 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152628.00,A,3648.14873,N,12147.22367,W,0.136,16.43,110324,,,D*41 2024-03-11T15:26:29.818Z,1710170789.818 [NAL9602](INFO): GPS fix at 20240311T152628: (36.802479, -121.787061) 2024-03-11T15:26:30.639Z,1710170790.639 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 5 Latitude: 36.802479 Longitude: -121.787064 2024-03-11T15:26:31.052Z,1710170791.052 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.690001 2024-03-11T15:26:31.052Z,1710170791.052 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-03-11T15:26:31.052Z,1710170791.052 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-03-11T15:26:31.462Z,1710170791.462 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-03-11T15:26:31.462Z,1710170791.462 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-03-11T15:26:31.462Z,1710170791.462 [IBIT](IMPORTANT): Pressure:9.695540 PSI 2024-03-11T15:26:31.463Z,1710170791.463 [IBIT](IMPORTANT): Humidity:12.745696 % 2024-03-11T15:26:31.881Z,1710170791.881 [IBIT](IMPORTANT): Vehicle Pitch:-1.200293 degrees 2024-03-11T15:26:31.881Z,1710170791.881 [IBIT](IMPORTANT): Vehicle Roll:1.675597 degrees 2024-03-11T15:26:31.882Z,1710170791.882 [IBIT](IMPORTANT): Vehicle Heading:33.978912 degrees 2024-03-11T15:26:32.270Z,1710170792.270 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-03-11T15:26:32.270Z,1710170792.270 [IBIT](IMPORTANT): buoyancyNeutral: 275.678955 cc 2024-03-11T15:26:32.270Z,1710170792.270 [IBIT](IMPORTANT): massDefault: -1.205487 cm 2024-03-11T15:26:32.271Z,1710170792.271 [IBIT](IMPORTANT): stopDepth: 250.000000 m 2024-03-11T15:26:32.271Z,1710170792.271 [IBIT](IMPORTANT): abortDepth: 270.000000 m 2024-03-11T15:26:32.271Z,1710170792.271 [IBIT](IMPORTANT): IBIT PASSED 2024-03-11T15:27:02.542Z,1710170822.542 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:28:58.928Z,1710170938.928 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:28:58.928Z,1710170938.928 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:28:58.928Z,1710170938.928 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:28:58.928Z,1710170938.928 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:28:59.319Z,1710170939.319 [Default:CheckIn:D] Stopped 2024-03-11T15:28:59.320Z,1710170939.320 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:28:59.728Z,1710170939.728 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.772689 min 2024-03-11T15:28:59.728Z,1710170939.728 [Default:CheckIn:E] Stopped 2024-03-11T15:28:59.728Z,1710170939.728 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:28:59.728Z,1710170939.728 [Default:CheckIn] Stopped 2024-03-11T15:28:59.728Z,1710170939.728 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:28:59.728Z,1710170939.728 [Default:CheckIn](INFO): Running loop #5 2024-03-11T15:28:59.729Z,1710170939.729 [Default:CheckIn] Running Loop=5 2024-03-11T15:28:59.729Z,1710170939.729 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:28:59.729Z,1710170939.729 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:29:01.735Z,1710170941.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152900.00,A,3648.14821,N,12147.22385,W,0.078,16.43,110324,,,D*44 2024-03-11T15:29:01.737Z,1710170941.737 [NAL9602](INFO): GPS fix at 20240311T152900: (36.802470, -121.787064) 2024-03-11T15:29:01.770Z,1710170941.770 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:29:01.770Z,1710170941.770 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:29:09.296Z,1710170949.296 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0016.lzma 2024-03-11T15:29:10.298Z,1710170950.298 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0016.lzma.bak 2024-03-11T15:29:10.298Z,1710170950.298 [DataOverHttps](INFO): SBD MOMSN=19310586 2024-03-11T15:29:21.537Z,1710170961.537 [NAL9602](INFO): SBD MO Status=0, MOMSN=47346, MT Status=0, MTMSN=0 2024-03-11T15:29:21.537Z,1710170961.537 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:29:26.349Z,1710170966.349 [DataOverHttps](INFO): Sending 822 bytes from file Logs/20240311T145043/Express0017.lzma 2024-03-11T15:29:27.350Z,1710170967.350 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0017.lzma.bak 2024-03-11T15:29:27.350Z,1710170967.350 [DataOverHttps](INFO): SBD MOMSN=19310589 2024-03-11T15:29:43.060Z,1710170983.060 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240311T145043/Express0020.lzma 2024-03-11T15:29:44.062Z,1710170984.062 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0020.lzma.bak 2024-03-11T15:29:44.062Z,1710170984.062 [DataOverHttps](INFO): SBD MOMSN=19310616 2024-03-11T15:29:45.374Z,1710170985.374 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:29:45.374Z,1710170985.374 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:29:45.374Z,1710170985.374 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:29:52.233Z,1710170992.233 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:31:53.630Z,1710171113.630 [DataOverHttps](IMPORTANT): SBD MTMSN=20240311T153152 2024-03-11T15:32:00.940Z,1710171120.940 [DataOverHttps](INFO): Received command: failc 2024-03-11T15:32:00.977Z,1710171120.977 [CommandExec](IMPORTANT): got command failComponent 2024-03-11T15:32:00.977Z,1710171120.977 [CommandExec](IMPORTANT): Failed components: 2024-03-11T15:32:00.977Z,1710171120.977 [CommandExec](IMPORTANT): No failed Components. 2024-03-11T15:34:46.052Z,1710171286.052 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:34:46.052Z,1710171286.052 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:34:46.052Z,1710171286.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:34:46.052Z,1710171286.052 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:34:46.455Z,1710171286.455 [Default:CheckIn:D] Stopped 2024-03-11T15:34:46.455Z,1710171286.455 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:34:46.855Z,1710171286.855 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.558289 min 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn:E] Stopped 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn] Stopped 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn](INFO): Running loop #6 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn] Running Loop=6 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:34:46.856Z,1710171286.856 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:34:48.866Z,1710171288.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153448.00,A,3648.14883,N,12147.22362,W,0.097,16.43,110324,,,D*44 2024-03-11T15:34:48.869Z,1710171288.869 [NAL9602](INFO): GPS fix at 20240311T153448: (36.802481, -121.787060) 2024-03-11T15:34:48.913Z,1710171288.913 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:34:48.913Z,1710171288.913 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:34:58.660Z,1710171298.660 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0022.lzma 2024-03-11T15:34:59.662Z,1710171299.662 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0022.lzma.bak 2024-03-11T15:34:59.662Z,1710171299.662 [DataOverHttps](INFO): SBD MOMSN=19310620 2024-03-11T15:35:05.679Z,1710171305.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=47347, MT Status=0, MTMSN=0 2024-03-11T15:35:05.679Z,1710171305.679 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:35:15.352Z,1710171315.352 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240311T145043/Courier0026.lzma 2024-03-11T15:35:16.354Z,1710171316.354 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0026.lzma.bak 2024-03-11T15:35:16.354Z,1710171316.354 [DataOverHttps](INFO): SBD MOMSN=19310624 2024-03-11T15:35:32.336Z,1710171332.336 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20240311T145043/Express0023.lzma 2024-03-11T15:35:33.338Z,1710171333.338 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0023.lzma.bak 2024-03-11T15:35:33.338Z,1710171333.338 [DataOverHttps](INFO): SBD MOMSN=19310626 2024-03-11T15:35:34.374Z,1710171334.374 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:35:34.374Z,1710171334.374 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:35:34.375Z,1710171334.375 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:35:36.395Z,1710171336.395 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:40:34.962Z,1710171634.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:40:34.962Z,1710171634.962 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:40:34.962Z,1710171634.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:40:34.962Z,1710171634.962 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:40:35.351Z,1710171635.351 [Default:CheckIn:D] Stopped 2024-03-11T15:40:35.351Z,1710171635.351 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:40:35.760Z,1710171635.760 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.373206 min 2024-03-11T15:40:35.760Z,1710171635.760 [Default:CheckIn:E] Stopped 2024-03-11T15:40:35.760Z,1710171635.760 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:40:35.760Z,1710171635.760 [Default:CheckIn] Stopped 2024-03-11T15:40:35.760Z,1710171635.760 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:40:35.761Z,1710171635.761 [Default:CheckIn](INFO): Running loop #7 2024-03-11T15:40:35.761Z,1710171635.761 [Default:CheckIn] Running Loop=7 2024-03-11T15:40:35.761Z,1710171635.761 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:40:35.761Z,1710171635.761 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:40:37.775Z,1710171637.775 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154037.00,A,3648.14864,N,12147.22341,W,0.058,16.43,110324,,,D*44 2024-03-11T15:40:37.778Z,1710171637.778 [NAL9602](INFO): GPS fix at 20240311T154037: (36.802477, -121.787057) 2024-03-11T15:40:37.788Z,1710171637.788 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:40:37.788Z,1710171637.788 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:40:45.044Z,1710171645.044 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0026.lzma 2024-03-11T15:40:46.046Z,1710171646.046 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0026.lzma.bak 2024-03-11T15:40:46.046Z,1710171646.046 [DataOverHttps](INFO): SBD MOMSN=19310624 2024-03-11T15:40:56.011Z,1710171656.011 [NAL9602](INFO): SBD MO Status=0, MOMSN=47348, MT Status=0, MTMSN=0 2024-03-11T15:40:56.011Z,1710171656.011 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:41:04.708Z,1710171664.708 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0027.lzma 2024-03-11T15:41:05.715Z,1710171665.715 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0027.lzma.bak 2024-03-11T15:41:05.715Z,1710171665.715 [DataOverHttps](INFO): SBD MOMSN=19310635 2024-03-11T15:41:06.933Z,1710171666.933 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:41:06.933Z,1710171666.933 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:41:06.933Z,1710171666.933 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:41:26.714Z,1710171686.714 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:46:07.505Z,1710171967.505 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:46:07.506Z,1710171967.506 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:46:07.506Z,1710171967.506 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:46:07.506Z,1710171967.506 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:46:07.920Z,1710171967.920 [Default:CheckIn:D] Stopped 2024-03-11T15:46:07.920Z,1710171967.920 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:46:08.320Z,1710171968.320 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.916032 min 2024-03-11T15:46:08.344Z,1710171968.344 [Default:CheckIn:E] Stopped 2024-03-11T15:46:08.344Z,1710171968.344 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:46:08.344Z,1710171968.344 [Default:CheckIn] Stopped 2024-03-11T15:46:08.345Z,1710171968.345 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:46:08.345Z,1710171968.345 [Default:CheckIn](INFO): Running loop #8 2024-03-11T15:46:08.345Z,1710171968.345 [Default:CheckIn] Running Loop=8 2024-03-11T15:46:08.345Z,1710171968.345 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:46:08.345Z,1710171968.345 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:46:10.322Z,1710171970.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154609.00,A,3648.14915,N,12147.22428,W,0.136,16.43,110324,,,D*49 2024-03-11T15:46:10.325Z,1710171970.325 [NAL9602](INFO): GPS fix at 20240311T154609: (36.802486, -121.787071) 2024-03-11T15:46:10.335Z,1710171970.335 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:46:10.335Z,1710171970.335 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:46:18.212Z,1710171978.212 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0031.lzma 2024-03-11T15:46:19.214Z,1710171979.214 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0031.lzma.bak 2024-03-11T15:46:19.214Z,1710171979.214 [DataOverHttps](INFO): SBD MOMSN=19310639 2024-03-11T15:46:20.426Z,1710171980.426 [NAL9602](INFO): SBD MO Status=0, MOMSN=47349, MT Status=0, MTMSN=0 2024-03-11T15:46:20.426Z,1710171980.426 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:46:37.844Z,1710171997.844 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20240311T145043/Express0032.lzma 2024-03-11T15:46:38.846Z,1710171998.846 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0032.lzma.bak 2024-03-11T15:46:38.846Z,1710171998.846 [DataOverHttps](INFO): SBD MOMSN=19310643 2024-03-11T15:46:39.890Z,1710171999.890 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:46:39.890Z,1710171999.890 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:46:39.890Z,1710171999.890 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:46:51.173Z,1710172011.173 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:51:40.524Z,1710172300.524 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:51:40.524Z,1710172300.524 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:51:40.524Z,1710172300.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:51:40.524Z,1710172300.524 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:51:40.914Z,1710172300.914 [Default:CheckIn:D] Stopped 2024-03-11T15:51:40.914Z,1710172300.914 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:51:41.324Z,1710172301.324 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.465934 min 2024-03-11T15:51:41.324Z,1710172301.324 [Default:CheckIn:E] Stopped 2024-03-11T15:51:41.324Z,1710172301.324 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:51:41.324Z,1710172301.324 [Default:CheckIn] Stopped 2024-03-11T15:51:41.324Z,1710172301.324 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:51:41.325Z,1710172301.325 [Default:CheckIn](INFO): Running loop #9 2024-03-11T15:51:41.325Z,1710172301.325 [Default:CheckIn] Running Loop=9 2024-03-11T15:51:41.325Z,1710172301.325 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:51:41.325Z,1710172301.325 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:51:43.335Z,1710172303.335 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155142.00,A,3648.14765,N,12147.22293,W,0.039,16.43,110324,,,D*41 2024-03-11T15:51:43.338Z,1710172303.338 [NAL9602](INFO): GPS fix at 20240311T155142: (36.802461, -121.787049) 2024-03-11T15:51:43.348Z,1710172303.348 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:51:43.348Z,1710172303.348 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:51:50.612Z,1710172310.612 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0034.lzma 2024-03-11T15:51:51.614Z,1710172311.614 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0034.lzma.bak 2024-03-11T15:51:51.614Z,1710172311.614 [DataOverHttps](INFO): SBD MOMSN=19310646 2024-03-11T15:52:07.556Z,1710172327.556 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240311T145043/Express0035.lzma 2024-03-11T15:52:08.558Z,1710172328.558 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0035.lzma.bak 2024-03-11T15:52:08.558Z,1710172328.558 [DataOverHttps](INFO): SBD MOMSN=19310649 2024-03-11T15:52:09.645Z,1710172329.645 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:52:09.645Z,1710172329.645 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:52:09.645Z,1710172329.645 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:52:14.067Z,1710172334.067 [NAL9602](INFO): SBD MO Status=2, MOMSN=47350, MT Status=2, MTMSN=0 2024-03-11T15:52:14.067Z,1710172334.067 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T15:52:33.055Z,1710172353.055 [NAL9602](INFO): SBD MO Status=0, MOMSN=47350, MT Status=0, MTMSN=0 2024-03-11T15:52:33.055Z,1710172353.055 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:53:03.765Z,1710172383.765 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T15:56:48.024Z,1710172608.024 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T15:57:00.550Z,1710172620.550 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T15:57:10.259Z,1710172630.259 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T15:57:10.259Z,1710172630.259 [Default:CheckIn:C.Wait] Stopped 2024-03-11T15:57:10.259Z,1710172630.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T15:57:10.260Z,1710172630.260 [Default:CheckIn:D] Running Loop=1 2024-03-11T15:57:10.671Z,1710172630.671 [Default:CheckIn:D] Stopped 2024-03-11T15:57:10.672Z,1710172630.672 [Default:CheckIn:E] Running Loop=1 2024-03-11T15:57:11.073Z,1710172631.073 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.961890 min 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn:E] Stopped 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn] Stopped 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn](INFO): Running loop #10 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn] Running Loop=10 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T15:57:11.074Z,1710172631.074 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T15:57:13.079Z,1710172633.079 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155712.00,A,3648.14920,N,12147.22368,W,0.039,0.00,110324,,,D*78 2024-03-11T15:57:13.082Z,1710172633.082 [NAL9602](INFO): GPS fix at 20240311T155712: (36.802487, -121.787061) 2024-03-11T15:57:13.107Z,1710172633.107 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T15:57:13.107Z,1710172633.107 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T15:57:13.885Z,1710172633.885 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T15:57:20.185Z,1710172640.185 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0037.lzma 2024-03-11T15:57:21.186Z,1710172641.186 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0037.lzma.bak 2024-03-11T15:57:21.187Z,1710172641.187 [DataOverHttps](INFO): SBD MOMSN=19310653 2024-03-11T15:57:26.020Z,1710172646.020 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T15:57:37.088Z,1710172657.088 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240311T145043/Express0038.lzma 2024-03-11T15:57:38.090Z,1710172658.090 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0038.lzma.bak 2024-03-11T15:57:38.090Z,1710172658.090 [DataOverHttps](INFO): SBD MOMSN=19310656 2024-03-11T15:57:38.543Z,1710172658.543 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T15:57:39.398Z,1710172659.398 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T15:57:39.399Z,1710172659.399 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T15:57:39.399Z,1710172659.399 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T15:57:51.877Z,1710172671.877 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T15:58:04.803Z,1710172684.803 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T15:58:54.928Z,1710172734.928 [NAL9602](INFO): SBD MO Status=0, MOMSN=47351, MT Status=0, MTMSN=0 2024-03-11T15:58:54.928Z,1710172734.928 [NAL9602](INFO): No messages in MT queue 2024-03-11T15:59:25.649Z,1710172765.649 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:00:39.573Z,1710172839.573 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T16:00:39.579Z,1710172839.579 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-03-11T16:00:39.582Z,1710172839.582 [BPC1](INFO): Received data from all battery sticks. 2024-03-11T16:02:40.011Z,1710172960.011 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:02:40.011Z,1710172960.011 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:02:40.011Z,1710172960.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:02:40.011Z,1710172960.011 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:02:40.416Z,1710172960.416 [Default:CheckIn:D] Stopped 2024-03-11T16:02:40.416Z,1710172960.416 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:02:40.826Z,1710172960.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.457625 min 2024-03-11T16:02:40.826Z,1710172960.826 [Default:CheckIn:E] Stopped 2024-03-11T16:02:40.827Z,1710172960.827 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:02:40.827Z,1710172960.827 [Default:CheckIn] Stopped 2024-03-11T16:02:40.827Z,1710172960.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:02:40.827Z,1710172960.827 [Default:CheckIn](INFO): Running loop #11 2024-03-11T16:02:40.827Z,1710172960.827 [Default:CheckIn] Running Loop=11 2024-03-11T16:02:40.827Z,1710172960.827 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:02:40.827Z,1710172960.827 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:02:42.831Z,1710172962.831 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160242.00,A,3648.14869,N,12147.22367,W,0.058,0.00,110324,,,D*7A 2024-03-11T16:02:42.833Z,1710172962.833 [NAL9602](INFO): GPS fix at 20240311T160242: (36.802478, -121.787061) 2024-03-11T16:02:42.844Z,1710172962.844 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:02:42.844Z,1710172962.844 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:02:51.428Z,1710172971.428 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240311T145043/Courier0040.lzma 2024-03-11T16:02:52.430Z,1710172972.430 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0040.lzma.bak 2024-03-11T16:02:52.430Z,1710172972.430 [DataOverHttps](INFO): SBD MOMSN=19310660 2024-03-11T16:03:09.908Z,1710172989.908 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240311T145043/Express0041.lzma 2024-03-11T16:03:10.910Z,1710172990.910 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0041.lzma.bak 2024-03-11T16:03:10.910Z,1710172990.910 [DataOverHttps](INFO): SBD MOMSN=19310663 2024-03-11T16:03:11.555Z,1710172991.555 [NAL9602](INFO): SBD MO Status=0, MOMSN=47352, MT Status=0, MTMSN=0 2024-03-11T16:03:11.555Z,1710172991.555 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:03:11.970Z,1710172991.970 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:03:11.970Z,1710172991.970 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:03:11.970Z,1710172991.970 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:03:42.252Z,1710173022.252 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:08:12.694Z,1710173292.694 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:08:12.694Z,1710173292.694 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:08:12.695Z,1710173292.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:08:12.695Z,1710173292.695 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:08:13.105Z,1710173293.105 [Default:CheckIn:D] Stopped 2024-03-11T16:08:13.105Z,1710173293.105 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:08:13.530Z,1710173293.530 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.002450 min 2024-03-11T16:08:13.530Z,1710173293.530 [Default:CheckIn:E] Stopped 2024-03-11T16:08:13.530Z,1710173293.530 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:08:13.530Z,1710173293.530 [Default:CheckIn] Stopped 2024-03-11T16:08:13.530Z,1710173293.530 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:08:13.530Z,1710173293.530 [Default:CheckIn](INFO): Running loop #12 2024-03-11T16:08:13.531Z,1710173293.531 [Default:CheckIn] Running Loop=12 2024-03-11T16:08:13.531Z,1710173293.531 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:08:13.531Z,1710173293.531 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:08:15.516Z,1710173295.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160814.00,A,3648.14843,N,12147.22301,W,0.097,0.00,110324,,,D*78 2024-03-11T16:08:15.518Z,1710173295.518 [NAL9602](INFO): GPS fix at 20240311T160814: (36.802474, -121.787050) 2024-03-11T16:08:15.529Z,1710173295.529 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:08:15.529Z,1710173295.529 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:08:26.036Z,1710173306.036 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0043.lzma 2024-03-11T16:08:27.038Z,1710173307.038 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0043.lzma.bak 2024-03-11T16:08:27.039Z,1710173307.039 [DataOverHttps](INFO): SBD MOMSN=19310667 2024-03-11T16:08:43.012Z,1710173323.012 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0044.lzma 2024-03-11T16:08:44.014Z,1710173324.014 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0044.lzma.bak 2024-03-11T16:08:44.014Z,1710173324.014 [DataOverHttps](INFO): SBD MOMSN=19310670 2024-03-11T16:08:45.072Z,1710173325.072 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:08:45.072Z,1710173325.072 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:08:45.072Z,1710173325.072 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:08:54.808Z,1710173334.808 [NAL9602](INFO): SBD MO Status=0, MOMSN=47353, MT Status=0, MTMSN=0 2024-03-11T16:08:54.808Z,1710173334.808 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:09:25.510Z,1710173365.510 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:13:45.536Z,1710173625.536 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:13:45.536Z,1710173625.536 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:13:45.536Z,1710173625.536 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:13:45.536Z,1710173625.536 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:13:45.931Z,1710173625.931 [Default:CheckIn:D] Stopped 2024-03-11T16:13:45.931Z,1710173625.931 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:13:46.336Z,1710173626.336 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.549552 min 2024-03-11T16:13:46.336Z,1710173626.336 [Default:CheckIn:E] Stopped 2024-03-11T16:13:46.336Z,1710173626.336 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:13:46.336Z,1710173626.336 [Default:CheckIn] Stopped 2024-03-11T16:13:46.336Z,1710173626.336 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:13:46.336Z,1710173626.336 [Default:CheckIn](INFO): Running loop #13 2024-03-11T16:13:46.337Z,1710173626.337 [Default:CheckIn] Running Loop=13 2024-03-11T16:13:46.337Z,1710173626.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:13:46.337Z,1710173626.337 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:13:48.344Z,1710173628.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161347.00,A,3648.14964,N,12147.22436,W,0.019,0.00,110324,,,D*75 2024-03-11T16:13:48.346Z,1710173628.346 [NAL9602](INFO): GPS fix at 20240311T161347: (36.802494, -121.787073) 2024-03-11T16:13:48.380Z,1710173628.380 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:13:48.380Z,1710173628.380 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:13:55.724Z,1710173635.724 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0046.lzma 2024-03-11T16:13:56.726Z,1710173636.726 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0046.lzma.bak 2024-03-11T16:13:56.726Z,1710173636.726 [DataOverHttps](INFO): SBD MOMSN=19310674 2024-03-11T16:14:06.119Z,1710173646.119 [NAL9602](INFO): SBD MO Status=0, MOMSN=47354, MT Status=0, MTMSN=0 2024-03-11T16:14:06.119Z,1710173646.119 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:14:12.556Z,1710173652.556 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240311T145043/Express0047.lzma 2024-03-11T16:14:13.558Z,1710173653.558 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0047.lzma.bak 2024-03-11T16:14:13.558Z,1710173653.558 [DataOverHttps](INFO): SBD MOMSN=19310677 2024-03-11T16:14:14.615Z,1710173654.615 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:14:14.615Z,1710173654.615 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:14:14.615Z,1710173654.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:14:36.853Z,1710173676.853 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:19:15.082Z,1710173955.082 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:19:15.082Z,1710173955.082 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:19:15.082Z,1710173955.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:19:15.082Z,1710173955.082 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:19:15.483Z,1710173955.483 [Default:CheckIn:D] Stopped 2024-03-11T16:19:15.483Z,1710173955.483 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:19:15.888Z,1710173955.888 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.042090 min 2024-03-11T16:19:15.888Z,1710173955.888 [Default:CheckIn:E] Stopped 2024-03-11T16:19:15.888Z,1710173955.888 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:19:15.888Z,1710173955.888 [Default:CheckIn] Stopped 2024-03-11T16:19:15.889Z,1710173955.889 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:19:15.889Z,1710173955.889 [Default:CheckIn](INFO): Running loop #14 2024-03-11T16:19:15.889Z,1710173955.889 [Default:CheckIn] Running Loop=14 2024-03-11T16:19:15.889Z,1710173955.889 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:19:15.889Z,1710173955.889 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:19:17.896Z,1710173957.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161917.00,A,3648.14866,N,12147.22338,W,0.058,0.00,110324,,,D*75 2024-03-11T16:19:17.898Z,1710173957.898 [NAL9602](INFO): GPS fix at 20240311T161917: (36.802478, -121.787056) 2024-03-11T16:19:17.909Z,1710173957.909 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:19:17.909Z,1710173957.909 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:19:25.212Z,1710173965.212 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0049.lzma 2024-03-11T16:19:26.215Z,1710173966.215 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0049.lzma.bak 2024-03-11T16:19:26.215Z,1710173966.215 [DataOverHttps](INFO): SBD MOMSN=19310681 2024-03-11T16:19:42.152Z,1710173982.152 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0050.lzma 2024-03-11T16:19:43.154Z,1710173983.154 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0050.lzma.bak 2024-03-11T16:19:43.154Z,1710173983.154 [DataOverHttps](INFO): SBD MOMSN=19310684 2024-03-11T16:19:44.185Z,1710173984.185 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:19:44.185Z,1710173984.185 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:19:44.185Z,1710173984.185 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:19:46.583Z,1710173986.583 [NAL9602](INFO): SBD MO Status=0, MOMSN=47355, MT Status=0, MTMSN=0 2024-03-11T16:19:46.583Z,1710173986.583 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:20:17.290Z,1710174017.290 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:24:44.625Z,1710174284.625 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:24:44.625Z,1710174284.625 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:24:44.625Z,1710174284.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:24:44.625Z,1710174284.625 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:24:45.043Z,1710174285.043 [Default:CheckIn:D] Stopped 2024-03-11T16:24:45.043Z,1710174285.043 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:24:45.428Z,1710174285.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.534741 min 2024-03-11T16:24:45.428Z,1710174285.428 [Default:CheckIn:E] Stopped 2024-03-11T16:24:45.429Z,1710174285.429 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:24:45.429Z,1710174285.429 [Default:CheckIn] Stopped 2024-03-11T16:24:45.429Z,1710174285.429 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:24:45.429Z,1710174285.429 [Default:CheckIn](INFO): Running loop #15 2024-03-11T16:24:45.429Z,1710174285.429 [Default:CheckIn] Running Loop=15 2024-03-11T16:24:45.429Z,1710174285.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:24:45.429Z,1710174285.429 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:24:47.455Z,1710174287.455 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162446.00,A,3648.14929,N,12147.22392,W,0.058,0.00,110324,,,D*75 2024-03-11T16:24:47.457Z,1710174287.457 [NAL9602](INFO): GPS fix at 20240311T162446: (36.802488, -121.787065) 2024-03-11T16:24:47.483Z,1710174287.483 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:24:47.483Z,1710174287.483 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:24:58.140Z,1710174298.140 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0052.lzma 2024-03-11T16:24:59.142Z,1710174299.142 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0052.lzma.bak 2024-03-11T16:24:59.143Z,1710174299.143 [DataOverHttps](INFO): SBD MOMSN=19310688 2024-03-11T16:25:15.076Z,1710174315.076 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240311T145043/Express0053.lzma 2024-03-11T16:25:16.078Z,1710174316.078 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0053.lzma.bak 2024-03-11T16:25:16.078Z,1710174316.078 [DataOverHttps](INFO): SBD MOMSN=19310691 2024-03-11T16:25:17.405Z,1710174317.405 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:25:17.405Z,1710174317.405 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:25:17.405Z,1710174317.405 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:26:12.339Z,1710174372.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=47356, MT Status=2, MTMSN=0 2024-03-11T16:26:12.339Z,1710174372.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T16:26:39.475Z,1710174399.475 [NAL9602](INFO): SBD MO Status=0, MOMSN=47356, MT Status=0, MTMSN=0 2024-03-11T16:26:39.475Z,1710174399.475 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:27:10.229Z,1710174430.229 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:30:17.815Z,1710174617.815 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:30:17.815Z,1710174617.815 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:30:17.815Z,1710174617.815 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:30:17.816Z,1710174617.816 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:30:18.229Z,1710174618.229 [Default:CheckIn:D] Stopped 2024-03-11T16:30:18.229Z,1710174618.229 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:30:18.632Z,1710174618.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.087842 min 2024-03-11T16:30:18.632Z,1710174618.632 [Default:CheckIn:E] Stopped 2024-03-11T16:30:18.632Z,1710174618.632 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:30:18.636Z,1710174618.636 [Default:CheckIn] Stopped 2024-03-11T16:30:18.636Z,1710174618.636 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:30:18.637Z,1710174618.637 [Default:CheckIn](INFO): Running loop #16 2024-03-11T16:30:18.639Z,1710174618.639 [Default:CheckIn] Running Loop=16 2024-03-11T16:30:18.639Z,1710174618.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:30:18.639Z,1710174618.639 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:30:20.632Z,1710174620.632 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163019.00,A,3648.14876,N,12147.22459,W,0.078,0.00,110324,,,D*73 2024-03-11T16:30:20.634Z,1710174620.634 [NAL9602](INFO): GPS fix at 20240311T163019: (36.802479, -121.787076) 2024-03-11T16:30:20.652Z,1710174620.652 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:30:20.652Z,1710174620.652 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:30:30.436Z,1710174630.436 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240311T145043/Courier0055.lzma 2024-03-11T16:30:31.438Z,1710174631.438 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0055.lzma.bak 2024-03-11T16:30:31.438Z,1710174631.438 [DataOverHttps](INFO): SBD MOMSN=19310697 2024-03-11T16:30:33.627Z,1710174633.627 [NAL9602](INFO): SBD MO Status=0, MOMSN=47357, MT Status=0, MTMSN=0 2024-03-11T16:30:33.627Z,1710174633.627 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:30:47.292Z,1710174647.292 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240311T145043/Express0056.lzma 2024-03-11T16:30:48.294Z,1710174648.294 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0056.lzma.bak 2024-03-11T16:30:48.295Z,1710174648.295 [DataOverHttps](INFO): SBD MOMSN=19310700 2024-03-11T16:30:49.392Z,1710174649.392 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:30:49.392Z,1710174649.392 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:30:49.392Z,1710174649.392 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:31:04.393Z,1710174664.393 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:35:50.169Z,1710174950.169 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:35:50.169Z,1710174950.169 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:35:50.169Z,1710174950.169 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:35:50.169Z,1710174950.169 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:35:50.561Z,1710174950.561 [Default:CheckIn:D] Stopped 2024-03-11T16:35:50.561Z,1710174950.561 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:35:50.964Z,1710174950.964 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.626709 min 2024-03-11T16:35:50.964Z,1710174950.964 [Default:CheckIn:E] Stopped 2024-03-11T16:35:50.964Z,1710174950.964 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:35:50.964Z,1710174950.964 [Default:CheckIn] Stopped 2024-03-11T16:35:50.964Z,1710174950.964 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:35:50.964Z,1710174950.964 [Default:CheckIn](INFO): Running loop #17 2024-03-11T16:35:50.965Z,1710174950.965 [Default:CheckIn] Running Loop=17 2024-03-11T16:35:50.965Z,1710174950.965 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:35:50.965Z,1710174950.965 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:35:52.981Z,1710174952.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163552.00,A,3648.14837,N,12147.22386,W,0.019,0.00,110324,,,D*7E 2024-03-11T16:35:52.983Z,1710174952.983 [NAL9602](INFO): GPS fix at 20240311T163552: (36.802473, -121.787064) 2024-03-11T16:35:52.994Z,1710174952.994 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:35:52.994Z,1710174952.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:35:59.796Z,1710174959.796 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0058.lzma 2024-03-11T16:36:00.799Z,1710174960.799 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0058.lzma.bak 2024-03-11T16:36:00.799Z,1710174960.799 [DataOverHttps](INFO): SBD MOMSN=19310704 2024-03-11T16:36:16.692Z,1710174976.692 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0059.lzma 2024-03-11T16:36:17.216Z,1710174977.216 [NAL9602](INFO): SBD MO Status=0, MOMSN=47358, MT Status=0, MTMSN=0 2024-03-11T16:36:17.217Z,1710174977.217 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:36:17.695Z,1710174977.695 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0059.lzma.bak 2024-03-11T16:36:17.695Z,1710174977.695 [DataOverHttps](INFO): SBD MOMSN=19310707 2024-03-11T16:36:18.843Z,1710174978.843 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:36:18.843Z,1710174978.843 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:36:18.843Z,1710174978.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:36:47.968Z,1710175007.968 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:41:19.605Z,1710175279.605 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:41:19.605Z,1710175279.605 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:41:19.605Z,1710175279.605 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:41:19.606Z,1710175279.606 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:41:20.019Z,1710175280.019 [Default:CheckIn:D] Stopped 2024-03-11T16:41:20.020Z,1710175280.020 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.117692 min 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn:E] Stopped 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn] Stopped 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn](INFO): Running loop #18 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn] Running Loop=18 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:41:20.416Z,1710175280.416 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:41:22.427Z,1710175282.427 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164121.00,A,3648.14767,N,12147.22224,W,0.058,0.00,110324,,,D*7F 2024-03-11T16:41:22.430Z,1710175282.430 [NAL9602](INFO): GPS fix at 20240311T164121: (36.802461, -121.787037) 2024-03-11T16:41:22.440Z,1710175282.440 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:41:22.440Z,1710175282.440 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:41:23.238Z,1710175283.238 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:41:23.238Z,1710175283.238 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:41:23.239Z,1710175283.239 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:41:47.071Z,1710175307.071 [NAL9602](INFO): SBD MO Status=2, MOMSN=47359, MT Status=2, MTMSN=0 2024-03-11T16:41:47.071Z,1710175307.071 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T16:44:21.803Z,1710175461.803 [NAL9602](INFO): SBD MO Status=0, MOMSN=47359, MT Status=0, MTMSN=0 2024-03-11T16:44:21.803Z,1710175461.803 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:44:52.509Z,1710175492.509 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:46:23.822Z,1710175583.822 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:46:23.823Z,1710175583.823 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:46:23.823Z,1710175583.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:46:23.823Z,1710175583.823 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:46:24.236Z,1710175584.236 [Default:CheckIn:D] Stopped 2024-03-11T16:46:24.237Z,1710175584.237 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:46:24.646Z,1710175584.646 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.187964 min 2024-03-11T16:46:24.646Z,1710175584.646 [Default:CheckIn:E] Stopped 2024-03-11T16:46:24.647Z,1710175584.647 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:46:24.647Z,1710175584.647 [Default:CheckIn] Stopped 2024-03-11T16:46:24.647Z,1710175584.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:46:24.647Z,1710175584.647 [Default:CheckIn](INFO): Running loop #19 2024-03-11T16:46:24.647Z,1710175584.647 [Default:CheckIn] Running Loop=19 2024-03-11T16:46:24.647Z,1710175584.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:46:24.647Z,1710175584.647 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:46:26.647Z,1710175586.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164625.00,A,3648.14817,N,12147.22361,W,0.019,0.00,110324,,,D*71 2024-03-11T16:46:26.650Z,1710175586.650 [NAL9602](INFO): GPS fix at 20240311T164625: (36.802470, -121.787060) 2024-03-11T16:46:26.660Z,1710175586.660 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:46:26.660Z,1710175586.660 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:46:33.992Z,1710175593.992 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0061.lzma 2024-03-11T16:46:34.994Z,1710175594.994 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0061.lzma.bak 2024-03-11T16:46:34.994Z,1710175594.994 [DataOverHttps](INFO): SBD MOMSN=19310712 2024-03-11T16:46:38.767Z,1710175598.767 [NAL9602](INFO): SBD MO Status=0, MOMSN=47360, MT Status=0, MTMSN=0 2024-03-11T16:46:38.767Z,1710175598.767 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:46:53.376Z,1710175613.376 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0064.lzma 2024-03-11T16:46:54.378Z,1710175614.378 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0064.lzma.bak 2024-03-11T16:46:54.379Z,1710175614.379 [DataOverHttps](INFO): SBD MOMSN=19310715 2024-03-11T16:47:09.513Z,1710175629.513 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:47:10.668Z,1710175630.668 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0062.lzma 2024-03-11T16:47:11.670Z,1710175631.670 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0062.lzma.bak 2024-03-11T16:47:11.671Z,1710175631.671 [DataOverHttps](INFO): SBD MOMSN=19310719 2024-03-11T16:47:27.584Z,1710175647.584 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0065.lzma 2024-03-11T16:47:28.586Z,1710175648.586 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0065.lzma.bak 2024-03-11T16:47:28.587Z,1710175648.587 [DataOverHttps](INFO): SBD MOMSN=19310722 2024-03-11T16:47:29.724Z,1710175649.724 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:47:29.724Z,1710175649.724 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:47:29.724Z,1710175649.724 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:52:30.325Z,1710175950.325 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:52:30.325Z,1710175950.325 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:52:30.325Z,1710175950.325 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:52:30.325Z,1710175950.325 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:52:30.723Z,1710175950.723 [Default:CheckIn:D] Stopped 2024-03-11T16:52:30.724Z,1710175950.724 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:52:31.123Z,1710175951.123 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.296086 min 2024-03-11T16:52:31.123Z,1710175951.123 [Default:CheckIn:E] Stopped 2024-03-11T16:52:31.123Z,1710175951.123 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:52:31.123Z,1710175951.123 [Default:CheckIn] Stopped 2024-03-11T16:52:31.123Z,1710175951.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:52:31.123Z,1710175951.123 [Default:CheckIn](INFO): Running loop #20 2024-03-11T16:52:31.123Z,1710175951.123 [Default:CheckIn] Running Loop=20 2024-03-11T16:52:31.124Z,1710175951.124 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:52:31.124Z,1710175951.124 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:52:33.136Z,1710175953.136 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165232.00,A,3648.14821,N,12147.22436,W,0.019,0.00,110324,,,D*72 2024-03-11T16:52:33.138Z,1710175953.138 [NAL9602](INFO): GPS fix at 20240311T165232: (36.802470, -121.787073) 2024-03-11T16:52:33.148Z,1710175953.148 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:52:33.148Z,1710175953.148 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:52:40.140Z,1710175960.140 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0067.lzma 2024-03-11T16:52:41.142Z,1710175961.142 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0067.lzma.bak 2024-03-11T16:52:41.142Z,1710175961.142 [DataOverHttps](INFO): SBD MOMSN=19310725 2024-03-11T16:52:59.916Z,1710175979.916 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240311T145043/Express0068.lzma 2024-03-11T16:53:00.918Z,1710175980.918 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0068.lzma.bak 2024-03-11T16:53:00.919Z,1710175980.919 [DataOverHttps](INFO): SBD MOMSN=19310729 2024-03-11T16:53:02.268Z,1710175982.268 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:53:02.268Z,1710175982.268 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:53:02.269Z,1710175982.269 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:53:17.204Z,1710175997.204 [NAL9602](INFO): SBD MO Status=2, MOMSN=47361, MT Status=2, MTMSN=0 2024-03-11T16:53:17.205Z,1710175997.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T16:53:35.383Z,1710176015.383 [NAL9602](INFO): SBD MO Status=0, MOMSN=47361, MT Status=0, MTMSN=0 2024-03-11T16:53:35.383Z,1710176015.383 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:54:06.085Z,1710176046.085 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T16:58:02.865Z,1710176282.865 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T16:58:02.865Z,1710176282.865 [Default:CheckIn:C.Wait] Stopped 2024-03-11T16:58:02.865Z,1710176282.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T16:58:02.865Z,1710176282.865 [Default:CheckIn:D] Running Loop=1 2024-03-11T16:58:03.250Z,1710176283.250 [Default:CheckIn:D] Stopped 2024-03-11T16:58:03.250Z,1710176283.250 [Default:CheckIn:E] Running Loop=1 2024-03-11T16:58:03.660Z,1710176283.660 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.838200 min 2024-03-11T16:58:03.660Z,1710176283.660 [Default:CheckIn:E] Stopped 2024-03-11T16:58:03.660Z,1710176283.660 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T16:58:03.660Z,1710176283.660 [Default:CheckIn] Stopped 2024-03-11T16:58:03.660Z,1710176283.660 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T16:58:03.660Z,1710176283.660 [Default:CheckIn](INFO): Running loop #21 2024-03-11T16:58:03.661Z,1710176283.661 [Default:CheckIn] Running Loop=21 2024-03-11T16:58:03.661Z,1710176283.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T16:58:03.661Z,1710176283.661 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T16:58:05.669Z,1710176285.669 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165804.00,A,3648.14711,N,12147.22361,W,0.058,0.00,110324,,,D*71 2024-03-11T16:58:05.671Z,1710176285.671 [NAL9602](INFO): GPS fix at 20240311T165804: (36.802452, -121.787060) 2024-03-11T16:58:05.682Z,1710176285.682 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T16:58:05.682Z,1710176285.682 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T16:58:13.212Z,1710176293.212 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240311T145043/Courier0070.lzma 2024-03-11T16:58:14.214Z,1710176294.214 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0070.lzma.bak 2024-03-11T16:58:14.214Z,1710176294.214 [DataOverHttps](INFO): SBD MOMSN=19310733 2024-03-11T16:58:21.483Z,1710176301.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=47362, MT Status=0, MTMSN=0 2024-03-11T16:58:21.483Z,1710176301.483 [NAL9602](INFO): No messages in MT queue 2024-03-11T16:58:32.912Z,1710176312.912 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240311T145043/Express0071.lzma 2024-03-11T16:58:33.915Z,1710176313.915 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0071.lzma.bak 2024-03-11T16:58:33.915Z,1710176313.915 [DataOverHttps](INFO): SBD MOMSN=19310736 2024-03-11T16:58:35.223Z,1710176315.223 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T16:58:35.223Z,1710176315.223 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T16:58:35.223Z,1710176315.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T16:58:52.177Z,1710176332.177 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:01:46.771Z,1710176506.771 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:01:59.683Z,1710176519.683 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:02:13.015Z,1710176533.015 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:02:26.350Z,1710176546.350 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:02:39.679Z,1710176559.679 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:02:52.607Z,1710176572.607 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:03:05.535Z,1710176585.535 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:03:35.858Z,1710176615.858 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:03:35.858Z,1710176615.858 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:03:35.858Z,1710176615.858 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:03:35.858Z,1710176615.858 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:03:36.260Z,1710176616.260 [Default:CheckIn:D] Stopped 2024-03-11T17:03:36.261Z,1710176616.261 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:03:36.682Z,1710176616.682 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.388371 min 2024-03-11T17:03:36.682Z,1710176616.682 [Default:CheckIn:E] Stopped 2024-03-11T17:03:36.682Z,1710176616.682 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:03:36.682Z,1710176616.682 [Default:CheckIn] Stopped 2024-03-11T17:03:36.682Z,1710176616.682 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:03:36.683Z,1710176616.683 [Default:CheckIn](INFO): Running loop #22 2024-03-11T17:03:36.683Z,1710176616.683 [Default:CheckIn] Running Loop=22 2024-03-11T17:03:36.683Z,1710176616.683 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:03:36.683Z,1710176616.683 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:03:38.671Z,1710176618.671 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170338.00,A,3648.14758,N,12147.22292,W,0.039,0.00,110324,,,D*76 2024-03-11T17:03:38.674Z,1710176618.674 [NAL9602](INFO): GPS fix at 20240311T170338: (36.802460, -121.787049) 2024-03-11T17:03:38.684Z,1710176618.684 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:03:38.684Z,1710176618.684 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:03:46.260Z,1710176626.260 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0073.lzma 2024-03-11T17:03:47.262Z,1710176627.262 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0073.lzma.bak 2024-03-11T17:03:47.262Z,1710176627.262 [DataOverHttps](INFO): SBD MOMSN=19310741 2024-03-11T17:03:48.369Z,1710176628.369 [NAL9602](INFO): SBD MO Status=0, MOMSN=47363, MT Status=0, MTMSN=0 2024-03-11T17:03:48.369Z,1710176628.369 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:04:03.231Z,1710176643.231 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0074.lzma 2024-03-11T17:04:04.231Z,1710176644.231 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0074.lzma.bak 2024-03-11T17:04:04.231Z,1710176644.231 [DataOverHttps](INFO): SBD MOMSN=19310745 2024-03-11T17:04:05.343Z,1710176645.343 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:04:05.343Z,1710176645.343 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:04:05.343Z,1710176645.343 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:04:19.065Z,1710176659.065 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:05:39.057Z,1710176739.057 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T17:05:39.064Z,1710176739.064 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-03-11T17:05:39.066Z,1710176739.066 [BPC1](INFO): Received data from all battery sticks. 2024-03-11T17:09:05.993Z,1710176945.993 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:09:05.993Z,1710176945.993 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:09:05.993Z,1710176945.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:09:05.993Z,1710176945.993 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:09:06.333Z,1710176946.333 [Default:CheckIn:D] Stopped 2024-03-11T17:09:06.333Z,1710176946.333 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:09:06.748Z,1710176946.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.889575 min 2024-03-11T17:09:06.748Z,1710176946.748 [Default:CheckIn:E] Stopped 2024-03-11T17:09:06.748Z,1710176946.748 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:09:06.752Z,1710176946.752 [Default:CheckIn] Stopped 2024-03-11T17:09:06.752Z,1710176946.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:09:06.753Z,1710176946.753 [Default:CheckIn](INFO): Running loop #23 2024-03-11T17:09:06.753Z,1710176946.753 [Default:CheckIn] Running Loop=23 2024-03-11T17:09:06.753Z,1710176946.753 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:09:06.753Z,1710176946.753 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:09:08.743Z,1710176948.743 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170908.00,A,3648.14734,N,12147.22173,W,0.039,0.00,110324,,,D*79 2024-03-11T17:09:08.745Z,1710176948.745 [NAL9602](INFO): GPS fix at 20240311T170908: (36.802456, -121.787029) 2024-03-11T17:09:08.780Z,1710176948.780 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:09:08.781Z,1710176948.781 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:09:15.988Z,1710176955.988 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20240311T145043/Courier0076.lzma 2024-03-11T17:09:16.990Z,1710176956.990 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0076.lzma.bak 2024-03-11T17:09:16.990Z,1710176956.990 [DataOverHttps](INFO): SBD MOMSN=19310748 2024-03-11T17:09:33.056Z,1710176973.056 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0077.lzma 2024-03-11T17:09:34.058Z,1710176974.058 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0077.lzma.bak 2024-03-11T17:09:34.059Z,1710176974.059 [DataOverHttps](INFO): SBD MOMSN=19310751 2024-03-11T17:09:35.426Z,1710176975.426 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:09:35.426Z,1710176975.426 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:09:35.426Z,1710176975.426 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:10:17.018Z,1710177017.018 [NAL9602](INFO): SBD MO Status=0, MOMSN=47364, MT Status=0, MTMSN=0 2024-03-11T17:10:17.018Z,1710177017.018 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:10:47.725Z,1710177047.725 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:14:36.007Z,1710177276.007 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:14:36.008Z,1710177276.008 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:14:36.008Z,1710177276.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:14:36.008Z,1710177276.008 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:14:36.415Z,1710177276.415 [Default:CheckIn:D] Stopped 2024-03-11T17:14:36.416Z,1710177276.416 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:14:36.815Z,1710177276.815 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.390951 min 2024-03-11T17:14:36.816Z,1710177276.816 [Default:CheckIn:E] Stopped 2024-03-11T17:14:36.816Z,1710177276.816 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:14:36.816Z,1710177276.816 [Default:CheckIn] Stopped 2024-03-11T17:14:36.816Z,1710177276.816 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:14:36.816Z,1710177276.816 [Default:CheckIn](INFO): Running loop #24 2024-03-11T17:14:36.816Z,1710177276.816 [Default:CheckIn] Running Loop=24 2024-03-11T17:14:36.816Z,1710177276.816 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:14:36.817Z,1710177276.817 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:14:38.827Z,1710177278.827 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171438.00,A,3648.14888,N,12147.22373,W,0.058,0.00,110324,,,D*7B 2024-03-11T17:14:38.830Z,1710177278.830 [NAL9602](INFO): GPS fix at 20240311T171438: (36.802481, -121.787062) 2024-03-11T17:14:38.863Z,1710177278.863 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:14:38.863Z,1710177278.863 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:14:45.724Z,1710177285.724 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0079.lzma 2024-03-11T17:14:46.726Z,1710177286.726 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0079.lzma.bak 2024-03-11T17:14:46.726Z,1710177286.726 [DataOverHttps](INFO): SBD MOMSN=19310755 2024-03-11T17:15:03.115Z,1710177303.115 [NAL9602](INFO): SBD MO Status=2, MOMSN=47365, MT Status=2, MTMSN=0 2024-03-11T17:15:03.115Z,1710177303.115 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T17:15:05.204Z,1710177305.204 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240311T145043/Express0080.lzma 2024-03-11T17:15:06.206Z,1710177306.206 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0080.lzma.bak 2024-03-11T17:15:06.206Z,1710177306.206 [DataOverHttps](INFO): SBD MOMSN=19310758 2024-03-11T17:15:07.581Z,1710177307.581 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:15:07.582Z,1710177307.582 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:15:07.582Z,1710177307.582 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:15:27.763Z,1710177327.763 [NAL9602](INFO): SBD MO Status=2, MOMSN=47365, MT Status=2, MTMSN=0 2024-03-11T17:15:27.763Z,1710177327.763 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T17:15:44.327Z,1710177344.327 [NAL9602](INFO): SBD MO Status=0, MOMSN=47365, MT Status=0, MTMSN=0 2024-03-11T17:15:44.327Z,1710177344.327 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:16:15.030Z,1710177375.030 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:20:08.167Z,1710177608.167 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:20:08.167Z,1710177608.167 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:20:08.167Z,1710177608.167 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:20:08.167Z,1710177608.167 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:20:08.575Z,1710177608.575 [Default:CheckIn:D] Stopped 2024-03-11T17:20:08.575Z,1710177608.575 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:20:08.992Z,1710177608.992 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.926953 min 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn:E] Stopped 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn] Stopped 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn](INFO): Running loop #25 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn] Running Loop=25 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:20:08.993Z,1710177608.993 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:20:10.980Z,1710177610.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172010.00,A,3648.14822,N,12147.22389,W,0.039,0.00,110324,,,D*74 2024-03-11T17:20:10.982Z,1710177610.982 [NAL9602](INFO): GPS fix at 20240311T172010: (36.802470, -121.787065) 2024-03-11T17:20:10.993Z,1710177610.993 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:20:10.993Z,1710177610.993 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:20:19.160Z,1710177619.160 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0082.lzma 2024-03-11T17:20:20.163Z,1710177620.163 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0082.lzma.bak 2024-03-11T17:20:20.163Z,1710177620.163 [DataOverHttps](INFO): SBD MOMSN=19310764 2024-03-11T17:20:28.395Z,1710177628.395 [NAL9602](INFO): SBD MO Status=0, MOMSN=47366, MT Status=0, MTMSN=0 2024-03-11T17:20:28.395Z,1710177628.395 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:20:38.260Z,1710177638.260 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0083.lzma 2024-03-11T17:20:39.263Z,1710177639.263 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0083.lzma.bak 2024-03-11T17:20:39.263Z,1710177639.263 [DataOverHttps](INFO): SBD MOMSN=19310767 2024-03-11T17:20:40.531Z,1710177640.531 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:20:40.531Z,1710177640.531 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:20:40.531Z,1710177640.531 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:20:59.097Z,1710177659.097 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:25:41.207Z,1710177941.207 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:25:41.207Z,1710177941.207 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:25:41.207Z,1710177941.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:25:41.207Z,1710177941.207 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:25:41.602Z,1710177941.602 [Default:CheckIn:D] Stopped 2024-03-11T17:25:41.602Z,1710177941.602 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:25:42.008Z,1710177942.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 151.477409 min 2024-03-11T17:25:42.008Z,1710177942.008 [Default:CheckIn:E] Stopped 2024-03-11T17:25:42.009Z,1710177942.009 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:25:42.009Z,1710177942.009 [Default:CheckIn] Stopped 2024-03-11T17:25:42.009Z,1710177942.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:25:42.009Z,1710177942.009 [Default:CheckIn](INFO): Running loop #26 2024-03-11T17:25:42.009Z,1710177942.009 [Default:CheckIn] Running Loop=26 2024-03-11T17:25:42.009Z,1710177942.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:25:42.009Z,1710177942.009 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:25:44.025Z,1710177944.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172543.00,A,3648.14777,N,12147.22304,W,0.039,0.00,110324,,,D*7D 2024-03-11T17:25:44.027Z,1710177944.027 [NAL9602](INFO): GPS fix at 20240311T172543: (36.802463, -121.787051) 2024-03-11T17:25:44.038Z,1710177944.038 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:25:44.038Z,1710177944.038 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:25:51.620Z,1710177951.620 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0085.lzma 2024-03-11T17:25:52.622Z,1710177952.622 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0085.lzma.bak 2024-03-11T17:25:52.622Z,1710177952.622 [DataOverHttps](INFO): SBD MOMSN=19310771 2024-03-11T17:26:00.587Z,1710177960.587 [NAL9602](INFO): SBD MO Status=0, MOMSN=47367, MT Status=0, MTMSN=0 2024-03-11T17:26:00.587Z,1710177960.587 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:26:08.560Z,1710177968.560 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0086.lzma 2024-03-11T17:26:09.562Z,1710177969.562 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0086.lzma.bak 2024-03-11T17:26:09.563Z,1710177969.563 [DataOverHttps](INFO): SBD MOMSN=19310774 2024-03-11T17:26:10.701Z,1710177970.701 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:26:10.701Z,1710177970.701 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:26:10.701Z,1710177970.701 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:26:31.281Z,1710177991.281 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:31:11.379Z,1710178271.379 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:31:11.379Z,1710178271.379 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:31:11.379Z,1710178271.379 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:31:11.379Z,1710178271.379 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:31:11.787Z,1710178271.787 [Default:CheckIn:D] Stopped 2024-03-11T17:31:11.788Z,1710178271.788 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:31:12.192Z,1710178272.192 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.980485 min 2024-03-11T17:31:12.192Z,1710178272.192 [Default:CheckIn:E] Stopped 2024-03-11T17:31:12.193Z,1710178272.193 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:31:12.193Z,1710178272.193 [Default:CheckIn] Stopped 2024-03-11T17:31:12.193Z,1710178272.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:31:12.193Z,1710178272.193 [Default:CheckIn](INFO): Running loop #27 2024-03-11T17:31:12.193Z,1710178272.193 [Default:CheckIn] Running Loop=27 2024-03-11T17:31:12.193Z,1710178272.193 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:31:12.193Z,1710178272.193 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:31:14.196Z,1710178274.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173113.00,A,3648.14782,N,12147.22342,W,0.039,0.00,110324,,,D*75 2024-03-11T17:31:14.198Z,1710178274.198 [NAL9602](INFO): GPS fix at 20240311T173113: (36.802464, -121.787057) 2024-03-11T17:31:14.237Z,1710178274.237 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:31:14.237Z,1710178274.237 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:31:21.396Z,1710178281.396 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0088.lzma 2024-03-11T17:31:22.398Z,1710178282.398 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0088.lzma.bak 2024-03-11T17:31:22.398Z,1710178282.398 [DataOverHttps](INFO): SBD MOMSN=19310778 2024-03-11T17:31:29.955Z,1710178289.955 [NAL9602](INFO): SBD MO Status=0, MOMSN=47368, MT Status=0, MTMSN=0 2024-03-11T17:31:29.955Z,1710178289.955 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:31:38.180Z,1710178298.180 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240311T145043/Express0089.lzma 2024-03-11T17:31:39.182Z,1710178299.182 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0089.lzma.bak 2024-03-11T17:31:39.183Z,1710178299.183 [DataOverHttps](INFO): SBD MOMSN=19310781 2024-03-11T17:31:40.469Z,1710178300.469 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:31:40.469Z,1710178300.469 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:31:40.469Z,1710178300.469 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:32:00.652Z,1710178320.652 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:36:41.105Z,1710178601.105 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:36:41.105Z,1710178601.105 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:36:41.105Z,1710178601.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:36:41.105Z,1710178601.105 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:36:41.511Z,1710178601.511 [Default:CheckIn:D] Stopped 2024-03-11T17:36:41.512Z,1710178601.512 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:36:41.921Z,1710178601.921 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 162.475895 min 2024-03-11T17:36:41.921Z,1710178601.921 [Default:CheckIn:E] Stopped 2024-03-11T17:36:41.921Z,1710178601.921 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:36:41.922Z,1710178601.922 [Default:CheckIn] Stopped 2024-03-11T17:36:41.922Z,1710178601.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:36:41.922Z,1710178601.922 [Default:CheckIn](INFO): Running loop #28 2024-03-11T17:36:41.922Z,1710178601.922 [Default:CheckIn] Running Loop=28 2024-03-11T17:36:41.922Z,1710178601.922 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:36:41.922Z,1710178601.922 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:36:43.927Z,1710178603.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173643.00,A,3648.14812,N,12147.22403,W,0.000,0.00,110324,,,D*79 2024-03-11T17:36:43.930Z,1710178603.930 [NAL9602](INFO): GPS fix at 20240311T173643: (36.802469, -121.787067) 2024-03-11T17:36:43.960Z,1710178603.960 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:36:43.960Z,1710178603.960 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:36:53.684Z,1710178613.684 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0091.lzma 2024-03-11T17:36:54.687Z,1710178614.687 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0091.lzma.bak 2024-03-11T17:36:54.687Z,1710178614.687 [DataOverHttps](INFO): SBD MOMSN=19310785 2024-03-11T17:37:10.920Z,1710178630.920 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0092.lzma 2024-03-11T17:37:11.922Z,1710178631.922 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0092.lzma.bak 2024-03-11T17:37:11.923Z,1710178631.923 [DataOverHttps](INFO): SBD MOMSN=19310788 2024-03-11T17:37:13.060Z,1710178633.060 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:37:13.060Z,1710178633.060 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:37:13.060Z,1710178633.060 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:37:18.712Z,1710178638.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=47369, MT Status=0, MTMSN=0 2024-03-11T17:37:18.712Z,1710178638.712 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:37:49.413Z,1710178669.413 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:42:13.782Z,1710178933.782 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:42:13.782Z,1710178933.782 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:42:13.782Z,1710178933.782 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:42:13.782Z,1710178933.782 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:42:14.183Z,1710178934.183 [Default:CheckIn:D] Stopped 2024-03-11T17:42:14.184Z,1710178934.184 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:42:14.602Z,1710178934.602 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.020426 min 2024-03-11T17:42:14.602Z,1710178934.602 [Default:CheckIn:E] Stopped 2024-03-11T17:42:14.603Z,1710178934.603 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:42:14.603Z,1710178934.603 [Default:CheckIn] Stopped 2024-03-11T17:42:14.603Z,1710178934.603 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:42:14.603Z,1710178934.603 [Default:CheckIn](INFO): Running loop #29 2024-03-11T17:42:14.603Z,1710178934.603 [Default:CheckIn] Running Loop=29 2024-03-11T17:42:14.603Z,1710178934.603 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:42:14.603Z,1710178934.603 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:42:16.611Z,1710178936.611 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174215.00,A,3648.14956,N,12147.22451,W,0.058,0.00,110324,,,D*72 2024-03-11T17:42:16.614Z,1710178936.614 [NAL9602](INFO): GPS fix at 20240311T174215: (36.802493, -121.787075) 2024-03-11T17:42:16.652Z,1710178936.652 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:42:16.652Z,1710178936.652 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:42:26.528Z,1710178946.528 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0094.lzma 2024-03-11T17:42:27.531Z,1710178947.531 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0094.lzma.bak 2024-03-11T17:42:27.531Z,1710178947.531 [DataOverHttps](INFO): SBD MOMSN=19310792 2024-03-11T17:42:43.396Z,1710178963.396 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0095.lzma 2024-03-11T17:42:44.399Z,1710178964.399 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0095.lzma.bak 2024-03-11T17:42:44.399Z,1710178964.399 [DataOverHttps](INFO): SBD MOMSN=19310795 2024-03-11T17:42:45.440Z,1710178965.440 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:42:45.440Z,1710178965.440 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:42:45.440Z,1710178965.440 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:43:23.455Z,1710179003.455 [NAL9602](INFO): SBD MO Status=2, MOMSN=47370, MT Status=2, MTMSN=0 2024-03-11T17:43:23.455Z,1710179003.455 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T17:43:42.041Z,1710179022.041 [NAL9602](INFO): SBD MO Status=0, MOMSN=47370, MT Status=0, MTMSN=0 2024-03-11T17:43:42.042Z,1710179022.042 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:44:12.736Z,1710179052.736 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:47:45.867Z,1710179265.867 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:47:45.868Z,1710179265.868 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:47:45.868Z,1710179265.868 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:47:45.868Z,1710179265.868 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:47:46.277Z,1710179266.277 [Default:CheckIn:D] Stopped 2024-03-11T17:47:46.277Z,1710179266.277 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:47:46.680Z,1710179266.680 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 173.555322 min 2024-03-11T17:47:46.680Z,1710179266.680 [Default:CheckIn:E] Stopped 2024-03-11T17:47:46.681Z,1710179266.681 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:47:46.681Z,1710179266.681 [Default:CheckIn] Stopped 2024-03-11T17:47:46.681Z,1710179266.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:47:46.681Z,1710179266.681 [Default:CheckIn](INFO): Running loop #30 2024-03-11T17:47:46.681Z,1710179266.681 [Default:CheckIn] Running Loop=30 2024-03-11T17:47:46.681Z,1710179266.681 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:47:46.681Z,1710179266.681 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:47:48.686Z,1710179268.686 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174748.00,A,3648.13892,N,12147.20508,W,0.700,197.55,110324,,,D*7B 2024-03-11T17:47:48.688Z,1710179268.688 [NAL9602](INFO): GPS fix at 20240311T174748: (36.802315, -121.786751) 2024-03-11T17:47:48.711Z,1710179268.711 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:47:48.711Z,1710179268.711 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:47:55.684Z,1710179275.684 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0097.lzma 2024-03-11T17:47:56.686Z,1710179276.686 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0097.lzma.bak 2024-03-11T17:47:56.687Z,1710179276.687 [DataOverHttps](INFO): SBD MOMSN=19310799 2024-03-11T17:48:09.291Z,1710179289.291 [NAL9602](INFO): SBD MO Status=2, MOMSN=47371, MT Status=2, MTMSN=0 2024-03-11T17:48:09.291Z,1710179289.291 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T17:48:12.452Z,1710179292.452 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20240311T145043/Express0098.lzma 2024-03-11T17:48:13.454Z,1710179293.454 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0098.lzma.bak 2024-03-11T17:48:13.455Z,1710179293.455 [DataOverHttps](INFO): SBD MOMSN=19310802 2024-03-11T17:48:14.555Z,1710179294.555 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:48:14.556Z,1710179294.556 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:48:14.556Z,1710179294.556 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:48:18.211Z,1710179298.211 [NAL9602](INFO): SBD MO Status=0, MOMSN=47371, MT Status=0, MTMSN=0 2024-03-11T17:48:18.211Z,1710179298.211 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:48:48.941Z,1710179328.941 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:53:15.044Z,1710179595.044 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:53:15.044Z,1710179595.044 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:53:15.044Z,1710179595.044 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:53:15.044Z,1710179595.044 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:53:15.460Z,1710179595.460 [Default:CheckIn:D] Stopped 2024-03-11T17:53:15.460Z,1710179595.460 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:53:15.900Z,1710179595.900 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.041699 min 2024-03-11T17:53:15.900Z,1710179595.900 [Default:CheckIn:E] Stopped 2024-03-11T17:53:15.900Z,1710179595.900 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:53:15.900Z,1710179595.900 [Default:CheckIn] Stopped 2024-03-11T17:53:15.900Z,1710179595.900 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:53:15.900Z,1710179595.900 [Default:CheckIn](INFO): Running loop #31 2024-03-11T17:53:15.901Z,1710179595.901 [Default:CheckIn] Running Loop=31 2024-03-11T17:53:15.901Z,1710179595.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:53:15.901Z,1710179595.901 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:53:17.867Z,1710179597.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175317.00,A,3648.13711,N,12147.20617,W,0.058,299.73,110324,,,A*7B 2024-03-11T17:53:17.870Z,1710179597.870 [NAL9602](INFO): GPS fix at 20240311T175317: (36.802285, -121.786770) 2024-03-11T17:53:17.881Z,1710179597.881 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:53:17.881Z,1710179597.881 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:53:25.688Z,1710179605.688 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0100.lzma 2024-03-11T17:53:26.690Z,1710179606.690 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0100.lzma.bak 2024-03-11T17:53:26.691Z,1710179606.691 [DataOverHttps](INFO): SBD MOMSN=19310946 2024-03-11T17:53:42.536Z,1710179622.536 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240311T145043/Express0101.lzma 2024-03-11T17:53:43.538Z,1710179623.538 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0101.lzma.bak 2024-03-11T17:53:43.539Z,1710179623.539 [DataOverHttps](INFO): SBD MOMSN=19310949 2024-03-11T17:53:44.948Z,1710179624.948 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:53:44.948Z,1710179624.948 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:53:44.948Z,1710179624.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:54:18.879Z,1710179658.879 [NAL9602](INFO): SBD MO Status=2, MOMSN=47372, MT Status=2, MTMSN=0 2024-03-11T17:54:18.879Z,1710179658.879 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T17:54:51.667Z,1710179691.667 [NAL9602](INFO): SBD MO Status=0, MOMSN=47372, MT Status=0, MTMSN=0 2024-03-11T17:54:51.667Z,1710179691.667 [NAL9602](INFO): No messages in MT queue 2024-03-11T17:55:22.380Z,1710179722.380 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T17:58:45.364Z,1710179925.364 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T17:58:45.364Z,1710179925.364 [Default:CheckIn:C.Wait] Stopped 2024-03-11T17:58:45.364Z,1710179925.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T17:58:45.364Z,1710179925.364 [Default:CheckIn:D] Running Loop=1 2024-03-11T17:58:45.779Z,1710179925.779 [Default:CheckIn:D] Stopped 2024-03-11T17:58:45.779Z,1710179925.779 [Default:CheckIn:E] Running Loop=1 2024-03-11T17:58:46.172Z,1710179926.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 184.547021 min 2024-03-11T17:58:46.172Z,1710179926.172 [Default:CheckIn:E] Stopped 2024-03-11T17:58:46.173Z,1710179926.173 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T17:58:46.173Z,1710179926.173 [Default:CheckIn] Stopped 2024-03-11T17:58:46.173Z,1710179926.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T17:58:46.173Z,1710179926.173 [Default:CheckIn](INFO): Running loop #32 2024-03-11T17:58:46.173Z,1710179926.173 [Default:CheckIn] Running Loop=32 2024-03-11T17:58:46.173Z,1710179926.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T17:58:46.173Z,1710179926.173 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T17:58:48.183Z,1710179928.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175847.00,A,3648.13723,N,12147.20559,W,0.058,299.73,110324,,,A*7D 2024-03-11T17:58:48.185Z,1710179928.185 [NAL9602](INFO): GPS fix at 20240311T175847: (36.802287, -121.786760) 2024-03-11T17:58:48.213Z,1710179928.213 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T17:58:48.213Z,1710179928.213 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T17:58:58.172Z,1710179938.172 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0103.lzma 2024-03-11T17:58:59.175Z,1710179939.175 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0103.lzma.bak 2024-03-11T17:58:59.175Z,1710179939.175 [DataOverHttps](INFO): SBD MOMSN=19310985 2024-03-11T17:59:14.000Z,1710179955.000 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240311T145043/Express0104.lzma 2024-03-11T17:59:16.003Z,1710179956.003 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0104.lzma.bak 2024-03-11T17:59:16.003Z,1710179956.003 [DataOverHttps](INFO): SBD MOMSN=19310988 2024-03-11T17:59:17.064Z,1710179957.064 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T17:59:17.064Z,1710179957.064 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T17:59:17.064Z,1710179957.064 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T17:59:40.079Z,1710179980.079 [NAL9602](INFO): SBD MO Status=2, MOMSN=47373, MT Status=2, MTMSN=0 2024-03-11T17:59:40.079Z,1710179980.079 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T18:00:08.354Z,1710180008.354 [NAL9602](INFO): SBD MO Status=0, MOMSN=47373, MT Status=0, MTMSN=0 2024-03-11T18:00:08.354Z,1710180008.354 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:00:39.105Z,1710180039.105 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:04:17.825Z,1710180257.825 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:04:17.825Z,1710180257.825 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:04:17.825Z,1710180257.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:04:17.825Z,1710180257.825 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:04:18.248Z,1710180258.248 [Default:CheckIn:D] Stopped 2024-03-11T18:04:18.248Z,1710180258.248 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:04:18.681Z,1710180258.681 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.088167 min 2024-03-11T18:04:18.681Z,1710180258.681 [Default:CheckIn:E] Stopped 2024-03-11T18:04:18.681Z,1710180258.681 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:04:18.681Z,1710180258.681 [Default:CheckIn] Stopped 2024-03-11T18:04:18.682Z,1710180258.682 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:04:18.682Z,1710180258.682 [Default:CheckIn](INFO): Running loop #33 2024-03-11T18:04:18.682Z,1710180258.682 [Default:CheckIn] Running Loop=33 2024-03-11T18:04:18.682Z,1710180258.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:04:18.682Z,1710180258.682 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:04:20.648Z,1710180260.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180419.00,A,3648.13855,N,12147.20595,W,0.019,299.73,110324,,,A*7B 2024-03-11T18:04:20.650Z,1710180260.650 [NAL9602](INFO): GPS fix at 20240311T180419: (36.802309, -121.786766) 2024-03-11T18:04:20.669Z,1710180260.669 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:04:20.669Z,1710180260.669 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:04:30.488Z,1710180270.488 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0106.lzma 2024-03-11T18:04:31.491Z,1710180271.491 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0106.lzma.bak 2024-03-11T18:04:31.491Z,1710180271.491 [DataOverHttps](INFO): SBD MOMSN=19310995 2024-03-11T18:04:47.576Z,1710180287.576 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240311T145043/Express0107.lzma 2024-03-11T18:04:48.579Z,1710180288.579 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0107.lzma.bak 2024-03-11T18:04:48.579Z,1710180288.579 [DataOverHttps](INFO): SBD MOMSN=19310998 2024-03-11T18:04:49.921Z,1710180289.921 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:04:49.921Z,1710180289.921 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:04:49.921Z,1710180289.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:05:41.271Z,1710180341.271 [NAL9602](INFO): SBD MO Status=0, MOMSN=47374, MT Status=0, MTMSN=0 2024-03-11T18:05:41.271Z,1710180341.271 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:06:11.970Z,1710180371.970 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:06:46.351Z,1710180406.351 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:06:59.299Z,1710180419.299 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:07:12.227Z,1710180432.227 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:07:25.559Z,1710180445.559 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:07:38.487Z,1710180458.487 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:07:51.820Z,1710180471.820 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:08:03.939Z,1710180483.939 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:09:50.620Z,1710180590.620 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:09:50.620Z,1710180590.620 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:09:50.620Z,1710180590.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:09:50.620Z,1710180590.620 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:09:51.087Z,1710180591.087 [Default:CheckIn:D] Stopped 2024-03-11T18:09:51.087Z,1710180591.087 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:09:51.422Z,1710180591.422 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.635482 min 2024-03-11T18:09:51.422Z,1710180591.422 [Default:CheckIn:E] Stopped 2024-03-11T18:09:51.422Z,1710180591.422 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:09:51.422Z,1710180591.422 [Default:CheckIn] Stopped 2024-03-11T18:09:51.423Z,1710180591.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:09:51.423Z,1710180591.423 [Default:CheckIn](INFO): Running loop #34 2024-03-11T18:09:51.423Z,1710180591.423 [Default:CheckIn] Running Loop=34 2024-03-11T18:09:51.423Z,1710180591.423 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:09:51.423Z,1710180591.423 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:09:53.428Z,1710180593.428 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180952.00,A,3648.13775,N,12147.20577,W,0.058,299.73,110324,,,A*7D 2024-03-11T18:09:53.430Z,1710180593.430 [NAL9602](INFO): GPS fix at 20240311T180952: (36.802296, -121.786763) 2024-03-11T18:09:53.464Z,1710180593.464 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:09:53.464Z,1710180593.464 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:10:01.220Z,1710180601.220 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0109.lzma 2024-03-11T18:10:02.223Z,1710180602.223 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0109.lzma.bak 2024-03-11T18:10:02.223Z,1710180602.223 [DataOverHttps](INFO): SBD MOMSN=19311055 2024-03-11T18:10:04.362Z,1710180604.362 [NAL9602](INFO): SBD MO Status=0, MOMSN=47375, MT Status=0, MTMSN=0 2024-03-11T18:10:04.362Z,1710180604.362 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:10:18.040Z,1710180618.040 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0110.lzma 2024-03-11T18:10:19.043Z,1710180619.043 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0110.lzma.bak 2024-03-11T18:10:19.043Z,1710180619.043 [DataOverHttps](INFO): SBD MOMSN=19311058 2024-03-11T18:10:20.107Z,1710180620.107 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:10:20.108Z,1710180620.108 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:10:20.108Z,1710180620.108 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:10:35.089Z,1710180635.089 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:10:45.991Z,1710180645.991 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-11T18:10:45.998Z,1710180645.998 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 5. 2024-03-11T18:10:46.028Z,1710180646.028 [BPC1](INFO): Received data from all battery sticks. 2024-03-11T18:15:20.807Z,1710180920.807 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:15:20.807Z,1710180920.807 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:15:20.807Z,1710180920.807 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:15:20.808Z,1710180920.808 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:15:21.216Z,1710180921.216 [Default:CheckIn:D] Stopped 2024-03-11T18:15:21.216Z,1710180921.216 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:15:21.624Z,1710180921.624 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.137630 min 2024-03-11T18:15:21.624Z,1710180921.624 [Default:CheckIn:E] Stopped 2024-03-11T18:15:21.624Z,1710180921.624 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:15:21.625Z,1710180921.625 [Default:CheckIn] Stopped 2024-03-11T18:15:21.625Z,1710180921.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:15:21.625Z,1710180921.625 [Default:CheckIn](INFO): Running loop #35 2024-03-11T18:15:21.625Z,1710180921.625 [Default:CheckIn] Running Loop=35 2024-03-11T18:15:21.625Z,1710180921.625 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:15:21.625Z,1710180921.625 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:15:23.623Z,1710180923.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181522.00,A,3648.13705,N,12147.20690,W,0.058,299.73,110324,,,A*7A 2024-03-11T18:15:23.626Z,1710180923.626 [NAL9602](INFO): GPS fix at 20240311T181522: (36.802284, -121.786782) 2024-03-11T18:15:23.661Z,1710180923.661 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:15:23.661Z,1710180923.661 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:15:30.952Z,1710180930.952 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240311T145043/Courier0112.lzma 2024-03-11T18:15:31.954Z,1710180931.954 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0112.lzma.bak 2024-03-11T18:15:31.955Z,1710180931.955 [DataOverHttps](INFO): SBD MOMSN=19311063 2024-03-11T18:15:38.979Z,1710180938.979 [NAL9602](INFO): SBD MO Status=0, MOMSN=47376, MT Status=0, MTMSN=0 2024-03-11T18:15:38.979Z,1710180938.979 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:15:47.792Z,1710180947.792 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240311T145043/Express0113.lzma 2024-03-11T18:15:48.794Z,1710180948.794 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0113.lzma.bak 2024-03-11T18:15:48.795Z,1710180948.795 [DataOverHttps](INFO): SBD MOMSN=19311066 2024-03-11T18:15:49.892Z,1710180949.892 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:15:49.892Z,1710180949.892 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:15:49.892Z,1710180949.892 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:16:09.681Z,1710180969.681 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:20:50.602Z,1710181250.602 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:20:50.602Z,1710181250.602 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:20:50.602Z,1710181250.602 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:20:50.602Z,1710181250.602 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:20:50.976Z,1710181250.976 [Default:CheckIn:D] Stopped 2024-03-11T18:20:50.976Z,1710181250.976 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:20:51.380Z,1710181251.380 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 206.633626 min 2024-03-11T18:20:51.380Z,1710181251.380 [Default:CheckIn:E] Stopped 2024-03-11T18:20:51.380Z,1710181251.380 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:20:51.388Z,1710181251.388 [Default:CheckIn] Stopped 2024-03-11T18:20:51.389Z,1710181251.389 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:20:51.389Z,1710181251.389 [Default:CheckIn](INFO): Running loop #36 2024-03-11T18:20:51.389Z,1710181251.389 [Default:CheckIn] Running Loop=36 2024-03-11T18:20:51.389Z,1710181251.389 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:20:51.389Z,1710181251.389 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:20:53.448Z,1710181253.448 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182052.00,A,3648.19795,N,12147.15792,W,4.413,24.77,110324,,,D*47 2024-03-11T18:20:53.450Z,1710181253.450 [NAL9602](INFO): GPS fix at 20240311T182052: (36.803299, -121.785965) 2024-03-11T18:20:53.469Z,1710181253.469 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:20:53.469Z,1710181253.469 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:21:00.648Z,1710181260.648 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240311T145043/Courier0115.lzma 2024-03-11T18:21:01.650Z,1710181261.650 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0115.lzma.bak 2024-03-11T18:21:01.651Z,1710181261.651 [DataOverHttps](INFO): SBD MOMSN=19311118 2024-03-11T18:21:17.468Z,1710181277.468 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240311T145043/Express0116.lzma 2024-03-11T18:21:18.470Z,1710181278.470 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0116.lzma.bak 2024-03-11T18:21:18.471Z,1710181278.471 [DataOverHttps](INFO): SBD MOMSN=19311121 2024-03-11T18:21:19.802Z,1710181279.802 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:21:19.802Z,1710181279.802 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:21:19.802Z,1710181279.802 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:21:24.223Z,1710181284.223 [NAL9602](INFO): SBD MO Status=2, MOMSN=47377, MT Status=2, MTMSN=0 2024-03-11T18:21:24.223Z,1710181284.223 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T18:21:49.271Z,1710181309.271 [NAL9602](INFO): SBD MO Status=2, MOMSN=47377, MT Status=2, MTMSN=0 2024-03-11T18:21:49.271Z,1710181309.271 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-11T18:22:09.879Z,1710181329.879 [NAL9602](INFO): SBD MO Status=0, MOMSN=47377, MT Status=0, MTMSN=0 2024-03-11T18:22:09.879Z,1710181329.879 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:22:40.605Z,1710181360.605 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:26:20.458Z,1710181580.458 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:26:20.458Z,1710181580.458 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:26:20.458Z,1710181580.458 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:26:20.458Z,1710181580.458 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:26:20.847Z,1710181580.847 [Default:CheckIn:D] Stopped 2024-03-11T18:26:20.847Z,1710181580.847 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:26:21.247Z,1710181581.247 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.131478 min 2024-03-11T18:26:21.247Z,1710181581.247 [Default:CheckIn:E] Stopped 2024-03-11T18:26:21.248Z,1710181581.248 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:26:21.248Z,1710181581.248 [Default:CheckIn] Stopped 2024-03-11T18:26:21.248Z,1710181581.248 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:26:21.248Z,1710181581.248 [Default:CheckIn](INFO): Running loop #37 2024-03-11T18:26:21.248Z,1710181581.248 [Default:CheckIn] Running Loop=37 2024-03-11T18:26:21.248Z,1710181581.248 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:26:21.248Z,1710181581.248 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:26:23.256Z,1710181583.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182622.00,A,3648.35820,N,12147.47946,W,15.492,233.71,110324,,,D*42 2024-03-11T18:26:23.258Z,1710181583.258 [NAL9602](INFO): GPS fix at 20240311T182622: (36.805970, -121.791324) 2024-03-11T18:26:23.289Z,1710181583.289 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:26:23.289Z,1710181583.289 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:26:31.400Z,1710181591.400 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0118.lzma 2024-03-11T18:26:32.402Z,1710181592.402 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0118.lzma.bak 2024-03-11T18:26:32.403Z,1710181592.403 [DataOverHttps](INFO): SBD MOMSN=19311125 2024-03-11T18:26:38.203Z,1710181598.203 [NAL9602](INFO): SBD MO Status=0, MOMSN=47378, MT Status=0, MTMSN=0 2024-03-11T18:26:38.203Z,1710181598.203 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:26:48.500Z,1710181608.500 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240311T145043/Express0119.lzma 2024-03-11T18:26:49.503Z,1710181609.503 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0119.lzma.bak 2024-03-11T18:26:49.503Z,1710181609.503 [DataOverHttps](INFO): SBD MOMSN=19311129 2024-03-11T18:26:50.747Z,1710181610.747 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:26:50.747Z,1710181610.747 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:26:50.747Z,1710181610.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:27:08.932Z,1710181628.932 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:31:04.140Z,1710181864.140 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2109,HPR45,EXT,TILT 2024-03-11T18:31:51.419Z,1710181911.419 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:31:51.419Z,1710181911.419 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:31:51.419Z,1710181911.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:31:51.419Z,1710181911.419 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:31:51.825Z,1710181911.825 [Default:CheckIn:D] Stopped 2024-03-11T18:31:51.825Z,1710181911.825 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:31:52.235Z,1710181912.235 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.647786 min 2024-03-11T18:31:52.235Z,1710181912.235 [Default:CheckIn:E] Stopped 2024-03-11T18:31:52.236Z,1710181912.236 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:31:52.236Z,1710181912.236 [Default:CheckIn] Stopped 2024-03-11T18:31:52.237Z,1710181912.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:31:52.238Z,1710181912.238 [Default:CheckIn](INFO): Running loop #38 2024-03-11T18:31:52.239Z,1710181912.239 [Default:CheckIn] Running Loop=38 2024-03-11T18:31:52.239Z,1710181912.239 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:31:52.240Z,1710181912.240 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:31:54.240Z,1710181914.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183153.00,A,3647.95353,N,12149.15419,W,15.570,260.52,110324,,,D*4C 2024-03-11T18:31:54.247Z,1710181914.247 [NAL9602](INFO): GPS fix at 20240311T183153: (36.799225, -121.819237) 2024-03-11T18:31:54.273Z,1710181914.273 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:31:54.273Z,1710181914.273 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:32:02.012Z,1710181922.012 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0121.lzma 2024-03-11T18:32:03.015Z,1710181923.015 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0121.lzma.bak 2024-03-11T18:32:03.015Z,1710181923.015 [DataOverHttps](INFO): SBD MOMSN=19311180 2024-03-11T18:32:06.759Z,1710181926.759 [NAL9602](INFO): SBD MO Status=0, MOMSN=47379, MT Status=0, MTMSN=0 2024-03-11T18:32:06.759Z,1710181926.759 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:32:20.152Z,1710181940.152 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240311T145043/Express0122.lzma 2024-03-11T18:32:21.155Z,1710181941.155 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0122.lzma.bak 2024-03-11T18:32:21.155Z,1710181941.155 [DataOverHttps](INFO): SBD MOMSN=19311183 2024-03-11T18:32:22.562Z,1710181942.562 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:32:22.562Z,1710181942.562 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:32:22.562Z,1710181942.562 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:32:37.488Z,1710181957.488 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:37:23.144Z,1710182243.144 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:37:23.144Z,1710182243.144 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:37:23.144Z,1710182243.144 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:37:23.144Z,1710182243.144 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:37:23.558Z,1710182243.558 [Default:CheckIn:D] Stopped 2024-03-11T18:37:23.558Z,1710182243.558 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:37:23.938Z,1710182243.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.176676 min 2024-03-11T18:37:23.938Z,1710182243.938 [Default:CheckIn:E] Stopped 2024-03-11T18:37:23.939Z,1710182243.939 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:37:23.939Z,1710182243.939 [Default:CheckIn] Stopped 2024-03-11T18:37:23.939Z,1710182243.939 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:37:23.940Z,1710182243.940 [Default:CheckIn](INFO): Running loop #39 2024-03-11T18:37:23.940Z,1710182243.940 [Default:CheckIn] Running Loop=39 2024-03-11T18:37:23.940Z,1710182243.940 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:37:23.940Z,1710182243.940 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:37:25.959Z,1710182245.959 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183725.00,A,3647.76842,N,12150.90101,W,4.238,125.05,110324,,,D*7F 2024-03-11T18:37:25.962Z,1710182245.962 [NAL9602](INFO): GPS fix at 20240311T183725: (36.796140, -121.848350) 2024-03-11T18:37:25.972Z,1710182245.972 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:37:25.972Z,1710182245.972 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:37:33.492Z,1710182253.492 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240311T145043/Courier0124.lzma 2024-03-11T18:37:34.494Z,1710182254.494 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0124.lzma.bak 2024-03-11T18:37:34.495Z,1710182254.495 [DataOverHttps](INFO): SBD MOMSN=19311187 2024-03-11T18:37:43.318Z,1710182263.318 [NAL9602](INFO): SBD MO Status=0, MOMSN=47380, MT Status=0, MTMSN=0 2024-03-11T18:37:43.318Z,1710182263.318 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:37:51.888Z,1710182271.888 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240311T145043/Express0125.lzma 2024-03-11T18:38:14.021Z,1710182294.021 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:38:14.977Z,1710182294.977 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-03-11T18:38:34.992Z,1710182314.992 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240311T145043/Express0125.lzma 2024-03-11T18:38:35.995Z,1710182315.995 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0125.lzma.bak 2024-03-11T18:38:35.995Z,1710182315.995 [DataOverHttps](INFO): SBD MOMSN=19311190 2024-03-11T18:38:37.095Z,1710182317.095 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:38:37.095Z,1710182317.095 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:38:37.096Z,1710182317.096 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:43:37.734Z,1710182617.734 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-11T18:43:37.735Z,1710182617.735 [Default:CheckIn:C.Wait] Stopped 2024-03-11T18:43:37.735Z,1710182617.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-11T18:43:37.735Z,1710182617.735 [Default:CheckIn:D] Running Loop=1 2024-03-11T18:43:38.126Z,1710182618.126 [Default:CheckIn:D] Stopped 2024-03-11T18:43:38.126Z,1710182618.126 [Default:CheckIn:E] Running Loop=1 2024-03-11T18:43:38.536Z,1710182618.536 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 229.419466 min 2024-03-11T18:43:38.536Z,1710182618.536 [Default:CheckIn:E] Stopped 2024-03-11T18:43:38.536Z,1710182618.536 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-11T18:43:38.536Z,1710182618.536 [Default:CheckIn] Stopped 2024-03-11T18:43:38.536Z,1710182618.536 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-11T18:43:38.537Z,1710182618.537 [Default:CheckIn](INFO): Running loop #40 2024-03-11T18:43:38.537Z,1710182618.537 [Default:CheckIn] Running Loop=40 2024-03-11T18:43:38.537Z,1710182618.537 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-11T18:43:38.537Z,1710182618.537 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-11T18:43:40.545Z,1710182620.545 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184339.00,A,3647.74052,N,12150.90469,W,1.263,249.75,110324,,,D*74 2024-03-11T18:43:40.547Z,1710182620.547 [NAL9602](INFO): GPS fix at 20240311T184339: (36.795675, -121.848411) 2024-03-11T18:43:40.563Z,1710182620.563 [Default:CheckIn:Read_GPS] Stopped 2024-03-11T18:43:40.563Z,1710182620.563 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-11T18:43:48.472Z,1710182628.472 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240311T145043/Courier0127.lzma 2024-03-11T18:43:49.475Z,1710182629.475 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Courier0127.lzma.bak 2024-03-11T18:43:49.475Z,1710182629.475 [DataOverHttps](INFO): SBD MOMSN=19311201 2024-03-11T18:44:06.384Z,1710182646.384 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240311T145043/Express0128.lzma 2024-03-11T18:44:06.811Z,1710182646.811 [NAL9602](INFO): SBD MO Status=0, MOMSN=47381, MT Status=0, MTMSN=0 2024-03-11T18:44:06.811Z,1710182646.811 [NAL9602](INFO): No messages in MT queue 2024-03-11T18:44:07.389Z,1710182647.389 [DataOverHttps](INFO): Moved sent file to Logs/20240311T145043/Express0128.lzma.bak 2024-03-11T18:44:07.389Z,1710182647.389 [DataOverHttps](INFO): SBD MOMSN=19311204 2024-03-11T18:44:08.442Z,1710182648.442 [Default:CheckIn:Read_Iridium] Stopped 2024-03-11T18:44:08.442Z,1710182648.442 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-11T18:44:08.443Z,1710182648.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-11T18:44:37.515Z,1710182677.515 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-11T18:47:49.606Z,1710182869.606 [DataOverHttps](IMPORTANT): SBD MTMSN=20240311T184748 2024-03-11T18:47:56.000Z,1710182877.000 [DataOverHttps](INFO): Received command: restart logs