2024-03-18T18:29:16.021Z,1710786556.021 [Supervisor](DEBUG): Initializing supervisor. 2024-03-18T18:29:16.025Z,1710786556.025 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-03-18T18:29:16.026Z,1710786556.026 [SyncHandler](INFO): Protected caller Thread ID is 9058 2024-03-18T18:29:16.026Z,1710786556.026 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-03-18T18:29:16.027Z,1710786556.027 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-03-18T18:29:16.028Z,1710786556.028 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9059 2024-03-18T18:29:16.031Z,1710786556.031 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-03-18T18:29:16.049Z,1710786556.049 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-03-18T18:29:16.050Z,1710786556.050 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-03-18T18:29:16.050Z,1710786556.050 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9060 2024-03-18T18:29:16.054Z,1710786556.054 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-03-18T18:29:16.055Z,1710786556.055 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-03-18T18:29:16.056Z,1710786556.056 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9061 2024-03-18T18:29:16.058Z,1710786556.058 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-03-18T18:29:16.059Z,1710786556.059 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-03-18T18:29:16.059Z,1710786556.059 [logger ThreadHandler](INFO): Protected caller Thread ID is 9062 2024-03-18T18:29:16.063Z,1710786556.063 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-03-18T18:29:16.063Z,1710786556.063 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-03-18T18:29:16.065Z,1710786556.065 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-03-18T18:29:16.333Z,1710786556.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-03-18T18:29:16.334Z,1710786556.334 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-03-18T18:29:16.417Z,1710786556.417 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-03-18T18:29:16.932Z,1710786556.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-03-18T18:29:16.933Z,1710786556.933 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-03-18T18:29:17.270Z,1710786557.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-03-18T18:29:17.271Z,1710786557.271 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-03-18T18:29:17.369Z,1710786557.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-03-18T18:29:17.370Z,1710786557.370 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-03-18T18:29:17.776Z,1710786557.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-03-18T18:29:17.777Z,1710786557.777 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-03-18T18:29:17.997Z,1710786557.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-03-18T18:29:17.998Z,1710786557.998 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-03-18T18:29:18.519Z,1710786558.519 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-03-18T18:29:18.520Z,1710786558.520 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-03-18T18:29:18.660Z,1710786558.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-03-18T18:29:18.661Z,1710786558.661 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-03-18T18:29:18.742Z,1710786558.742 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-03-18T18:29:19.551Z,1710786559.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-03-18T18:29:19.551Z,1710786559.551 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-03-18T18:29:19.932Z,1710786559.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-03-18T18:29:19.932Z,1710786559.932 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-03-18T18:29:20.131Z,1710786560.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-03-18T18:29:20.132Z,1710786560.132 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-03-18T18:29:20.279Z,1710786560.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-03-18T18:29:20.280Z,1710786560.280 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-03-18T18:29:20.786Z,1710786560.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-03-18T18:29:20.786Z,1710786560.786 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-03-18T18:29:21.285Z,1710786561.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-03-18T18:29:21.287Z,1710786561.287 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-03-18T18:29:21.287Z,1710786561.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-03-18T18:29:21.391Z,1710786561.391 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-03-18T18:29:21.480Z,1710786561.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-03-18T18:29:21.585Z,1710786561.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-03-18T18:29:21.671Z,1710786561.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-03-18T18:29:21.776Z,1710786561.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-03-18T18:29:21.881Z,1710786561.881 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-03-18T18:29:21.998Z,1710786561.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-03-18T18:29:22.154Z,1710786562.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-03-18T18:29:22.298Z,1710786562.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-03-18T18:29:22.413Z,1710786562.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-03-18T18:29:22.680Z,1710786562.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-03-18T18:29:22.682Z,1710786562.682 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-03-18T18:29:22.682Z,1710786562.682 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2024-03-18T18:29:22.692Z,1710786562.692 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-03-18T18:29:22.744Z,1710786562.744 [VerticalControl](DEBUG): Construct VerticalControl. 2024-03-18T18:29:22.803Z,1710786562.803 [VerticalControl] Loaded 2024-03-18T18:29:22.804Z,1710786562.804 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-03-18T18:29:22.806Z,1710786562.806 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-03-18T18:29:22.847Z,1710786562.847 [HorizontalControl] Loaded 2024-03-18T18:29:22.848Z,1710786562.848 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-03-18T18:29:22.850Z,1710786562.850 [SpeedControl](DEBUG): Construct SpeedControl. 2024-03-18T18:29:22.853Z,1710786562.853 [SpeedControl] Loaded 2024-03-18T18:29:22.853Z,1710786562.853 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-03-18T18:29:22.856Z,1710786562.856 [LoopControl](DEBUG): Construct LoopControl. 2024-03-18T18:29:22.857Z,1710786562.857 [LoopControl] Loaded 2024-03-18T18:29:22.857Z,1710786562.857 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-03-18T18:29:22.857Z,1710786562.857 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-03-18T18:29:22.858Z,1710786562.858 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-03-18T18:29:22.868Z,1710786562.868 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-03-18T18:29:22.869Z,1710786562.869 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-03-18T18:29:23.015Z,1710786563.015 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-03-18T18:29:23.015Z,1710786563.015 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-03-18T18:29:23.169Z,1710786563.169 [BuoyancyServo] Loaded 2024-03-18T18:29:23.170Z,1710786563.170 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-03-18T18:29:23.189Z,1710786563.189 [ElevatorServo] Loaded 2024-03-18T18:29:23.190Z,1710786563.190 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-03-18T18:29:23.209Z,1710786563.209 [MassServo] Loaded 2024-03-18T18:29:23.209Z,1710786563.209 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-03-18T18:29:23.228Z,1710786563.228 [RudderServo] Loaded 2024-03-18T18:29:23.228Z,1710786563.228 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-03-18T18:29:23.242Z,1710786563.242 [ThrusterHE] Loaded 2024-03-18T18:29:23.243Z,1710786563.243 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-03-18T18:29:23.243Z,1710786563.243 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-03-18T18:29:23.243Z,1710786563.243 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-03-18T18:29:23.356Z,1710786563.356 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-03-18T18:29:23.356Z,1710786563.356 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-03-18T18:29:23.370Z,1710786563.370 [NavChart] Loaded 2024-03-18T18:29:23.370Z,1710786563.370 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-03-18T18:29:23.376Z,1710786563.376 [UniversalFixResidualReporter] Loaded 2024-03-18T18:29:23.376Z,1710786563.376 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-03-18T18:29:23.376Z,1710786563.376 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-03-18T18:29:23.377Z,1710786563.377 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-03-18T18:29:23.433Z,1710786563.433 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-03-18T18:29:23.433Z,1710786563.433 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-03-18T18:29:23.856Z,1710786563.856 [AHRS_M2] Loaded 2024-03-18T18:29:23.856Z,1710786563.856 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-03-18T18:29:24.683Z,1710786564.683 [BPC1] Loaded 2024-03-18T18:29:24.684Z,1710786564.684 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-03-18T18:29:24.761Z,1710786564.761 [DataOverHttps] Loaded 2024-03-18T18:29:24.761Z,1710786564.761 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-03-18T18:29:24.762Z,1710786564.762 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0 2024-03-18T18:29:24.763Z,1710786564.763 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9144 2024-03-18T18:29:24.783Z,1710786564.783 [Depth_Keller] Loaded 2024-03-18T18:29:24.784Z,1710786564.784 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-03-18T18:29:24.789Z,1710786564.789 [DropWeight] Loaded 2024-03-18T18:29:24.789Z,1710786564.789 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-03-18T18:29:24.850Z,1710786564.850 [NAL9602] Loaded 2024-03-18T18:29:24.851Z,1710786564.851 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-03-18T18:29:24.881Z,1710786564.881 [Onboard] Loaded 2024-03-18T18:29:24.881Z,1710786564.881 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-03-18T18:29:24.882Z,1710786564.882 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0 2024-03-18T18:29:24.882Z,1710786564.882 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9145 2024-03-18T18:29:24.889Z,1710786564.889 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1 2024-03-18T18:29:24.901Z,1710786564.901 [PowerOnly] Loaded 2024-03-18T18:29:24.901Z,1710786564.901 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-03-18T18:29:24.913Z,1710786564.913 [Power24vConverter] Loaded 2024-03-18T18:29:24.913Z,1710786564.913 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-03-18T18:29:24.926Z,1710786564.926 [Radio_Surface] Loaded 2024-03-18T18:29:24.927Z,1710786564.927 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-03-18T18:29:24.928Z,1710786564.928 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0 2024-03-18T18:29:24.928Z,1710786564.928 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9146 2024-03-18T18:29:24.929Z,1710786564.929 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-03-18T18:29:24.930Z,1710786564.930 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-03-18T18:29:24.979Z,1710786564.979 [DepthRateCalculator] Loaded 2024-03-18T18:29:24.980Z,1710786564.980 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-03-18T18:29:24.985Z,1710786564.985 [PitchRateCalculator] Loaded 2024-03-18T18:29:24.986Z,1710786564.986 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-03-18T18:29:24.995Z,1710786564.995 [SpeedCalculator] Loaded 2024-03-18T18:29:24.995Z,1710786564.995 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-03-18T18:29:24.999Z,1710786564.999 [YawRateCalculator] Loaded 2024-03-18T18:29:24.999Z,1710786564.999 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-03-18T18:29:25.018Z,1710786565.018 [ElevatorOffsetCalculator] Loaded 2024-03-18T18:29:25.018Z,1710786565.018 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-03-18T18:29:25.019Z,1710786565.019 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-03-18T18:29:25.019Z,1710786565.019 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-03-18T18:29:25.151Z,1710786565.151 [CANONSampler] Loaded 2024-03-18T18:29:25.151Z,1710786565.151 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-03-18T18:29:25.211Z,1710786565.211 [CTD_Seabird] Loaded 2024-03-18T18:29:25.212Z,1710786565.212 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-03-18T18:29:25.213Z,1710786565.213 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0 2024-03-18T18:29:25.213Z,1710786565.213 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9147 2024-03-18T18:29:25.242Z,1710786565.242 [PAR_Licor] Loaded 2024-03-18T18:29:25.242Z,1710786565.242 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-03-18T18:29:25.335Z,1710786565.335 [WetLabsBB2FL] Loaded 2024-03-18T18:29:25.335Z,1710786565.335 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-03-18T18:29:25.336Z,1710786565.336 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0 2024-03-18T18:29:25.336Z,1710786565.336 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9148 2024-03-18T18:29:25.337Z,1710786565.337 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-03-18T18:29:25.337Z,1710786565.337 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-03-18T18:29:25.483Z,1710786565.483 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-03-18T18:29:25.483Z,1710786565.483 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-03-18T18:29:26.159Z,1710786566.159 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-03-18T18:29:26.160Z,1710786566.160 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-03-18T18:29:26.298Z,1710786566.298 [SBIT](DEBUG): Construct Startup Built In Test. 2024-03-18T18:29:26.307Z,1710786566.307 [SBIT] Loaded 2024-03-18T18:29:26.308Z,1710786566.308 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-03-18T18:29:26.311Z,1710786566.311 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-03-18T18:29:26.324Z,1710786566.324 [IBIT] Loaded 2024-03-18T18:29:26.325Z,1710786566.325 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-03-18T18:29:26.330Z,1710786566.330 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-03-18T18:29:26.433Z,1710786566.433 [CBIT] Loaded 2024-03-18T18:29:26.434Z,1710786566.434 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-03-18T18:29:26.434Z,1710786566.434 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-03-18T18:29:26.435Z,1710786566.435 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-03-18T18:29:26.544Z,1710786566.544 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-03-18T18:29:26.551Z,1710786566.551 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-03-18T18:29:26.553Z,1710786566.553 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-03-18T18:29:26.565Z,1710786566.565 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-03-18T18:29:26.566Z,1710786566.566 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BB14E0 2024-03-18T18:29:26.566Z,1710786566.566 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9149 2024-03-18T18:29:26.571Z,1710786566.571 [Supervisor](INFO): Main Thread ID is 8878 2024-03-18T18:29:26.571Z,1710786566.571 [Supervisor](DEBUG): Running supervisor. 2024-03-18T18:29:26.571Z,1710786566.571 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9150 2024-03-18T18:29:26.572Z,1710786566.572 [CommandExec](INFO): Initializing the command executive. 2024-03-18T18:29:26.573Z,1710786566.573 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9151 2024-03-18T18:29:26.576Z,1710786566.576 [controlThread ThreadHandler](INFO): Handler Thread ID is 9152 2024-03-18T18:29:26.576Z,1710786566.576 [controlThread](DEBUG): Initializing ControlThread 2024-03-18T18:29:26.577Z,1710786566.577 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-03-18T18:29:26.579Z,1710786566.579 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-03-18T18:29:26.579Z,1710786566.579 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-03-18T18:29:26.580Z,1710786566.580 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-03-18T18:29:26.582Z,1710786566.582 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-03-18T18:29:26.582Z,1710786566.582 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-03-18T18:29:26.588Z,1710786566.588 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-03-18T18:29:26.588Z,1710786566.588 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-03-18T18:29:26.589Z,1710786566.589 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-03-18T18:29:26.589Z,1710786566.589 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-03-18T18:29:26.589Z,1710786566.589 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-03-18T18:29:26.594Z,1710786566.594 [SBIT](INFO): Initialize SBIT Component. 2024-03-18T18:29:26.595Z,1710786566.595 [SBIT](IMPORTANT): git: 2024-02-14 2024-03-18T18:29:26.595Z,1710786566.595 [SBIT](INFO): git hash: 8fc4e848ca047b5de9ae8a7c02f55958c6301ab2 2024-03-18T18:29:26.595Z,1710786566.595 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-03-18T18:29:26.596Z,1710786566.596 [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-18T18:29:26.597Z,1710786566.597 [SBIT](INFO): Beginning SBIT in 83.000000 seconds. 2024-03-18T18:29:26.598Z,1710786566.598 [IBIT](INFO): Initialize IBIT Component. 2024-03-18T18:29:26.599Z,1710786566.599 [CBIT](DEBUG): Initialize CBIT Component. 2024-03-18T18:29:26.600Z,1710786566.600 [logger ThreadHandler](INFO): Handler Thread ID is 9153 2024-03-18T18:29:26.610Z,1710786566.610 [CBIT](DEBUG): Initialized mux pins. 2024-03-18T18:29:26.610Z,1710786566.610 [CBIT](DEBUG): Initializing the watchdog timer. 2024-03-18T18:29:26.619Z,1710786566.619 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9154 2024-03-18T18:29:26.620Z,1710786566.620 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-03-18T18:29:26.631Z,1710786566.631 [Onboard ThreadHandler](INFO): Handler Thread ID is 9155 2024-03-18T18:29:26.635Z,1710786566.635 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-03-18T18:29:26.635Z,1710786566.635 [CBIT](DEBUG): Initializing heartbeat. 2024-03-18T18:29:26.651Z,1710786566.651 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9156 2024-03-18T18:29:26.663Z,1710786566.663 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9157 2024-03-18T18:29:26.663Z,1710786566.663 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-03-18T18:29:26.668Z,1710786566.668 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9159 2024-03-18T18:29:26.671Z,1710786566.671 [WetLabsBB2FL](INFO): Powering up 2024-03-18T18:29:26.672Z,1710786566.672 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9161 2024-03-18T18:29:26.675Z,1710786566.675 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-03-18T18:29:26.676Z,1710786566.676 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-03-18T18:29:26.676Z,1710786566.676 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-03-18T18:29:26.676Z,1710786566.676 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-03-18T18:29:26.676Z,1710786566.676 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-03-18T18:29:26.676Z,1710786566.676 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-03-18T18:29:26.677Z,1710786566.677 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-03-18T18:29:26.677Z,1710786566.677 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-03-18T18:29:26.706Z,1710786566.706 [CBIT](DEBUG): Deactivating GF circuits. 2024-03-18T18:29:26.706Z,1710786566.706 [CBIT](DEBUG): Deactivating emergency mode. 2024-03-18T18:29:26.746Z,1710786566.746 [CBIT](DEBUG): Backplane powered. 2024-03-18T18:29:26.747Z,1710786566.747 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-03-18T18:29:26.747Z,1710786566.747 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Startup.tx 2024-03-18T18:29:26.759Z,1710786566.759 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-18T18:29:26.794Z,1710786566.794 [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-18T18:29:26.795Z,1710786566.795 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-03-18T18:29:26.795Z,1710786566.795 [MissionManager](IMPORTANT): Loading Compiled TethysL script from file Missions/Default.tx 2024-03-18T18:29:26.869Z,1710786566.869 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-03-18T18:29:26.870Z,1710786566.870 [Default:A.Wait](DEBUG): Construct Wait. 2024-03-18T18:29:26.872Z,1710786566.872 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-03-18T18:29:26.916Z,1710786566.916 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-03-18T18:29:26.919Z,1710786566.919 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-03-18T18:29:26.940Z,1710786566.940 [Default:E.Execute](DEBUG): Construct Execute. 2024-03-18T18:29:26.943Z,1710786566.943 [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-18T18:29:26.956Z,1710786566.956 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,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-18T18:29:26.973Z,1710786566.973 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-03-18T18:29:27.047Z,1710786567.047 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m 2024-03-18T18:29:27.049Z,1710786567.049 [PowerOnly](INFO): Powering up loadControl 2024-03-18T18:29:27.054Z,1710786567.054 [Radio_Surface](INFO): Powering up 2024-03-18T18:29:27.067Z,1710786567.067 [Power24vConverter](INFO): Powering up. 2024-03-18T18:29:27.067Z,1710786567.067 [CANONSampler](INFO): Powering down 2024-03-18T18:29:27.176Z,1710786567.176 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-03-18T18:29:27.220Z,1710786567.220 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-03-18T18:29:27.226Z,1710786567.226 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-03-18T18:29:27.227Z,1710786567.227 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-03-18T18:29:27.234Z,1710786567.234 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-03-18T18:29:27.235Z,1710786567.235 [MassServo](DEBUG): Initializing EZServoServo. 2024-03-18T18:29:27.243Z,1710786567.243 [MassServo](DEBUG): Initializing MassServo. 2024-03-18T18:29:27.243Z,1710786567.243 [RudderServo](DEBUG): Initializing EZServoServo. 2024-03-18T18:29:27.251Z,1710786567.251 [RudderServo](DEBUG): Initializing RudderServo. 2024-03-18T18:29:27.252Z,1710786567.252 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-03-18T18:29:27.258Z,1710786567.258 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-03-18T18:29:28.778Z,1710786568.778 [WetLabsBB2FL](INFO): Powering down 2024-03-18T18:29:32.864Z,1710786572.864 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-03-18T18:29:55.191Z,1710786595.191 [CommandExec](IMPORTANT): got command failComponent 2024-03-18T18:29:55.191Z,1710786595.191 [CommandExec](IMPORTANT): Failed components: 2024-03-18T18:29:55.191Z,1710786595.191 [CommandExec](IMPORTANT): No failed Components. 2024-03-18T18:29:55.300Z,1710786595.300 [NAL9602](INFO): Powering up NAL9602 2024-03-18T18:30:06.156Z,1710786606.156 [NAL9602](INFO): NAL9602 initialized 2024-03-18T18:30:33.223Z,1710786633.223 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:30:46.147Z,1710786646.147 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:30:50.209Z,1710786650.209 [SBIT](IMPORTANT): Beginning Startup BIT 2024-03-18T18:30:50.213Z,1710786650.213 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-18T18:30:58.671Z,1710786658.671 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:31:01.181Z,1710786661.181 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-18T18:31:01.554Z,1710786661.554 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-18T18:31:12.015Z,1710786672.015 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:31:12.554Z,1710786672.554 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002375 CHAN A1 (24V): 0.000556 CHAN A2 (12V): -0.002442 CHAN A3 (5V): -0.001448 CHAN B0 (3.3V): 0.000317 CHAN B1 (3.15aV): 0.000077 CHAN B2 (3.15bV): -0.000391 CHAN B3 (GND): 0.000085 OPEN: -0.000568 Full Scale: +/- 1 mA 2024-03-18T18:31:24.930Z,1710786684.930 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:31:37.455Z,1710786697.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:31:44.364Z,1710786704.364 [SBIT](IMPORTANT): SBIT PASSED 2024-03-18T18:31:44.365Z,1710786704.365 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-03-18T18:31:44.365Z,1710786704.365 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2024-03-18T18:31:44.365Z,1710786704.365 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count; 2024-03-18T18:31:44.366Z,1710786704.366 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2024-03-18T18:31:44.366Z,1710786704.366 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-03-18T18:31:44.366Z,1710786704.366 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool; 2024-03-18T18:31:44.366Z,1710786704.366 [SBIT](IMPORTANT): PowerOnly.sampleTime1=0 second; 2024-03-18T18:31:44.366Z,1710786704.366 [SBIT](IMPORTANT): PowerOnly.simulateHardware=0 bool; 2024-03-18T18:31:44.366Z,1710786704.366 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool; 2024-03-18T18:31:44.367Z,1710786704.367 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent; 2024-03-18T18:31:44.367Z,1710786704.367 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=319.031769 cubic_centimeter; 2024-03-18T18:31:44.367Z,1710786704.367 [SBIT](IMPORTANT): VerticalControl.massDefault=-15.950069 millimeter; 2024-03-18T18:31:44.769Z,1710786704.769 [MissionManager](IMPORTANT): Started mission Startup 2024-03-18T18:31:44.769Z,1710786704.769 [Startup] Running Loop=1 2024-03-18T18:31:44.770Z,1710786704.770 [Startup](DEBUG): Aggregate::initialize Startup 2024-03-18T18:31:44.770Z,1710786704.770 [Startup:A.GoToSurface] Running Loop=1 2024-03-18T18:31:44.770Z,1710786704.770 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-18T18:31:44.771Z,1710786704.771 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-18T18:31:44.771Z,1710786704.771 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-18T18:31:44.771Z,1710786704.771 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-18T18:31:44.772Z,1710786704.772 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-18T18:31:44.772Z,1710786704.772 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-18T18:31:44.773Z,1710786704.773 [Startup:StartupSatComms] Running Loop=1 2024-03-18T18:31:44.773Z,1710786704.773 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-03-18T18:31:44.773Z,1710786704.773 [Startup:StartupSatComms:A] Running Loop=1 2024-03-18T18:31:45.152Z,1710786705.152 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-03-18T18:31:50.787Z,1710786710.787 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:32:09.560Z,1710786729.560 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004570 2024-03-18T18:32:26.759Z,1710786746.759 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-03-18T18:32:26.759Z,1710786746.759 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:32:26.769Z,1710786746.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:32:27.172Z,1710786747.172 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:32:27.173Z,1710786747.173 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-03-18T18:32:44.963Z,1710786764.963 [Startup:StartupSatComms:A](INFO): Timed out from 2024-03-18T18:31:44.8Z 2024-03-18T18:32:44.963Z,1710786764.963 [Startup:StartupSatComms:A] Stopped 2024-03-18T18:32:44.963Z,1710786764.963 [Startup:StartupSatComms:B] Running Loop=1 2024-03-18T18:32:45.357Z,1710786765.357 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-03-18T18:32:52.270Z,1710786772.270 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240318T182915/Courier0000.lzma 2024-03-18T18:32:53.272Z,1710786773.272 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0000.lzma.bak 2024-03-18T18:32:53.272Z,1710786773.272 [DataOverHttps](INFO): SBD MOMSN=19327987 2024-03-18T18:33:08.883Z,1710786788.883 [DataOverHttps](INFO): Sending 937 bytes from file Logs/20240318T182915/Express0001.lzma 2024-03-18T18:33:09.884Z,1710786789.884 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0001.lzma.bak 2024-03-18T18:33:09.884Z,1710786789.884 [DataOverHttps](INFO): SBD MOMSN=19327990 2024-03-18T18:33:11.200Z,1710786791.200 [Startup:StartupSatComms:B] Stopped 2024-03-18T18:33:11.200Z,1710786791.200 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-03-18T18:33:11.200Z,1710786791.200 [Startup:StartupSatComms] Stopped 2024-03-18T18:33:11.200Z,1710786791.200 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-03-18T18:33:11.201Z,1710786791.201 [Startup](INFO): Completed Startup 2024-03-18T18:33:11.201Z,1710786791.201 [MissionManager](INFO): Startup is completed. 2024-03-18T18:33:11.201Z,1710786791.201 [MissionManager](INFO): Uninitializing Mission Startup 2024-03-18T18:33:11.201Z,1710786791.201 [Startup] Stopped 2024-03-18T18:33:11.201Z,1710786791.201 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-03-18T18:33:11.201Z,1710786791.201 [Startup:A.GoToSurface] Stopped 2024-03-18T18:33:11.201Z,1710786791.201 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-03-18T18:33:11.616Z,1710786791.616 [MissionManager](IMPORTANT): Started mission Default 2024-03-18T18:33:11.616Z,1710786791.616 [Default] Running Loop=1 2024-03-18T18:33:11.616Z,1710786791.616 [Default](DEBUG): Aggregate::initialize Default 2024-03-18T18:33:11.616Z,1710786791.616 [Default:B.GoToSurface] Running Loop=1 2024-03-18T18:33:11.616Z,1710786791.616 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-03-18T18:33:11.617Z,1710786791.617 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-03-18T18:33:11.617Z,1710786791.617 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-03-18T18:33:11.617Z,1710786791.617 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-03-18T18:33:11.618Z,1710786791.618 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-03-18T18:33:11.618Z,1710786791.618 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-03-18T18:33:11.618Z,1710786791.618 [Default:A.Wait] Running Loop=1 2024-03-18T18:33:11.618Z,1710786791.618 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-03-18T18:33:24.949Z,1710786804.949 [Default:A.Wait](INFO): Done Waiting. 2024-03-18T18:33:24.949Z,1710786804.949 [Default:A.Wait] Stopped 2024-03-18T18:33:24.949Z,1710786804.949 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T18:33:25.372Z,1710786805.372 [Default:CheckIn] Running Loop=1 2024-03-18T18:33:25.372Z,1710786805.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T18:33:25.372Z,1710786805.372 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T18:33:25.765Z,1710786805.765 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-03-18T18:33:47.783Z,1710786827.783 [CommandExec](IMPORTANT): got command burn on 2024-03-18T18:33:47.783Z,1710786827.783 [CommandExec](IMPORTANT): Activating dropweight wire 2024-03-18T18:34:06.215Z,1710786846.215 [CommandExec](IMPORTANT): got command burn off 2024-03-18T18:34:06.215Z,1710786846.215 [CommandExec](IMPORTANT): Deactivating dropweight wire 2024-03-18T18:34:18.291Z,1710786858.291 [CommandExec](IMPORTANT): got command strobe off 2024-03-18T18:34:18.292Z,1710786858.292 [CommandExec](IMPORTANT): Deactivating strobe 2024-03-18T18:34:23.499Z,1710786863.499 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-18T18:34:24.322Z,1710786864.322 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T18:34:24.330Z,1710786864.330 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-18T18:34:24.333Z,1710786864.333 [BPC1](INFO): Received data from all battery sticks. 2024-03-18T18:35:09.964Z,1710786909.964 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-03-18T18:35:27.751Z,1710786927.751 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-03-18T18:35:27.752Z,1710786927.752 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:35:27.762Z,1710786927.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:35:28.180Z,1710786928.180 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:35:28.180Z,1710786928.180 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-03-18T18:38:25.569Z,1710787105.569 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-18T18:33:25.4Z 2024-03-18T18:38:25.569Z,1710787105.569 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T18:38:25.569Z,1710787105.569 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T18:38:25.956Z,1710787105.956 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-03-18T18:38:28.832Z,1710787108.832 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-03-18T18:38:28.832Z,1710787108.832 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:38:28.891Z,1710787108.891 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:38:29.257Z,1710787109.257 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:38:29.257Z,1710787109.257 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-03-18T18:38:33.138Z,1710787113.138 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240318T182915/Courier0004.lzma 2024-03-18T18:38:34.140Z,1710787114.140 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0004.lzma.bak 2024-03-18T18:38:34.140Z,1710787114.140 [DataOverHttps](INFO): SBD MOMSN=19328036 2024-03-18T18:38:50.327Z,1710787130.327 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20240318T182915/Express0005.lzma 2024-03-18T18:38:51.328Z,1710787131.328 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0005.lzma.bak 2024-03-18T18:38:51.328Z,1710787131.328 [DataOverHttps](INFO): SBD MOMSN=19328038 2024-03-18T18:38:52.629Z,1710787132.629 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T18:38:52.629Z,1710787132.629 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T18:38:52.629Z,1710787132.629 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T18:40:09.384Z,1710787209.384 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-18T18:40:09.384Z,1710787209.384 [NAL9602] Data Fault, FailCount= 1 2024-03-18T18:40:09.384Z,1710787209.384 [NAL9602](ERROR): Data Fault 2024-03-18T18:40:09.423Z,1710787209.423 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-18T18:40:09.787Z,1710787209.787 [NAL9602](INFO): Powering down 2024-03-18T18:40:10.621Z,1710787210.621 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-18T18:40:10.621Z,1710787210.621 [NAL9602] No Fault, FailCount= 1 2024-03-18T18:40:40.088Z,1710787240.088 [NAL9602](INFO): Powering up NAL9602 2024-03-18T18:40:51.001Z,1710787251.001 [NAL9602](INFO): NAL9602 initialized 2024-03-18T18:41:29.828Z,1710787289.828 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-03-18T18:41:29.828Z,1710787289.828 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:41:29.839Z,1710787289.839 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:41:30.231Z,1710787290.231 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:41:30.232Z,1710787290.232 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-03-18T18:43:53.233Z,1710787433.233 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T18:43:53.233Z,1710787433.233 [Default:CheckIn:C.Wait] Stopped 2024-03-18T18:43:53.233Z,1710787433.233 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T18:43:53.233Z,1710787433.233 [Default:CheckIn:D] Running Loop=1 2024-03-18T18:43:53.616Z,1710787433.616 [Default:CheckIn:D] Stopped 2024-03-18T18:43:53.616Z,1710787433.616 [Default:CheckIn:E] Running Loop=1 2024-03-18T18:43:54.029Z,1710787434.029 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.699999 min 2024-03-18T18:43:54.029Z,1710787434.029 [Default:CheckIn:E] Stopped 2024-03-18T18:43:54.029Z,1710787434.029 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T18:43:54.030Z,1710787434.030 [Default:CheckIn] Stopped 2024-03-18T18:43:54.030Z,1710787434.030 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T18:43:54.030Z,1710787434.030 [Default:CheckIn](INFO): Running loop #2 2024-03-18T18:43:54.030Z,1710787434.030 [Default:CheckIn] Running Loop=2 2024-03-18T18:43:54.030Z,1710787434.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T18:43:54.030Z,1710787434.030 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T18:44:30.803Z,1710787470.803 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-03-18T18:44:30.803Z,1710787470.803 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:44:30.821Z,1710787470.821 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:44:31.228Z,1710787471.228 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:44:31.229Z,1710787471.229 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-03-18T18:47:31.806Z,1710787651.806 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-03-18T18:47:31.806Z,1710787651.806 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:47:31.825Z,1710787651.825 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:47:32.227Z,1710787652.227 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:47:32.228Z,1710787652.228 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-03-18T18:48:54.235Z,1710787734.235 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-03-18T18:43:54.0Z 2024-03-18T18:48:54.235Z,1710787734.235 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T18:48:54.236Z,1710787734.236 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T18:49:01.217Z,1710787741.217 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240318T182915/Courier0007.lzma 2024-03-18T18:49:02.220Z,1710787742.220 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0007.lzma.bak 2024-03-18T18:49:02.220Z,1710787742.220 [DataOverHttps](INFO): SBD MOMSN=19328061 2024-03-18T18:49:17.658Z,1710787757.658 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20240318T182915/Express0008.lzma 2024-03-18T18:49:18.660Z,1710787758.660 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0008.lzma.bak 2024-03-18T18:49:18.660Z,1710787758.660 [DataOverHttps](INFO): SBD MOMSN=19328063 2024-03-18T18:49:20.052Z,1710787760.052 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T18:49:20.052Z,1710787760.052 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T18:49:20.052Z,1710787760.052 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T18:50:32.828Z,1710787832.828 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-03-18T18:50:32.828Z,1710787832.828 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:50:32.839Z,1710787832.839 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:50:33.244Z,1710787833.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:50:33.244Z,1710787833.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-03-18T18:50:54.648Z,1710787854.648 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-03-18T18:50:54.648Z,1710787854.648 [NAL9602] Data Fault, FailCount= 2 2024-03-18T18:50:54.648Z,1710787854.648 [NAL9602](ERROR): Data Fault 2024-03-18T18:50:54.761Z,1710787854.761 [CBIT](ERROR): Data Fault in component: NAL9602 2024-03-18T18:50:55.052Z,1710787855.052 [NAL9602](INFO): Powering down 2024-03-18T18:50:55.957Z,1710787855.957 [CBIT](INFO): Clearing failed state for component NAL9602 2024-03-18T18:50:55.957Z,1710787855.957 [NAL9602] No Fault, FailCount= 2 2024-03-18T18:51:25.368Z,1710787885.368 [NAL9602](INFO): Powering up NAL9602 2024-03-18T18:51:36.280Z,1710787896.280 [NAL9602](INFO): NAL9602 initialized 2024-03-18T18:53:33.855Z,1710788013.855 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2024-03-18T18:53:33.855Z,1710788013.855 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:53:33.866Z,1710788013.866 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:53:34.275Z,1710788014.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:53:34.275Z,1710788014.275 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2024-03-18T18:54:20.732Z,1710788060.732 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T18:54:20.732Z,1710788060.732 [Default:CheckIn:C.Wait] Stopped 2024-03-18T18:54:20.732Z,1710788060.732 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T18:54:20.732Z,1710788060.732 [Default:CheckIn:D] Running Loop=1 2024-03-18T18:54:21.116Z,1710788061.116 [Default:CheckIn:D] Stopped 2024-03-18T18:54:21.116Z,1710788061.116 [Default:CheckIn:E] Running Loop=1 2024-03-18T18:54:21.522Z,1710788061.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.158325 min 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn:E] Stopped 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn] Stopped 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn](INFO): Running loop #3 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn] Running Loop=3 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T18:54:21.523Z,1710788061.523 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T18:56:34.867Z,1710788194.867 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2024-03-18T18:56:34.867Z,1710788194.867 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-03-18T18:56:34.878Z,1710788194.878 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-03-18T18:56:35.276Z,1710788195.276 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-03-18T18:56:35.276Z,1710788195.276 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2024-03-18T18:57:01.905Z,1710788221.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185700.00,A,3648.17242,N,12147.27255,W,1.516,33.56,180324,,,A*46 2024-03-18T18:57:01.949Z,1710788221.949 [NAL9602](INFO): GPS fix at 20240318T185700: (36.802874, -121.787876) 2024-03-18T18:57:01.991Z,1710788221.991 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T18:57:01.991Z,1710788221.991 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T18:57:12.318Z,1710788232.318 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240318T182915/Courier0010.lzma 2024-03-18T18:57:13.320Z,1710788233.320 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0010.lzma.bak 2024-03-18T18:57:13.320Z,1710788233.320 [DataOverHttps](INFO): SBD MOMSN=19328093 2024-03-18T18:57:30.227Z,1710788250.227 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20240318T182915/Express0011.lzma 2024-03-18T18:57:31.228Z,1710788251.228 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0011.lzma.bak 2024-03-18T18:57:31.228Z,1710788251.228 [DataOverHttps](INFO): SBD MOMSN=19328096 2024-03-18T18:57:32.617Z,1710788252.617 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T18:57:32.617Z,1710788252.617 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T18:57:32.617Z,1710788252.617 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T18:57:34.227Z,1710788254.227 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:02:17.239Z,1710788537.239 [CommandExec](IMPORTANT): got command ibit 2024-03-18T19:02:17.464Z,1710788537.464 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-03-18T19:02:17.464Z,1710788537.464 [IBIT](IMPORTANT): Beginning control surface checks. 2024-03-18T19:02:17.467Z,1710788537.467 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-18T19:02:19.044Z,1710788539.044 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190217.00,A,3648.14056,N,12147.22973,W,0.039,49.20,180324,,,D*4F 2024-03-18T19:02:19.047Z,1710788539.047 [NAL9602](INFO): GPS fix at 20240318T190217: (36.802343, -121.787162) 2024-03-18T19:02:28.389Z,1710788548.389 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-18T19:02:28.761Z,1710788548.761 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-18T19:02:36.827Z,1710788556.827 [NAL9602](INFO): SBD MO Status=0, MOMSN=47784, MT Status=0, MTMSN=0 2024-03-18T19:02:36.827Z,1710788556.827 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:02:38.032Z,1710788558.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190236.00,A,3648.13972,N,12147.22964,W,0.039,49.20,180324,,,D*42 2024-03-18T19:02:38.035Z,1710788558.035 [NAL9602](INFO): GPS fix at 20240318T190236: (36.802329, -121.787161) 2024-03-18T19:02:39.735Z,1710788559.735 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-03-18T19:02:40.187Z,1710788560.187 [CBIT](IMPORTANT): Beginning ground fault scan 2024-03-18T19:02:40.865Z,1710788560.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190239.00,A,3648.13949,N,12147.22967,W,0.019,49.20,180324,,,D*44 2024-03-18T19:02:40.867Z,1710788560.867 [NAL9602](INFO): GPS fix at 20240318T190239: (36.802325, -121.787161) 2024-03-18T19:02:44.093Z,1710788564.093 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190242.00,A,3648.13928,N,12147.22968,W,0.039,49.20,180324,,,D*42 2024-03-18T19:02:44.095Z,1710788564.095 [NAL9602](INFO): GPS fix at 20240318T190242: (36.802321, -121.787161) 2024-03-18T19:02:46.925Z,1710788566.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190245.00,A,3648.13903,N,12147.22970,W,0.058,49.20,180324,,,D*42 2024-03-18T19:02:46.927Z,1710788566.927 [NAL9602](INFO): GPS fix at 20240318T190245: (36.802317, -121.787162) 2024-03-18T19:02:50.153Z,1710788570.153 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190248.00,A,3648.13880,N,12147.22970,W,0.019,49.20,180324,,,D*40 2024-03-18T19:02:50.155Z,1710788570.155 [NAL9602](INFO): GPS fix at 20240318T190248: (36.802313, -121.787162) 2024-03-18T19:02:51.050Z,1710788571.050 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002285 CHAN A1 (24V): 0.000340 CHAN A2 (12V): -0.002338 CHAN A3 (5V): -0.001700 CHAN B0 (3.3V): -0.000025 CHAN B1 (3.15aV): -0.000226 CHAN B2 (3.15bV): -0.000163 CHAN B3 (GND): 0.000183 OPEN: -0.000647 Full Scale: +/- 1 mA 2024-03-18T19:02:52.989Z,1710788572.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190251.00,A,3648.13861,N,12147.22967,W,0.019,49.20,180324,,,D*41 2024-03-18T19:02:52.991Z,1710788572.991 [NAL9602](INFO): GPS fix at 20240318T190251: (36.802310, -121.787161) 2024-03-18T19:02:56.235Z,1710788576.235 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190254.00,A,3648.13849,N,12147.22959,W,0.058,49.20,180324,,,D*46 2024-03-18T19:02:56.237Z,1710788576.237 [NAL9602](INFO): GPS fix at 20240318T190254: (36.802308, -121.787160) 2024-03-18T19:02:59.057Z,1710788579.057 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190257.00,A,3648.13852,N,12147.22949,W,0.019,49.20,180324,,,D*4B 2024-03-18T19:02:59.060Z,1710788579.060 [NAL9602](INFO): GPS fix at 20240318T190257: (36.802309, -121.787158) 2024-03-18T19:03:01.885Z,1710788581.885 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190300.00,A,3648.13865,N,12147.22938,W,0.039,49.20,180324,,,D*48 2024-03-18T19:03:01.888Z,1710788581.888 [NAL9602](INFO): GPS fix at 20240318T190300: (36.802311, -121.787156) 2024-03-18T19:03:03.950Z,1710788583.950 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.802311 Longitude: -121.787155 2024-03-18T19:03:04.333Z,1710788584.333 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.611000 2024-03-18T19:03:04.334Z,1710788584.334 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-03-18T19:03:04.334Z,1710788584.334 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-03-18T19:03:04.732Z,1710788584.732 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-03-18T19:03:04.732Z,1710788584.732 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-03-18T19:03:04.733Z,1710788584.733 [IBIT](IMPORTANT): Pressure:9.693280 PSI 2024-03-18T19:03:04.733Z,1710788584.733 [IBIT](IMPORTANT): Humidity:12.690757 % 2024-03-18T19:03:05.135Z,1710788585.135 [IBIT](IMPORTANT): Vehicle Pitch:-1.319973 degrees 2024-03-18T19:03:05.135Z,1710788585.135 [IBIT](IMPORTANT): Vehicle Roll:6.061136 degrees 2024-03-18T19:03:05.135Z,1710788585.135 [IBIT](IMPORTANT): Vehicle Heading:7.928483 degrees 2024-03-18T19:03:05.544Z,1710788585.544 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-03-18T19:03:05.545Z,1710788585.545 [IBIT](IMPORTANT): buoyancyNeutral: 319.031769 cc 2024-03-18T19:03:05.545Z,1710788585.545 [IBIT](IMPORTANT): massDefault: -1.595007 cm 2024-03-18T19:03:05.545Z,1710788585.545 [IBIT](IMPORTANT): stopDepth: 250.000000 m 2024-03-18T19:03:05.545Z,1710788585.545 [IBIT](IMPORTANT): abortDepth: 270.000000 m 2024-03-18T19:03:05.545Z,1710788585.545 [IBIT](IMPORTANT): IBIT PASSED 2024-03-18T19:03:05.939Z,1710788585.939 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:03:05.939Z,1710788585.939 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:03:05.939Z,1710788585.939 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:03:05.939Z,1710788585.939 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:03:06.342Z,1710788586.342 [Default:CheckIn:D] Stopped 2024-03-18T19:03:06.342Z,1710788586.342 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.912089 min 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn:E] Stopped 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn] Stopped 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn](INFO): Running loop #4 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn] Running Loop=4 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:03:06.752Z,1710788586.752 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:03:08.757Z,1710788588.757 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190307.00,A,3648.13933,N,12147.22899,W,0.039,49.20,180324,,,D*47 2024-03-18T19:03:08.759Z,1710788588.759 [NAL9602](INFO): GPS fix at 20240318T190307: (36.802322, -121.787150) 2024-03-18T19:03:08.794Z,1710788588.794 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:03:08.795Z,1710788588.795 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:03:15.329Z,1710788595.329 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0013.lzma 2024-03-18T19:03:16.332Z,1710788596.332 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0013.lzma.bak 2024-03-18T19:03:16.332Z,1710788596.332 [DataOverHttps](INFO): SBD MOMSN=19328108 2024-03-18T19:03:17.888Z,1710788597.888 [CommandLine](INFO): End of History 2024-03-18T19:03:17.983Z,1710788597.983 [CommandLine](INFO): End of History 2024-03-18T19:03:17.984Z,1710788597.984 [CommandLine](INFO): End of History 2024-03-18T19:03:17.985Z,1710788597.985 [CommandLine](INFO): End of History 2024-03-18T19:03:18.101Z,1710788598.101 [CommandLine](INFO): End of History 2024-03-18T19:03:18.102Z,1710788598.102 [CommandLine](INFO): End of History 2024-03-18T19:03:18.103Z,1710788598.103 [CommandLine](INFO): End of History 2024-03-18T19:03:32.135Z,1710788612.135 [DataOverHttps](INFO): Sending 806 bytes from file Logs/20240318T182915/Express0014.lzma 2024-03-18T19:03:33.136Z,1710788613.136 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0014.lzma.bak 2024-03-18T19:03:33.136Z,1710788613.136 [DataOverHttps](INFO): SBD MOMSN=19328111 2024-03-18T19:03:34.222Z,1710788614.222 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:03:34.222Z,1710788614.222 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:03:34.223Z,1710788614.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:03:41.480Z,1710788621.480 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:03:41.791Z,1710788621.791 [CommandExec](IMPORTANT): got command failComponent 2024-03-18T19:03:41.791Z,1710788621.791 [CommandExec](IMPORTANT): Failed components: 2024-03-18T19:03:41.791Z,1710788621.791 [CommandExec](IMPORTANT): No failed Components. 2024-03-18T19:08:34.806Z,1710788914.806 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:08:34.806Z,1710788914.806 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:08:34.806Z,1710788914.806 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:08:34.806Z,1710788914.806 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:08:35.201Z,1710788915.201 [Default:CheckIn:D] Stopped 2024-03-18T19:08:35.201Z,1710788915.201 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.393083 min 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn:E] Stopped 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn] Stopped 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn](INFO): Running loop #5 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn] Running Loop=5 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:08:35.604Z,1710788915.604 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:08:37.612Z,1710788917.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190836.00,A,3648.14141,N,12147.23164,W,0.019,49.20,180324,,,D*4C 2024-03-18T19:08:37.626Z,1710788917.626 [NAL9602](INFO): GPS fix at 20240318T190836: (36.802357, -121.787194) 2024-03-18T19:08:37.637Z,1710788917.637 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:08:37.637Z,1710788917.637 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:08:44.577Z,1710788924.577 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0016.lzma 2024-03-18T19:08:45.580Z,1710788925.580 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0016.lzma.bak 2024-03-18T19:08:45.580Z,1710788925.580 [DataOverHttps](INFO): SBD MOMSN=19328149 2024-03-18T19:08:48.945Z,1710788928.945 [NAL9602](INFO): SBD MO Status=0, MOMSN=47785, MT Status=0, MTMSN=0 2024-03-18T19:08:48.945Z,1710788928.945 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:09:04.630Z,1710788944.630 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20240318T182915/Express0017.lzma 2024-03-18T19:09:05.632Z,1710788945.632 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0017.lzma.bak 2024-03-18T19:09:05.632Z,1710788945.632 [DataOverHttps](INFO): SBD MOMSN=19328152 2024-03-18T19:09:06.757Z,1710788946.757 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:09:06.757Z,1710788946.757 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:09:06.757Z,1710788946.757 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:09:19.670Z,1710788959.670 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:14:07.346Z,1710789247.346 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:14:07.347Z,1710789247.347 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:14:07.347Z,1710789247.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:14:07.347Z,1710789247.347 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:14:07.741Z,1710789247.741 [Default:CheckIn:D] Stopped 2024-03-18T19:14:07.741Z,1710789247.741 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:14:08.146Z,1710789248.146 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.935413 min 2024-03-18T19:14:08.146Z,1710789248.146 [Default:CheckIn:E] Stopped 2024-03-18T19:14:08.147Z,1710789248.147 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:14:08.147Z,1710789248.147 [Default:CheckIn] Stopped 2024-03-18T19:14:08.147Z,1710789248.147 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:14:08.147Z,1710789248.147 [Default:CheckIn](INFO): Running loop #6 2024-03-18T19:14:08.147Z,1710789248.147 [Default:CheckIn] Running Loop=6 2024-03-18T19:14:08.147Z,1710789248.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:14:08.147Z,1710789248.147 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:14:10.166Z,1710789250.166 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191409.00,A,3648.13895,N,12147.23042,W,0.019,49.20,180324,,,D*4F 2024-03-18T19:14:10.168Z,1710789250.168 [NAL9602](INFO): GPS fix at 20240318T191409: (36.802316, -121.787174) 2024-03-18T19:14:10.179Z,1710789250.179 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:14:10.179Z,1710789250.179 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:14:18.150Z,1710789258.150 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0019.lzma 2024-03-18T19:14:19.152Z,1710789259.152 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0019.lzma.bak 2024-03-18T19:14:19.153Z,1710789259.153 [DataOverHttps](INFO): SBD MOMSN=19328165 2024-03-18T19:14:23.517Z,1710789263.517 [NAL9602](INFO): SBD MO Status=0, MOMSN=47786, MT Status=0, MTMSN=0 2024-03-18T19:14:23.517Z,1710789263.517 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:14:38.058Z,1710789278.058 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240318T182915/Express0020.lzma 2024-03-18T19:14:39.060Z,1710789279.060 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0020.lzma.bak 2024-03-18T19:14:39.060Z,1710789279.060 [DataOverHttps](INFO): SBD MOMSN=19328168 2024-03-18T19:14:40.123Z,1710789280.123 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:14:40.123Z,1710789280.123 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:14:40.123Z,1710789280.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:14:54.243Z,1710789294.243 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:19:40.785Z,1710789580.785 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:19:40.785Z,1710789580.785 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:19:40.785Z,1710789580.785 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:19:40.786Z,1710789580.786 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:19:41.190Z,1710789581.190 [Default:CheckIn:D] Stopped 2024-03-18T19:19:41.190Z,1710789581.190 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:19:41.603Z,1710789581.603 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.492891 min 2024-03-18T19:19:41.603Z,1710789581.603 [Default:CheckIn:E] Stopped 2024-03-18T19:19:41.603Z,1710789581.603 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:19:41.604Z,1710789581.604 [Default:CheckIn] Stopped 2024-03-18T19:19:41.604Z,1710789581.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:19:41.604Z,1710789581.604 [Default:CheckIn](INFO): Running loop #7 2024-03-18T19:19:41.604Z,1710789581.604 [Default:CheckIn] Running Loop=7 2024-03-18T19:19:41.604Z,1710789581.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:19:41.604Z,1710789581.604 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:19:43.605Z,1710789583.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191942.00,A,3648.13838,N,12147.22914,W,0.039,49.20,180324,,,D*43 2024-03-18T19:19:43.607Z,1710789583.607 [NAL9602](INFO): GPS fix at 20240318T191942: (36.802306, -121.787152) 2024-03-18T19:19:43.635Z,1710789583.635 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:19:43.635Z,1710789583.635 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:19:50.691Z,1710789590.691 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0022.lzma 2024-03-18T19:19:51.692Z,1710789591.692 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0022.lzma.bak 2024-03-18T19:19:51.692Z,1710789591.692 [DataOverHttps](INFO): SBD MOMSN=19328180 2024-03-18T19:19:57.341Z,1710789597.341 [NAL9602](INFO): SBD MO Status=0, MOMSN=47787, MT Status=0, MTMSN=0 2024-03-18T19:19:57.341Z,1710789597.341 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:20:07.470Z,1710789607.470 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240318T182915/Express0023.lzma 2024-03-18T19:20:08.472Z,1710789608.472 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0023.lzma.bak 2024-03-18T19:20:08.472Z,1710789608.472 [DataOverHttps](INFO): SBD MOMSN=19328183 2024-03-18T19:20:09.873Z,1710789609.873 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:20:09.873Z,1710789609.873 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:20:09.873Z,1710789609.873 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:20:28.039Z,1710789628.039 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:25:10.516Z,1710789910.516 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:25:10.516Z,1710789910.516 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:25:10.516Z,1710789910.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:25:10.516Z,1710789910.516 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:25:10.925Z,1710789910.925 [Default:CheckIn:D] Stopped 2024-03-18T19:25:10.925Z,1710789910.925 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:25:11.358Z,1710789911.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.988485 min 2024-03-18T19:25:11.358Z,1710789911.358 [Default:CheckIn:E] Stopped 2024-03-18T19:25:11.358Z,1710789911.358 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:25:11.358Z,1710789911.358 [Default:CheckIn] Stopped 2024-03-18T19:25:11.359Z,1710789911.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:25:11.359Z,1710789911.359 [Default:CheckIn](INFO): Running loop #8 2024-03-18T19:25:11.359Z,1710789911.359 [Default:CheckIn] Running Loop=8 2024-03-18T19:25:11.359Z,1710789911.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:25:11.359Z,1710789911.359 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:25:13.337Z,1710789913.337 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192512.00,A,3648.13757,N,12147.22871,W,0.019,49.20,180324,,,D*4F 2024-03-18T19:25:13.339Z,1710789913.339 [NAL9602](INFO): GPS fix at 20240318T192512: (36.802293, -121.787145) 2024-03-18T19:25:13.350Z,1710789913.350 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:25:13.350Z,1710789913.350 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:25:20.941Z,1710789920.941 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0025.lzma 2024-03-18T19:25:21.944Z,1710789921.944 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0025.lzma.bak 2024-03-18T19:25:21.944Z,1710789921.944 [DataOverHttps](INFO): SBD MOMSN=19328193 2024-03-18T19:25:28.281Z,1710789928.281 [NAL9602](INFO): SBD MO Status=0, MOMSN=47788, MT Status=0, MTMSN=0 2024-03-18T19:25:28.281Z,1710789928.281 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:25:37.706Z,1710789937.706 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240318T182915/Express0026.lzma 2024-03-18T19:25:38.708Z,1710789938.708 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0026.lzma.bak 2024-03-18T19:25:38.708Z,1710789938.708 [DataOverHttps](INFO): SBD MOMSN=19328197 2024-03-18T19:25:40.004Z,1710789940.004 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:25:40.005Z,1710789940.005 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:25:40.005Z,1710789940.005 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:25:58.984Z,1710789958.984 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:30:40.644Z,1710790240.644 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:30:40.644Z,1710790240.644 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:30:40.644Z,1710790240.644 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:30:40.644Z,1710790240.644 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:30:41.045Z,1710790241.045 [Default:CheckIn:D] Stopped 2024-03-18T19:30:41.045Z,1710790241.045 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.490474 min 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn:E] Stopped 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn] Stopped 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn](INFO): Running loop #9 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn] Running Loop=9 2024-03-18T19:30:41.456Z,1710790241.456 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:30:41.456Z,1710790241.457 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:30:43.446Z,1710790243.446 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193042.00,A,3648.14089,N,12147.22980,W,0.058,49.20,180324,,,D*47 2024-03-18T19:30:43.448Z,1710790243.448 [NAL9602](INFO): GPS fix at 20240318T193042: (36.802348, -121.787163) 2024-03-18T19:30:43.508Z,1710790243.508 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:30:43.508Z,1710790243.508 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:30:52.474Z,1710790252.474 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0028.lzma 2024-03-18T19:30:53.476Z,1710790253.476 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0028.lzma.bak 2024-03-18T19:30:53.476Z,1710790253.476 [DataOverHttps](INFO): SBD MOMSN=19328209 2024-03-18T19:31:10.618Z,1710790270.618 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0029.lzma 2024-03-18T19:31:11.620Z,1710790271.620 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0029.lzma.bak 2024-03-18T19:31:11.620Z,1710790271.620 [DataOverHttps](INFO): SBD MOMSN=19328212 2024-03-18T19:31:12.989Z,1710790272.989 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:31:12.990Z,1710790272.990 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:31:12.990Z,1710790272.990 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:31:14.591Z,1710790274.591 [NAL9602](INFO): SBD MO Status=2, MOMSN=47789, MT Status=2, MTMSN=0 2024-03-18T19:31:14.591Z,1710790274.591 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T19:31:33.163Z,1710790293.163 [NAL9602](INFO): SBD MO Status=0, MOMSN=47789, MT Status=0, MTMSN=0 2024-03-18T19:31:33.163Z,1710790293.163 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:32:03.867Z,1710790323.867 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:35:31.667Z,1710790531.667 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:35:44.572Z,1710790544.572 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:35:57.502Z,1710790557.502 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:36:10.431Z,1710790570.431 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:36:13.677Z,1710790573.677 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:36:13.677Z,1710790573.677 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:36:13.677Z,1710790573.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:36:13.677Z,1710790573.677 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:36:14.091Z,1710790574.091 [Default:CheckIn:D] Stopped 2024-03-18T19:36:14.091Z,1710790574.091 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.041252 min 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn:E] Stopped 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn] Stopped 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn](INFO): Running loop #10 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn] Running Loop=10 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:36:14.488Z,1710790574.488 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:36:16.497Z,1710790576.497 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193615.00,A,3648.13871,N,12147.22923,W,0.019,49.20,180324,,,D*47 2024-03-18T19:36:16.500Z,1710790576.500 [NAL9602](INFO): GPS fix at 20240318T193615: (36.802312, -121.787154) 2024-03-18T19:36:16.511Z,1710790576.511 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:36:16.512Z,1710790576.512 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:36:23.412Z,1710790583.412 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:36:25.938Z,1710790585.938 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0031.lzma 2024-03-18T19:36:26.940Z,1710790586.940 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0031.lzma.bak 2024-03-18T19:36:26.940Z,1710790586.940 [DataOverHttps](INFO): SBD MOMSN=19328223 2024-03-18T19:36:35.553Z,1710790595.553 [NAL9602](INFO): SBD MO Status=0, MOMSN=47790, MT Status=0, MTMSN=0 2024-03-18T19:36:35.553Z,1710790595.553 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:36:36.352Z,1710790596.352 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:36:42.958Z,1710790602.958 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0032.lzma 2024-03-18T19:36:43.960Z,1710790603.960 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0032.lzma.bak 2024-03-18T19:36:43.960Z,1710790603.960 [DataOverHttps](INFO): SBD MOMSN=19328226 2024-03-18T19:36:45.265Z,1710790605.265 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:36:45.265Z,1710790605.265 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:36:45.265Z,1710790605.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:36:48.887Z,1710790608.887 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:37:06.321Z,1710790626.321 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:39:18.515Z,1710790758.515 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-18T19:39:29.827Z,1710790769.827 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T19:39:29.834Z,1710790769.834 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-18T19:39:29.836Z,1710790769.836 [BPC1](INFO): Received data from all battery sticks. 2024-03-18T19:41:45.755Z,1710790905.755 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:41:45.755Z,1710790905.755 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:41:45.755Z,1710790905.755 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:41:45.755Z,1710790905.755 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:41:46.143Z,1710790906.143 [Default:CheckIn:D] Stopped 2024-03-18T19:41:46.143Z,1710790906.143 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:41:46.545Z,1710790906.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.575439 min 2024-03-18T19:41:46.545Z,1710790906.545 [Default:CheckIn:E] Stopped 2024-03-18T19:41:46.545Z,1710790906.545 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:41:46.546Z,1710790906.546 [Default:CheckIn] Stopped 2024-03-18T19:41:46.546Z,1710790906.546 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:41:46.546Z,1710790906.546 [Default:CheckIn](INFO): Running loop #11 2024-03-18T19:41:46.546Z,1710790906.546 [Default:CheckIn] Running Loop=11 2024-03-18T19:41:46.546Z,1710790906.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:41:46.546Z,1710790906.546 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:41:48.559Z,1710790908.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194147.00,A,3648.13896,N,12147.22960,W,0.019,49.20,180324,,,D*4E 2024-03-18T19:41:48.561Z,1710790908.561 [NAL9602](INFO): GPS fix at 20240318T194147: (36.802316, -121.787160) 2024-03-18T19:41:48.572Z,1710790908.572 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:41:48.572Z,1710790908.572 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:41:55.705Z,1710790915.705 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240318T182915/Courier0034.lzma 2024-03-18T19:41:56.708Z,1710790916.708 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0034.lzma.bak 2024-03-18T19:41:56.708Z,1710790916.708 [DataOverHttps](INFO): SBD MOMSN=19328236 2024-03-18T19:42:12.526Z,1710790932.526 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0035.lzma 2024-03-18T19:42:13.528Z,1710790933.528 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0035.lzma.bak 2024-03-18T19:42:13.528Z,1710790933.528 [DataOverHttps](INFO): SBD MOMSN=19328239 2024-03-18T19:42:14.816Z,1710790934.816 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:42:14.816Z,1710790934.816 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:42:14.816Z,1710790934.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:42:45.549Z,1710790965.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=47791, MT Status=0, MTMSN=0 2024-03-18T19:42:45.549Z,1710790965.549 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:43:16.309Z,1710790996.309 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:47:15.297Z,1710791235.297 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:47:15.298Z,1710791235.298 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:47:15.298Z,1710791235.298 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:47:15.298Z,1710791235.298 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:47:15.736Z,1710791235.736 [Default:CheckIn:D] Stopped 2024-03-18T19:47:15.736Z,1710791235.736 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.068652 min 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn:E] Stopped 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn] Stopped 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn](INFO): Running loop #12 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn] Running Loop=12 2024-03-18T19:47:16.106Z,1710791236.106 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:47:16.107Z,1710791236.107 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:47:18.117Z,1710791238.117 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194717.00,A,3648.13864,N,12147.22886,W,0.039,0.00,180324,,,D*74 2024-03-18T19:47:18.129Z,1710791238.129 [NAL9602](INFO): GPS fix at 20240318T194717: (36.802311, -121.787148) 2024-03-18T19:47:18.140Z,1710791238.140 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:47:18.140Z,1710791238.140 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:47:25.085Z,1710791245.085 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0037.lzma 2024-03-18T19:47:26.088Z,1710791246.088 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0037.lzma.bak 2024-03-18T19:47:26.088Z,1710791246.088 [DataOverHttps](INFO): SBD MOMSN=19328250 2024-03-18T19:47:42.034Z,1710791262.034 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0038.lzma 2024-03-18T19:47:43.036Z,1710791263.036 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0038.lzma.bak 2024-03-18T19:47:43.036Z,1710791263.036 [DataOverHttps](INFO): SBD MOMSN=19328253 2024-03-18T19:47:44.402Z,1710791264.402 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:47:44.402Z,1710791264.402 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:47:44.402Z,1710791264.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:47:44.801Z,1710791264.801 [NAL9602](INFO): SBD MO Status=0, MOMSN=47792, MT Status=0, MTMSN=0 2024-03-18T19:47:44.801Z,1710791264.801 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:48:15.493Z,1710791295.493 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:52:44.840Z,1710791564.840 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:52:44.840Z,1710791564.840 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:52:44.840Z,1710791564.840 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:52:44.840Z,1710791564.840 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:52:45.253Z,1710791565.253 [Default:CheckIn:D] Stopped 2024-03-18T19:52:45.254Z,1710791565.254 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:52:45.657Z,1710791565.657 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.560620 min 2024-03-18T19:52:45.657Z,1710791565.657 [Default:CheckIn:E] Stopped 2024-03-18T19:52:45.658Z,1710791565.658 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:52:45.658Z,1710791565.658 [Default:CheckIn] Stopped 2024-03-18T19:52:45.662Z,1710791565.662 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:52:45.662Z,1710791565.662 [Default:CheckIn](INFO): Running loop #13 2024-03-18T19:52:45.662Z,1710791565.662 [Default:CheckIn] Running Loop=13 2024-03-18T19:52:45.662Z,1710791565.662 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:52:45.662Z,1710791565.662 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:52:47.661Z,1710791567.661 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195246.00,A,3648.13905,N,12147.22983,W,0.233,0.00,180324,,,D*7E 2024-03-18T19:52:47.663Z,1710791567.663 [NAL9602](INFO): GPS fix at 20240318T195246: (36.802318, -121.787164) 2024-03-18T19:52:47.698Z,1710791567.698 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:52:47.698Z,1710791567.698 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:52:58.041Z,1710791578.041 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0040.lzma 2024-03-18T19:52:59.044Z,1710791579.044 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0040.lzma.bak 2024-03-18T19:52:59.044Z,1710791579.044 [DataOverHttps](INFO): SBD MOMSN=19328264 2024-03-18T19:53:09.373Z,1710791589.373 [NAL9602](INFO): SBD MO Status=2, MOMSN=47793, MT Status=2, MTMSN=0 2024-03-18T19:53:09.373Z,1710791589.373 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T19:53:14.954Z,1710791594.954 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240318T182915/Express0041.lzma 2024-03-18T19:53:15.956Z,1710791595.956 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0041.lzma.bak 2024-03-18T19:53:15.956Z,1710791595.956 [DataOverHttps](INFO): SBD MOMSN=19328267 2024-03-18T19:53:17.052Z,1710791597.052 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:53:17.052Z,1710791597.052 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:53:17.052Z,1710791597.052 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T19:53:37.244Z,1710791617.244 [NAL9602](INFO): SBD MO Status=0, MOMSN=47793, MT Status=0, MTMSN=0 2024-03-18T19:53:37.244Z,1710791617.244 [NAL9602](INFO): No messages in MT queue 2024-03-18T19:54:07.948Z,1710791647.948 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T19:58:17.472Z,1710791897.472 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T19:58:17.472Z,1710791897.472 [Default:CheckIn:C.Wait] Stopped 2024-03-18T19:58:17.472Z,1710791897.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T19:58:17.472Z,1710791897.472 [Default:CheckIn:D] Running Loop=1 2024-03-18T19:58:17.868Z,1710791897.868 [Default:CheckIn:D] Stopped 2024-03-18T19:58:17.868Z,1710791897.868 [Default:CheckIn:E] Running Loop=1 2024-03-18T19:58:18.280Z,1710791898.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.104191 min 2024-03-18T19:58:18.280Z,1710791898.280 [Default:CheckIn:E] Stopped 2024-03-18T19:58:18.280Z,1710791898.280 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T19:58:18.281Z,1710791898.281 [Default:CheckIn] Stopped 2024-03-18T19:58:18.281Z,1710791898.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T19:58:18.281Z,1710791898.281 [Default:CheckIn](INFO): Running loop #14 2024-03-18T19:58:18.281Z,1710791898.281 [Default:CheckIn] Running Loop=14 2024-03-18T19:58:18.281Z,1710791898.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T19:58:18.281Z,1710791898.281 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T19:58:20.260Z,1710791900.260 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195819.00,A,3648.13954,N,12147.22962,W,0.097,0.00,180324,,,D*79 2024-03-18T19:58:20.262Z,1710791900.262 [NAL9602](INFO): GPS fix at 20240318T195819: (36.802326, -121.787160) 2024-03-18T19:58:20.281Z,1710791900.281 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T19:58:20.281Z,1710791900.281 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T19:58:30.709Z,1710791910.709 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240318T182915/Courier0043.lzma 2024-03-18T19:58:31.712Z,1710791911.712 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0043.lzma.bak 2024-03-18T19:58:31.712Z,1710791911.712 [DataOverHttps](INFO): SBD MOMSN=19328279 2024-03-18T19:58:47.722Z,1710791927.722 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240318T182915/Express0044.lzma 2024-03-18T19:58:48.724Z,1710791928.724 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0044.lzma.bak 2024-03-18T19:58:48.724Z,1710791928.724 [DataOverHttps](INFO): SBD MOMSN=19328282 2024-03-18T19:58:49.828Z,1710791929.828 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T19:58:49.828Z,1710791929.828 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T19:58:49.828Z,1710791929.828 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:00:30.064Z,1710792030.064 [NAL9602](INFO): SBD MO Status=0, MOMSN=47794, MT Status=0, MTMSN=0 2024-03-18T20:00:30.064Z,1710792030.064 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:01:00.779Z,1710792060.779 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:03:50.474Z,1710792230.474 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:03:50.474Z,1710792230.474 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:03:50.474Z,1710792230.474 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:03:50.474Z,1710792230.474 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:03:50.876Z,1710792230.876 [Default:CheckIn:D] Stopped 2024-03-18T20:03:50.876Z,1710792230.876 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.654329 min 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn:E] Stopped 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn] Stopped 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn](INFO): Running loop #15 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn] Running Loop=15 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:03:51.273Z,1710792231.273 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:03:53.284Z,1710792233.284 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200352.00,A,3648.13981,N,12147.22976,W,0.000,0.00,180324,,,D*71 2024-03-18T20:03:53.286Z,1710792233.286 [NAL9602](INFO): GPS fix at 20240318T200352: (36.802330, -121.787163) 2024-03-18T20:03:53.297Z,1710792233.297 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:03:53.297Z,1710792233.297 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:04:00.517Z,1710792240.517 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0046.lzma 2024-03-18T20:04:01.520Z,1710792241.520 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0046.lzma.bak 2024-03-18T20:04:01.520Z,1710792241.520 [DataOverHttps](INFO): SBD MOMSN=19328292 2024-03-18T20:04:07.021Z,1710792247.021 [NAL9602](INFO): SBD MO Status=0, MOMSN=47795, MT Status=0, MTMSN=0 2024-03-18T20:04:07.021Z,1710792247.021 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:04:17.458Z,1710792257.458 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0047.lzma 2024-03-18T20:04:18.460Z,1710792258.460 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0047.lzma.bak 2024-03-18T20:04:18.460Z,1710792258.460 [DataOverHttps](INFO): SBD MOMSN=19328295 2024-03-18T20:04:19.584Z,1710792259.584 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:04:19.584Z,1710792259.584 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:04:19.584Z,1710792259.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:04:37.794Z,1710792277.794 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:09:19.983Z,1710792559.983 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:09:19.983Z,1710792559.983 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:09:19.983Z,1710792559.983 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:09:19.983Z,1710792559.983 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:09:20.300Z,1710792560.300 [Default:CheckIn:D] Stopped 2024-03-18T20:09:20.300Z,1710792560.300 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:09:20.708Z,1710792560.708 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.144735 min 2024-03-18T20:09:20.708Z,1710792560.708 [Default:CheckIn:E] Stopped 2024-03-18T20:09:20.708Z,1710792560.708 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:09:20.708Z,1710792560.708 [Default:CheckIn] Stopped 2024-03-18T20:09:20.708Z,1710792560.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:09:20.709Z,1710792560.709 [Default:CheckIn](INFO): Running loop #16 2024-03-18T20:09:20.709Z,1710792560.709 [Default:CheckIn] Running Loop=16 2024-03-18T20:09:20.709Z,1710792560.709 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:09:20.709Z,1710792560.709 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:09:22.727Z,1710792562.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200922.00,A,3648.13952,N,12147.22981,W,0.019,0.00,180324,,,D*72 2024-03-18T20:09:22.729Z,1710792562.729 [NAL9602](INFO): GPS fix at 20240318T200922: (36.802325, -121.787164) 2024-03-18T20:09:22.740Z,1710792562.740 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:09:22.740Z,1710792562.740 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:09:30.505Z,1710792570.505 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240318T182915/Courier0049.lzma 2024-03-18T20:09:31.508Z,1710792571.508 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0049.lzma.bak 2024-03-18T20:09:31.508Z,1710792571.508 [DataOverHttps](INFO): SBD MOMSN=19328306 2024-03-18T20:09:44.941Z,1710792584.941 [NAL9602](INFO): SBD MO Status=0, MOMSN=47796, MT Status=0, MTMSN=0 2024-03-18T20:09:44.941Z,1710792584.941 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:09:47.394Z,1710792587.394 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0050.lzma 2024-03-18T20:09:48.396Z,1710792588.396 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0050.lzma.bak 2024-03-18T20:09:48.396Z,1710792588.396 [DataOverHttps](INFO): SBD MOMSN=19328309 2024-03-18T20:09:49.801Z,1710792589.801 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:09:49.801Z,1710792589.801 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:09:49.802Z,1710792589.802 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:10:15.639Z,1710792615.639 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:14:50.618Z,1710792890.618 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:14:50.618Z,1710792890.618 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:14:50.619Z,1710792890.619 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:14:50.619Z,1710792890.619 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:14:50.920Z,1710792890.920 [Default:CheckIn:D] Stopped 2024-03-18T20:14:50.920Z,1710792890.920 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:14:51.359Z,1710792891.359 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.655062 min 2024-03-18T20:14:51.359Z,1710792891.359 [Default:CheckIn:E] Stopped 2024-03-18T20:14:51.359Z,1710792891.359 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:14:51.360Z,1710792891.360 [Default:CheckIn] Stopped 2024-03-18T20:14:51.360Z,1710792891.360 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:14:51.360Z,1710792891.360 [Default:CheckIn](INFO): Running loop #17 2024-03-18T20:14:51.360Z,1710792891.360 [Default:CheckIn] Running Loop=17 2024-03-18T20:14:51.360Z,1710792891.360 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:14:51.360Z,1710792891.360 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:14:53.330Z,1710792893.330 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201452.00,A,3648.13985,N,12147.22959,W,0.019,0.00,180324,,,D*76 2024-03-18T20:14:53.332Z,1710792893.332 [NAL9602](INFO): GPS fix at 20240318T201452: (36.802331, -121.787160) 2024-03-18T20:14:53.343Z,1710792893.343 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:14:53.343Z,1710792893.343 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:15:02.813Z,1710792902.813 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0052.lzma 2024-03-18T20:15:03.816Z,1710792903.816 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0052.lzma.bak 2024-03-18T20:15:03.816Z,1710792903.816 [DataOverHttps](INFO): SBD MOMSN=19328313 2024-03-18T20:15:19.646Z,1710792919.646 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0053.lzma 2024-03-18T20:15:20.648Z,1710792920.648 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0053.lzma.bak 2024-03-18T20:15:20.648Z,1710792920.648 [DataOverHttps](INFO): SBD MOMSN=19328316 2024-03-18T20:15:21.668Z,1710792921.668 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:15:21.668Z,1710792921.668 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:15:21.668Z,1710792921.668 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:15:23.697Z,1710792923.697 [NAL9602](INFO): SBD MO Status=0, MOMSN=47797, MT Status=0, MTMSN=0 2024-03-18T20:15:23.697Z,1710792923.697 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:15:54.381Z,1710792954.381 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:20:22.253Z,1710793222.253 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:20:22.254Z,1710793222.254 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:20:22.254Z,1710793222.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:20:22.254Z,1710793222.254 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:20:22.676Z,1710793222.676 [Default:CheckIn:D] Stopped 2024-03-18T20:20:22.676Z,1710793222.676 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:20:23.060Z,1710793223.060 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.184334 min 2024-03-18T20:20:23.060Z,1710793223.060 [Default:CheckIn:E] Stopped 2024-03-18T20:20:23.060Z,1710793223.060 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:20:23.060Z,1710793223.060 [Default:CheckIn] Stopped 2024-03-18T20:20:23.060Z,1710793223.060 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:20:23.061Z,1710793223.061 [Default:CheckIn](INFO): Running loop #18 2024-03-18T20:20:23.061Z,1710793223.061 [Default:CheckIn] Running Loop=18 2024-03-18T20:20:23.061Z,1710793223.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:20:23.061Z,1710793223.061 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:20:25.061Z,1710793225.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202024.00,A,3648.13959,N,12147.22985,W,0.058,0.00,180324,,,D*75 2024-03-18T20:20:25.074Z,1710793225.074 [NAL9602](INFO): GPS fix at 20240318T202024: (36.802326, -121.787164) 2024-03-18T20:20:25.099Z,1710793225.099 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:20:25.099Z,1710793225.099 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:20:33.110Z,1710793233.110 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0055.lzma 2024-03-18T20:20:34.112Z,1710793234.112 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0055.lzma.bak 2024-03-18T20:20:34.112Z,1710793234.112 [DataOverHttps](INFO): SBD MOMSN=19328333 2024-03-18T20:20:36.372Z,1710793236.372 [NAL9602](INFO): SBD MO Status=0, MOMSN=47798, MT Status=0, MTMSN=0 2024-03-18T20:20:36.372Z,1710793236.372 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:20:51.302Z,1710793251.302 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240318T182915/Express0056.lzma 2024-03-18T20:20:52.304Z,1710793252.304 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0056.lzma.bak 2024-03-18T20:20:52.304Z,1710793252.304 [DataOverHttps](INFO): SBD MOMSN=19328336 2024-03-18T20:20:53.370Z,1710793253.370 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:20:53.370Z,1710793253.370 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:20:53.370Z,1710793253.370 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:21:07.103Z,1710793267.103 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:25:53.965Z,1710793553.965 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:25:53.965Z,1710793553.965 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:25:53.965Z,1710793553.965 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:25:53.965Z,1710793553.965 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:25:54.370Z,1710793554.370 [Default:CheckIn:D] Stopped 2024-03-18T20:25:54.370Z,1710793554.370 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:25:54.784Z,1710793554.784 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.712557 min 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn:E] Stopped 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn] Stopped 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn](INFO): Running loop #19 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn] Running Loop=19 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:25:54.785Z,1710793554.785 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:25:56.789Z,1710793556.789 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202556.00,A,3648.13930,N,12147.23057,W,0.058,17.44,180324,,,D*4B 2024-03-18T20:25:56.791Z,1710793556.791 [NAL9602](INFO): GPS fix at 20240318T202556: (36.802322, -121.787176) 2024-03-18T20:25:56.802Z,1710793556.802 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:25:56.802Z,1710793556.802 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:26:03.781Z,1710793563.781 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0058.lzma 2024-03-18T20:26:04.784Z,1710793564.784 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0058.lzma.bak 2024-03-18T20:26:04.784Z,1710793564.784 [DataOverHttps](INFO): SBD MOMSN=19328346 2024-03-18T20:26:22.406Z,1710793582.406 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0059.lzma 2024-03-18T20:26:23.408Z,1710793583.408 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0059.lzma.bak 2024-03-18T20:26:23.408Z,1710793583.408 [DataOverHttps](INFO): SBD MOMSN=19328349 2024-03-18T20:26:24.691Z,1710793584.691 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:26:24.691Z,1710793584.691 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:26:24.691Z,1710793584.691 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:26:33.157Z,1710793593.157 [NAL9602](INFO): SBD MO Status=2, MOMSN=47799, MT Status=2, MTMSN=0 2024-03-18T20:26:33.157Z,1710793593.157 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T20:26:48.113Z,1710793608.113 [NAL9602](INFO): SBD MO Status=0, MOMSN=47799, MT Status=0, MTMSN=0 2024-03-18T20:26:48.113Z,1710793608.113 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:27:18.801Z,1710793638.801 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:31:25.261Z,1710793885.261 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:31:25.261Z,1710793885.261 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:31:25.261Z,1710793885.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:31:25.261Z,1710793885.261 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:31:25.665Z,1710793885.665 [Default:CheckIn:D] Stopped 2024-03-18T20:31:25.666Z,1710793885.666 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.234155 min 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn:E] Stopped 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn] Stopped 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn](INFO): Running loop #20 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn] Running Loop=20 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:31:26.067Z,1710793886.067 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:31:28.082Z,1710793888.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203127.00,A,3648.13926,N,12147.23010,W,0.019,17.44,180324,,,D*49 2024-03-18T20:31:28.084Z,1710793888.084 [NAL9602](INFO): GPS fix at 20240318T203127: (36.802321, -121.787168) 2024-03-18T20:31:28.113Z,1710793888.113 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:31:28.113Z,1710793888.113 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:31:35.641Z,1710793895.641 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0061.lzma 2024-03-18T20:31:36.644Z,1710793896.644 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0061.lzma.bak 2024-03-18T20:31:36.644Z,1710793896.644 [DataOverHttps](INFO): SBD MOMSN=19328360 2024-03-18T20:31:52.414Z,1710793912.414 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0062.lzma 2024-03-18T20:31:53.416Z,1710793913.416 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0062.lzma.bak 2024-03-18T20:31:53.417Z,1710793913.417 [DataOverHttps](INFO): SBD MOMSN=19328363 2024-03-18T20:31:54.751Z,1710793914.751 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:31:54.751Z,1710793914.751 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:31:54.751Z,1710793914.751 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:31:57.165Z,1710793917.165 [NAL9602](INFO): SBD MO Status=0, MOMSN=47800, MT Status=0, MTMSN=0 2024-03-18T20:31:57.165Z,1710793917.165 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:32:27.871Z,1710793947.871 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:36:55.373Z,1710794215.373 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:36:55.373Z,1710794215.373 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:36:55.373Z,1710794215.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:36:55.373Z,1710794215.373 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:36:55.742Z,1710794215.742 [Default:CheckIn:D] Stopped 2024-03-18T20:36:55.742Z,1710794215.742 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:36:56.155Z,1710794216.155 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.735425 min 2024-03-18T20:36:56.155Z,1710794216.155 [Default:CheckIn:E] Stopped 2024-03-18T20:36:56.155Z,1710794216.155 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:36:56.155Z,1710794216.155 [Default:CheckIn] Stopped 2024-03-18T20:36:56.156Z,1710794216.156 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:36:56.156Z,1710794216.156 [Default:CheckIn](INFO): Running loop #21 2024-03-18T20:36:56.156Z,1710794216.156 [Default:CheckIn] Running Loop=21 2024-03-18T20:36:56.156Z,1710794216.156 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:36:56.156Z,1710794216.156 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:36:58.157Z,1710794218.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203657.00,A,3648.13829,N,12147.22929,W,0.019,17.44,180324,,,D*45 2024-03-18T20:36:58.190Z,1710794218.190 [NAL9602](INFO): GPS fix at 20240318T203657: (36.802305, -121.787155) 2024-03-18T20:36:58.215Z,1710794218.215 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:36:58.215Z,1710794218.215 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:37:05.110Z,1710794225.110 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240318T182915/Courier0064.lzma 2024-03-18T20:37:06.112Z,1710794226.112 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0064.lzma.bak 2024-03-18T20:37:06.112Z,1710794226.112 [DataOverHttps](INFO): SBD MOMSN=19328373 2024-03-18T20:37:13.109Z,1710794233.109 [NAL9602](INFO): SBD MO Status=0, MOMSN=47801, MT Status=0, MTMSN=0 2024-03-18T20:37:13.109Z,1710794233.109 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:37:22.122Z,1710794242.122 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0065.lzma 2024-03-18T20:37:23.124Z,1710794243.124 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0065.lzma.bak 2024-03-18T20:37:23.124Z,1710794243.124 [DataOverHttps](INFO): SBD MOMSN=19328376 2024-03-18T20:37:24.437Z,1710794244.437 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:37:24.437Z,1710794244.437 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:37:24.437Z,1710794244.437 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:37:43.811Z,1710794263.811 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:40:36.717Z,1710794436.717 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:40:50.054Z,1710794450.054 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:41:02.169Z,1710794462.169 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:41:15.501Z,1710794475.501 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:41:28.833Z,1710794488.833 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:41:41.359Z,1710794501.359 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:41:54.688Z,1710794514.688 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:42:25.063Z,1710794545.063 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:42:25.063Z,1710794545.063 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:42:25.063Z,1710794545.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:42:25.063Z,1710794545.063 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:42:25.479Z,1710794545.479 [Default:CheckIn:D] Stopped 2024-03-18T20:42:25.479Z,1710794545.479 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:42:25.852Z,1710794545.852 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.231038 min 2024-03-18T20:42:25.852Z,1710794545.852 [Default:CheckIn:E] Stopped 2024-03-18T20:42:25.852Z,1710794545.852 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:42:25.852Z,1710794545.852 [Default:CheckIn] Stopped 2024-03-18T20:42:25.852Z,1710794545.852 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:42:25.852Z,1710794545.852 [Default:CheckIn](INFO): Running loop #22 2024-03-18T20:42:25.852Z,1710794545.852 [Default:CheckIn] Running Loop=22 2024-03-18T20:42:25.853Z,1710794545.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:42:25.853Z,1710794545.853 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:42:27.826Z,1710794547.826 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204227.00,A,3648.14033,N,12147.23067,W,0.039,17.44,180324,,,D*45 2024-03-18T20:42:27.828Z,1710794547.828 [NAL9602](INFO): GPS fix at 20240318T204227: (36.802339, -121.787178) 2024-03-18T20:42:27.840Z,1710794547.840 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:42:27.840Z,1710794547.840 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:42:35.633Z,1710794555.633 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0067.lzma 2024-03-18T20:42:36.636Z,1710794556.636 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0067.lzma.bak 2024-03-18T20:42:36.636Z,1710794556.636 [DataOverHttps](INFO): SBD MOMSN=19328387 2024-03-18T20:42:55.910Z,1710794575.910 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240318T182915/Express0068.lzma 2024-03-18T20:42:56.555Z,1710794576.555 [NAL9602](INFO): SBD MO Status=2, MOMSN=47802, MT Status=2, MTMSN=0 2024-03-18T20:42:56.555Z,1710794576.555 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T20:42:56.912Z,1710794576.912 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0068.lzma.bak 2024-03-18T20:42:56.912Z,1710794576.912 [DataOverHttps](INFO): SBD MOMSN=19328390 2024-03-18T20:42:58.179Z,1710794578.179 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:42:58.179Z,1710794578.179 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:42:58.179Z,1710794578.179 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:43:20.407Z,1710794600.407 [NAL9602](INFO): SBD MO Status=2, MOMSN=47802, MT Status=2, MTMSN=0 2024-03-18T20:43:20.407Z,1710794600.407 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T20:43:39.785Z,1710794619.785 [NAL9602](INFO): SBD MO Status=2, MOMSN=47802, MT Status=2, MTMSN=0 2024-03-18T20:43:39.785Z,1710794619.785 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T20:44:00.809Z,1710794640.809 [NAL9602](INFO): SBD MO Status=0, MOMSN=47802, MT Status=0, MTMSN=0 2024-03-18T20:44:00.809Z,1710794640.809 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:44:27.863Z,1710794667.863 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-18T20:44:31.498Z,1710794671.498 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:44:36.345Z,1710794676.345 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T20:44:36.352Z,1710794676.352 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-18T20:44:36.355Z,1710794676.355 [BPC1](INFO): Received data from all battery sticks. 2024-03-18T20:47:58.807Z,1710794878.807 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:47:58.807Z,1710794878.807 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:47:58.807Z,1710794878.807 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:47:58.807Z,1710794878.807 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:47:59.186Z,1710794879.186 [Default:CheckIn:D] Stopped 2024-03-18T20:47:59.186Z,1710794879.186 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:47:59.599Z,1710794879.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.792830 min 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn:E] Stopped 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn] Stopped 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn](INFO): Running loop #23 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn] Running Loop=23 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:47:59.600Z,1710794879.600 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:48:01.601Z,1710794881.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204800.00,A,3648.14009,N,12147.23059,W,0.019,17.44,180324,,,D*4C 2024-03-18T20:48:01.613Z,1710794881.613 [NAL9602](INFO): GPS fix at 20240318T204800: (36.802335, -121.787177) 2024-03-18T20:48:01.624Z,1710794881.624 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:48:01.625Z,1710794881.625 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:48:08.853Z,1710794888.853 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240318T182915/Courier0070.lzma 2024-03-18T20:48:09.856Z,1710794889.856 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0070.lzma.bak 2024-03-18T20:48:09.856Z,1710794889.856 [DataOverHttps](INFO): SBD MOMSN=19328400 2024-03-18T20:48:16.557Z,1710794896.557 [NAL9602](INFO): SBD MO Status=0, MOMSN=47803, MT Status=0, MTMSN=0 2024-03-18T20:48:16.557Z,1710794896.557 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:48:28.803Z,1710794908.803 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0071.lzma 2024-03-18T20:48:29.804Z,1710794909.804 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0071.lzma.bak 2024-03-18T20:48:29.804Z,1710794909.804 [DataOverHttps](INFO): SBD MOMSN=19328403 2024-03-18T20:48:31.156Z,1710794911.156 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:48:31.156Z,1710794911.156 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:48:31.156Z,1710794911.156 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:48:47.301Z,1710794927.301 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:53:31.747Z,1710795211.747 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:53:31.747Z,1710795211.747 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:53:31.747Z,1710795211.747 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:53:31.748Z,1710795211.748 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:53:32.142Z,1710795212.142 [Default:CheckIn:D] Stopped 2024-03-18T20:53:32.142Z,1710795212.142 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:53:32.547Z,1710795212.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.342090 min 2024-03-18T20:53:32.547Z,1710795212.547 [Default:CheckIn:E] Stopped 2024-03-18T20:53:32.547Z,1710795212.547 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:53:32.547Z,1710795212.547 [Default:CheckIn] Stopped 2024-03-18T20:53:32.547Z,1710795212.547 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:53:32.548Z,1710795212.548 [Default:CheckIn](INFO): Running loop #24 2024-03-18T20:53:32.548Z,1710795212.548 [Default:CheckIn] Running Loop=24 2024-03-18T20:53:32.548Z,1710795212.548 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:53:32.548Z,1710795212.548 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:53:34.553Z,1710795214.553 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205333.00,A,3648.14024,N,12147.22953,W,0.039,17.44,180324,,,D*49 2024-03-18T20:53:34.555Z,1710795214.555 [NAL9602](INFO): GPS fix at 20240318T205333: (36.802337, -121.787159) 2024-03-18T20:53:34.567Z,1710795214.567 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:53:34.567Z,1710795214.567 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:53:41.529Z,1710795221.529 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0073.lzma 2024-03-18T20:53:42.532Z,1710795222.532 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0073.lzma.bak 2024-03-18T20:53:42.532Z,1710795222.532 [DataOverHttps](INFO): SBD MOMSN=19328414 2024-03-18T20:53:47.077Z,1710795227.077 [NAL9602](INFO): SBD MO Status=0, MOMSN=47804, MT Status=0, MTMSN=0 2024-03-18T20:53:47.077Z,1710795227.077 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:53:58.338Z,1710795238.338 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0074.lzma 2024-03-18T20:53:59.340Z,1710795239.340 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0074.lzma.bak 2024-03-18T20:53:59.340Z,1710795239.340 [DataOverHttps](INFO): SBD MOMSN=19328417 2024-03-18T20:54:00.445Z,1710795240.445 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:54:00.446Z,1710795240.446 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:54:00.446Z,1710795240.446 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:54:17.785Z,1710795257.785 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T20:59:01.019Z,1710795541.019 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T20:59:01.019Z,1710795541.019 [Default:CheckIn:C.Wait] Stopped 2024-03-18T20:59:01.019Z,1710795541.019 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T20:59:01.019Z,1710795541.019 [Default:CheckIn:D] Running Loop=1 2024-03-18T20:59:01.415Z,1710795541.415 [Default:CheckIn:D] Stopped 2024-03-18T20:59:01.415Z,1710795541.415 [Default:CheckIn:E] Running Loop=1 2024-03-18T20:59:01.811Z,1710795541.811 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.829980 min 2024-03-18T20:59:01.811Z,1710795541.811 [Default:CheckIn:E] Stopped 2024-03-18T20:59:01.811Z,1710795541.811 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T20:59:01.811Z,1710795541.811 [Default:CheckIn] Stopped 2024-03-18T20:59:01.811Z,1710795541.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T20:59:01.811Z,1710795541.811 [Default:CheckIn](INFO): Running loop #25 2024-03-18T20:59:01.811Z,1710795541.811 [Default:CheckIn] Running Loop=25 2024-03-18T20:59:01.812Z,1710795541.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T20:59:01.812Z,1710795541.812 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T20:59:03.821Z,1710795543.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205903.00,A,3648.13894,N,12147.22916,W,0.019,17.44,180324,,,D*47 2024-03-18T20:59:03.833Z,1710795543.833 [NAL9602](INFO): GPS fix at 20240318T205903: (36.802316, -121.787153) 2024-03-18T20:59:03.844Z,1710795543.844 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T20:59:03.844Z,1710795543.844 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T20:59:10.766Z,1710795550.766 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0076.lzma 2024-03-18T20:59:11.768Z,1710795551.768 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0076.lzma.bak 2024-03-18T20:59:11.768Z,1710795551.768 [DataOverHttps](INFO): SBD MOMSN=19328427 2024-03-18T20:59:20.381Z,1710795560.381 [NAL9602](INFO): SBD MO Status=0, MOMSN=47805, MT Status=0, MTMSN=0 2024-03-18T20:59:20.381Z,1710795560.381 [NAL9602](INFO): No messages in MT queue 2024-03-18T20:59:27.946Z,1710795567.946 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0077.lzma 2024-03-18T20:59:28.948Z,1710795568.948 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0077.lzma.bak 2024-03-18T20:59:28.948Z,1710795568.948 [DataOverHttps](INFO): SBD MOMSN=19328430 2024-03-18T20:59:30.089Z,1710795570.089 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T20:59:30.089Z,1710795570.089 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T20:59:30.090Z,1710795570.090 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T20:59:51.087Z,1710795591.087 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:04:30.678Z,1710795870.678 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:04:30.678Z,1710795870.678 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:04:30.678Z,1710795870.678 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:04:30.679Z,1710795870.679 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:04:31.074Z,1710795871.074 [Default:CheckIn:D] Stopped 2024-03-18T21:04:31.074Z,1710795871.074 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:04:31.473Z,1710795871.473 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 151.324300 min 2024-03-18T21:04:31.473Z,1710795871.473 [Default:CheckIn:E] Stopped 2024-03-18T21:04:31.473Z,1710795871.473 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:04:31.473Z,1710795871.473 [Default:CheckIn] Stopped 2024-03-18T21:04:31.473Z,1710795871.473 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:04:31.474Z,1710795871.474 [Default:CheckIn](INFO): Running loop #26 2024-03-18T21:04:31.474Z,1710795871.474 [Default:CheckIn] Running Loop=26 2024-03-18T21:04:31.474Z,1710795871.474 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:04:31.474Z,1710795871.474 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:04:33.487Z,1710795873.487 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210432.00,A,3648.13820,N,12147.22919,W,0.019,0.00,180324,,,D*7A 2024-03-18T21:04:33.489Z,1710795873.489 [NAL9602](INFO): GPS fix at 20240318T210432: (36.802303, -121.787153) 2024-03-18T21:04:33.501Z,1710795873.501 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:04:33.501Z,1710795873.501 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:04:40.685Z,1710795880.685 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240318T182915/Courier0079.lzma 2024-03-18T21:04:41.688Z,1710795881.688 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0079.lzma.bak 2024-03-18T21:04:41.688Z,1710795881.688 [DataOverHttps](INFO): SBD MOMSN=19328441 2024-03-18T21:04:43.993Z,1710795883.993 [NAL9602](INFO): SBD MO Status=0, MOMSN=47806, MT Status=0, MTMSN=0 2024-03-18T21:04:43.993Z,1710795883.993 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:05:00.530Z,1710795900.530 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0080.lzma 2024-03-18T21:05:01.532Z,1710795901.532 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0080.lzma.bak 2024-03-18T21:05:01.532Z,1710795901.532 [DataOverHttps](INFO): SBD MOMSN=19328445 2024-03-18T21:05:02.633Z,1710795902.633 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:05:02.633Z,1710795902.633 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:05:02.634Z,1710795902.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:05:14.741Z,1710795914.741 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:10:03.226Z,1710796203.226 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:10:03.226Z,1710796203.226 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:10:03.226Z,1710796203.226 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:10:03.226Z,1710796203.226 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:10:03.613Z,1710796203.613 [Default:CheckIn:D] Stopped 2024-03-18T21:10:03.613Z,1710796203.613 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:10:04.018Z,1710796204.018 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.866602 min 2024-03-18T21:10:04.018Z,1710796204.018 [Default:CheckIn:E] Stopped 2024-03-18T21:10:04.019Z,1710796204.019 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:10:04.019Z,1710796204.019 [Default:CheckIn] Stopped 2024-03-18T21:10:04.019Z,1710796204.019 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:10:04.019Z,1710796204.019 [Default:CheckIn](INFO): Running loop #27 2024-03-18T21:10:04.019Z,1710796204.019 [Default:CheckIn] Running Loop=27 2024-03-18T21:10:04.019Z,1710796204.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:10:04.019Z,1710796204.019 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:10:06.033Z,1710796206.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211005.00,A,3648.14032,N,12147.22967,W,0.039,0.00,180324,,,D*7C 2024-03-18T21:10:06.035Z,1710796206.035 [NAL9602](INFO): GPS fix at 20240318T211005: (36.802339, -121.787161) 2024-03-18T21:10:06.047Z,1710796206.047 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:10:06.047Z,1710796206.047 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:10:13.393Z,1710796213.393 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0082.lzma 2024-03-18T21:10:14.396Z,1710796214.396 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0082.lzma.bak 2024-03-18T21:10:14.396Z,1710796214.396 [DataOverHttps](INFO): SBD MOMSN=19328456 2024-03-18T21:10:21.457Z,1710796221.457 [NAL9602](INFO): SBD MO Status=0, MOMSN=47807, MT Status=0, MTMSN=0 2024-03-18T21:10:21.457Z,1710796221.457 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:10:33.090Z,1710796233.090 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0083.lzma 2024-03-18T21:10:34.092Z,1710796234.092 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0083.lzma.bak 2024-03-18T21:10:34.092Z,1710796234.092 [DataOverHttps](INFO): SBD MOMSN=19328459 2024-03-18T21:10:35.183Z,1710796235.183 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:10:35.183Z,1710796235.183 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:10:35.183Z,1710796235.183 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:10:52.138Z,1710796252.138 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:15:35.780Z,1710796535.780 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:15:35.780Z,1710796535.780 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:15:35.781Z,1710796535.781 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:15:35.781Z,1710796535.781 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:15:36.186Z,1710796536.186 [Default:CheckIn:D] Stopped 2024-03-18T21:15:36.186Z,1710796536.186 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:15:36.596Z,1710796536.596 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 162.409505 min 2024-03-18T21:15:36.596Z,1710796536.596 [Default:CheckIn:E] Stopped 2024-03-18T21:15:36.596Z,1710796536.596 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:15:36.597Z,1710796536.597 [Default:CheckIn] Stopped 2024-03-18T21:15:36.597Z,1710796536.597 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:15:36.597Z,1710796536.597 [Default:CheckIn](INFO): Running loop #28 2024-03-18T21:15:36.597Z,1710796536.597 [Default:CheckIn] Running Loop=28 2024-03-18T21:15:36.597Z,1710796536.597 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:15:36.597Z,1710796536.597 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:15:38.605Z,1710796538.605 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211537.00,A,3648.13841,N,12147.23004,W,0.039,0.00,180324,,,D*7E 2024-03-18T21:15:38.617Z,1710796538.617 [NAL9602](INFO): GPS fix at 20240318T211537: (36.802307, -121.787167) 2024-03-18T21:15:38.628Z,1710796538.628 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:15:38.628Z,1710796538.628 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:15:45.833Z,1710796545.833 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0085.lzma 2024-03-18T21:15:46.836Z,1710796546.836 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0085.lzma.bak 2024-03-18T21:15:46.836Z,1710796546.836 [DataOverHttps](INFO): SBD MOMSN=19328470 2024-03-18T21:15:57.997Z,1710796557.997 [NAL9602](INFO): SBD MO Status=0, MOMSN=47808, MT Status=0, MTMSN=0 2024-03-18T21:15:57.997Z,1710796557.997 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:16:02.718Z,1710796562.718 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0086.lzma 2024-03-18T21:16:03.720Z,1710796563.720 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0086.lzma.bak 2024-03-18T21:16:03.720Z,1710796563.720 [DataOverHttps](INFO): SBD MOMSN=19328473 2024-03-18T21:16:04.881Z,1710796564.881 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:16:04.881Z,1710796564.881 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:16:04.881Z,1710796564.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:16:28.701Z,1710796588.701 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:21:05.476Z,1710796865.476 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:21:05.477Z,1710796865.477 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:21:05.477Z,1710796865.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:21:05.477Z,1710796865.477 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:21:05.870Z,1710796865.870 [Default:CheckIn:D] Stopped 2024-03-18T21:21:05.870Z,1710796865.870 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:21:06.294Z,1710796866.294 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.904232 min 2024-03-18T21:21:06.294Z,1710796866.294 [Default:CheckIn:E] Stopped 2024-03-18T21:21:06.294Z,1710796866.294 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:21:06.294Z,1710796866.294 [Default:CheckIn] Stopped 2024-03-18T21:21:06.295Z,1710796866.295 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:21:06.295Z,1710796866.295 [Default:CheckIn](INFO): Running loop #29 2024-03-18T21:21:06.295Z,1710796866.295 [Default:CheckIn] Running Loop=29 2024-03-18T21:21:06.295Z,1710796866.295 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:21:06.295Z,1710796866.295 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:21:08.277Z,1710796868.277 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212107.00,A,3648.14017,N,12147.22905,W,0.000,0.00,180324,,,D*75 2024-03-18T21:21:08.279Z,1710796868.279 [NAL9602](INFO): GPS fix at 20240318T212107: (36.802336, -121.787151) 2024-03-18T21:21:08.290Z,1710796868.290 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:21:08.290Z,1710796868.290 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:21:15.390Z,1710796875.390 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0088.lzma 2024-03-18T21:21:16.392Z,1710796876.392 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0088.lzma.bak 2024-03-18T21:21:16.392Z,1710796876.392 [DataOverHttps](INFO): SBD MOMSN=19328483 2024-03-18T21:21:28.069Z,1710796888.069 [NAL9602](INFO): SBD MO Status=0, MOMSN=47809, MT Status=0, MTMSN=0 2024-03-18T21:21:28.069Z,1710796888.069 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:21:32.250Z,1710796892.250 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0089.lzma 2024-03-18T21:21:33.253Z,1710796893.253 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0089.lzma.bak 2024-03-18T21:21:33.254Z,1710796893.254 [DataOverHttps](INFO): SBD MOMSN=19328486 2024-03-18T21:21:34.540Z,1710796894.540 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:21:34.540Z,1710796894.540 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:21:34.540Z,1710796894.540 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:21:58.771Z,1710796918.771 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:26:35.118Z,1710797195.118 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:26:35.118Z,1710797195.118 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:26:35.118Z,1710797195.118 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:26:35.119Z,1710797195.119 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:26:35.535Z,1710797195.535 [Default:CheckIn:D] Stopped 2024-03-18T21:26:35.535Z,1710797195.535 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:26:35.930Z,1710797195.930 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 173.398649 min 2024-03-18T21:26:35.930Z,1710797195.930 [Default:CheckIn:E] Stopped 2024-03-18T21:26:35.930Z,1710797195.930 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:26:35.930Z,1710797195.930 [Default:CheckIn] Stopped 2024-03-18T21:26:35.930Z,1710797195.930 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:26:35.931Z,1710797195.931 [Default:CheckIn](INFO): Running loop #30 2024-03-18T21:26:35.931Z,1710797195.931 [Default:CheckIn] Running Loop=30 2024-03-18T21:26:35.931Z,1710797195.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:26:35.931Z,1710797195.931 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:26:37.941Z,1710797197.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212637.00,A,3648.14110,N,12147.22989,W,0.019,0.00,180324,,,D*7B 2024-03-18T21:26:37.943Z,1710797197.943 [NAL9602](INFO): GPS fix at 20240318T212637: (36.802352, -121.787165) 2024-03-18T21:26:37.954Z,1710797197.954 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:26:37.954Z,1710797197.954 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:26:45.817Z,1710797205.817 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0091.lzma 2024-03-18T21:26:46.820Z,1710797206.820 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0091.lzma.bak 2024-03-18T21:26:46.820Z,1710797206.820 [DataOverHttps](INFO): SBD MOMSN=19328497 2024-03-18T21:26:51.701Z,1710797211.701 [NAL9602](INFO): SBD MO Status=0, MOMSN=47810, MT Status=0, MTMSN=0 2024-03-18T21:26:51.701Z,1710797211.701 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:27:05.842Z,1710797225.842 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0092.lzma 2024-03-18T21:27:06.846Z,1710797226.846 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0092.lzma.bak 2024-03-18T21:27:06.846Z,1710797226.846 [DataOverHttps](INFO): SBD MOMSN=19328500 2024-03-18T21:27:07.905Z,1710797227.905 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:27:07.905Z,1710797227.905 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:27:07.905Z,1710797227.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:27:22.435Z,1710797242.435 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:32:08.495Z,1710797528.495 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:32:08.495Z,1710797528.495 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:32:08.495Z,1710797528.495 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:32:08.495Z,1710797528.495 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:32:08.882Z,1710797528.882 [Default:CheckIn:D] Stopped 2024-03-18T21:32:08.882Z,1710797528.882 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:32:09.290Z,1710797529.290 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.954427 min 2024-03-18T21:32:09.290Z,1710797529.290 [Default:CheckIn:E] Stopped 2024-03-18T21:32:09.290Z,1710797529.290 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:32:09.290Z,1710797529.290 [Default:CheckIn] Stopped 2024-03-18T21:32:09.290Z,1710797529.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:32:09.291Z,1710797529.291 [Default:CheckIn](INFO): Running loop #31 2024-03-18T21:32:09.291Z,1710797529.291 [Default:CheckIn] Running Loop=31 2024-03-18T21:32:09.291Z,1710797529.291 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:32:09.291Z,1710797529.291 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:32:11.293Z,1710797531.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213210.00,A,3648.14138,N,12147.22877,W,0.019,0.00,180324,,,D*71 2024-03-18T21:32:11.295Z,1710797531.295 [NAL9602](INFO): GPS fix at 20240318T213210: (36.802356, -121.787146) 2024-03-18T21:32:11.331Z,1710797531.331 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:32:11.331Z,1710797531.331 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:32:19.274Z,1710797539.274 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0094.lzma 2024-03-18T21:32:20.276Z,1710797540.276 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0094.lzma.bak 2024-03-18T21:32:20.277Z,1710797540.277 [DataOverHttps](INFO): SBD MOMSN=19328510 2024-03-18T21:32:23.009Z,1710797543.009 [NAL9602](INFO): SBD MO Status=0, MOMSN=47811, MT Status=0, MTMSN=0 2024-03-18T21:32:23.009Z,1710797543.009 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:32:39.038Z,1710797559.038 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0095.lzma 2024-03-18T21:32:40.040Z,1710797560.040 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0095.lzma.bak 2024-03-18T21:32:40.040Z,1710797560.040 [DataOverHttps](INFO): SBD MOMSN=19328513 2024-03-18T21:32:41.239Z,1710797561.239 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:32:41.239Z,1710797561.239 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:32:41.239Z,1710797561.239 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:32:53.746Z,1710797573.746 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:37:41.851Z,1710797861.851 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:37:41.851Z,1710797861.851 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:37:41.851Z,1710797861.851 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:37:41.851Z,1710797861.851 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:37:42.246Z,1710797862.246 [Default:CheckIn:D] Stopped 2024-03-18T21:37:42.246Z,1710797862.246 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:37:42.650Z,1710797862.650 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 184.510498 min 2024-03-18T21:37:42.650Z,1710797862.650 [Default:CheckIn:E] Stopped 2024-03-18T21:37:42.650Z,1710797862.650 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:37:42.650Z,1710797862.650 [Default:CheckIn] Stopped 2024-03-18T21:37:42.650Z,1710797862.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:37:42.651Z,1710797862.651 [Default:CheckIn](INFO): Running loop #32 2024-03-18T21:37:42.651Z,1710797862.651 [Default:CheckIn] Running Loop=32 2024-03-18T21:37:42.651Z,1710797862.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:37:42.651Z,1710797862.651 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:37:44.658Z,1710797864.658 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213743.00,A,3648.14026,N,12147.22990,W,0.039,0.00,180324,,,D*76 2024-03-18T21:37:44.660Z,1710797864.660 [NAL9602](INFO): GPS fix at 20240318T213743: (36.802338, -121.787165) 2024-03-18T21:37:44.672Z,1710797864.672 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:37:44.672Z,1710797864.672 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:37:52.006Z,1710797872.006 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0097.lzma 2024-03-18T21:37:53.008Z,1710797873.008 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0097.lzma.bak 2024-03-18T21:37:53.009Z,1710797873.009 [DataOverHttps](INFO): SBD MOMSN=19328524 2024-03-18T21:38:06.073Z,1710797886.073 [NAL9602](INFO): SBD MO Status=0, MOMSN=47812, MT Status=0, MTMSN=0 2024-03-18T21:38:06.073Z,1710797886.073 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:38:08.870Z,1710797888.870 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0098.lzma 2024-03-18T21:38:09.872Z,1710797889.872 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0098.lzma.bak 2024-03-18T21:38:09.873Z,1710797889.873 [DataOverHttps](INFO): SBD MOMSN=19328527 2024-03-18T21:38:10.942Z,1710797890.942 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:38:10.942Z,1710797890.942 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:38:10.942Z,1710797890.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:38:36.770Z,1710797916.770 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:43:11.641Z,1710798191.641 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:43:11.641Z,1710798191.641 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:43:11.641Z,1710798191.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:43:11.641Z,1710798191.641 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:43:12.036Z,1710798192.036 [Default:CheckIn:D] Stopped 2024-03-18T21:43:12.037Z,1710798192.037 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.006999 min 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn:E] Stopped 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn] Stopped 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn](INFO): Running loop #33 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn] Running Loop=33 2024-03-18T21:43:12.431Z,1710798192.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:43:12.432Z,1710798192.432 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:43:14.441Z,1710798194.441 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214313.00,A,3648.14921,N,12147.22275,W,0.019,35.17,180324,,,D*4C 2024-03-18T21:43:14.444Z,1710798194.444 [NAL9602](INFO): GPS fix at 20240318T214313: (36.802487, -121.787046) 2024-03-18T21:43:14.455Z,1710798194.455 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:43:14.455Z,1710798194.455 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:43:22.097Z,1710798202.097 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0100.lzma 2024-03-18T21:43:23.100Z,1710798203.100 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0100.lzma.bak 2024-03-18T21:43:23.100Z,1710798203.100 [DataOverHttps](INFO): SBD MOMSN=19328531 2024-03-18T21:43:39.070Z,1710798219.070 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0101.lzma 2024-03-18T21:43:40.072Z,1710798220.072 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0101.lzma.bak 2024-03-18T21:43:40.072Z,1710798220.072 [DataOverHttps](INFO): SBD MOMSN=19328534 2024-03-18T21:43:41.120Z,1710798221.120 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:43:41.120Z,1710798221.120 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:43:41.120Z,1710798221.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:43:43.133Z,1710798223.133 [NAL9602](INFO): SBD MO Status=0, MOMSN=47813, MT Status=0, MTMSN=0 2024-03-18T21:43:43.133Z,1710798223.133 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:44:13.835Z,1710798253.835 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:45:43.536Z,1710798343.536 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:45:56.449Z,1710798356.449 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:46:09.379Z,1710798369.379 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:46:21.936Z,1710798381.936 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:46:34.861Z,1710798394.861 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:46:47.385Z,1710798407.385 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:47:00.749Z,1710798420.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:48:41.802Z,1710798521.802 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:48:41.802Z,1710798521.802 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:48:41.802Z,1710798521.802 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:48:41.802Z,1710798521.802 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:48:42.210Z,1710798522.210 [Default:CheckIn:D] Stopped 2024-03-18T21:48:42.210Z,1710798522.210 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:48:42.618Z,1710798522.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.509896 min 2024-03-18T21:48:42.618Z,1710798522.618 [Default:CheckIn:E] Stopped 2024-03-18T21:48:42.618Z,1710798522.618 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:48:42.619Z,1710798522.619 [Default:CheckIn] Stopped 2024-03-18T21:48:42.619Z,1710798522.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:48:42.619Z,1710798522.619 [Default:CheckIn](INFO): Running loop #34 2024-03-18T21:48:42.619Z,1710798522.619 [Default:CheckIn] Running Loop=34 2024-03-18T21:48:42.619Z,1710798522.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:48:42.619Z,1710798522.619 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:48:44.621Z,1710798524.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214843.00,A,3648.15011,N,12147.22214,W,0.097,35.17,180324,,,D*48 2024-03-18T21:48:44.623Z,1710798524.623 [NAL9602](INFO): GPS fix at 20240318T214843: (36.802502, -121.787036) 2024-03-18T21:48:44.657Z,1710798524.657 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:48:44.657Z,1710798524.657 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:48:54.810Z,1710798534.810 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0103.lzma 2024-03-18T21:48:55.812Z,1710798535.812 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0103.lzma.bak 2024-03-18T21:48:55.813Z,1710798535.813 [DataOverHttps](INFO): SBD MOMSN=19328543 2024-03-18T21:49:10.117Z,1710798550.117 [NAL9602](INFO): SBD MO Status=2, MOMSN=47814, MT Status=2, MTMSN=0 2024-03-18T21:49:10.117Z,1710798550.117 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T21:49:12.638Z,1710798552.638 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240318T182915/Express0104.lzma 2024-03-18T21:49:13.640Z,1710798553.640 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0104.lzma.bak 2024-03-18T21:49:13.640Z,1710798553.640 [DataOverHttps](INFO): SBD MOMSN=19328546 2024-03-18T21:49:15.005Z,1710798555.005 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:49:15.005Z,1710798555.005 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:49:15.005Z,1710798555.005 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:49:19.529Z,1710798559.529 [NAL9602](INFO): SBD MO Status=0, MOMSN=47814, MT Status=0, MTMSN=0 2024-03-18T21:49:19.529Z,1710798559.529 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:49:33.944Z,1710798573.944 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-03-18T21:49:40.812Z,1710798580.812 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-03-18T21:49:40.819Z,1710798580.819 [BPC1](INFO): Calculating totals. Valid battery stick count: 37. Valid reserve battery stick count: 5. 2024-03-18T21:49:40.822Z,1710798580.822 [BPC1](INFO): Received data from all battery sticks. 2024-03-18T21:49:50.115Z,1710798590.115 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:54:15.783Z,1710798855.783 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:54:15.783Z,1710798855.783 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:54:15.783Z,1710798855.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:54:15.783Z,1710798855.783 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:54:16.174Z,1710798856.174 [Default:CheckIn:D] Stopped 2024-03-18T21:54:16.174Z,1710798856.174 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:54:16.591Z,1710798856.591 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.075960 min 2024-03-18T21:54:16.591Z,1710798856.591 [Default:CheckIn:E] Stopped 2024-03-18T21:54:16.591Z,1710798856.591 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:54:16.591Z,1710798856.591 [Default:CheckIn] Stopped 2024-03-18T21:54:16.591Z,1710798856.591 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:54:16.592Z,1710798856.592 [Default:CheckIn](INFO): Running loop #35 2024-03-18T21:54:16.592Z,1710798856.592 [Default:CheckIn] Running Loop=35 2024-03-18T21:54:16.592Z,1710798856.592 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:54:16.592Z,1710798856.592 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:54:18.589Z,1710798858.589 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215417.00,A,3648.15007,N,12147.22175,W,0.136,35.17,180324,,,A*48 2024-03-18T21:54:18.591Z,1710798858.591 [NAL9602](INFO): GPS fix at 20240318T215417: (36.802501, -121.787029) 2024-03-18T21:54:18.602Z,1710798858.602 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:54:18.602Z,1710798858.602 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:54:28.110Z,1710798868.110 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240318T182915/Courier0106.lzma 2024-03-18T21:54:29.112Z,1710798869.112 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0106.lzma.bak 2024-03-18T21:54:29.113Z,1710798869.113 [DataOverHttps](INFO): SBD MOMSN=19328550 2024-03-18T21:54:45.186Z,1710798885.186 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240318T182915/Express0107.lzma 2024-03-18T21:54:46.188Z,1710798886.188 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0107.lzma.bak 2024-03-18T21:54:46.188Z,1710798886.188 [DataOverHttps](INFO): SBD MOMSN=19328553 2024-03-18T21:54:47.367Z,1710798887.367 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T21:54:47.367Z,1710798887.367 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T21:54:47.367Z,1710798887.367 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T21:54:57.089Z,1710798897.089 [NAL9602](INFO): SBD MO Status=0, MOMSN=47815, MT Status=0, MTMSN=0 2024-03-18T21:54:57.089Z,1710798897.089 [NAL9602](INFO): No messages in MT queue 2024-03-18T21:55:27.795Z,1710798927.795 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T21:59:47.778Z,1710799187.778 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T21:59:47.778Z,1710799187.778 [Default:CheckIn:C.Wait] Stopped 2024-03-18T21:59:47.778Z,1710799187.778 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T21:59:47.778Z,1710799187.778 [Default:CheckIn:D] Running Loop=1 2024-03-18T21:59:48.175Z,1710799188.175 [Default:CheckIn:D] Stopped 2024-03-18T21:59:48.175Z,1710799188.175 [Default:CheckIn:E] Running Loop=1 2024-03-18T21:59:48.585Z,1710799188.585 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 206.609310 min 2024-03-18T21:59:48.585Z,1710799188.585 [Default:CheckIn:E] Stopped 2024-03-18T21:59:48.585Z,1710799188.585 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T21:59:48.585Z,1710799188.585 [Default:CheckIn] Stopped 2024-03-18T21:59:48.585Z,1710799188.585 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T21:59:48.585Z,1710799188.585 [Default:CheckIn](INFO): Running loop #36 2024-03-18T21:59:48.585Z,1710799188.585 [Default:CheckIn] Running Loop=36 2024-03-18T21:59:48.586Z,1710799188.586 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T21:59:48.586Z,1710799188.586 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T21:59:50.593Z,1710799190.593 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215949.00,A,3648.43762,N,12147.11104,W,4.685,5.19,180324,,,A*79 2024-03-18T21:59:50.595Z,1710799190.595 [NAL9602](INFO): GPS fix at 20240318T215949: (36.807294, -121.785184) 2024-03-18T21:59:50.636Z,1710799190.636 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T21:59:50.637Z,1710799190.637 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T21:59:58.422Z,1710799198.422 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0109.lzma 2024-03-18T21:59:59.424Z,1710799199.424 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0109.lzma.bak 2024-03-18T21:59:59.424Z,1710799199.424 [DataOverHttps](INFO): SBD MOMSN=19328566 2024-03-18T22:00:12.001Z,1710799212.001 [NAL9602](INFO): SBD MO Status=0, MOMSN=47816, MT Status=0, MTMSN=0 2024-03-18T22:00:12.001Z,1710799212.001 [NAL9602](INFO): No messages in MT queue 2024-03-18T22:00:15.402Z,1710799215.402 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240318T182915/Express0110.lzma 2024-03-18T22:00:16.404Z,1710799216.404 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0110.lzma.bak 2024-03-18T22:00:16.404Z,1710799216.404 [DataOverHttps](INFO): SBD MOMSN=19328569 2024-03-18T22:00:17.664Z,1710799217.664 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T22:00:17.664Z,1710799217.664 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T22:00:17.664Z,1710799217.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T22:00:42.767Z,1710799242.767 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T22:05:18.157Z,1710799518.157 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T22:05:18.157Z,1710799518.157 [Default:CheckIn:C.Wait] Stopped 2024-03-18T22:05:18.158Z,1710799518.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T22:05:18.158Z,1710799518.158 [Default:CheckIn:D] Running Loop=1 2024-03-18T22:05:18.557Z,1710799518.557 [Default:CheckIn:D] Stopped 2024-03-18T22:05:18.557Z,1710799518.557 [Default:CheckIn:E] Running Loop=1 2024-03-18T22:05:18.964Z,1710799518.964 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.115674 min 2024-03-18T22:05:18.964Z,1710799518.964 [Default:CheckIn:E] Stopped 2024-03-18T22:05:18.964Z,1710799518.964 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T22:05:18.964Z,1710799518.964 [Default:CheckIn] Stopped 2024-03-18T22:05:18.965Z,1710799518.965 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T22:05:18.965Z,1710799518.965 [Default:CheckIn](INFO): Running loop #37 2024-03-18T22:05:18.965Z,1710799518.965 [Default:CheckIn] Running Loop=37 2024-03-18T22:05:18.965Z,1710799518.965 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T22:05:18.965Z,1710799518.965 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T22:05:20.973Z,1710799520.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220520.00,A,3648.32815,N,12147.94243,W,10.205,268.10,180324,,,A*41 2024-03-18T22:05:20.976Z,1710799520.976 [NAL9602](INFO): GPS fix at 20240318T220520: (36.805469, -121.799041) 2024-03-18T22:05:21.007Z,1710799521.007 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T22:05:21.008Z,1710799521.008 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T22:05:28.557Z,1710799528.557 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240318T182915/Courier0112.lzma 2024-03-18T22:05:29.560Z,1710799529.560 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0112.lzma.bak 2024-03-18T22:05:29.560Z,1710799529.560 [DataOverHttps](INFO): SBD MOMSN=19328573 2024-03-18T22:05:38.345Z,1710799538.345 [NAL9602](INFO): SBD MO Status=0, MOMSN=47817, MT Status=0, MTMSN=0 2024-03-18T22:05:38.345Z,1710799538.345 [NAL9602](INFO): No messages in MT queue 2024-03-18T22:05:45.674Z,1710799545.674 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240318T182915/Express0113.lzma 2024-03-18T22:05:46.676Z,1710799546.676 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0113.lzma.bak 2024-03-18T22:05:46.676Z,1710799546.676 [DataOverHttps](INFO): SBD MOMSN=19328576 2024-03-18T22:05:48.066Z,1710799548.066 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T22:05:48.066Z,1710799548.066 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T22:05:48.067Z,1710799548.067 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T22:06:09.051Z,1710799569.051 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T22:08:50.011Z,1710799730.011 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-03-18T22:10:48.903Z,1710799848.903 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T22:10:48.903Z,1710799848.903 [Default:CheckIn:C.Wait] Stopped 2024-03-18T22:10:48.903Z,1710799848.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T22:10:48.903Z,1710799848.903 [Default:CheckIn:D] Running Loop=1 2024-03-18T22:10:49.289Z,1710799849.289 [Default:CheckIn:D] Stopped 2024-03-18T22:10:49.289Z,1710799849.289 [Default:CheckIn:E] Running Loop=1 2024-03-18T22:10:49.638Z,1710799849.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.627881 min 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn:E] Stopped 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn] Stopped 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn](INFO): Running loop #38 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn] Running Loop=38 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T22:10:49.639Z,1710799849.639 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T22:10:51.649Z,1710799851.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221050.00,A,3648.30386,N,12148.96313,W,9.583,254.87,180324,,,A*78 2024-03-18T22:10:51.651Z,1710799851.651 [NAL9602](INFO): GPS fix at 20240318T221050: (36.805064, -121.816052) 2024-03-18T22:10:51.699Z,1710799851.699 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T22:10:51.699Z,1710799851.699 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T22:11:01.430Z,1710799861.430 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0115.lzma 2024-03-18T22:11:02.432Z,1710799862.432 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0115.lzma.bak 2024-03-18T22:11:02.432Z,1710799862.432 [DataOverHttps](INFO): SBD MOMSN=19328586 2024-03-18T22:11:18.250Z,1710799878.250 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240318T182915/Express0116.lzma 2024-03-18T22:11:19.252Z,1710799879.252 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0116.lzma.bak 2024-03-18T22:11:19.252Z,1710799879.252 [DataOverHttps](INFO): SBD MOMSN=19328589 2024-03-18T22:11:20.357Z,1710799880.357 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T22:11:20.357Z,1710799880.357 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T22:11:20.357Z,1710799880.357 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T22:11:23.214Z,1710799883.214 [NAL9602](INFO): SBD MO Status=0, MOMSN=47818, MT Status=0, MTMSN=0 2024-03-18T22:11:23.214Z,1710799883.214 [NAL9602](INFO): No messages in MT queue 2024-03-18T22:11:53.879Z,1710799913.879 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T22:16:21.035Z,1710800181.035 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T22:16:21.035Z,1710800181.035 [Default:CheckIn:C.Wait] Stopped 2024-03-18T22:16:21.035Z,1710800181.035 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T22:16:21.035Z,1710800181.035 [Default:CheckIn:D] Running Loop=1 2024-03-18T22:16:21.450Z,1710800181.450 [Default:CheckIn:D] Stopped 2024-03-18T22:16:21.450Z,1710800181.450 [Default:CheckIn:E] Running Loop=1 2024-03-18T22:16:21.833Z,1710800181.833 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.163883 min 2024-03-18T22:16:21.833Z,1710800181.833 [Default:CheckIn:E] Stopped 2024-03-18T22:16:21.833Z,1710800181.833 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T22:16:21.833Z,1710800181.833 [Default:CheckIn] Stopped 2024-03-18T22:16:21.833Z,1710800181.833 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T22:16:21.833Z,1710800181.833 [Default:CheckIn](INFO): Running loop #39 2024-03-18T22:16:21.833Z,1710800181.833 [Default:CheckIn] Running Loop=39 2024-03-18T22:16:21.834Z,1710800181.834 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T22:16:21.834Z,1710800181.834 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T22:16:23.844Z,1710800183.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221623.00,A,3648.17426,N,12149.65576,W,8.028,268.17,180324,,,A*79 2024-03-18T22:16:23.847Z,1710800183.847 [NAL9602](INFO): GPS fix at 20240318T221623: (36.802904, -121.827596) 2024-03-18T22:16:23.866Z,1710800183.866 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T22:16:23.890Z,1710800183.890 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T22:16:32.605Z,1710800192.605 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240318T182915/Courier0118.lzma 2024-03-18T22:16:33.608Z,1710800193.608 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0118.lzma.bak 2024-03-18T22:16:33.608Z,1710800193.608 [DataOverHttps](INFO): SBD MOMSN=19328593 2024-03-18T22:16:44.209Z,1710800204.209 [NAL9602](INFO): SBD MO Status=2, MOMSN=47819, MT Status=2, MTMSN=0 2024-03-18T22:16:44.209Z,1710800204.209 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T22:16:49.806Z,1710800209.806 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240318T182915/Express0119.lzma 2024-03-18T22:16:50.808Z,1710800210.808 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0119.lzma.bak 2024-03-18T22:16:50.809Z,1710800210.809 [DataOverHttps](INFO): SBD MOMSN=19328596 2024-03-18T22:16:51.922Z,1710800211.922 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T22:16:51.922Z,1710800211.922 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T22:16:51.922Z,1710800211.922 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T22:16:55.961Z,1710800215.961 [NAL9602](INFO): SBD MO Status=0, MOMSN=47819, MT Status=0, MTMSN=0 2024-03-18T22:16:55.961Z,1710800215.961 [NAL9602](INFO): No messages in MT queue 2024-03-18T22:17:26.675Z,1710800246.675 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T22:21:52.639Z,1710800512.639 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T22:21:52.639Z,1710800512.639 [Default:CheckIn:C.Wait] Stopped 2024-03-18T22:21:52.639Z,1710800512.639 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T22:21:52.639Z,1710800512.639 [Default:CheckIn:D] Running Loop=1 2024-03-18T22:21:53.034Z,1710800513.034 [Default:CheckIn:D] Stopped 2024-03-18T22:21:53.035Z,1710800513.035 [Default:CheckIn:E] Running Loop=1 2024-03-18T22:21:53.438Z,1710800513.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.690299 min 2024-03-18T22:21:53.438Z,1710800513.438 [Default:CheckIn:E] Stopped 2024-03-18T22:21:53.438Z,1710800513.438 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T22:21:53.438Z,1710800513.438 [Default:CheckIn] Stopped 2024-03-18T22:21:53.439Z,1710800513.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T22:21:53.439Z,1710800513.439 [Default:CheckIn](INFO): Running loop #40 2024-03-18T22:21:53.439Z,1710800513.439 [Default:CheckIn] Running Loop=40 2024-03-18T22:21:53.439Z,1710800513.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T22:21:53.439Z,1710800513.439 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T22:21:55.445Z,1710800515.445 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222154.00,A,3648.01848,N,12150.46849,W,7.076,270.26,180324,,,A*79 2024-03-18T22:21:55.447Z,1710800515.447 [NAL9602](INFO): GPS fix at 20240318T222154: (36.800308, -121.841142) 2024-03-18T22:21:55.459Z,1710800515.459 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T22:21:55.459Z,1710800515.459 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T22:21:56.887Z,1710800516.887 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:22:11.605Z,1710800531.605 [NAL9602](INFO): SBD MO Status=0, MOMSN=47820, MT Status=0, MTMSN=0 2024-03-18T22:22:11.605Z,1710800531.605 [NAL9602](INFO): No messages in MT queue 2024-03-18T22:22:28.002Z,1710800548.002 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:22:42.307Z,1710800562.307 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T22:22:59.126Z,1710800579.126 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:23:30.262Z,1710800610.262 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:24:01.386Z,1710800641.386 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:24:05.403Z,1710800645.403 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.735504 2024-03-18T22:24:31.049Z,1710800671.049 [NAL9602](INFO): SBD MO Status=1, MOMSN=47821, MT Status=0, MTMSN=0 2024-03-18T22:24:31.102Z,1710800671.102 [NAL9602](INFO): Sent 72 bytes from file Logs/20240318T182915/Courier0121.lzma 2024-03-18T22:24:31.102Z,1710800671.102 [NAL9602](INFO): Packets left to send: 0 2024-03-18T22:24:36.526Z,1710800676.526 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:24:42.401Z,1710800682.401 [NAL9602](INFO): SBD MO Status=1, MOMSN=47822, MT Status=0, MTMSN=0 2024-03-18T22:24:42.458Z,1710800682.458 [NAL9602](INFO): Sent 144 bytes from file Logs/20240318T182915/Express0122.lzma 2024-03-18T22:24:42.458Z,1710800682.458 [NAL9602](INFO): Packets left to send: 0 2024-03-18T22:25:02.201Z,1710800702.201 [NAL9602](INFO): SBD MO Status=2, MOMSN=47823, MT Status=2, MTMSN=0 2024-03-18T22:25:02.201Z,1710800702.201 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T22:25:19.569Z,1710800719.569 [NAL9602](INFO): SBD MO Status=2, MOMSN=47823, MT Status=2, MTMSN=0 2024-03-18T22:25:19.569Z,1710800719.569 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-03-18T22:25:20.591Z,1710800720.591 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003376 2024-03-18T22:25:21.630Z,1710800721.630 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T22:25:21.630Z,1710800721.630 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T22:25:21.631Z,1710800721.631 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T22:25:24.449Z,1710800724.449 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2024-03-18T22:25:55.124Z,1710800755.124 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T22:26:22.230Z,1710800782.230 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:26:53.370Z,1710800813.370 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:30:22.241Z,1710801022.241 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-03-18T22:30:22.241Z,1710801022.241 [Default:CheckIn:C.Wait] Stopped 2024-03-18T22:30:22.241Z,1710801022.241 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-03-18T22:30:22.241Z,1710801022.241 [Default:CheckIn:D] Running Loop=1 2024-03-18T22:30:22.659Z,1710801022.659 [Default:CheckIn:D] Stopped 2024-03-18T22:30:22.659Z,1710801022.659 [Default:CheckIn:E] Running Loop=1 2024-03-18T22:30:23.050Z,1710801023.050 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 237.184033 min 2024-03-18T22:30:23.050Z,1710801023.050 [Default:CheckIn:E] Stopped 2024-03-18T22:30:23.050Z,1710801023.050 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-03-18T22:30:23.050Z,1710801023.050 [Default:CheckIn] Stopped 2024-03-18T22:30:23.050Z,1710801023.050 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-03-18T22:30:23.051Z,1710801023.051 [Default:CheckIn](INFO): Running loop #41 2024-03-18T22:30:23.051Z,1710801023.051 [Default:CheckIn] Running Loop=41 2024-03-18T22:30:23.051Z,1710801023.051 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-03-18T22:30:23.052Z,1710801023.052 [Default:CheckIn:Read_GPS] Running Loop=1 2024-03-18T22:30:25.064Z,1710801025.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223024.00,A,3647.78641,N,12150.78558,W,0.661,248.79,180324,,,D*7B 2024-03-18T22:30:25.066Z,1710801025.066 [NAL9602](INFO): GPS fix at 20240318T223024: (36.796440, -121.846426) 2024-03-18T22:30:25.077Z,1710801025.077 [Default:CheckIn:Read_GPS] Stopped 2024-03-18T22:30:25.077Z,1710801025.077 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-03-18T22:30:43.641Z,1710801043.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=47823, MT Status=0, MTMSN=0 2024-03-18T22:30:43.641Z,1710801043.641 [NAL9602](INFO): No messages in MT queue 2024-03-18T22:30:59.994Z,1710801059.994 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:31:14.341Z,1710801074.341 [NAL9602](INFO): Not Powering down - fast GPS 2024-03-18T22:31:31.118Z,1710801091.118 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:32:02.242Z,1710801122.242 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-03-18T22:32:09.641Z,1710801129.641 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240318T182915/Courier0124.lzma 2024-03-18T22:32:10.644Z,1710801130.644 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Courier0124.lzma.bak 2024-03-18T22:32:10.644Z,1710801130.644 [DataOverHttps](INFO): SBD MOMSN=19328623 2024-03-18T22:32:30.778Z,1710801150.778 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240318T182915/Express0125.lzma 2024-03-18T22:32:31.780Z,1710801151.780 [DataOverHttps](INFO): Moved sent file to Logs/20240318T182915/Express0125.lzma.bak 2024-03-18T22:32:31.780Z,1710801151.780 [DataOverHttps](INFO): SBD MOMSN=19328626 2024-03-18T22:32:33.145Z,1710801153.145 [Default:CheckIn:Read_Iridium] Stopped 2024-03-18T22:32:33.145Z,1710801153.145 [Default:CheckIn:C.Wait] Running Loop=1 2024-03-18T22:32:33.145Z,1710801153.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-03-18T22:33:18.879Z,1710801198.879 [DataOverHttps](IMPORTANT): SBD MTMSN=20240318T223317 2024-03-18T22:33:28.460Z,1710801208.460 [DataOverHttps](INFO): Received command: restart logs