2019-07-16T22:57:26.958Z,1563317846.958 [Supervisor](DEBUG): Initializing supervisor.
2019-07-16T22:57:26.961Z,1563317846.961 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-07-16T22:57:26.962Z,1563317846.962 [SyncHandler](INFO): Protected caller Thread ID is 5133
2019-07-16T22:57:26.962Z,1563317846.962 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-07-16T22:57:26.963Z,1563317846.963 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-07-16T22:57:26.963Z,1563317846.963 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5134
2019-07-16T22:57:26.966Z,1563317846.966 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-07-16T22:57:26.977Z,1563317846.977 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-07-16T22:57:26.978Z,1563317846.978 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-07-16T22:57:26.978Z,1563317846.978 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5135
2019-07-16T22:57:26.979Z,1563317846.979 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-07-16T22:57:26.980Z,1563317846.980 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-07-16T22:57:26.980Z,1563317846.980 [logger ThreadHandler](INFO): Protected caller Thread ID is 5136
2019-07-16T22:57:26.982Z,1563317846.982 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-07-16T22:57:26.983Z,1563317846.983 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-07-16T22:57:26.984Z,1563317846.984 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-07-16T22:57:27.393Z,1563317847.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-07-16T22:57:27.394Z,1563317847.394 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-07-16T22:57:27.491Z,1563317847.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-07-16T22:57:27.491Z,1563317847.491 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-07-16T22:57:27.798Z,1563317847.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-07-16T22:57:27.798Z,1563317847.798 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-07-16T22:57:27.957Z,1563317847.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-07-16T22:57:27.957Z,1563317847.957 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-07-16T22:57:28.362Z,1563317848.362 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-07-16T22:57:28.362Z,1563317848.362 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-07-16T22:57:28.813Z,1563317848.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-07-16T22:57:28.814Z,1563317848.814 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-07-16T22:57:29.016Z,1563317849.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-07-16T22:57:29.016Z,1563317849.016 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-07-16T22:57:29.158Z,1563317849.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-07-16T22:57:29.159Z,1563317849.159 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-07-16T22:57:29.348Z,1563317849.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-07-16T22:57:29.348Z,1563317849.348 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-07-16T22:57:29.445Z,1563317849.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-07-16T22:57:29.445Z,1563317849.445 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-07-16T22:57:29.737Z,1563317849.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-07-16T22:57:29.738Z,1563317849.738 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-07-16T22:57:29.818Z,1563317849.818 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-07-16T22:57:29.929Z,1563317849.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-07-16T22:57:29.930Z,1563317849.930 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-07-16T22:57:30.504Z,1563317850.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-07-16T22:57:30.505Z,1563317850.505 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-07-16T22:57:30.885Z,1563317850.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-07-16T22:57:30.887Z,1563317850.887 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-07-16T22:57:30.888Z,1563317850.888 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-07-16T22:57:31.093Z,1563317851.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-07-16T22:57:31.193Z,1563317851.193 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-07-16T22:57:31.292Z,1563317851.292 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-07-16T22:57:31.514Z,1563317851.514 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-07-16T22:57:31.515Z,1563317851.515 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-07-16T22:57:31.600Z,1563317851.600 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-07-16T22:57:31.693Z,1563317851.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-07-16T22:57:31.789Z,1563317851.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-07-16T22:57:31.872Z,1563317851.872 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-07-16T22:57:31.979Z,1563317851.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-07-16T22:57:32.154Z,1563317852.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-07-16T22:57:32.283Z,1563317852.283 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-07-16T22:57:32.284Z,1563317852.284 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-07-16T22:57:32.287Z,1563317852.287 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-07-16T22:57:32.666Z,1563317852.666 [AHRS_M2] Loaded
2019-07-16T22:57:32.667Z,1563317852.667 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-07-16T22:57:32.739Z,1563317852.739 [DataOverHttps] Loaded
2019-07-16T22:57:32.739Z,1563317852.739 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-07-16T22:57:32.741Z,1563317852.741 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407654E0
2019-07-16T22:57:32.741Z,1563317852.741 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5215
2019-07-16T22:57:32.754Z,1563317852.754 [Depth_Keller] Loaded
2019-07-16T22:57:32.754Z,1563317852.754 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-07-16T22:57:32.758Z,1563317852.758 [DropWeight] Loaded
2019-07-16T22:57:32.759Z,1563317852.759 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-07-16T22:57:32.815Z,1563317852.815 [DUSBL_Hydroid] Loaded
2019-07-16T22:57:32.815Z,1563317852.815 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-07-16T22:57:32.862Z,1563317852.862 [Micromodem] Loaded
2019-07-16T22:57:32.863Z,1563317852.863 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-07-16T22:57:32.957Z,1563317852.957 [NAL9602] Loaded
2019-07-16T22:57:32.957Z,1563317852.957 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-07-16T22:57:32.973Z,1563317852.973 [Onboard] Loaded
2019-07-16T22:57:32.974Z,1563317852.974 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-07-16T22:57:32.979Z,1563317852.979 [PowerOnly] Loaded
2019-07-16T22:57:32.980Z,1563317852.980 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-07-16T22:57:32.986Z,1563317852.986 [Radio_Surface] Loaded
2019-07-16T22:57:32.986Z,1563317852.986 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-07-16T22:57:32.987Z,1563317852.987 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407954E0
2019-07-16T22:57:32.988Z,1563317852.988 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5216
2019-07-16T22:57:33.030Z,1563317853.030 [RDI_Pathfinder] Loaded
2019-07-16T22:57:33.031Z,1563317853.031 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-07-16T22:57:35.037Z,1563317855.037 [BPC1] Loaded
2019-07-16T22:57:35.037Z,1563317855.037 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-07-16T22:57:35.038Z,1563317855.038 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-07-16T22:57:35.038Z,1563317855.038 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-07-16T22:57:35.051Z,1563317855.051 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-07-16T22:57:35.052Z,1563317855.052 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-07-16T22:57:35.156Z,1563317855.156 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-07-16T22:57:35.156Z,1563317855.156 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-07-16T22:57:35.176Z,1563317855.176 [NavChart] Loaded
2019-07-16T22:57:35.177Z,1563317855.177 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-07-16T22:57:35.181Z,1563317855.181 [UniversalFixResidualReporter] Loaded
2019-07-16T22:57:35.181Z,1563317855.181 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-07-16T22:57:35.181Z,1563317855.181 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-07-16T22:57:35.182Z,1563317855.182 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-07-16T22:57:35.283Z,1563317855.283 [BuoyancyServo] Loaded
2019-07-16T22:57:35.284Z,1563317855.284 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-07-16T22:57:35.298Z,1563317855.298 [ElevatorServo] Loaded
2019-07-16T22:57:35.299Z,1563317855.299 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-07-16T22:57:35.313Z,1563317855.313 [MassServo] Loaded
2019-07-16T22:57:35.314Z,1563317855.314 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-07-16T22:57:35.329Z,1563317855.329 [RudderServo] Loaded
2019-07-16T22:57:35.329Z,1563317855.329 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-07-16T22:57:35.343Z,1563317855.343 [ThrusterServo] Loaded
2019-07-16T22:57:35.343Z,1563317855.343 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-07-16T22:57:35.344Z,1563317855.344 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-07-16T22:57:35.344Z,1563317855.344 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-07-16T22:57:35.583Z,1563317855.583 [CTD_NeilBrown] Loaded
2019-07-16T22:57:35.584Z,1563317855.584 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-07-16T22:57:35.585Z,1563317855.585 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408E04E0
2019-07-16T22:57:35.585Z,1563317855.585 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5217
2019-07-16T22:57:35.629Z,1563317855.629 [WetLabsSeaOWL_UV_A] Loaded
2019-07-16T22:57:35.629Z,1563317855.629 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-07-16T22:57:35.630Z,1563317855.630 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409104E0
2019-07-16T22:57:35.631Z,1563317855.631 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 5218
2019-07-16T22:57:35.631Z,1563317855.631 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-07-16T22:57:35.632Z,1563317855.632 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-07-16T22:57:35.911Z,1563317855.911 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-07-16T22:57:35.912Z,1563317855.912 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-07-16T22:57:35.953Z,1563317855.953 [DepthRateCalculator] Loaded
2019-07-16T22:57:35.953Z,1563317855.953 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-07-16T22:57:35.958Z,1563317855.958 [PitchRateCalculator] Loaded
2019-07-16T22:57:35.959Z,1563317855.959 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-07-16T22:57:35.971Z,1563317855.971 [SpeedCalculator] Loaded
2019-07-16T22:57:35.971Z,1563317855.971 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-07-16T22:57:35.991Z,1563317855.991 [TempGradientCalculator] Loaded
2019-07-16T22:57:35.992Z,1563317855.992 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-07-16T22:57:35.997Z,1563317855.997 [YawRateCalculator] Loaded
2019-07-16T22:57:35.997Z,1563317855.997 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-07-16T22:57:36.037Z,1563317856.037 [ElevatorOffsetCalculator] Loaded
2019-07-16T22:57:36.038Z,1563317856.038 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-07-16T22:57:36.038Z,1563317856.038 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-07-16T22:57:36.039Z,1563317856.039 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-07-16T22:57:36.167Z,1563317856.167 [SBIT](DEBUG): Construct Startup Built In Test.
2019-07-16T22:57:36.189Z,1563317856.189 [SBIT] Loaded
2019-07-16T22:57:36.189Z,1563317856.189 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-07-16T22:57:36.190Z,1563317856.190 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-07-16T22:57:36.201Z,1563317856.201 [IBIT] Loaded
2019-07-16T22:57:36.201Z,1563317856.201 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-07-16T22:57:36.204Z,1563317856.204 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-07-16T22:57:36.342Z,1563317856.342 [CBIT] Loaded
2019-07-16T22:57:36.342Z,1563317856.342 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-07-16T22:57:36.342Z,1563317856.342 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-07-16T22:57:36.343Z,1563317856.343 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-07-16T22:57:36.420Z,1563317856.420 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-07-16T22:57:36.420Z,1563317856.420 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-07-16T22:57:36.513Z,1563317856.513 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-07-16T22:57:36.514Z,1563317856.514 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-07-16T22:57:36.579Z,1563317856.579 [VerticalControl](DEBUG): Construct VerticalControl.
2019-07-16T22:57:36.660Z,1563317856.660 [VerticalControl] Loaded
2019-07-16T22:57:36.661Z,1563317856.661 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-07-16T22:57:36.661Z,1563317856.661 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-07-16T22:57:36.717Z,1563317856.717 [HorizontalControl] Loaded
2019-07-16T22:57:36.717Z,1563317856.717 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-07-16T22:57:36.718Z,1563317856.718 [SpeedControl](DEBUG): Construct SpeedControl.
2019-07-16T22:57:36.719Z,1563317856.719 [SpeedControl] Loaded
2019-07-16T22:57:36.720Z,1563317856.720 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-07-16T22:57:36.720Z,1563317856.720 [LoopControl](DEBUG): Construct LoopControl.
2019-07-16T22:57:36.721Z,1563317856.721 [LoopControl] Loaded
2019-07-16T22:57:36.721Z,1563317856.721 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-07-16T22:57:36.721Z,1563317856.721 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-07-16T22:57:36.722Z,1563317856.722 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-07-16T22:57:36.752Z,1563317856.752 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-07-16T22:57:36.756Z,1563317856.756 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-07-16T22:57:36.757Z,1563317856.757 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-07-16T22:57:36.764Z,1563317856.764 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-07-16T22:57:36.765Z,1563317856.765 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0
2019-07-16T22:57:36.765Z,1563317856.765 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5219
2019-07-16T22:57:36.769Z,1563317856.769 [Supervisor](INFO): Main Thread ID is 7035
2019-07-16T22:57:36.770Z,1563317856.770 [Supervisor](DEBUG): Running supervisor.
2019-07-16T22:57:36.770Z,1563317856.770 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5220
2019-07-16T22:57:36.773Z,1563317856.773 [controlThread ThreadHandler](INFO): Handler Thread ID is 5221
2019-07-16T22:57:36.774Z,1563317856.774 [controlThread](DEBUG): Initializing ControlThread
2019-07-16T22:57:36.779Z,1563317856.779 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-07-16T22:57:36.779Z,1563317856.779 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-07-16T22:57:36.780Z,1563317856.780 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-07-16T22:57:36.781Z,1563317856.781 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-07-16T22:57:36.781Z,1563317856.781 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-07-16T22:57:36.781Z,1563317856.781 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-07-16T22:57:36.782Z,1563317856.782 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-07-16T22:57:36.782Z,1563317856.782 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-07-16T22:57:36.783Z,1563317856.783 [SBIT](INFO): Initialize SBIT Component.
2019-07-16T22:57:36.783Z,1563317856.783 [SBIT](IMPORTANT): git: 2019-07-16A
2019-07-16T22:57:36.784Z,1563317856.784 [SBIT](INFO): git hash: 5b3bfb91c2b42ce18c1af41de1400573ebf5a9f6
2019-07-16T22:57:36.784Z,1563317856.784 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-07-16T22:57:36.785Z,1563317856.785 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-07-16T22:57:36.786Z,1563317856.786 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-07-16T22:57:36.787Z,1563317856.787 [IBIT](INFO): Initialize IBIT Component.
2019-07-16T22:57:36.788Z,1563317856.788 [CBIT](DEBUG): Initialize CBIT Component.
2019-07-16T22:57:36.789Z,1563317856.789 [logger ThreadHandler](INFO): Handler Thread ID is 5222
2019-07-16T22:57:36.800Z,1563317856.800 [CBIT](DEBUG): Initialized mux pins.
2019-07-16T22:57:36.800Z,1563317856.800 [CBIT](DEBUG): Initializing the watchdog timer.
2019-07-16T22:57:36.808Z,1563317856.808 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5223
2019-07-16T22:57:36.809Z,1563317856.809 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-07-16T22:57:36.820Z,1563317856.820 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5224
2019-07-16T22:57:36.824Z,1563317856.824 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-07-16T22:57:36.824Z,1563317856.824 [CBIT](DEBUG): Initializing heartbeat.
2019-07-16T22:57:36.832Z,1563317856.832 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5225
2019-07-16T22:57:36.833Z,1563317856.833 [CTD_NeilBrown](INFO): Powering down
2019-07-16T22:57:36.868Z,1563317856.868 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 5226
2019-07-16T22:57:36.869Z,1563317856.869 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-07-16T22:57:36.890Z,1563317856.890 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5227
2019-07-16T22:57:36.893Z,1563317856.893 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-07-16T22:57:36.893Z,1563317856.893 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-07-16T22:57:36.893Z,1563317856.893 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-07-16T22:57:36.893Z,1563317856.893 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-07-16T22:57:36.894Z,1563317856.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-07-16T22:57:36.894Z,1563317856.894 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-07-16T22:57:36.894Z,1563317856.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-07-16T22:57:36.894Z,1563317856.894 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-07-16T22:57:36.894Z,1563317856.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-07-16T22:57:36.895Z,1563317856.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-07-16T22:57:36.895Z,1563317856.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-07-16T22:57:36.895Z,1563317856.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-07-16T22:57:36.895Z,1563317856.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-07-16T22:57:36.895Z,1563317856.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-07-16T22:57:36.896Z,1563317856.896 [CBIT](DEBUG): Deactivating GF circuits.
2019-07-16T22:57:36.896Z,1563317856.896 [CBIT](DEBUG): Deactivating emergency mode.
2019-07-16T22:57:36.896Z,1563317856.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-07-16T22:57:36.896Z,1563317856.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-07-16T22:57:36.932Z,1563317856.932 [CBIT](DEBUG): Backplane powered.
2019-07-16T22:57:36.932Z,1563317856.932 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-07-16T22:57:36.934Z,1563317856.934 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-07-16T22:57:36.934Z,1563317856.934 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-07-16T22:57:36.935Z,1563317856.935 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-07-16T22:57:36.936Z,1563317856.936 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-07-16T22:57:36.946Z,1563317856.946 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-16T22:57:36.965Z,1563317856.965 [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>


2019-07-16T22:57:36.966Z,1563317856.966 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-07-16T22:57:37.040Z,1563317857.040 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-07-16T22:57:37.041Z,1563317857.041 [Default:A.Wait](DEBUG): Construct Wait.
2019-07-16T22:57:37.043Z,1563317857.043 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-16T22:57:37.089Z,1563317857.089 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-07-16T22:57:37.092Z,1563317857.092 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-07-16T22:57:37.113Z,1563317857.113 [Default:E.Execute](DEBUG): Construct Execute.
2019-07-16T22:57:37.117Z,1563317857.117 [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>


2019-07-16T22:57:37.132Z,1563317857.132 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-16T22:57:37.145Z,1563317857.145 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-07-16T22:57:37.189Z,1563317857.189 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-07-16T22:57:37.190Z,1563317857.190 [DUSBL_Hydroid](INFO): Powering up
2019-07-16T22:57:37.190Z,1563317857.190 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-07-16T22:57:37.224Z,1563317857.224 [Radio_Surface](INFO): Powering up
2019-07-16T22:57:37.265Z,1563317857.265 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-07-16T22:57:37.275Z,1563317857.275 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-07-16T22:57:37.284Z,1563317857.284 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-07-16T22:57:37.285Z,1563317857.285 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-07-16T22:57:37.292Z,1563317857.292 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-07-16T22:57:37.293Z,1563317857.293 [MassServo](DEBUG): Initializing EZServoServo.
2019-07-16T22:57:37.308Z,1563317857.308 [MassServo](DEBUG): Initializing MassServo.
2019-07-16T22:57:37.309Z,1563317857.309 [RudderServo](DEBUG): Initializing EZServoServo.
2019-07-16T22:57:37.316Z,1563317857.316 [RudderServo](DEBUG): Initializing RudderServo.
2019-07-16T22:57:37.317Z,1563317857.317 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-07-16T22:57:37.324Z,1563317857.324 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-07-16T22:57:37.659Z,1563317857.659 [Micromodem](INFO): Powering up
2019-07-16T22:57:37.660Z,1563317857.660 [Micromodem](DEBUG): Initializing Micromodem.
2019-07-16T22:57:42.462Z,1563317862.462 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-07-16T22:57:47.690Z,1563317867.690 [Micromodem](INFO): Nmea in: $CATMG,2019-07-16T22:57:46Z,RTC,RTC*74

2019-07-16T22:57:47.691Z,1563317867.691 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-07-16T22:57:46Z,RTC,RTC*74

2019-07-16T22:57:50.535Z,1563317870.535 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-07-16T22:57:53.741Z,1563317873.741 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-07-16T22:57:54.142Z,1563317874.142 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-07-16T22:57:54.142Z,1563317874.142 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-07-16T22:57:54.546Z,1563317874.546 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-07-16T22:57:54.546Z,1563317874.546 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-07-16T22:57:54.954Z,1563317874.954 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-07-16T22:57:54.954Z,1563317874.954 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-07-16T22:57:55.369Z,1563317875.369 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-07-16T22:57:55.385Z,1563317875.385 [Micromodem](INFO): Nmea in: $CACFG,BND,1*39

2019-07-16T22:57:55.385Z,1563317875.385 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-07-16T22:57:55.758Z,1563317875.758 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-07-16T22:57:55.758Z,1563317875.758 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-07-16T22:57:56.166Z,1563317876.166 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-07-16T22:57:56.166Z,1563317876.166 [Micromodem](INFO): Nmea out: $CCCLK,2019,07,16,22,57,56*4F

2019-07-16T22:57:57.378Z,1563317877.378 [Micromodem](INFO): Nmea in: $CACLK,2019,7,16,22,57,56*7D

2019-07-16T22:57:57.379Z,1563317877.379 [Micromodem](INFO): Nmea in: $CATMS,0,2019-07-16T22:57:57Z*7C

2019-07-16T22:57:57.381Z,1563317877.381 [Micromodem](INFO): Nmea in: $CATMG,2019-07-16T22:57:57Z,USER_CMD,RTC*35

2019-07-16T22:58:03.031Z,1563317883.031 [NAL9602](INFO): Powering up NAL9602
2019-07-16T22:58:05.502Z,1563317885.502 [SBIT](IMPORTANT): Beginning Startup BIT
2019-07-16T22:58:05.510Z,1563317885.510 [CBIT](IMPORTANT): Beginning ground fault scan
2019-07-16T22:58:14.074Z,1563317894.074 [NAL9602](INFO): NAL9602 initialized
2019-07-16T22:58:14.900Z,1563317894.900 [NAL9602](DEBUG): Fix Requested
2019-07-16T22:58:16.600Z,1563317896.600 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012741
CHAN A1 (24V): -0.028090
CHAN A2 (12V): -0.006988
CHAN A3 (5V): -0.002127
CHAN B0 (3.3V): 0.000220
CHAN B1 (3.15aV): 0.000373
CHAN B2 (3.15bV): 0.000248
CHAN B3 (GND): 0.002521
OPEN: 0.004997
Full Scale Calc: 4.765 mA, -1.589 mA
2019-07-16T22:58:58.909Z,1563317938.909 [SBIT](IMPORTANT): SBIT PASSED
2019-07-16T22:58:58.946Z,1563317938.946 [CommandLine](IMPORTANT): got command configSet list
2019-07-16T22:58:58.946Z,1563317938.946 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-07-16T22:58:58.947Z,1563317938.947 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=5 meter;

2019-07-16T22:58:59.241Z,1563317939.241 [MissionManager](IMPORTANT): Started mission Startup
2019-07-16T22:58:59.241Z,1563317939.241 [Startup] Running Loop=1
2019-07-16T22:58:59.242Z,1563317939.242 [Startup](DEBUG): Aggregate::initialize Startup
2019-07-16T22:58:59.242Z,1563317939.242 [Startup:A.GoToSurface] Running Loop=1
2019-07-16T22:58:59.242Z,1563317939.242 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-16T22:58:59.242Z,1563317939.242 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-16T22:58:59.243Z,1563317939.243 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-16T22:58:59.243Z,1563317939.243 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-16T22:58:59.244Z,1563317939.244 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-16T22:58:59.244Z,1563317939.244 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-16T22:58:59.245Z,1563317939.245 [Startup:StartupSatComms] Running Loop=1
2019-07-16T22:58:59.246Z,1563317939.246 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-07-16T22:58:59.246Z,1563317939.246 [Startup:StartupSatComms:A] Running Loop=1
2019-07-16T22:58:59.653Z,1563317939.653 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-07-16T22:59:59.421Z,1563317999.421 [Startup:StartupSatComms:A](INFO): Timed out from 2019-07-16T22:58:59.2Z
2019-07-16T22:59:59.421Z,1563317999.421 [Startup:StartupSatComms:A] Stopped
2019-07-16T22:59:59.421Z,1563317999.421 [Startup:StartupSatComms:B] Running Loop=1
2019-07-16T22:59:59.851Z,1563317999.851 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-16T23:00:03.340Z,1563318003.340 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.012861
2019-07-16T23:00:08.576Z,1563318008.576 [DataOverHttps](INFO): Sending 599 bytes from file Logs/20190716T225726/Express0001.lzma
2019-07-16T23:00:09.381Z,1563318009.381 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0001.lzma.bak
2019-07-16T23:00:09.382Z,1563318009.382 [DataOverHttps](INFO): SBD MOMSN=11448772
2019-07-16T23:00:09.944Z,1563318009.944 [Startup:StartupSatComms:B] Stopped
2019-07-16T23:00:09.945Z,1563318009.945 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-07-16T23:00:09.945Z,1563318009.945 [Startup:StartupSatComms] Stopped
2019-07-16T23:00:09.945Z,1563318009.945 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-07-16T23:00:09.945Z,1563318009.945 [Startup](INFO): Completed Startup
2019-07-16T23:00:09.946Z,1563318009.946 [MissionManager](INFO): Startup is completed.
2019-07-16T23:00:09.946Z,1563318009.946 [MissionManager](INFO): Uninitializing Mission Startup
2019-07-16T23:00:09.946Z,1563318009.946 [Startup] Stopped
2019-07-16T23:00:09.946Z,1563318009.946 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-07-16T23:00:09.946Z,1563318009.946 [Startup:A.GoToSurface] Stopped
2019-07-16T23:00:09.946Z,1563318009.946 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-16T23:00:10.367Z,1563318010.367 [MissionManager](IMPORTANT): Started mission Default
2019-07-16T23:00:10.372Z,1563318010.372 [Default] Running Loop=1
2019-07-16T23:00:10.372Z,1563318010.372 [Default](DEBUG): Aggregate::initialize Default
2019-07-16T23:00:10.372Z,1563318010.372 [Default:B.GoToSurface] Running Loop=1
2019-07-16T23:00:10.372Z,1563318010.372 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-16T23:00:10.372Z,1563318010.372 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-16T23:00:10.373Z,1563318010.373 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-16T23:00:10.373Z,1563318010.373 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-16T23:00:10.373Z,1563318010.373 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-16T23:00:10.373Z,1563318010.373 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-16T23:00:10.374Z,1563318010.374 [Default:A.Wait] Running Loop=1
2019-07-16T23:00:10.374Z,1563318010.374 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:00:23.701Z,1563318023.701 [Default:A.Wait](INFO): Done Waiting.
2019-07-16T23:00:23.701Z,1563318023.701 [Default:A.Wait] Stopped
2019-07-16T23:00:23.701Z,1563318023.701 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:00:24.089Z,1563318024.089 [Default:CheckIn] Running Loop=1
2019-07-16T23:00:24.089Z,1563318024.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:00:24.089Z,1563318024.089 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:00:24.632Z,1563318024.632 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-07-16T23:00:37.028Z,1563318037.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-07-16T23:00:37.028Z,1563318037.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:00:37.119Z,1563318037.119 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:00:37.530Z,1563318037.530 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:00:37.530Z,1563318037.530 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-07-16T23:02:10.733Z,1563318130.733 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-16T23:02:10.733Z,1563318130.733 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-07-16T23:02:36.709Z,1563318156.709 [CommandLine](IMPORTANT): got command burn on
2019-07-16T23:02:36.709Z,1563318156.709 [CommandLine](IMPORTANT): Activating dropweight wire
2019-07-16T23:02:41.437Z,1563318161.437 [CommandLine](IMPORTANT): got command burn off
2019-07-16T23:02:41.437Z,1563318161.437 [CommandLine](IMPORTANT): Deactivating dropweight wire
2019-07-16T23:02:53.205Z,1563318173.205 [CommandLine](IMPORTANT): got command failComponent
2019-07-16T23:02:53.206Z,1563318173.206 [CommandLine](IMPORTANT): Failed components:
2019-07-16T23:02:53.206Z,1563318173.206 [CommandLine](IMPORTANT): No failed Components.
2019-07-16T23:03:17.374Z,1563318197.374 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-16T23:03:18.196Z,1563318198.196 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:21.832Z,1563318201.832 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:25.060Z,1563318205.060 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:27.896Z,1563318207.896 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:30.723Z,1563318210.723 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:32.736Z,1563318212.736 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:34.760Z,1563318214.760 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:37.988Z,1563318217.988 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:38.020Z,1563318218.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-07-16T23:03:38.020Z,1563318218.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:03:38.106Z,1563318218.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:03:38.507Z,1563318218.507 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:03:38.508Z,1563318218.508 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-07-16T23:03:40.820Z,1563318220.820 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:44.064Z,1563318224.064 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:46.880Z,1563318226.880 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:50.112Z,1563318230.112 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:52.132Z,1563318232.132 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:54.148Z,1563318234.148 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:03:57.384Z,1563318237.384 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:00.212Z,1563318240.212 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:03.444Z,1563318243.444 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:06.272Z,1563318246.272 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:09.100Z,1563318249.100 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:11.124Z,1563318251.124 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:13.544Z,1563318253.544 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:16.376Z,1563318256.376 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:19.616Z,1563318259.616 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:22.432Z,1563318262.432 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:25.260Z,1563318265.260 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:28.088Z,1563318268.088 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:30.108Z,1563318270.108 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:32.128Z,1563318272.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:35.376Z,1563318275.376 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:38.184Z,1563318278.184 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:41.424Z,1563318281.424 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:44.248Z,1563318284.248 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:47.484Z,1563318287.484 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:50.312Z,1563318290.312 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:53.132Z,1563318293.132 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:55.567Z,1563318295.567 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:04:58.388Z,1563318298.388 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:01.620Z,1563318301.620 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:04.444Z,1563318304.444 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:07.276Z,1563318307.276 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:10.100Z,1563318310.100 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:12.120Z,1563318312.120 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:15.372Z,1563318315.372 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:18.180Z,1563318318.180 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:21.416Z,1563318321.416 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:24.244Z,1563318324.244 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:24.269Z,1563318324.269 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-16T23:00:24.1Z
2019-07-16T23:05:24.269Z,1563318324.269 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:05:24.269Z,1563318324.269 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:05:24.679Z,1563318324.679 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-16T23:05:27.476Z,1563318327.476 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:27.895Z,1563318327.895 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-16T23:05:27.895Z,1563318327.895 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-07-16T23:05:29.795Z,1563318329.795 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190716T225726/Courier0004.lzma
2019-07-16T23:05:30.316Z,1563318330.316 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:30.601Z,1563318330.601 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0004.lzma.bak
2019-07-16T23:05:30.601Z,1563318330.601 [DataOverHttps](INFO): SBD MOMSN=11448787
2019-07-16T23:05:33.128Z,1563318333.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:36.360Z,1563318336.360 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:39.192Z,1563318339.192 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:42.080Z,1563318342.080 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20190716T225726/Express0005.lzma
2019-07-16T23:05:42.420Z,1563318342.420 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:42.886Z,1563318342.886 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0005.lzma.bak
2019-07-16T23:05:42.886Z,1563318342.886 [DataOverHttps](INFO): SBD MOMSN=11448789
2019-07-16T23:05:43.654Z,1563318343.654 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:05:43.654Z,1563318343.654 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:05:43.654Z,1563318343.654 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:05:45.252Z,1563318345.252 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:48.484Z,1563318348.484 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:51.316Z,1563318351.316 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:54.140Z,1563318354.140 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:05:54.163Z,1563318354.163 [BPC1](ERROR): Battery stick #19 (s/n: 0136) reported OVER_TEMP_ALARM. Status code: 0x1717.
2019-07-16T23:05:54.164Z,1563318354.164 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2019-07-16T23:05:54.167Z,1563318354.167 [BPC1](INFO): Received data from all battery sticks.
2019-07-16T23:05:57.372Z,1563318357.372 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:00.200Z,1563318360.200 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:03.428Z,1563318363.428 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:06.280Z,1563318366.280 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:09.092Z,1563318369.092 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:11.108Z,1563318371.108 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:13.532Z,1563318373.532 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:16.360Z,1563318376.360 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:19.588Z,1563318379.588 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:22.436Z,1563318382.436 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:25.264Z,1563318385.264 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:28.092Z,1563318388.092 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:30.112Z,1563318390.112 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:32.136Z,1563318392.136 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:35.376Z,1563318395.376 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:38.192Z,1563318398.192 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:39.044Z,1563318399.044 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-07-16T23:06:39.044Z,1563318399.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:06:39.120Z,1563318399.120 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:06:39.508Z,1563318399.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:06:39.508Z,1563318399.508 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-07-16T23:06:41.428Z,1563318401.428 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:44.256Z,1563318404.256 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:47.080Z,1563318407.080 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:48.721Z,1563318408.721 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-07-16T23:06:48.721Z,1563318408.721 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,       0.00,537.99
2019-07-16T23:06:49.500Z,1563318409.500 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:52.346Z,1563318412.346 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:55.560Z,1563318415.560 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:06:58.396Z,1563318418.396 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:01.624Z,1563318421.624 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:04.452Z,1563318424.452 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:07.284Z,1563318427.284 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:10.104Z,1563318430.104 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:12.128Z,1563318432.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:15.373Z,1563318435.373 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:18.188Z,1563318438.188 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:21.420Z,1563318441.420 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:24.248Z,1563318444.248 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:27.484Z,1563318447.484 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:27.528Z,1563318447.528 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-16T23:07:27.528Z,1563318447.528 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32BE,-32768,-32768,-32768,V
2019-07-16T23:07:30.316Z,1563318450.316 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:33.132Z,1563318453.132 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:36.376Z,1563318456.376 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:39.192Z,1563318459.192 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:42.428Z,1563318462.428 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:45.256Z,1563318465.256 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:48.084Z,1563318468.084 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:50.100Z,1563318470.100 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:53.352Z,1563318473.352 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:56.160Z,1563318476.160 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:07:59.396Z,1563318479.396 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:08:02.224Z,1563318482.224 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:08:05.456Z,1563318485.456 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:08:08.280Z,1563318488.280 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:08:11.108Z,1563318491.108 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:08:13.536Z,1563318493.536 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:08:16.354Z,1563318496.354 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-16T23:08:16.354Z,1563318496.354 [NAL9602] Data Fault, FailCount= 1
2019-07-16T23:08:16.354Z,1563318496.354 [NAL9602](ERROR): Data Fault
2019-07-16T23:08:16.503Z,1563318496.503 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-16T23:08:16.754Z,1563318496.754 [NAL9602](INFO): Powering down
2019-07-16T23:08:17.697Z,1563318497.697 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-16T23:08:17.697Z,1563318497.697 [NAL9602] No Fault, FailCount= 1
2019-07-16T23:08:31.721Z,1563318511.721 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-16T23:08:31.721Z,1563318511.721 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-07-16T23:08:47.054Z,1563318527.054 [NAL9602](INFO): Powering up NAL9602
2019-07-16T23:08:57.958Z,1563318537.958 [NAL9602](INFO): NAL9602 initialized
2019-07-16T23:08:58.780Z,1563318538.780 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:02.016Z,1563318542.016 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:04.840Z,1563318544.840 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:08.080Z,1563318548.080 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:10.908Z,1563318550.908 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:13.738Z,1563318553.738 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:15.756Z,1563318555.756 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:17.772Z,1563318557.772 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:21.004Z,1563318561.004 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:23.836Z,1563318563.836 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:27.068Z,1563318567.068 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:29.892Z,1563318569.892 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:33.124Z,1563318573.124 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:35.960Z,1563318575.960 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:38.780Z,1563318578.780 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:40.028Z,1563318580.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-07-16T23:09:40.028Z,1563318580.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:09:40.104Z,1563318580.104 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:09:40.500Z,1563318580.500 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:09:40.500Z,1563318580.500 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-07-16T23:09:42.024Z,1563318582.024 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:44.849Z,1563318584.849 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:48.076Z,1563318588.076 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:50.904Z,1563318590.904 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:54.132Z,1563318594.132 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:56.968Z,1563318596.968 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:09:59.788Z,1563318599.788 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:03.028Z,1563318603.028 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:05.848Z,1563318605.848 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:09.084Z,1563318609.084 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:11.912Z,1563318611.912 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:14.754Z,1563318614.754 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:16.764Z,1563318616.764 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:18.784Z,1563318618.784 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:22.008Z,1563318622.008 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:24.844Z,1563318624.844 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:28.068Z,1563318628.068 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:30.900Z,1563318630.900 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:34.128Z,1563318634.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:36.964Z,1563318636.964 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:39.788Z,1563318639.788 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:43.020Z,1563318643.020 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:44.251Z,1563318644.251 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:10:44.251Z,1563318644.251 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:10:44.252Z,1563318644.252 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:10:44.252Z,1563318644.252 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:10:44.677Z,1563318644.677 [Default:CheckIn:D] Stopped
2019-07-16T23:10:44.677Z,1563318644.677 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:10:45.053Z,1563318645.053 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.571819 min 
2019-07-16T23:10:45.053Z,1563318645.053 [Default:CheckIn:E] Stopped
2019-07-16T23:10:45.053Z,1563318645.053 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:10:45.053Z,1563318645.053 [Default:CheckIn] Stopped
2019-07-16T23:10:45.053Z,1563318645.053 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:10:45.053Z,1563318645.053 [Default:CheckIn](INFO): Running loop #2
2019-07-16T23:10:45.054Z,1563318645.054 [Default:CheckIn] Running Loop=2
2019-07-16T23:10:45.054Z,1563318645.054 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:10:45.054Z,1563318645.054 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:10:45.852Z,1563318645.852 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:49.080Z,1563318649.080 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:51.908Z,1563318651.908 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:54.736Z,1563318654.736 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:56.760Z,1563318656.760 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:10:58.772Z,1563318658.772 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:02.004Z,1563318662.004 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:04.836Z,1563318664.836 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:08.068Z,1563318668.068 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:10.896Z,1563318670.896 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:14.128Z,1563318674.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:16.956Z,1563318676.956 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:19.780Z,1563318679.780 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:23.016Z,1563318683.016 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:25.844Z,1563318685.844 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:29.080Z,1563318689.080 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:31.900Z,1563318691.900 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:34.732Z,1563318694.732 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:36.752Z,1563318696.752 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:38.772Z,1563318698.772 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:42.004Z,1563318702.004 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:44.832Z,1563318704.832 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:48.060Z,1563318708.060 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:50.896Z,1563318710.896 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:54.124Z,1563318714.124 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:56.952Z,1563318716.952 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:11:59.783Z,1563318719.783 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:03.012Z,1563318723.012 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:05.848Z,1563318725.848 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:09.076Z,1563318729.076 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:11.896Z,1563318731.896 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:15.132Z,1563318735.132 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:17.956Z,1563318737.956 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:20.788Z,1563318740.788 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:24.016Z,1563318744.016 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:26.848Z,1563318746.848 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:30.076Z,1563318750.076 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:32.910Z,1563318752.910 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:35.736Z,1563318755.736 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:37.752Z,1563318757.752 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:39.783Z,1563318759.783 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:40.997Z,1563318760.997 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-07-16T23:12:40.998Z,1563318760.998 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:12:41.008Z,1563318761.008 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:12:41.428Z,1563318761.428 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:12:41.428Z,1563318761.428 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-07-16T23:12:43.008Z,1563318763.008 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:45.832Z,1563318765.832 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:49.068Z,1563318769.068 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:51.892Z,1563318771.892 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:55.124Z,1563318775.124 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:12:57.952Z,1563318777.952 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:00.780Z,1563318780.780 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:04.012Z,1563318784.012 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:06.844Z,1563318786.844 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:10.072Z,1563318790.072 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:12.904Z,1563318792.904 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:15.733Z,1563318795.733 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:17.756Z,1563318797.756 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:19.768Z,1563318799.768 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:23.004Z,1563318803.004 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:25.836Z,1563318805.836 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:29.068Z,1563318809.068 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:31.892Z,1563318811.892 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:35.120Z,1563318815.120 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:37.948Z,1563318817.948 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:40.776Z,1563318820.776 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:44.008Z,1563318824.008 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:46.840Z,1563318826.840 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:50.068Z,1563318830.068 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:52.908Z,1563318832.908 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:56.140Z,1563318836.140 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:13:58.956Z,1563318838.956 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:01.788Z,1563318841.788 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:05.016Z,1563318845.016 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:07.848Z,1563318847.848 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:11.076Z,1563318851.076 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:13.908Z,1563318853.908 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:17.140Z,1563318857.140 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:19.964Z,1563318859.964 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:22.792Z,1563318862.792 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:26.028Z,1563318866.028 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:28.856Z,1563318868.856 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:32.088Z,1563318872.088 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:34.912Z,1563318874.912 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:37.748Z,1563318877.748 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:39.760Z,1563318879.760 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:41.780Z,1563318881.780 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:45.012Z,1563318885.012 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:47.840Z,1563318887.840 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:51.076Z,1563318891.076 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:53.904Z,1563318893.904 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:56.736Z,1563318896.736 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:14:58.752Z,1563318898.752 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:00.772Z,1563318900.772 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:02.788Z,1563318902.788 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:06.032Z,1563318906.032 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:08.848Z,1563318908.848 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:12.084Z,1563318912.084 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:14.908Z,1563318914.908 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:17.740Z,1563318917.740 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:19.756Z,1563318919.756 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:21.776Z,1563318921.776 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:25.012Z,1563318925.012 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:27.836Z,1563318927.836 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:31.072Z,1563318931.072 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:33.904Z,1563318933.904 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:37.131Z,1563318937.131 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:39.976Z,1563318939.976 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:41.988Z,1563318941.988 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-07-16T23:15:41.988Z,1563318941.988 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:15:42.002Z,1563318942.002 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:15:42.422Z,1563318942.422 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:15:42.422Z,1563318942.422 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-07-16T23:15:42.784Z,1563318942.784 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:45.217Z,1563318945.217 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-16T23:10:45.1Z
2019-07-16T23:15:45.217Z,1563318945.217 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:15:45.217Z,1563318945.217 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:15:45.898Z,1563318945.898 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20190716T225726/Courier0007.lzma
2019-07-16T23:15:46.032Z,1563318946.032 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:46.701Z,1563318946.701 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0007.lzma.bak
2019-07-16T23:15:46.702Z,1563318946.702 [DataOverHttps](INFO): SBD MOMSN=11448807
2019-07-16T23:15:48.852Z,1563318948.852 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:52.088Z,1563318952.088 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:54.912Z,1563318954.912 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:57.740Z,1563318957.740 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:58.179Z,1563318958.179 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20190716T225726/Express0008.lzma
2019-07-16T23:15:58.985Z,1563318958.985 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0008.lzma.bak
2019-07-16T23:15:58.985Z,1563318958.985 [DataOverHttps](INFO): SBD MOMSN=11448809
2019-07-16T23:15:59.760Z,1563318959.760 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:15:59.790Z,1563318959.790 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:15:59.790Z,1563318959.790 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:15:59.791Z,1563318959.791 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:16:02.996Z,1563318962.996 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:05.820Z,1563318965.820 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:09.052Z,1563318969.052 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:11.880Z,1563318971.880 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:15.128Z,1563318975.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:17.942Z,1563318977.942 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:20.772Z,1563318980.772 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:22.788Z,1563318982.788 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:26.036Z,1563318986.036 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:28.848Z,1563318988.848 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:32.080Z,1563318992.080 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:34.908Z,1563318994.908 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:37.736Z,1563318997.736 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:39.756Z,1563318999.756 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:41.776Z,1563319001.776 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:45.008Z,1563319005.008 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:47.836Z,1563319007.836 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:51.072Z,1563319011.072 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:53.904Z,1563319013.904 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:57.136Z,1563319017.136 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:16:59.960Z,1563319019.960 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:02.784Z,1563319022.784 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:06.044Z,1563319026.044 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:08.844Z,1563319028.844 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:12.080Z,1563319032.080 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:14.904Z,1563319034.904 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:17.332Z,1563319037.332 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:20.160Z,1563319040.160 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:23.392Z,1563319043.392 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:26.216Z,1563319046.216 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:29.448Z,1563319049.448 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:30.926Z,1563319050.926 [CommandLine](IMPORTANT): got command failComponent
2019-07-16T23:17:30.926Z,1563319050.926 [CommandLine](IMPORTANT): Failed components:
2019-07-16T23:17:30.926Z,1563319050.926 [CommandLine](IMPORTANT): No failed Components.
2019-07-16T23:17:32.276Z,1563319052.276 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:35.508Z,1563319055.508 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:38.340Z,1563319058.340 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:41.164Z,1563319061.164 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:43.592Z,1563319063.592 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:46.440Z,1563319066.440 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:49.660Z,1563319069.660 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:52.484Z,1563319072.484 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:55.320Z,1563319075.320 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:17:58.140Z,1563319078.140 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:01.376Z,1563319081.376 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:04.208Z,1563319084.208 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:07.432Z,1563319087.432 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:10.264Z,1563319090.264 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:13.500Z,1563319093.500 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:16.320Z,1563319096.320 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:19.552Z,1563319099.552 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:22.384Z,1563319102.384 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:25.612Z,1563319105.612 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:28.440Z,1563319108.440 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:31.672Z,1563319111.672 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:34.500Z,1563319114.500 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:37.344Z,1563319117.344 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:40.164Z,1563319120.164 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:42.000Z,1563319123.000 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-07-16T23:18:42.000Z,1563319123.000 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:18:43.010Z,1563319123.010 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:18:43.388Z,1563319123.388 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:43.427Z,1563319123.427 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:18:43.427Z,1563319123.427 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-07-16T23:18:46.220Z,1563319126.220 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:49.448Z,1563319129.448 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:52.276Z,1563319132.276 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:55.508Z,1563319135.508 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:18:58.336Z,1563319138.336 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:19:01.558Z,1563319141.558 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-07-16T23:19:01.558Z,1563319141.558 [NAL9602] Data Fault, FailCount= 2
2019-07-16T23:19:01.558Z,1563319141.558 [NAL9602](ERROR): Data Fault
2019-07-16T23:19:01.597Z,1563319141.597 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-16T23:19:01.962Z,1563319141.962 [NAL9602](INFO): Powering down
2019-07-16T23:19:02.805Z,1563319142.805 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-16T23:19:02.805Z,1563319142.805 [NAL9602] No Fault, FailCount= 2
2019-07-16T23:19:32.258Z,1563319172.258 [NAL9602](INFO): Powering up NAL9602
2019-07-16T23:19:43.166Z,1563319183.166 [NAL9602](INFO): NAL9602 initialized
2019-07-16T23:19:43.988Z,1563319183.988 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:19:47.224Z,1563319187.224 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:19:49.652Z,1563319189.652 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:19:52.476Z,1563319192.476 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:19:53.706Z,1563319193.706 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-07-16T23:19:53.706Z,1563319193.706 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3768,V
2019-07-16T23:19:55.300Z,1563319195.300 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:19:58.128Z,1563319198.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:00.152Z,1563319200.152 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:03.383Z,1563319203.383 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:06.212Z,1563319206.212 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:09.440Z,1563319209.440 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:12.268Z,1563319212.268 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:15.500Z,1563319215.500 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:18.328Z,1563319218.328 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:21.156Z,1563319221.156 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:24.388Z,1563319224.388 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:27.224Z,1563319227.224 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:30.450Z,1563319230.450 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:33.284Z,1563319233.284 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:36.508Z,1563319236.508 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:39.340Z,1563319239.340 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:42.164Z,1563319242.164 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:45.408Z,1563319245.408 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:48.224Z,1563319248.224 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:51.460Z,1563319251.460 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:54.292Z,1563319254.292 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:20:57.528Z,1563319257.528 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:00.364Z,1563319260.364 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:00.395Z,1563319260.395 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:21:00.395Z,1563319260.395 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:21:00.396Z,1563319260.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:21:00.396Z,1563319260.396 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:21:00.781Z,1563319260.781 [Default:CheckIn:D] Stopped
2019-07-16T23:21:00.781Z,1563319260.781 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:21:01.205Z,1563319261.205 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.840222 min 
2019-07-16T23:21:01.205Z,1563319261.205 [Default:CheckIn:E] Stopped
2019-07-16T23:21:01.205Z,1563319261.205 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:21:01.205Z,1563319261.205 [Default:CheckIn] Stopped
2019-07-16T23:21:01.205Z,1563319261.205 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:21:01.205Z,1563319261.205 [Default:CheckIn](INFO): Running loop #3
2019-07-16T23:21:01.206Z,1563319261.206 [Default:CheckIn] Running Loop=3
2019-07-16T23:21:01.206Z,1563319261.206 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:21:01.206Z,1563319261.206 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:21:03.188Z,1563319263.188 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:06.424Z,1563319266.424 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:09.236Z,1563319269.236 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:12.464Z,1563319272.464 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:15.296Z,1563319275.296 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:18.524Z,1563319278.524 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:21.364Z,1563319281.364 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:24.184Z,1563319284.184 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:27.412Z,1563319287.412 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:30.244Z,1563319290.244 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:33.476Z,1563319293.476 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:36.300Z,1563319296.300 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:39.536Z,1563319299.536 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:42.364Z,1563319302.364 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:43.988Z,1563319303.988 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-07-16T23:21:43.988Z,1563319303.988 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-16T23:21:43.998Z,1563319303.998 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-16T23:21:44.412Z,1563319304.412 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-16T23:21:44.412Z,1563319304.412 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-07-16T23:21:45.188Z,1563319305.188 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:48.424Z,1563319308.424 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:51.252Z,1563319311.252 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:54.484Z,1563319314.484 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:21:57.308Z,1563319317.308 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:00.548Z,1563319320.548 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:03.368Z,1563319323.368 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:06.604Z,1563319326.604 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:09.428Z,1563319329.428 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:12.256Z,1563319332.256 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:15.500Z,1563319335.500 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:18.319Z,1563319338.319 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:21.556Z,1563319341.556 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:24.376Z,1563319344.376 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:27.612Z,1563319347.612 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:30.436Z,1563319350.436 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:33.264Z,1563319353.264 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:36.496Z,1563319356.496 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:39.324Z,1563319359.324 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:42.560Z,1563319362.560 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:45.392Z,1563319365.392 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:48.623Z,1563319368.623 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:51.452Z,1563319371.452 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:52.669Z,1563319372.669 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-07-16T23:22:54.272Z,1563319374.272 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:22:57.520Z,1563319377.520 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:00.360Z,1563319380.360 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:03.576Z,1563319383.576 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:06.416Z,1563319386.416 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:09.624Z,1563319389.624 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:12.455Z,1563319392.455 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:15.688Z,1563319395.688 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:18.516Z,1563319398.516 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:21.356Z,1563319401.356 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:24.572Z,1563319404.572 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:27.412Z,1563319407.412 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:30.632Z,1563319410.632 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:33.460Z,1563319413.460 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:23:33.860Z,1563319413.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232332.00,A,3648.13183,N,12147.22793,W,0.758,145.56,160719,,,A*71
2019-07-16T23:23:33.863Z,1563319413.863 [NAL9602](INFO): GPS fix at 20190716T232332: (36.802197, -121.787132)
2019-07-16T23:23:33.887Z,1563319413.887 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:23:33.887Z,1563319413.887 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:23:42.575Z,1563319422.575 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190716T225726/Courier0010.lzma
2019-07-16T23:23:43.381Z,1563319423.381 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0010.lzma.bak
2019-07-16T23:23:43.381Z,1563319423.381 [DataOverHttps](INFO): SBD MOMSN=11448823
2019-07-16T23:23:57.112Z,1563319437.112 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20190716T225726/Express0011.lzma
2019-07-16T23:23:57.917Z,1563319437.917 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0011.lzma.bak
2019-07-16T23:23:57.917Z,1563319437.917 [DataOverHttps](INFO): SBD MOMSN=11448826
2019-07-16T23:23:58.525Z,1563319438.525 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:23:58.525Z,1563319438.525 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:23:58.525Z,1563319438.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:24:06.183Z,1563319446.183 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-16T23:27:00.347Z,1563319620.347 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-16T23:27:00.347Z,1563319620.347 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00,

2019-07-16T23:28:59.105Z,1563319739.105 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:28:59.105Z,1563319739.105 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:28:59.105Z,1563319739.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:28:59.105Z,1563319739.105 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:28:59.519Z,1563319739.519 [Default:CheckIn:D] Stopped
2019-07-16T23:28:59.519Z,1563319739.519 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:28:59.924Z,1563319739.924 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.819183 min 
2019-07-16T23:28:59.924Z,1563319739.924 [Default:CheckIn:E] Stopped
2019-07-16T23:28:59.924Z,1563319739.924 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:28:59.924Z,1563319739.924 [Default:CheckIn] Stopped
2019-07-16T23:28:59.924Z,1563319739.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:28:59.925Z,1563319739.925 [Default:CheckIn](INFO): Running loop #4
2019-07-16T23:28:59.925Z,1563319739.925 [Default:CheckIn] Running Loop=4
2019-07-16T23:28:59.925Z,1563319739.925 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:28:59.925Z,1563319739.925 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:29:01.516Z,1563319741.516 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:29:01.909Z,1563319741.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232901.00,A,3648.15069,N,12147.22324,W,0.078,358.49,160719,,,A*75
2019-07-16T23:29:01.911Z,1563319741.911 [NAL9602](INFO): GPS fix at 20190716T232901: (36.802512, -121.787054)
2019-07-16T23:29:01.934Z,1563319741.934 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:29:01.934Z,1563319741.934 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:29:07.663Z,1563319747.663 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190716T225726/Courier0013.lzma
2019-07-16T23:29:08.469Z,1563319748.469 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0013.lzma.bak
2019-07-16T23:29:08.469Z,1563319748.469 [DataOverHttps](INFO): SBD MOMSN=11448833
2019-07-16T23:29:19.899Z,1563319759.899 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190716T225726/Express0014.lzma
2019-07-16T23:29:20.705Z,1563319760.705 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0014.lzma.bak
2019-07-16T23:29:20.705Z,1563319760.705 [DataOverHttps](INFO): SBD MOMSN=11448836
2019-07-16T23:29:21.388Z,1563319761.388 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:29:21.388Z,1563319761.388 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:29:21.388Z,1563319761.388 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:29:36.253Z,1563319776.253 [NAL9602](INFO): SBD MO Status=0, MOMSN=8134, MT Status=0, MTMSN=0
2019-07-16T23:29:36.253Z,1563319776.253 [NAL9602](INFO): No messages in MT queue
2019-07-16T23:30:06.954Z,1563319806.954 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-16T23:34:21.931Z,1563320061.931 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:34:21.931Z,1563320061.931 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:34:21.931Z,1563320061.931 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:34:21.931Z,1563320061.931 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:34:22.360Z,1563320062.360 [Default:CheckIn:D] Stopped
2019-07-16T23:34:22.360Z,1563320062.360 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:34:22.727Z,1563320062.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.199878 min 
2019-07-16T23:34:22.727Z,1563320062.727 [Default:CheckIn:E] Stopped
2019-07-16T23:34:22.727Z,1563320062.727 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:34:22.727Z,1563320062.727 [Default:CheckIn] Stopped
2019-07-16T23:34:22.727Z,1563320062.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:34:22.728Z,1563320062.728 [Default:CheckIn](INFO): Running loop #5
2019-07-16T23:34:22.728Z,1563320062.728 [Default:CheckIn] Running Loop=5
2019-07-16T23:34:22.728Z,1563320062.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:34:22.728Z,1563320062.728 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:34:24.340Z,1563320064.340 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:34:24.732Z,1563320064.732 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233424.00,A,3648.15112,N,12147.22481,W,0.136,358.49,160719,,,D*75
2019-07-16T23:34:24.734Z,1563320064.734 [NAL9602](INFO): GPS fix at 20190716T233424: (36.802519, -121.787080)
2019-07-16T23:34:24.763Z,1563320064.763 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:34:24.763Z,1563320064.763 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:34:30.419Z,1563320070.419 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190716T225726/Courier0016.lzma
2019-07-16T23:34:31.225Z,1563320071.225 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0016.lzma.bak
2019-07-16T23:34:31.225Z,1563320071.225 [DataOverHttps](INFO): SBD MOMSN=11448841
2019-07-16T23:34:42.667Z,1563320082.667 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190716T225726/Express0017.lzma
2019-07-16T23:34:43.473Z,1563320083.473 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0017.lzma.bak
2019-07-16T23:34:43.473Z,1563320083.473 [DataOverHttps](INFO): SBD MOMSN=11448844
2019-07-16T23:34:44.141Z,1563320084.141 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:34:44.141Z,1563320084.141 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:34:44.141Z,1563320084.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:34:47.755Z,1563320087.755 [NAL9602](INFO): SBD MO Status=2, MOMSN=8135, MT Status=2, MTMSN=0
2019-07-16T23:34:47.756Z,1563320087.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-16T23:35:40.679Z,1563320140.679 [NAL9602](INFO): SBD MO Status=0, MOMSN=8135, MT Status=0, MTMSN=0
2019-07-16T23:35:40.679Z,1563320140.679 [NAL9602](INFO): No messages in MT queue
2019-07-16T23:36:11.382Z,1563320171.382 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-16T23:39:39.050Z,1563320379.050 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-16T23:39:39.051Z,1563320379.051 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19071616494935,35.0, -0.1,   0.0,1448:RA,  0.00,   0.00,   0.00,   0.00,   0.00
2019-07-16T23:39:44.751Z,1563320384.751 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:39:44.751Z,1563320384.751 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:39:44.751Z,1563320384.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:39:44.751Z,1563320384.751 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:39:45.139Z,1563320385.139 [Default:CheckIn:D] Stopped
2019-07-16T23:39:45.139Z,1563320385.139 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:39:45.552Z,1563320385.552 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.579525 min 
2019-07-16T23:39:45.552Z,1563320385.552 [Default:CheckIn:E] Stopped
2019-07-16T23:39:45.552Z,1563320385.552 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:39:45.553Z,1563320385.553 [Default:CheckIn] Stopped
2019-07-16T23:39:45.553Z,1563320385.553 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:39:45.553Z,1563320385.553 [Default:CheckIn](INFO): Running loop #6
2019-07-16T23:39:45.553Z,1563320385.553 [Default:CheckIn] Running Loop=6
2019-07-16T23:39:45.553Z,1563320385.553 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:39:45.553Z,1563320385.553 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:39:47.136Z,1563320387.136 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:39:47.532Z,1563320387.532 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233946.00,A,3648.15016,N,12147.22388,W,0.097,191.24,160719,,,D*71
2019-07-16T23:39:47.534Z,1563320387.534 [NAL9602](INFO): GPS fix at 20190716T233946: (36.802503, -121.787065)
2019-07-16T23:39:47.568Z,1563320387.568 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:39:47.568Z,1563320387.568 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:39:53.286Z,1563320393.286 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190716T225726/Courier0019.lzma
2019-07-16T23:39:54.081Z,1563320394.081 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0019.lzma.bak
2019-07-16T23:39:54.081Z,1563320394.081 [DataOverHttps](INFO): SBD MOMSN=11448849
2019-07-16T23:40:04.895Z,1563320404.895 [NAL9602](INFO): SBD MO Status=0, MOMSN=8136, MT Status=0, MTMSN=0
2019-07-16T23:40:04.895Z,1563320404.895 [NAL9602](INFO): No messages in MT queue
2019-07-16T23:40:05.511Z,1563320405.511 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190716T225726/Express0020.lzma
2019-07-16T23:40:06.317Z,1563320406.317 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0020.lzma.bak
2019-07-16T23:40:06.318Z,1563320406.318 [DataOverHttps](INFO): SBD MOMSN=11448852
2019-07-16T23:40:06.957Z,1563320406.957 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:40:06.957Z,1563320406.957 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:40:06.958Z,1563320406.958 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:40:35.594Z,1563320435.594 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-16T23:41:02.681Z,1563320462.681 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-16T23:41:02.681Z,1563320462.681 [RDI_Pathfinder](ERROR): Failed to parse:
:TS6511335,35.0, -0.1,   0.0,1448.9,  0
2019-07-16T23:42:00.460Z,1563320520.460 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for BIT error
2019-07-16T23:42:00.460Z,1563320520.460 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19071616521035,35.0, -0.1,

2019-07-16T23:45:07.539Z,1563320707.539 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:45:07.539Z,1563320707.539 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:45:07.539Z,1563320707.539 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:45:07.539Z,1563320707.539 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:45:07.917Z,1563320707.917 [Default:CheckIn:D] Stopped
2019-07-16T23:45:07.917Z,1563320707.917 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:45:08.321Z,1563320708.321 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.959159 min 
2019-07-16T23:45:08.321Z,1563320708.321 [Default:CheckIn:E] Stopped
2019-07-16T23:45:08.322Z,1563320708.322 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:45:08.322Z,1563320708.322 [Default:CheckIn] Stopped
2019-07-16T23:45:08.322Z,1563320708.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:45:08.322Z,1563320708.322 [Default:CheckIn](INFO): Running loop #7
2019-07-16T23:45:08.322Z,1563320708.322 [Default:CheckIn] Running Loop=7
2019-07-16T23:45:08.322Z,1563320708.322 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:45:08.322Z,1563320708.322 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:45:09.928Z,1563320709.928 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:45:10.351Z,1563320710.351 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234509.00,A,3648.40105,N,12147.12458,W,6.026,0.77,160719,,,A*7D
2019-07-16T23:45:10.357Z,1563320710.357 [NAL9602](INFO): GPS fix at 20190716T234509: (36.806684, -121.785410)
2019-07-16T23:45:10.409Z,1563320710.409 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:45:10.409Z,1563320710.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:45:16.179Z,1563320716.179 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190716T225726/Courier0022.lzma
2019-07-16T23:45:16.985Z,1563320716.985 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0022.lzma.bak
2019-07-16T23:45:16.985Z,1563320716.985 [DataOverHttps](INFO): SBD MOMSN=11448861
2019-07-16T23:45:28.399Z,1563320728.399 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190716T225726/Express0023.lzma
2019-07-16T23:45:29.205Z,1563320729.205 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0023.lzma.bak
2019-07-16T23:45:29.205Z,1563320729.205 [DataOverHttps](INFO): SBD MOMSN=11448864
2019-07-16T23:45:29.748Z,1563320729.748 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:45:29.748Z,1563320729.748 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:45:29.748Z,1563320729.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:45:57.991Z,1563320757.991 [NAL9602](INFO): SBD MO Status=2, MOMSN=8137, MT Status=2, MTMSN=0
2019-07-16T23:45:57.992Z,1563320757.992 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-16T23:46:07.299Z,1563320767.299 [NAL9602](INFO): SBD MO Status=0, MOMSN=8137, MT Status=0, MTMSN=0
2019-07-16T23:46:07.299Z,1563320767.299 [NAL9602](INFO): No messages in MT queue
2019-07-16T23:46:37.982Z,1563320797.982 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-16T23:50:17.085Z,1563321017.085 [DataOverHttps](IMPORTANT): SBD MTMSN=20190716T235016
2019-07-16T23:50:22.368Z,1563321022.368 [DataOverHttps](INFO): Received command:Ibit
2019-07-16T23:50:22.400Z,1563321022.400 [CommandLine](IMPORTANT): got command ibit
2019-07-16T23:50:22.645Z,1563321022.645 [IBIT](IMPORTANT): Beginning Initiated BIT
2019-07-16T23:50:22.645Z,1563321022.645 [IBIT](IMPORTANT): Beginning control surface checks.
2019-07-16T23:50:22.648Z,1563321022.648 [CBIT](IMPORTANT): Beginning ground fault scan
2019-07-16T23:50:23.832Z,1563321023.832 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:50:24.227Z,1563321024.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235023.00,A,3648.36458,N,12147.89824,W,10.205,269.20,160719,,,A*43
2019-07-16T23:50:24.230Z,1563321024.230 [NAL9602](INFO): GPS fix at 20190716T235023: (36.806076, -121.798304)
2019-07-16T23:50:33.566Z,1563321033.566 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012098
CHAN A1 (24V): -0.027713
CHAN A2 (12V): -0.007294
CHAN A3 (5V): -0.002080
CHAN B0 (3.3V): -0.000155
CHAN B1 (3.15aV): 0.000049
CHAN B2 (3.15bV): 0.000205
CHAN B3 (GND): 0.002210
OPEN: 0.004847
Full Scale Calc: 4.765 mA, -1.589 mA
2019-07-16T23:50:46.037Z,1563321046.037 [NAL9602](INFO): SBD MO Status=0, MOMSN=8138, MT Status=0, MTMSN=0
2019-07-16T23:50:46.037Z,1563321046.037 [NAL9602](INFO): No messages in MT queue
2019-07-16T23:50:46.856Z,1563321046.856 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:50:47.259Z,1563321047.259 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235046.00,A,3648.36159,N,12147.98810,W,14.307,268.30,160719,,,A*44
2019-07-16T23:50:47.266Z,1563321047.266 [NAL9602](INFO): GPS fix at 20190716T235046: (36.806027, -121.799802)
2019-07-16T23:50:49.692Z,1563321049.692 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:50:50.084Z,1563321050.084 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235049.00,A,3648.36208,N,12148.00362,W,15.706,271.11,160719,,,A*43
2019-07-16T23:50:50.086Z,1563321050.086 [NAL9602](INFO): GPS fix at 20190716T235049: (36.806035, -121.800060)
2019-07-16T23:50:52.525Z,1563321052.525 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:50:52.905Z,1563321052.905 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235052.00,A,3648.36210,N,12148.01786,W,13.121,270.34,160719,,,A*4C
2019-07-16T23:50:52.907Z,1563321052.907 [NAL9602](INFO): GPS fix at 20190716T235052: (36.806035, -121.800298)
2019-07-16T23:50:55.371Z,1563321055.371 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:50:55.747Z,1563321055.747 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235055.00,A,3648.36172,N,12148.03140,W,12.907,263.98,160719,,,A*4B
2019-07-16T23:50:55.750Z,1563321055.750 [NAL9602](INFO): GPS fix at 20190716T235055: (36.806029, -121.800523)
2019-07-16T23:50:58.580Z,1563321058.580 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:50:58.966Z,1563321058.966 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235058.00,A,3648.36098,N,12148.04446,W,11.760,266.25,160719,,,A*48
2019-07-16T23:50:58.968Z,1563321058.968 [NAL9602](INFO): GPS fix at 20190716T235058: (36.806016, -121.800741)
2019-07-16T23:51:01.400Z,1563321061.400 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:51:01.796Z,1563321061.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235101.00,A,3648.36041,N,12148.05730,W,13.024,266.60,160719,,,A*46
2019-07-16T23:51:01.798Z,1563321061.798 [NAL9602](INFO): GPS fix at 20190716T235101: (36.806007, -121.800955)
2019-07-16T23:51:04.640Z,1563321064.640 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:51:05.025Z,1563321065.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235104.00,A,3648.35974,N,12148.06867,W,8.339,261.85,160719,,,A*78
2019-07-16T23:51:05.027Z,1563321065.027 [NAL9602](INFO): GPS fix at 20190716T235104: (36.805996, -121.801145)
2019-07-16T23:51:07.476Z,1563321067.476 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:51:07.859Z,1563321067.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235107.00,A,3648.35954,N,12148.07985,W,11.585,272.78,160719,,,A*4C
2019-07-16T23:51:07.862Z,1563321067.862 [NAL9602](INFO): GPS fix at 20190716T235107: (36.805992, -121.801331)
2019-07-16T23:51:09.125Z,1563321069.125 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.805992 Longitude: -121.801331
2019-07-16T23:51:09.508Z,1563321069.508 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 0.000000
2019-07-16T23:51:09.509Z,1563321069.509 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2019-07-16T23:51:09.509Z,1563321069.509 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2019-07-16T23:51:09.930Z,1563321069.930 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2019-07-16T23:51:09.930Z,1563321069.930 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2019-07-16T23:51:09.930Z,1563321069.930 [IBIT](IMPORTANT): Pressure:9.575514 PSI
2019-07-16T23:51:09.931Z,1563321069.931 [IBIT](IMPORTANT): Humidity:10.596996 %
2019-07-16T23:51:10.350Z,1563321070.350 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2019-07-16T23:51:10.350Z,1563321070.350 [IBIT](IMPORTANT): buoyancyNeutral: 410.000000 cc
2019-07-16T23:51:10.350Z,1563321070.350 [IBIT](IMPORTANT): massDefault: 0.200000 cm
2019-07-16T23:51:10.351Z,1563321070.351 [IBIT](IMPORTANT): stopDepth: 215.000000 m
2019-07-16T23:51:10.351Z,1563321070.351 [IBIT](IMPORTANT): abortDepth: 255.000000 m
2019-07-16T23:51:10.351Z,1563321070.351 [IBIT](IMPORTANT): IBIT PASSED
2019-07-16T23:51:10.743Z,1563321070.743 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:51:10.743Z,1563321070.743 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:51:10.743Z,1563321070.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:51:10.743Z,1563321070.743 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:51:11.112Z,1563321071.112 [Default:CheckIn:D] Stopped
2019-07-16T23:51:11.112Z,1563321071.112 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:51:11.520Z,1563321071.520 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.012415 min 
2019-07-16T23:51:11.520Z,1563321071.520 [Default:CheckIn:E] Stopped
2019-07-16T23:51:11.520Z,1563321071.520 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:51:11.521Z,1563321071.521 [Default:CheckIn] Stopped
2019-07-16T23:51:11.521Z,1563321071.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:51:11.521Z,1563321071.521 [Default:CheckIn](INFO): Running loop #8
2019-07-16T23:51:11.521Z,1563321071.521 [Default:CheckIn] Running Loop=8
2019-07-16T23:51:11.521Z,1563321071.521 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:51:11.521Z,1563321071.521 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:51:13.128Z,1563321073.128 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:51:13.516Z,1563321073.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235112.00,A,3648.36055,N,12148.10172,W,13.587,269.26,160719,,,A*44
2019-07-16T23:51:13.518Z,1563321073.518 [NAL9602](INFO): GPS fix at 20190716T235112: (36.806009, -121.801695)
2019-07-16T23:51:13.570Z,1563321073.570 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:51:13.570Z,1563321073.570 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:51:19.359Z,1563321079.359 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20190716T225726/Courier0025.lzma
2019-07-16T23:51:20.165Z,1563321080.165 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0025.lzma.bak
2019-07-16T23:51:20.165Z,1563321080.165 [DataOverHttps](INFO): SBD MOMSN=11448872
2019-07-16T23:51:31.565Z,1563321091.565 [DataOverHttps](INFO): Sending 759 bytes from file Logs/20190716T225726/Express0026.lzma
2019-07-16T23:51:32.369Z,1563321092.369 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0026.lzma.bak
2019-07-16T23:51:32.369Z,1563321092.369 [DataOverHttps](INFO): SBD MOMSN=11448875
2019-07-16T23:51:43.787Z,1563321103.787 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20190716T225726/Express0029.lzma
2019-07-16T23:51:44.593Z,1563321104.593 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0029.lzma.bak
2019-07-16T23:51:44.593Z,1563321104.593 [DataOverHttps](INFO): SBD MOMSN=11448898
2019-07-16T23:51:45.054Z,1563321105.054 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:51:45.055Z,1563321105.055 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:51:45.055Z,1563321105.055 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:51:45.423Z,1563321105.423 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-16T23:52:36.393Z,1563321156.393 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-07-16T23:52:36.393Z,1563321156.393 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,     ,   0.00,3285.53
2019-07-16T23:53:47.870Z,1563321227.870 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-16T23:53:47.871Z,1563321227.871 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-07-16T23:56:27.501Z,1563321387.501 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,        +0.00,        +0.00,   0.00,3516.47
2019-07-16T23:56:45.642Z,1563321405.642 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-16T23:56:45.642Z,1563321405.642 [Default:CheckIn:C.Wait] Stopped
2019-07-16T23:56:45.643Z,1563321405.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-16T23:56:45.643Z,1563321405.643 [Default:CheckIn:D] Running Loop=1
2019-07-16T23:56:46.032Z,1563321406.032 [Default:CheckIn:D] Stopped
2019-07-16T23:56:46.032Z,1563321406.032 [Default:CheckIn:E] Running Loop=1
2019-07-16T23:56:46.447Z,1563321406.447 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.594413 min 
2019-07-16T23:56:46.447Z,1563321406.447 [Default:CheckIn:E] Stopped
2019-07-16T23:56:46.448Z,1563321406.448 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-16T23:56:46.448Z,1563321406.448 [Default:CheckIn] Stopped
2019-07-16T23:56:46.448Z,1563321406.448 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-16T23:56:46.448Z,1563321406.448 [Default:CheckIn](INFO): Running loop #9
2019-07-16T23:56:46.448Z,1563321406.448 [Default:CheckIn] Running Loop=9
2019-07-16T23:56:46.449Z,1563321406.449 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-16T23:56:46.449Z,1563321406.449 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-16T23:56:48.044Z,1563321408.044 [NAL9602](DEBUG): Fix Requested
2019-07-16T23:56:48.435Z,1563321408.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235647.00,A,3648.44945,N,12149.47128,W,2.041,286.19,160719,,,A*70
2019-07-16T23:56:48.446Z,1563321408.446 [NAL9602](INFO): GPS fix at 20190716T235647: (36.807491, -121.824521)
2019-07-16T23:56:48.475Z,1563321408.475 [Default:CheckIn:Read_GPS] Stopped
2019-07-16T23:56:48.475Z,1563321408.475 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-16T23:56:51.696Z,1563321411.696 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2019-07-16T23:56:51.696Z,1563321411.696 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00
2019-07-16T23:56:54.623Z,1563321414.623 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20190716T225726/Courier0031.lzma
2019-07-16T23:56:55.429Z,1563321415.429 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0031.lzma.bak
2019-07-16T23:56:55.429Z,1563321415.429 [DataOverHttps](INFO): SBD MOMSN=11448902
2019-07-16T23:57:02.171Z,1563321422.171 [NAL9602](INFO): SBD MO Status=0, MOMSN=8139, MT Status=0, MTMSN=0
2019-07-16T23:57:02.171Z,1563321422.171 [NAL9602](INFO): No messages in MT queue
2019-07-16T23:57:06.927Z,1563321426.927 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20190716T225726/Express0032.lzma
2019-07-16T23:57:07.733Z,1563321427.733 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0032.lzma.bak
2019-07-16T23:57:07.733Z,1563321427.733 [DataOverHttps](INFO): SBD MOMSN=11448905
2019-07-16T23:57:08.260Z,1563321428.260 [Default:CheckIn:Read_Iridium] Stopped
2019-07-16T23:57:08.260Z,1563321428.260 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-16T23:57:08.260Z,1563321428.260 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-16T23:57:32.874Z,1563321452.874 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-17T00:00:32.311Z,1563321632.311 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-07-17T00:00:32.311Z,1563321632.311 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-3276,V
2019-07-17T00:02:08.849Z,1563321728.849 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-17T00:02:08.849Z,1563321728.849 [Default:CheckIn:C.Wait] Stopped
2019-07-17T00:02:08.849Z,1563321728.849 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-17T00:02:08.849Z,1563321728.849 [Default:CheckIn:D] Running Loop=1
2019-07-17T00:02:09.251Z,1563321729.251 [Default:CheckIn:D] Stopped
2019-07-17T00:02:09.251Z,1563321729.251 [Default:CheckIn:E] Running Loop=1
2019-07-17T00:02:09.683Z,1563321729.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.981384 min 
2019-07-17T00:02:09.683Z,1563321729.683 [Default:CheckIn:E] Stopped
2019-07-17T00:02:09.683Z,1563321729.683 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-17T00:02:09.683Z,1563321729.683 [Default:CheckIn] Stopped
2019-07-17T00:02:09.683Z,1563321729.683 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-17T00:02:09.683Z,1563321729.683 [Default:CheckIn](INFO): Running loop #10
2019-07-17T00:02:09.684Z,1563321729.684 [Default:CheckIn] Running Loop=10
2019-07-17T00:02:09.684Z,1563321729.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-17T00:02:09.684Z,1563321729.684 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-17T00:02:11.260Z,1563321731.260 [NAL9602](DEBUG): Fix Requested
2019-07-17T00:02:11.648Z,1563321731.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000210.00,A,3648.48140,N,12149.46812,W,0.311,48.98,170719,,,A*4E
2019-07-17T00:02:11.650Z,1563321731.650 [NAL9602](INFO): GPS fix at 20190717T000210: (36.808023, -121.824469)
2019-07-17T00:02:11.682Z,1563321731.682 [Default:CheckIn:Read_GPS] Stopped
2019-07-17T00:02:11.682Z,1563321731.682 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-17T00:02:17.359Z,1563321737.359 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190716T225726/Courier0034.lzma
2019-07-17T00:02:18.165Z,1563321738.165 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0034.lzma.bak
2019-07-17T00:02:18.165Z,1563321738.165 [DataOverHttps](INFO): SBD MOMSN=11448910
2019-07-17T00:02:25.788Z,1563321745.788 [NAL9602](INFO): SBD MO Status=0, MOMSN=8140, MT Status=0, MTMSN=0
2019-07-17T00:02:25.788Z,1563321745.788 [NAL9602](INFO): No messages in MT queue
2019-07-17T00:02:29.583Z,1563321749.583 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190716T225726/Express0035.lzma
2019-07-17T00:02:30.793Z,1563321750.793 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0035.lzma.bak
2019-07-17T00:02:30.794Z,1563321750.794 [DataOverHttps](INFO): SBD MOMSN=11448913
2019-07-17T00:02:31.483Z,1563321751.483 [Default:CheckIn:Read_Iridium] Stopped
2019-07-17T00:02:31.483Z,1563321751.483 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-17T00:02:31.483Z,1563321751.483 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-17T00:02:56.489Z,1563321776.489 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-17T00:04:09.621Z,1563321849.621 [Micromodem](INFO): Nmea in: $CADQF,179,1*5F

2019-07-17T00:04:09.625Z,1563321849.625 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:04:10.026Z,1563321850.026 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000409.236790,06,448,26,0092,0150,246,00,00,00,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,179,-0.10,-999,9760,4000*7A

2019-07-17T00:05:06.582Z,1563321906.582 [Micromodem](INFO): Nmea in: $CADQF,170,1*56

2019-07-17T00:05:06.583Z,1563321906.583 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:05:06.989Z,1563321906.989 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000505.992082,06,1364,25,0094,0150,246,01,01,01,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,170,0.05,-999,9760,4000*6A

2019-07-17T00:05:13.869Z,1563321913.869 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-17T00:05:13.869Z,1563321913.869 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19071617152435,35.0, -0.1,448.9,  0
2019-07-17T00:05:22.276Z,1563321922.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:05:23.084Z,1563321923.084 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.195488
2019-07-17T00:05:47.803Z,1563321947.803 [Micromodem](INFO): Nmea in: $CADQF,191,1*59

2019-07-17T00:05:47.805Z,1563321947.805 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:05:48.201Z,1563321948.201 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000547.503683,06,315,24,0090,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,191,-0.05,-999,9760,4000*7D

2019-07-17T00:05:53.552Z,1563321953.552 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-07-17T00:05:53.552Z,1563321953.552 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-07-17T00:05:53.552Z,1563321953.552 [RDI_Pathfinder](ERROR): Communications Fault
2019-07-17T00:05:53.552Z,1563321953.552 [RDI_Pathfinder](ERROR): Failed to parse:
2019-07-17T00:05:53.575Z,1563321953.575 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-07-17T00:05:53.940Z,1563321953.940 [RDI_Pathfinder](INFO): Powering down
2019-07-17T00:05:54.723Z,1563321954.723 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-07-17T00:05:54.723Z,1563321954.723 [RDI_Pathfinder] No Fault, FailCount= 1
2019-07-17T00:05:55.864Z,1563321955.864 [Micromodem](INFO): Nmea in: $CADQF,183,1*5A

2019-07-17T00:05:55.865Z,1563321955.865 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:05:56.277Z,1563321956.277 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000555.474192,06,1033,30,0092,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,183,0.00,-999,9760,4000*62

2019-07-17T00:05:57.424Z,1563321957.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:06:04.771Z,1563321964.771 [Micromodem](INFO): Nmea in: $CADQF,192,1*5A

2019-07-17T00:06:04.773Z,1563321964.773 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:06:05.161Z,1563321965.161 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000604.452749,06,435,26,0090,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,192,0.00,-999,9760,4000*57

2019-07-17T00:06:08.022Z,1563321968.022 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-07-17T00:06:09.610Z,1563321969.610 [Micromodem](INFO): Nmea in: $CADQF,192,1*5A

2019-07-17T00:06:09.612Z,1563321969.612 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:06:10.013Z,1563321970.013 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000609.321607,06,847,23,0092,0150,246,00,00,01,00,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,192,-0.05,-999,9760,4000*77

2019-07-17T00:06:32.572Z,1563321992.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:07:07.720Z,1563322027.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:07:32.052Z,1563322052.052 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-17T00:07:32.052Z,1563322052.052 [Default:CheckIn:C.Wait] Stopped
2019-07-17T00:07:32.052Z,1563322052.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-17T00:07:32.052Z,1563322052.052 [Default:CheckIn:D] Running Loop=1
2019-07-17T00:07:32.476Z,1563322052.476 [Default:CheckIn:D] Stopped
2019-07-17T00:07:32.476Z,1563322052.476 [Default:CheckIn:E] Running Loop=1
2019-07-17T00:07:32.854Z,1563322052.854 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.368465 min 
2019-07-17T00:07:32.854Z,1563322052.854 [Default:CheckIn:E] Stopped
2019-07-17T00:07:32.854Z,1563322052.854 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-17T00:07:32.855Z,1563322052.855 [Default:CheckIn] Stopped
2019-07-17T00:07:32.855Z,1563322052.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-17T00:07:32.856Z,1563322052.856 [Default:CheckIn](INFO): Running loop #11
2019-07-17T00:07:32.857Z,1563322052.857 [Default:CheckIn] Running Loop=11
2019-07-17T00:07:32.857Z,1563322052.857 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-17T00:07:32.857Z,1563322052.857 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-17T00:07:34.472Z,1563322054.472 [NAL9602](DEBUG): Fix Requested
2019-07-17T00:07:34.848Z,1563322054.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000734.00,A,3648.50568,N,12149.38971,W,1.322,353.84,170719,,,A*72
2019-07-17T00:07:34.850Z,1563322054.850 [NAL9602](INFO): GPS fix at 20190717T000734: (36.808428, -121.823162)
2019-07-17T00:07:34.883Z,1563322054.883 [Default:CheckIn:Read_GPS] Stopped
2019-07-17T00:07:34.883Z,1563322054.883 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-17T00:07:40.913Z,1563322060.913 [Micromodem](INFO): Nmea in: $CADQF,193,1*5B

2019-07-17T00:07:41.314Z,1563322061.314 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:07:41.721Z,1563322061.721 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000740.438274,06,331,13,0095,0150,246,00,00,02,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,193,0.00,-999,9760,4000*52

2019-07-17T00:07:42.868Z,1563322062.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:07:45.760Z,1563322065.760 [NAL9602](INFO): SBD MO Status=0, MOMSN=8141, MT Status=0, MTMSN=0
2019-07-17T00:07:45.760Z,1563322065.760 [NAL9602](INFO): No messages in MT queue
2019-07-17T00:08:16.455Z,1563322096.455 [NAL9602](INFO): Not Powering down - fast GPS
2019-07-17T00:08:18.016Z,1563322098.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:08:33.430Z,1563322113.430 [Micromodem](INFO): Nmea in: $CADQF,184,1*5D

2019-07-17T00:08:33.834Z,1563322113.834 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:08:34.241Z,1563322114.241 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717000833.162344,06,128,21,0090,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,184,0.05,-999,9760,4000*5E

2019-07-17T00:08:53.164Z,1563322133.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:09:28.312Z,1563322168.312 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:10:03.460Z,1563322203.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-07-17T00:10:14.540Z,1563322214.540 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003300
2019-07-17T00:10:19.991Z,1563322219.991 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190716T225726/Courier0037.lzma
2019-07-17T00:10:20.797Z,1563322220.797 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0037.lzma.bak
2019-07-17T00:10:20.798Z,1563322220.797 [DataOverHttps](INFO): SBD MOMSN=11448924
2019-07-17T00:10:22.525Z,1563322222.525 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-17T00:10:22.525Z,1563322222.525 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19071617203302,35.0, -0.1,   0.0,1448.9,,  0.00, 369.91, 363.43, 346.12, 395.87
2019-07-17T00:10:33.575Z,1563322233.575 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20190716T225726/Express0038.lzma
2019-07-17T00:10:34.381Z,1563322234.381 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Express0038.lzma.bak
2019-07-17T00:10:34.381Z,1563322234.381 [DataOverHttps](INFO): SBD MOMSN=11448928
2019-07-17T00:10:35.030Z,1563322235.030 [Micromodem](INFO): Nmea in: $CADQF,185,1*5C

2019-07-17T00:10:35.118Z,1563322235.118 [Default:CheckIn:Read_Iridium] Stopped
2019-07-17T00:10:35.118Z,1563322235.118 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-17T00:10:35.118Z,1563322235.118 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-17T00:10:35.438Z,1563322235.438 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:10:35.443Z,1563322235.443 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001034.405323,06,605,27,0092,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,185,0.00,-999,9760,4000*5D

2019-07-17T00:10:44.726Z,1563322244.726 [Micromodem](INFO): Nmea in: $CADQF,193,1*5B

2019-07-17T00:10:44.727Z,1563322244.727 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:10:45.146Z,1563322245.146 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001044.285801,06,1985,32,0091,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,193,0.05,-999,9760,4000*6C

2019-07-17T00:10:58.466Z,1563322258.466 [Micromodem](INFO): Nmea in: $CADQF,183,1*5A

2019-07-17T00:10:58.467Z,1563322258.467 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:10:58.869Z,1563322258.869 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001057.983955,06,853,29,0095,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,183,0.05,-999,9760,4000*57

2019-07-17T00:11:44.932Z,1563322304.932 [Micromodem](INFO): Nmea in: $CADQF,179,1*5F

2019-07-17T00:11:44.933Z,1563322304.933 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:11:45.333Z,1563322305.333 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001144.567909,06,160,16,0092,0150,246,00,00,01,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,179,0.00,-999,9760,4000*58

2019-07-17T00:11:53.406Z,1563322313.406 [Micromodem](INFO): Nmea in: $CADQF,190,1*58

2019-07-17T00:11:53.407Z,1563322313.407 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:11:53.821Z,1563322313.821 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001152.964215,06,238,17,0090,0150,246,01,01,01,03,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,190,0.00,-999,9760,4000*5D

2019-07-17T00:12:18.492Z,1563322338.492 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-17T00:12:18.492Z,1563322338.492 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-07-17T00:12:45.122Z,1563322365.122 [Micromodem](INFO): Nmea in: $CADQF,188,1*51

2019-07-17T00:12:45.123Z,1563322365.123 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:12:45.525Z,1563322365.525 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001244.584717,06,836,29,0090,0150,246,00,00,00,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,188,-0.05,-999,9760,4000*74

2019-07-17T00:12:58.046Z,1563322378.046 [Micromodem](INFO): Nmea in: $CADQF,172,1*54

2019-07-17T00:12:58.047Z,1563322378.047 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:12:58.457Z,1563322378.457 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001257.594029,06,2029,33,0094,0150,246,00,00,00,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,172,0.00,-999,9760,4000*68

2019-07-17T00:13:25.122Z,1563322405.122 [Micromodem](INFO): Nmea in: $CADQF,197,1*5F

2019-07-17T00:13:25.123Z,1563322405.123 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:13:25.521Z,1563322405.521 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001324.867550,06,2952,34,0130,0150,246,00,00,00,00,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,197,-0.10,-999,9760,4000*4C

2019-07-17T00:13:37.638Z,1563322417.638 [Micromodem](INFO): Nmea in: $CADQF,190,1*58

2019-07-17T00:13:37.639Z,1563322417.639 [Micromodem](INFO): Nmea in: $CAMSG,BAD_CRC,0*21

2019-07-17T00:13:38.045Z,1563322418.045 [Micromodem](INFO): Nmea in: $CACST,6,1,20190717001337.201587,06,727,22,0095,0150,246,00,00,01,03,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,190,0.00,-999,9760,4000*50

2019-07-17T00:14:41.121Z,1563322481.121 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-07-17T00:14:41.121Z,1563322481.121 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -24,  +150,
2019-07-17T00:15:35.633Z,1563322535.633 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-17T00:15:35.633Z,1563322535.633 [Default:CheckIn:C.Wait] Stopped
2019-07-17T00:15:35.633Z,1563322535.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-17T00:15:35.633Z,1563322535.633 [Default:CheckIn:D] Running Loop=1
2019-07-17T00:15:36.078Z,1563322536.078 [Default:CheckIn:D] Stopped
2019-07-17T00:15:36.078Z,1563322536.078 [Default:CheckIn:E] Running Loop=1
2019-07-17T00:15:36.479Z,1563322536.479 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.428499 min 
2019-07-17T00:15:36.479Z,1563322536.479 [Default:CheckIn:E] Stopped
2019-07-17T00:15:36.479Z,1563322536.479 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-17T00:15:36.479Z,1563322536.479 [Default:CheckIn] Stopped
2019-07-17T00:15:36.479Z,1563322536.479 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-17T00:15:36.480Z,1563322536.480 [Default:CheckIn](INFO): Running loop #12
2019-07-17T00:15:36.480Z,1563322536.480 [Default:CheckIn] Running Loop=12
2019-07-17T00:15:36.480Z,1563322536.480 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-17T00:15:36.480Z,1563322536.480 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-17T00:15:38.048Z,1563322538.048 [NAL9602](DEBUG): Fix Requested
2019-07-17T00:15:38.440Z,1563322538.440 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001537.00,A,3648.51799,N,12149.37807,W,1.127,1.81,170719,,,A*76
2019-07-17T00:15:38.442Z,1563322538.442 [NAL9602](INFO): GPS fix at 20190717T001537: (36.808633, -121.822968)
2019-07-17T00:15:38.475Z,1563322538.475 [Default:CheckIn:Read_GPS] Stopped
2019-07-17T00:15:38.475Z,1563322538.475 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-17T00:15:44.143Z,1563322544.143 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190716T225726/Courier0040.lzma
2019-07-17T00:15:44.949Z,1563322544.949 [DataOverHttps](INFO): Moved sent file to Logs/20190716T225726/Courier0040.lzma.bak
2019-07-17T00:15:44.950Z,1563322544.950 [DataOverHttps](INFO): SBD MOMSN=11448932
2019-07-17T00:15:50.166Z,1563322550.166 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=8142, MT Status=1, MTMSN=311
2019-07-17T00:15:50.166Z,1563322550.166 [NAL9602](INFO): Data available in MT queue
2019-07-17T00:15:50.644Z,1563322550.644 [NAL9602](INFO): Received command:restart logs