2019-02-06T20:38:29.132Z,1549485509.132 [Supervisor](DEBUG): Initializing supervisor.
2019-02-06T20:38:29.135Z,1549485509.135 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-02-06T20:38:29.136Z,1549485509.136 [SyncHandler](INFO): Protected caller Thread ID is 799
2019-02-06T20:38:29.136Z,1549485509.136 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-02-06T20:38:29.137Z,1549485509.137 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-02-06T20:38:29.137Z,1549485509.137 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 800
2019-02-06T20:38:29.140Z,1549485509.140 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-02-06T20:38:29.151Z,1549485509.151 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-02-06T20:38:29.152Z,1549485509.152 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-02-06T20:38:29.153Z,1549485509.153 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 801
2019-02-06T20:38:29.153Z,1549485509.153 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-02-06T20:38:29.154Z,1549485509.154 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-02-06T20:38:29.155Z,1549485509.155 [logger ThreadHandler](INFO): Protected caller Thread ID is 802
2019-02-06T20:38:29.157Z,1549485509.157 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-02-06T20:38:29.157Z,1549485509.157 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-02-06T20:38:29.162Z,1549485509.162 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-02-06T20:38:29.512Z,1549485509.512 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-02-06T20:38:29.512Z,1549485509.512 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-02-06T20:38:29.958Z,1549485509.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-02-06T20:38:29.960Z,1549485509.960 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-02-06T20:38:30.284Z,1549485510.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-02-06T20:38:30.285Z,1549485510.285 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-02-06T20:38:30.477Z,1549485510.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-02-06T20:38:30.478Z,1549485510.478 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-02-06T20:38:30.922Z,1549485510.922 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-02-06T20:38:30.923Z,1549485510.923 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-02-06T20:38:31.402Z,1549485511.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-02-06T20:38:31.403Z,1549485511.403 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-02-06T20:38:31.507Z,1549485511.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-02-06T20:38:31.508Z,1549485511.508 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-02-06T20:38:31.701Z,1549485511.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-02-06T20:38:31.701Z,1549485511.701 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-02-06T20:38:31.845Z,1549485511.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-02-06T20:38:31.846Z,1549485511.846 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-02-06T20:38:31.927Z,1549485511.927 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-02-06T20:38:32.136Z,1549485512.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-02-06T20:38:32.137Z,1549485512.137 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-02-06T20:38:32.238Z,1549485512.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-02-06T20:38:32.239Z,1549485512.239 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-02-06T20:38:32.336Z,1549485512.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-02-06T20:38:32.337Z,1549485512.337 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-02-06T20:38:32.486Z,1549485512.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-02-06T20:38:32.487Z,1549485512.487 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-02-06T20:38:33.395Z,1549485513.395 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-02-06T20:38:33.397Z,1549485513.397 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-02-06T20:38:33.401Z,1549485513.401 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-02-06T20:38:33.536Z,1549485513.536 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-02-06T20:38:33.770Z,1549485513.770 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-02-06T20:38:33.771Z,1549485513.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-02-06T20:38:33.936Z,1549485513.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-02-06T20:38:34.036Z,1549485514.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-02-06T20:38:34.136Z,1549485514.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-02-06T20:38:34.325Z,1549485514.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-02-06T20:38:34.410Z,1549485514.410 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-02-06T20:38:34.508Z,1549485514.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-02-06T20:38:34.601Z,1549485514.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-02-06T20:38:34.684Z,1549485514.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-02-06T20:38:34.791Z,1549485514.791 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-02-06T20:38:34.794Z,1549485514.794 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-02-06T20:38:34.957Z,1549485514.957 [SBIT](DEBUG): Construct Startup Built In Test.
2019-02-06T20:38:34.988Z,1549485514.988 [SBIT] Loaded
2019-02-06T20:38:34.988Z,1549485514.988 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-02-06T20:38:34.989Z,1549485514.989 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-02-06T20:38:35.017Z,1549485515.017 [IBIT] Loaded
2019-02-06T20:38:35.017Z,1549485515.017 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-02-06T20:38:35.020Z,1549485515.020 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-02-06T20:38:35.413Z,1549485515.413 [CBIT] Loaded
2019-02-06T20:38:35.413Z,1549485515.413 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-02-06T20:38:35.414Z,1549485515.414 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-02-06T20:38:35.415Z,1549485515.415 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-02-06T20:38:35.565Z,1549485515.565 [BuoyancyServo] Loaded
2019-02-06T20:38:35.565Z,1549485515.565 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-02-06T20:38:35.580Z,1549485515.580 [ElevatorServo] Loaded
2019-02-06T20:38:35.580Z,1549485515.580 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-02-06T20:38:35.595Z,1549485515.595 [MassServo] Loaded
2019-02-06T20:38:35.595Z,1549485515.595 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-02-06T20:38:35.610Z,1549485515.610 [RudderServo] Loaded
2019-02-06T20:38:35.610Z,1549485515.610 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-02-06T20:38:35.625Z,1549485515.625 [ThrusterServo] Loaded
2019-02-06T20:38:35.625Z,1549485515.625 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-02-06T20:38:35.626Z,1549485515.626 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-02-06T20:38:35.626Z,1549485515.626 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-02-06T20:38:35.750Z,1549485515.750 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-02-06T20:38:35.751Z,1549485515.751 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-02-06T20:38:35.811Z,1549485515.811 [DeadReckonUsingSpeedCalculator] Loaded
2019-02-06T20:38:35.811Z,1549485515.811 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-02-06T20:38:35.870Z,1549485515.870 [DeadReckonWithRespectToSeafloor] Loaded
2019-02-06T20:38:35.871Z,1549485515.871 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-02-06T20:38:35.887Z,1549485515.887 [NavChart] Loaded
2019-02-06T20:38:35.887Z,1549485515.887 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-02-06T20:38:35.891Z,1549485515.891 [UniversalFixResidualReporter] Loaded
2019-02-06T20:38:35.891Z,1549485515.891 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-02-06T20:38:35.892Z,1549485515.892 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-02-06T20:38:35.893Z,1549485515.893 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-02-06T20:38:35.916Z,1549485515.916 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-02-06T20:38:35.917Z,1549485515.917 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-02-06T20:38:36.199Z,1549485516.199 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-02-06T20:38:36.199Z,1549485516.199 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-02-06T20:38:36.855Z,1549485516.855 [AHRS_M2] Loaded
2019-02-06T20:38:36.856Z,1549485516.856 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-02-06T20:38:36.991Z,1549485516.991 [DataOverHttps] Loaded
2019-02-06T20:38:36.991Z,1549485516.991 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2019-02-06T20:38:37.005Z,1549485517.005 [Depth_Keller] Loaded
2019-02-06T20:38:37.006Z,1549485517.006 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-02-06T20:38:37.011Z,1549485517.011 [DropWeight] Loaded
2019-02-06T20:38:37.011Z,1549485517.011 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-02-06T20:38:37.101Z,1549485517.101 [NAL9602] Loaded
2019-02-06T20:38:37.102Z,1549485517.102 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-02-06T20:38:37.107Z,1549485517.107 [Onboard] Loaded
2019-02-06T20:38:37.107Z,1549485517.107 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-02-06T20:38:37.114Z,1549485517.114 [Radio_Surface] Loaded
2019-02-06T20:38:37.114Z,1549485517.114 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-02-06T20:38:37.116Z,1549485517.116 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408494E0
2019-02-06T20:38:37.116Z,1549485517.116 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 881
2019-02-06T20:38:37.144Z,1549485517.144 [RDI_Pathfinder] Loaded
2019-02-06T20:38:37.145Z,1549485517.145 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-02-06T20:38:37.168Z,1549485517.168 [RDI_PathfinderUp] Loaded
2019-02-06T20:38:37.168Z,1549485517.168 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-02-06T20:38:38.989Z,1549485518.989 [BPC1] Loaded
2019-02-06T20:38:38.990Z,1549485518.990 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-02-06T20:38:38.990Z,1549485518.990 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-02-06T20:38:38.991Z,1549485518.991 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-02-06T20:38:39.080Z,1549485519.080 [VerticalControl](DEBUG): Construct VerticalControl.
2019-02-06T20:38:39.168Z,1549485519.168 [VerticalControl] Loaded
2019-02-06T20:38:39.169Z,1549485519.169 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-02-06T20:38:39.169Z,1549485519.169 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-02-06T20:38:39.227Z,1549485519.227 [HorizontalControl] Loaded
2019-02-06T20:38:39.228Z,1549485519.228 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-02-06T20:38:39.228Z,1549485519.228 [SpeedControl](DEBUG): Construct SpeedControl.
2019-02-06T20:38:39.230Z,1549485519.230 [SpeedControl] Loaded
2019-02-06T20:38:39.231Z,1549485519.231 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-02-06T20:38:39.231Z,1549485519.231 [LoopControl](DEBUG): Construct LoopControl.
2019-02-06T20:38:39.232Z,1549485519.232 [LoopControl] Loaded
2019-02-06T20:38:39.232Z,1549485519.232 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-02-06T20:38:39.233Z,1549485519.233 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-02-06T20:38:39.233Z,1549485519.233 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-02-06T20:38:39.362Z,1549485519.362 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-02-06T20:38:39.363Z,1549485519.363 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-02-06T20:38:39.600Z,1549485519.600 [Aanderaa_O2] Loaded
2019-02-06T20:38:39.601Z,1549485519.601 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-02-06T20:38:39.687Z,1549485519.687 [CTD_NeilBrown] Loaded
2019-02-06T20:38:39.687Z,1549485519.687 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-02-06T20:38:39.689Z,1549485519.689 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4095A4E0
2019-02-06T20:38:39.689Z,1549485519.689 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 882
2019-02-06T20:38:39.734Z,1549485519.734 [WetLabsSeaOWL_UV_A] Loaded
2019-02-06T20:38:39.735Z,1549485519.735 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-02-06T20:38:39.736Z,1549485519.736 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 4098A4E0
2019-02-06T20:38:39.736Z,1549485519.736 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 883
2019-02-06T20:38:39.737Z,1549485519.737 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-02-06T20:38:39.737Z,1549485519.737 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-02-06T20:38:39.841Z,1549485519.841 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-02-06T20:38:39.842Z,1549485519.842 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-02-06T20:38:39.897Z,1549485519.897 [DepthRateCalculator] Loaded
2019-02-06T20:38:39.898Z,1549485519.898 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-02-06T20:38:39.903Z,1549485519.903 [PitchRateCalculator] Loaded
2019-02-06T20:38:39.903Z,1549485519.903 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-02-06T20:38:39.915Z,1549485519.915 [SpeedCalculator] Loaded
2019-02-06T20:38:39.915Z,1549485519.915 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-02-06T20:38:39.937Z,1549485519.937 [TempGradientCalculator] Loaded
2019-02-06T20:38:39.937Z,1549485519.937 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-02-06T20:38:39.942Z,1549485519.942 [YawRateCalculator] Loaded
2019-02-06T20:38:39.942Z,1549485519.942 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-02-06T20:38:39.973Z,1549485519.973 [ElevatorOffsetCalculator] Loaded
2019-02-06T20:38:39.973Z,1549485519.973 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-02-06T20:38:39.973Z,1549485519.973 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-02-06T20:38:39.974Z,1549485519.974 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-02-06T20:38:40.017Z,1549485520.017 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-02-06T20:38:40.021Z,1549485520.021 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-02-06T20:38:40.022Z,1549485520.022 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-02-06T20:38:40.028Z,1549485520.028 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-02-06T20:38:40.029Z,1549485520.029 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2019-02-06T20:38:40.030Z,1549485520.030 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 884
2019-02-06T20:38:40.034Z,1549485520.034 [Supervisor](INFO): Main Thread ID is 795
2019-02-06T20:38:40.034Z,1549485520.034 [Supervisor](DEBUG): Running supervisor.
2019-02-06T20:38:40.035Z,1549485520.035 [CommandLine ThreadHandler](INFO): Handler Thread ID is 885
2019-02-06T20:38:40.037Z,1549485520.037 [controlThread ThreadHandler](INFO): Handler Thread ID is 886
2019-02-06T20:38:40.038Z,1549485520.038 [controlThread](DEBUG): Initializing ControlThread
2019-02-06T20:38:40.039Z,1549485520.039 [SBIT](INFO): Initialize SBIT Component.
2019-02-06T20:38:40.039Z,1549485520.039 [SBIT](IMPORTANT): git: 2019-01-14A-54-g0d094c9
2019-02-06T20:38:40.039Z,1549485520.039 [SBIT](INFO): git hash: 0d094c9fe207dedbc4f0c5a93b42d412b4ef2ca6
2019-02-06T20:38:40.040Z,1549485520.040 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-02-06T20:38:40.040Z,1549485520.040 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-02-06T20:38:40.041Z,1549485520.041 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-02-06T20:38:40.041Z,1549485520.041 [IBIT](INFO): Initialize IBIT Component.
2019-02-06T20:38:40.042Z,1549485520.042 [CBIT](DEBUG): Initialize CBIT Component.
2019-02-06T20:38:40.043Z,1549485520.043 [logger ThreadHandler](INFO): Handler Thread ID is 887
2019-02-06T20:38:40.055Z,1549485520.055 [CBIT](DEBUG): Initialized mux pins.
2019-02-06T20:38:40.055Z,1549485520.055 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-02-06T20:38:40.055Z,1549485520.055 [CBIT](DEBUG): Initializing the watchdog timer.
2019-02-06T20:38:40.063Z,1549485520.063 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 888
2019-02-06T20:38:40.069Z,1549485520.069 [Radio_Surface](INFO): Powering up
2019-02-06T20:38:40.075Z,1549485520.075 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 889
2019-02-06T20:38:40.076Z,1549485520.076 [CTD_NeilBrown](INFO): Powering down
2019-02-06T20:38:40.079Z,1549485520.079 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-02-06T20:38:40.079Z,1549485520.079 [CBIT](DEBUG): Initializing heartbeat.
2019-02-06T20:38:40.111Z,1549485520.111 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 890
2019-02-06T20:38:40.112Z,1549485520.112 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-06T20:38:40.147Z,1549485520.147 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 891
2019-02-06T20:38:40.151Z,1549485520.151 [CBIT](DEBUG): Deactivating GF circuits.
2019-02-06T20:38:40.151Z,1549485520.151 [CBIT](DEBUG): Deactivating emergency mode.
2019-02-06T20:38:40.158Z,1549485520.158 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-02-06T20:38:40.158Z,1549485520.158 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-02-06T20:38:40.158Z,1549485520.158 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-02-06T20:38:40.158Z,1549485520.158 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-02-06T20:38:40.158Z,1549485520.158 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-02-06T20:38:40.159Z,1549485520.159 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-02-06T20:38:40.159Z,1549485520.159 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-02-06T20:38:40.159Z,1549485520.159 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-02-06T20:38:40.159Z,1549485520.159 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-02-06T20:38:40.159Z,1549485520.159 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-02-06T20:38:40.160Z,1549485520.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-02-06T20:38:40.160Z,1549485520.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-02-06T20:38:40.160Z,1549485520.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-02-06T20:38:40.160Z,1549485520.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-02-06T20:38:40.160Z,1549485520.160 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-02-06T20:38:40.160Z,1549485520.160 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-02-06T20:38:40.189Z,1549485520.189 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T20:38:40.189Z,1549485520.189 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-02-06T20:38:40.190Z,1549485520.190 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-02-06T20:38:40.198Z,1549485520.198 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-02-06T20:38:40.200Z,1549485520.200 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-02-06T20:38:40.200Z,1549485520.200 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-02-06T20:38:40.201Z,1549485520.201 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-02-06T20:38:40.201Z,1549485520.201 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-02-06T20:38:40.202Z,1549485520.202 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-02-06T20:38:40.202Z,1549485520.202 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-02-06T20:38:40.202Z,1549485520.202 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-02-06T20:38:40.207Z,1549485520.207 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-02-06T20:38:40.208Z,1549485520.208 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-02-06T20:38:40.209Z,1549485520.209 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-02-06T20:38:40.245Z,1549485520.245 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-06T20:38:40.256Z,1549485520.256 [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-02-06T20:38:40.257Z,1549485520.257 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-02-06T20:38:40.336Z,1549485520.336 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-02-06T20:38:40.337Z,1549485520.337 [Default:A.Wait](DEBUG): Construct Wait.
2019-02-06T20:38:40.339Z,1549485520.339 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-02-06T20:38:40.378Z,1549485520.378 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-02-06T20:38:40.393Z,1549485520.393 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-02-06T20:38:40.398Z,1549485520.398 [Default:E.Execute](DEBUG): Construct Execute.
2019-02-06T20:38:40.441Z,1549485520.441 [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-02-06T20:38:40.446Z,1549485520.446 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-02-06T20:38:40.470Z,1549485520.470 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-02-06T20:38:40.617Z,1549485520.617 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-02-06T20:38:40.632Z,1549485520.632 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-02-06T20:38:40.794Z,1549485520.794 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-06T20:38:41.040Z,1549485521.040 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-06T20:38:41.047Z,1549485521.047 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-06T20:38:41.070Z,1549485521.070 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-02-06T20:38:41.075Z,1549485521.075 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-02-06T20:38:41.097Z,1549485521.097 [MassServo](DEBUG): Initializing EZServoServo.
2019-02-06T20:38:41.103Z,1549485521.103 [MassServo](DEBUG): Initializing MassServo.
2019-02-06T20:38:41.109Z,1549485521.109 [RudderServo](DEBUG): Initializing EZServoServo.
2019-02-06T20:38:41.115Z,1549485521.115 [RudderServo](DEBUG): Initializing RudderServo.
2019-02-06T20:38:41.137Z,1549485521.137 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-02-06T20:38:41.143Z,1549485521.143 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-02-06T20:38:41.403Z,1549485521.403 [RDI_PathfinderUp](INFO): Powering down
2019-02-06T20:38:41.463Z,1549485521.463 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-06T20:38:41.664Z,1549485521.664 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-02-06T20:38:42.011Z,1549485522.011 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2019-02-06T20:38:42.011Z,1549485522.011 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2019-02-06T20:38:42.011Z,1549485522.011 [BuoyancyServo] Communications Fault, FailCount= 1
2019-02-06T20:38:42.011Z,1549485522.011 [BuoyancyServo](ERROR): Communications Fault
2019-02-06T20:38:42.215Z,1549485522.215 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2019-02-06T20:38:44.031Z,1549485524.031 [Aanderaa_O2](INFO): Powering down
2019-02-06T20:38:44.344Z,1549485524.344 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2019-02-06T20:38:44.344Z,1549485524.344 [BuoyancyServo] No Fault, FailCount= 1
2019-02-06T20:38:44.705Z,1549485524.705 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-02-06T20:38:44.824Z,1549485524.824 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-02-06T20:38:53.958Z,1549485533.958 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-02-06T20:39:07.105Z,1549485547.105 [NAL9602](INFO): Powering up NAL9602
2019-02-06T20:39:08.757Z,1549485548.757 [SBIT](IMPORTANT): Beginning Startup BIT
2019-02-06T20:39:08.795Z,1549485548.795 [CBIT](IMPORTANT): Beginning ground fault scan
2019-02-06T20:39:18.021Z,1549485558.021 [NAL9602](INFO): NAL9602 initialized
2019-02-06T20:39:18.831Z,1549485558.831 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:39:19.753Z,1549485559.753 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.007491
CHAN A1 (24V): -0.198268
CHAN A2 (12V): -0.005650
CHAN A3 (5V): -0.002533
CHAN B0 (3.3V): -0.000380
CHAN B1 (3.15aV): -0.000019
CHAN B2 (3.15bV): 0.000160
CHAN B3 (GND): 0.001026
OPEN: 0.012581
Full Scale Calc: 4.765 mA, -1.589 mA
2019-02-06T20:40:02.569Z,1549485602.569 [SBIT](IMPORTANT): SBIT PASSED
2019-02-06T20:40:02.602Z,1549485602.602 [CommandLine](IMPORTANT): got command configSet list
2019-02-06T20:40:02.602Z,1549485602.602 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-02-06T20:40:02.605Z,1549485602.605 [CommandLine](IMPORTANT): AHRS_M2.readMagnetics=1 bool;

2019-02-06T20:40:02.605Z,1549485602.605 [CommandLine](IMPORTANT): NAL9602.requestGGA=1 bool;

2019-02-06T20:40:02.945Z,1549485602.945 [MissionManager](IMPORTANT): Started mission Startup
2019-02-06T20:40:02.945Z,1549485602.945 [Startup] Running Loop=1
2019-02-06T20:40:02.945Z,1549485602.945 [Startup](DEBUG): Aggregate::initialize Startup
2019-02-06T20:40:02.945Z,1549485602.945 [Startup:A.GoToSurface] Running Loop=1
2019-02-06T20:40:02.945Z,1549485602.945 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-06T20:40:02.946Z,1549485602.946 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-06T20:40:02.946Z,1549485602.946 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-06T20:40:02.947Z,1549485602.947 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-06T20:40:02.947Z,1549485602.947 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-06T20:40:02.948Z,1549485602.948 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-06T20:40:02.954Z,1549485602.954 [Startup:StartupSatComms] Running Loop=1
2019-02-06T20:40:02.954Z,1549485602.954 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-02-06T20:40:02.954Z,1549485602.954 [Startup:StartupSatComms:A] Running Loop=1
2019-02-06T20:40:03.344Z,1549485603.344 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-02-06T20:41:02.961Z,1549485662.961 [Startup:StartupSatComms:A](INFO): Timed out from 2019-02-06T20:40:02.0Z
2019-02-06T20:41:02.961Z,1549485662.961 [Startup:StartupSatComms:A] Stopped
2019-02-06T20:41:02.961Z,1549485662.961 [Startup:StartupSatComms:B] Running Loop=1
2019-02-06T20:41:03.344Z,1549485663.344 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-06T20:41:07.792Z,1549485667.792 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004986
2019-02-06T20:41:13.483Z,1549485673.483 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T201533/Courier0010.lzma
2019-02-06T20:41:14.260Z,1549485674.260 [DataOverHttps](INFO): Moved sent file to Logs/20190206T201533/Courier0010.lzma.bak
2019-02-06T20:41:14.260Z,1549485674.260 [DataOverHttps](INFO): SBD MOMSN=9637625
2019-02-06T20:41:25.096Z,1549485685.096 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20190206T201533/Express0011.lzma
2019-02-06T20:41:25.860Z,1549485685.860 [DataOverHttps](INFO): Moved sent file to Logs/20190206T201533/Express0011.lzma.bak
2019-02-06T20:41:25.860Z,1549485685.860 [DataOverHttps](INFO): SBD MOMSN=9637627
2019-02-06T20:41:36.582Z,1549485696.582 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190206T203540/Express0001.lzma
2019-02-06T20:41:37.376Z,1549485697.376 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203540/Express0001.lzma.bak
2019-02-06T20:41:37.376Z,1549485697.376 [DataOverHttps](INFO): SBD MOMSN=9637631
2019-02-06T20:41:43.111Z,1549485703.111 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-02-06T20:41:43.111Z,1549485703.111 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T20:41:43.113Z,1549485703.113 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-02-06T20:41:43.113Z,1549485703.113 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T20:41:43.114Z,1549485703.114 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-06T20:41:43.114Z,1549485703.114 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T20:41:43.127Z,1549485703.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T20:41:43.127Z,1549485703.127 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T20:41:43.128Z,1549485703.128 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T20:41:43.273Z,1549485703.273 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T20:41:43.273Z,1549485703.273 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-02-06T20:41:43.273Z,1549485703.273 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T20:41:43.274Z,1549485703.274 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-02-06T20:41:43.274Z,1549485703.274 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T20:41:43.274Z,1549485703.274 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-06T20:41:43.576Z,1549485703.576 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T20:41:48.156Z,1549485708.156 [DataOverHttps](INFO): Sending 746 bytes from file Logs/20190206T203829/Express0001.lzma
2019-02-06T20:41:48.948Z,1549485708.948 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0001.lzma.bak
2019-02-06T20:41:48.948Z,1549485708.948 [DataOverHttps](INFO): SBD MOMSN=9637634
2019-02-06T20:41:51.307Z,1549485711.307 [Startup:StartupSatComms:B] Stopped
2019-02-06T20:41:51.307Z,1549485711.307 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-02-06T20:41:51.307Z,1549485711.307 [Startup:StartupSatComms] Stopped
2019-02-06T20:41:51.307Z,1549485711.307 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-02-06T20:41:51.308Z,1549485711.308 [Startup](INFO): Completed Startup
2019-02-06T20:41:51.308Z,1549485711.308 [MissionManager](INFO): Startup is completed.
2019-02-06T20:41:51.309Z,1549485711.309 [MissionManager](INFO): Uninitializing Mission Startup
2019-02-06T20:41:51.309Z,1549485711.309 [Startup] Stopped
2019-02-06T20:41:51.309Z,1549485711.309 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-02-06T20:41:51.309Z,1549485711.309 [Startup:A.GoToSurface] Stopped
2019-02-06T20:41:51.309Z,1549485711.309 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-06T20:41:51.442Z,1549485711.442 [MissionManager](IMPORTANT): Started mission Default
2019-02-06T20:41:51.442Z,1549485711.442 [Default] Running Loop=1
2019-02-06T20:41:51.442Z,1549485711.442 [Default](DEBUG): Aggregate::initialize Default
2019-02-06T20:41:51.442Z,1549485711.442 [Default:B.GoToSurface] Running Loop=1
2019-02-06T20:41:51.442Z,1549485711.442 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-02-06T20:41:51.447Z,1549485711.447 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-02-06T20:41:51.447Z,1549485711.447 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-02-06T20:41:51.448Z,1549485711.448 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-02-06T20:41:51.448Z,1549485711.448 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-02-06T20:41:51.448Z,1549485711.448 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-02-06T20:41:51.449Z,1549485711.449 [Default:A.Wait] Running Loop=1
2019-02-06T20:41:51.449Z,1549485711.449 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-02-06T20:42:04.698Z,1549485724.698 [Default:A.Wait](INFO): Done Waiting.
2019-02-06T20:42:04.699Z,1549485724.699 [Default:A.Wait] Stopped
2019-02-06T20:42:04.699Z,1549485724.699 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T20:42:05.127Z,1549485725.127 [Default:CheckIn] Running Loop=1
2019-02-06T20:42:05.128Z,1549485725.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T20:42:05.128Z,1549485725.128 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T20:42:05.507Z,1549485725.507 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-02-06T20:43:36.950Z,1549485816.950 [NAL9602](INFO): SBD MO Status=2, MOMSN=2080, MT Status=2, MTMSN=0
2019-02-06T20:43:36.950Z,1549485816.950 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T20:44:21.489Z,1549485861.489 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T20:44:22.315Z,1549485862.315 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:24.715Z,1549485864.715 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:27.511Z,1549485867.511 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:30.711Z,1549485870.711 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:33.507Z,1549485873.507 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:36.707Z,1549485876.707 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:39.511Z,1549485879.511 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:42.707Z,1549485882.707 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:43.945Z,1549485883.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-02-06T20:44:43.945Z,1549485883.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T20:44:43.951Z,1549485883.951 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-02-06T20:44:43.952Z,1549485883.952 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T20:44:43.954Z,1549485883.954 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-06T20:44:43.954Z,1549485883.954 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T20:44:43.980Z,1549485883.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T20:44:43.981Z,1549485883.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T20:44:43.981Z,1549485883.981 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T20:44:44.363Z,1549485884.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T20:44:44.364Z,1549485884.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-02-06T20:44:44.364Z,1549485884.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T20:44:44.364Z,1549485884.364 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-02-06T20:44:44.365Z,1549485884.365 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T20:44:44.365Z,1549485884.365 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-06T20:44:44.728Z,1549485884.728 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T20:44:45.507Z,1549485885.507 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:51.239Z,1549485891.239 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:53.595Z,1549485893.595 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:56.795Z,1549485896.795 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:44:59.587Z,1549485899.587 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:02.783Z,1549485902.783 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:05.588Z,1549485905.588 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:08.783Z,1549485908.783 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:11.591Z,1549485911.591 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:14.787Z,1549485914.787 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:17.587Z,1549485917.587 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:20.787Z,1549485920.787 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:26.371Z,1549485926.371 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:28.919Z,1549485928.919 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:31.703Z,1549485931.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:34.899Z,1549485934.899 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:37.707Z,1549485937.707 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:38.162Z,1549485938.162 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2019-02-06T20:45:40.909Z,1549485940.909 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:43.699Z,1549485943.699 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:46.907Z,1549485946.907 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:49.703Z,1549485949.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:52.903Z,1549485952.903 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:45:55.699Z,1549485955.699 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:05.611Z,1549485965.611 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:08.683Z,1549485968.683 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:11.487Z,1549485971.487 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:14.699Z,1549485974.699 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:17.487Z,1549485977.487 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:20.683Z,1549485980.683 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:23.488Z,1549485983.488 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:24.318Z,1549485984.318 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-02-06T20:46:26.703Z,1549485986.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:29.494Z,1549485989.494 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:32.687Z,1549485992.687 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:37.539Z,1549485997.539 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:40.743Z,1549486000.743 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:43.535Z,1549486003.535 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:46.739Z,1549486006.739 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:49.543Z,1549486009.543 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:52.735Z,1549486012.735 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:55.539Z,1549486015.539 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:46:58.739Z,1549486018.739 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:01.535Z,1549486021.535 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:04.739Z,1549486024.739 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:05.179Z,1549486025.179 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T20:42:05.1Z
2019-02-06T20:47:05.179Z,1549486025.179 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T20:47:05.179Z,1549486025.179 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T20:47:05.575Z,1549486025.575 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-02-06T20:47:10.271Z,1549486030.271 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:10.381Z,1549486030.381 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20190206T203829/Courier0004.lzma
2019-02-06T20:47:11.155Z,1549486031.155 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0004.lzma.bak
2019-02-06T20:47:11.156Z,1549486031.156 [DataOverHttps](INFO): SBD MOMSN=9637652
2019-02-06T20:47:16.755Z,1549486036.755 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:21.775Z,1549486041.775 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20190206T203829/Express0005.lzma
2019-02-06T20:47:22.163Z,1549486042.163 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:22.536Z,1549486042.536 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0005.lzma.bak
2019-02-06T20:47:22.536Z,1549486042.536 [DataOverHttps](INFO): SBD MOMSN=9637654
2019-02-06T20:47:24.911Z,1549486044.911 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T20:47:24.911Z,1549486044.911 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T20:47:24.911Z,1549486044.911 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T20:47:28.707Z,1549486048.707 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:31.507Z,1549486051.507 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:34.707Z,1549486054.707 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:37.503Z,1549486057.503 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:40.703Z,1549486060.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:43.503Z,1549486063.503 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:45.121Z,1549486065.121 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-02-06T20:47:45.121Z,1549486065.121 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T20:47:45.127Z,1549486065.127 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-02-06T20:47:45.127Z,1549486065.127 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T20:47:45.128Z,1549486065.128 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-06T20:47:45.128Z,1549486065.128 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T20:47:45.148Z,1549486065.148 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T20:47:45.149Z,1549486065.149 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T20:47:45.149Z,1549486065.149 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T20:47:45.544Z,1549486065.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T20:47:45.544Z,1549486065.544 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-02-06T20:47:45.545Z,1549486065.545 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T20:47:45.545Z,1549486065.545 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-02-06T20:47:45.545Z,1549486065.545 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T20:47:45.546Z,1549486065.546 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-06T20:47:45.928Z,1549486065.928 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T20:47:46.707Z,1549486066.707 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:49.503Z,1549486069.503 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:52.703Z,1549486072.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:55.503Z,1549486075.503 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:47:58.703Z,1549486078.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:04.007Z,1549486084.007 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:06.791Z,1549486086.791 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:09.595Z,1549486089.595 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:12.791Z,1549486092.791 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:15.587Z,1549486095.587 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:18.787Z,1549486098.787 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:21.591Z,1549486101.591 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:24.787Z,1549486104.787 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:27.591Z,1549486107.591 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:30.783Z,1549486110.783 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:33.587Z,1549486113.587 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:39.303Z,1549486119.303 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:41.703Z,1549486121.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:44.507Z,1549486124.507 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:46.907Z,1549486126.907 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:49.715Z,1549486129.715 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:52.903Z,1549486132.903 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:55.707Z,1549486135.707 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:48:58.923Z,1549486138.923 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:49:01.703Z,1549486141.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:49:04.911Z,1549486144.911 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:49:07.703Z,1549486147.703 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:49:10.925Z,1549486150.925 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:49:15.791Z,1549486155.791 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:49:18.595Z,1549486158.595 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:49:18.976Z,1549486158.976 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T20:49:18.977Z,1549486158.977 [NAL9602] Data Fault, FailCount= 1
2019-02-06T20:49:18.977Z,1549486158.977 [NAL9602](ERROR): Data Fault
2019-02-06T20:49:19.069Z,1549486159.069 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T20:49:19.376Z,1549486159.376 [NAL9602](INFO): Powering down
2019-02-06T20:49:20.253Z,1549486160.253 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T20:49:20.253Z,1549486160.253 [NAL9602] No Fault, FailCount= 1
2019-02-06T20:49:49.381Z,1549486189.381 [NAL9602](INFO): Powering up NAL9602
2019-02-06T20:50:00.313Z,1549486200.313 [NAL9602](INFO): NAL9602 initialized
2019-02-06T20:50:03.127Z,1549486203.127 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:05.927Z,1549486205.927 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:09.127Z,1549486209.127 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:11.931Z,1549486211.931 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:15.127Z,1549486215.127 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:17.527Z,1549486217.527 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:20.731Z,1549486220.731 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:25.191Z,1549486225.191 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:27.587Z,1549486227.587 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:30.783Z,1549486230.783 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:33.591Z,1549486233.591 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:36.787Z,1549486236.787 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:39.583Z,1549486239.583 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:42.783Z,1549486242.783 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:45.583Z,1549486245.583 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:46.028Z,1549486246.028 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-02-06T20:50:46.028Z,1549486246.028 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-02-06T20:50:46.029Z,1549486246.029 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-02-06T20:50:46.029Z,1549486246.029 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-02-06T20:50:46.030Z,1549486246.030 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-06T20:50:46.030Z,1549486246.030 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T20:50:46.056Z,1549486246.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-02-06T20:50:46.056Z,1549486246.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-02-06T20:50:46.056Z,1549486246.056 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T20:50:46.438Z,1549486246.438 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-02-06T20:50:46.439Z,1549486246.439 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-02-06T20:50:46.439Z,1549486246.439 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-02-06T20:50:46.439Z,1549486246.439 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-02-06T20:50:46.440Z,1549486246.440 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T20:50:46.440Z,1549486246.440 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-06T20:50:46.805Z,1549486246.805 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-02-06T20:50:48.783Z,1549486248.783 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:51.583Z,1549486251.583 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:50:54.783Z,1549486254.783 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:03.895Z,1549486263.895 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:06.659Z,1549486266.659 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:09.467Z,1549486269.467 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:11.464Z,1549486271.464 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:13.461Z,1549486273.461 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:15.859Z,1549486275.859 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:18.655Z,1549486278.655 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:21.867Z,1549486281.867 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:24.659Z,1549486284.659 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:27.851Z,1549486287.851 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:30.659Z,1549486290.659 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:35.551Z,1549486295.551 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:38.755Z,1549486298.755 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:41.551Z,1549486301.551 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:44.751Z,1549486304.751 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:47.551Z,1549486307.551 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:50.755Z,1549486310.755 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:53.551Z,1549486313.551 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:56.763Z,1549486316.763 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:51:59.951Z,1549486319.951 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:02.751Z,1549486322.751 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:05.555Z,1549486325.555 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:10.915Z,1549486330.915 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:13.702Z,1549486333.702 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:16.891Z,1549486336.891 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:19.691Z,1549486339.691 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:22.895Z,1549486342.895 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:23.278Z,1549486343.278 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205143.00,A,3648.17636,N,12147.27971,W,0.156,0.00,060219,,,A*74
2019-02-06T20:52:23.281Z,1549486343.281 [NAL9602](INFO): GPS fix at 20190206T205143: (36.802939, -121.787995)
2019-02-06T20:52:25.349Z,1549486345.349 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T20:52:25.349Z,1549486345.349 [Default:CheckIn:C.Wait] Stopped
2019-02-06T20:52:25.349Z,1549486345.349 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T20:52:25.349Z,1549486345.349 [Default:CheckIn:D] Running Loop=1
2019-02-06T20:52:25.754Z,1549486345.754 [Default:CheckIn:D] Stopped
2019-02-06T20:52:25.754Z,1549486345.754 [Default:CheckIn:E] Running Loop=1
2019-02-06T20:52:26.130Z,1549486346.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.571825 min 
2019-02-06T20:52:26.156Z,1549486346.156 [Default:CheckIn:E] Stopped
2019-02-06T20:52:26.156Z,1549486346.156 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T20:52:26.156Z,1549486346.156 [Default:CheckIn] Stopped
2019-02-06T20:52:26.157Z,1549486346.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T20:52:26.157Z,1549486346.157 [Default:CheckIn](INFO): Running loop #2
2019-02-06T20:52:26.157Z,1549486346.157 [Default:CheckIn] Running Loop=2
2019-02-06T20:52:26.157Z,1549486346.157 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T20:52:26.157Z,1549486346.157 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T20:52:27.691Z,1549486347.691 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:52:28.090Z,1549486348.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205148.00,A,3648.17486,N,12147.27926,W,0.272,0.00,060219,,,A*71
2019-02-06T20:52:28.092Z,1549486348.092 [NAL9602](INFO): GPS fix at 20190206T205148: (36.802914, -121.787988)
2019-02-06T20:52:28.153Z,1549486348.153 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T20:52:28.153Z,1549486348.153 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T20:52:33.836Z,1549486353.836 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190206T203829/Courier0007.lzma
2019-02-06T20:52:34.457Z,1549486354.457 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0007.lzma.bak
2019-02-06T20:52:34.457Z,1549486354.457 [DataOverHttps](INFO): SBD MOMSN=9637666
2019-02-06T20:52:46.235Z,1549486366.235 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20190206T203829/Express0008.lzma
2019-02-06T20:52:46.981Z,1549486366.981 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0008.lzma.bak
2019-02-06T20:52:46.981Z,1549486366.981 [DataOverHttps](INFO): SBD MOMSN=9637669
2019-02-06T20:52:49.377Z,1549486369.377 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T20:52:49.377Z,1549486369.377 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T20:52:49.377Z,1549486369.377 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T20:53:00.408Z,1549486380.408 [NAL9602](INFO): Powering down
2019-02-06T20:53:47.185Z,1549486427.185 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-06T20:53:47.185Z,1549486427.185 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T20:53:47.202Z,1549486427.202 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T20:53:47.598Z,1549486427.598 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T20:53:47.598Z,1549486427.598 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-06T20:56:48.261Z,1549486608.261 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-06T20:56:48.261Z,1549486608.261 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T20:56:48.291Z,1549486608.291 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T20:56:48.662Z,1549486608.662 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T20:56:48.662Z,1549486608.662 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-06T20:57:49.643Z,1549486669.643 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T20:57:49.643Z,1549486669.643 [Default:CheckIn:C.Wait] Stopped
2019-02-06T20:57:49.644Z,1549486669.644 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T20:57:49.644Z,1549486669.644 [Default:CheckIn:D] Running Loop=1
2019-02-06T20:57:50.038Z,1549486670.038 [Default:CheckIn:D] Stopped
2019-02-06T20:57:50.038Z,1549486670.038 [Default:CheckIn:E] Running Loop=1
2019-02-06T20:57:50.438Z,1549486670.438 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.976591 min 
2019-02-06T20:57:50.438Z,1549486670.438 [Default:CheckIn:E] Stopped
2019-02-06T20:57:50.438Z,1549486670.438 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T20:57:50.439Z,1549486670.439 [Default:CheckIn] Stopped
2019-02-06T20:57:50.439Z,1549486670.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T20:57:50.439Z,1549486670.439 [Default:CheckIn](INFO): Running loop #3
2019-02-06T20:57:50.439Z,1549486670.439 [Default:CheckIn] Running Loop=3
2019-02-06T20:57:50.439Z,1549486670.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T20:57:50.439Z,1549486670.439 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T20:57:51.196Z,1549486671.196 [NAL9602](INFO): Powering up
2019-02-06T20:58:02.008Z,1549486682.008 [NAL9602](INFO): NAL9602 initialized
2019-02-06T20:58:02.809Z,1549486682.809 [NAL9602](DEBUG): Fix Requested
2019-02-06T20:59:49.257Z,1549486789.257 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-06T20:59:49.257Z,1549486789.257 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T20:59:49.282Z,1549486789.282 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T20:59:49.676Z,1549486789.676 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T20:59:49.676Z,1549486789.676 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-06T21:01:16.590Z,1549486876.590 [NAL9602](INFO): SBD MO Status=0, MOMSN=2080, MT Status=0, MTMSN=0
2019-02-06T21:01:16.590Z,1549486876.590 [NAL9602](INFO): No messages in MT queue
2019-02-06T21:01:17.415Z,1549486877.415 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:19.811Z,1549486879.811 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:22.599Z,1549486882.599 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:25.809Z,1549486885.809 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:28.599Z,1549486888.599 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:31.811Z,1549486891.811 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:34.599Z,1549486894.599 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:37.799Z,1549486897.799 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:43.691Z,1549486903.691 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:46.879Z,1549486906.879 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:49.683Z,1549486909.683 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:52.891Z,1549486912.891 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:55.688Z,1549486915.688 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:01:58.903Z,1549486918.903 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:01.691Z,1549486921.691 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:04.887Z,1549486924.887 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:07.680Z,1549486927.680 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:10.899Z,1549486930.899 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:16.491Z,1549486936.491 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:18.603Z,1549486938.603 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:21.799Z,1549486941.799 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:24.603Z,1549486944.603 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:27.800Z,1549486947.800 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:30.603Z,1549486950.603 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:33.799Z,1549486953.799 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:36.599Z,1549486956.599 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:39.803Z,1549486959.803 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:42.595Z,1549486962.595 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:45.799Z,1549486965.799 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:48.599Z,1549486968.599 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:02:51.863Z,1549486971.863 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-06T21:02:51.863Z,1549486971.863 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:02:51.874Z,1549486971.874 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T20:57:50.4Z
2019-02-06T21:02:51.874Z,1549486971.874 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T21:02:51.874Z,1549486971.874 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T21:02:51.904Z,1549486971.904 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:02:52.061Z,1549486972.061 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:02:52.061Z,1549486972.061 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-06T21:02:57.765Z,1549486977.765 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0010.lzma
2019-02-06T21:02:58.534Z,1549486978.534 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0010.lzma.bak
2019-02-06T21:02:58.534Z,1549486978.534 [DataOverHttps](INFO): SBD MOMSN=9637682
2019-02-06T21:03:10.327Z,1549486990.327 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190206T203829/Express0011.lzma
2019-02-06T21:03:11.122Z,1549486991.122 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0011.lzma.bak
2019-02-06T21:03:11.122Z,1549486991.122 [DataOverHttps](INFO): SBD MOMSN=9637684
2019-02-06T21:03:13.682Z,1549486993.682 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T21:03:13.682Z,1549486993.682 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T21:03:13.682Z,1549486993.682 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T21:03:23.536Z,1549487003.536 [NAL9602](INFO): Powering down
2019-02-06T21:05:43.297Z,1549487143.297 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-02-06T21:05:53.710Z,1549487153.710 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-02-06T21:05:53.710Z,1549487153.710 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:05:53.724Z,1549487153.724 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:05:54.129Z,1549487154.129 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:05:54.129Z,1549487154.129 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-02-06T21:06:50.181Z,1549487210.181 [RDI_Pathfinder](ERROR): Failed to parse:

2019-02-06T21:08:13.826Z,1549487293.826 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T21:08:13.826Z,1549487293.826 [Default:CheckIn:C.Wait] Stopped
2019-02-06T21:08:13.826Z,1549487293.826 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T21:08:13.826Z,1549487293.826 [Default:CheckIn:D] Running Loop=1
2019-02-06T21:08:14.229Z,1549487294.229 [Default:CheckIn:D] Stopped
2019-02-06T21:08:14.229Z,1549487294.229 [Default:CheckIn:E] Running Loop=1
2019-02-06T21:08:14.631Z,1549487294.631 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.379775 min 
2019-02-06T21:08:14.631Z,1549487294.631 [Default:CheckIn:E] Stopped
2019-02-06T21:08:14.631Z,1549487294.631 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T21:08:14.631Z,1549487294.631 [Default:CheckIn] Stopped
2019-02-06T21:08:14.632Z,1549487294.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T21:08:14.632Z,1549487294.632 [Default:CheckIn](INFO): Running loop #4
2019-02-06T21:08:14.632Z,1549487294.632 [Default:CheckIn] Running Loop=4
2019-02-06T21:08:14.632Z,1549487294.632 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T21:08:14.632Z,1549487294.632 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T21:08:15.384Z,1549487295.384 [NAL9602](INFO): Powering up
2019-02-06T21:08:26.581Z,1549487306.581 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:08:27.389Z,1549487307.389 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T21:08:27.389Z,1549487307.389 [NAL9602] Data Fault, FailCount= 1
2019-02-06T21:08:27.389Z,1549487307.389 [NAL9602](ERROR): Data Fault
2019-02-06T21:08:27.486Z,1549487307.486 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T21:08:27.780Z,1549487307.780 [NAL9602](INFO): Powering down
2019-02-06T21:08:28.639Z,1549487308.639 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T21:08:28.640Z,1549487308.640 [NAL9602] No Fault, FailCount= 1
2019-02-06T21:08:54.738Z,1549487334.738 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-02-06T21:08:54.738Z,1549487334.738 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:08:54.768Z,1549487334.768 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:08:55.191Z,1549487335.191 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:08:55.191Z,1549487335.191 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-02-06T21:08:57.916Z,1549487337.916 [NAL9602](INFO): Powering up NAL9602
2019-02-06T21:09:10.041Z,1549487350.041 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:09:10.871Z,1549487350.871 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:09:41.638Z,1549487381.638 [NAL9602](INFO): SBD MO Status=2, MOMSN=2081, MT Status=2, MTMSN=0
2019-02-06T21:09:41.638Z,1549487381.638 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:09:58.770Z,1549487398.770 [NAL9602](INFO): SBD MO Status=2, MOMSN=2081, MT Status=2, MTMSN=0
2019-02-06T21:09:58.770Z,1549487398.770 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:10:40.558Z,1549487440.558 [NAL9602](INFO): SBD MO Status=2, MOMSN=2081, MT Status=2, MTMSN=0
2019-02-06T21:10:40.558Z,1549487440.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:11:22.362Z,1549487482.362 [NAL9602](INFO): SBD MO Status=2, MOMSN=2081, MT Status=2, MTMSN=0
2019-02-06T21:11:22.362Z,1549487482.362 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:11:55.723Z,1549487515.723 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2019-02-06T21:11:55.724Z,1549487515.724 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:11:55.737Z,1549487515.737 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:11:56.166Z,1549487516.166 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:11:56.166Z,1549487516.166 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2019-02-06T21:13:16.667Z,1549487596.667 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T21:08:14.6Z
2019-02-06T21:13:16.668Z,1549487596.668 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T21:13:16.668Z,1549487596.668 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T21:13:22.564Z,1549487602.564 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0013.lzma
2019-02-06T21:13:23.322Z,1549487603.322 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0013.lzma.bak
2019-02-06T21:13:23.322Z,1549487603.322 [DataOverHttps](INFO): SBD MOMSN=9637694
2019-02-06T21:13:34.815Z,1549487614.815 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20190206T203829/Express0014.lzma
2019-02-06T21:13:35.586Z,1549487615.586 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0014.lzma.bak
2019-02-06T21:13:35.586Z,1549487615.586 [DataOverHttps](INFO): SBD MOMSN=9637696
2019-02-06T21:13:38.062Z,1549487618.062 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T21:13:38.063Z,1549487618.063 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T21:13:38.063Z,1549487618.063 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T21:14:13.493Z,1549487653.493 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T21:14:46.889Z,1549487686.889 [NAL9602](INFO): Powering down
2019-02-06T21:14:56.634Z,1549487696.634 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2019-02-06T21:14:56.634Z,1549487696.634 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:14:56.683Z,1549487696.683 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:14:57.078Z,1549487697.078 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:14:57.078Z,1549487697.078 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2019-02-06T21:17:57.786Z,1549487877.786 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2019-02-06T21:17:57.786Z,1549487877.786 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:17:57.799Z,1549487877.799 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:17:58.220Z,1549487878.220 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:17:58.220Z,1549487878.220 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2019-02-06T21:18:38.362Z,1549487918.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T21:18:38.367Z,1549487918.367 [Default:CheckIn:C.Wait] Stopped
2019-02-06T21:18:38.367Z,1549487918.367 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T21:18:38.367Z,1549487918.367 [Default:CheckIn:D] Running Loop=1
2019-02-06T21:18:38.762Z,1549487918.762 [Default:CheckIn:D] Stopped
2019-02-06T21:18:38.762Z,1549487918.762 [Default:CheckIn:E] Running Loop=1
2019-02-06T21:18:39.181Z,1549487919.181 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.788668 min 
2019-02-06T21:18:39.181Z,1549487919.181 [Default:CheckIn:E] Stopped
2019-02-06T21:18:39.181Z,1549487919.181 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T21:18:39.182Z,1549487919.182 [Default:CheckIn] Stopped
2019-02-06T21:18:39.182Z,1549487919.182 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T21:18:39.182Z,1549487919.182 [Default:CheckIn](INFO): Running loop #5
2019-02-06T21:18:39.182Z,1549487919.182 [Default:CheckIn] Running Loop=5
2019-02-06T21:18:39.182Z,1549487919.182 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T21:18:39.182Z,1549487919.182 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T21:18:39.920Z,1549487919.920 [NAL9602](INFO): Powering up
2019-02-06T21:18:50.728Z,1549487930.728 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:18:51.535Z,1549487931.535 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:19:22.465Z,1549487962.465 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-02-06T21:19:22.547Z,1549487962.547 [NAL9602](FAULT): received: 
+CSQ:0

OK81, 2, 0, 0, 0

OK
2019-02-06T21:19:22.547Z,1549487962.547 [NAL9602] Data Fault, FailCount= 2
2019-02-06T21:19:22.547Z,1549487962.547 [NAL9602](ERROR): Data Fault
2019-02-06T21:19:22.640Z,1549487962.640 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T21:19:22.889Z,1549487962.889 [NAL9602](INFO): Powering down
2019-02-06T21:19:23.728Z,1549487963.728 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T21:19:23.729Z,1549487963.729 [NAL9602] No Fault, FailCount= 2
2019-02-06T21:19:53.044Z,1549487993.044 [NAL9602](INFO): Powering up NAL9602
2019-02-06T21:20:03.846Z,1549488003.846 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:20:04.652Z,1549488004.652 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T21:20:04.652Z,1549488004.652 [NAL9602] Data Fault, FailCount= 3
2019-02-06T21:20:04.653Z,1549488004.653 [NAL9602](ERROR): Data Fault
2019-02-06T21:20:04.743Z,1549488004.743 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T21:20:05.044Z,1549488005.044 [NAL9602](INFO): Powering down
2019-02-06T21:20:05.938Z,1549488005.938 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T21:20:05.938Z,1549488005.938 [NAL9602] No Fault, FailCount= 3
2019-02-06T21:20:35.144Z,1549488035.144 [NAL9602](INFO): Powering up NAL9602
2019-02-06T21:20:48.508Z,1549488048.508 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:20:49.311Z,1549488049.311 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:20:58.967Z,1549488058.967 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2019-02-06T21:20:58.968Z,1549488058.968 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:20:59.006Z,1549488059.006 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:20:59.409Z,1549488059.409 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:20:59.410Z,1549488059.410 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2019-02-06T21:21:08.102Z,1549488068.102 [NAL9602](INFO): SBD MO Status=2, MOMSN=2081, MT Status=2, MTMSN=0
2019-02-06T21:21:08.102Z,1549488068.102 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:21:29.878Z,1549488089.878 [NAL9602](INFO): SBD MO Status=2, MOMSN=2081, MT Status=2, MTMSN=0
2019-02-06T21:21:29.878Z,1549488089.878 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:23:41.439Z,1549488221.439 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T21:18:39.2Z
2019-02-06T21:23:41.439Z,1549488221.439 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T21:23:41.439Z,1549488221.439 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T21:23:47.172Z,1549488227.172 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0016.lzma
2019-02-06T21:23:47.952Z,1549488227.952 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0016.lzma.bak
2019-02-06T21:23:47.953Z,1549488227.953 [DataOverHttps](INFO): SBD MOMSN=9637708
2019-02-06T21:23:54.460Z,1549488234.460 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T21:23:58.974Z,1549488238.974 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20190206T203829/Express0017.lzma
2019-02-06T21:23:59.722Z,1549488239.722 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0017.lzma.bak
2019-02-06T21:23:59.722Z,1549488239.722 [DataOverHttps](INFO): SBD MOMSN=9637710
2019-02-06T21:23:59.792Z,1549488239.792 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11
2019-02-06T21:23:59.792Z,1549488239.792 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:23:59.810Z,1549488239.810 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:24:02.123Z,1549488242.123 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T21:24:02.123Z,1549488242.123 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T21:24:02.123Z,1549488242.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T21:24:02.147Z,1549488242.147 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:24:02.147Z,1549488242.147 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11
2019-02-06T21:24:25.268Z,1549488265.268 [NAL9602](INFO): Powering down
2019-02-06T21:27:02.502Z,1549488422.502 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 12
2019-02-06T21:27:02.502Z,1549488422.502 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:27:02.543Z,1549488422.543 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:27:02.962Z,1549488422.962 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:27:02.962Z,1549488422.962 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 12
2019-02-06T21:27:38.204Z,1549488458.204 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-02-06T21:29:02.364Z,1549488542.364 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T21:29:02.364Z,1549488542.364 [Default:CheckIn:C.Wait] Stopped
2019-02-06T21:29:02.364Z,1549488542.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T21:29:02.364Z,1549488542.364 [Default:CheckIn:D] Running Loop=1
2019-02-06T21:29:02.785Z,1549488542.785 [Default:CheckIn:D] Stopped
2019-02-06T21:29:02.785Z,1549488542.785 [Default:CheckIn:E] Running Loop=1
2019-02-06T21:29:03.143Z,1549488543.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.189042 min 
2019-02-06T21:29:03.143Z,1549488543.143 [Default:CheckIn:E] Stopped
2019-02-06T21:29:03.143Z,1549488543.143 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T21:29:03.143Z,1549488543.143 [Default:CheckIn] Stopped
2019-02-06T21:29:03.144Z,1549488543.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T21:29:03.144Z,1549488543.144 [Default:CheckIn](INFO): Running loop #6
2019-02-06T21:29:03.144Z,1549488543.144 [Default:CheckIn] Running Loop=6
2019-02-06T21:29:03.144Z,1549488543.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T21:29:03.144Z,1549488543.144 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T21:29:03.892Z,1549488543.892 [NAL9602](INFO): Powering up
2019-02-06T21:29:14.688Z,1549488554.688 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:29:15.509Z,1549488555.509 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:29:46.491Z,1549488586.491 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-02-06T21:29:46.575Z,1549488586.575 [NAL9602](FAULT): received: 
+CSQ:0

OK81, 2, 0, 0, 0

OK
2019-02-06T21:29:46.575Z,1549488586.575 [NAL9602] Data Fault, FailCount= 4
2019-02-06T21:29:46.575Z,1549488586.575 [NAL9602](ERROR): Data Fault
2019-02-06T21:29:46.654Z,1549488586.654 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T21:29:46.872Z,1549488586.872 [NAL9602](INFO): Powering down
2019-02-06T21:29:47.725Z,1549488587.725 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T21:29:47.725Z,1549488587.725 [NAL9602] No Fault, FailCount= 4
2019-02-06T21:30:03.420Z,1549488603.420 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 13
2019-02-06T21:30:03.420Z,1549488603.420 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:30:03.438Z,1549488603.438 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:30:03.832Z,1549488603.832 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:30:03.832Z,1549488603.832 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 13
2019-02-06T21:30:16.980Z,1549488616.980 [NAL9602](INFO): Powering up NAL9602
2019-02-06T21:30:28.181Z,1549488628.181 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:30:28.992Z,1549488628.992 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:32:14.248Z,1549488734.248 [RDI_Pathfinder](ERROR): Failed to parse:
68,-32768,-32768,-32768,V
2019-02-06T21:33:04.324Z,1549488784.324 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 14
2019-02-06T21:33:04.324Z,1549488784.324 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:33:04.366Z,1549488784.366 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:33:04.736Z,1549488784.736 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:33:04.736Z,1549488784.736 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 14
2019-02-06T21:33:35.992Z,1549488815.992 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-02-06T21:34:04.007Z,1549488844.007 [NAL9602](INFO): SBD MO Status=0, MOMSN=2081, MT Status=0, MTMSN=0
2019-02-06T21:34:04.007Z,1549488844.007 [NAL9602](INFO): No messages in MT queue
2019-02-06T21:34:04.103Z,1549488844.103 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T21:29:03.1Z
2019-02-06T21:34:04.103Z,1549488844.103 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T21:34:04.103Z,1549488844.103 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T21:34:10.027Z,1549488850.027 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0019.lzma
2019-02-06T21:34:11.057Z,1549488851.057 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0019.lzma.bak
2019-02-06T21:34:11.057Z,1549488851.057 [DataOverHttps](INFO): SBD MOMSN=9637732
2019-02-06T21:34:21.770Z,1549488861.770 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20190206T203829/Express0020.lzma
2019-02-06T21:34:22.553Z,1549488862.553 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0020.lzma.bak
2019-02-06T21:34:22.553Z,1549488862.553 [DataOverHttps](INFO): SBD MOMSN=9637734
2019-02-06T21:34:24.948Z,1549488864.948 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T21:34:24.948Z,1549488864.948 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T21:34:24.948Z,1549488864.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T21:34:34.480Z,1549488874.480 [NAL9602](INFO): Powering down
2019-02-06T21:35:05.150Z,1549488905.150 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-02-06T21:35:40.170Z,1549488940.170 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-02-06T21:36:05.417Z,1549488965.417 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 15
2019-02-06T21:36:05.417Z,1549488965.417 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:36:05.431Z,1549488965.431 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:36:05.842Z,1549488965.842 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:36:05.842Z,1549488965.842 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 15
2019-02-06T21:39:06.503Z,1549489146.503 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 16
2019-02-06T21:39:06.503Z,1549489146.503 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:39:06.533Z,1549489146.533 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:39:06.964Z,1549489146.964 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:39:06.964Z,1549489146.964 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 16
2019-02-06T21:39:25.105Z,1549489165.105 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T21:39:25.105Z,1549489165.105 [Default:CheckIn:C.Wait] Stopped
2019-02-06T21:39:25.105Z,1549489165.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T21:39:25.105Z,1549489165.105 [Default:CheckIn:D] Running Loop=1
2019-02-06T21:39:25.490Z,1549489165.490 [Default:CheckIn:D] Stopped
2019-02-06T21:39:25.490Z,1549489165.490 [Default:CheckIn:E] Running Loop=1
2019-02-06T21:39:25.909Z,1549489165.909 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.567460 min 
2019-02-06T21:39:25.909Z,1549489165.909 [Default:CheckIn:E] Stopped
2019-02-06T21:39:25.909Z,1549489165.909 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T21:39:25.909Z,1549489165.909 [Default:CheckIn] Stopped
2019-02-06T21:39:25.909Z,1549489165.909 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T21:39:25.909Z,1549489165.909 [Default:CheckIn](INFO): Running loop #7
2019-02-06T21:39:25.909Z,1549489165.909 [Default:CheckIn] Running Loop=7
2019-02-06T21:39:25.910Z,1549489165.910 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T21:39:25.910Z,1549489165.910 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T21:39:26.648Z,1549489166.648 [NAL9602](INFO): Powering up
2019-02-06T21:39:37.850Z,1549489177.850 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:39:38.644Z,1549489178.644 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T21:39:38.644Z,1549489178.644 [NAL9602] Data Fault, FailCount= 1
2019-02-06T21:39:38.644Z,1549489178.644 [NAL9602](ERROR): Data Fault
2019-02-06T21:39:38.725Z,1549489178.725 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T21:39:39.040Z,1549489179.040 [NAL9602](INFO): Powering down
2019-02-06T21:39:39.943Z,1549489179.943 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T21:39:39.943Z,1549489179.943 [NAL9602] No Fault, FailCount= 1
2019-02-06T21:40:09.140Z,1549489209.140 [NAL9602](INFO): Powering up NAL9602
2019-02-06T21:40:21.247Z,1549489221.247 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:40:22.059Z,1549489222.059 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:42:10.552Z,1549489330.552 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 17
2019-02-06T21:42:10.552Z,1549489330.552 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:42:10.586Z,1549489330.586 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:42:11.002Z,1549489331.002 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:42:11.002Z,1549489331.002 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 17
2019-02-06T21:42:43.128Z,1549489363.128 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-02-06T21:43:34.026Z,1549489414.026 [NAL9602](INFO): SBD MO Status=2, MOMSN=2082, MT Status=2, MTMSN=0
2019-02-06T21:43:34.026Z,1549489414.026 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:43:56.538Z,1549489436.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=2082, MT Status=2, MTMSN=0
2019-02-06T21:43:56.538Z,1549489436.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:44:14.138Z,1549489454.138 [NAL9602](INFO): SBD MO Status=2, MOMSN=2082, MT Status=2, MTMSN=0
2019-02-06T21:44:14.138Z,1549489454.138 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:44:27.780Z,1549489467.780 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T21:39:25.9Z
2019-02-06T21:44:27.780Z,1549489467.780 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T21:44:27.780Z,1549489467.780 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T21:44:33.767Z,1549489473.767 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0022.lzma
2019-02-06T21:44:34.526Z,1549489474.526 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0022.lzma.bak
2019-02-06T21:44:34.526Z,1549489474.526 [DataOverHttps](INFO): SBD MOMSN=9637748
2019-02-06T21:44:36.792Z,1549489476.792 [NAL9602](INFO): SBD MO Status=2, MOMSN=2082, MT Status=2, MTMSN=0
2019-02-06T21:44:36.792Z,1549489476.792 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:44:40.188Z,1549489480.188 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-02-06T21:44:45.380Z,1549489485.380 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20190206T203829/Express0023.lzma
2019-02-06T21:44:46.133Z,1549489486.133 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0023.lzma.bak
2019-02-06T21:44:46.133Z,1549489486.133 [DataOverHttps](INFO): SBD MOMSN=9637754
2019-02-06T21:44:48.533Z,1549489488.533 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T21:44:48.533Z,1549489488.533 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T21:44:48.533Z,1549489488.533 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T21:44:52.803Z,1549489492.803 [NAL9602](INFO): SBD MO Status=2, MOMSN=2082, MT Status=2, MTMSN=0
2019-02-06T21:44:52.803Z,1549489492.803 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T21:45:00.429Z,1549489500.429 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-02-06T21:45:00.430Z,1549489500.430 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +0.00,     ,        +0.00,   0.00,3965.37
2019-02-06T21:45:02.398Z,1549489502.398 [NAL9602](INFO): SBD MO Status=0, MOMSN=2082, MT Status=0, MTMSN=0
2019-02-06T21:45:02.398Z,1549489502.398 [NAL9602](INFO): No messages in MT queue
2019-02-06T21:45:11.633Z,1549489511.633 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 18
2019-02-06T21:45:11.633Z,1549489511.633 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:45:11.663Z,1549489511.663 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:45:12.084Z,1549489512.084 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:45:12.084Z,1549489512.084 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 18
2019-02-06T21:45:32.892Z,1549489532.892 [NAL9602](INFO): Powering down
2019-02-06T21:48:12.476Z,1549489692.476 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 19
2019-02-06T21:48:12.476Z,1549489692.476 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:48:12.514Z,1549489692.514 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:48:12.933Z,1549489692.933 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T21:48:12.933Z,1549489692.933 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 19
2019-02-06T21:49:48.969Z,1549489788.969 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T21:49:48.969Z,1549489788.969 [Default:CheckIn:C.Wait] Stopped
2019-02-06T21:49:48.969Z,1549489788.969 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T21:49:48.969Z,1549489788.969 [Default:CheckIn:D] Running Loop=1
2019-02-06T21:49:49.366Z,1549489789.366 [Default:CheckIn:D] Stopped
2019-02-06T21:49:49.366Z,1549489789.366 [Default:CheckIn:E] Running Loop=1
2019-02-06T21:49:49.764Z,1549489789.764 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.965397 min 
2019-02-06T21:49:49.764Z,1549489789.764 [Default:CheckIn:E] Stopped
2019-02-06T21:49:49.764Z,1549489789.764 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T21:49:49.764Z,1549489789.764 [Default:CheckIn] Stopped
2019-02-06T21:49:49.764Z,1549489789.764 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T21:49:49.765Z,1549489789.765 [Default:CheckIn](INFO): Running loop #8
2019-02-06T21:49:49.765Z,1549489789.765 [Default:CheckIn] Running Loop=8
2019-02-06T21:49:49.765Z,1549489789.765 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T21:49:49.765Z,1549489789.765 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T21:49:50.520Z,1549489790.520 [NAL9602](INFO): Powering up
2019-02-06T21:50:01.333Z,1549489801.333 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:50:02.138Z,1549489802.138 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:50:23.121Z,1549489823.121 [RDI_Pathfinder](ERROR): Failed to parse:
:H8d,0ffe, 15.672, -0.050, 0.000
2019-02-06T21:51:13.481Z,1549489873.481 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 20
2019-02-06T21:51:13.481Z,1549489873.481 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T21:51:13.530Z,1549489873.530 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:51:13.530Z,1549489873.530 [CBIT](FAULT): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T21:51:56.002Z,1549489916.002 [NAL9602](INFO): SBD MO Status=0, MOMSN=2083, MT Status=0, MTMSN=0
2019-02-06T21:51:56.002Z,1549489916.002 [NAL9602](INFO): No messages in MT queue
2019-02-06T21:51:56.790Z,1549489916.790 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T21:51:56.790Z,1549489916.790 [NAL9602] Data Fault, FailCount= 1
2019-02-06T21:51:56.790Z,1549489916.790 [NAL9602](ERROR): Data Fault
2019-02-06T21:51:56.882Z,1549489916.882 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T21:51:57.200Z,1549489917.200 [NAL9602](INFO): Powering down
2019-02-06T21:51:58.068Z,1549489918.068 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T21:51:58.068Z,1549489918.068 [NAL9602] No Fault, FailCount= 1
2019-02-06T21:52:30.394Z,1549489950.394 [NAL9602](INFO): Powering up NAL9602
2019-02-06T21:52:41.328Z,1549489961.328 [NAL9602](INFO): NAL9602 initialized
2019-02-06T21:52:42.139Z,1549489962.139 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:52:44.959Z,1549489964.959 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:52:48.151Z,1549489968.151 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:52:50.543Z,1549489970.543 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:52:52.671Z,1549489972.671 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-02-06T21:52:52.947Z,1549489972.947 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:52:55.747Z,1549489975.747 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:52:58.943Z,1549489978.943 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:01.739Z,1549489981.739 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:06.251Z,1549489986.251 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:08.643Z,1549489988.643 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:09.480Z,1549489989.480 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-02-06T21:53:11.451Z,1549489991.451 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:14.647Z,1549489994.647 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:17.443Z,1549489997.443 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:20.647Z,1549490000.647 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:23.443Z,1549490003.443 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:26.651Z,1549490006.651 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:29.450Z,1549490009.450 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:32.643Z,1549490012.643 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:35.455Z,1549490015.455 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:41.447Z,1549490021.447 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:44.635Z,1549490024.635 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:47.427Z,1549490027.427 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:50.631Z,1549490030.631 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:53.435Z,1549490033.435 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:55.423Z,1549490035.423 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:53:57.427Z,1549490037.427 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:00.625Z,1549490040.625 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:03.427Z,1549490043.427 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:06.627Z,1549490046.627 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:09.427Z,1549490049.427 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:11.431Z,1549490051.431 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:16.811Z,1549490056.811 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:19.603Z,1549490059.603 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:22.819Z,1549490062.819 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:25.603Z,1549490065.603 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:28.811Z,1549490068.811 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:31.603Z,1549490071.603 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:34.813Z,1549490074.813 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:37.611Z,1549490077.611 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:40.814Z,1549490080.814 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:43.607Z,1549490083.607 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:46.824Z,1549490086.824 [NAL9602](DEBUG): Fix Requested
2019-02-06T21:54:51.768Z,1549490091.768 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T21:49:49.8Z
2019-02-06T21:54:51.768Z,1549490091.768 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T21:54:51.768Z,1549490091.768 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T21:54:57.439Z,1549490097.439 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20190206T203829/Courier0025.lzma
2019-02-06T21:54:58.206Z,1549490098.206 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0025.lzma.bak
2019-02-06T21:54:58.206Z,1549490098.206 [DataOverHttps](INFO): SBD MOMSN=9637770
2019-02-06T21:55:09.261Z,1549490109.261 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20190206T203829/Express0026.lzma
2019-02-06T21:55:10.013Z,1549490110.013 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0026.lzma.bak
2019-02-06T21:55:10.013Z,1549490110.013 [DataOverHttps](INFO): SBD MOMSN=9637772
2019-02-06T21:55:12.374Z,1549490112.374 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T21:55:12.374Z,1549490112.374 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T21:55:12.375Z,1549490112.375 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T21:55:22.160Z,1549490122.160 [NAL9602](INFO): Powering down
2019-02-06T22:00:12.766Z,1549490412.766 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T22:00:12.766Z,1549490412.766 [Default:CheckIn:C.Wait] Stopped
2019-02-06T22:00:12.766Z,1549490412.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T22:00:12.766Z,1549490412.766 [Default:CheckIn:D] Running Loop=1
2019-02-06T22:00:13.166Z,1549490413.166 [Default:CheckIn:D] Stopped
2019-02-06T22:00:13.166Z,1549490413.166 [Default:CheckIn:E] Running Loop=1
2019-02-06T22:00:13.577Z,1549490413.577 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.362069 min 
2019-02-06T22:00:13.577Z,1549490413.577 [Default:CheckIn:E] Stopped
2019-02-06T22:00:13.577Z,1549490413.577 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T22:00:13.577Z,1549490413.577 [Default:CheckIn] Stopped
2019-02-06T22:00:13.577Z,1549490413.577 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T22:00:13.578Z,1549490413.578 [Default:CheckIn](INFO): Running loop #9
2019-02-06T22:00:13.578Z,1549490413.578 [Default:CheckIn] Running Loop=9
2019-02-06T22:00:13.578Z,1549490413.578 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T22:00:13.578Z,1549490413.578 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T22:00:14.328Z,1549490414.328 [NAL9602](INFO): Powering up
2019-02-06T22:00:25.545Z,1549490425.545 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:00:26.339Z,1549490426.339 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:01:51.501Z,1549490511.501 [NAL9602](INFO): SBD MO Status=2, MOMSN=2084, MT Status=2, MTMSN=0
2019-02-06T22:01:51.502Z,1549490511.502 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:02:31.217Z,1549490551.217 [NAL9602](INFO): SBD MO Status=2, MOMSN=2084, MT Status=2, MTMSN=0
2019-02-06T22:02:31.218Z,1549490551.218 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:02:54.023Z,1549490574.023 [NAL9602](INFO): SBD MO Status=2, MOMSN=2084, MT Status=2, MTMSN=0
2019-02-06T22:02:54.023Z,1549490574.023 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:03:21.354Z,1549490601.354 [NAL9602](INFO): SBD MO Status=2, MOMSN=2084, MT Status=2, MTMSN=0
2019-02-06T22:03:21.354Z,1549490601.354 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:03:51.566Z,1549490631.566 [NAL9602](INFO): SBD MO Status=2, MOMSN=2084, MT Status=2, MTMSN=0
2019-02-06T22:03:51.566Z,1549490631.566 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:04:28.934Z,1549490668.934 [NAL9602](INFO): SBD MO Status=0, MOMSN=2084, MT Status=0, MTMSN=0
2019-02-06T22:04:28.934Z,1549490668.934 [NAL9602](INFO): No messages in MT queue
2019-02-06T22:04:29.728Z,1549490669.728 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T22:04:29.728Z,1549490669.728 [NAL9602] Data Fault, FailCount= 1
2019-02-06T22:04:29.728Z,1549490669.728 [NAL9602](ERROR): Data Fault
2019-02-06T22:04:29.826Z,1549490669.826 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T22:04:30.128Z,1549490670.128 [NAL9602](INFO): Powering down
2019-02-06T22:04:31.022Z,1549490671.022 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T22:04:31.022Z,1549490671.022 [NAL9602] No Fault, FailCount= 1
2019-02-06T22:05:00.248Z,1549490700.248 [NAL9602](INFO): Powering up NAL9602
2019-02-06T22:05:11.056Z,1549490711.056 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:05:11.871Z,1549490711.871 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:05:15.098Z,1549490715.098 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T22:00:13.6Z
2019-02-06T22:05:15.098Z,1549490715.098 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T22:05:15.098Z,1549490715.098 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T22:05:20.928Z,1549490720.928 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0028.lzma
2019-02-06T22:05:21.693Z,1549490721.693 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0028.lzma.bak
2019-02-06T22:05:21.693Z,1549490721.693 [DataOverHttps](INFO): SBD MOMSN=9637784
2019-02-06T22:05:24.089Z,1549490724.089 [RDI_Pathfinder](ERROR): Failed to parse:

2019-02-06T22:05:32.377Z,1549490732.377 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20190206T203829/Express0029.lzma
2019-02-06T22:05:33.158Z,1549490733.158 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0029.lzma.bak
2019-02-06T22:05:33.159Z,1549490733.159 [DataOverHttps](INFO): SBD MOMSN=9637786
2019-02-06T22:05:35.665Z,1549490735.665 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T22:05:35.665Z,1549490735.665 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T22:05:35.665Z,1549490735.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T22:05:46.849Z,1549490746.849 [NAL9602](INFO): Powering down
2019-02-06T22:06:18.029Z,1549490778.029 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:06:18.029Z,1549490778.029 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 20
2019-02-06T22:09:18.305Z,1549490958.305 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-02-06T22:09:18.306Z,1549490958.306 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:09:18.321Z,1549490958.321 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:09:18.724Z,1549490958.724 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:09:18.724Z,1549490958.724 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-02-06T22:10:36.128Z,1549491036.128 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T22:10:36.128Z,1549491036.128 [Default:CheckIn:C.Wait] Stopped
2019-02-06T22:10:36.128Z,1549491036.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T22:10:36.128Z,1549491036.128 [Default:CheckIn:D] Running Loop=1
2019-02-06T22:10:36.528Z,1549491036.528 [Default:CheckIn:D] Stopped
2019-02-06T22:10:36.528Z,1549491036.528 [Default:CheckIn:E] Running Loop=1
2019-02-06T22:10:36.929Z,1549491036.929 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.751424 min 
2019-02-06T22:10:36.929Z,1549491036.929 [Default:CheckIn:E] Stopped
2019-02-06T22:10:36.930Z,1549491036.930 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T22:10:36.930Z,1549491036.930 [Default:CheckIn] Stopped
2019-02-06T22:10:36.930Z,1549491036.930 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T22:10:36.930Z,1549491036.930 [Default:CheckIn](INFO): Running loop #10
2019-02-06T22:10:36.930Z,1549491036.930 [Default:CheckIn] Running Loop=10
2019-02-06T22:10:36.930Z,1549491036.930 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T22:10:36.930Z,1549491036.930 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T22:10:37.681Z,1549491037.681 [NAL9602](INFO): Powering up
2019-02-06T22:10:48.890Z,1549491048.890 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:10:49.691Z,1549491049.691 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:10:59.190Z,1549491059.190 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,-32768,-32768,-32768,V
2019-02-06T22:11:03.587Z,1549491063.587 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2019-02-06T22:11:03.588Z,1549491063.588 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,-32768,32768,-32768,V
2019-02-06T22:11:34.245Z,1549491094.245 [NAL9602](INFO): SBD MO Status=2, MOMSN=2085, MT Status=2, MTMSN=0
2019-02-06T22:11:34.246Z,1549491094.246 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:12:19.317Z,1549491139.317 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-02-06T22:12:19.317Z,1549491139.317 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:12:19.359Z,1549491139.359 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:12:19.680Z,1549491139.680 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:12:19.681Z,1549491139.681 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-02-06T22:12:58.625Z,1549491178.625 [NAL9602](INFO): SBD MO Status=2, MOMSN=2085, MT Status=2, MTMSN=0
2019-02-06T22:12:58.626Z,1549491178.626 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-02-06T22:15:20.158Z,1549491320.158 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-02-06T22:15:20.158Z,1549491320.158 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:15:20.185Z,1549491320.185 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:15:20.560Z,1549491320.560 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:15:20.560Z,1549491320.560 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-02-06T22:15:37.972Z,1549491337.972 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T22:10:36.9Z
2019-02-06T22:15:37.972Z,1549491337.972 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T22:15:37.972Z,1549491337.972 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T22:15:43.655Z,1549491343.655 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0031.lzma
2019-02-06T22:15:44.426Z,1549491344.426 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0031.lzma.bak
2019-02-06T22:15:44.426Z,1549491344.426 [DataOverHttps](INFO): SBD MOMSN=9637798
2019-02-06T22:15:55.753Z,1549491355.753 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T22:15:56.013Z,1549491356.013 [DataOverHttps](INFO): Sending 121 bytes from file Logs/20190206T203829/Express0032.lzma
2019-02-06T22:15:56.762Z,1549491356.762 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0032.lzma.bak
2019-02-06T22:15:56.762Z,1549491356.762 [DataOverHttps](INFO): SBD MOMSN=9637800
2019-02-06T22:16:01.908Z,1549491361.908 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T22:16:01.908Z,1549491361.908 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T22:16:01.909Z,1549491361.909 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T22:16:07.628Z,1549491367.628 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-02-06T22:16:26.076Z,1549491386.076 [NAL9602](INFO): Powering down
2019-02-06T22:18:21.248Z,1549491501.248 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-02-06T22:18:21.248Z,1549491501.248 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:18:21.278Z,1549491501.278 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:18:21.660Z,1549491501.660 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:18:21.661Z,1549491501.661 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-02-06T22:21:02.411Z,1549491662.411 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T22:21:02.411Z,1549491662.411 [Default:CheckIn:C.Wait] Stopped
2019-02-06T22:21:02.411Z,1549491662.411 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T22:21:02.411Z,1549491662.411 [Default:CheckIn:D] Running Loop=1
2019-02-06T22:21:02.786Z,1549491662.786 [Default:CheckIn:D] Stopped
2019-02-06T22:21:02.811Z,1549491662.811 [Default:CheckIn:E] Running Loop=1
2019-02-06T22:21:03.246Z,1549491663.246 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.189071 min 
2019-02-06T22:21:03.247Z,1549491663.247 [Default:CheckIn:E] Stopped
2019-02-06T22:21:03.247Z,1549491663.247 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T22:21:03.247Z,1549491663.247 [Default:CheckIn] Stopped
2019-02-06T22:21:03.247Z,1549491663.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T22:21:03.247Z,1549491663.247 [Default:CheckIn](INFO): Running loop #11
2019-02-06T22:21:03.247Z,1549491663.247 [Default:CheckIn] Running Loop=11
2019-02-06T22:21:03.247Z,1549491663.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T22:21:03.248Z,1549491663.248 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T22:21:03.956Z,1549491663.956 [NAL9602](INFO): Powering up
2019-02-06T22:21:14.764Z,1549491674.764 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:21:15.557Z,1549491675.557 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T22:21:15.557Z,1549491675.557 [NAL9602] Data Fault, FailCount= 2
2019-02-06T22:21:15.557Z,1549491675.557 [NAL9602](ERROR): Data Fault
2019-02-06T22:21:15.644Z,1549491675.644 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T22:21:15.956Z,1549491675.956 [NAL9602](INFO): Powering down
2019-02-06T22:21:16.810Z,1549491676.810 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T22:21:16.810Z,1549491676.810 [NAL9602] No Fault, FailCount= 2
2019-02-06T22:21:22.489Z,1549491682.489 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-02-06T22:21:22.489Z,1549491682.489 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:21:22.503Z,1549491682.503 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:21:22.651Z,1549491682.651 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:21:22.652Z,1549491682.652 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-02-06T22:21:46.124Z,1549491706.124 [NAL9602](INFO): Powering up NAL9602
2019-02-06T22:21:58.240Z,1549491718.240 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:21:59.043Z,1549491719.043 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:23:57.228Z,1549491837.228 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-02-06T22:23:57.228Z,1549491837.228 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19020615310447,35.448.9,  0
2019-02-06T22:24:07.209Z,1549491847.209 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 2768
2019-02-06T22:24:07.210Z,1549491847.210 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,-32768,-32768,-32768,V
2019-02-06T22:24:23.074Z,1549491863.074 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-02-06T22:24:23.074Z,1549491863.074 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:24:23.101Z,1549491863.101 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:24:23.477Z,1549491863.477 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:24:23.477Z,1549491863.477 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-02-06T22:26:07.078Z,1549491967.078 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T22:21:03.2Z
2019-02-06T22:26:07.078Z,1549491967.078 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T22:26:07.078Z,1549491967.078 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T22:26:15.748Z,1549491975.748 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0034.lzma
2019-02-06T22:26:16.518Z,1549491976.518 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0034.lzma.bak
2019-02-06T22:26:16.518Z,1549491976.518 [DataOverHttps](INFO): SBD MOMSN=9637811
2019-02-06T22:26:27.579Z,1549491987.579 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190206T203829/Express0035.lzma
2019-02-06T22:26:28.357Z,1549491988.357 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0035.lzma.bak
2019-02-06T22:26:28.357Z,1549491988.357 [DataOverHttps](INFO): SBD MOMSN=9637814
2019-02-06T22:26:30.822Z,1549491990.822 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T22:26:30.822Z,1549491990.822 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T22:26:30.822Z,1549491990.822 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T22:27:01.400Z,1549492021.400 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T22:27:23.972Z,1549492043.972 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2019-02-06T22:27:23.972Z,1549492043.972 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:27:23.986Z,1549492043.986 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:27:24.383Z,1549492044.383 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:27:24.384Z,1549492044.384 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2019-02-06T22:27:31.920Z,1549492051.920 [NAL9602](INFO): Powering down
2019-02-06T22:30:24.993Z,1549492224.993 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2019-02-06T22:30:24.993Z,1549492224.993 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:30:25.018Z,1549492225.018 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:30:25.413Z,1549492225.413 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:30:25.413Z,1549492225.413 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2019-02-06T22:31:31.306Z,1549492291.306 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T22:31:31.309Z,1549492291.309 [Default:CheckIn:C.Wait] Stopped
2019-02-06T22:31:31.309Z,1549492291.309 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T22:31:31.309Z,1549492291.309 [Default:CheckIn:D] Running Loop=1
2019-02-06T22:31:31.705Z,1549492291.705 [Default:CheckIn:D] Stopped
2019-02-06T22:31:31.705Z,1549492291.705 [Default:CheckIn:E] Running Loop=1
2019-02-06T22:31:32.110Z,1549492292.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.671045 min 
2019-02-06T22:31:32.110Z,1549492292.110 [Default:CheckIn:E] Stopped
2019-02-06T22:31:32.110Z,1549492292.110 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T22:31:32.110Z,1549492292.110 [Default:CheckIn] Stopped
2019-02-06T22:31:32.110Z,1549492292.110 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T22:31:32.111Z,1549492292.111 [Default:CheckIn](INFO): Running loop #12
2019-02-06T22:31:32.111Z,1549492292.111 [Default:CheckIn] Running Loop=12
2019-02-06T22:31:32.111Z,1549492292.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T22:31:32.111Z,1549492292.111 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T22:31:32.864Z,1549492292.864 [NAL9602](INFO): Powering up
2019-02-06T22:31:43.660Z,1549492303.660 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:31:44.475Z,1549492304.475 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:32:15.440Z,1549492335.440 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-02-06T22:32:15.515Z,1549492335.515 [NAL9602](FAULT): received: 
+CSQ:0

OK85, 2, 0, 0, 0

OK
2019-02-06T22:32:15.515Z,1549492335.515 [NAL9602] Data Fault, FailCount= 3
2019-02-06T22:32:15.515Z,1549492335.515 [NAL9602](ERROR): Data Fault
2019-02-06T22:32:15.602Z,1549492335.602 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T22:32:15.852Z,1549492335.852 [NAL9602](INFO): Powering down
2019-02-06T22:32:16.715Z,1549492336.715 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T22:32:16.715Z,1549492336.715 [NAL9602] No Fault, FailCount= 3
2019-02-06T22:32:46.020Z,1549492366.020 [NAL9602](INFO): Powering up NAL9602
2019-02-06T22:32:57.216Z,1549492377.216 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:32:58.016Z,1549492378.016 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T22:32:58.017Z,1549492378.017 [NAL9602] Data Fault, FailCount= 4
2019-02-06T22:32:58.017Z,1549492378.017 [NAL9602](ERROR): Data Fault
2019-02-06T22:32:58.111Z,1549492378.111 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T22:32:58.412Z,1549492378.412 [NAL9602](INFO): Powering down
2019-02-06T22:33:02.132Z,1549492382.132 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T22:33:02.132Z,1549492382.132 [NAL9602] No Fault, FailCount= 4
2019-02-06T22:33:25.789Z,1549492405.789 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2019-02-06T22:33:25.789Z,1549492405.789 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:33:25.862Z,1549492405.862 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:33:26.224Z,1549492406.224 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:33:26.224Z,1549492406.224 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2019-02-06T22:33:28.552Z,1549492408.552 [NAL9602](INFO): Powering up NAL9602
2019-02-06T22:33:39.450Z,1549492419.450 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:33:40.271Z,1549492420.271 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:36:26.887Z,1549492586.887 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2019-02-06T22:36:26.887Z,1549492586.887 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:36:26.930Z,1549492586.930 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:36:27.313Z,1549492587.313 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:36:27.313Z,1549492587.313 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2019-02-06T22:36:33.322Z,1549492593.322 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T22:31:32.1Z
2019-02-06T22:36:33.322Z,1549492593.322 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T22:36:33.322Z,1549492593.322 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T22:36:39.045Z,1549492599.045 [RDI_Pathfinder](ERROR): Failed to parse:
:HM,G,D,0e8d,0ffe, 15.672, -0.035, 0.000
2019-02-06T22:36:39.113Z,1549492599.113 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190206T203829/Courier0037.lzma
2019-02-06T22:36:39.889Z,1549492599.889 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0037.lzma.bak
2019-02-06T22:36:39.890Z,1549492599.890 [DataOverHttps](INFO): SBD MOMSN=9637825
2019-02-06T22:36:47.480Z,1549492607.480 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T22:36:55.746Z,1549492615.746 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20190206T203829/Express0038.lzma
2019-02-06T22:36:56.525Z,1549492616.525 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0038.lzma.bak
2019-02-06T22:36:56.525Z,1549492616.525 [DataOverHttps](INFO): SBD MOMSN=9637831
2019-02-06T22:36:58.915Z,1549492618.915 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T22:36:58.916Z,1549492618.916 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T22:36:58.916Z,1549492618.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T22:37:20.818Z,1549492640.818 [NAL9602](INFO): Powering down
2019-02-06T22:37:50.212Z,1549492670.212 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-02-06T22:37:50.213Z,1549492670.213 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,            +0.00,   0.00,7130.85
2019-02-06T22:38:15.972Z,1549492695.972 [DeadReckonWithRespectToSeafloor](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-02-06T22:38:15.972Z,1549492695.972 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11
2019-02-06T22:38:15.972Z,1549492695.972 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:38:15.986Z,1549492695.986 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:38:16.389Z,1549492696.389 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:38:16.389Z,1549492696.389 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11
2019-02-06T22:41:16.978Z,1549492876.978 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 12
2019-02-06T22:41:16.978Z,1549492876.978 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:41:17.024Z,1549492877.024 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:41:17.332Z,1549492877.332 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:41:17.332Z,1549492877.332 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 12
2019-02-06T22:41:47.067Z,1549492907.067 [DeadReckonWithRespectToSeafloor](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-02-06T22:41:47.068Z,1549492907.068 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 13
2019-02-06T22:41:47.068Z,1549492907.068 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:41:47.093Z,1549492907.093 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:41:47.474Z,1549492907.474 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:41:47.474Z,1549492907.474 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 13
2019-02-06T22:41:59.447Z,1549492919.447 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-02-06T22:41:59.447Z,1549492919.447 [Default:CheckIn:C.Wait] Stopped
2019-02-06T22:41:59.447Z,1549492919.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T22:41:59.447Z,1549492919.447 [Default:CheckIn:D] Running Loop=1
2019-02-06T22:41:59.884Z,1549492919.884 [Default:CheckIn:D] Stopped
2019-02-06T22:41:59.884Z,1549492919.884 [Default:CheckIn:E] Running Loop=1
2019-02-06T22:42:00.252Z,1549492920.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.140690 min 
2019-02-06T22:42:00.252Z,1549492920.252 [Default:CheckIn:E] Stopped
2019-02-06T22:42:00.252Z,1549492920.252 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-02-06T22:42:00.253Z,1549492920.253 [Default:CheckIn] Stopped
2019-02-06T22:42:00.253Z,1549492920.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T22:42:00.253Z,1549492920.253 [Default:CheckIn](INFO): Running loop #13
2019-02-06T22:42:00.253Z,1549492920.253 [Default:CheckIn] Running Loop=13
2019-02-06T22:42:00.253Z,1549492920.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-02-06T22:42:00.253Z,1549492920.253 [Default:CheckIn:Read_GPS] Running Loop=1
2019-02-06T22:42:01.012Z,1549492921.012 [NAL9602](INFO): Powering up
2019-02-06T22:42:11.812Z,1549492931.812 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:42:12.625Z,1549492932.625 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:42:43.556Z,1549492963.556 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-02-06T22:42:43.631Z,1549492963.631 [NAL9602](FAULT): received: 
+CSQ:0

OK85, 2, 0, 0, 0

OK
2019-02-06T22:42:43.631Z,1549492963.631 [NAL9602] Data Fault, FailCount= 5
2019-02-06T22:42:43.631Z,1549492963.631 [NAL9602](ERROR): Data Fault
2019-02-06T22:42:43.720Z,1549492963.720 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T22:42:43.721Z,1549492963.721 [CBIT](CRITICAL): Data Fault in component: NAL9602
2019-02-06T22:42:43.952Z,1549492963.952 [NAL9602](INFO): Powering down
2019-02-06T22:42:44.043Z,1549492964.043 [CommandLine](FAULT): Scheduling is paused
2019-02-06T22:42:44.044Z,1549492964.044 [CBIT](INFO): Critical error at 20190206T224243
2019-02-06T22:42:44.044Z,1549492964.044 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-02-06T22:44:44.028Z,1549493084.028 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T22:44:44.028Z,1549493084.028 [NAL9602] No Fault, FailCount= 5
2019-02-06T22:44:44.332Z,1549493084.332 [NAL9602](INFO): Powering up NAL9602
2019-02-06T22:44:47.997Z,1549493087.997 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 14
2019-02-06T22:44:47.997Z,1549493087.997 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:44:48.023Z,1549493088.023 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:44:48.407Z,1549493088.407 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:44:48.407Z,1549493088.407 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 14
2019-02-06T22:44:55.134Z,1549493095.134 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:44:55.932Z,1549493095.932 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-02-06T22:44:55.932Z,1549493095.932 [NAL9602] Data Fault, FailCount= 1
2019-02-06T22:44:55.932Z,1549493095.932 [NAL9602](ERROR): Data Fault
2019-02-06T22:44:56.002Z,1549493096.002 [CBIT](ERROR): Data Fault in component: NAL9602
2019-02-06T22:44:56.332Z,1549493096.332 [NAL9602](INFO): Powering down
2019-02-06T22:44:57.212Z,1549493097.212 [CBIT](INFO): Clearing failed state for component NAL9602
2019-02-06T22:44:57.213Z,1549493097.213 [NAL9602] No Fault, FailCount= 1
2019-02-06T22:45:26.400Z,1549493126.400 [NAL9602](INFO): Powering up NAL9602
2019-02-06T22:45:37.209Z,1549493137.209 [NAL9602](INFO): NAL9602 initialized
2019-02-06T22:45:38.013Z,1549493138.013 [NAL9602](DEBUG): Fix Requested
2019-02-06T22:47:01.785Z,1549493221.785 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-02-06T22:42:00.3Z
2019-02-06T22:47:01.785Z,1549493221.785 [Default:CheckIn:Read_GPS] Stopped
2019-02-06T22:47:01.785Z,1549493221.785 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-02-06T22:47:07.488Z,1549493227.488 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20190206T203829/Courier0040.lzma
2019-02-06T22:47:08.253Z,1549493228.253 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Courier0040.lzma.bak
2019-02-06T22:47:08.254Z,1549493228.254 [DataOverHttps](INFO): SBD MOMSN=9637847
2019-02-06T22:47:15.440Z,1549493235.440 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-02-06T22:47:19.294Z,1549493239.294 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20190206T203829/Express0041.lzma
2019-02-06T22:47:20.037Z,1549493240.037 [DataOverHttps](INFO): Moved sent file to Logs/20190206T203829/Express0041.lzma.bak
2019-02-06T22:47:20.038Z,1549493240.038 [DataOverHttps](INFO): SBD MOMSN=9637850
2019-02-06T22:47:22.416Z,1549493242.416 [Default:CheckIn:Read_Iridium] Stopped
2019-02-06T22:47:22.416Z,1549493242.416 [Default:CheckIn:C.Wait] Running Loop=1
2019-02-06T22:47:22.416Z,1549493242.416 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-02-06T22:47:46.188Z,1549493266.188 [NAL9602](INFO): Powering down
2019-02-06T22:47:49.037Z,1549493269.037 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 15
2019-02-06T22:47:49.037Z,1549493269.037 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:47:49.079Z,1549493269.079 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:47:49.476Z,1549493269.476 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:47:49.476Z,1549493269.476 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 15
2019-02-06T22:47:54.263Z,1549493274.263 [DeadReckonWithRespectToSeafloor](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2019-02-06T22:47:54.263Z,1549493274.263 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 16
2019-02-06T22:47:54.263Z,1549493274.263 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-02-06T22:47:54.276Z,1549493274.276 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-02-06T22:47:54.643Z,1549493274.643 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-02-06T22:47:54.643Z,1549493274.643 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 16
2019-02-06T22:48:24.472Z,1549493304.472 [CommandLine](IMPORTANT): got command restart application
2019-02-06T22:48:25.498Z,1549493305.498 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T22:48:25.498Z,1549493305.498 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:25.659Z,1549493305.659 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-02-06T22:48:25.659Z,1549493305.659 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:25.659Z,1549493305.659 [CommandLine](INFO): Join timeout helper Thread ID is 1057
2019-02-06T22:48:25.660Z,1549493305.660 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-02-06T22:48:25.660Z,1549493305.660 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:25.660Z,1549493305.660 [NavChartDb](INFO): Join timeout helper Thread ID is 1058
2019-02-06T22:48:25.751Z,1549493305.751 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T22:48:25.751Z,1549493305.751 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:25.759Z,1549493305.759 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-02-06T22:48:25.759Z,1549493305.759 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:25.759Z,1549493305.759 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1059
2019-02-06T22:48:25.895Z,1549493305.895 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T22:48:25.895Z,1549493305.895 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-02-06T22:48:25.896Z,1549493305.896 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:25.907Z,1549493305.907 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-02-06T22:48:25.907Z,1549493305.907 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:25.907Z,1549493305.907 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1060
2019-02-06T22:48:26.055Z,1549493306.055 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T22:48:26.055Z,1549493306.055 [CTD_NeilBrown](INFO): Powering down
2019-02-06T22:48:26.071Z,1549493306.071 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.091Z,1549493306.091 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-02-06T22:48:26.091Z,1549493306.091 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.091Z,1549493306.091 [Radio_Surface](INFO): Join timeout helper Thread ID is 1061
2019-02-06T22:48:26.179Z,1549493306.179 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T22:48:26.179Z,1549493306.179 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.192Z,1549493306.192 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-02-06T22:48:26.192Z,1549493306.192 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.192Z,1549493306.192 [logger](INFO): Join timeout helper Thread ID is 1062
2019-02-06T22:48:26.215Z,1549493306.215 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T22:48:26.215Z,1549493306.215 [logger ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.231Z,1549493306.231 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-02-06T22:48:26.232Z,1549493306.232 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.232Z,1549493306.232 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-02-06T22:48:26.232Z,1549493306.232 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.232Z,1549493306.232 [controlThread](INFO): Join timeout helper Thread ID is 1063
2019-02-06T22:48:26.259Z,1549493306.259 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-02-06T22:48:26.259Z,1549493306.259 [controlThread](DEBUG): Uninitializing ControlThread
2019-02-06T22:48:26.260Z,1549493306.260 [AHRS_M2](INFO): Powering down
2019-02-06T22:48:26.332Z,1549493306.332 [NAL9602](INFO): Powering down
2019-02-06T22:48:26.403Z,1549493306.403 [RDI_Pathfinder](INFO): Powering down
2019-02-06T22:48:26.404Z,1549493306.404 [RDI_PathfinderUp](INFO): Powering down
2019-02-06T22:48:26.405Z,1549493306.405 [Aanderaa_O2](INFO): Powering down
2019-02-06T22:48:26.406Z,1549493306.406 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-02-06T22:48:26.407Z,1549493306.407 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-02-06T22:48:26.408Z,1549493306.408 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-02-06T22:48:26.408Z,1549493306.408 [MissionManager](INFO): Uninitializing Mission Default
2019-02-06T22:48:26.408Z,1549493306.408 [Default] Stopped
2019-02-06T22:48:26.408Z,1549493306.408 [Default](DEBUG): Aggregate::uninitialize Default
2019-02-06T22:48:26.408Z,1549493306.408 [Default:B.GoToSurface] Stopped
2019-02-06T22:48:26.408Z,1549493306.408 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-02-06T22:48:26.409Z,1549493306.409 [Default:CheckIn] Stopped
2019-02-06T22:48:26.409Z,1549493306.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-02-06T22:48:26.409Z,1549493306.409 [Default:CheckIn:C.Wait] Stopped
2019-02-06T22:48:26.409Z,1549493306.409 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-02-06T22:48:26.412Z,1549493306.412 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-02-06T22:48:26.412Z,1549493306.412 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-02-06T22:48:26.412Z,1549493306.412 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-02-06T22:48:26.413Z,1549493306.413 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-02-06T22:48:26.413Z,1549493306.413 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-02-06T22:48:26.413Z,1549493306.413 [BuoyancyServo](INFO): Powering down
2019-02-06T22:48:26.427Z,1549493306.427 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-02-06T22:48:26.427Z,1549493306.427 [ElevatorServo](INFO): Powering down
2019-02-06T22:48:26.428Z,1549493306.428 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-02-06T22:48:26.428Z,1549493306.428 [MassServo](INFO): Powering down
2019-02-06T22:48:26.429Z,1549493306.429 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-02-06T22:48:26.429Z,1549493306.429 [RudderServo](INFO): Powering down
2019-02-06T22:48:26.430Z,1549493306.430 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-02-06T22:48:26.430Z,1549493306.430 [ThrusterServo](INFO): Powering down
2019-02-06T22:48:26.431Z,1549493306.431 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-02-06T22:48:26.431Z,1549493306.431 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-02-06T22:48:26.431Z,1549493306.431 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-02-06T22:48:26.431Z,1549493306.431 [CBIT](DEBUG): Powering off loads.
2019-02-06T22:48:26.443Z,1549493306.443 [CBIT](DEBUG): Disabling WDT.
2019-02-06T22:48:26.455Z,1549493306.455 [CBIT](DEBUG): Opening all GF detection circuits.
2019-02-06T22:48:26.455Z,1549493306.455 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.564Z,1549493306.564 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.606Z,1549493306.606 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.608Z,1549493306.608 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.630Z,1549493306.630 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-02-06T22:48:26.697Z,1549493306.697 [logger ThreadHandler](INFO): Thread cancelled.