2022-03-15T15:24:27.412Z,1647357867.412 [Supervisor](DEBUG): Initializing supervisor.
2022-03-15T15:24:27.416Z,1647357867.416 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2022-03-15T15:24:27.417Z,1647357867.417 [SyncHandler](INFO): Protected caller Thread ID is 833
2022-03-15T15:24:27.417Z,1647357867.417 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2022-03-15T15:24:27.418Z,1647357867.418 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2022-03-15T15:24:27.418Z,1647357867.418 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 834
2022-03-15T15:24:27.422Z,1647357867.422 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2022-03-15T15:24:27.441Z,1647357867.441 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2022-03-15T15:24:27.442Z,1647357867.442 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2022-03-15T15:24:27.442Z,1647357867.442 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 835
2022-03-15T15:24:27.446Z,1647357867.446 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2022-03-15T15:24:27.447Z,1647357867.447 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2022-03-15T15:24:27.448Z,1647357867.448 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 836
2022-03-15T15:24:27.450Z,1647357867.450 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2022-03-15T15:24:27.451Z,1647357867.451 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2022-03-15T15:24:27.451Z,1647357867.451 [logger ThreadHandler](INFO): Protected caller Thread ID is 837
2022-03-15T15:24:27.455Z,1647357867.455 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2022-03-15T15:24:27.456Z,1647357867.456 [Supervisor](INFO): Looking for Config files in directory: Config/
2022-03-15T15:24:27.460Z,1647357867.460 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2022-03-15T15:24:27.685Z,1647357867.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2022-03-15T15:24:27.687Z,1647357867.687 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2022-03-15T15:24:27.768Z,1647357867.768 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2022-03-15T15:24:28.272Z,1647357868.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2022-03-15T15:24:28.272Z,1647357868.272 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2022-03-15T15:24:28.592Z,1647357868.592 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2022-03-15T15:24:28.593Z,1647357868.593 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2022-03-15T15:24:28.688Z,1647357868.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2022-03-15T15:24:28.690Z,1647357868.690 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2022-03-15T15:24:29.047Z,1647357869.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2022-03-15T15:24:29.049Z,1647357869.049 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2022-03-15T15:24:29.249Z,1647357869.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2022-03-15T15:24:29.250Z,1647357869.250 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2022-03-15T15:24:29.700Z,1647357869.700 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2022-03-15T15:24:29.701Z,1647357869.701 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2022-03-15T15:24:29.827Z,1647357869.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2022-03-15T15:24:29.829Z,1647357869.829 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2022-03-15T15:24:29.909Z,1647357869.909 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2022-03-15T15:24:31.049Z,1647357871.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2022-03-15T15:24:31.050Z,1647357871.050 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2022-03-15T15:24:31.408Z,1647357871.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2022-03-15T15:24:31.408Z,1647357871.408 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2022-03-15T15:24:31.594Z,1647357871.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2022-03-15T15:24:31.596Z,1647357871.596 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2022-03-15T15:24:31.706Z,1647357871.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2022-03-15T15:24:31.708Z,1647357871.708 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2022-03-15T15:24:31.857Z,1647357871.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2022-03-15T15:24:31.859Z,1647357871.859 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2022-03-15T15:24:32.082Z,1647357872.082 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2022-03-15T15:24:32.083Z,1647357872.083 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2022-03-15T15:24:32.087Z,1647357872.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2022-03-15T15:24:32.177Z,1647357872.177 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2022-03-15T15:24:32.262Z,1647357872.262 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2022-03-15T15:24:32.364Z,1647357872.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2022-03-15T15:24:32.446Z,1647357872.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2022-03-15T15:24:32.542Z,1647357872.542 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2022-03-15T15:24:32.642Z,1647357872.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2022-03-15T15:24:32.833Z,1647357872.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2022-03-15T15:24:32.910Z,1647357872.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2022-03-15T15:24:33.054Z,1647357873.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2022-03-15T15:24:33.189Z,1647357873.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2022-03-15T15:24:33.303Z,1647357873.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2022-03-15T15:24:33.565Z,1647357873.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2022-03-15T15:24:33.565Z,1647357873.565 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2022-03-15T15:24:33.578Z,1647357873.578 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2022-03-15T15:24:33.651Z,1647357873.651 [VerticalControl](DEBUG): Construct VerticalControl.
2022-03-15T15:24:33.714Z,1647357873.714 [VerticalControl] Loaded
2022-03-15T15:24:33.714Z,1647357873.714 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2022-03-15T15:24:33.717Z,1647357873.717 [HorizontalControl](DEBUG): Construct HorizontalControl.
2022-03-15T15:24:33.756Z,1647357873.756 [HorizontalControl] Loaded
2022-03-15T15:24:33.756Z,1647357873.756 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2022-03-15T15:24:33.759Z,1647357873.759 [SpeedControl](DEBUG): Construct SpeedControl.
2022-03-15T15:24:33.762Z,1647357873.762 [SpeedControl] Loaded
2022-03-15T15:24:33.762Z,1647357873.762 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2022-03-15T15:24:33.765Z,1647357873.765 [LoopControl](DEBUG): Construct LoopControl.
2022-03-15T15:24:33.765Z,1647357873.765 [LoopControl] Loaded
2022-03-15T15:24:33.765Z,1647357873.765 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2022-03-15T15:24:33.766Z,1647357873.766 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2022-03-15T15:24:33.768Z,1647357873.768 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2022-03-15T15:24:33.783Z,1647357873.783 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2022-03-15T15:24:33.784Z,1647357873.784 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2022-03-15T15:24:33.916Z,1647357873.916 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2022-03-15T15:24:33.917Z,1647357873.917 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2022-03-15T15:24:34.124Z,1647357874.124 [BuoyancyServo] Loaded
2022-03-15T15:24:34.124Z,1647357874.124 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2022-03-15T15:24:34.156Z,1647357874.156 [ElevatorServo] Loaded
2022-03-15T15:24:34.156Z,1647357874.156 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2022-03-15T15:24:34.179Z,1647357874.179 [MassServo] Loaded
2022-03-15T15:24:34.179Z,1647357874.179 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2022-03-15T15:24:34.197Z,1647357874.197 [RudderServo] Loaded
2022-03-15T15:24:34.197Z,1647357874.197 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2022-03-15T15:24:34.215Z,1647357874.215 [ThrusterServo] Loaded
2022-03-15T15:24:34.215Z,1647357874.215 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2022-03-15T15:24:34.215Z,1647357874.215 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2022-03-15T15:24:34.216Z,1647357874.216 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2022-03-15T15:24:34.316Z,1647357874.316 [DeadReckonUsingMultipleVelocitySources] Loaded
2022-03-15T15:24:34.317Z,1647357874.317 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2022-03-15T15:24:34.330Z,1647357874.330 [NavChart] Loaded
2022-03-15T15:24:34.330Z,1647357874.330 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2022-03-15T15:24:34.336Z,1647357874.336 [UniversalFixResidualReporter] Loaded
2022-03-15T15:24:34.336Z,1647357874.336 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2022-03-15T15:24:34.337Z,1647357874.337 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2022-03-15T15:24:34.337Z,1647357874.337 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2022-03-15T15:24:34.421Z,1647357874.421 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2022-03-15T15:24:34.422Z,1647357874.422 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2022-03-15T15:24:35.115Z,1647357875.115 [AHRS_M2] Loaded
2022-03-15T15:24:35.115Z,1647357875.115 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2022-03-15T15:24:35.917Z,1647357875.917 [BPC1] Loaded
2022-03-15T15:24:35.917Z,1647357875.917 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2022-03-15T15:24:36.058Z,1647357876.058 [DataOverHttps] Loaded
2022-03-15T15:24:36.059Z,1647357876.059 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2022-03-15T15:24:36.060Z,1647357876.060 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0
2022-03-15T15:24:36.060Z,1647357876.060 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2022-03-15T15:24:36.080Z,1647357876.080 [Depth_Keller] Loaded
2022-03-15T15:24:36.081Z,1647357876.081 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2022-03-15T15:24:36.085Z,1647357876.085 [DropWeight] Loaded
2022-03-15T15:24:36.086Z,1647357876.086 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2022-03-15T15:24:36.146Z,1647357876.146 [NAL9602] Loaded
2022-03-15T15:24:36.146Z,1647357876.146 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2022-03-15T15:24:36.175Z,1647357876.175 [Onboard] Loaded
2022-03-15T15:24:36.176Z,1647357876.176 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2022-03-15T15:24:36.177Z,1647357876.177 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0
2022-03-15T15:24:36.177Z,1647357876.177 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2022-03-15T15:24:36.190Z,1647357876.190 [Power24vConverter] Loaded
2022-03-15T15:24:36.190Z,1647357876.190 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2022-03-15T15:24:36.203Z,1647357876.203 [Radio_Surface] Loaded
2022-03-15T15:24:36.204Z,1647357876.204 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2022-03-15T15:24:36.205Z,1647357876.205 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409264E0
2022-03-15T15:24:36.205Z,1647357876.205 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2022-03-15T15:24:36.206Z,1647357876.206 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2022-03-15T15:24:36.207Z,1647357876.207 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2022-03-15T15:24:36.364Z,1647357876.364 [DepthRateCalculator] Loaded
2022-03-15T15:24:36.364Z,1647357876.364 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2022-03-15T15:24:36.369Z,1647357876.369 [PitchRateCalculator] Loaded
2022-03-15T15:24:36.369Z,1647357876.369 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2022-03-15T15:24:36.418Z,1647357876.418 [SpeedCalculator] Loaded
2022-03-15T15:24:36.418Z,1647357876.418 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2022-03-15T15:24:36.478Z,1647357876.478 [TempGradientCalculator] Loaded
2022-03-15T15:24:36.478Z,1647357876.478 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2022-03-15T15:24:36.482Z,1647357876.482 [YawRateCalculator] Loaded
2022-03-15T15:24:36.483Z,1647357876.483 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2022-03-15T15:24:36.572Z,1647357876.572 [ElevatorOffsetCalculator] Loaded
2022-03-15T15:24:36.572Z,1647357876.572 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2022-03-15T15:24:36.572Z,1647357876.572 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2022-03-15T15:24:36.573Z,1647357876.573 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2022-03-15T15:24:37.233Z,1647357877.233 [CANONSampler] Loaded
2022-03-15T15:24:37.233Z,1647357877.233 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2022-03-15T15:24:37.292Z,1647357877.292 [CTD_Seabird] Loaded
2022-03-15T15:24:37.292Z,1647357877.292 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2022-03-15T15:24:37.293Z,1647357877.293 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2022-03-15T15:24:37.294Z,1647357877.294 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2022-03-15T15:24:37.314Z,1647357877.314 [PAR_Licor] Loaded
2022-03-15T15:24:37.315Z,1647357877.315 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2022-03-15T15:24:37.347Z,1647357877.347 [WetLabsBB2FL] Loaded
2022-03-15T15:24:37.348Z,1647357877.348 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2022-03-15T15:24:37.349Z,1647357877.349 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A0B4E0
2022-03-15T15:24:37.349Z,1647357877.349 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2022-03-15T15:24:37.350Z,1647357877.350 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2022-03-15T15:24:37.351Z,1647357877.351 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2022-03-15T15:24:37.393Z,1647357877.393 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2022-03-15T15:24:37.393Z,1647357877.393 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2022-03-15T15:24:37.768Z,1647357877.768 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2022-03-15T15:24:37.769Z,1647357877.769 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2022-03-15T15:24:37.944Z,1647357877.944 [SBIT](DEBUG): Construct Startup Built In Test.
2022-03-15T15:24:37.953Z,1647357877.953 [SBIT] Loaded
2022-03-15T15:24:37.954Z,1647357877.954 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2022-03-15T15:24:37.956Z,1647357877.956 [IBIT](DEBUG): Construct Initiated Built In Test.
2022-03-15T15:24:37.970Z,1647357877.970 [IBIT] Loaded
2022-03-15T15:24:37.970Z,1647357877.970 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2022-03-15T15:24:37.976Z,1647357877.976 [CBIT](DEBUG): Construct Continuous Built In Test.
2022-03-15T15:24:38.080Z,1647357878.080 [CBIT] Loaded
2022-03-15T15:24:38.080Z,1647357878.080 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2022-03-15T15:24:38.081Z,1647357878.081 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2022-03-15T15:24:38.081Z,1647357878.081 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2022-03-15T15:24:38.200Z,1647357878.200 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2022-03-15T15:24:38.207Z,1647357878.207 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2022-03-15T15:24:38.209Z,1647357878.209 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2022-03-15T15:24:38.220Z,1647357878.220 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2022-03-15T15:24:38.221Z,1647357878.221 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B414E0
2022-03-15T15:24:38.222Z,1647357878.222 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2022-03-15T15:24:38.226Z,1647357878.226 [Supervisor](INFO): Main Thread ID is 829
2022-03-15T15:24:38.226Z,1647357878.226 [Supervisor](DEBUG): Running supervisor.
2022-03-15T15:24:38.227Z,1647357878.227 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2022-03-15T15:24:38.228Z,1647357878.228 [CommandExec](INFO): Initializing the command executive.
2022-03-15T15:24:38.229Z,1647357878.229 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2022-03-15T15:24:38.231Z,1647357878.231 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2022-03-15T15:24:38.232Z,1647357878.232 [controlThread](DEBUG): Initializing ControlThread
2022-03-15T15:24:38.233Z,1647357878.233 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2022-03-15T15:24:38.235Z,1647357878.235 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2022-03-15T15:24:38.235Z,1647357878.235 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2022-03-15T15:24:38.236Z,1647357878.236 [LoopControl](DEBUG): Initialize LoopControlComponent.
2022-03-15T15:24:38.238Z,1647357878.238 [NavChart](DEBUG): Initialize NavChart Navigation.
2022-03-15T15:24:38.238Z,1647357878.238 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2022-03-15T15:24:38.242Z,1647357878.242 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2022-03-15T15:24:38.242Z,1647357878.242 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2022-03-15T15:24:38.242Z,1647357878.242 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2022-03-15T15:24:38.243Z,1647357878.243 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2022-03-15T15:24:38.243Z,1647357878.243 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2022-03-15T15:24:38.244Z,1647357878.244 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2022-03-15T15:24:38.248Z,1647357878.248 [SBIT](INFO): Initialize SBIT Component.
2022-03-15T15:24:38.249Z,1647357878.249 [SBIT](IMPORTANT): git: 2022-01-05
2022-03-15T15:24:38.249Z,1647357878.249 [SBIT](INFO): git hash: 4648efb7c0362dd4a6331fa887af857d1a6abffc
2022-03-15T15:24:38.249Z,1647357878.249 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2022-03-15T15:24:38.250Z,1647357878.250 [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
2022-03-15T15:24:38.252Z,1647357878.252 [SBIT](INFO): Beginning SBIT in 56.000000 seconds.
2022-03-15T15:24:38.252Z,1647357878.252 [IBIT](INFO): Initialize IBIT Component.
2022-03-15T15:24:38.253Z,1647357878.253 [CBIT](DEBUG): Initialize CBIT Component.
2022-03-15T15:24:38.254Z,1647357878.254 [logger ThreadHandler](INFO): Handler Thread ID is 932
2022-03-15T15:24:38.268Z,1647357878.268 [CBIT](DEBUG): Initialized mux pins.
2022-03-15T15:24:38.268Z,1647357878.268 [CBIT](DEBUG): Initializing the watchdog timer.
2022-03-15T15:24:38.272Z,1647357878.272 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933
2022-03-15T15:24:38.273Z,1647357878.273 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2022-03-15T15:24:38.281Z,1647357878.281 [Onboard ThreadHandler](INFO): Handler Thread ID is 934
2022-03-15T15:24:38.293Z,1647357878.293 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2022-03-15T15:24:38.293Z,1647357878.293 [CBIT](DEBUG): Initializing heartbeat.
2022-03-15T15:24:38.302Z,1647357878.302 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935
2022-03-15T15:24:38.320Z,1647357878.320 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2022-03-15T15:24:38.321Z,1647357878.321 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2022-03-15T15:24:38.325Z,1647357878.325 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2022-03-15T15:24:38.329Z,1647357878.329 [WetLabsBB2FL](INFO): Powering up
2022-03-15T15:24:38.330Z,1647357878.330 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2022-03-15T15:24:38.338Z,1647357878.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2022-03-15T15:24:38.338Z,1647357878.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2022-03-15T15:24:38.339Z,1647357878.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2022-03-15T15:24:38.339Z,1647357878.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2022-03-15T15:24:38.339Z,1647357878.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2022-03-15T15:24:38.339Z,1647357878.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2022-03-15T15:24:38.339Z,1647357878.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2022-03-15T15:24:38.339Z,1647357878.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2022-03-15T15:24:38.340Z,1647357878.340 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2022-03-15T15:24:38.340Z,1647357878.340 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2022-03-15T15:24:38.340Z,1647357878.340 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2022-03-15T15:24:38.341Z,1647357878.341 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2022-03-15T15:24:38.341Z,1647357878.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2022-03-15T15:24:38.341Z,1647357878.341 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2022-03-15T15:24:38.341Z,1647357878.341 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2022-03-15T15:24:38.341Z,1647357878.341 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2022-03-15T15:24:38.364Z,1647357878.364 [CBIT](DEBUG): Deactivating GF circuits.
2022-03-15T15:24:38.364Z,1647357878.364 [CBIT](DEBUG): Deactivating emergency mode.
2022-03-15T15:24:38.400Z,1647357878.400 [CBIT](DEBUG): Backplane powered.
2022-03-15T15:24:38.401Z,1647357878.401 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2022-03-15T15:24:38.427Z,1647357878.427 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-15T15:24:38.457Z,1647357878.457 [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>


2022-03-15T15:24:38.458Z,1647357878.458 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2022-03-15T15:24:38.543Z,1647357878.543 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2022-03-15T15:24:38.544Z,1647357878.544 [Default:A.Wait](DEBUG): Construct Wait.
2022-03-15T15:24:38.547Z,1647357878.547 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2022-03-15T15:24:38.595Z,1647357878.595 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2022-03-15T15:24:38.597Z,1647357878.597 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2022-03-15T15:24:38.620Z,1647357878.620 [Default:E.Execute](DEBUG): Construct Execute.
2022-03-15T15:24:38.623Z,1647357878.623 [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="P2H">
                <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>


2022-03-15T15:24:38.638Z,1647357878.638 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2022-03-15T15:24:38.665Z,1647357878.665 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2022-03-15T15:24:38.716Z,1647357878.716 [Radio_Surface](INFO): Powering up
2022-03-15T15:24:38.842Z,1647357878.842 [Power24vConverter](INFO): Powering up.
2022-03-15T15:24:38.843Z,1647357878.843 [CANONSampler](INFO): Powering down
2022-03-15T15:24:38.885Z,1647357878.885 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2022-03-15T15:24:38.892Z,1647357878.892 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2022-03-15T15:24:38.893Z,1647357878.893 [ElevatorServo](DEBUG): Initializing EZServoServo.
2022-03-15T15:24:38.901Z,1647357878.901 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2022-03-15T15:24:38.902Z,1647357878.902 [MassServo](DEBUG): Initializing EZServoServo.
2022-03-15T15:24:38.908Z,1647357878.908 [MassServo](DEBUG): Initializing MassServo.
2022-03-15T15:24:38.909Z,1647357878.909 [RudderServo](DEBUG): Initializing EZServoServo.
2022-03-15T15:24:38.916Z,1647357878.916 [RudderServo](DEBUG): Initializing RudderServo.
2022-03-15T15:24:38.917Z,1647357878.917 [ThrusterServo](DEBUG): Initializing EZServoServo.
2022-03-15T15:24:38.932Z,1647357878.932 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2022-03-15T15:24:39.784Z,1647357879.784 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2022-03-15T15:24:39.784Z,1647357879.784 [RudderServo](FAULT): Rudder failed to initialize
2022-03-15T15:24:39.784Z,1647357879.784 [RudderServo] Communications Fault, FailCount= 1
2022-03-15T15:24:39.784Z,1647357879.784 [RudderServo](ERROR): Communications Fault
2022-03-15T15:24:39.884Z,1647357879.884 [CBIT](ERROR): Communications Fault in component: RudderServo
2022-03-15T15:24:40.045Z,1647357880.045 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2022-03-15T15:24:40.045Z,1647357880.045 [RudderServo](INFO): Powering down
2022-03-15T15:24:40.436Z,1647357880.436 [WetLabsBB2FL](INFO): Powering down
2022-03-15T15:24:40.796Z,1647357880.796 [RudderServo](DEBUG): Initializing EZServoServo.
2022-03-15T15:24:40.917Z,1647357880.917 [RudderServo](DEBUG): Initializing RudderServo.
2022-03-15T15:24:40.921Z,1647357880.921 [CBIT](INFO): Clearing failed state for component RudderServo
2022-03-15T15:24:40.921Z,1647357880.921 [RudderServo] No Fault, FailCount= 1
2022-03-15T15:25:06.158Z,1647357906.158 [NAL9602](INFO): Powering up NAL9602
2022-03-15T15:25:17.066Z,1647357917.066 [NAL9602](INFO): NAL9602 initialized
2022-03-15T15:25:34.882Z,1647357934.882 [SBIT](IMPORTANT): Beginning Startup BIT
2022-03-15T15:25:34.886Z,1647357934.886 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-15T15:25:45.889Z,1647357945.889 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-03-15T15:25:46.265Z,1647357946.265 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-15T15:25:57.117Z,1647357957.117 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.016895
CHAN A1 (24V): 0.001074
CHAN A2 (12V): -0.001656
CHAN A3 (5V): -0.001354
CHAN B0 (3.3V): 0.000261
CHAN B1 (3.15aV): -0.000473
CHAN B2 (3.15bV): 0.000110
CHAN B3 (GND): -0.000212
OPEN: -0.000070
Full Scale: +/- 1 mA
2022-03-15T15:26:29.006Z,1647357989.006 [SBIT](IMPORTANT): SBIT PASSED
2022-03-15T15:26:29.006Z,1647357989.006 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2022-03-15T15:26:29.007Z,1647357989.007 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2022-03-15T15:26:29.007Z,1647357989.007 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2022-03-15T15:26:29.007Z,1647357989.007 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2022-03-15T15:26:29.007Z,1647357989.007 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool;

2022-03-15T15:26:29.007Z,1647357989.007 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.118193 cubic_centimeter;

2022-03-15T15:26:29.008Z,1647357989.008 [SBIT](IMPORTANT): VerticalControl.massDefault=-9.115137 millimeter;

2022-03-15T15:26:29.393Z,1647357989.393 [MissionManager](IMPORTANT): Started mission Startup
2022-03-15T15:26:29.394Z,1647357989.394 [Startup] Running Loop=1
2022-03-15T15:26:29.394Z,1647357989.394 [Startup](DEBUG): Aggregate::initialize Startup
2022-03-15T15:26:29.394Z,1647357989.394 [Startup:A.GoToSurface] Running Loop=1
2022-03-15T15:26:29.394Z,1647357989.394 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-15T15:26:29.395Z,1647357989.395 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-15T15:26:29.395Z,1647357989.395 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-15T15:26:29.395Z,1647357989.395 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-15T15:26:29.396Z,1647357989.396 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-15T15:26:29.396Z,1647357989.396 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-15T15:26:29.398Z,1647357989.398 [Startup:StartupSatComms] Running Loop=1
2022-03-15T15:26:29.398Z,1647357989.398 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2022-03-15T15:26:29.398Z,1647357989.398 [Startup:StartupSatComms:A] Running Loop=1
2022-03-15T15:26:29.815Z,1647357989.815 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2022-03-15T15:27:04.131Z,1647358024.131 [NAL9602](INFO): SBD MO Status=2, MOMSN=31873, MT Status=2, MTMSN=0
2022-03-15T15:27:04.131Z,1647358024.131 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T15:27:08.230Z,1647358028.230 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005173
2022-03-15T15:27:29.518Z,1647358049.518 [Startup:StartupSatComms:A](INFO): Timed out from 2022-03-15T15:26:29.4Z
2022-03-15T15:27:29.518Z,1647358049.518 [Startup:StartupSatComms:A] Stopped
2022-03-15T15:27:29.518Z,1647358049.518 [Startup:StartupSatComms:B] Running Loop=1
2022-03-15T15:27:29.903Z,1647358049.903 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-03-15T15:27:34.583Z,1647358054.583 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20220315T152427/Courier0000.lzma
2022-03-15T15:27:35.585Z,1647358055.585 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0000.lzma.bak
2022-03-15T15:27:35.585Z,1647358055.585 [DataOverHttps](INFO): SBD MOMSN=16572307
2022-03-15T15:27:38.365Z,1647358058.365 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2022-03-15T15:27:38.365Z,1647358058.365 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-15T15:27:38.376Z,1647358058.376 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-15T15:27:38.827Z,1647358058.827 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-15T15:27:38.827Z,1647358058.827 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2022-03-15T15:27:51.342Z,1647358071.342 [DataOverHttps](INFO): Sending 750 bytes from file Logs/20220315T152427/Express0001.lzma
2022-03-15T15:27:52.341Z,1647358072.341 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0001.lzma.bak
2022-03-15T15:27:52.341Z,1647358072.341 [DataOverHttps](INFO): SBD MOMSN=16572310
2022-03-15T15:27:53.721Z,1647358073.721 [Startup:StartupSatComms:B] Stopped
2022-03-15T15:27:53.721Z,1647358073.721 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2022-03-15T15:27:53.721Z,1647358073.721 [Startup:StartupSatComms] Stopped
2022-03-15T15:27:53.721Z,1647358073.721 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2022-03-15T15:27:53.722Z,1647358073.722 [Startup](INFO): Completed Startup
2022-03-15T15:27:53.722Z,1647358073.722 [MissionManager](INFO): Startup is completed.
2022-03-15T15:27:53.722Z,1647358073.722 [MissionManager](INFO): Uninitializing Mission Startup
2022-03-15T15:27:53.722Z,1647358073.722 [Startup] Stopped
2022-03-15T15:27:53.722Z,1647358073.722 [Startup](DEBUG): Aggregate::uninitialize Startup
2022-03-15T15:27:53.722Z,1647358073.722 [Startup:A.GoToSurface] Stopped
2022-03-15T15:27:53.723Z,1647358073.723 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2022-03-15T15:27:54.146Z,1647358074.146 [MissionManager](IMPORTANT): Started mission Default
2022-03-15T15:27:54.146Z,1647358074.146 [Default] Running Loop=1
2022-03-15T15:27:54.146Z,1647358074.146 [Default](DEBUG): Aggregate::initialize Default
2022-03-15T15:27:54.146Z,1647358074.146 [Default:B.GoToSurface] Running Loop=1
2022-03-15T15:27:54.146Z,1647358074.146 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2022-03-15T15:27:54.147Z,1647358074.147 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2022-03-15T15:27:54.147Z,1647358074.147 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2022-03-15T15:27:54.151Z,1647358074.151 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2022-03-15T15:27:54.152Z,1647358074.152 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2022-03-15T15:27:54.152Z,1647358074.152 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2022-03-15T15:27:54.153Z,1647358074.153 [Default:A.Wait] Running Loop=1
2022-03-15T15:27:54.153Z,1647358074.153 [Default:A.Wait](DEBUG): Initialize Wait Component.
2022-03-15T15:28:07.463Z,1647358087.463 [Default:A.Wait](INFO): Done Waiting.
2022-03-15T15:28:07.464Z,1647358087.464 [Default:A.Wait] Stopped
2022-03-15T15:28:07.464Z,1647358087.464 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T15:28:07.878Z,1647358087.878 [Default:CheckIn] Running Loop=1
2022-03-15T15:28:07.878Z,1647358087.878 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T15:28:07.878Z,1647358087.878 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T15:28:08.261Z,1647358088.261 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2022-03-15T15:30:20.370Z,1647358220.370 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2022-03-15T15:30:39.395Z,1647358239.395 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2022-03-15T15:30:39.395Z,1647358239.395 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-15T15:30:39.407Z,1647358239.407 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-15T15:30:39.796Z,1647358239.796 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-15T15:30:39.796Z,1647358239.796 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2022-03-15T15:30:48.241Z,1647358248.241 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-03-15T15:30:48.245Z,1647358248.245 [BPC1](INFO): Received data from all battery sticks.
2022-03-15T15:32:06.194Z,1647358326.194 [CommandExec](IMPORTANT): got command burn on
2022-03-15T15:32:06.194Z,1647358326.194 [CommandExec](IMPORTANT): Activating dropweight wire
2022-03-15T15:32:15.002Z,1647358335.002 [CommandExec](IMPORTANT): got command burn off
2022-03-15T15:32:15.002Z,1647358335.002 [CommandExec](IMPORTANT): Deactivating dropweight wire
2022-03-15T15:33:08.044Z,1647358388.044 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-15T15:28:07.9Z
2022-03-15T15:33:08.044Z,1647358388.044 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T15:33:08.044Z,1647358388.044 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T15:33:08.453Z,1647358388.453 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2022-03-15T15:33:14.919Z,1647358394.919 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20220315T152427/Courier0004.lzma
2022-03-15T15:33:15.921Z,1647358395.921 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0004.lzma.bak
2022-03-15T15:33:15.921Z,1647358395.921 [DataOverHttps](INFO): SBD MOMSN=16572329
2022-03-15T15:33:31.427Z,1647358411.427 [DataOverHttps](INFO): Sending 264 bytes from file Logs/20220315T152427/Express0005.lzma
2022-03-15T15:33:32.430Z,1647358412.430 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0005.lzma.bak
2022-03-15T15:33:32.430Z,1647358412.430 [DataOverHttps](INFO): SBD MOMSN=16572331
2022-03-15T15:33:33.506Z,1647358413.506 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T15:33:33.506Z,1647358413.506 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T15:33:33.506Z,1647358413.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T15:33:40.349Z,1647358420.349 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2022-03-15T15:33:40.349Z,1647358420.349 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-15T15:33:40.377Z,1647358420.377 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-15T15:33:40.766Z,1647358420.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-15T15:33:40.766Z,1647358420.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2022-03-15T15:34:39.752Z,1647358479.752 [Power24vConverter](INFO): Powering down.
2022-03-15T15:35:18.113Z,1647358518.113 [NAL9602](FAULT): GPS failed to acquire within timeout.
2022-03-15T15:35:18.113Z,1647358518.113 [NAL9602] Data Fault, FailCount= 1
2022-03-15T15:35:18.113Z,1647358518.113 [NAL9602](ERROR): Data Fault
2022-03-15T15:35:18.160Z,1647358518.160 [CBIT](ERROR): Data Fault in component: NAL9602
2022-03-15T15:35:18.517Z,1647358518.517 [NAL9602](INFO): Powering down
2022-03-15T15:35:19.346Z,1647358519.346 [CBIT](INFO): Clearing failed state for component NAL9602
2022-03-15T15:35:19.347Z,1647358519.347 [NAL9602] No Fault, FailCount= 1
2022-03-15T15:35:48.824Z,1647358548.824 [NAL9602](INFO): Powering up NAL9602
2022-03-15T15:35:59.725Z,1647358559.725 [NAL9602](INFO): NAL9602 initialized
2022-03-15T15:36:41.371Z,1647358601.371 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2022-03-15T15:36:41.371Z,1647358601.371 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-15T15:36:41.383Z,1647358601.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-15T15:36:41.800Z,1647358601.800 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-15T15:36:41.801Z,1647358601.801 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2022-03-15T15:38:34.065Z,1647358714.065 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T15:38:34.066Z,1647358714.066 [Default:CheckIn:C.Wait] Stopped
2022-03-15T15:38:34.066Z,1647358714.066 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T15:38:34.066Z,1647358714.066 [Default:CheckIn:D] Running Loop=1
2022-03-15T15:38:34.514Z,1647358714.514 [Default:CheckIn:D] Stopped
2022-03-15T15:38:34.514Z,1647358714.514 [Default:CheckIn:E] Running Loop=1
2022-03-15T15:38:34.903Z,1647358714.903 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672792 min 
2022-03-15T15:38:34.903Z,1647358714.903 [Default:CheckIn:E] Stopped
2022-03-15T15:38:34.903Z,1647358714.903 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T15:38:34.903Z,1647358714.903 [Default:CheckIn] Stopped
2022-03-15T15:38:34.903Z,1647358714.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T15:38:34.904Z,1647358714.904 [Default:CheckIn](INFO): Running loop #2
2022-03-15T15:38:34.904Z,1647358714.904 [Default:CheckIn] Running Loop=2
2022-03-15T15:38:34.904Z,1647358714.904 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T15:38:34.904Z,1647358714.904 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T15:39:42.388Z,1647358782.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2022-03-15T15:39:42.388Z,1647358782.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-15T15:39:42.399Z,1647358782.399 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-15T15:39:42.760Z,1647358782.760 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-15T15:39:42.760Z,1647358782.760 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2022-03-15T15:42:43.373Z,1647358963.373 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2022-03-15T15:42:43.373Z,1647358963.373 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2022-03-15T15:42:43.393Z,1647358963.393 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2022-03-15T15:42:43.803Z,1647358963.803 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2022-03-15T15:42:43.803Z,1647358963.803 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2022-03-15T15:43:35.086Z,1647359015.086 [Default:CheckIn:Read_GPS](INFO): Timed out from 2022-03-15T15:38:34.9Z
2022-03-15T15:43:35.086Z,1647359015.086 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T15:43:35.086Z,1647359015.086 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T15:43:42.439Z,1647359022.439 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20220315T152427/Courier0007.lzma
2022-03-15T15:43:43.441Z,1647359023.441 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0007.lzma.bak
2022-03-15T15:43:43.441Z,1647359023.441 [DataOverHttps](INFO): SBD MOMSN=16572339
2022-03-15T15:43:58.887Z,1647359038.887 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20220315T152427/Express0008.lzma
2022-03-15T15:43:59.889Z,1647359039.889 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0008.lzma.bak
2022-03-15T15:43:59.889Z,1647359039.889 [DataOverHttps](INFO): SBD MOMSN=16572341
2022-03-15T15:44:00.926Z,1647359040.926 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T15:44:00.926Z,1647359040.926 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T15:44:00.926Z,1647359040.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T15:45:01.919Z,1647359101.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154500.00,A,3648.17944,N,12147.28164,W,0.136,0.00,150322,,,A*74
2022-03-15T15:45:01.923Z,1647359101.923 [NAL9602](INFO): GPS fix at 20220315T154500: (36.802991, -121.788027)
2022-03-15T15:45:34.241Z,1647359134.241 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T15:46:01.996Z,1647359161.996 [CommandExec](IMPORTANT): got command ibit
2022-03-15T15:46:02.134Z,1647359162.134 [IBIT](IMPORTANT): Beginning Initiated BIT
2022-03-15T15:46:02.135Z,1647359162.135 [IBIT](IMPORTANT): Beginning control surface checks.
2022-03-15T15:46:02.138Z,1647359162.138 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-15T15:46:03.737Z,1647359163.737 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154602.00,A,3648.18013,N,12147.28146,W,0.972,58.90,150322,,,A*4D
2022-03-15T15:46:03.739Z,1647359163.739 [NAL9602](INFO): GPS fix at 20220315T154602: (36.803002, -121.788024)
2022-03-15T15:46:13.045Z,1647359173.045 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-03-15T15:46:13.463Z,1647359173.463 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-15T15:46:21.107Z,1647359181.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=31873, MT Status=0, MTMSN=0
2022-03-15T15:46:21.107Z,1647359181.107 [NAL9602](INFO): No messages in MT queue
2022-03-15T15:46:22.320Z,1647359182.320 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154620.00,A,3648.18062,N,12147.28134,W,1.030,7.19,150322,,,A*7B
2022-03-15T15:46:22.322Z,1647359182.322 [NAL9602](INFO): GPS fix at 20220315T154620: (36.803010, -121.788022)
2022-03-15T15:46:24.444Z,1647359184.444 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2022-03-15T15:46:24.747Z,1647359184.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154623.00,A,3648.18088,N,12147.28137,W,0.797,357.33,150322,,,A*7A
2022-03-15T15:46:24.749Z,1647359184.749 [NAL9602](INFO): GPS fix at 20220315T154623: (36.803015, -121.788023)
2022-03-15T15:46:24.957Z,1647359184.957 [CBIT](IMPORTANT): Beginning ground fault scan
2022-03-15T15:46:27.978Z,1647359187.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154626.00,A,3648.18052,N,12147.28108,W,1.322,24.18,150322,,,A*41
2022-03-15T15:46:27.981Z,1647359187.981 [NAL9602](INFO): GPS fix at 20220315T154626: (36.803009, -121.788018)
2022-03-15T15:46:30.807Z,1647359190.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154629.00,A,3648.18045,N,12147.28098,W,0.778,75.89,150322,,,A*46
2022-03-15T15:46:30.809Z,1647359190.809 [NAL9602](INFO): GPS fix at 20220315T154629: (36.803007, -121.788016)
2022-03-15T15:46:34.039Z,1647359194.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154632.00,A,3648.18007,N,12147.28084,W,0.641,75.89,150322,,,A*4C
2022-03-15T15:46:34.041Z,1647359194.041 [NAL9602](INFO): GPS fix at 20220315T154632: (36.803001, -121.788014)
2022-03-15T15:46:36.160Z,1647359196.160 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.002824
CHAN A1 (24V): -0.177727
CHAN A2 (12V): -0.002004
CHAN A3 (5V): -0.001150
CHAN B0 (3.3V): -0.000390
CHAN B1 (3.15aV): -0.000235
CHAN B2 (3.15bV): -0.000370
CHAN B3 (GND): 0.000109
OPEN: -0.000427
Full Scale: +/- 1 mA
2022-03-15T15:46:36.867Z,1647359196.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154635.00,A,3648.17916,N,12147.28049,W,0.952,74.08,150322,,,A*49
2022-03-15T15:46:36.870Z,1647359196.870 [NAL9602](INFO): GPS fix at 20220315T154635: (36.802986, -121.788008)
2022-03-15T15:46:39.695Z,1647359199.695 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154637.00,A,3648.17894,N,12147.28046,W,0.233,74.08,150322,,,A*43
2022-03-15T15:46:39.697Z,1647359199.697 [NAL9602](INFO): GPS fix at 20220315T154637: (36.802982, -121.788008)
2022-03-15T15:46:42.922Z,1647359202.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154641.00,A,3648.17674,N,12147.27758,W,3.946,74.08,150322,,,A*4F
2022-03-15T15:46:42.937Z,1647359202.937 [NAL9602](INFO): GPS fix at 20220315T154641: (36.802946, -121.787960)
2022-03-15T15:46:45.753Z,1647359205.753 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154644.00,A,3648.17329,N,12147.27448,W,3.635,136.64,150322,,,A*73
2022-03-15T15:46:45.755Z,1647359205.755 [NAL9602](INFO): GPS fix at 20220315T154644: (36.802888, -121.787908)
2022-03-15T15:46:48.204Z,1647359208.204 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802887 Longitude: -121.787910
2022-03-15T15:46:48.627Z,1647359208.627 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2022-03-15T15:46:48.628Z,1647359208.628 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2022-03-15T15:46:48.628Z,1647359208.628 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2022-03-15T15:46:48.999Z,1647359208.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154647.00,A,3648.17136,N,12147.27137,W,4.024,134.73,150322,,,A*74
2022-03-15T15:46:49.002Z,1647359209.002 [NAL9602](INFO): GPS fix at 20220315T154647: (36.802856, -121.787856)
2022-03-15T15:46:49.018Z,1647359209.018 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2022-03-15T15:46:49.018Z,1647359209.018 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2022-03-15T15:46:49.019Z,1647359209.019 [IBIT](IMPORTANT): Pressure:10.095493 PSI
2022-03-15T15:46:49.019Z,1647359209.019 [IBIT](IMPORTANT): Humidity:9.064827 %
2022-03-15T15:46:49.446Z,1647359209.446 [IBIT](IMPORTANT): Vehicle Pitch:-0.183159 degrees
2022-03-15T15:46:49.446Z,1647359209.446 [IBIT](IMPORTANT): Vehicle  Roll:3.309583 degrees
2022-03-15T15:46:49.447Z,1647359209.447 [IBIT](IMPORTANT): Vehicle  Heading:115.589508 degrees
2022-03-15T15:46:49.839Z,1647359209.839 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2022-03-15T15:46:49.839Z,1647359209.839 [IBIT](IMPORTANT): buoyancyNeutral: 184.118195 cc
2022-03-15T15:46:49.840Z,1647359209.840 [IBIT](IMPORTANT): massDefault: -0.911514 cm
2022-03-15T15:46:49.840Z,1647359209.840 [IBIT](IMPORTANT): stopDepth: 205.000000 m
2022-03-15T15:46:49.840Z,1647359209.840 [IBIT](IMPORTANT): abortDepth: 250.000000 m
2022-03-15T15:46:49.840Z,1647359209.840 [IBIT](IMPORTANT): IBIT PASSED
2022-03-15T15:47:21.313Z,1647359241.313 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T15:48:20.466Z,1647359300.466 [CommandLine](INFO): End of History
2022-03-15T15:48:20.468Z,1647359300.468 [CommandLine](INFO): End of History
2022-03-15T15:48:20.469Z,1647359300.469 [CommandLine](INFO): End of History
2022-03-15T15:48:20.470Z,1647359300.470 [CommandLine](INFO): End of History
2022-03-15T15:48:20.471Z,1647359300.471 [CommandLine](INFO): End of History
2022-03-15T15:48:20.476Z,1647359300.476 [CommandLine](INFO): End of History
2022-03-15T15:48:20.477Z,1647359300.477 [CommandLine](INFO): End of History
2022-03-15T15:48:20.478Z,1647359300.478 [CommandLine](INFO): End of History
2022-03-15T15:48:20.479Z,1647359300.479 [CommandLine](INFO): End of History
2022-03-15T15:48:21.431Z,1647359301.431 [CommandLine](INFO): End of History
2022-03-15T15:48:21.436Z,1647359301.436 [CommandLine](INFO): End of History
2022-03-15T15:48:21.440Z,1647359301.440 [CommandLine](INFO): End of History
2022-03-15T15:48:21.446Z,1647359301.446 [CommandLine](INFO): End of History
2022-03-15T15:48:21.451Z,1647359301.451 [CommandLine](INFO): End of History
2022-03-15T15:48:21.463Z,1647359301.463 [CommandLine](INFO): End of History
2022-03-15T15:48:21.504Z,1647359301.504 [CommandLine](INFO): End of History
2022-03-15T15:48:21.505Z,1647359301.505 [CommandLine](INFO): End of History
2022-03-15T15:48:21.506Z,1647359301.506 [CommandLine](INFO): End of History
2022-03-15T15:48:21.631Z,1647359301.631 [CommandLine](INFO): End of History
2022-03-15T15:48:21.640Z,1647359301.640 [CommandLine](INFO): End of History
2022-03-15T15:48:21.641Z,1647359301.641 [CommandLine](INFO): End of History
2022-03-15T15:48:23.605Z,1647359303.605 [CommandLine](INFO): End of History
2022-03-15T15:48:23.610Z,1647359303.610 [CommandLine](INFO): End of History
2022-03-15T15:48:23.614Z,1647359303.614 [CommandLine](INFO): End of History
2022-03-15T15:48:23.674Z,1647359303.674 [CommandLine](INFO): End of History
2022-03-15T15:48:23.675Z,1647359303.675 [CommandLine](INFO): End of History
2022-03-15T15:48:23.676Z,1647359303.676 [CommandLine](INFO): End of History
2022-03-15T15:48:23.677Z,1647359303.677 [CommandLine](INFO): End of History
2022-03-15T15:48:23.678Z,1647359303.678 [CommandLine](INFO): End of History
2022-03-15T15:48:23.687Z,1647359303.687 [CommandLine](INFO): End of History
2022-03-15T15:48:23.729Z,1647359303.729 [CommandLine](INFO): End of History
2022-03-15T15:48:23.730Z,1647359303.730 [CommandLine](INFO): End of History
2022-03-15T15:48:23.731Z,1647359303.731 [CommandLine](INFO): End of History
2022-03-15T15:48:23.773Z,1647359303.773 [CommandLine](INFO): End of History
2022-03-15T15:48:23.774Z,1647359303.774 [CommandLine](INFO): End of History
2022-03-15T15:48:23.775Z,1647359303.775 [CommandLine](INFO): End of History
2022-03-15T15:48:23.872Z,1647359303.872 [CommandLine](INFO): End of History
2022-03-15T15:48:23.873Z,1647359303.873 [CommandLine](INFO): End of History
2022-03-15T15:48:23.874Z,1647359303.874 [CommandLine](INFO): End of History
2022-03-15T15:48:34.225Z,1647359314.225 [CommandLine](INFO): End of History
2022-03-15T15:48:34.226Z,1647359314.226 [CommandLine](INFO): End of History
2022-03-15T15:48:35.518Z,1647359315.518 [CommandLine](INFO): End of History
2022-03-15T15:48:35.522Z,1647359315.522 [CommandLine](INFO): End of History
2022-03-15T15:48:35.527Z,1647359315.527 [CommandLine](INFO): End of History
2022-03-15T15:48:35.532Z,1647359315.532 [CommandLine](INFO): End of History
2022-03-15T15:48:35.536Z,1647359315.536 [CommandLine](INFO): End of History
2022-03-15T15:48:35.537Z,1647359315.537 [CommandLine](INFO): End of History
2022-03-15T15:48:35.538Z,1647359315.538 [CommandLine](INFO): End of History
2022-03-15T15:48:35.547Z,1647359315.547 [CommandLine](INFO): End of History
2022-03-15T15:48:35.552Z,1647359315.552 [CommandLine](INFO): End of History
2022-03-15T15:48:35.557Z,1647359315.557 [CommandLine](INFO): End of History
2022-03-15T15:48:35.563Z,1647359315.563 [CommandLine](INFO): End of History
2022-03-15T15:48:35.590Z,1647359315.590 [CommandLine](INFO): End of History
2022-03-15T15:48:35.688Z,1647359315.688 [CommandLine](INFO): End of History
2022-03-15T15:48:35.689Z,1647359315.689 [CommandLine](INFO): End of History
2022-03-15T15:48:35.690Z,1647359315.690 [CommandLine](INFO): End of History
2022-03-15T15:48:35.691Z,1647359315.691 [CommandLine](INFO): End of History
2022-03-15T15:48:35.692Z,1647359315.692 [CommandLine](INFO): End of History
2022-03-15T15:48:36.243Z,1647359316.243 [CommandLine](INFO): End of History
2022-03-15T15:48:36.260Z,1647359316.260 [CommandLine](INFO): End of History
2022-03-15T15:48:36.263Z,1647359316.263 [CommandLine](INFO): End of History
2022-03-15T15:48:36.270Z,1647359316.270 [CommandLine](INFO): End of History
2022-03-15T15:48:36.274Z,1647359316.274 [CommandLine](INFO): End of History
2022-03-15T15:48:36.278Z,1647359316.278 [CommandLine](INFO): End of History
2022-03-15T15:48:36.281Z,1647359316.281 [CommandLine](INFO): End of History
2022-03-15T15:48:36.282Z,1647359316.282 [CommandLine](INFO): End of History
2022-03-15T15:48:36.283Z,1647359316.283 [CommandLine](INFO): End of History
2022-03-15T15:48:36.284Z,1647359316.284 [CommandLine](INFO): End of History
2022-03-15T15:48:36.292Z,1647359316.292 [CommandLine](INFO): End of History
2022-03-15T15:48:36.293Z,1647359316.293 [CommandLine](INFO): End of History
2022-03-15T15:48:36.294Z,1647359316.294 [CommandLine](INFO): End of History
2022-03-15T15:48:36.295Z,1647359316.295 [CommandLine](INFO): End of History
2022-03-15T15:48:36.296Z,1647359316.296 [CommandLine](INFO): End of History
2022-03-15T15:48:36.369Z,1647359316.369 [CommandLine](INFO): End of History
2022-03-15T15:48:36.371Z,1647359316.371 [CommandLine](INFO): End of History
2022-03-15T15:48:36.372Z,1647359316.372 [CommandLine](INFO): End of History
2022-03-15T15:48:36.373Z,1647359316.373 [CommandLine](INFO): End of History
2022-03-15T15:48:36.373Z,1647359316.373 [CommandLine](INFO): End of History
2022-03-15T15:48:36.374Z,1647359316.374 [CommandLine](INFO): End of History
2022-03-15T15:48:36.376Z,1647359316.376 [CommandLine](INFO): End of History
2022-03-15T15:48:36.377Z,1647359316.377 [CommandLine](INFO): End of History
2022-03-15T15:48:36.378Z,1647359316.378 [CommandLine](INFO): End of History
2022-03-15T15:48:36.379Z,1647359316.379 [CommandLine](INFO): End of History
2022-03-15T15:48:36.380Z,1647359316.380 [CommandLine](INFO): End of History
2022-03-15T15:48:36.381Z,1647359316.381 [CommandLine](INFO): End of History
2022-03-15T15:48:36.382Z,1647359316.382 [CommandLine](INFO): End of History
2022-03-15T15:48:36.383Z,1647359316.383 [CommandLine](INFO): End of History
2022-03-15T15:48:36.384Z,1647359316.384 [CommandLine](INFO): End of History
2022-03-15T15:48:36.385Z,1647359316.385 [CommandLine](INFO): End of History
2022-03-15T15:48:36.386Z,1647359316.386 [CommandLine](INFO): End of History
2022-03-15T15:48:36.387Z,1647359316.387 [CommandLine](INFO): End of History
2022-03-15T15:48:36.400Z,1647359316.400 [CommandLine](INFO): End of History
2022-03-15T15:48:36.401Z,1647359316.401 [CommandLine](INFO): End of History
2022-03-15T15:48:36.508Z,1647359316.508 [CommandLine](INFO): End of History
2022-03-15T15:48:36.509Z,1647359316.509 [CommandLine](INFO): End of History
2022-03-15T15:48:36.510Z,1647359316.510 [CommandLine](INFO): End of History
2022-03-15T15:48:36.511Z,1647359316.511 [CommandLine](INFO): End of History
2022-03-15T15:48:36.512Z,1647359316.512 [CommandLine](INFO): End of History
2022-03-15T15:49:01.524Z,1647359341.524 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T15:49:01.524Z,1647359341.524 [Default:CheckIn:C.Wait] Stopped
2022-03-15T15:49:01.524Z,1647359341.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T15:49:01.524Z,1647359341.524 [Default:CheckIn:D] Running Loop=1
2022-03-15T15:49:01.932Z,1647359341.932 [Default:CheckIn:D] Stopped
2022-03-15T15:49:01.932Z,1647359341.932 [Default:CheckIn:E] Running Loop=1
2022-03-15T15:49:02.341Z,1647359342.341 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.129771 min 
2022-03-15T15:49:02.341Z,1647359342.341 [Default:CheckIn:E] Stopped
2022-03-15T15:49:02.341Z,1647359342.341 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T15:49:02.342Z,1647359342.342 [Default:CheckIn] Stopped
2022-03-15T15:49:02.342Z,1647359342.342 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T15:49:02.342Z,1647359342.342 [Default:CheckIn](INFO): Running loop #3
2022-03-15T15:49:02.342Z,1647359342.342 [Default:CheckIn] Running Loop=3
2022-03-15T15:49:02.342Z,1647359342.342 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T15:49:02.342Z,1647359342.342 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T15:49:04.347Z,1647359344.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154902.00,A,3648.13820,N,12147.22887,W,0.194,185.18,150322,,,A*7E
2022-03-15T15:49:04.349Z,1647359344.349 [NAL9602](INFO): GPS fix at 20220315T154902: (36.802303, -121.787148)
2022-03-15T15:49:04.392Z,1647359344.392 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T15:49:04.392Z,1647359344.392 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T15:49:12.143Z,1647359352.143 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20220315T152427/Courier0010.lzma
2022-03-15T15:49:13.145Z,1647359353.145 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0010.lzma.bak
2022-03-15T15:49:13.145Z,1647359353.145 [DataOverHttps](INFO): SBD MOMSN=16572346
2022-03-15T15:49:29.117Z,1647359369.117 [DataOverHttps](INFO): Sending 897 bytes from file Logs/20220315T152427/Express0011.lzma
2022-03-15T15:49:30.117Z,1647359370.117 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0011.lzma.bak
2022-03-15T15:49:30.117Z,1647359370.117 [DataOverHttps](INFO): SBD MOMSN=16572349
2022-03-15T15:49:31.448Z,1647359371.448 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T15:49:31.448Z,1647359371.448 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T15:49:31.449Z,1647359371.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T15:49:34.707Z,1647359374.707 [CommandLine](INFO): End of History
2022-03-15T15:49:34.712Z,1647359374.712 [CommandLine](INFO): End of History
2022-03-15T15:49:34.716Z,1647359374.716 [CommandLine](INFO): End of History
2022-03-15T15:49:34.721Z,1647359374.721 [CommandLine](INFO): End of History
2022-03-15T15:49:34.725Z,1647359374.725 [CommandLine](INFO): End of History
2022-03-15T15:49:34.728Z,1647359374.728 [CommandLine](INFO): End of History
2022-03-15T15:49:34.729Z,1647359374.729 [CommandLine](INFO): End of History
2022-03-15T15:49:34.730Z,1647359374.730 [CommandLine](INFO): End of History
2022-03-15T15:49:34.731Z,1647359374.731 [CommandLine](INFO): End of History
2022-03-15T15:49:34.740Z,1647359374.740 [CommandLine](INFO): End of History
2022-03-15T15:49:34.749Z,1647359374.749 [CommandLine](INFO): End of History
2022-03-15T15:49:34.768Z,1647359374.768 [CommandLine](INFO): End of History
2022-03-15T15:49:34.785Z,1647359374.785 [CommandLine](INFO): End of History
2022-03-15T15:49:34.789Z,1647359374.789 [CommandLine](INFO): End of History
2022-03-15T15:49:34.794Z,1647359374.794 [CommandLine](INFO): End of History
2022-03-15T15:49:34.807Z,1647359374.807 [CommandLine](INFO): End of History
2022-03-15T15:49:34.820Z,1647359374.820 [CommandLine](INFO): End of History
2022-03-15T15:49:34.825Z,1647359374.825 [CommandLine](INFO): End of History
2022-03-15T15:49:34.831Z,1647359374.831 [CommandLine](INFO): End of History
2022-03-15T15:49:34.854Z,1647359374.854 [CommandLine](INFO): End of History
2022-03-15T15:49:34.872Z,1647359374.872 [CommandLine](INFO): End of History
2022-03-15T15:49:34.873Z,1647359374.873 [CommandLine](INFO): End of History
2022-03-15T15:49:34.874Z,1647359374.874 [CommandLine](INFO): End of History
2022-03-15T15:49:34.875Z,1647359374.875 [CommandLine](INFO): End of History
2022-03-15T15:49:34.900Z,1647359374.900 [CommandLine](INFO): End of History
2022-03-15T15:49:34.901Z,1647359374.901 [CommandLine](INFO): End of History
2022-03-15T15:49:34.902Z,1647359374.902 [CommandLine](INFO): End of History
2022-03-15T15:49:34.917Z,1647359374.917 [CommandLine](INFO): End of History
2022-03-15T15:49:34.936Z,1647359374.936 [CommandLine](INFO): End of History
2022-03-15T15:49:34.940Z,1647359374.940 [CommandLine](INFO): End of History
2022-03-15T15:49:34.941Z,1647359374.941 [CommandLine](INFO): End of History
2022-03-15T15:49:34.942Z,1647359374.942 [CommandLine](INFO): End of History
2022-03-15T15:49:34.943Z,1647359374.943 [CommandLine](INFO): End of History
2022-03-15T15:49:34.968Z,1647359374.968 [CommandLine](INFO): End of History
2022-03-15T15:49:34.974Z,1647359374.974 [CommandLine](INFO): End of History
2022-03-15T15:49:44.184Z,1647359384.184 [CommandExec](IMPORTANT): got command failComponent
2022-03-15T15:49:44.184Z,1647359384.184 [CommandExec](IMPORTANT): Failed components:
2022-03-15T15:49:44.185Z,1647359384.185 [CommandExec](IMPORTANT): No failed Components.
2022-03-15T15:49:44.770Z,1647359384.770 [NAL9602](INFO): SBD MO Status=0, MOMSN=31874, MT Status=0, MTMSN=0
2022-03-15T15:49:44.771Z,1647359384.771 [NAL9602](INFO): No messages in MT queue
2022-03-15T15:50:15.468Z,1647359415.468 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T15:54:32.036Z,1647359672.036 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T15:54:32.036Z,1647359672.036 [Default:CheckIn:C.Wait] Stopped
2022-03-15T15:54:32.036Z,1647359672.036 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T15:54:32.036Z,1647359672.036 [Default:CheckIn:D] Running Loop=1
2022-03-15T15:54:32.433Z,1647359672.433 [Default:CheckIn:D] Stopped
2022-03-15T15:54:32.433Z,1647359672.433 [Default:CheckIn:E] Running Loop=1
2022-03-15T15:54:32.849Z,1647359672.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.638108 min 
2022-03-15T15:54:32.849Z,1647359672.849 [Default:CheckIn:E] Stopped
2022-03-15T15:54:32.849Z,1647359672.849 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T15:54:32.849Z,1647359672.849 [Default:CheckIn] Stopped
2022-03-15T15:54:32.849Z,1647359672.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T15:54:32.850Z,1647359672.850 [Default:CheckIn](INFO): Running loop #4
2022-03-15T15:54:32.850Z,1647359672.850 [Default:CheckIn] Running Loop=4
2022-03-15T15:54:32.850Z,1647359672.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T15:54:32.850Z,1647359672.850 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T15:54:34.847Z,1647359674.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155434.00,A,3648.13967,N,12147.22659,W,0.136,14.56,150322,,,D*46
2022-03-15T15:54:34.850Z,1647359674.850 [NAL9602](INFO): GPS fix at 20220315T155434: (36.802328, -121.787110)
2022-03-15T15:54:34.861Z,1647359674.861 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T15:54:34.861Z,1647359674.861 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T15:54:42.511Z,1647359682.511 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0013.lzma
2022-03-15T15:54:43.514Z,1647359683.514 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0013.lzma.bak
2022-03-15T15:54:43.514Z,1647359683.514 [DataOverHttps](INFO): SBD MOMSN=16572377
2022-03-15T15:55:02.891Z,1647359702.891 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20220315T152427/Express0014.lzma
2022-03-15T15:55:03.893Z,1647359703.893 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0014.lzma.bak
2022-03-15T15:55:03.893Z,1647359703.893 [DataOverHttps](INFO): SBD MOMSN=16572380
2022-03-15T15:55:05.224Z,1647359705.224 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T15:55:05.224Z,1647359705.224 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T15:55:05.224Z,1647359705.224 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T15:55:08.438Z,1647359708.438 [NAL9602](INFO): SBD MO Status=0, MOMSN=31875, MT Status=0, MTMSN=0
2022-03-15T15:55:08.438Z,1647359708.438 [NAL9602](INFO): No messages in MT queue
2022-03-15T15:55:39.145Z,1647359739.145 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:00:05.793Z,1647360005.793 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:00:05.793Z,1647360005.793 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:00:05.793Z,1647360005.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:00:05.793Z,1647360005.793 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:00:06.228Z,1647360006.228 [Default:CheckIn:D] Stopped
2022-03-15T16:00:06.230Z,1647360006.230 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:00:06.601Z,1647360006.601 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.201284 min 
2022-03-15T16:00:06.601Z,1647360006.601 [Default:CheckIn:E] Stopped
2022-03-15T16:00:06.601Z,1647360006.601 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:00:06.602Z,1647360006.602 [Default:CheckIn] Stopped
2022-03-15T16:00:06.602Z,1647360006.602 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:00:06.602Z,1647360006.602 [Default:CheckIn](INFO): Running loop #5
2022-03-15T16:00:06.602Z,1647360006.602 [Default:CheckIn] Running Loop=5
2022-03-15T16:00:06.602Z,1647360006.602 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:00:06.602Z,1647360006.602 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:00:08.613Z,1647360008.613 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160007.00,A,3648.13025,N,12147.23311,W,0.058,233.95,150322,,,D*72
2022-03-15T16:00:08.616Z,1647360008.616 [NAL9602](INFO): GPS fix at 20220315T160007: (36.802171, -121.787218)
2022-03-15T16:00:08.626Z,1647360008.626 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:00:08.626Z,1647360008.626 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:00:16.313Z,1647360016.313 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0016.lzma
2022-03-15T16:00:17.313Z,1647360017.313 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0016.lzma.bak
2022-03-15T16:00:17.314Z,1647360017.314 [DataOverHttps](INFO): SBD MOMSN=16572388
2022-03-15T16:00:36.523Z,1647360036.523 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220315T152427/Express0017.lzma
2022-03-15T16:00:37.527Z,1647360037.527 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0017.lzma.bak
2022-03-15T16:00:37.527Z,1647360037.527 [DataOverHttps](INFO): SBD MOMSN=16572391
2022-03-15T16:00:38.571Z,1647360038.571 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:00:38.571Z,1647360038.571 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:00:38.579Z,1647360038.579 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:00:59.978Z,1647360059.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=31876, MT Status=0, MTMSN=0
2022-03-15T16:00:59.978Z,1647360059.978 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:01:30.676Z,1647360090.676 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:05:39.188Z,1647360339.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:05:39.188Z,1647360339.188 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:05:39.188Z,1647360339.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:05:39.189Z,1647360339.189 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:05:39.601Z,1647360339.601 [Default:CheckIn:D] Stopped
2022-03-15T16:05:39.601Z,1647360339.601 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:05:39.999Z,1647360339.999 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.757581 min 
2022-03-15T16:05:39.999Z,1647360339.999 [Default:CheckIn:E] Stopped
2022-03-15T16:05:39.999Z,1647360339.999 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:05:39.999Z,1647360339.999 [Default:CheckIn] Stopped
2022-03-15T16:05:40.023Z,1647360340.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:05:40.024Z,1647360340.024 [Default:CheckIn](INFO): Running loop #6
2022-03-15T16:05:40.024Z,1647360340.024 [Default:CheckIn] Running Loop=6
2022-03-15T16:05:40.024Z,1647360340.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:05:40.024Z,1647360340.024 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:05:42.011Z,1647360342.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160541.00,A,3648.13799,N,12147.22823,W,0.058,333.40,150322,,,D*77
2022-03-15T16:05:42.014Z,1647360342.014 [NAL9602](INFO): GPS fix at 20220315T160541: (36.802300, -121.787137)
2022-03-15T16:05:42.025Z,1647360342.025 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:05:42.025Z,1647360342.025 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:05:49.427Z,1647360349.427 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0019.lzma
2022-03-15T16:05:50.429Z,1647360350.429 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0019.lzma.bak
2022-03-15T16:05:50.429Z,1647360350.429 [DataOverHttps](INFO): SBD MOMSN=16572397
2022-03-15T16:05:54.930Z,1647360354.930 [NAL9602](INFO): SBD MO Status=0, MOMSN=31877, MT Status=0, MTMSN=0
2022-03-15T16:05:54.931Z,1647360354.931 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:06:06.311Z,1647360366.311 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220315T152427/Express0020.lzma
2022-03-15T16:06:07.313Z,1647360367.313 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0020.lzma.bak
2022-03-15T16:06:07.313Z,1647360367.313 [DataOverHttps](INFO): SBD MOMSN=16572400
2022-03-15T16:06:08.676Z,1647360368.676 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:06:08.676Z,1647360368.676 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:06:08.676Z,1647360368.676 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:06:25.636Z,1647360385.636 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:11:09.332Z,1647360669.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:11:09.332Z,1647360669.332 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:11:09.333Z,1647360669.333 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:11:09.333Z,1647360669.333 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:11:09.747Z,1647360669.747 [Default:CheckIn:D] Stopped
2022-03-15T16:11:09.747Z,1647360669.747 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.260014 min 
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn:E] Stopped
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn] Stopped
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn](INFO): Running loop #7
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn] Running Loop=7
2022-03-15T16:11:10.140Z,1647360670.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:11:10.141Z,1647360670.141 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:11:12.151Z,1647360672.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161111.00,A,3648.13644,N,12147.22849,W,0.058,129.13,150322,,,D*75
2022-03-15T16:11:12.153Z,1647360672.153 [NAL9602](INFO): GPS fix at 20220315T161111: (36.802274, -121.787142)
2022-03-15T16:11:12.165Z,1647360672.165 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:11:12.165Z,1647360672.165 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:11:19.991Z,1647360679.991 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0022.lzma
2022-03-15T16:11:20.993Z,1647360680.993 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0022.lzma.bak
2022-03-15T16:11:20.993Z,1647360680.993 [DataOverHttps](INFO): SBD MOMSN=16572404
2022-03-15T16:11:34.374Z,1647360694.374 [NAL9602](INFO): SBD MO Status=2, MOMSN=31878, MT Status=2, MTMSN=0
2022-03-15T16:11:34.374Z,1647360694.374 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T16:11:36.918Z,1647360696.918 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220315T152427/Express0023.lzma
2022-03-15T16:11:37.917Z,1647360697.917 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0023.lzma.bak
2022-03-15T16:11:37.917Z,1647360697.917 [DataOverHttps](INFO): SBD MOMSN=16572407
2022-03-15T16:11:39.227Z,1647360699.227 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:11:39.227Z,1647360699.227 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:11:39.227Z,1647360699.227 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:11:51.346Z,1647360711.346 [NAL9602](INFO): SBD MO Status=0, MOMSN=31878, MT Status=0, MTMSN=0
2022-03-15T16:11:51.346Z,1647360711.346 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:12:22.041Z,1647360742.041 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:16:39.841Z,1647360999.841 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:16:39.841Z,1647360999.841 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:16:39.841Z,1647360999.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:16:39.841Z,1647360999.841 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:16:40.256Z,1647361000.256 [Default:CheckIn:D] Stopped
2022-03-15T16:16:40.257Z,1647361000.257 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:16:40.655Z,1647361000.655 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.768506 min 
2022-03-15T16:16:40.655Z,1647361000.655 [Default:CheckIn:E] Stopped
2022-03-15T16:16:40.655Z,1647361000.655 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:16:40.655Z,1647361000.655 [Default:CheckIn] Stopped
2022-03-15T16:16:40.700Z,1647361000.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:16:40.701Z,1647361000.701 [Default:CheckIn](INFO): Running loop #8
2022-03-15T16:16:40.701Z,1647361000.701 [Default:CheckIn] Running Loop=8
2022-03-15T16:16:40.701Z,1647361000.701 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:16:40.701Z,1647361000.701 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:16:42.660Z,1647361002.660 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161641.00,A,3648.13752,N,12147.22659,W,0.078,186.71,150322,,,D*7D
2022-03-15T16:16:42.663Z,1647361002.663 [NAL9602](INFO): GPS fix at 20220315T161641: (36.802292, -121.787110)
2022-03-15T16:16:42.674Z,1647361002.674 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:16:42.674Z,1647361002.674 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:16:51.063Z,1647361011.063 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0025.lzma
2022-03-15T16:16:52.065Z,1647361012.066 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0025.lzma.bak
2022-03-15T16:16:52.066Z,1647361012.066 [DataOverHttps](INFO): SBD MOMSN=16572411
2022-03-15T16:17:00.470Z,1647361020.470 [NAL9602](INFO): SBD MO Status=0, MOMSN=31879, MT Status=0, MTMSN=0
2022-03-15T16:17:00.470Z,1647361020.470 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:17:10.163Z,1647361030.163 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220315T152427/Express0026.lzma
2022-03-15T16:17:11.166Z,1647361031.166 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0026.lzma.bak
2022-03-15T16:17:11.166Z,1647361031.166 [DataOverHttps](INFO): SBD MOMSN=16572414
2022-03-15T16:17:12.223Z,1647361032.223 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:17:12.223Z,1647361032.223 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:17:12.223Z,1647361032.223 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:17:31.197Z,1647361051.197 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:22:12.998Z,1647361332.998 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:22:12.999Z,1647361332.999 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:22:12.999Z,1647361332.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:22:12.999Z,1647361332.999 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:22:13.396Z,1647361333.396 [Default:CheckIn:D] Stopped
2022-03-15T16:22:13.396Z,1647361333.396 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:22:13.795Z,1647361333.795 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.320833 min 
2022-03-15T16:22:13.795Z,1647361333.795 [Default:CheckIn:E] Stopped
2022-03-15T16:22:13.795Z,1647361333.795 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:22:13.795Z,1647361333.795 [Default:CheckIn] Stopped
2022-03-15T16:22:13.796Z,1647361333.796 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:22:13.796Z,1647361333.796 [Default:CheckIn](INFO): Running loop #9
2022-03-15T16:22:13.796Z,1647361333.796 [Default:CheckIn] Running Loop=9
2022-03-15T16:22:13.796Z,1647361333.796 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:22:13.796Z,1647361333.796 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:22:15.806Z,1647361335.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162215.00,A,3648.13765,N,12147.22682,W,0.078,43.05,150322,,,D*42
2022-03-15T16:22:15.809Z,1647361335.809 [NAL9602](INFO): GPS fix at 20220315T162215: (36.802294, -121.787114)
2022-03-15T16:22:15.820Z,1647361335.820 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:22:15.820Z,1647361335.820 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:22:26.068Z,1647361346.068 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0028.lzma
2022-03-15T16:22:27.070Z,1647361347.070 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0028.lzma.bak
2022-03-15T16:22:27.070Z,1647361347.070 [DataOverHttps](INFO): SBD MOMSN=16572418
2022-03-15T16:22:30.814Z,1647361350.814 [NAL9602](INFO): SBD MO Status=0, MOMSN=31880, MT Status=0, MTMSN=0
2022-03-15T16:22:30.814Z,1647361350.814 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:22:42.867Z,1647361362.867 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20220315T152427/Express0029.lzma
2022-03-15T16:22:43.872Z,1647361363.872 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0029.lzma.bak
2022-03-15T16:22:43.872Z,1647361363.872 [DataOverHttps](INFO): SBD MOMSN=16572421
2022-03-15T16:22:44.960Z,1647361364.960 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:22:44.960Z,1647361364.960 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:22:44.961Z,1647361364.961 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:23:01.573Z,1647361381.573 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:27:45.439Z,1647361665.439 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:27:45.439Z,1647361665.439 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:27:45.439Z,1647361665.439 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:27:45.439Z,1647361665.439 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:27:45.847Z,1647361665.847 [Default:CheckIn:D] Stopped
2022-03-15T16:27:45.847Z,1647361665.847 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:27:46.255Z,1647361666.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.861682 min 
2022-03-15T16:27:46.255Z,1647361666.255 [Default:CheckIn:E] Stopped
2022-03-15T16:27:46.255Z,1647361666.255 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:27:46.255Z,1647361666.255 [Default:CheckIn] Stopped
2022-03-15T16:27:46.255Z,1647361666.255 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:27:46.256Z,1647361666.256 [Default:CheckIn](INFO): Running loop #10
2022-03-15T16:27:46.256Z,1647361666.256 [Default:CheckIn] Running Loop=10
2022-03-15T16:27:46.256Z,1647361666.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:27:46.256Z,1647361666.256 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:27:48.263Z,1647361668.263 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162747.00,A,3648.13763,N,12147.22642,W,0.622,43.05,150322,,,D*43
2022-03-15T16:27:48.279Z,1647361668.279 [NAL9602](INFO): GPS fix at 20220315T162747: (36.802294, -121.787107)
2022-03-15T16:27:48.290Z,1647361668.290 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:27:48.290Z,1647361668.290 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:27:56.047Z,1647361676.047 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0031.lzma
2022-03-15T16:27:57.049Z,1647361677.049 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0031.lzma.bak
2022-03-15T16:27:57.050Z,1647361677.050 [DataOverHttps](INFO): SBD MOMSN=16572425
2022-03-15T16:28:08.498Z,1647361688.498 [NAL9602](INFO): SBD MO Status=0, MOMSN=31881, MT Status=0, MTMSN=0
2022-03-15T16:28:08.499Z,1647361688.499 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:28:12.995Z,1647361692.995 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220315T152427/Express0032.lzma
2022-03-15T16:28:13.997Z,1647361693.997 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0032.lzma.bak
2022-03-15T16:28:13.997Z,1647361693.997 [DataOverHttps](INFO): SBD MOMSN=16572430
2022-03-15T16:28:15.338Z,1647361695.338 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:28:15.338Z,1647361695.338 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:28:15.339Z,1647361695.339 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:28:39.221Z,1647361719.221 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:33:15.845Z,1647361995.845 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:33:15.845Z,1647361995.845 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:33:15.845Z,1647361995.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:33:15.845Z,1647361995.845 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:33:16.253Z,1647361996.253 [Default:CheckIn:D] Stopped
2022-03-15T16:33:16.253Z,1647361996.253 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:33:16.704Z,1647361996.704 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.368441 min 
2022-03-15T16:33:16.704Z,1647361996.704 [Default:CheckIn:E] Stopped
2022-03-15T16:33:16.704Z,1647361996.704 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:33:16.704Z,1647361996.704 [Default:CheckIn] Stopped
2022-03-15T16:33:16.704Z,1647361996.704 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:33:16.704Z,1647361996.704 [Default:CheckIn](INFO): Running loop #11
2022-03-15T16:33:16.704Z,1647361996.704 [Default:CheckIn] Running Loop=11
2022-03-15T16:33:16.705Z,1647361996.705 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:33:16.705Z,1647361996.705 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:33:18.647Z,1647361998.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163317.00,A,3648.14111,N,12147.22338,W,0.758,161.10,150322,,,D*76
2022-03-15T16:33:18.650Z,1647361998.650 [NAL9602](INFO): GPS fix at 20220315T163317: (36.802352, -121.787056)
2022-03-15T16:33:18.660Z,1647361998.660 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:33:18.660Z,1647361998.660 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:33:26.459Z,1647362006.459 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0034.lzma
2022-03-15T16:33:27.461Z,1647362007.461 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0034.lzma.bak
2022-03-15T16:33:27.462Z,1647362007.462 [DataOverHttps](INFO): SBD MOMSN=16572602
2022-03-15T16:33:36.018Z,1647362016.018 [NAL9602](INFO): SBD MO Status=2, MOMSN=31882, MT Status=2, MTMSN=0
2022-03-15T16:33:36.018Z,1647362016.018 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T16:33:43.611Z,1647362023.611 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220315T152427/Express0035.lzma
2022-03-15T16:33:44.614Z,1647362024.614 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0035.lzma.bak
2022-03-15T16:33:44.614Z,1647362024.614 [DataOverHttps](INFO): SBD MOMSN=16572605
2022-03-15T16:33:45.723Z,1647362025.723 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:33:45.723Z,1647362025.723 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:33:45.723Z,1647362025.723 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:33:53.390Z,1647362033.390 [NAL9602](INFO): SBD MO Status=0, MOMSN=31882, MT Status=0, MTMSN=0
2022-03-15T16:33:53.390Z,1647362033.390 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:34:23.801Z,1647362063.801 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:38:15.046Z,1647362295.046 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-03-15T16:38:15.049Z,1647362295.049 [BPC1](INFO): Received data from all battery sticks.
2022-03-15T16:38:46.201Z,1647362326.201 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:38:46.201Z,1647362326.201 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:38:46.201Z,1647362326.201 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:38:46.201Z,1647362326.201 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:38:46.609Z,1647362326.609 [Default:CheckIn:D] Stopped
2022-03-15T16:38:46.609Z,1647362326.609 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:38:47.001Z,1647362327.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.874382 min 
2022-03-15T16:38:47.001Z,1647362327.001 [Default:CheckIn:E] Stopped
2022-03-15T16:38:47.001Z,1647362327.001 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:38:47.002Z,1647362327.002 [Default:CheckIn] Stopped
2022-03-15T16:38:47.002Z,1647362327.002 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:38:47.002Z,1647362327.002 [Default:CheckIn](INFO): Running loop #12
2022-03-15T16:38:47.002Z,1647362327.002 [Default:CheckIn] Running Loop=12
2022-03-15T16:38:47.002Z,1647362327.002 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:38:47.002Z,1647362327.002 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:38:49.014Z,1647362329.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163848.00,A,3648.13780,N,12147.22700,W,0.058,161.10,150322,,,D*76
2022-03-15T16:38:49.026Z,1647362329.026 [NAL9602](INFO): GPS fix at 20220315T163848: (36.802297, -121.787117)
2022-03-15T16:38:49.038Z,1647362329.038 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:38:49.038Z,1647362329.038 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:38:59.735Z,1647362339.735 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20220315T152427/Courier0037.lzma
2022-03-15T16:39:00.737Z,1647362340.737 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0037.lzma.bak
2022-03-15T16:39:00.737Z,1647362340.737 [DataOverHttps](INFO): SBD MOMSN=16572639
2022-03-15T16:39:16.600Z,1647362356.600 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220315T152427/Express0038.lzma
2022-03-15T16:39:17.601Z,1647362357.601 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0038.lzma.bak
2022-03-15T16:39:17.601Z,1647362357.601 [DataOverHttps](INFO): SBD MOMSN=16572642
2022-03-15T16:39:18.994Z,1647362358.994 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:39:18.994Z,1647362358.994 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:39:18.994Z,1647362358.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:39:31.102Z,1647362371.102 [NAL9602](INFO): SBD MO Status=2, MOMSN=31883, MT Status=2, MTMSN=0
2022-03-15T16:39:31.102Z,1647362371.102 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T16:40:04.227Z,1647362404.227 [NAL9602](INFO): SBD MO Status=0, MOMSN=31883, MT Status=0, MTMSN=0
2022-03-15T16:40:04.227Z,1647362404.227 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:40:34.985Z,1647362434.985 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:44:19.665Z,1647362659.665 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:44:19.665Z,1647362659.665 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:44:19.665Z,1647362659.665 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:44:19.666Z,1647362659.666 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:44:20.081Z,1647362660.081 [Default:CheckIn:D] Stopped
2022-03-15T16:44:20.081Z,1647362660.081 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:44:20.500Z,1647362660.500 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.432243 min 
2022-03-15T16:44:20.500Z,1647362660.500 [Default:CheckIn:E] Stopped
2022-03-15T16:44:20.501Z,1647362660.501 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:44:20.501Z,1647362660.501 [Default:CheckIn] Stopped
2022-03-15T16:44:20.501Z,1647362660.501 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:44:20.501Z,1647362660.501 [Default:CheckIn](INFO): Running loop #13
2022-03-15T16:44:20.501Z,1647362660.501 [Default:CheckIn] Running Loop=13
2022-03-15T16:44:20.501Z,1647362660.501 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:44:20.501Z,1647362660.501 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:44:22.482Z,1647362662.482 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164421.00,A,3648.13701,N,12147.22727,W,0.019,161.10,150322,,,D*7B
2022-03-15T16:44:22.493Z,1647362662.493 [NAL9602](INFO): GPS fix at 20220315T164421: (36.802284, -121.787121)
2022-03-15T16:44:22.504Z,1647362662.504 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:44:22.504Z,1647362662.504 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:44:31.294Z,1647362671.294 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0040.lzma
2022-03-15T16:44:32.294Z,1647362672.294 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0040.lzma.bak
2022-03-15T16:44:32.294Z,1647362672.294 [DataOverHttps](INFO): SBD MOMSN=16572675
2022-03-15T16:44:45.518Z,1647362685.518 [NAL9602](INFO): SBD MO Status=2, MOMSN=31884, MT Status=2, MTMSN=0
2022-03-15T16:44:45.518Z,1647362685.518 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T16:44:48.799Z,1647362688.799 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220315T152427/Express0041.lzma
2022-03-15T16:44:49.801Z,1647362689.801 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0041.lzma.bak
2022-03-15T16:44:49.802Z,1647362689.802 [DataOverHttps](INFO): SBD MOMSN=16572678
2022-03-15T16:44:50.824Z,1647362690.824 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:44:50.824Z,1647362690.824 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:44:50.824Z,1647362690.824 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:45:14.234Z,1647362714.234 [NAL9602](INFO): SBD MO Status=0, MOMSN=31884, MT Status=0, MTMSN=0
2022-03-15T16:45:14.235Z,1647362714.235 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:45:44.941Z,1647362744.941 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:49:51.405Z,1647362991.405 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:49:51.405Z,1647362991.405 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:49:51.405Z,1647362991.405 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:49:51.405Z,1647362991.405 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:49:51.799Z,1647362991.799 [Default:CheckIn:D] Stopped
2022-03-15T16:49:51.799Z,1647362991.799 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.960881 min 
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn:E] Stopped
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn] Stopped
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn](INFO): Running loop #14
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn] Running Loop=14
2022-03-15T16:49:52.204Z,1647362992.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:49:52.205Z,1647362992.205 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:49:54.215Z,1647362994.215 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164953.00,A,3648.13828,N,12147.22734,W,0.019,161.10,150322,,,D*75
2022-03-15T16:49:54.217Z,1647362994.217 [NAL9602](INFO): GPS fix at 20220315T164953: (36.802305, -121.787122)
2022-03-15T16:49:54.229Z,1647362994.229 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:49:54.229Z,1647362994.229 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:50:01.903Z,1647363001.903 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0043.lzma
2022-03-15T16:50:02.906Z,1647363002.906 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0043.lzma.bak
2022-03-15T16:50:02.906Z,1647363002.906 [DataOverHttps](INFO): SBD MOMSN=16572682
2022-03-15T16:50:08.758Z,1647363008.758 [NAL9602](INFO): SBD MO Status=0, MOMSN=31885, MT Status=0, MTMSN=0
2022-03-15T16:50:08.758Z,1647363008.758 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:50:19.759Z,1647363019.759 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20220315T152427/Express0044.lzma
2022-03-15T16:50:20.761Z,1647363020.761 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0044.lzma.bak
2022-03-15T16:50:20.762Z,1647363020.762 [DataOverHttps](INFO): SBD MOMSN=16572685
2022-03-15T16:50:22.118Z,1647363022.118 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:50:22.118Z,1647363022.118 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:50:22.119Z,1647363022.119 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:50:39.509Z,1647363039.509 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T16:55:22.755Z,1647363322.755 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T16:55:22.755Z,1647363322.755 [Default:CheckIn:C.Wait] Stopped
2022-03-15T16:55:22.755Z,1647363322.755 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T16:55:22.755Z,1647363322.755 [Default:CheckIn:D] Running Loop=1
2022-03-15T16:55:23.167Z,1647363323.167 [Default:CheckIn:D] Stopped
2022-03-15T16:55:23.167Z,1647363323.167 [Default:CheckIn:E] Running Loop=1
2022-03-15T16:55:23.571Z,1647363323.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.483683 min 
2022-03-15T16:55:23.576Z,1647363323.576 [Default:CheckIn:E] Stopped
2022-03-15T16:55:23.576Z,1647363323.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T16:55:23.576Z,1647363323.576 [Default:CheckIn] Stopped
2022-03-15T16:55:23.576Z,1647363323.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T16:55:23.577Z,1647363323.577 [Default:CheckIn](INFO): Running loop #15
2022-03-15T16:55:23.577Z,1647363323.577 [Default:CheckIn] Running Loop=15
2022-03-15T16:55:23.577Z,1647363323.577 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T16:55:23.577Z,1647363323.577 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T16:55:25.575Z,1647363325.575 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165524.00,A,3648.13835,N,12147.22763,W,0.058,161.10,150322,,,D*73
2022-03-15T16:55:25.577Z,1647363325.577 [NAL9602](INFO): GPS fix at 20220315T165524: (36.802306, -121.787127)
2022-03-15T16:55:25.621Z,1647363325.621 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T16:55:25.621Z,1647363325.621 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T16:55:33.427Z,1647363333.427 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220315T152427/Courier0046.lzma
2022-03-15T16:55:34.429Z,1647363334.429 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0046.lzma.bak
2022-03-15T16:55:34.429Z,1647363334.429 [DataOverHttps](INFO): SBD MOMSN=16572696
2022-03-15T16:55:50.343Z,1647363350.343 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220315T152427/Express0047.lzma
2022-03-15T16:55:51.345Z,1647363351.345 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0047.lzma.bak
2022-03-15T16:55:51.345Z,1647363351.345 [DataOverHttps](INFO): SBD MOMSN=16572699
2022-03-15T16:55:52.676Z,1647363352.676 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T16:55:52.676Z,1647363352.676 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T16:55:52.676Z,1647363352.676 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T16:56:39.526Z,1647363399.526 [NAL9602](INFO): SBD MO Status=0, MOMSN=31886, MT Status=0, MTMSN=0
2022-03-15T16:56:39.526Z,1647363399.526 [NAL9602](INFO): No messages in MT queue
2022-03-15T16:57:10.231Z,1647363430.231 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:00:53.272Z,1647363653.272 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:00:53.272Z,1647363653.272 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:00:53.272Z,1647363653.272 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:00:53.272Z,1647363653.272 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:00:53.649Z,1647363653.649 [Default:CheckIn:D] Stopped
2022-03-15T17:00:53.649Z,1647363653.649 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:00:54.069Z,1647363654.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.991707 min 
2022-03-15T17:00:54.069Z,1647363654.069 [Default:CheckIn:E] Stopped
2022-03-15T17:00:54.070Z,1647363654.070 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:00:54.070Z,1647363654.070 [Default:CheckIn] Stopped
2022-03-15T17:00:54.070Z,1647363654.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:00:54.070Z,1647363654.070 [Default:CheckIn](INFO): Running loop #16
2022-03-15T17:00:54.070Z,1647363654.070 [Default:CheckIn] Running Loop=16
2022-03-15T17:00:54.070Z,1647363654.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:00:54.070Z,1647363654.070 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:00:56.067Z,1647363656.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170055.00,A,3648.13835,N,12147.22839,W,0.058,0.00,150322,,,D*73
2022-03-15T17:00:56.079Z,1647363656.079 [NAL9602](INFO): GPS fix at 20220315T170055: (36.802306, -121.787140)
2022-03-15T17:00:56.089Z,1647363656.089 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:00:56.090Z,1647363656.090 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:01:03.379Z,1647363663.379 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0049.lzma
2022-03-15T17:01:04.381Z,1647363664.381 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0049.lzma.bak
2022-03-15T17:01:04.382Z,1647363664.382 [DataOverHttps](INFO): SBD MOMSN=16572732
2022-03-15T17:01:20.423Z,1647363680.423 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220315T152427/Express0050.lzma
2022-03-15T17:01:21.426Z,1647363681.426 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0050.lzma.bak
2022-03-15T17:01:21.426Z,1647363681.426 [DataOverHttps](INFO): SBD MOMSN=16572735
2022-03-15T17:01:22.745Z,1647363682.745 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:01:22.745Z,1647363682.745 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:01:22.745Z,1647363682.745 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:02:21.718Z,1647363741.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=31887, MT Status=2, MTMSN=0
2022-03-15T17:02:21.719Z,1647363741.719 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T17:03:08.594Z,1647363788.594 [NAL9602](INFO): SBD MO Status=2, MOMSN=31887, MT Status=2, MTMSN=0
2022-03-15T17:03:08.595Z,1647363788.595 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T17:03:47.778Z,1647363827.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=31887, MT Status=2, MTMSN=0
2022-03-15T17:03:47.778Z,1647363827.778 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T17:05:29.998Z,1647363929.998 [NAL9602](INFO): SBD MO Status=0, MOMSN=31887, MT Status=0, MTMSN=0
2022-03-15T17:05:29.998Z,1647363929.998 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:06:00.697Z,1647363960.697 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:06:23.345Z,1647363983.345 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:06:23.345Z,1647363983.345 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:06:23.345Z,1647363983.345 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:06:23.346Z,1647363983.346 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:06:23.760Z,1647363983.760 [Default:CheckIn:D] Stopped
2022-03-15T17:06:23.760Z,1647363983.760 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:06:24.153Z,1647363984.153 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.493571 min 
2022-03-15T17:06:24.153Z,1647363984.153 [Default:CheckIn:E] Stopped
2022-03-15T17:06:24.153Z,1647363984.153 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:06:24.153Z,1647363984.153 [Default:CheckIn] Stopped
2022-03-15T17:06:24.153Z,1647363984.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:06:24.154Z,1647363984.154 [Default:CheckIn](INFO): Running loop #17
2022-03-15T17:06:24.154Z,1647363984.154 [Default:CheckIn] Running Loop=17
2022-03-15T17:06:24.154Z,1647363984.154 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:06:24.154Z,1647363984.154 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:06:26.151Z,1647363986.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170625.00,A,3648.13784,N,12147.22697,W,0.078,0.00,150322,,,D*7F
2022-03-15T17:06:26.153Z,1647363986.153 [NAL9602](INFO): GPS fix at 20220315T170625: (36.802297, -121.787116)
2022-03-15T17:06:26.187Z,1647363986.187 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:06:26.187Z,1647363986.187 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:06:33.991Z,1647363993.991 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220315T152427/Courier0052.lzma
2022-03-15T17:06:34.993Z,1647363994.993 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0052.lzma.bak
2022-03-15T17:06:34.993Z,1647363994.993 [DataOverHttps](INFO): SBD MOMSN=16572748
2022-03-15T17:06:43.933Z,1647364003.933 [NAL9602](INFO): SBD MO Status=0, MOMSN=31888, MT Status=0, MTMSN=0
2022-03-15T17:06:43.934Z,1647364003.934 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:06:53.659Z,1647364013.659 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20220315T152427/Express0053.lzma
2022-03-15T17:06:54.662Z,1647364014.662 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0053.lzma.bak
2022-03-15T17:06:54.662Z,1647364014.662 [DataOverHttps](INFO): SBD MOMSN=16572751
2022-03-15T17:06:55.729Z,1647364015.729 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:06:55.729Z,1647364015.729 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:06:55.729Z,1647364015.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:07:14.680Z,1647364034.680 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:11:56.306Z,1647364316.306 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:11:56.306Z,1647364316.306 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:11:56.306Z,1647364316.306 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:11:56.306Z,1647364316.306 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:11:56.732Z,1647364316.732 [Default:CheckIn:D] Stopped
2022-03-15T17:11:56.732Z,1647364316.732 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:11:57.107Z,1647364317.107 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.043099 min 
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn:E] Stopped
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn] Stopped
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn](INFO): Running loop #18
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn] Running Loop=18
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:11:57.108Z,1647364317.108 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:11:59.119Z,1647364319.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171158.00,A,3648.13695,N,12147.22874,W,0.000,0.00,150322,,,D*7E
2022-03-15T17:11:59.122Z,1647364319.122 [NAL9602](INFO): GPS fix at 20220315T171158: (36.802282, -121.787146)
2022-03-15T17:11:59.132Z,1647364319.132 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:11:59.132Z,1647364319.132 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:12:06.515Z,1647364326.515 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20220315T152427/Courier0055.lzma
2022-03-15T17:12:07.517Z,1647364327.517 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0055.lzma.bak
2022-03-15T17:12:07.517Z,1647364327.517 [DataOverHttps](INFO): SBD MOMSN=16572766
2022-03-15T17:12:16.902Z,1647364336.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=31889, MT Status=0, MTMSN=0
2022-03-15T17:12:16.902Z,1647364336.902 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:12:26.359Z,1647364346.359 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20220315T152427/Express0056.lzma
2022-03-15T17:12:27.362Z,1647364347.362 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0056.lzma.bak
2022-03-15T17:12:27.362Z,1647364347.362 [DataOverHttps](INFO): SBD MOMSN=16572769
2022-03-15T17:12:28.697Z,1647364348.697 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:12:28.697Z,1647364348.697 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:12:28.697Z,1647364348.697 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:12:47.649Z,1647364367.649 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:17:29.274Z,1647364649.274 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:17:29.274Z,1647364649.274 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:17:29.274Z,1647364649.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:17:29.275Z,1647364649.275 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:17:29.682Z,1647364649.682 [Default:CheckIn:D] Stopped
2022-03-15T17:17:29.682Z,1647364649.682 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:17:30.072Z,1647364650.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.592261 min 
2022-03-15T17:17:30.072Z,1647364650.072 [Default:CheckIn:E] Stopped
2022-03-15T17:17:30.072Z,1647364650.072 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:17:30.072Z,1647364650.072 [Default:CheckIn] Stopped
2022-03-15T17:17:30.073Z,1647364650.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:17:30.073Z,1647364650.073 [Default:CheckIn](INFO): Running loop #19
2022-03-15T17:17:30.073Z,1647364650.073 [Default:CheckIn] Running Loop=19
2022-03-15T17:17:30.073Z,1647364650.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:17:30.073Z,1647364650.073 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:17:32.083Z,1647364652.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171731.00,A,3648.13759,N,12147.22836,W,0.019,0.00,150322,,,D*78
2022-03-15T17:17:32.085Z,1647364652.085 [NAL9602](INFO): GPS fix at 20220315T171731: (36.802293, -121.787139)
2022-03-15T17:17:32.097Z,1647364652.097 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:17:32.097Z,1647364652.097 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:17:39.435Z,1647364659.435 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0058.lzma
2022-03-15T17:17:40.437Z,1647364660.437 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0058.lzma.bak
2022-03-15T17:17:40.438Z,1647364660.438 [DataOverHttps](INFO): SBD MOMSN=16572782
2022-03-15T17:17:56.395Z,1647364676.395 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220315T152427/Express0059.lzma
2022-03-15T17:17:57.397Z,1647364677.397 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0059.lzma.bak
2022-03-15T17:17:57.398Z,1647364677.398 [DataOverHttps](INFO): SBD MOMSN=16572785
2022-03-15T17:17:58.755Z,1647364678.755 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:17:58.755Z,1647364678.755 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:17:58.756Z,1647364678.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:18:01.982Z,1647364681.982 [NAL9602](INFO): SBD MO Status=2, MOMSN=31890, MT Status=2, MTMSN=0
2022-03-15T17:18:01.983Z,1647364681.983 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T17:18:29.859Z,1647364709.859 [NAL9602](INFO): SBD MO Status=0, MOMSN=31890, MT Status=0, MTMSN=0
2022-03-15T17:18:29.859Z,1647364709.859 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:19:00.573Z,1647364740.573 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:22:59.339Z,1647364979.339 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:22:59.339Z,1647364979.339 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:22:59.339Z,1647364979.339 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:22:59.339Z,1647364979.339 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:22:59.743Z,1647364979.743 [Default:CheckIn:D] Stopped
2022-03-15T17:22:59.743Z,1647364979.743 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:23:00.146Z,1647364980.146 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.093270 min 
2022-03-15T17:23:00.146Z,1647364980.146 [Default:CheckIn:E] Stopped
2022-03-15T17:23:00.146Z,1647364980.146 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:23:00.146Z,1647364980.146 [Default:CheckIn] Stopped
2022-03-15T17:23:00.146Z,1647364980.146 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:23:00.147Z,1647364980.147 [Default:CheckIn](INFO): Running loop #20
2022-03-15T17:23:00.147Z,1647364980.147 [Default:CheckIn] Running Loop=20
2022-03-15T17:23:00.147Z,1647364980.147 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:23:00.147Z,1647364980.147 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:23:02.151Z,1647364982.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172301.00,A,3648.13888,N,12147.22700,W,0.039,0.00,150322,,,D*77
2022-03-15T17:23:02.154Z,1647364982.154 [NAL9602](INFO): GPS fix at 20220315T172301: (36.802315, -121.787117)
2022-03-15T17:23:02.181Z,1647364982.181 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:23:02.181Z,1647364982.181 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:23:09.431Z,1647364989.431 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0061.lzma
2022-03-15T17:23:10.434Z,1647364990.434 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0061.lzma.bak
2022-03-15T17:23:10.434Z,1647364990.434 [DataOverHttps](INFO): SBD MOMSN=16572796
2022-03-15T17:23:26.451Z,1647365006.451 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220315T152427/Express0062.lzma
2022-03-15T17:23:27.454Z,1647365007.454 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0062.lzma.bak
2022-03-15T17:23:27.454Z,1647365007.454 [DataOverHttps](INFO): SBD MOMSN=16572799
2022-03-15T17:23:28.847Z,1647365008.847 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:23:28.847Z,1647365008.847 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:23:28.847Z,1647365008.847 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:24:15.301Z,1647365055.301 [NAL9602](INFO): SBD MO Status=0, MOMSN=31891, MT Status=0, MTMSN=0
2022-03-15T17:24:15.302Z,1647365055.302 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:24:46.012Z,1647365086.012 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:28:29.430Z,1647365309.430 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:28:29.430Z,1647365309.430 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:28:29.430Z,1647365309.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:28:29.430Z,1647365309.430 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:28:29.839Z,1647365309.839 [Default:CheckIn:D] Stopped
2022-03-15T17:28:29.839Z,1647365309.839 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:28:30.243Z,1647365310.243 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.594881 min 
2022-03-15T17:28:30.243Z,1647365310.243 [Default:CheckIn:E] Stopped
2022-03-15T17:28:30.244Z,1647365310.244 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:28:30.244Z,1647365310.244 [Default:CheckIn] Stopped
2022-03-15T17:28:30.244Z,1647365310.244 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:28:30.244Z,1647365310.244 [Default:CheckIn](INFO): Running loop #21
2022-03-15T17:28:30.244Z,1647365310.244 [Default:CheckIn] Running Loop=21
2022-03-15T17:28:30.244Z,1647365310.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:28:30.244Z,1647365310.244 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:28:32.247Z,1647365312.247 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172831.00,A,3648.16290,N,12147.21370,W,0.097,24.27,150322,,,D*4E
2022-03-15T17:28:32.250Z,1647365312.250 [NAL9602](INFO): GPS fix at 20220315T172831: (36.802715, -121.786895)
2022-03-15T17:28:32.284Z,1647365312.284 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:28:32.284Z,1647365312.284 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:28:39.627Z,1647365319.627 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0064.lzma
2022-03-15T17:28:40.629Z,1647365320.629 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0064.lzma.bak
2022-03-15T17:28:40.630Z,1647365320.630 [DataOverHttps](INFO): SBD MOMSN=16572810
2022-03-15T17:28:43.559Z,1647365323.559 [NAL9602](INFO): SBD MO Status=0, MOMSN=31892, MT Status=0, MTMSN=0
2022-03-15T17:28:43.559Z,1647365323.559 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:28:59.855Z,1647365339.855 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20220315T152427/Express0065.lzma
2022-03-15T17:29:00.859Z,1647365340.859 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0065.lzma.bak
2022-03-15T17:29:00.859Z,1647365340.859 [DataOverHttps](INFO): SBD MOMSN=16572813
2022-03-15T17:29:02.231Z,1647365342.231 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:29:02.231Z,1647365342.231 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:29:02.231Z,1647365342.231 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:29:14.327Z,1647365354.327 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:34:02.824Z,1647365642.824 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:34:02.824Z,1647365642.824 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:34:02.824Z,1647365642.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:34:02.824Z,1647365642.824 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:34:03.207Z,1647365643.207 [Default:CheckIn:D] Stopped
2022-03-15T17:34:03.207Z,1647365643.207 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:34:03.606Z,1647365643.606 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.151017 min 
2022-03-15T17:34:03.606Z,1647365643.606 [Default:CheckIn:E] Stopped
2022-03-15T17:34:03.606Z,1647365643.606 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:34:03.606Z,1647365643.606 [Default:CheckIn] Stopped
2022-03-15T17:34:03.606Z,1647365643.606 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:34:03.606Z,1647365643.606 [Default:CheckIn](INFO): Running loop #22
2022-03-15T17:34:03.607Z,1647365643.607 [Default:CheckIn] Running Loop=22
2022-03-15T17:34:03.607Z,1647365643.607 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:34:03.607Z,1647365643.607 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:34:05.623Z,1647365645.623 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173404.00,A,3648.16333,N,12147.21395,W,0.058,24.27,150322,,,A*40
2022-03-15T17:34:05.637Z,1647365645.637 [NAL9602](INFO): GPS fix at 20220315T173404: (36.802722, -121.786899)
2022-03-15T17:34:05.648Z,1647365645.648 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:34:05.648Z,1647365645.648 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:34:13.315Z,1647365653.315 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220315T152427/Courier0067.lzma
2022-03-15T17:34:14.317Z,1647365654.317 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0067.lzma.bak
2022-03-15T17:34:14.317Z,1647365654.317 [DataOverHttps](INFO): SBD MOMSN=16572824
2022-03-15T17:34:33.699Z,1647365673.699 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220315T152427/Express0068.lzma
2022-03-15T17:34:34.701Z,1647365674.701 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0068.lzma.bak
2022-03-15T17:34:34.702Z,1647365674.702 [DataOverHttps](INFO): SBD MOMSN=16572827
2022-03-15T17:34:35.000Z,1647365676.000 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:34:35.000Z,1647365676.000 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:34:35.000Z,1647365676.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:34:59.799Z,1647365699.799 [NAL9602](INFO): SBD MO Status=2, MOMSN=31893, MT Status=2, MTMSN=0
2022-03-15T17:34:59.799Z,1647365699.799 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T17:36:27.062Z,1647365787.062 [NAL9602](INFO): SBD MO Status=2, MOMSN=31893, MT Status=2, MTMSN=0
2022-03-15T17:36:27.063Z,1647365787.063 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2022-03-15T17:36:48.879Z,1647365808.879 [NAL9602](INFO): SBD MO Status=0, MOMSN=31893, MT Status=0, MTMSN=0
2022-03-15T17:36:48.879Z,1647365808.879 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:37:19.586Z,1647365839.586 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:39:36.635Z,1647365976.635 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:39:36.637Z,1647365976.637 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:39:36.637Z,1647365976.637 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:39:36.638Z,1647365976.638 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:39:37.028Z,1647365977.028 [Default:CheckIn:D] Stopped
2022-03-15T17:39:37.028Z,1647365977.028 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:39:37.399Z,1647365977.399 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.714697 min 
2022-03-15T17:39:37.399Z,1647365977.399 [Default:CheckIn:E] Stopped
2022-03-15T17:39:37.399Z,1647365977.399 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:39:37.399Z,1647365977.399 [Default:CheckIn] Stopped
2022-03-15T17:39:37.400Z,1647365977.400 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:39:37.400Z,1647365977.400 [Default:CheckIn](INFO): Running loop #23
2022-03-15T17:39:37.400Z,1647365977.400 [Default:CheckIn] Running Loop=23
2022-03-15T17:39:37.400Z,1647365977.400 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:39:37.400Z,1647365977.400 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:39:39.375Z,1647365979.375 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173938.00,A,3648.16228,N,12147.21594,W,0.097,24.27,150322,,,A*4D
2022-03-15T17:39:39.377Z,1647365979.377 [NAL9602](INFO): GPS fix at 20220315T173938: (36.802705, -121.786932)
2022-03-15T17:39:39.413Z,1647365979.413 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:39:39.413Z,1647365979.413 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:39:47.071Z,1647365987.071 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0070.lzma
2022-03-15T17:39:48.073Z,1647365988.073 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0070.lzma.bak
2022-03-15T17:39:48.074Z,1647365988.074 [DataOverHttps](INFO): SBD MOMSN=16572838
2022-03-15T17:39:49.468Z,1647365989.468 [NAL9602](INFO): SBD MO Status=0, MOMSN=31894, MT Status=0, MTMSN=0
2022-03-15T17:39:49.468Z,1647365989.468 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:40:04.083Z,1647366004.083 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220315T152427/Express0071.lzma
2022-03-15T17:40:05.086Z,1647366005.086 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0071.lzma.bak
2022-03-15T17:40:05.086Z,1647366005.086 [DataOverHttps](INFO): SBD MOMSN=16572841
2022-03-15T17:40:06.458Z,1647366006.458 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:40:06.458Z,1647366006.458 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:40:06.458Z,1647366006.458 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:40:20.189Z,1647366020.189 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:45:07.020Z,1647366307.020 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:45:07.020Z,1647366307.020 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:45:07.020Z,1647366307.020 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:45:07.021Z,1647366307.021 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:45:07.438Z,1647366307.438 [Default:CheckIn:D] Stopped
2022-03-15T17:45:07.438Z,1647366307.438 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:45:07.863Z,1647366307.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.221533 min 
2022-03-15T17:45:07.863Z,1647366307.863 [Default:CheckIn:E] Stopped
2022-03-15T17:45:07.863Z,1647366307.863 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:45:07.867Z,1647366307.867 [Default:CheckIn] Stopped
2022-03-15T17:45:07.868Z,1647366307.868 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:45:07.868Z,1647366307.868 [Default:CheckIn](INFO): Running loop #24
2022-03-15T17:45:07.868Z,1647366307.868 [Default:CheckIn] Running Loop=24
2022-03-15T17:45:07.868Z,1647366307.868 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:45:07.868Z,1647366307.868 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:45:09.847Z,1647366309.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174509.00,A,3648.16219,N,12147.21517,W,0.039,24.27,150322,,,A*49
2022-03-15T17:45:09.849Z,1647366309.849 [NAL9602](INFO): GPS fix at 20220315T174509: (36.802703, -121.786919)
2022-03-15T17:45:09.861Z,1647366309.861 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:45:09.861Z,1647366309.861 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:45:17.079Z,1647366317.079 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20220315T152427/Courier0073.lzma
2022-03-15T17:45:18.082Z,1647366318.082 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0073.lzma.bak
2022-03-15T17:45:18.082Z,1647366318.082 [DataOverHttps](INFO): SBD MOMSN=16572857
2022-03-15T17:45:34.179Z,1647366334.179 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20220315T152427/Express0074.lzma
2022-03-15T17:45:35.182Z,1647366335.182 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0074.lzma.bak
2022-03-15T17:45:35.182Z,1647366335.182 [DataOverHttps](INFO): SBD MOMSN=16572860
2022-03-15T17:45:36.516Z,1647366336.516 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:45:36.516Z,1647366336.516 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:45:36.516Z,1647366336.516 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:45:39.738Z,1647366339.738 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2022-03-15T17:45:39.741Z,1647366339.741 [BPC1](INFO): Received data from all battery sticks.
2022-03-15T17:45:51.459Z,1647366351.459 [NAL9602](INFO): SBD MO Status=0, MOMSN=31895, MT Status=0, MTMSN=0
2022-03-15T17:45:51.459Z,1647366351.459 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:46:22.163Z,1647366382.163 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:50:37.094Z,1647366637.094 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:50:37.094Z,1647366637.094 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:50:37.095Z,1647366637.095 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:50:37.095Z,1647366637.095 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:50:37.506Z,1647366637.506 [Default:CheckIn:D] Stopped
2022-03-15T17:50:37.506Z,1647366637.506 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:50:37.943Z,1647366637.943 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.722656 min 
2022-03-15T17:50:37.943Z,1647366637.943 [Default:CheckIn:E] Stopped
2022-03-15T17:50:37.943Z,1647366637.943 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:50:37.943Z,1647366637.943 [Default:CheckIn] Stopped
2022-03-15T17:50:37.943Z,1647366637.943 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:50:37.943Z,1647366637.943 [Default:CheckIn](INFO): Running loop #25
2022-03-15T17:50:37.944Z,1647366637.944 [Default:CheckIn] Running Loop=25
2022-03-15T17:50:37.944Z,1647366637.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:50:37.944Z,1647366637.944 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:50:39.911Z,1647366639.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175039.00,A,3648.16875,N,12147.20629,W,1.205,40.53,150322,,,A*4C
2022-03-15T17:50:39.913Z,1647366639.913 [NAL9602](INFO): GPS fix at 20220315T175039: (36.802813, -121.786772)
2022-03-15T17:50:39.929Z,1647366639.929 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:50:39.929Z,1647366639.929 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:50:47.147Z,1647366647.147 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20220315T152427/Courier0076.lzma
2022-03-15T17:50:48.150Z,1647366648.150 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0076.lzma.bak
2022-03-15T17:50:48.150Z,1647366648.150 [DataOverHttps](INFO): SBD MOMSN=16572871
2022-03-15T17:50:53.698Z,1647366653.698 [NAL9602](INFO): SBD MO Status=0, MOMSN=31896, MT Status=0, MTMSN=0
2022-03-15T17:50:53.698Z,1647366653.698 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:51:06.999Z,1647366666.999 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20220315T152427/Express0077.lzma
2022-03-15T17:51:08.002Z,1647366668.002 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0077.lzma.bak
2022-03-15T17:51:08.002Z,1647366668.002 [DataOverHttps](INFO): SBD MOMSN=16572874
2022-03-15T17:51:09.058Z,1647366669.058 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:51:09.058Z,1647366669.058 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:51:09.058Z,1647366669.058 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:51:24.397Z,1647366684.397 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T17:56:09.643Z,1647366969.643 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T17:56:09.643Z,1647366969.643 [Default:CheckIn:C.Wait] Stopped
2022-03-15T17:56:09.643Z,1647366969.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T17:56:09.643Z,1647366969.643 [Default:CheckIn:D] Running Loop=1
2022-03-15T17:56:10.052Z,1647366970.052 [Default:CheckIn:D] Stopped
2022-03-15T17:56:10.053Z,1647366970.053 [Default:CheckIn:E] Running Loop=1
2022-03-15T17:56:10.459Z,1647366970.459 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.265104 min 
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn:E] Stopped
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn] Stopped
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn](INFO): Running loop #26
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn] Running Loop=26
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T17:56:10.460Z,1647366970.460 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T17:56:12.495Z,1647366972.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175611.00,A,3648.43768,N,12147.10380,W,4.646,4.59,150322,,,A*7A
2022-03-15T17:56:12.497Z,1647366972.497 [NAL9602](INFO): GPS fix at 20220315T175611: (36.807295, -121.785063)
2022-03-15T17:56:12.508Z,1647366972.508 [Default:CheckIn:Read_GPS] Stopped
2022-03-15T17:56:12.508Z,1647366972.508 [Default:CheckIn:Read_Iridium] Running Loop=1
2022-03-15T17:56:22.103Z,1647366982.103 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20220315T152427/Courier0079.lzma
2022-03-15T17:56:22.986Z,1647366982.986 [NAL9602](INFO): SBD MO Status=0, MOMSN=31897, MT Status=0, MTMSN=0
2022-03-15T17:56:22.986Z,1647366982.986 [NAL9602](INFO): No messages in MT queue
2022-03-15T17:56:23.106Z,1647366983.106 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Courier0079.lzma.bak
2022-03-15T17:56:23.106Z,1647366983.106 [DataOverHttps](INFO): SBD MOMSN=16572891
2022-03-15T17:56:40.595Z,1647367000.595 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20220315T152427/Express0080.lzma
2022-03-15T17:56:41.598Z,1647367001.598 [DataOverHttps](INFO): Moved sent file to Logs/20220315T152427/Express0080.lzma.bak
2022-03-15T17:56:41.598Z,1647367001.598 [DataOverHttps](INFO): SBD MOMSN=16572894
2022-03-15T17:56:42.800Z,1647367002.800 [Default:CheckIn:Read_Iridium] Stopped
2022-03-15T17:56:42.800Z,1647367002.800 [Default:CheckIn:C.Wait] Running Loop=1
2022-03-15T17:56:42.800Z,1647367002.800 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2022-03-15T17:56:53.709Z,1647367013.709 [NAL9602](INFO): Not Powering down - fast GPS
2022-03-15T18:01:43.558Z,1647367303.558 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2022-03-15T18:01:43.559Z,1647367303.559 [Default:CheckIn:C.Wait] Stopped
2022-03-15T18:01:43.559Z,1647367303.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2022-03-15T18:01:43.559Z,1647367303.559 [Default:CheckIn:D] Running Loop=1
2022-03-15T18:01:43.970Z,1647367303.970 [Default:CheckIn:D] Stopped
2022-03-15T18:01:43.970Z,1647367303.970 [Default:CheckIn:E] Running Loop=1
2022-03-15T18:01:44.384Z,1647367304.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.830404 min 
2022-03-15T18:01:44.384Z,1647367304.384 [Default:CheckIn:E] Stopped
2022-03-15T18:01:44.385Z,1647367304.385 [Default:CheckIn](INFO): Completed Default:CheckIn
2022-03-15T18:01:44.385Z,1647367304.385 [Default:CheckIn] Stopped
2022-03-15T18:01:44.385Z,1647367304.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2022-03-15T18:01:44.385Z,1647367304.385 [Default:CheckIn](INFO): Running loop #27
2022-03-15T18:01:44.385Z,1647367304.385 [Default:CheckIn] Running Loop=27
2022-03-15T18:01:44.385Z,1647367304.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2022-03-15T18:01:44.385Z,1647367304.385 [Default:CheckIn:Read_GPS] Running Loop=1
2022-03-15T18:01:46.379Z,1647367306.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180145.00,A,3648.08216,N,12148.36014,W,20.819,253.41,150322,,,D*4E
2022-03-15T18: