2023-12-11T15:48:42.815Z,1702309722.815 [Supervisor](DEBUG): Initializing supervisor.
2023-12-11T15:48:42.819Z,1702309722.819 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-12-11T15:48:42.820Z,1702309722.820 [SyncHandler](INFO): Protected caller Thread ID is 836
2023-12-11T15:48:42.820Z,1702309722.820 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-12-11T15:48:42.821Z,1702309722.821 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-12-11T15:48:42.822Z,1702309722.822 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2023-12-11T15:48:42.826Z,1702309722.826 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-12-11T15:48:42.843Z,1702309722.843 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-12-11T15:48:42.844Z,1702309722.844 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-12-11T15:48:42.844Z,1702309722.844 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2023-12-11T15:48:42.848Z,1702309722.848 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-12-11T15:48:42.850Z,1702309722.850 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-12-11T15:48:42.850Z,1702309722.850 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2023-12-11T15:48:42.852Z,1702309722.852 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-12-11T15:48:42.853Z,1702309722.853 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-12-11T15:48:42.853Z,1702309722.853 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2023-12-11T15:48:42.857Z,1702309722.857 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-12-11T15:48:42.857Z,1702309722.857 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-12-11T15:48:42.861Z,1702309722.861 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-12-11T15:48:43.122Z,1702309723.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-12-11T15:48:43.124Z,1702309723.124 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-12-11T15:48:43.205Z,1702309723.205 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-12-11T15:48:43.684Z,1702309723.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-12-11T15:48:43.687Z,1702309723.687 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-12-11T15:48:44.015Z,1702309724.015 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-12-11T15:48:44.017Z,1702309724.017 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-12-11T15:48:44.113Z,1702309724.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-12-11T15:48:44.115Z,1702309724.115 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-12-11T15:48:44.516Z,1702309724.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-12-11T15:48:44.518Z,1702309724.518 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-12-11T15:48:44.736Z,1702309724.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-12-11T15:48:44.737Z,1702309724.737 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-12-11T15:48:45.269Z,1702309725.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-12-11T15:48:45.270Z,1702309725.270 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-12-11T15:48:45.408Z,1702309725.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-12-11T15:48:45.410Z,1702309725.410 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-12-11T15:48:45.491Z,1702309725.491 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-12-11T15:48:46.468Z,1702309726.468 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-12-11T15:48:46.469Z,1702309726.469 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-12-11T15:48:47.256Z,1702309727.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-12-11T15:48:47.257Z,1702309727.257 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-12-11T15:48:47.448Z,1702309727.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-12-11T15:48:47.449Z,1702309727.449 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-12-11T15:48:47.561Z,1702309727.561 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-12-11T15:48:47.563Z,1702309727.563 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-12-11T15:48:47.794Z,1702309727.794 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-12-11T15:48:47.794Z,1702309727.794 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-12-11T15:48:48.048Z,1702309728.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-12-11T15:48:48.050Z,1702309728.050 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-12-11T15:48:48.053Z,1702309728.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-12-11T15:48:48.146Z,1702309728.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-12-11T15:48:48.232Z,1702309728.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-12-11T15:48:48.336Z,1702309728.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-12-11T15:48:48.421Z,1702309728.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-12-11T15:48:48.525Z,1702309728.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-12-11T15:48:48.631Z,1702309728.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-12-11T15:48:48.745Z,1702309728.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-12-11T15:48:48.822Z,1702309728.822 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-12-11T15:48:48.976Z,1702309728.976 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-12-11T15:48:49.120Z,1702309729.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-12-11T15:48:49.232Z,1702309729.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-12-11T15:48:49.503Z,1702309729.503 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-12-11T15:48:49.503Z,1702309729.503 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-12-11T15:48:49.519Z,1702309729.519 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-12-11T15:48:49.589Z,1702309729.589 [VerticalControl](DEBUG): Construct VerticalControl.
2023-12-11T15:48:49.649Z,1702309729.649 [VerticalControl] Loaded
2023-12-11T15:48:49.650Z,1702309729.650 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-12-11T15:48:49.652Z,1702309729.652 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-12-11T15:48:49.693Z,1702309729.693 [HorizontalControl] Loaded
2023-12-11T15:48:49.694Z,1702309729.694 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-12-11T15:48:49.696Z,1702309729.696 [SpeedControl](DEBUG): Construct SpeedControl.
2023-12-11T15:48:49.699Z,1702309729.699 [SpeedControl] Loaded
2023-12-11T15:48:49.699Z,1702309729.699 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-12-11T15:48:49.702Z,1702309729.702 [LoopControl](DEBUG): Construct LoopControl.
2023-12-11T15:48:49.702Z,1702309729.702 [LoopControl] Loaded
2023-12-11T15:48:49.703Z,1702309729.703 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-12-11T15:48:49.703Z,1702309729.703 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-12-11T15:48:49.705Z,1702309729.705 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-12-11T15:48:49.720Z,1702309729.720 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-12-11T15:48:49.720Z,1702309729.720 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-12-11T15:48:49.904Z,1702309729.904 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-12-11T15:48:49.905Z,1702309729.905 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-12-11T15:48:50.089Z,1702309730.089 [BuoyancyServo] Loaded
2023-12-11T15:48:50.089Z,1702309730.089 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-12-11T15:48:50.109Z,1702309730.109 [ElevatorServo] Loaded
2023-12-11T15:48:50.109Z,1702309730.109 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-12-11T15:48:50.128Z,1702309730.128 [MassServo] Loaded
2023-12-11T15:48:50.128Z,1702309730.128 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-12-11T15:48:50.146Z,1702309730.146 [RudderServo] Loaded
2023-12-11T15:48:50.147Z,1702309730.147 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-12-11T15:48:50.161Z,1702309730.161 [ThrusterHE] Loaded
2023-12-11T15:48:50.161Z,1702309730.161 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-12-11T15:48:50.162Z,1702309730.162 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-12-11T15:48:50.162Z,1702309730.162 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-12-11T15:48:50.304Z,1702309730.304 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-12-11T15:48:50.304Z,1702309730.304 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-12-11T15:48:50.318Z,1702309730.318 [NavChart] Loaded
2023-12-11T15:48:50.319Z,1702309730.319 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-12-11T15:48:50.325Z,1702309730.325 [UniversalFixResidualReporter] Loaded
2023-12-11T15:48:50.325Z,1702309730.325 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-12-11T15:48:50.325Z,1702309730.325 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-12-11T15:48:50.326Z,1702309730.326 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-12-11T15:48:50.409Z,1702309730.409 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-12-11T15:48:50.410Z,1702309730.410 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-12-11T15:48:51.158Z,1702309731.158 [AHRS_M2] Loaded
2023-12-11T15:48:51.159Z,1702309731.159 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-12-11T15:48:52.003Z,1702309732.003 [BPC1] Loaded
2023-12-11T15:48:52.004Z,1702309732.004 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-12-11T15:48:52.150Z,1702309732.150 [DataOverHttps] Loaded
2023-12-11T15:48:52.151Z,1702309732.151 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-12-11T15:48:52.152Z,1702309732.152 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090F4E0
2023-12-11T15:48:52.152Z,1702309732.152 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2023-12-11T15:48:52.173Z,1702309732.173 [Depth_Keller] Loaded
2023-12-11T15:48:52.173Z,1702309732.173 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-12-11T15:48:52.178Z,1702309732.178 [DropWeight] Loaded
2023-12-11T15:48:52.178Z,1702309732.178 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-12-11T15:48:52.240Z,1702309732.240 [NAL9602] Loaded
2023-12-11T15:48:52.240Z,1702309732.240 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-12-11T15:48:52.271Z,1702309732.271 [Onboard] Loaded
2023-12-11T15:48:52.271Z,1702309732.271 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-12-11T15:48:52.272Z,1702309732.272 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093F4E0
2023-12-11T15:48:52.272Z,1702309732.272 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2023-12-11T15:48:52.286Z,1702309732.286 [Power24vConverter] Loaded
2023-12-11T15:48:52.286Z,1702309732.286 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-12-11T15:48:52.299Z,1702309732.299 [Radio_Surface] Loaded
2023-12-11T15:48:52.299Z,1702309732.299 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-12-11T15:48:52.300Z,1702309732.300 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096F4E0
2023-12-11T15:48:52.301Z,1702309732.301 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2023-12-11T15:48:52.314Z,1702309732.314 [Sonardyne_Nano] Loaded
2023-12-11T15:48:52.315Z,1702309732.315 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-12-11T15:48:52.315Z,1702309732.315 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-12-11T15:48:52.316Z,1702309732.316 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-12-11T15:48:52.472Z,1702309732.472 [DepthRateCalculator] Loaded
2023-12-11T15:48:52.473Z,1702309732.473 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-12-11T15:48:52.492Z,1702309732.492 [PitchRateCalculator] Loaded
2023-12-11T15:48:52.492Z,1702309732.492 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-12-11T15:48:52.604Z,1702309732.604 [SpeedCalculator] Loaded
2023-12-11T15:48:52.604Z,1702309732.604 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-12-11T15:48:52.608Z,1702309732.608 [YawRateCalculator] Loaded
2023-12-11T15:48:52.609Z,1702309732.609 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-12-11T15:48:52.660Z,1702309732.660 [ElevatorOffsetCalculator] Loaded
2023-12-11T15:48:52.660Z,1702309732.660 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-12-11T15:48:52.661Z,1702309732.661 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-12-11T15:48:52.683Z,1702309732.683 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-12-11T15:48:53.128Z,1702309733.128 [CANONSampler] Loaded
2023-12-11T15:48:53.128Z,1702309733.128 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-12-11T15:48:53.189Z,1702309733.189 [CTD_Seabird] Loaded
2023-12-11T15:48:53.189Z,1702309733.189 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-12-11T15:48:53.190Z,1702309733.190 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A2A4E0
2023-12-11T15:48:53.191Z,1702309733.191 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2023-12-11T15:48:53.211Z,1702309733.211 [PAR_Licor] Loaded
2023-12-11T15:48:53.211Z,1702309733.211 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-12-11T15:48:53.251Z,1702309733.251 [WetLabsBB2FL] Loaded
2023-12-11T15:48:53.251Z,1702309733.251 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-12-11T15:48:53.252Z,1702309733.252 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A5A4E0
2023-12-11T15:48:53.253Z,1702309733.253 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2023-12-11T15:48:53.254Z,1702309733.254 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-12-11T15:48:53.254Z,1702309733.254 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-12-11T15:48:53.297Z,1702309733.297 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-12-11T15:48:53.298Z,1702309733.298 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-12-11T15:48:53.695Z,1702309733.695 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-12-11T15:48:53.696Z,1702309733.696 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-12-11T15:48:53.869Z,1702309733.869 [SBIT](DEBUG): Construct Startup Built In Test.
2023-12-11T15:48:53.878Z,1702309733.878 [SBIT] Loaded
2023-12-11T15:48:53.878Z,1702309733.878 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-12-11T15:48:53.882Z,1702309733.882 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-12-11T15:48:53.895Z,1702309733.895 [IBIT] Loaded
2023-12-11T15:48:53.896Z,1702309733.896 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-12-11T15:48:53.902Z,1702309733.902 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-12-11T15:48:54.006Z,1702309734.006 [CBIT] Loaded
2023-12-11T15:48:54.006Z,1702309734.006 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-12-11T15:48:54.007Z,1702309734.007 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-12-11T15:48:54.007Z,1702309734.007 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-12-11T15:48:54.147Z,1702309734.147 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-12-11T15:48:54.154Z,1702309734.154 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-12-11T15:48:54.156Z,1702309734.156 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-12-11T15:48:54.168Z,1702309734.168 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-12-11T15:48:54.169Z,1702309734.169 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BAF4E0
2023-12-11T15:48:54.169Z,1702309734.169 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2023-12-11T15:48:54.174Z,1702309734.174 [Supervisor](INFO): Main Thread ID is 829
2023-12-11T15:48:54.174Z,1702309734.174 [Supervisor](DEBUG): Running supervisor.
2023-12-11T15:48:54.174Z,1702309734.174 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2023-12-11T15:48:54.175Z,1702309734.175 [CommandExec](INFO): Initializing the command executive.
2023-12-11T15:48:54.176Z,1702309734.176 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2023-12-11T15:48:54.179Z,1702309734.179 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2023-12-11T15:48:54.179Z,1702309734.179 [controlThread](DEBUG): Initializing ControlThread
2023-12-11T15:48:54.180Z,1702309734.180 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-12-11T15:48:54.182Z,1702309734.182 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-12-11T15:48:54.183Z,1702309734.183 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-12-11T15:48:54.183Z,1702309734.183 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-12-11T15:48:54.185Z,1702309734.185 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-12-11T15:48:54.185Z,1702309734.185 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-12-11T15:48:54.191Z,1702309734.191 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-12-11T15:48:54.191Z,1702309734.191 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-12-11T15:48:54.192Z,1702309734.192 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-12-11T15:48:54.192Z,1702309734.192 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-12-11T15:48:54.192Z,1702309734.192 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-12-11T15:48:54.197Z,1702309734.197 [SBIT](INFO): Initialize SBIT Component.
2023-12-11T15:48:54.198Z,1702309734.198 [SBIT](IMPORTANT): git: 2023-12-07
2023-12-11T15:48:54.198Z,1702309734.198 [SBIT](INFO): git hash: dbc7300d59e166afd06c26988583bb6e3d4bec93
2023-12-11T15:48:54.198Z,1702309734.198 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-12-11T15:48:54.199Z,1702309734.199 [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
2023-12-11T15:48:54.200Z,1702309734.200 [SBIT](INFO): Beginning SBIT in 67.000000 seconds.
2023-12-11T15:48:54.201Z,1702309734.201 [IBIT](INFO): Initialize IBIT Component.
2023-12-11T15:48:54.202Z,1702309734.202 [CBIT](DEBUG): Initialize CBIT Component.
2023-12-11T15:48:54.203Z,1702309734.203 [logger ThreadHandler](INFO): Handler Thread ID is 934
2023-12-11T15:48:54.213Z,1702309734.213 [CBIT](DEBUG): Initialized mux pins.
2023-12-11T15:48:54.213Z,1702309734.213 [CBIT](DEBUG): Initializing the watchdog timer.
2023-12-11T15:48:54.221Z,1702309734.221 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2023-12-11T15:48:54.223Z,1702309734.223 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-12-11T15:48:54.233Z,1702309734.233 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2023-12-11T15:48:54.238Z,1702309734.238 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-12-11T15:48:54.238Z,1702309734.238 [CBIT](DEBUG): Initializing heartbeat.
2023-12-11T15:48:54.255Z,1702309734.255 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2023-12-11T15:48:54.269Z,1702309734.269 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938
2023-12-11T15:48:54.270Z,1702309734.270 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-12-11T15:48:54.275Z,1702309734.275 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940
2023-12-11T15:48:54.278Z,1702309734.278 [WetLabsBB2FL](INFO): Powering up
2023-12-11T15:48:54.279Z,1702309734.279 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
2023-12-11T15:48:54.289Z,1702309734.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-12-11T15:48:54.289Z,1702309734.289 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-12-11T15:48:54.289Z,1702309734.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-12-11T15:48:54.290Z,1702309734.290 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-12-11T15:48:54.290Z,1702309734.290 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-12-11T15:48:54.290Z,1702309734.290 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-12-11T15:48:54.290Z,1702309734.290 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-12-11T15:48:54.290Z,1702309734.290 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-12-11T15:48:54.309Z,1702309734.309 [CBIT](DEBUG): Deactivating GF circuits.
2023-12-11T15:48:54.309Z,1702309734.309 [CBIT](DEBUG): Deactivating emergency mode.
2023-12-11T15:48:54.349Z,1702309734.349 [CBIT](DEBUG): Backplane powered.
2023-12-11T15:48:54.350Z,1702309734.350 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-12-11T15:48:54.381Z,1702309734.381 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-12-11T15:48:54.397Z,1702309734.397 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-12-11T15:48:54.398Z,1702309734.398 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-12-11T15:48:54.477Z,1702309734.477 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-12-11T15:48:54.478Z,1702309734.478 [Default:A.Wait](DEBUG): Construct Wait.
2023-12-11T15:48:54.481Z,1702309734.481 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-12-11T15:48:54.516Z,1702309734.516 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-12-11T15:48:54.534Z,1702309734.534 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-12-11T15:48:54.540Z,1702309734.540 [Default:E.Execute](DEBUG): Construct Execute.
2023-12-11T15:48:54.559Z,1702309734.559 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-12-11T15:48:54.564Z,1702309734.564 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-12-11T15:48:54.588Z,1702309734.588 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-12-11T15:48:54.661Z,1702309734.661 [Radio_Surface](INFO): Powering up
2023-12-11T15:48:54.735Z,1702309734.735 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m
2023-12-11T15:48:54.736Z,1702309734.736 [Power24vConverter](INFO): Powering up.
2023-12-11T15:48:54.737Z,1702309734.737 [Sonardyne_Nano](INFO): Initializing.
2023-12-11T15:48:54.757Z,1702309734.757 [CANONSampler](INFO): Powering down
2023-12-11T15:48:54.766Z,1702309734.766 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-12-11T15:48:54.805Z,1702309734.805 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-12-11T15:48:54.813Z,1702309734.813 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-12-11T15:48:54.814Z,1702309734.814 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-12-11T15:48:54.821Z,1702309734.821 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-12-11T15:48:54.822Z,1702309734.822 [MassServo](DEBUG): Initializing EZServoServo.
2023-12-11T15:48:54.829Z,1702309734.829 [MassServo](DEBUG): Initializing MassServo.
2023-12-11T15:48:54.830Z,1702309734.830 [RudderServo](DEBUG): Initializing EZServoServo.
2023-12-11T15:48:54.838Z,1702309734.838 [RudderServo](DEBUG): Initializing RudderServo.
2023-12-11T15:48:54.839Z,1702309734.839 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-12-11T15:48:54.845Z,1702309734.845 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-12-11T15:48:56.889Z,1702309736.889 [WetLabsBB2FL](INFO): Powering down
2023-12-11T15:48:56.995Z,1702309736.995 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-12-11T15:48:58.235Z,1702309738.235 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2023-12-11T15:49:00.458Z,1702309740.458 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-12-11T15:49:22.451Z,1702309762.451 [NAL9602](INFO): Powering up NAL9602
2023-12-11T15:49:33.363Z,1702309773.363 [NAL9602](INFO): NAL9602 initialized
2023-12-11T15:50:01.713Z,1702309801.713 [SBIT](IMPORTANT): Beginning Startup BIT
2023-12-11T15:50:01.731Z,1702309801.731 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-11T15:50:12.569Z,1702309812.569 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-12-11T15:50:12.984Z,1702309812.984 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-11T15:50:14.969Z,1702309814.969 [BPC1](ERROR): BPC1B: No match for serial number FFFF in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-12-11T15:50:23.948Z,1702309823.948 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-12-11T15:50:24.338Z,1702309824.338 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-11T15:50:35.220Z,1702309835.220 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.014643
CHAN A1 (24V): 0.000586
CHAN A2 (12V): -0.002439
CHAN A3 (5V): -0.001039
CHAN B0 (3.3V): 0.000176
CHAN B1 (3.15aV): -0.000784
CHAN B2 (3.15bV): -0.000382
CHAN B3 (GND): 0.000049
OPEN: -0.000215
Full Scale: +/- 1 mA
2023-12-11T15:50:55.829Z,1702309855.829 [SBIT](IMPORTANT): SBIT PASSED
2023-12-11T15:50:55.829Z,1702309855.829 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-12-11T15:50:55.830Z,1702309855.830 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-12-11T15:50:55.830Z,1702309855.830 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2023-12-11T15:50:55.830Z,1702309855.830 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-12-11T15:50:55.830Z,1702309855.830 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-12-11T15:50:55.831Z,1702309855.831 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-12-11T15:50:55.831Z,1702309855.831 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-12-11T15:50:55.831Z,1702309855.831 [SBIT](IMPORTANT): ThrusterServo.currLimit=50 percent;

2023-12-11T15:50:55.831Z,1702309855.831 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=255.186074 cubic_centimeter;

2023-12-11T15:50:55.831Z,1702309855.831 [SBIT](IMPORTANT): VerticalControl.massDefault=-11.847765 millimeter;

2023-12-11T15:50:56.283Z,1702309856.283 [MissionManager](IMPORTANT): Started mission Startup
2023-12-11T15:50:56.284Z,1702309856.284 [Startup] Running Loop=1
2023-12-11T15:50:56.284Z,1702309856.284 [Startup](DEBUG): Aggregate::initialize Startup
2023-12-11T15:50:56.284Z,1702309856.284 [Startup:A.GoToSurface] Running Loop=1
2023-12-11T15:50:56.284Z,1702309856.284 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-12-11T15:50:56.285Z,1702309856.285 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-12-11T15:50:56.286Z,1702309856.286 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-12-11T15:50:56.286Z,1702309856.286 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-12-11T15:50:56.287Z,1702309856.287 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-12-11T15:50:56.287Z,1702309856.287 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-12-11T15:50:56.288Z,1702309856.288 [Startup:StartupSatComms] Running Loop=1
2023-12-11T15:50:56.289Z,1702309856.289 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-12-11T15:50:56.289Z,1702309856.289 [Startup:StartupSatComms:A] Running Loop=1
2023-12-11T15:50:56.619Z,1702309856.619 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-12-11T15:51:26.292Z,1702309886.292 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005056
2023-12-11T15:51:51.659Z,1702309911.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:51:54.508Z,1702309914.508 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-12-11T15:51:54.508Z,1702309914.508 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T15:51:54.519Z,1702309914.519 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T15:51:54.901Z,1702309914.901 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T15:51:54.901Z,1702309914.901 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-12-11T15:51:56.510Z,1702309916.510 [Startup:StartupSatComms:A](INFO): Timed out from 2023-12-11T15:50:56.3Z
2023-12-11T15:51:56.510Z,1702309916.510 [Startup:StartupSatComms:A] Stopped
2023-12-11T15:51:56.510Z,1702309916.510 [Startup:StartupSatComms:B] Running Loop=1
2023-12-11T15:51:56.913Z,1702309916.913 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-12-11T15:51:58.107Z,1702309918.107 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:52:03.454Z,1702309923.454 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20231211T154640/Courier0000.lzma
2023-12-11T15:52:04.456Z,1702309924.456 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154640/Courier0000.lzma.bak
2023-12-11T15:52:04.456Z,1702309924.456 [DataOverHttps](INFO): SBD MOMSN=19100087
2023-12-11T15:52:12.248Z,1702309932.248 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:52:20.100Z,1702309940.100 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20231211T154842/Courier0000.lzma
2023-12-11T15:52:21.100Z,1702309941.100 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0000.lzma.bak
2023-12-11T15:52:21.100Z,1702309941.100 [DataOverHttps](INFO): SBD MOMSN=19100090
2023-12-11T15:52:26.387Z,1702309946.387 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:52:36.654Z,1702309956.654 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20231211T154640/Express0001.lzma
2023-12-11T15:52:37.656Z,1702309957.656 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154640/Express0001.lzma.bak
2023-12-11T15:52:37.656Z,1702309957.656 [DataOverHttps](INFO): SBD MOMSN=19100093
2023-12-11T15:52:40.124Z,1702309960.124 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:52:53.343Z,1702309973.343 [DataOverHttps](INFO): Sending 844 bytes from file Logs/20231211T154842/Express0001.lzma
2023-12-11T15:52:53.455Z,1702309973.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:52:54.352Z,1702309974.352 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0001.lzma.bak
2023-12-11T15:52:54.352Z,1702309974.352 [DataOverHttps](INFO): SBD MOMSN=19100095
2023-12-11T15:52:55.506Z,1702309975.506 [Startup:StartupSatComms:B] Stopped
2023-12-11T15:52:55.507Z,1702309975.507 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-12-11T15:52:55.507Z,1702309975.507 [Startup:StartupSatComms] Stopped
2023-12-11T15:52:55.507Z,1702309975.507 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-12-11T15:52:55.508Z,1702309975.508 [Startup](INFO): Completed Startup
2023-12-11T15:52:55.508Z,1702309975.508 [MissionManager](INFO): Startup is completed.
2023-12-11T15:52:55.508Z,1702309975.508 [MissionManager](INFO): Uninitializing Mission Startup
2023-12-11T15:52:55.508Z,1702309975.508 [Startup] Stopped
2023-12-11T15:52:55.508Z,1702309975.508 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-12-11T15:52:55.508Z,1702309975.508 [Startup:A.GoToSurface] Stopped
2023-12-11T15:52:55.508Z,1702309975.508 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-12-11T15:52:55.897Z,1702309975.897 [MissionManager](IMPORTANT): Started mission Default
2023-12-11T15:52:55.897Z,1702309975.897 [Default] Running Loop=1
2023-12-11T15:52:55.897Z,1702309975.897 [Default](DEBUG): Aggregate::initialize Default
2023-12-11T15:52:55.897Z,1702309975.897 [Default:B.GoToSurface] Running Loop=1
2023-12-11T15:52:55.897Z,1702309975.897 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-12-11T15:52:55.898Z,1702309975.898 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-12-11T15:52:55.898Z,1702309975.898 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-12-11T15:52:55.898Z,1702309975.898 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-12-11T15:52:55.899Z,1702309975.899 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-12-11T15:52:55.899Z,1702309975.899 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-12-11T15:52:55.899Z,1702309975.899 [Default:A.Wait] Running Loop=1
2023-12-11T15:52:55.899Z,1702309975.899 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-12-11T15:53:07.192Z,1702309987.192 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:53:09.229Z,1702309989.229 [Default:A.Wait](INFO): Done Waiting.
2023-12-11T15:53:09.229Z,1702309989.229 [Default:A.Wait] Stopped
2023-12-11T15:53:09.229Z,1702309989.229 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T15:53:09.636Z,1702309989.636 [Default:CheckIn] Running Loop=1
2023-12-11T15:53:09.636Z,1702309989.636 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T15:53:09.637Z,1702309989.637 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T15:53:10.053Z,1702309990.053 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-12-11T15:53:21.740Z,1702310001.740 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:53:35.876Z,1702310015.876 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:53:50.045Z,1702310030.045 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:54:03.816Z,1702310043.816 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2023-12-11T15:54:04.167Z,1702310044.167 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T15:54:37.301Z,1702310077.301 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-12-11T15:54:55.480Z,1702310095.480 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-12-11T15:54:55.480Z,1702310095.480 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T15:54:55.490Z,1702310095.490 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T15:54:55.908Z,1702310095.908 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T15:54:55.908Z,1702310095.908 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-12-11T15:55:39.420Z,1702310139.420 [CommandExec](IMPORTANT): got command strobe off
2023-12-11T15:55:39.420Z,1702310139.420 [CommandExec](IMPORTANT): Deactivating strobe
2023-12-11T15:56:36.876Z,1702310196.876 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-12-11T15:56:36.879Z,1702310196.879 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T15:57:56.503Z,1702310276.503 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-12-11T15:57:56.503Z,1702310276.503 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T15:57:56.514Z,1702310276.514 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T15:57:56.885Z,1702310276.885 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T15:57:56.886Z,1702310276.886 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-12-11T15:58:09.808Z,1702310289.808 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-11T15:53:09.6Z
2023-12-11T15:58:09.808Z,1702310289.808 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T15:58:09.809Z,1702310289.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T15:58:10.240Z,1702310290.240 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-12-11T15:58:17.502Z,1702310297.502 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20231211T154842/Courier0004.lzma
2023-12-11T15:58:18.504Z,1702310298.504 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0004.lzma.bak
2023-12-11T15:58:18.504Z,1702310298.504 [DataOverHttps](INFO): SBD MOMSN=19100118
2023-12-11T15:58:33.927Z,1702310313.927 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20231211T154842/Express0005.lzma
2023-12-11T15:58:34.928Z,1702310314.928 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0005.lzma.bak
2023-12-11T15:58:34.928Z,1702310314.928 [DataOverHttps](INFO): SBD MOMSN=19100120
2023-12-11T15:58:36.064Z,1702310316.064 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T15:58:36.064Z,1702310316.064 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T15:58:36.064Z,1702310316.064 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T15:58:55.445Z,1702310335.445 [Power24vConverter](INFO): Powering down.
2023-12-11T15:59:34.639Z,1702310374.639 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-12-11T15:59:34.639Z,1702310374.639 [NAL9602] Data Fault, FailCount= 1
2023-12-11T15:59:34.639Z,1702310374.639 [NAL9602](ERROR): Data Fault
2023-12-11T15:59:34.657Z,1702310374.657 [CBIT](ERROR): Data Fault in component: NAL9602
2023-12-11T15:59:35.036Z,1702310375.036 [NAL9602](INFO): Powering down
2023-12-11T15:59:35.865Z,1702310375.865 [CBIT](INFO): Clearing failed state for component NAL9602
2023-12-11T15:59:35.865Z,1702310375.865 [NAL9602] No Fault, FailCount= 1
2023-12-11T16:00:05.340Z,1702310405.340 [NAL9602](INFO): Powering up NAL9602
2023-12-11T16:00:16.288Z,1702310416.288 [NAL9602](INFO): NAL9602 initialized
2023-12-11T16:00:57.509Z,1702310457.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-12-11T16:00:57.509Z,1702310457.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:00:57.520Z,1702310457.520 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:00:57.938Z,1702310457.938 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:00:57.938Z,1702310457.938 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-12-11T16:01:08.579Z,1702310468.579 [CommandExec](IMPORTANT): got command burn on
2023-12-11T16:01:08.580Z,1702310468.580 [CommandExec](IMPORTANT): Activating dropweight wire
2023-12-11T16:01:13.155Z,1702310473.155 [CommandExec](IMPORTANT): got command burn off
2023-12-11T16:01:13.156Z,1702310473.156 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-12-11T16:02:18.155Z,1702310538.155 [CommandExec](IMPORTANT): got command burn on
2023-12-11T16:02:18.156Z,1702310538.156 [CommandExec](IMPORTANT): Activating dropweight wire
2023-12-11T16:03:06.036Z,1702310586.036 [CommandExec](IMPORTANT): got command burn off
2023-12-11T16:03:06.036Z,1702310586.036 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-12-11T16:03:36.708Z,1702310616.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:03:36.708Z,1702310616.708 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:03:36.708Z,1702310616.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:03:36.708Z,1702310616.708 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:03:37.089Z,1702310617.089 [Default:CheckIn:D] Stopped
2023-12-11T16:03:37.089Z,1702310617.089 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:03:37.502Z,1702310617.502 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.686526 min 
2023-12-11T16:03:37.502Z,1702310617.502 [Default:CheckIn:E] Stopped
2023-12-11T16:03:37.502Z,1702310617.502 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:03:37.502Z,1702310617.502 [Default:CheckIn] Stopped
2023-12-11T16:03:37.502Z,1702310617.502 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:03:37.503Z,1702310617.503 [Default:CheckIn](INFO): Running loop #2
2023-12-11T16:03:37.503Z,1702310617.503 [Default:CheckIn] Running Loop=2
2023-12-11T16:03:37.503Z,1702310617.503 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:03:37.503Z,1702310617.503 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:03:58.527Z,1702310638.527 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-12-11T16:03:58.527Z,1702310638.527 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:03:58.539Z,1702310638.539 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:03:58.950Z,1702310638.950 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:03:58.950Z,1702310638.950 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-12-11T16:06:59.489Z,1702310819.489 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-12-11T16:06:59.489Z,1702310819.489 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:06:59.500Z,1702310819.500 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:06:59.914Z,1702310819.914 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:06:59.914Z,1702310819.914 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-12-11T16:08:37.664Z,1702310917.664 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-11T16:03:37.5Z
2023-12-11T16:08:37.664Z,1702310917.664 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:08:37.664Z,1702310917.664 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:08:44.834Z,1702310924.834 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231211T154842/Courier0007.lzma
2023-12-11T16:08:45.841Z,1702310925.841 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0007.lzma.bak
2023-12-11T16:08:45.841Z,1702310925.841 [DataOverHttps](INFO): SBD MOMSN=19100127
2023-12-11T16:09:03.874Z,1702310943.874 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20231211T154842/Express0008.lzma
2023-12-11T16:09:04.876Z,1702310944.876 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0008.lzma.bak
2023-12-11T16:09:04.876Z,1702310944.876 [DataOverHttps](INFO): SBD MOMSN=19100129
2023-12-11T16:09:05.993Z,1702310945.993 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:09:05.993Z,1702310945.993 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:09:05.993Z,1702310945.993 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:10:00.540Z,1702311000.540 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-12-11T16:10:00.540Z,1702311000.540 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:10:00.567Z,1702311000.567 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:10:00.956Z,1702311000.956 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:10:00.957Z,1702311000.957 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-12-11T16:10:17.904Z,1702311017.904 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-12-11T16:10:17.904Z,1702311017.904 [NAL9602] Data Fault, FailCount= 2
2023-12-11T16:10:17.904Z,1702311017.904 [NAL9602](ERROR): Data Fault
2023-12-11T16:10:17.983Z,1702311017.983 [CBIT](ERROR): Data Fault in component: NAL9602
2023-12-11T16:10:18.323Z,1702311018.323 [NAL9602](INFO): Powering down
2023-12-11T16:10:19.137Z,1702311019.137 [CBIT](INFO): Clearing failed state for component NAL9602
2023-12-11T16:10:19.137Z,1702311019.137 [NAL9602] No Fault, FailCount= 2
2023-12-11T16:10:48.608Z,1702311048.608 [NAL9602](INFO): Powering up NAL9602
2023-12-11T16:10:59.517Z,1702311059.517 [NAL9602](INFO): NAL9602 initialized
2023-12-11T16:11:06.979Z,1702311066.979 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:11:38.119Z,1702311098.119 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:12:09.243Z,1702311129.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:12:40.371Z,1702311160.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:13:01.535Z,1702311181.535 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-12-11T16:13:01.535Z,1702311181.535 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:13:01.546Z,1702311181.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:13:01.953Z,1702311181.953 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:13:01.953Z,1702311181.953 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-12-11T16:13:11.507Z,1702311191.507 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:13:17.531Z,1702311197.531 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.783310
2023-12-11T16:14:06.597Z,1702311246.597 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:14:06.597Z,1702311246.597 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:14:06.597Z,1702311246.597 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:14:06.597Z,1702311246.597 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:14:06.997Z,1702311246.997 [Default:CheckIn:D] Stopped
2023-12-11T16:14:06.997Z,1702311246.997 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:14:07.385Z,1702311247.385 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.185000 min 
2023-12-11T16:14:07.385Z,1702311247.385 [Default:CheckIn:E] Stopped
2023-12-11T16:14:07.385Z,1702311247.385 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:14:07.385Z,1702311247.385 [Default:CheckIn] Stopped
2023-12-11T16:14:07.385Z,1702311247.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:14:07.386Z,1702311247.386 [Default:CheckIn](INFO): Running loop #3
2023-12-11T16:14:07.386Z,1702311247.386 [Default:CheckIn] Running Loop=3
2023-12-11T16:14:07.386Z,1702311247.386 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:14:07.386Z,1702311247.386 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:14:12.771Z,1702311252.771 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:14:43.915Z,1702311283.915 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:15:15.039Z,1702311315.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:15:46.163Z,1702311346.163 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:16:02.532Z,1702311362.532 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-12-11T16:16:02.532Z,1702311362.532 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:16:02.543Z,1702311362.543 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:16:02.933Z,1702311362.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:16:02.933Z,1702311362.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-12-11T16:16:17.287Z,1702311377.287 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:16:48.423Z,1702311408.423 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:17:19.547Z,1702311439.547 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:17:50.671Z,1702311470.671 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:18:21.819Z,1702311501.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:18:52.943Z,1702311532.943 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:19:03.514Z,1702311543.514 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2023-12-11T16:19:03.514Z,1702311543.514 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:19:03.541Z,1702311543.541 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:19:03.929Z,1702311543.929 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:19:03.929Z,1702311543.929 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2023-12-11T16:19:07.573Z,1702311547.573 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-12-11T16:14:07.4Z
2023-12-11T16:19:07.573Z,1702311547.573 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:19:07.573Z,1702311547.573 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:19:24.067Z,1702311564.067 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:19:39.514Z,1702311579.514 [NAL9602](INFO): SBD MO Status=2, MOMSN=45817, MT Status=2, MTMSN=0
2023-12-11T16:19:39.515Z,1702311579.515 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T16:19:55.191Z,1702311595.191 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:20:23.191Z,1702311623.191 [NAL9602](INFO): SBD MO Status=2, MOMSN=45817, MT Status=2, MTMSN=0
2023-12-11T16:20:23.191Z,1702311623.191 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T16:20:26.315Z,1702311626.315 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:20:57.447Z,1702311657.447 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:21:01.164Z,1702311661.164 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-12-11T16:21:01.164Z,1702311661.164 [NAL9602] Data Fault, FailCount= 3
2023-12-11T16:21:01.164Z,1702311661.164 [NAL9602](ERROR): Data Fault
2023-12-11T16:21:01.182Z,1702311661.182 [CBIT](ERROR): Data Fault in component: NAL9602
2023-12-11T16:21:01.570Z,1702311661.570 [NAL9602](INFO): Powering down
2023-12-11T16:21:02.393Z,1702311662.393 [CBIT](INFO): Clearing failed state for component NAL9602
2023-12-11T16:21:02.393Z,1702311662.393 [NAL9602] No Fault, FailCount= 3
2023-12-11T16:21:28.583Z,1702311688.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:21:31.868Z,1702311691.868 [NAL9602](INFO): Powering up NAL9602
2023-12-11T16:21:42.776Z,1702311702.776 [NAL9602](INFO): NAL9602 initialized
2023-12-11T16:21:59.727Z,1702311719.727 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:22:04.623Z,1702311724.623 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2023-12-11T16:22:04.623Z,1702311724.623 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-12-11T16:22:04.634Z,1702311724.634 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-12-11T16:22:05.013Z,1702311725.013 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-12-11T16:22:05.013Z,1702311725.013 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2023-12-11T16:22:30.851Z,1702311750.851 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:22:48.666Z,1702311768.666 [NAL9602](INFO): SBD MO Status=2, MOMSN=45817, MT Status=2, MTMSN=0
2023-12-11T16:22:48.666Z,1702311768.666 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T16:23:01.975Z,1702311781.975 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T16:23:15.374Z,1702311795.374 [NAL9602](INFO): SBD MO Status=1, MOMSN=45817, MT Status=0, MTMSN=0
2023-12-11T16:23:15.427Z,1702311795.427 [NAL9602](INFO): Sent 39 bytes from file Logs/20231211T154842/Courier0010.lzma
2023-12-11T16:23:15.427Z,1702311795.427 [NAL9602](INFO): Packets left to send: 0
2023-12-11T16:23:30.250Z,1702311810.250 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002773
2023-12-11T16:23:36.017Z,1702311816.017 [NAL9602](INFO): SBD MO Status=1, MOMSN=45818, MT Status=0, MTMSN=0
2023-12-11T16:23:36.067Z,1702311816.067 [NAL9602](INFO): Sent 149 bytes from file Logs/20231211T154842/Express0011.lzma
2023-12-11T16:23:36.067Z,1702311816.067 [NAL9602](INFO): Packets left to send: 0
2023-12-11T16:23:36.853Z,1702311816.853 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:23:36.853Z,1702311816.853 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:23:36.853Z,1702311816.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:23:37.230Z,1702311817.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162335.00,A,3648.14208,N,12147.24351,W,3.091,108.15,111223,,,A*7E
2023-12-11T16:23:37.233Z,1702311817.233 [NAL9602](INFO): GPS fix at 20231211T162335: (36.802368, -121.787392)
2023-12-11T16:24:09.583Z,1702311849.583 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T16:28:37.555Z,1702312117.555 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:28:37.555Z,1702312117.555 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:28:37.555Z,1702312117.555 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:28:37.555Z,1702312117.555 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:28:37.954Z,1702312117.954 [Default:CheckIn:D] Stopped
2023-12-11T16:28:37.954Z,1702312117.954 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:28:38.354Z,1702312118.354 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.700940 min 
2023-12-11T16:28:38.354Z,1702312118.354 [Default:CheckIn:E] Stopped
2023-12-11T16:28:38.354Z,1702312118.354 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:28:38.354Z,1702312118.354 [Default:CheckIn] Stopped
2023-12-11T16:28:38.355Z,1702312118.355 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:28:38.355Z,1702312118.355 [Default:CheckIn](INFO): Running loop #4
2023-12-11T16:28:38.355Z,1702312118.355 [Default:CheckIn] Running Loop=4
2023-12-11T16:28:38.355Z,1702312118.355 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:28:38.355Z,1702312118.355 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:28:40.372Z,1702312120.372 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162838.00,A,3648.14230,N,12147.23013,W,0.039,88.48,111223,,,D*44
2023-12-11T16:28:40.374Z,1702312120.374 [NAL9602](INFO): GPS fix at 20231211T162838: (36.802372, -121.787169)
2023-12-11T16:28:40.385Z,1702312120.385 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:28:40.385Z,1702312120.385 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:28:48.462Z,1702312128.462 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20231211T154842/Courier0013.lzma
2023-12-11T16:28:48.851Z,1702312128.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=45819, MT Status=0, MTMSN=0
2023-12-11T16:28:48.851Z,1702312128.851 [NAL9602](INFO): No messages in MT queue
2023-12-11T16:28:49.465Z,1702312129.465 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0013.lzma.bak
2023-12-11T16:28:49.465Z,1702312129.465 [DataOverHttps](INFO): SBD MOMSN=19100157
2023-12-11T16:29:08.015Z,1702312148.015 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20231211T154842/Express0014.lzma
2023-12-11T16:29:09.016Z,1702312149.016 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0014.lzma.bak
2023-12-11T16:29:09.017Z,1702312149.017 [DataOverHttps](INFO): SBD MOMSN=19100160
2023-12-11T16:29:10.318Z,1702312150.318 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:29:10.318Z,1702312150.318 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:29:10.318Z,1702312150.318 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:29:19.596Z,1702312159.596 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T16:34:10.943Z,1702312450.943 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:34:10.943Z,1702312450.943 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:34:10.943Z,1702312450.943 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:34:10.944Z,1702312450.944 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:34:11.350Z,1702312451.350 [Default:CheckIn:D] Stopped
2023-12-11T16:34:11.350Z,1702312451.350 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.257552 min 
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn:E] Stopped
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn] Stopped
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn](INFO): Running loop #5
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn] Running Loop=5
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:34:11.767Z,1702312451.767 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:34:13.761Z,1702312453.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163413.00,A,3648.14092,N,12147.22932,W,0.019,88.48,111223,,,D*43
2023-12-11T16:34:13.764Z,1702312453.764 [NAL9602](INFO): GPS fix at 20231211T163413: (36.802349, -121.787155)
2023-12-11T16:34:13.774Z,1702312453.774 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:34:13.774Z,1702312453.774 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:34:23.230Z,1702312463.230 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0016.lzma
2023-12-11T16:34:24.232Z,1702312464.232 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0016.lzma.bak
2023-12-11T16:34:24.233Z,1702312464.233 [DataOverHttps](INFO): SBD MOMSN=19100167
2023-12-11T16:34:40.814Z,1702312480.814 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20231211T154842/Express0017.lzma
2023-12-11T16:34:41.816Z,1702312481.816 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0017.lzma.bak
2023-12-11T16:34:41.816Z,1702312481.816 [DataOverHttps](INFO): SBD MOMSN=19100170
2023-12-11T16:34:42.899Z,1702312482.899 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:34:42.899Z,1702312482.899 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:34:42.899Z,1702312482.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:34:59.457Z,1702312499.457 [NAL9602](INFO): SBD MO Status=0, MOMSN=45820, MT Status=0, MTMSN=0
2023-12-11T16:34:59.457Z,1702312499.457 [NAL9602](INFO): No messages in MT queue
2023-12-11T16:35:30.159Z,1702312530.159 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T16:39:43.686Z,1702312783.686 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:39:43.712Z,1702312783.712 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:39:43.712Z,1702312783.712 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:39:43.712Z,1702312783.712 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:39:44.085Z,1702312784.085 [Default:CheckIn:D] Stopped
2023-12-11T16:39:44.085Z,1702312784.085 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:39:44.499Z,1702312784.499 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.803133 min 
2023-12-11T16:39:44.499Z,1702312784.499 [Default:CheckIn:E] Stopped
2023-12-11T16:39:44.500Z,1702312784.500 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:39:44.500Z,1702312784.500 [Default:CheckIn] Stopped
2023-12-11T16:39:44.500Z,1702312784.500 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:39:44.500Z,1702312784.500 [Default:CheckIn](INFO): Running loop #6
2023-12-11T16:39:44.500Z,1702312784.500 [Default:CheckIn] Running Loop=6
2023-12-11T16:39:44.500Z,1702312784.500 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:39:44.500Z,1702312784.500 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:39:46.518Z,1702312786.518 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163945.00,A,3648.14051,N,12147.22969,W,0.019,88.48,111223,,,D*4C
2023-12-11T16:39:46.522Z,1702312786.522 [NAL9602](INFO): GPS fix at 20231211T163945: (36.802342, -121.787161)
2023-12-11T16:39:46.533Z,1702312786.533 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:39:46.533Z,1702312786.533 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:39:54.530Z,1702312794.530 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0019.lzma
2023-12-11T16:39:55.532Z,1702312795.532 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0019.lzma.bak
2023-12-11T16:39:55.532Z,1702312795.532 [DataOverHttps](INFO): SBD MOMSN=19100175
2023-12-11T16:40:11.307Z,1702312811.307 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0020.lzma
2023-12-11T16:40:12.308Z,1702312812.308 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0020.lzma.bak
2023-12-11T16:40:12.308Z,1702312812.308 [DataOverHttps](INFO): SBD MOMSN=19100178
2023-12-11T16:40:12.765Z,1702312812.765 [NAL9602](INFO): SBD MO Status=0, MOMSN=45821, MT Status=0, MTMSN=0
2023-12-11T16:40:12.765Z,1702312812.765 [NAL9602](INFO): No messages in MT queue
2023-12-11T16:40:13.583Z,1702312813.583 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:40:13.583Z,1702312813.583 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:40:13.583Z,1702312813.583 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:40:28.592Z,1702312828.592 [CommandExec](IMPORTANT): got command show variable sonardyne
2023-12-11T16:40:28.658Z,1702312828.658 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none)
2023-12-11T16:40:28.658Z,1702312828.658 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none)
2023-12-11T16:40:28.659Z,1702312828.659 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second)
2023-12-11T16:40:28.714Z,1702312828.714 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool)
2023-12-11T16:40:28.714Z,1702312828.714 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool)
2023-12-11T16:40:28.729Z,1702312828.729 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent)
2023-12-11T16:40:28.730Z,1702312828.730 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent)
2023-12-11T16:40:28.789Z,1702312828.789 [CommandExec](IMPORTANT): Sonardyne_Nano.enableBroadcast (bool)
2023-12-11T16:40:28.789Z,1702312828.789 [CommandExec](IMPORTANT): Sonardyne_Nano.component_voltage (volt)
2023-12-11T16:40:28.790Z,1702312828.790 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgVoltage (volt)
2023-12-11T16:40:28.790Z,1702312828.790 [CommandExec](IMPORTANT): Sonardyne_Nano.component_current (milliampere)
2023-12-11T16:40:28.790Z,1702312828.790 [CommandExec](IMPORTANT): Sonardyne_Nano.component_avgCurrent (milliampere)
2023-12-11T16:40:28.791Z,1702312828.791 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent (percent)
2023-12-11T16:40:43.487Z,1702312843.487 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T16:41:00.700Z,1702312860.700 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2023-12-11T16:41:00.700Z,1702312860.700 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 90.000000 %
2023-12-11T16:41:12.367Z,1702312872.367 [CommandExec](IMPORTANT): got command failComponent
2023-12-11T16:41:12.367Z,1702312872.367 [CommandExec](IMPORTANT): Failed components:
2023-12-11T16:41:12.367Z,1702312872.367 [CommandExec](IMPORTANT): No failed Components.
2023-12-11T16:43:54.496Z,1702313034.496 [DataOverHttps](IMPORTANT): SBD MTMSN=20231211T164353
2023-12-11T16:44:01.946Z,1702313041.946 [DataOverHttps](INFO): Received command: ibit
2023-12-11T16:44:01.975Z,1702313041.975 [CommandExec](IMPORTANT): got command ibit
2023-12-11T16:44:02.034Z,1702313042.034 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-12-11T16:44:02.034Z,1702313042.034 [IBIT](IMPORTANT): Beginning control surface checks.
2023-12-11T16:44:02.071Z,1702313042.071 [CBIT](IMPORTANT): Beginning ground fault scan
2023-12-11T16:44:03.634Z,1702313043.634 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164402.00,A,3648.13945,N,12147.22952,W,0.039,88.48,111223,,,D*44
2023-12-11T16:44:03.636Z,1702313043.636 [NAL9602](INFO): GPS fix at 20231211T164402: (36.802324, -121.787159)
2023-12-11T16:44:04.951Z,1702313044.951 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-12-11T16:44:12.964Z,1702313052.964 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002925
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002103
CHAN A3 (5V): -0.001557
CHAN B0 (3.3V): 0.000034
CHAN B1 (3.15aV): 0.000074
CHAN B2 (3.15bV): -0.000063
CHAN B3 (GND): -0.000072
OPEN: -0.000280
Full Scale: +/- 1 mA
2023-12-11T16:44:27.481Z,1702313067.481 [NAL9602](INFO): SBD MO Status=2, MOMSN=45822, MT Status=2, MTMSN=0
2023-12-11T16:44:27.481Z,1702313067.481 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T16:44:50.532Z,1702313090.532 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802322 Longitude: -121.787155
2023-12-11T16:44:50.940Z,1702313090.940 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2023-12-11T16:44:50.940Z,1702313090.940 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-12-11T16:44:50.941Z,1702313090.941 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-12-11T16:44:51.353Z,1702313091.353 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-12-11T16:44:51.353Z,1702313091.353 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-12-11T16:44:51.353Z,1702313091.353 [IBIT](IMPORTANT): Pressure:9.686417 PSI
2023-12-11T16:44:51.354Z,1702313091.354 [IBIT](IMPORTANT): Humidity:12.562569 %
2023-12-11T16:44:51.734Z,1702313091.734 [IBIT](IMPORTANT): Vehicle Pitch:-0.828783 degrees
2023-12-11T16:44:51.734Z,1702313091.734 [IBIT](IMPORTANT): Vehicle  Roll:3.095035 degrees
2023-12-11T16:44:51.735Z,1702313091.735 [IBIT](IMPORTANT): Vehicle  Heading:202.357925 degrees
2023-12-11T16:44:52.164Z,1702313092.164 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-12-11T16:44:52.164Z,1702313092.164 [IBIT](IMPORTANT): buoyancyNeutral: 255.186081 cc
2023-12-11T16:44:52.164Z,1702313092.164 [IBIT](IMPORTANT): massDefault: -1.184777 cm
2023-12-11T16:44:52.165Z,1702313092.165 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-12-11T16:44:52.165Z,1702313092.165 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-12-11T16:44:52.165Z,1702313092.165 [IBIT](IMPORTANT): IBIT PASSED
2023-12-11T16:44:58.581Z,1702313098.581 [NAL9602](INFO): SBD MO Status=0, MOMSN=45822, MT Status=0, MTMSN=0
2023-12-11T16:44:58.581Z,1702313098.581 [NAL9602](INFO): No messages in MT queue
2023-12-11T16:45:14.347Z,1702313114.347 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:45:14.348Z,1702313114.348 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:45:14.348Z,1702313114.348 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:45:14.348Z,1702313114.348 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:45:14.767Z,1702313114.767 [Default:CheckIn:D] Stopped
2023-12-11T16:45:14.767Z,1702313114.767 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:45:15.153Z,1702313115.153 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.314486 min 
2023-12-11T16:45:15.153Z,1702313115.153 [Default:CheckIn:E] Stopped
2023-12-11T16:45:15.153Z,1702313115.153 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:45:15.153Z,1702313115.153 [Default:CheckIn] Stopped
2023-12-11T16:45:15.154Z,1702313115.154 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:45:15.154Z,1702313115.154 [Default:CheckIn](INFO): Running loop #7
2023-12-11T16:45:15.154Z,1702313115.154 [Default:CheckIn] Running Loop=7
2023-12-11T16:45:15.154Z,1702313115.154 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:45:15.154Z,1702313115.154 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:45:17.169Z,1702313117.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164516.00,A,3648.14020,N,12147.22868,W,0.019,88.48,111223,,,D*47
2023-12-11T16:45:17.172Z,1702313117.172 [NAL9602](INFO): GPS fix at 20231211T164516: (36.802337, -121.787145)
2023-12-11T16:45:17.183Z,1702313117.183 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:45:17.183Z,1702313117.183 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:45:24.182Z,1702313124.182 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0022.lzma
2023-12-11T16:45:25.184Z,1702313125.184 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0022.lzma.bak
2023-12-11T16:45:25.184Z,1702313125.184 [DataOverHttps](INFO): SBD MOMSN=19100184
2023-12-11T16:45:40.870Z,1702313140.870 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20231211T154842/Courier0025.lzma
2023-12-11T16:45:41.872Z,1702313141.872 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0025.lzma.bak
2023-12-11T16:45:41.873Z,1702313141.873 [DataOverHttps](INFO): SBD MOMSN=19100187
2023-12-11T16:45:49.505Z,1702313149.505 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T16:45:57.568Z,1702313157.568 [DataOverHttps](INFO): Sending 1099 bytes from file Logs/20231211T154842/Express0023.lzma
2023-12-11T16:45:58.568Z,1702313158.568 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0023.lzma.bak
2023-12-11T16:45:58.568Z,1702313158.568 [DataOverHttps](INFO): SBD MOMSN=19100189
2023-12-11T16:45:59.622Z,1702313159.622 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:45:59.622Z,1702313159.622 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:45:59.622Z,1702313159.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:51:00.200Z,1702313460.200 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:51:00.200Z,1702313460.200 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:51:00.200Z,1702313460.200 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:51:00.200Z,1702313460.200 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:51:00.601Z,1702313460.601 [Default:CheckIn:D] Stopped
2023-12-11T16:51:00.601Z,1702313460.601 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:51:01.011Z,1702313461.011 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.078406 min 
2023-12-11T16:51:01.011Z,1702313461.011 [Default:CheckIn:E] Stopped
2023-12-11T16:51:01.012Z,1702313461.012 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:51:01.012Z,1702313461.012 [Default:CheckIn] Stopped
2023-12-11T16:51:01.012Z,1702313461.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:51:01.012Z,1702313461.012 [Default:CheckIn](INFO): Running loop #8
2023-12-11T16:51:01.012Z,1702313461.012 [Default:CheckIn] Running Loop=8
2023-12-11T16:51:01.012Z,1702313461.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:51:01.012Z,1702313461.012 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:51:03.021Z,1702313463.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165102.00,A,3648.14122,N,12147.22848,W,0.039,88.48,111223,,,D*44
2023-12-11T16:51:03.032Z,1702313463.032 [NAL9602](INFO): GPS fix at 20231211T165102: (36.802354, -121.787141)
2023-12-11T16:51:03.044Z,1702313463.044 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:51:03.044Z,1702313463.044 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:51:10.742Z,1702313470.742 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0027.lzma
2023-12-11T16:51:11.744Z,1702313471.744 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0027.lzma.bak
2023-12-11T16:51:11.744Z,1702313471.744 [DataOverHttps](INFO): SBD MOMSN=19100270
2023-12-11T16:51:17.965Z,1702313477.965 [NAL9602](INFO): SBD MO Status=0, MOMSN=45823, MT Status=0, MTMSN=0
2023-12-11T16:51:17.965Z,1702313477.965 [NAL9602](INFO): No messages in MT queue
2023-12-11T16:51:27.686Z,1702313487.686 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0026.lzma
2023-12-11T16:51:28.688Z,1702313488.688 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0026.lzma.bak
2023-12-11T16:51:28.689Z,1702313488.689 [DataOverHttps](INFO): SBD MOMSN=19100273
2023-12-11T16:51:29.700Z,1702313489.700 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:51:29.700Z,1702313489.700 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:51:29.700Z,1702313489.700 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:51:48.671Z,1702313508.671 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T16:56:30.274Z,1702313790.274 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T16:56:30.274Z,1702313790.274 [Default:CheckIn:C.Wait] Stopped
2023-12-11T16:56:30.274Z,1702313790.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T16:56:30.274Z,1702313790.274 [Default:CheckIn:D] Running Loop=1
2023-12-11T16:56:30.688Z,1702313790.688 [Default:CheckIn:D] Stopped
2023-12-11T16:56:30.688Z,1702313790.688 [Default:CheckIn:E] Running Loop=1
2023-12-11T16:56:31.100Z,1702313791.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.579842 min 
2023-12-11T16:56:31.100Z,1702313791.100 [Default:CheckIn:E] Stopped
2023-12-11T16:56:31.100Z,1702313791.100 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T16:56:31.100Z,1702313791.100 [Default:CheckIn] Stopped
2023-12-11T16:56:31.101Z,1702313791.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T16:56:31.101Z,1702313791.101 [Default:CheckIn](INFO): Running loop #9
2023-12-11T16:56:31.101Z,1702313791.101 [Default:CheckIn] Running Loop=9
2023-12-11T16:56:31.101Z,1702313791.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T16:56:31.101Z,1702313791.101 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T16:56:33.097Z,1702313793.097 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165632.00,A,3648.14101,N,12147.22914,W,0.039,0.00,111223,,,D*75
2023-12-11T16:56:33.100Z,1702313793.100 [NAL9602](INFO): GPS fix at 20231211T165632: (36.802350, -121.787152)
2023-12-11T16:56:33.131Z,1702313793.131 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T16:56:33.131Z,1702313793.131 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T16:56:40.122Z,1702313800.122 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0031.lzma
2023-12-11T16:56:41.124Z,1702313801.124 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0031.lzma.bak
2023-12-11T16:56:41.124Z,1702313801.124 [DataOverHttps](INFO): SBD MOMSN=19100317
2023-12-11T16:56:52.514Z,1702313812.514 [NAL9602](INFO): SBD MO Status=0, MOMSN=45824, MT Status=0, MTMSN=0
2023-12-11T16:56:52.514Z,1702313812.514 [NAL9602](INFO): No messages in MT queue
2023-12-11T16:56:59.786Z,1702313819.786 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0032.lzma
2023-12-11T16:57:00.788Z,1702313820.788 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0032.lzma.bak
2023-12-11T16:57:00.788Z,1702313820.788 [DataOverHttps](INFO): SBD MOMSN=19100320
2023-12-11T16:57:01.837Z,1702313821.837 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T16:57:01.837Z,1702313821.837 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T16:57:01.837Z,1702313821.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T16:57:23.251Z,1702313843.251 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:01:36.557Z,1702314096.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T17:01:36.564Z,1702314096.564 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 6.
2023-12-11T17:01:36.567Z,1702314096.567 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T17:02:02.430Z,1702314122.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:02:02.430Z,1702314122.430 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:02:02.430Z,1702314122.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:02:02.430Z,1702314122.430 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:02:02.843Z,1702314122.843 [Default:CheckIn:D] Stopped
2023-12-11T17:02:02.843Z,1702314122.843 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:02:03.238Z,1702314123.238 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.115771 min 
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn:E] Stopped
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn] Stopped
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn](INFO): Running loop #10
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn] Running Loop=10
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:02:03.239Z,1702314123.239 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:02:05.249Z,1702314125.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170204.00,A,3648.14075,N,12147.22947,W,0.039,0.00,111223,,,D*74
2023-12-11T17:02:05.252Z,1702314125.252 [NAL9602](INFO): GPS fix at 20231211T170204: (36.802346, -121.787158)
2023-12-11T17:02:05.263Z,1702314125.263 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:02:05.263Z,1702314125.263 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:02:12.910Z,1702314132.910 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20231211T154842/Courier0034.lzma
2023-12-11T17:02:13.912Z,1702314133.912 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0034.lzma.bak
2023-12-11T17:02:13.913Z,1702314133.913 [DataOverHttps](INFO): SBD MOMSN=19100363
2023-12-11T17:02:33.158Z,1702314153.158 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0035.lzma
2023-12-11T17:02:34.160Z,1702314154.160 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0035.lzma.bak
2023-12-11T17:02:34.160Z,1702314154.160 [DataOverHttps](INFO): SBD MOMSN=19100366
2023-12-11T17:02:35.244Z,1702314155.244 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:02:35.244Z,1702314155.244 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:02:35.244Z,1702314155.244 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:03:21.665Z,1702314201.665 [NAL9602](INFO): SBD MO Status=2, MOMSN=45825, MT Status=2, MTMSN=0
2023-12-11T17:03:21.665Z,1702314201.665 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T17:03:41.053Z,1702314221.053 [NAL9602](INFO): SBD MO Status=0, MOMSN=45825, MT Status=0, MTMSN=0
2023-12-11T17:03:41.053Z,1702314221.053 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:04:11.755Z,1702314251.755 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:07:35.838Z,1702314455.838 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:07:35.838Z,1702314455.838 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:07:35.838Z,1702314455.838 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:07:35.838Z,1702314455.838 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:07:36.234Z,1702314456.234 [Default:CheckIn:D] Stopped
2023-12-11T17:07:36.234Z,1702314456.234 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:07:36.638Z,1702314456.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.672274 min 
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn:E] Stopped
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn] Stopped
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn](INFO): Running loop #11
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn] Running Loop=11
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:07:36.639Z,1702314456.639 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:07:38.649Z,1702314458.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170737.00,A,3648.14119,N,12147.22941,W,0.019,0.00,111223,,,D*7E
2023-12-11T17:07:38.652Z,1702314458.652 [NAL9602](INFO): GPS fix at 20231211T170737: (36.802353, -121.787157)
2023-12-11T17:07:38.663Z,1702314458.663 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:07:38.663Z,1702314458.663 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:07:45.598Z,1702314465.598 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0037.lzma
2023-12-11T17:07:46.600Z,1702314466.600 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0037.lzma.bak
2023-12-11T17:07:46.600Z,1702314466.600 [DataOverHttps](INFO): SBD MOMSN=19100386
2023-12-11T17:07:56.021Z,1702314476.021 [NAL9602](INFO): SBD MO Status=0, MOMSN=45826, MT Status=0, MTMSN=0
2023-12-11T17:07:56.021Z,1702314476.021 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:08:02.278Z,1702314482.278 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0038.lzma
2023-12-11T17:08:03.281Z,1702314483.281 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0038.lzma.bak
2023-12-11T17:08:03.281Z,1702314483.281 [DataOverHttps](INFO): SBD MOMSN=19100389
2023-12-11T17:08:04.512Z,1702314484.512 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:08:04.512Z,1702314484.512 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:08:04.512Z,1702314484.512 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:08:26.724Z,1702314506.724 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:13:05.163Z,1702314785.163 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:13:05.163Z,1702314785.163 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:13:05.163Z,1702314785.163 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:13:05.163Z,1702314785.163 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:13:05.566Z,1702314785.566 [Default:CheckIn:D] Stopped
2023-12-11T17:13:05.566Z,1702314785.566 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:13:05.979Z,1702314785.979 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.161149 min 
2023-12-11T17:13:05.979Z,1702314785.979 [Default:CheckIn:E] Stopped
2023-12-11T17:13:05.979Z,1702314785.979 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:13:05.979Z,1702314785.979 [Default:CheckIn] Stopped
2023-12-11T17:13:05.979Z,1702314785.979 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:13:05.979Z,1702314785.979 [Default:CheckIn](INFO): Running loop #12
2023-12-11T17:13:05.980Z,1702314785.980 [Default:CheckIn] Running Loop=12
2023-12-11T17:13:05.980Z,1702314785.980 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:13:05.980Z,1702314785.980 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:13:07.981Z,1702314787.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171307.00,A,3648.13935,N,12147.22991,W,0.058,0.00,111223,,,D*71
2023-12-11T17:13:07.984Z,1702314787.984 [NAL9602](INFO): GPS fix at 20231211T171307: (36.802323, -121.787165)
2023-12-11T17:13:08.016Z,1702314788.016 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:13:08.016Z,1702314788.016 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:13:15.778Z,1702314795.778 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0040.lzma
2023-12-11T17:13:16.780Z,1702314796.780 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0040.lzma.bak
2023-12-11T17:13:16.780Z,1702314796.780 [DataOverHttps](INFO): SBD MOMSN=19100427
2023-12-11T17:13:32.606Z,1702314812.606 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0041.lzma
2023-12-11T17:13:33.608Z,1702314813.608 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0041.lzma.bak
2023-12-11T17:13:33.609Z,1702314813.609 [DataOverHttps](INFO): SBD MOMSN=19100435
2023-12-11T17:13:34.245Z,1702314814.245 [NAL9602](INFO): SBD MO Status=0, MOMSN=45827, MT Status=0, MTMSN=0
2023-12-11T17:13:34.245Z,1702314814.245 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:13:34.653Z,1702314814.653 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:13:34.653Z,1702314814.653 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:13:34.653Z,1702314814.653 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:14:04.947Z,1702314844.947 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:18:35.234Z,1702315115.234 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:18:35.234Z,1702315115.234 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:18:35.234Z,1702315115.234 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:18:35.234Z,1702315115.234 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:18:35.642Z,1702315115.642 [Default:CheckIn:D] Stopped
2023-12-11T17:18:35.642Z,1702315115.642 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:18:36.056Z,1702315116.056 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.662419 min 
2023-12-11T17:18:36.056Z,1702315116.056 [Default:CheckIn:E] Stopped
2023-12-11T17:18:36.056Z,1702315116.056 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:18:36.056Z,1702315116.056 [Default:CheckIn] Stopped
2023-12-11T17:18:36.057Z,1702315116.057 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:18:36.057Z,1702315116.057 [Default:CheckIn](INFO): Running loop #13
2023-12-11T17:18:36.057Z,1702315116.057 [Default:CheckIn] Running Loop=13
2023-12-11T17:18:36.057Z,1702315116.057 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:18:36.057Z,1702315116.057 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:18:38.054Z,1702315118.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171837.00,A,3648.13948,N,12147.23118,W,0.117,0.00,111223,,,D*71
2023-12-11T17:18:38.056Z,1702315118.056 [NAL9602](INFO): GPS fix at 20231211T171837: (36.802325, -121.787186)
2023-12-11T17:18:38.066Z,1702315118.066 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:18:38.066Z,1702315118.066 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:18:45.534Z,1702315125.534 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0043.lzma
2023-12-11T17:18:46.536Z,1702315126.536 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0043.lzma.bak
2023-12-11T17:18:46.536Z,1702315126.536 [DataOverHttps](INFO): SBD MOMSN=19100525
2023-12-11T17:18:56.288Z,1702315136.288 [NAL9602](INFO): SBD MO Status=0, MOMSN=45828, MT Status=0, MTMSN=0
2023-12-11T17:18:56.288Z,1702315136.288 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:19:05.326Z,1702315145.326 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231211T154842/Express0044.lzma
2023-12-11T17:19:06.328Z,1702315146.328 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0044.lzma.bak
2023-12-11T17:19:06.329Z,1702315146.329 [DataOverHttps](INFO): SBD MOMSN=19100528
2023-12-11T17:19:07.611Z,1702315147.611 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:19:07.611Z,1702315147.611 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:19:07.611Z,1702315147.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:19:26.995Z,1702315166.995 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:24:08.215Z,1702315448.215 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:24:08.216Z,1702315448.216 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:24:08.216Z,1702315448.216 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:24:08.216Z,1702315448.216 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:24:08.630Z,1702315448.630 [Default:CheckIn:D] Stopped
2023-12-11T17:24:08.630Z,1702315448.630 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:24:09.026Z,1702315449.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.212215 min 
2023-12-11T17:24:09.026Z,1702315449.026 [Default:CheckIn:E] Stopped
2023-12-11T17:24:09.026Z,1702315449.026 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:24:09.026Z,1702315449.026 [Default:CheckIn] Stopped
2023-12-11T17:24:09.026Z,1702315449.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:24:09.026Z,1702315449.026 [Default:CheckIn](INFO): Running loop #14
2023-12-11T17:24:09.027Z,1702315449.027 [Default:CheckIn] Running Loop=14
2023-12-11T17:24:09.027Z,1702315449.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:24:09.027Z,1702315449.027 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:24:11.033Z,1702315451.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172410.00,A,3648.14183,N,12147.22764,W,0.000,0.00,111223,,,D*78
2023-12-11T17:24:11.035Z,1702315451.035 [NAL9602](INFO): GPS fix at 20231211T172410: (36.802364, -121.787127)
2023-12-11T17:24:11.046Z,1702315451.046 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:24:11.046Z,1702315451.046 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:24:19.562Z,1702315459.562 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0046.lzma
2023-12-11T17:24:20.564Z,1702315460.564 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0046.lzma.bak
2023-12-11T17:24:20.565Z,1702315460.565 [DataOverHttps](INFO): SBD MOMSN=19100534
2023-12-11T17:24:38.890Z,1702315478.890 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20231211T154842/Express0047.lzma
2023-12-11T17:24:39.893Z,1702315479.893 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0047.lzma.bak
2023-12-11T17:24:39.893Z,1702315479.893 [DataOverHttps](INFO): SBD MOMSN=19100540
2023-12-11T17:24:40.979Z,1702315480.979 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:24:40.979Z,1702315480.979 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:24:40.979Z,1702315480.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:24:47.825Z,1702315487.825 [NAL9602](INFO): SBD MO Status=2, MOMSN=45829, MT Status=2, MTMSN=0
2023-12-11T17:24:47.825Z,1702315487.825 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T17:25:13.681Z,1702315513.681 [NAL9602](INFO): SBD MO Status=2, MOMSN=45829, MT Status=2, MTMSN=0
2023-12-11T17:25:13.681Z,1702315513.681 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T17:26:26.827Z,1702315586.827 [NAL9602](INFO): SBD MO Status=0, MOMSN=45829, MT Status=0, MTMSN=0
2023-12-11T17:26:26.827Z,1702315586.827 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:26:57.548Z,1702315617.548 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:29:41.674Z,1702315781.674 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:29:41.674Z,1702315781.674 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:29:41.674Z,1702315781.674 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:29:41.674Z,1702315781.674 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:29:42.073Z,1702315782.073 [Default:CheckIn:D] Stopped
2023-12-11T17:29:42.073Z,1702315782.073 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:29:42.483Z,1702315782.483 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.769604 min 
2023-12-11T17:29:42.483Z,1702315782.483 [Default:CheckIn:E] Stopped
2023-12-11T17:29:42.483Z,1702315782.483 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:29:42.483Z,1702315782.483 [Default:CheckIn] Stopped
2023-12-11T17:29:42.483Z,1702315782.483 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:29:42.483Z,1702315782.483 [Default:CheckIn](INFO): Running loop #15
2023-12-11T17:29:42.483Z,1702315782.483 [Default:CheckIn] Running Loop=15
2023-12-11T17:29:42.484Z,1702315782.484 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:29:42.484Z,1702315782.484 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:29:44.493Z,1702315784.493 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172943.00,A,3648.13969,N,12147.23039,W,0.039,0.00,111223,,,D*7C
2023-12-11T17:29:44.504Z,1702315784.504 [NAL9602](INFO): GPS fix at 20231211T172943: (36.802328, -121.787173)
2023-12-11T17:29:44.516Z,1702315784.516 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:29:44.516Z,1702315784.516 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:29:52.306Z,1702315792.306 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0049.lzma
2023-12-11T17:29:53.308Z,1702315793.308 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0049.lzma.bak
2023-12-11T17:29:53.308Z,1702315793.308 [DataOverHttps](INFO): SBD MOMSN=19100582
2023-12-11T17:30:00.649Z,1702315800.649 [NAL9602](INFO): SBD MO Status=0, MOMSN=45830, MT Status=0, MTMSN=0
2023-12-11T17:30:00.649Z,1702315800.649 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:30:09.090Z,1702315809.090 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231211T154842/Express0050.lzma
2023-12-11T17:30:10.092Z,1702315810.092 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0050.lzma.bak
2023-12-11T17:30:10.093Z,1702315810.093 [DataOverHttps](INFO): SBD MOMSN=19100585
2023-12-11T17:30:11.167Z,1702315811.167 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:30:11.167Z,1702315811.167 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:30:11.167Z,1702315811.167 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:30:31.367Z,1702315831.367 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:35:11.859Z,1702316111.859 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:35:11.859Z,1702316111.859 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:35:11.859Z,1702316111.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:35:11.859Z,1702316111.859 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:35:12.274Z,1702316112.274 [Default:CheckIn:D] Stopped
2023-12-11T17:35:12.274Z,1702316112.274 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:35:12.670Z,1702316112.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.272949 min 
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn:E] Stopped
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn] Stopped
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn](INFO): Running loop #16
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn] Running Loop=16
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:35:12.671Z,1702316112.671 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:35:14.677Z,1702316114.677 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173513.00,A,3648.14035,N,12147.22942,W,0.019,0.00,111223,,,D*75
2023-12-11T17:35:14.680Z,1702316114.680 [NAL9602](INFO): GPS fix at 20231211T173513: (36.802339, -121.787157)
2023-12-11T17:35:14.695Z,1702316114.695 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:35:14.695Z,1702316114.695 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:35:22.358Z,1702316122.358 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0052.lzma
2023-12-11T17:35:23.360Z,1702316123.360 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0052.lzma.bak
2023-12-11T17:35:23.361Z,1702316123.361 [DataOverHttps](INFO): SBD MOMSN=19100625
2023-12-11T17:35:30.437Z,1702316130.437 [NAL9602](INFO): SBD MO Status=0, MOMSN=45831, MT Status=0, MTMSN=0
2023-12-11T17:35:30.437Z,1702316130.437 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:35:39.278Z,1702316139.278 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0053.lzma
2023-12-11T17:35:40.280Z,1702316140.280 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0053.lzma.bak
2023-12-11T17:35:40.281Z,1702316140.281 [DataOverHttps](INFO): SBD MOMSN=19100628
2023-12-11T17:35:41.362Z,1702316141.362 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:35:41.362Z,1702316141.362 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:35:41.362Z,1702316141.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:36:01.139Z,1702316161.139 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:40:42.114Z,1702316442.114 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:40:42.114Z,1702316442.114 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:40:42.114Z,1702316442.114 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:40:42.114Z,1702316442.114 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:40:42.528Z,1702316442.528 [Default:CheckIn:D] Stopped
2023-12-11T17:40:42.528Z,1702316442.528 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:40:42.922Z,1702316442.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.777173 min 
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn:E] Stopped
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn] Stopped
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn](INFO): Running loop #17
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn] Running Loop=17
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:40:42.923Z,1702316442.923 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:40:44.930Z,1702316444.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174044.00,A,3648.14074,N,12147.22814,W,0.039,0.00,111223,,,D*70
2023-12-11T17:40:44.932Z,1702316444.932 [NAL9602](INFO): GPS fix at 20231211T174044: (36.802346, -121.787136)
2023-12-11T17:40:44.951Z,1702316444.951 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:40:44.951Z,1702316444.951 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:40:55.542Z,1702316455.542 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0055.lzma
2023-12-11T17:40:56.544Z,1702316456.544 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0055.lzma.bak
2023-12-11T17:40:56.545Z,1702316456.545 [DataOverHttps](INFO): SBD MOMSN=19100639
2023-12-11T17:41:06.393Z,1702316466.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=45832, MT Status=2, MTMSN=0
2023-12-11T17:41:06.393Z,1702316466.393 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T17:41:12.458Z,1702316472.458 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0056.lzma
2023-12-11T17:41:13.460Z,1702316473.460 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0056.lzma.bak
2023-12-11T17:41:13.461Z,1702316473.461 [DataOverHttps](INFO): SBD MOMSN=19100642
2023-12-11T17:41:14.519Z,1702316474.519 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:41:14.520Z,1702316474.520 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:41:14.520Z,1702316474.520 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:41:45.585Z,1702316505.585 [NAL9602](INFO): SBD MO Status=0, MOMSN=45832, MT Status=0, MTMSN=0
2023-12-11T17:41:45.585Z,1702316505.585 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:42:16.282Z,1702316536.282 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:46:14.910Z,1702316774.910 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:46:14.910Z,1702316774.910 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:46:14.910Z,1702316774.910 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:46:14.910Z,1702316774.910 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:46:15.323Z,1702316775.323 [Default:CheckIn:D] Stopped
2023-12-11T17:46:15.323Z,1702316775.323 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:46:15.714Z,1702316775.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.323763 min 
2023-12-11T17:46:15.714Z,1702316775.714 [Default:CheckIn:E] Stopped
2023-12-11T17:46:15.714Z,1702316775.714 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:46:15.714Z,1702316775.714 [Default:CheckIn] Stopped
2023-12-11T17:46:15.714Z,1702316775.714 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:46:15.715Z,1702316775.715 [Default:CheckIn](INFO): Running loop #18
2023-12-11T17:46:15.715Z,1702316775.715 [Default:CheckIn] Running Loop=18
2023-12-11T17:46:15.715Z,1702316775.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:46:15.715Z,1702316775.715 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:46:17.727Z,1702316777.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174617.00,A,3648.14195,N,12147.22821,W,0.000,0.00,111223,,,D*72
2023-12-11T17:46:17.729Z,1702316777.729 [NAL9602](INFO): GPS fix at 20231211T174617: (36.802366, -121.787137)
2023-12-11T17:46:17.744Z,1702316777.744 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:46:17.744Z,1702316777.744 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:46:27.742Z,1702316787.742 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0058.lzma
2023-12-11T17:46:28.744Z,1702316788.744 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0058.lzma.bak
2023-12-11T17:46:28.745Z,1702316788.745 [DataOverHttps](INFO): SBD MOMSN=19100660
2023-12-11T17:46:34.865Z,1702316794.865 [NAL9602](INFO): SBD MO Status=0, MOMSN=45833, MT Status=0, MTMSN=0
2023-12-11T17:46:34.865Z,1702316794.865 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:46:44.478Z,1702316804.478 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231211T154842/Express0059.lzma
2023-12-11T17:46:45.480Z,1702316805.480 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0059.lzma.bak
2023-12-11T17:46:45.481Z,1702316805.481 [DataOverHttps](INFO): SBD MOMSN=19100663
2023-12-11T17:46:46.594Z,1702316806.594 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:46:46.594Z,1702316806.594 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:46:46.594Z,1702316806.594 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:47:05.619Z,1702316825.619 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:51:47.434Z,1702317107.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:51:47.434Z,1702317107.434 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:51:47.434Z,1702317107.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:51:47.434Z,1702317107.434 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:51:47.832Z,1702317107.832 [Default:CheckIn:D] Stopped
2023-12-11T17:51:47.832Z,1702317107.832 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:51:48.234Z,1702317108.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.865576 min 
2023-12-11T17:51:48.234Z,1702317108.234 [Default:CheckIn:E] Stopped
2023-12-11T17:51:48.234Z,1702317108.234 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:51:48.234Z,1702317108.234 [Default:CheckIn] Stopped
2023-12-11T17:51:48.234Z,1702317108.234 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:51:48.234Z,1702317108.234 [Default:CheckIn](INFO): Running loop #19
2023-12-11T17:51:48.235Z,1702317108.235 [Default:CheckIn] Running Loop=19
2023-12-11T17:51:48.235Z,1702317108.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:51:48.235Z,1702317108.235 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:51:50.242Z,1702317110.242 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175149.00,A,3648.13990,N,12147.23328,W,0.039,0.00,111223,,,D*7C
2023-12-11T17:51:50.244Z,1702317110.244 [NAL9602](INFO): GPS fix at 20231211T175149: (36.802332, -121.787221)
2023-12-11T17:51:50.260Z,1702317110.260 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:51:50.260Z,1702317110.260 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:51:57.758Z,1702317117.758 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0061.lzma
2023-12-11T17:51:58.760Z,1702317118.760 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0061.lzma.bak
2023-12-11T17:51:58.761Z,1702317118.761 [DataOverHttps](INFO): SBD MOMSN=19100697
2023-12-11T17:52:03.173Z,1702317123.173 [NAL9602](INFO): SBD MO Status=0, MOMSN=45834, MT Status=0, MTMSN=0
2023-12-11T17:52:03.173Z,1702317123.173 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:52:14.514Z,1702317134.514 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0062.lzma
2023-12-11T17:52:15.516Z,1702317135.516 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0062.lzma.bak
2023-12-11T17:52:15.517Z,1702317135.517 [DataOverHttps](INFO): SBD MOMSN=19100700
2023-12-11T17:52:16.952Z,1702317136.952 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:52:16.952Z,1702317136.952 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:52:16.952Z,1702317136.952 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:52:33.932Z,1702317153.932 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T17:57:17.353Z,1702317437.353 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T17:57:17.353Z,1702317437.353 [Default:CheckIn:C.Wait] Stopped
2023-12-11T17:57:17.354Z,1702317437.354 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T17:57:17.354Z,1702317437.354 [Default:CheckIn:D] Running Loop=1
2023-12-11T17:57:17.766Z,1702317437.766 [Default:CheckIn:D] Stopped
2023-12-11T17:57:17.766Z,1702317437.766 [Default:CheckIn:E] Running Loop=1
2023-12-11T17:57:18.174Z,1702317438.174 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.364478 min 
2023-12-11T17:57:18.174Z,1702317438.174 [Default:CheckIn:E] Stopped
2023-12-11T17:57:18.175Z,1702317438.175 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T17:57:18.175Z,1702317438.175 [Default:CheckIn] Stopped
2023-12-11T17:57:18.175Z,1702317438.175 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T17:57:18.175Z,1702317438.175 [Default:CheckIn](INFO): Running loop #20
2023-12-11T17:57:18.175Z,1702317438.175 [Default:CheckIn] Running Loop=20
2023-12-11T17:57:18.175Z,1702317438.175 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T17:57:18.175Z,1702317438.175 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T17:57:20.177Z,1702317440.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175719.00,A,3648.14157,N,12147.22951,W,0.039,0.00,111223,,,D*7E
2023-12-11T17:57:20.180Z,1702317440.180 [NAL9602](INFO): GPS fix at 20231211T175719: (36.802360, -121.787159)
2023-12-11T17:57:20.191Z,1702317440.191 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T17:57:20.191Z,1702317440.191 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T17:57:27.878Z,1702317447.878 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0064.lzma
2023-12-11T17:57:28.880Z,1702317448.880 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0064.lzma.bak
2023-12-11T17:57:28.880Z,1702317448.880 [DataOverHttps](INFO): SBD MOMSN=19100714
2023-12-11T17:57:31.942Z,1702317451.942 [NAL9602](INFO): SBD MO Status=0, MOMSN=45835, MT Status=0, MTMSN=0
2023-12-11T17:57:31.942Z,1702317451.942 [NAL9602](INFO): No messages in MT queue
2023-12-11T17:57:44.830Z,1702317464.830 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0065.lzma
2023-12-11T17:57:45.832Z,1702317465.832 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0065.lzma.bak
2023-12-11T17:57:45.833Z,1702317465.833 [DataOverHttps](INFO): SBD MOMSN=19100717
2023-12-11T17:57:46.866Z,1702317466.866 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T17:57:46.866Z,1702317466.866 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T17:57:46.866Z,1702317466.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T17:58:02.590Z,1702317482.590 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:02:47.366Z,1702317767.366 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:02:47.366Z,1702317767.366 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:02:47.370Z,1702317767.370 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:02:47.371Z,1702317767.371 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:02:47.754Z,1702317767.754 [Default:CheckIn:D] Stopped
2023-12-11T18:02:47.754Z,1702317767.754 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:02:48.158Z,1702317768.158 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.864282 min 
2023-12-11T18:02:48.158Z,1702317768.158 [Default:CheckIn:E] Stopped
2023-12-11T18:02:48.158Z,1702317768.158 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:02:48.158Z,1702317768.158 [Default:CheckIn] Stopped
2023-12-11T18:02:48.158Z,1702317768.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:02:48.159Z,1702317768.159 [Default:CheckIn](INFO): Running loop #21
2023-12-11T18:02:48.159Z,1702317768.159 [Default:CheckIn] Running Loop=21
2023-12-11T18:02:48.159Z,1702317768.159 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:02:48.159Z,1702317768.159 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:02:50.177Z,1702317770.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180249.00,A,3648.14087,N,12147.22944,W,0.039,0.00,111223,,,D*7C
2023-12-11T18:02:50.179Z,1702317770.179 [NAL9602](INFO): GPS fix at 20231211T180249: (36.802348, -121.787157)
2023-12-11T18:02:50.223Z,1702317770.223 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:02:50.223Z,1702317770.223 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:02:59.542Z,1702317779.542 [NAL9602](INFO): SBD MO Status=0, MOMSN=45836, MT Status=0, MTMSN=0
2023-12-11T18:02:59.542Z,1702317779.542 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:03:00.702Z,1702317780.702 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0067.lzma
2023-12-11T18:03:01.704Z,1702317781.704 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0067.lzma.bak
2023-12-11T18:03:01.705Z,1702317781.705 [DataOverHttps](INFO): SBD MOMSN=19100730
2023-12-11T18:03:17.610Z,1702317797.610 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0068.lzma
2023-12-11T18:03:18.612Z,1702317798.612 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0068.lzma.bak
2023-12-11T18:03:18.613Z,1702317798.613 [DataOverHttps](INFO): SBD MOMSN=19100733
2023-12-11T18:03:19.735Z,1702317799.735 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:03:19.735Z,1702317799.735 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:03:19.735Z,1702317799.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:03:30.227Z,1702317810.227 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:05:40.395Z,1702317940.395 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2023-12-11T18:06:35.783Z,1702317995.783 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-12-11T18:06:35.790Z,1702317995.790 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 6.
2023-12-11T18:06:35.792Z,1702317995.792 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T18:08:20.439Z,1702318100.439 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:08:20.439Z,1702318100.439 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:08:20.439Z,1702318100.439 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:08:20.439Z,1702318100.439 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:08:20.849Z,1702318100.849 [Default:CheckIn:D] Stopped
2023-12-11T18:08:20.849Z,1702318100.849 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:08:21.259Z,1702318101.259 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.415861 min 
2023-12-11T18:08:21.259Z,1702318101.259 [Default:CheckIn:E] Stopped
2023-12-11T18:08:21.260Z,1702318101.260 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:08:21.260Z,1702318101.260 [Default:CheckIn] Stopped
2023-12-11T18:08:21.260Z,1702318101.260 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:08:21.260Z,1702318101.260 [Default:CheckIn](INFO): Running loop #22
2023-12-11T18:08:21.260Z,1702318101.260 [Default:CheckIn] Running Loop=22
2023-12-11T18:08:21.260Z,1702318101.260 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:08:21.260Z,1702318101.260 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:08:23.245Z,1702318103.245 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180822.00,A,3648.14131,N,12147.22983,W,0.019,0.00,111223,,,D*7E
2023-12-11T18:08:23.266Z,1702318103.266 [NAL9602](INFO): GPS fix at 20231211T180822: (36.802355, -121.787164)
2023-12-11T18:08:23.285Z,1702318103.285 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:08:23.285Z,1702318103.285 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:08:30.462Z,1702318110.462 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20231211T154842/Courier0070.lzma
2023-12-11T18:08:31.464Z,1702318111.464 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0070.lzma.bak
2023-12-11T18:08:31.465Z,1702318111.465 [DataOverHttps](INFO): SBD MOMSN=19100746
2023-12-11T18:08:37.797Z,1702318117.797 [NAL9602](INFO): SBD MO Status=0, MOMSN=45837, MT Status=0, MTMSN=0
2023-12-11T18:08:37.797Z,1702318117.797 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:08:47.186Z,1702318127.186 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0071.lzma
2023-12-11T18:08:48.189Z,1702318128.189 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0071.lzma.bak
2023-12-11T18:08:48.189Z,1702318128.189 [DataOverHttps](INFO): SBD MOMSN=19100749
2023-12-11T18:08:49.526Z,1702318129.526 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:08:49.526Z,1702318129.526 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:08:49.526Z,1702318129.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:09:08.558Z,1702318148.558 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:13:50.250Z,1702318430.250 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:13:50.250Z,1702318430.250 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:13:50.250Z,1702318430.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:13:50.250Z,1702318430.250 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:13:50.652Z,1702318430.652 [Default:CheckIn:D] Stopped
2023-12-11T18:13:50.652Z,1702318430.652 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.912581 min 
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn:E] Stopped
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn] Stopped
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn](INFO): Running loop #23
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn] Running Loop=23
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:13:51.067Z,1702318431.067 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:13:53.069Z,1702318433.069 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181352.00,A,3648.14116,N,12147.22969,W,0.019,0.00,111223,,,D*72
2023-12-11T18:13:53.072Z,1702318433.072 [NAL9602](INFO): GPS fix at 20231211T181352: (36.802353, -121.787161)
2023-12-11T18:13:53.083Z,1702318433.083 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:13:53.083Z,1702318433.083 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:14:00.810Z,1702318440.810 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0073.lzma
2023-12-11T18:14:01.812Z,1702318441.812 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0073.lzma.bak
2023-12-11T18:14:01.813Z,1702318441.813 [DataOverHttps](INFO): SBD MOMSN=19100762
2023-12-11T18:14:17.658Z,1702318457.658 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20231211T154842/Express0074.lzma
2023-12-11T18:14:18.661Z,1702318458.661 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0074.lzma.bak
2023-12-11T18:14:18.661Z,1702318458.661 [DataOverHttps](INFO): SBD MOMSN=19100765
2023-12-11T18:14:19.756Z,1702318459.756 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:14:19.756Z,1702318459.756 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:14:19.756Z,1702318459.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:14:21.793Z,1702318461.793 [NAL9602](INFO): SBD MO Status=2, MOMSN=45838, MT Status=2, MTMSN=0
2023-12-11T18:14:21.793Z,1702318461.793 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T18:14:39.584Z,1702318479.584 [NAL9602](INFO): SBD MO Status=0, MOMSN=45838, MT Status=0, MTMSN=0
2023-12-11T18:14:39.584Z,1702318479.584 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:15:10.327Z,1702318510.327 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:19:20.428Z,1702318760.428 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:19:20.428Z,1702318760.428 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:19:20.428Z,1702318760.428 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:19:20.429Z,1702318760.429 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:19:20.838Z,1702318760.838 [Default:CheckIn:D] Stopped
2023-12-11T18:19:20.838Z,1702318760.838 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.415674 min 
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn:E] Stopped
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn] Stopped
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn](INFO): Running loop #24
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn] Running Loop=24
2023-12-11T18:19:21.260Z,1702318761.260 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:19:21.261Z,1702318761.261 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:19:23.249Z,1702318763.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181922.00,A,3648.14148,N,12147.23054,W,0.058,0.00,111223,,,D*77
2023-12-11T18:19:23.252Z,1702318763.252 [NAL9602](INFO): GPS fix at 20231211T181922: (36.802358, -121.787176)
2023-12-11T18:19:23.263Z,1702318763.263 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:19:23.263Z,1702318763.263 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:19:30.522Z,1702318770.522 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0076.lzma
2023-12-11T18:19:31.524Z,1702318771.524 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0076.lzma.bak
2023-12-11T18:19:31.525Z,1702318771.525 [DataOverHttps](INFO): SBD MOMSN=19100784
2023-12-11T18:19:36.181Z,1702318776.181 [NAL9602](INFO): SBD MO Status=0, MOMSN=45839, MT Status=0, MTMSN=0
2023-12-11T18:19:36.181Z,1702318776.181 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:19:47.399Z,1702318787.399 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0077.lzma
2023-12-11T18:19:48.400Z,1702318788.400 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0077.lzma.bak
2023-12-11T18:19:48.401Z,1702318788.401 [DataOverHttps](INFO): SBD MOMSN=19100790
2023-12-11T18:19:49.513Z,1702318789.513 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:19:49.513Z,1702318789.513 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:19:49.513Z,1702318789.513 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:20:06.876Z,1702318806.876 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:24:50.208Z,1702319090.208 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:24:50.208Z,1702319090.208 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:24:50.208Z,1702319090.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:24:50.208Z,1702319090.208 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:24:50.618Z,1702319090.618 [Default:CheckIn:D] Stopped
2023-12-11T18:24:50.618Z,1702319090.618 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:24:51.025Z,1702319091.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 151.912012 min 
2023-12-11T18:24:51.025Z,1702319091.025 [Default:CheckIn:E] Stopped
2023-12-11T18:24:51.025Z,1702319091.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:24:51.026Z,1702319091.026 [Default:CheckIn] Stopped
2023-12-11T18:24:51.026Z,1702319091.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:24:51.026Z,1702319091.026 [Default:CheckIn](INFO): Running loop #25
2023-12-11T18:24:51.026Z,1702319091.026 [Default:CheckIn] Running Loop=25
2023-12-11T18:24:51.026Z,1702319091.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:24:51.026Z,1702319091.026 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:24:53.029Z,1702319093.029 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182452.00,A,3648.13985,N,12147.22861,W,0.039,0.00,111223,,,D*78
2023-12-11T18:24:53.032Z,1702319093.032 [NAL9602](INFO): GPS fix at 20231211T182452: (36.802331, -121.787143)
2023-12-11T18:24:53.043Z,1702319093.043 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:24:53.043Z,1702319093.043 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:25:00.754Z,1702319100.754 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0079.lzma
2023-12-11T18:25:01.513Z,1702319101.513 [NAL9602](INFO): SBD MO Status=0, MOMSN=45840, MT Status=0, MTMSN=0
2023-12-11T18:25:01.513Z,1702319101.513 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:25:01.756Z,1702319101.756 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0079.lzma.bak
2023-12-11T18:25:01.757Z,1702319101.757 [DataOverHttps](INFO): SBD MOMSN=19100814
2023-12-11T18:25:17.546Z,1702319117.546 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0080.lzma
2023-12-11T18:25:18.549Z,1702319118.549 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0080.lzma.bak
2023-12-11T18:25:18.549Z,1702319118.549 [DataOverHttps](INFO): SBD MOMSN=19100818
2023-12-11T18:25:19.702Z,1702319119.702 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:25:19.702Z,1702319119.702 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:25:19.702Z,1702319119.702 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:25:32.217Z,1702319132.217 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:30:20.351Z,1702319420.351 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:30:20.351Z,1702319420.351 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:30:20.351Z,1702319420.351 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:30:20.351Z,1702319420.351 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:30:20.756Z,1702319420.756 [Default:CheckIn:D] Stopped
2023-12-11T18:30:20.756Z,1702319420.756 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:30:21.170Z,1702319421.170 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.414323 min 
2023-12-11T18:30:21.170Z,1702319421.170 [Default:CheckIn:E] Stopped
2023-12-11T18:30:21.170Z,1702319421.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:30:21.179Z,1702319421.179 [Default:CheckIn] Stopped
2023-12-11T18:30:21.180Z,1702319421.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:30:21.180Z,1702319421.180 [Default:CheckIn](INFO): Running loop #26
2023-12-11T18:30:21.180Z,1702319421.180 [Default:CheckIn] Running Loop=26
2023-12-11T18:30:21.180Z,1702319421.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:30:21.180Z,1702319421.180 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:30:23.161Z,1702319423.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183022.00,A,3648.14006,N,12147.22991,W,0.019,0.00,111223,,,D*73
2023-12-11T18:30:23.164Z,1702319423.164 [NAL9602](INFO): GPS fix at 20231211T183022: (36.802334, -121.787165)
2023-12-11T18:30:23.184Z,1702319423.184 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:30:23.184Z,1702319423.184 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:30:31.158Z,1702319431.158 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0082.lzma
2023-12-11T18:30:32.160Z,1702319432.160 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0082.lzma.bak
2023-12-11T18:30:32.161Z,1702319432.161 [DataOverHttps](INFO): SBD MOMSN=19100834
2023-12-11T18:30:39.729Z,1702319439.729 [NAL9602](INFO): SBD MO Status=2, MOMSN=45841, MT Status=2, MTMSN=0
2023-12-11T18:30:39.729Z,1702319439.729 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T18:30:47.930Z,1702319447.930 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0083.lzma
2023-12-11T18:30:48.933Z,1702319448.933 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0083.lzma.bak
2023-12-11T18:30:48.933Z,1702319448.933 [DataOverHttps](INFO): SBD MOMSN=19100837
2023-12-11T18:30:50.270Z,1702319450.270 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:30:50.271Z,1702319450.271 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:30:50.271Z,1702319450.271 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:30:55.949Z,1702319455.949 [NAL9602](INFO): SBD MO Status=2, MOMSN=45841, MT Status=2, MTMSN=0
2023-12-11T18:30:55.949Z,1702319455.949 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T18:31:14.940Z,1702319474.940 [NAL9602](INFO): SBD MO Status=0, MOMSN=45841, MT Status=0, MTMSN=0
2023-12-11T18:31:14.941Z,1702319474.941 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:31:45.643Z,1702319505.643 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:35:50.951Z,1702319750.951 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:35:50.952Z,1702319750.952 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:35:50.952Z,1702319750.952 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:35:50.952Z,1702319750.952 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:35:51.346Z,1702319751.346 [Default:CheckIn:D] Stopped
2023-12-11T18:35:51.346Z,1702319751.346 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:35:51.750Z,1702319751.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 162.924154 min 
2023-12-11T18:35:51.750Z,1702319751.750 [Default:CheckIn:E] Stopped
2023-12-11T18:35:51.750Z,1702319751.750 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:35:51.750Z,1702319751.750 [Default:CheckIn] Stopped
2023-12-11T18:35:51.750Z,1702319751.750 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:35:51.751Z,1702319751.751 [Default:CheckIn](INFO): Running loop #27
2023-12-11T18:35:51.751Z,1702319751.751 [Default:CheckIn] Running Loop=27
2023-12-11T18:35:51.751Z,1702319751.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:35:51.751Z,1702319751.751 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:35:53.765Z,1702319753.765 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183553.00,A,3648.14137,N,12147.22901,W,0.000,0.00,111223,,,D*72
2023-12-11T18:35:53.768Z,1702319753.768 [NAL9602](INFO): GPS fix at 20231211T183553: (36.802356, -121.787150)
2023-12-11T18:35:53.779Z,1702319753.779 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:35:53.779Z,1702319753.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:36:01.378Z,1702319761.378 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0085.lzma
2023-12-11T18:36:02.380Z,1702319762.380 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0085.lzma.bak
2023-12-11T18:36:02.381Z,1702319762.381 [DataOverHttps](INFO): SBD MOMSN=19100850
2023-12-11T18:36:07.897Z,1702319767.897 [NAL9602](INFO): SBD MO Status=0, MOMSN=45842, MT Status=0, MTMSN=0
2023-12-11T18:36:07.897Z,1702319767.897 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:36:18.798Z,1702319778.798 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20231211T154842/Express0086.lzma
2023-12-11T18:36:19.801Z,1702319779.801 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0086.lzma.bak
2023-12-11T18:36:19.801Z,1702319779.801 [DataOverHttps](INFO): SBD MOMSN=19100853
2023-12-11T18:36:20.868Z,1702319780.868 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:36:20.868Z,1702319780.868 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:36:20.869Z,1702319780.869 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:36:38.627Z,1702319798.627 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:41:21.482Z,1702320081.482 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:41:21.482Z,1702320081.482 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:41:21.482Z,1702320081.482 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:41:21.482Z,1702320081.482 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:41:21.887Z,1702320081.887 [Default:CheckIn:D] Stopped
2023-12-11T18:41:21.887Z,1702320081.887 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:41:22.291Z,1702320082.291 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.433154 min 
2023-12-11T18:41:22.291Z,1702320082.291 [Default:CheckIn:E] Stopped
2023-12-11T18:41:22.291Z,1702320082.291 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:41:22.291Z,1702320082.291 [Default:CheckIn] Stopped
2023-12-11T18:41:22.291Z,1702320082.291 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:41:22.292Z,1702320082.292 [Default:CheckIn](INFO): Running loop #28
2023-12-11T18:41:22.292Z,1702320082.292 [Default:CheckIn] Running Loop=28
2023-12-11T18:41:22.292Z,1702320082.292 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:41:22.292Z,1702320082.292 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:41:24.297Z,1702320084.297 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184123.00,A,3648.14004,N,12147.22896,W,0.039,0.00,111223,,,D*72
2023-12-11T18:41:24.300Z,1702320084.300 [NAL9602](INFO): GPS fix at 20231211T184123: (36.802334, -121.787149)
2023-12-11T18:41:24.333Z,1702320084.333 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:41:24.333Z,1702320084.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:41:32.090Z,1702320092.090 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0088.lzma
2023-12-11T18:41:33.092Z,1702320093.092 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0088.lzma.bak
2023-12-11T18:41:33.093Z,1702320093.093 [DataOverHttps](INFO): SBD MOMSN=19100866
2023-12-11T18:41:35.618Z,1702320095.618 [NAL9602](INFO): SBD MO Status=0, MOMSN=45843, MT Status=0, MTMSN=0
2023-12-11T18:41:35.618Z,1702320095.618 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:41:48.878Z,1702320108.878 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0089.lzma
2023-12-11T18:41:49.881Z,1702320109.881 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0089.lzma.bak
2023-12-11T18:41:49.881Z,1702320109.881 [DataOverHttps](INFO): SBD MOMSN=19100869
2023-12-11T18:41:50.988Z,1702320110.988 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:41:50.988Z,1702320110.988 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:41:50.989Z,1702320110.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:42:06.315Z,1702320126.315 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:46:51.678Z,1702320411.678 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:46:51.678Z,1702320411.678 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:46:51.679Z,1702320411.679 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:46:51.679Z,1702320411.679 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:46:52.112Z,1702320412.112 [Default:CheckIn:D] Stopped
2023-12-11T18:46:52.112Z,1702320412.112 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 173.936914 min 
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn:E] Stopped
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn] Stopped
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn](INFO): Running loop #29
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn] Running Loop=29
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:46:52.480Z,1702320412.480 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:46:54.485Z,1702320414.485 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184653.00,A,3648.14177,N,12147.22850,W,0.019,0.00,111223,,,D*7F
2023-12-11T18:46:54.487Z,1702320414.487 [NAL9602](INFO): GPS fix at 20231211T184653: (36.802363, -121.787142)
2023-12-11T18:46:54.527Z,1702320414.527 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:46:54.527Z,1702320414.527 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:47:02.166Z,1702320422.166 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0091.lzma
2023-12-11T18:47:03.169Z,1702320423.169 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0091.lzma.bak
2023-12-11T18:47:03.169Z,1702320423.169 [DataOverHttps](INFO): SBD MOMSN=19100883
2023-12-11T18:47:10.729Z,1702320430.729 [NAL9602](INFO): SBD MO Status=0, MOMSN=45844, MT Status=0, MTMSN=0
2023-12-11T18:47:10.729Z,1702320430.729 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:47:18.894Z,1702320438.894 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0092.lzma
2023-12-11T18:47:19.896Z,1702320439.896 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0092.lzma.bak
2023-12-11T18:47:19.897Z,1702320439.897 [DataOverHttps](INFO): SBD MOMSN=19100886
2023-12-11T18:47:21.242Z,1702320441.242 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:47:21.243Z,1702320441.243 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:47:21.243Z,1702320441.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:47:41.435Z,1702320461.435 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:52:21.910Z,1702320741.910 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:52:21.910Z,1702320741.910 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:52:21.910Z,1702320741.910 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:52:21.922Z,1702320741.922 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:52:22.301Z,1702320742.301 [Default:CheckIn:D] Stopped
2023-12-11T18:52:22.301Z,1702320742.301 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.440055 min 
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn:E] Stopped
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn] Stopped
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn](INFO): Running loop #30
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn] Running Loop=30
2023-12-11T18:52:22.703Z,1702320742.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:52:22.704Z,1702320742.704 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:52:24.709Z,1702320744.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185223.00,A,3648.14122,N,12147.22914,W,0.078,0.00,111223,,,D*7B
2023-12-11T18:52:24.712Z,1702320744.712 [NAL9602](INFO): GPS fix at 20231211T185223: (36.802354, -121.787152)
2023-12-11T18:52:24.765Z,1702320744.765 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:52:24.765Z,1702320744.765 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:52:31.714Z,1702320751.714 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0094.lzma
2023-12-11T18:52:32.716Z,1702320752.716 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0094.lzma.bak
2023-12-11T18:52:32.717Z,1702320752.717 [DataOverHttps](INFO): SBD MOMSN=19100891
2023-12-11T18:52:43.473Z,1702320763.473 [NAL9602](INFO): SBD MO Status=0, MOMSN=45845, MT Status=0, MTMSN=0
2023-12-11T18:52:43.473Z,1702320763.473 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:52:49.614Z,1702320769.614 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0095.lzma
2023-12-11T18:52:59.987Z,1702320779.987 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0095.lzma
2023-12-11T18:53:00.988Z,1702320780.988 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0095.lzma.bak
2023-12-11T18:53:00.989Z,1702320780.989 [DataOverHttps](INFO): SBD MOMSN=19100894
2023-12-11T18:53:02.097Z,1702320782.097 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:53:02.097Z,1702320782.097 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:53:02.097Z,1702320782.097 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:53:14.206Z,1702320794.206 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T18:58:02.681Z,1702321082.681 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T18:58:02.681Z,1702321082.681 [Default:CheckIn:C.Wait] Stopped
2023-12-11T18:58:02.681Z,1702321082.681 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T18:58:02.681Z,1702321082.681 [Default:CheckIn:D] Running Loop=1
2023-12-11T18:58:03.082Z,1702321083.082 [Default:CheckIn:D] Stopped
2023-12-11T18:58:03.082Z,1702321083.082 [Default:CheckIn:E] Running Loop=1
2023-12-11T18:58:03.486Z,1702321083.486 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.119743 min 
2023-12-11T18:58:03.486Z,1702321083.486 [Default:CheckIn:E] Stopped
2023-12-11T18:58:03.486Z,1702321083.486 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T18:58:03.486Z,1702321083.486 [Default:CheckIn] Stopped
2023-12-11T18:58:03.486Z,1702321083.486 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T18:58:03.487Z,1702321083.487 [Default:CheckIn](INFO): Running loop #31
2023-12-11T18:58:03.487Z,1702321083.487 [Default:CheckIn] Running Loop=31
2023-12-11T18:58:03.487Z,1702321083.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T18:58:03.487Z,1702321083.487 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T18:58:05.495Z,1702321085.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185804.00,A,3648.14041,N,12147.22756,W,0.039,0.00,111223,,,D*7D
2023-12-11T18:58:05.497Z,1702321085.497 [NAL9602](INFO): GPS fix at 20231211T185804: (36.802340, -121.787126)
2023-12-11T18:58:05.508Z,1702321085.508 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T18:58:05.508Z,1702321085.508 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T18:58:12.402Z,1702321092.402 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0097.lzma
2023-12-11T18:58:13.405Z,1702321093.405 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0097.lzma.bak
2023-12-11T18:58:13.405Z,1702321093.405 [DataOverHttps](INFO): SBD MOMSN=19100911
2023-12-11T18:58:20.877Z,1702321100.877 [NAL9602](INFO): SBD MO Status=0, MOMSN=45846, MT Status=0, MTMSN=0
2023-12-11T18:58:20.877Z,1702321100.877 [NAL9602](INFO): No messages in MT queue
2023-12-11T18:58:32.666Z,1702321112.666 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231211T154842/Express0098.lzma
2023-12-11T18:58:33.668Z,1702321113.668 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0098.lzma.bak
2023-12-11T18:58:33.669Z,1702321113.669 [DataOverHttps](INFO): SBD MOMSN=19100914
2023-12-11T18:58:35.038Z,1702321115.038 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T18:58:35.038Z,1702321115.038 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T18:58:35.038Z,1702321115.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T18:58:51.592Z,1702321131.592 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:03:35.654Z,1702321415.654 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:03:35.654Z,1702321415.654 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:03:35.654Z,1702321415.654 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:03:35.654Z,1702321415.654 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:03:36.053Z,1702321416.053 [Default:CheckIn:D] Stopped
2023-12-11T19:03:36.053Z,1702321416.053 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:03:36.468Z,1702321416.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.669271 min 
2023-12-11T19:03:36.468Z,1702321416.468 [Default:CheckIn:E] Stopped
2023-12-11T19:03:36.468Z,1702321416.468 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:03:36.468Z,1702321416.468 [Default:CheckIn] Stopped
2023-12-11T19:03:36.468Z,1702321416.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:03:36.468Z,1702321416.468 [Default:CheckIn](INFO): Running loop #32
2023-12-11T19:03:36.468Z,1702321416.468 [Default:CheckIn] Running Loop=32
2023-12-11T19:03:36.469Z,1702321416.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:03:36.469Z,1702321416.469 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:03:38.469Z,1702321418.469 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190337.00,A,3648.14083,N,12147.22886,W,0.078,0.00,111223,,,D*7B
2023-12-11T19:03:38.472Z,1702321418.472 [NAL9602](INFO): GPS fix at 20231211T190337: (36.802347, -121.787148)
2023-12-11T19:03:38.511Z,1702321418.511 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:03:38.511Z,1702321418.511 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:03:46.138Z,1702321426.138 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0100.lzma
2023-12-11T19:03:47.141Z,1702321427.141 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0100.lzma.bak
2023-12-11T19:03:47.141Z,1702321427.141 [DataOverHttps](INFO): SBD MOMSN=19100925
2023-12-11T19:03:59.477Z,1702321439.477 [NAL9602](INFO): SBD MO Status=0, MOMSN=45847, MT Status=0, MTMSN=0
2023-12-11T19:03:59.477Z,1702321439.477 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:04:03.170Z,1702321443.170 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0101.lzma
2023-12-11T19:04:04.173Z,1702321444.173 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0101.lzma.bak
2023-12-11T19:04:04.173Z,1702321444.173 [DataOverHttps](INFO): SBD MOMSN=19100928
2023-12-11T19:04:05.550Z,1702321445.550 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:04:05.550Z,1702321445.550 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:04:05.550Z,1702321445.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:04:30.179Z,1702321470.179 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:09:06.130Z,1702321746.130 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:09:06.130Z,1702321746.130 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:09:06.130Z,1702321746.130 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:09:06.130Z,1702321746.130 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:09:06.537Z,1702321746.537 [Default:CheckIn:D] Stopped
2023-12-11T19:09:06.537Z,1702321746.537 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:09:06.956Z,1702321746.956 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 196.177327 min 
2023-12-11T19:09:06.956Z,1702321746.956 [Default:CheckIn:E] Stopped
2023-12-11T19:09:06.956Z,1702321746.956 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:09:06.956Z,1702321746.956 [Default:CheckIn] Stopped
2023-12-11T19:09:06.956Z,1702321746.956 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:09:06.957Z,1702321746.957 [Default:CheckIn](INFO): Running loop #33
2023-12-11T19:09:06.957Z,1702321746.957 [Default:CheckIn] Running Loop=33
2023-12-11T19:09:06.957Z,1702321746.957 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:09:06.957Z,1702321746.957 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:09:08.953Z,1702321748.953 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190908.00,A,3648.14018,N,12147.22981,W,0.156,0.00,111223,,,D*74
2023-12-11T19:09:08.956Z,1702321748.956 [NAL9602](INFO): GPS fix at 20231211T190908: (36.802336, -121.787164)
2023-12-11T19:09:08.967Z,1702321748.967 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:09:08.967Z,1702321748.967 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:09:16.998Z,1702321756.998 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0103.lzma
2023-12-11T19:09:18.001Z,1702321758.001 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0103.lzma.bak
2023-12-11T19:09:18.001Z,1702321758.001 [DataOverHttps](INFO): SBD MOMSN=19100939
2023-12-11T19:09:21.877Z,1702321761.877 [NAL9602](INFO): SBD MO Status=0, MOMSN=45848, MT Status=0, MTMSN=0
2023-12-11T19:09:21.877Z,1702321761.877 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:09:34.060Z,1702321774.060 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0104.lzma
2023-12-11T19:09:35.061Z,1702321775.061 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0104.lzma.bak
2023-12-11T19:09:35.061Z,1702321775.061 [DataOverHttps](INFO): SBD MOMSN=19100942
2023-12-11T19:09:36.438Z,1702321776.438 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:09:36.438Z,1702321776.438 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:09:36.438Z,1702321776.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:09:52.590Z,1702321792.590 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:11:33.605Z,1702321893.605 [BPC1](INFO): Calculating totals. Valid battery stick count: 38. Valid reserve battery stick count: 6.
2023-12-11T19:11:33.608Z,1702321893.608 [BPC1](INFO): Received data from all battery sticks.
2023-12-11T19:14:37.031Z,1702322077.031 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:14:37.032Z,1702322077.032 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:14:37.032Z,1702322077.032 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:14:37.032Z,1702322077.032 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:14:37.426Z,1702322077.426 [Default:CheckIn:D] Stopped
2023-12-11T19:14:37.426Z,1702322077.426 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:14:37.830Z,1702322077.830 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.692155 min 
2023-12-11T19:14:37.830Z,1702322077.830 [Default:CheckIn:E] Stopped
2023-12-11T19:14:37.830Z,1702322077.830 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:14:37.830Z,1702322077.830 [Default:CheckIn] Stopped
2023-12-11T19:14:37.830Z,1702322077.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:14:37.830Z,1702322077.830 [Default:CheckIn](INFO): Running loop #34
2023-12-11T19:14:37.831Z,1702322077.831 [Default:CheckIn] Running Loop=34
2023-12-11T19:14:37.831Z,1702322077.831 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:14:37.831Z,1702322077.831 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:14:39.843Z,1702322079.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191439.00,A,3648.13892,N,12147.23025,W,0.097,0.00,111223,,,D*7D
2023-12-11T19:14:39.845Z,1702322079.845 [NAL9602](INFO): GPS fix at 20231211T191439: (36.802315, -121.787171)
2023-12-11T19:14:39.856Z,1702322079.856 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:14:39.856Z,1702322079.856 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:14:47.546Z,1702322087.546 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20231211T154842/Courier0106.lzma
2023-12-11T19:14:48.549Z,1702322088.549 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0106.lzma.bak
2023-12-11T19:14:48.549Z,1702322088.549 [DataOverHttps](INFO): SBD MOMSN=19100953
2023-12-11T19:15:04.521Z,1702322104.521 [NAL9602](INFO): SBD MO Status=2, MOMSN=45849, MT Status=2, MTMSN=0
2023-12-11T19:15:04.521Z,1702322104.521 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T19:15:07.426Z,1702322107.426 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231211T154842/Express0107.lzma
2023-12-11T19:15:08.429Z,1702322108.429 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0107.lzma.bak
2023-12-11T19:15:08.429Z,1702322108.429 [DataOverHttps](INFO): SBD MOMSN=19100956
2023-12-11T19:15:09.786Z,1702322109.786 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:15:09.786Z,1702322109.786 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:15:09.786Z,1702322109.786 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:15:17.453Z,1702322117.453 [NAL9602](INFO): SBD MO Status=0, MOMSN=45849, MT Status=0, MTMSN=0
2023-12-11T19:15:17.453Z,1702322117.453 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:15:48.152Z,1702322148.152 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:20:10.426Z,1702322410.426 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:20:10.435Z,1702322410.435 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:20:10.435Z,1702322410.435 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:20:10.435Z,1702322410.435 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:20:10.816Z,1702322410.816 [Default:CheckIn:D] Stopped
2023-12-11T19:20:10.816Z,1702322410.816 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:20:11.223Z,1702322411.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 207.248649 min 
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn:E] Stopped
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn] Stopped
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn](INFO): Running loop #35
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn] Running Loop=35
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:20:11.224Z,1702322411.224 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:20:13.229Z,1702322413.229 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192012.00,A,3648.13970,N,12147.23018,W,0.019,0.00,111223,,,D*76
2023-12-11T19:20:13.232Z,1702322413.232 [NAL9602](INFO): GPS fix at 20231211T192012: (36.802328, -121.787170)
2023-12-11T19:20:13.291Z,1702322413.291 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:20:13.291Z,1702322413.291 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:20:23.390Z,1702322423.390 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0109.lzma
2023-12-11T19:20:24.394Z,1702322424.394 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0109.lzma.bak
2023-12-11T19:20:24.395Z,1702322424.395 [DataOverHttps](INFO): SBD MOMSN=19100968
2023-12-11T19:20:27.041Z,1702322427.041 [NAL9602](INFO): SBD MO Status=2, MOMSN=45850, MT Status=2, MTMSN=0
2023-12-11T19:20:27.041Z,1702322427.041 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T19:20:40.261Z,1702322440.261 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0110.lzma
2023-12-11T19:20:41.261Z,1702322441.261 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0110.lzma.bak
2023-12-11T19:20:41.261Z,1702322441.261 [DataOverHttps](INFO): SBD MOMSN=19100972
2023-12-11T19:20:42.403Z,1702322442.403 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:20:42.403Z,1702322442.403 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:20:42.403Z,1702322442.403 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:20:44.405Z,1702322444.405 [NAL9602](INFO): SBD MO Status=2, MOMSN=45850, MT Status=2, MTMSN=0
2023-12-11T19:20:44.405Z,1702322444.405 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T19:21:15.917Z,1702322475.917 [NAL9602](INFO): SBD MO Status=0, MOMSN=45850, MT Status=0, MTMSN=0
2023-12-11T19:21:15.917Z,1702322475.917 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:21:46.619Z,1702322506.619 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:25:43.121Z,1702322743.121 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:25:43.122Z,1702322743.122 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:25:43.122Z,1702322743.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:25:43.122Z,1702322743.122 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:25:43.521Z,1702322743.521 [Default:CheckIn:D] Stopped
2023-12-11T19:25:43.521Z,1702322743.521 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:25:43.927Z,1702322743.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.793734 min 
2023-12-11T19:25:43.927Z,1702322743.927 [Default:CheckIn:E] Stopped
2023-12-11T19:25:43.927Z,1702322743.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:25:43.927Z,1702322743.927 [Default:CheckIn] Stopped
2023-12-11T19:25:43.927Z,1702322743.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:25:43.927Z,1702322743.927 [Default:CheckIn](INFO): Running loop #36
2023-12-11T19:25:43.927Z,1702322743.927 [Default:CheckIn] Running Loop=36
2023-12-11T19:25:43.928Z,1702322743.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:25:43.928Z,1702322743.928 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:25:45.955Z,1702322745.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192545.00,A,3648.14886,N,12147.22413,W,0.039,14.96,111223,,,D*48
2023-12-11T19:25:45.957Z,1702322745.957 [NAL9602](INFO): GPS fix at 20231211T192545: (36.802481, -121.787069)
2023-12-11T19:25:45.969Z,1702322745.969 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:25:45.969Z,1702322745.969 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:25:53.886Z,1702322753.886 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20231211T154842/Courier0112.lzma
2023-12-11T19:25:54.889Z,1702322754.889 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0112.lzma.bak
2023-12-11T19:25:54.889Z,1702322754.889 [DataOverHttps](INFO): SBD MOMSN=19101079
2023-12-11T19:26:10.686Z,1702322770.686 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20231211T154842/Express0113.lzma
2023-12-11T19:26:11.689Z,1702322771.689 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0113.lzma.bak
2023-12-11T19:26:11.689Z,1702322771.689 [DataOverHttps](INFO): SBD MOMSN=19101082
2023-12-11T19:26:13.022Z,1702322773.022 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:26:13.022Z,1702322773.022 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:26:13.022Z,1702322773.022 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:26:36.845Z,1702322796.845 [NAL9602](INFO): SBD MO Status=2, MOMSN=45851, MT Status=2, MTMSN=0
2023-12-11T19:26:36.845Z,1702322796.845 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T19:27:02.723Z,1702322822.723 [NAL9602](INFO): SBD MO Status=2, MOMSN=45851, MT Status=2, MTMSN=0
2023-12-11T19:27:02.723Z,1702322822.723 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T19:27:20.085Z,1702322840.085 [NAL9602](INFO): SBD MO Status=2, MOMSN=45851, MT Status=2, MTMSN=0
2023-12-11T19:27:20.085Z,1702322840.085 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-12-11T19:27:45.541Z,1702322865.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=45851, MT Status=0, MTMSN=0
2023-12-11T19:27:45.541Z,1702322865.541 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:28:16.238Z,1702322896.238 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:31:13.707Z,1702323073.707 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:31:13.708Z,1702323073.708 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:31:13.708Z,1702323073.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:31:13.708Z,1702323073.708 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:31:14.116Z,1702323074.116 [Default:CheckIn:D] Stopped
2023-12-11T19:31:14.116Z,1702323074.116 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:31:14.509Z,1702323074.509 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 218.303646 min 
2023-12-11T19:31:14.510Z,1702323074.510 [Default:CheckIn:E] Stopped
2023-12-11T19:31:14.510Z,1702323074.510 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:31:14.510Z,1702323074.510 [Default:CheckIn] Stopped
2023-12-11T19:31:14.510Z,1702323074.510 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:31:14.510Z,1702323074.510 [Default:CheckIn](INFO): Running loop #37
2023-12-11T19:31:14.510Z,1702323074.510 [Default:CheckIn] Running Loop=37
2023-12-11T19:31:14.518Z,1702323074.518 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:31:14.519Z,1702323074.519 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:31:16.522Z,1702323076.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193115.00,A,3648.15085,N,12147.22218,W,0.039,357.61,111223,,,D*73
2023-12-11T19:31:16.524Z,1702323076.524 [NAL9602](INFO): GPS fix at 20231211T193115: (36.802514, -121.787036)
2023-12-11T19:31:16.535Z,1702323076.535 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:31:16.535Z,1702323076.535 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:31:23.538Z,1702323083.538 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0115.lzma
2023-12-11T19:31:24.541Z,1702323084.541 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0115.lzma.bak
2023-12-11T19:31:24.541Z,1702323084.541 [DataOverHttps](INFO): SBD MOMSN=19101117
2023-12-11T19:31:25.011Z,1702323085.011 [NAL9602](INFO): SBD MO Status=0, MOMSN=45852, MT Status=0, MTMSN=0
2023-12-11T19:31:25.011Z,1702323085.011 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:31:40.298Z,1702323100.298 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20231211T154842/Express0116.lzma
2023-12-11T19:31:41.301Z,1702323101.301 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0116.lzma.bak
2023-12-11T19:31:41.301Z,1702323101.301 [DataOverHttps](INFO): SBD MOMSN=19101120
2023-12-11T19:31:42.385Z,1702323102.385 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:31:42.385Z,1702323102.385 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:31:42.385Z,1702323102.385 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:31:55.726Z,1702323115.726 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:36:43.174Z,1702323403.174 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:36:43.174Z,1702323403.174 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:36:43.174Z,1702323403.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:36:43.174Z,1702323403.174 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:36:43.590Z,1702323403.590 [Default:CheckIn:D] Stopped
2023-12-11T19:36:43.590Z,1702323403.590 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:36:43.996Z,1702323403.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.794873 min 
2023-12-11T19:36:43.996Z,1702323403.996 [Default:CheckIn:E] Stopped
2023-12-11T19:36:43.996Z,1702323403.996 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:36:43.996Z,1702323403.996 [Default:CheckIn] Stopped
2023-12-11T19:36:43.996Z,1702323403.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:36:43.996Z,1702323403.996 [Default:CheckIn](INFO): Running loop #38
2023-12-11T19:36:43.996Z,1702323403.996 [Default:CheckIn] Running Loop=38
2023-12-11T19:36:43.997Z,1702323403.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:36:43.997Z,1702323403.997 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:36:45.994Z,1702323405.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193645.00,A,3648.15238,N,12147.22073,W,1.263,34.02,111223,,,D*45
2023-12-11T19:36:45.996Z,1702323405.996 [NAL9602](INFO): GPS fix at 20231211T193645: (36.802540, -121.787012)
2023-12-11T19:36:46.028Z,1702323406.028 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:36:46.028Z,1702323406.028 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:36:56.206Z,1702323416.206 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0118.lzma
2023-12-11T19:36:57.211Z,1702323417.211 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0118.lzma.bak
2023-12-11T19:36:57.211Z,1702323417.211 [DataOverHttps](INFO): SBD MOMSN=19101131
2023-12-11T19:37:13.018Z,1702323433.018 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20231211T154842/Express0119.lzma
2023-12-11T19:37:14.021Z,1702323434.021 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0119.lzma.bak
2023-12-11T19:37:14.021Z,1702323434.021 [DataOverHttps](INFO): SBD MOMSN=19101134
2023-12-11T19:37:15.191Z,1702323435.191 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:37:15.191Z,1702323435.191 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:37:15.191Z,1702323435.191 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:37:28.472Z,1702323448.472 [NAL9602](INFO): SBD MO Status=0, MOMSN=45853, MT Status=0, MTMSN=0
2023-12-11T19:37:28.472Z,1702323448.472 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:37:59.179Z,1702323479.179 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:42:15.562Z,1702323735.562 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:42:15.562Z,1702323735.562 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:42:15.562Z,1702323735.562 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:42:15.562Z,1702323735.562 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:42:15.970Z,1702323735.970 [Default:CheckIn:D] Stopped
2023-12-11T19:42:15.970Z,1702323735.970 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:42:16.383Z,1702323736.383 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 229.334554 min 
2023-12-11T19:42:16.383Z,1702323736.383 [Default:CheckIn:E] Stopped
2023-12-11T19:42:16.383Z,1702323736.383 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:42:16.383Z,1702323736.383 [Default:CheckIn] Stopped
2023-12-11T19:42:16.383Z,1702323736.383 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:42:16.383Z,1702323736.383 [Default:CheckIn](INFO): Running loop #39
2023-12-11T19:42:16.383Z,1702323736.383 [Default:CheckIn] Running Loop=39
2023-12-11T19:42:16.384Z,1702323736.384 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:42:16.384Z,1702323736.384 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:42:18.381Z,1702323738.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194217.00,A,3648.37507,N,12147.11371,W,3.168,0.88,111223,,,D*74
2023-12-11T19:42:18.384Z,1702323738.384 [NAL9602](INFO): GPS fix at 20231211T194217: (36.806251, -121.785229)
2023-12-11T19:42:18.400Z,1702323738.400 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:42:18.400Z,1702323738.400 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:42:28.590Z,1702323748.590 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0121.lzma
2023-12-11T19:42:29.593Z,1702323749.593 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0121.lzma.bak
2023-12-11T19:42:29.593Z,1702323749.593 [DataOverHttps](INFO): SBD MOMSN=19101146
2023-12-11T19:42:40.385Z,1702323760.385 [NAL9602](INFO): SBD MO Status=0, MOMSN=45854, MT Status=0, MTMSN=0
2023-12-11T19:42:40.386Z,1702323760.386 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:42:45.306Z,1702323765.306 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20231211T154842/Express0122.lzma
2023-12-11T19:42:46.310Z,1702323766.310 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0122.lzma.bak
2023-12-11T19:42:46.310Z,1702323766.310 [DataOverHttps](INFO): SBD MOMSN=19101149
2023-12-11T19:42:47.701Z,1702323767.701 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:42:47.701Z,1702323767.701 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:42:47.701Z,1702323767.701 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:43:11.138Z,1702323791.138 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:47:48.485Z,1702324068.485 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:47:48.486Z,1702324068.486 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:47:48.486Z,1702324068.486 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:47:48.486Z,1702324068.486 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:47:48.890Z,1702324068.890 [Default:CheckIn:D] Stopped
2023-12-11T19:47:48.890Z,1702324068.890 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:47:49.291Z,1702324069.291 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 234.883219 min 
2023-12-11T19:47:49.291Z,1702324069.291 [Default:CheckIn:E] Stopped
2023-12-11T19:47:49.291Z,1702324069.291 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:47:49.291Z,1702324069.291 [Default:CheckIn] Stopped
2023-12-11T19:47:49.291Z,1702324069.291 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:47:49.291Z,1702324069.291 [Default:CheckIn](INFO): Running loop #40
2023-12-11T19:47:49.291Z,1702324069.291 [Default:CheckIn] Running Loop=40
2023-12-11T19:47:49.292Z,1702324069.292 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:47:49.292Z,1702324069.292 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:47:51.305Z,1702324071.305 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194750.00,A,3648.36968,N,12147.46511,W,8.320,237.42,111223,,,D*71
2023-12-11T19:47:51.308Z,1702324071.308 [NAL9602](INFO): GPS fix at 20231211T194750: (36.806161, -121.791085)
2023-12-11T19:47:51.341Z,1702324071.341 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:47:51.341Z,1702324071.341 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:47:58.170Z,1702324078.170 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20231211T154842/Courier0124.lzma
2023-12-11T19:47:59.176Z,1702324079.176 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Courier0124.lzma.bak
2023-12-11T19:47:59.176Z,1702324079.176 [DataOverHttps](INFO): SBD MOMSN=19101161
2023-12-11T19:48:09.497Z,1702324089.497 [NAL9602](INFO): SBD MO Status=0, MOMSN=45855, MT Status=0, MTMSN=0
2023-12-11T19:48:09.497Z,1702324089.497 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:48:15.002Z,1702324095.002 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20231211T154842/Express0125.lzma
2023-12-11T19:48:16.005Z,1702324096.005 [DataOverHttps](INFO): Moved sent file to Logs/20231211T154842/Express0125.lzma.bak
2023-12-11T19:48:16.005Z,1702324096.005 [DataOverHttps](INFO): SBD MOMSN=19101164
2023-12-11T19:48:17.165Z,1702324097.165 [Default:CheckIn:Read_Iridium] Stopped
2023-12-11T19:48:17.165Z,1702324097.165 [Default:CheckIn:C.Wait] Running Loop=1
2023-12-11T19:48:17.165Z,1702324097.165 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-12-11T19:48:40.247Z,1702324120.247 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:53:17.639Z,1702324397.639 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-12-11T19:53:17.639Z,1702324397.639 [Default:CheckIn:C.Wait] Stopped
2023-12-11T19:53:17.639Z,1702324397.639 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-12-11T19:53:17.640Z,1702324397.640 [Default:CheckIn:D] Running Loop=1
2023-12-11T19:53:18.060Z,1702324398.060 [Default:CheckIn:D] Stopped
2023-12-11T19:53:18.060Z,1702324398.060 [Default:CheckIn:E] Running Loop=1
2023-12-11T19:53:18.450Z,1702324398.450 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 240.369385 min 
2023-12-11T19:53:18.450Z,1702324398.450 [Default:CheckIn:E] Stopped
2023-12-11T19:53:18.451Z,1702324398.451 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-12-11T19:53:18.451Z,1702324398.451 [Default:CheckIn] Stopped
2023-12-11T19:53:18.451Z,1702324398.451 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-12-11T19:53:18.451Z,1702324398.451 [Default:CheckIn](INFO): Running loop #41
2023-12-11T19:53:18.451Z,1702324398.451 [Default:CheckIn] Running Loop=41
2023-12-11T19:53:18.451Z,1702324398.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-12-11T19:53:18.451Z,1702324398.451 [Default:CheckIn:Read_GPS] Running Loop=1
2023-12-11T19:53:20.457Z,1702324400.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195319.00,A,3648.12637,N,12148.85709,W,12.771,258.66,111223,,,A*40
2023-12-11T19:53:20.464Z,1702324400.464 [NAL9602](INFO): GPS fix at 20231211T195319: (36.802106, -121.814285)
2023-12-11T19:53:20.475Z,1702324400.475 [Default:CheckIn:Read_GPS] Stopped
2023-12-11T19:53:20.475Z,1702324400.475 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-12-11T19:53:37.833Z,1702324417.833 [NAL9602](INFO): SBD MO Status=0, MOMSN=45856, MT Status=0, MTMSN=0
2023-12-11T19:53:37.833Z,1702324417.833 [NAL9602](INFO): No messages in MT queue
2023-12-11T19:54:08.533Z,1702324448.533 [NAL9602](INFO): Not Powering down - fast GPS
2023-12-11T19:54:22.015Z,1702324462.015 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T19:54:53.139Z,1702324493.139 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T19:55:24.263Z,1702324524.263 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T19:55:55.387Z,1702324555.387 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-12-11T19:56:04.423Z,1702324564.423 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.075500
2023-12-11T19:56:13.895Z,1702324573.895 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003341
2023-12-11T19:56:21.822Z,1702324581.822 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231211T154842/Courier0127.lzma
2023-12-11T19:56:22.353Z,1702324582.353 [NAL9602](INFO): SBD MO Status=1, MOMSN=45857, MT Status=0, MTMSN=0
2023-12