2019-03-14T21:26:00.721Z,1552598760.721 [Supervisor](DEBUG): Initializing supervisor.
2019-03-14T21:26:00.724Z,1552598760.724 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-14T21:26:00.724Z,1552598760.724 [SyncHandler](INFO): Protected caller Thread ID is 1357
2019-03-14T21:26:00.725Z,1552598760.725 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-14T21:26:00.726Z,1552598760.726 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-14T21:26:00.726Z,1552598760.726 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1358
2019-03-14T21:26:00.730Z,1552598760.730 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-14T21:26:00.741Z,1552598760.741 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-14T21:26:00.742Z,1552598760.742 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-14T21:26:00.743Z,1552598760.743 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1359
2019-03-14T21:26:00.744Z,1552598760.744 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-14T21:26:00.745Z,1552598760.745 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-14T21:26:00.745Z,1552598760.745 [logger ThreadHandler](INFO): Protected caller Thread ID is 1360
2019-03-14T21:26:00.747Z,1552598760.747 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-14T21:26:00.747Z,1552598760.747 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-14T21:26:00.749Z,1552598760.749 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-14T21:26:01.201Z,1552598761.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-14T21:26:01.201Z,1552598761.201 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-14T21:26:01.310Z,1552598761.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-14T21:26:01.310Z,1552598761.310 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-14T21:26:01.649Z,1552598761.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-14T21:26:01.650Z,1552598761.650 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-14T21:26:01.796Z,1552598761.796 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-14T21:26:01.796Z,1552598761.796 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-14T21:26:01.994Z,1552598761.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-14T21:26:01.995Z,1552598761.995 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-14T21:26:02.469Z,1552598762.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-14T21:26:02.469Z,1552598762.469 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-14T21:26:02.687Z,1552598762.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-14T21:26:02.687Z,1552598762.687 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-14T21:26:02.841Z,1552598762.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-14T21:26:02.841Z,1552598762.841 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-14T21:26:03.045Z,1552598763.045 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-14T21:26:03.046Z,1552598763.046 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-14T21:26:03.144Z,1552598763.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-14T21:26:03.145Z,1552598763.145 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-14T21:26:03.465Z,1552598763.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-14T21:26:03.466Z,1552598763.466 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-14T21:26:03.547Z,1552598763.547 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-14T21:26:03.652Z,1552598763.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-14T21:26:03.652Z,1552598763.652 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-14T21:26:04.166Z,1552598764.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-14T21:26:04.167Z,1552598764.167 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-14T21:26:04.572Z,1552598764.572 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-14T21:26:04.574Z,1552598764.574 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-14T21:26:04.575Z,1552598764.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-14T21:26:04.791Z,1552598764.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-14T21:26:04.894Z,1552598764.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-14T21:26:04.994Z,1552598764.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-14T21:26:05.225Z,1552598765.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-14T21:26:05.226Z,1552598765.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-14T21:26:05.312Z,1552598765.312 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-14T21:26:05.407Z,1552598765.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-14T21:26:05.506Z,1552598765.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-14T21:26:05.590Z,1552598765.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-14T21:26:05.702Z,1552598765.702 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-14T21:26:05.882Z,1552598765.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-14T21:26:06.019Z,1552598766.019 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-14T21:26:06.019Z,1552598766.019 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-14T21:26:06.024Z,1552598766.024 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-14T21:26:06.345Z,1552598766.345 [DataOverHttps] Loaded
2019-03-14T21:26:06.345Z,1552598766.345 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-14T21:26:06.346Z,1552598766.346 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-14T21:26:06.347Z,1552598766.347 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1439
2019-03-14T21:26:06.386Z,1552598766.386 [Depth_Keller] Loaded
2019-03-14T21:26:06.386Z,1552598766.386 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-14T21:26:06.391Z,1552598766.391 [DropWeight] Loaded
2019-03-14T21:26:06.391Z,1552598766.391 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-14T21:26:06.439Z,1552598766.439 [DUSBL_Hydroid] Loaded
2019-03-14T21:26:06.439Z,1552598766.439 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-14T21:26:06.479Z,1552598766.479 [Micromodem] Loaded
2019-03-14T21:26:06.479Z,1552598766.479 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-14T21:26:06.575Z,1552598766.575 [NAL9602] Loaded
2019-03-14T21:26:06.575Z,1552598766.575 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-14T21:26:06.590Z,1552598766.590 [Onboard] Loaded
2019-03-14T21:26:06.591Z,1552598766.591 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-14T21:26:06.597Z,1552598766.597 [Radio_Surface] Loaded
2019-03-14T21:26:06.597Z,1552598766.597 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-14T21:26:06.599Z,1552598766.599 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-14T21:26:06.599Z,1552598766.599 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1440
2019-03-14T21:26:06.659Z,1552598766.659 [RDI_Pathfinder] Loaded
2019-03-14T21:26:06.659Z,1552598766.659 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-14T21:26:06.680Z,1552598766.680 [RDI_PathfinderUp] Loaded
2019-03-14T21:26:06.680Z,1552598766.680 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-14T21:26:09.086Z,1552598769.086 [BPC1] Loaded
2019-03-14T21:26:09.087Z,1552598769.087 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-14T21:26:09.087Z,1552598769.087 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-14T21:26:09.087Z,1552598769.087 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-14T21:26:09.100Z,1552598769.100 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-14T21:26:09.101Z,1552598769.101 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-14T21:26:09.205Z,1552598769.205 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-14T21:26:09.206Z,1552598769.206 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-14T21:26:09.264Z,1552598769.264 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-14T21:26:09.264Z,1552598769.264 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-14T21:26:09.324Z,1552598769.324 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-14T21:26:09.324Z,1552598769.324 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-14T21:26:09.344Z,1552598769.344 [NavChart] Loaded
2019-03-14T21:26:09.345Z,1552598769.345 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-14T21:26:09.349Z,1552598769.349 [UniversalFixResidualReporter] Loaded
2019-03-14T21:26:09.349Z,1552598769.349 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-14T21:26:09.349Z,1552598769.349 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-14T21:26:09.350Z,1552598769.350 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-14T21:26:09.450Z,1552598769.450 [BuoyancyServo] Loaded
2019-03-14T21:26:09.451Z,1552598769.451 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-14T21:26:09.466Z,1552598769.466 [ElevatorServo] Loaded
2019-03-14T21:26:09.466Z,1552598769.466 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-14T21:26:09.481Z,1552598769.481 [MassServo] Loaded
2019-03-14T21:26:09.481Z,1552598769.481 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-14T21:26:09.496Z,1552598769.496 [RudderServo] Loaded
2019-03-14T21:26:09.497Z,1552598769.497 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-14T21:26:09.511Z,1552598769.511 [ThrusterServo] Loaded
2019-03-14T21:26:09.511Z,1552598769.511 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-14T21:26:09.512Z,1552598769.512 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-14T21:26:09.512Z,1552598769.512 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-14T21:26:09.757Z,1552598769.757 [CTD_NeilBrown] Loaded
2019-03-14T21:26:09.758Z,1552598769.758 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-14T21:26:09.759Z,1552598769.759 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-14T21:26:09.759Z,1552598769.759 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1441
2019-03-14T21:26:09.803Z,1552598769.803 [WetLabsSeaOWL_UV_A] Loaded
2019-03-14T21:26:09.804Z,1552598769.804 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-14T21:26:09.805Z,1552598769.805 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-14T21:26:09.805Z,1552598769.805 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1442
2019-03-14T21:26:09.806Z,1552598769.806 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-14T21:26:09.806Z,1552598769.806 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-14T21:26:10.037Z,1552598770.037 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-14T21:26:10.038Z,1552598770.038 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-14T21:26:10.078Z,1552598770.078 [DepthRateCalculator] Loaded
2019-03-14T21:26:10.078Z,1552598770.078 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-14T21:26:10.084Z,1552598770.084 [PitchRateCalculator] Loaded
2019-03-14T21:26:10.084Z,1552598770.084 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-14T21:26:10.097Z,1552598770.097 [SpeedCalculator] Loaded
2019-03-14T21:26:10.097Z,1552598770.097 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-14T21:26:10.118Z,1552598770.118 [TempGradientCalculator] Loaded
2019-03-14T21:26:10.118Z,1552598770.118 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-14T21:26:10.124Z,1552598770.124 [YawRateCalculator] Loaded
2019-03-14T21:26:10.124Z,1552598770.124 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-14T21:26:10.164Z,1552598770.164 [ElevatorOffsetCalculator] Loaded
2019-03-14T21:26:10.164Z,1552598770.164 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-14T21:26:10.165Z,1552598770.165 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-14T21:26:10.165Z,1552598770.165 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-14T21:26:10.295Z,1552598770.295 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-14T21:26:10.318Z,1552598770.318 [SBIT] Loaded
2019-03-14T21:26:10.318Z,1552598770.318 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-14T21:26:10.319Z,1552598770.319 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-14T21:26:10.330Z,1552598770.330 [IBIT] Loaded
2019-03-14T21:26:10.331Z,1552598770.331 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-14T21:26:10.334Z,1552598770.334 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-14T21:26:10.473Z,1552598770.473 [CBIT] Loaded
2019-03-14T21:26:10.473Z,1552598770.473 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-14T21:26:10.474Z,1552598770.474 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-14T21:26:10.474Z,1552598770.474 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-14T21:26:10.536Z,1552598770.536 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-14T21:26:10.536Z,1552598770.536 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-14T21:26:10.631Z,1552598770.631 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-14T21:26:10.632Z,1552598770.632 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-14T21:26:10.697Z,1552598770.697 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-14T21:26:10.780Z,1552598770.780 [VerticalControl] Loaded
2019-03-14T21:26:10.780Z,1552598770.780 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-14T21:26:10.781Z,1552598770.781 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-14T21:26:10.838Z,1552598770.838 [HorizontalControl] Loaded
2019-03-14T21:26:10.839Z,1552598770.839 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-14T21:26:10.839Z,1552598770.839 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-14T21:26:10.841Z,1552598770.841 [SpeedControl] Loaded
2019-03-14T21:26:10.841Z,1552598770.841 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-14T21:26:10.842Z,1552598770.842 [LoopControl](DEBUG): Construct LoopControl.
2019-03-14T21:26:10.842Z,1552598770.842 [LoopControl] Loaded
2019-03-14T21:26:10.843Z,1552598770.843 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-14T21:26:10.843Z,1552598770.843 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-14T21:26:10.844Z,1552598770.844 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-14T21:26:10.870Z,1552598770.870 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-14T21:26:10.873Z,1552598770.873 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-14T21:26:10.874Z,1552598770.874 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-14T21:26:10.881Z,1552598770.881 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-14T21:26:10.882Z,1552598770.882 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-14T21:26:10.883Z,1552598770.883 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1443
2019-03-14T21:26:10.887Z,1552598770.887 [Supervisor](INFO): Main Thread ID is 800
2019-03-14T21:26:10.887Z,1552598770.887 [Supervisor](DEBUG): Running supervisor.
2019-03-14T21:26:10.888Z,1552598770.888 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1444
2019-03-14T21:26:10.891Z,1552598770.891 [controlThread ThreadHandler](INFO): Handler Thread ID is 1445
2019-03-14T21:26:10.891Z,1552598770.891 [controlThread](DEBUG): Initializing ControlThread
2019-03-14T21:26:10.897Z,1552598770.897 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:26:10.898Z,1552598770.898 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-14T21:26:10.899Z,1552598770.899 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-14T21:26:10.900Z,1552598770.900 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-14T21:26:10.900Z,1552598770.900 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-14T21:26:10.900Z,1552598770.900 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-14T21:26:10.900Z,1552598770.900 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-14T21:26:10.901Z,1552598770.901 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-14T21:26:10.901Z,1552598770.901 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-14T21:26:10.902Z,1552598770.902 [SBIT](INFO): Initialize SBIT Component.
2019-03-14T21:26:10.903Z,1552598770.903 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-14T21:26:10.903Z,1552598770.903 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-14T21:26:10.903Z,1552598770.903 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-14T21:26:10.903Z,1552598770.903 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-14T21:26:10.905Z,1552598770.905 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-14T21:26:10.905Z,1552598770.905 [IBIT](INFO): Initialize IBIT Component.
2019-03-14T21:26:10.906Z,1552598770.906 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-14T21:26:10.907Z,1552598770.907 [logger ThreadHandler](INFO): Handler Thread ID is 1446
2019-03-14T21:26:10.918Z,1552598770.918 [CBIT](DEBUG): Initialized mux pins.
2019-03-14T21:26:10.918Z,1552598770.918 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-14T21:26:10.927Z,1552598770.927 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1447
2019-03-14T21:26:10.928Z,1552598770.928 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-14T21:26:10.939Z,1552598770.939 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1448
2019-03-14T21:26:10.942Z,1552598770.942 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2019-03-14T21:26:10.942Z,1552598770.942 [CBIT](DEBUG): Initializing heartbeat.
2019-03-14T21:26:10.951Z,1552598770.951 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1449
2019-03-14T21:26:10.951Z,1552598770.951 [CTD_NeilBrown](INFO): Powering down
2019-03-14T21:26:10.979Z,1552598770.979 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1450
2019-03-14T21:26:10.979Z,1552598770.979 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-14T21:26:11.014Z,1552598771.014 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-14T21:26:11.014Z,1552598771.014 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-14T21:26:11.015Z,1552598771.015 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1451
2019-03-14T21:26:11.018Z,1552598771.018 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-14T21:26:11.019Z,1552598771.019 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-14T21:26:11.019Z,1552598771.019 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-14T21:26:11.019Z,1552598771.019 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-14T21:26:11.019Z,1552598771.019 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-14T21:26:11.019Z,1552598771.019 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-14T21:26:11.019Z,1552598771.019 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-14T21:26:11.020Z,1552598771.020 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-14T21:26:11.020Z,1552598771.020 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-14T21:26:11.020Z,1552598771.020 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-14T21:26:11.020Z,1552598771.020 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-14T21:26:11.020Z,1552598771.020 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-14T21:26:11.021Z,1552598771.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-14T21:26:11.021Z,1552598771.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-14T21:26:11.021Z,1552598771.021 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-14T21:26:11.021Z,1552598771.021 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-14T21:26:11.050Z,1552598771.050 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-14T21:26:11.052Z,1552598771.052 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-14T21:26:11.053Z,1552598771.053 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-14T21:26:11.053Z,1552598771.053 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-14T21:26:11.054Z,1552598771.054 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-14T21:26:11.064Z,1552598771.064 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-14T21:26:11.095Z,1552598771.095 [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-03-14T21:26:11.096Z,1552598771.096 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-14T21:26:11.162Z,1552598771.162 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-14T21:26:11.163Z,1552598771.163 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-14T21:26:11.165Z,1552598771.165 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-14T21:26:11.184Z,1552598771.184 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-14T21:26:11.211Z,1552598771.211 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-14T21:26:11.216Z,1552598771.216 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-14T21:26:11.237Z,1552598771.237 [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-03-14T21:26:11.242Z,1552598771.242 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,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-03-14T21:26:11.268Z,1552598771.268 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-14T21:26:11.269Z,1552598771.269 [DUSBL_Hydroid](INFO): Powering up
2019-03-14T21:26:11.270Z,1552598771.270 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-14T21:26:11.294Z,1552598771.294 [Micromodem](INFO): Start
2019-03-14T21:26:11.342Z,1552598771.342 [Radio_Surface](INFO): Powering up
2019-03-14T21:26:11.363Z,1552598771.363 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-14T21:26:11.392Z,1552598771.392 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-14T21:26:11.398Z,1552598771.398 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-14T21:26:11.399Z,1552598771.399 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-14T21:26:11.414Z,1552598771.414 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-14T21:26:11.415Z,1552598771.415 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-14T21:26:11.422Z,1552598771.422 [MassServo](DEBUG): Initializing MassServo.
2019-03-14T21:26:11.423Z,1552598771.423 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-14T21:26:11.431Z,1552598771.431 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-14T21:26:11.432Z,1552598771.432 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-14T21:26:11.438Z,1552598771.438 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-14T21:26:11.440Z,1552598771.440 [CommandLine](FAULT): Scheduling is paused
2019-03-14T21:26:11.441Z,1552598771.441 [CBIT](INFO): Critical error at 20190314T212610
2019-03-14T21:26:11.441Z,1552598771.441 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-14T21:26:11.668Z,1552598771.668 [Micromodem](INFO): Starting
2019-03-14T21:26:11.668Z,1552598771.668 [Micromodem](INFO): Powering up
2019-03-14T21:26:11.669Z,1552598771.669 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-14T21:26:11.774Z,1552598771.774 [RDI_PathfinderUp](INFO): Powering down
2019-03-14T21:26:11.826Z,1552598771.826 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-14T21:26:12.059Z,1552598772.059 [Micromodem](INFO): Starting
2019-03-14T21:26:12.078Z,1552598772.078 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-14T21:26:12.402Z,1552598772.402 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-14T21:26:12.402Z,1552598772.402 [RudderServo](FAULT): Rudder failed to initialize
2019-03-14T21:26:12.402Z,1552598772.402 [RudderServo] Communications Fault, FailCount= 1
2019-03-14T21:26:12.402Z,1552598772.402 [RudderServo](ERROR): Communications Fault
2019-03-14T21:26:12.514Z,1552598772.514 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-14T21:26:12.525Z,1552598772.525 [Micromodem](INFO): Starting
2019-03-14T21:26:12.687Z,1552598772.687 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-14T21:26:12.687Z,1552598772.687 [RudderServo](INFO): Powering down
2019-03-14T21:26:12.928Z,1552598772.928 [Micromodem](INFO): Starting
2019-03-14T21:26:13.332Z,1552598773.332 [Micromodem](INFO): Starting
2019-03-14T21:26:13.360Z,1552598773.360 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-14T21:26:13.481Z,1552598773.481 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-14T21:26:13.486Z,1552598773.486 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-14T21:26:13.486Z,1552598773.486 [RudderServo] No Fault, FailCount= 1
2019-03-14T21:26:13.736Z,1552598773.736 [Micromodem](INFO): Starting
2019-03-14T21:26:14.148Z,1552598774.148 [Micromodem](INFO): Starting
2019-03-14T21:26:14.544Z,1552598774.544 [Micromodem](INFO): Starting
2019-03-14T21:26:14.948Z,1552598774.948 [Micromodem](INFO): Starting
2019-03-14T21:26:15.352Z,1552598775.352 [Micromodem](INFO): Starting
2019-03-14T21:26:15.756Z,1552598775.756 [Micromodem](INFO): Starting
2019-03-14T21:26:16.160Z,1552598776.160 [Micromodem](INFO): Starting
2019-03-14T21:26:16.564Z,1552598776.564 [Micromodem](INFO): Starting
2019-03-14T21:26:16.564Z,1552598776.564 [Micromodem](INFO): Starting
2019-03-14T21:26:16.564Z,1552598776.564 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T21:26:16.565Z,1552598776.565 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T21:26:16.565Z,1552598776.565 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-03-14T21:26:16.565Z,1552598776.565 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T21:26:16.565Z,1552598776.565 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T21:26:16.566Z,1552598776.566 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T21:26:16.566Z,1552598776.566 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T21:26:16.567Z,1552598776.567 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T21:26:16.568Z,1552598776.568 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T21:26:16.968Z,1552598776.968 [Micromodem](INFO): Starting
2019-03-14T21:26:17.372Z,1552598777.372 [Micromodem](INFO): Starting
2019-03-14T21:26:17.776Z,1552598777.776 [Micromodem](INFO): Starting
2019-03-14T21:26:18.180Z,1552598778.180 [Micromodem](INFO): Starting
2019-03-14T21:26:18.584Z,1552598778.584 [Micromodem](INFO): Starting
2019-03-14T21:26:18.988Z,1552598778.988 [Micromodem](INFO): Starting
2019-03-14T21:26:19.392Z,1552598779.392 [Micromodem](INFO): Starting
2019-03-14T21:26:19.796Z,1552598779.796 [Micromodem](INFO): Starting
2019-03-14T21:26:20.200Z,1552598780.200 [Micromodem](INFO): Starting
2019-03-14T21:26:20.604Z,1552598780.604 [Micromodem](INFO): Starting
2019-03-14T21:26:21.018Z,1552598781.018 [Micromodem](INFO): Starting
2019-03-14T21:26:21.412Z,1552598781.412 [Micromodem](INFO): Starting
2019-03-14T21:26:21.816Z,1552598781.816 [Micromodem](INFO): Starting
2019-03-14T21:26:22.220Z,1552598782.220 [Micromodem](INFO): Starting
2019-03-14T21:26:22.624Z,1552598782.624 [Micromodem](INFO): Starting
2019-03-14T21:26:23.028Z,1552598783.028 [Micromodem](INFO): Starting
2019-03-14T21:26:23.432Z,1552598783.432 [Micromodem](INFO): Starting
2019-03-14T21:26:23.836Z,1552598783.836 [Micromodem](INFO): Starting
2019-03-14T21:26:24.240Z,1552598784.240 [Micromodem](INFO): Starting
2019-03-14T21:26:24.644Z,1552598784.644 [Micromodem](INFO): Starting
2019-03-14T21:26:24.683Z,1552598784.683 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-14T21:26:25.048Z,1552598785.048 [Micromodem](INFO): Starting
2019-03-14T21:26:25.456Z,1552598785.456 [Micromodem](INFO): Starting
2019-03-14T21:26:25.856Z,1552598785.856 [Micromodem](INFO): Starting
2019-03-14T21:26:26.260Z,1552598786.260 [Micromodem](INFO): Starting
2019-03-14T21:26:26.664Z,1552598786.664 [Micromodem](INFO): Starting
2019-03-14T21:26:27.068Z,1552598787.068 [Micromodem](INFO): Starting
2019-03-14T21:26:27.472Z,1552598787.472 [Micromodem](INFO): Starting
2019-03-14T21:26:27.876Z,1552598787.876 [Micromodem](INFO): Starting
2019-03-14T21:26:27.880Z,1552598787.880 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-14T21:26:28.280Z,1552598788.280 [Micromodem](INFO): Starting
2019-03-14T21:26:28.685Z,1552598788.685 [Micromodem](INFO): Starting
2019-03-14T21:26:29.089Z,1552598789.089 [Micromodem](INFO): Starting
2019-03-14T21:26:29.492Z,1552598789.492 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-14T21:26:29.506Z,1552598789.506 [Micromodem](INFO): Starting
2019-03-14T21:26:29.896Z,1552598789.896 [Micromodem](INFO): Starting
2019-03-14T21:26:30.299Z,1552598790.299 [Micromodem](INFO): Starting
2019-03-14T21:26:30.703Z,1552598790.703 [Micromodem](INFO): Starting
2019-03-14T21:26:31.107Z,1552598791.107 [Micromodem](INFO): Starting
2019-03-14T21:26:31.511Z,1552598791.511 [Micromodem](INFO): Starting
2019-03-14T21:26:31.915Z,1552598791.915 [Micromodem](INFO): Starting
2019-03-14T21:26:32.319Z,1552598792.319 [Micromodem](INFO): Starting
2019-03-14T21:26:32.723Z,1552598792.723 [Micromodem](INFO): Starting
2019-03-14T21:26:33.128Z,1552598793.128 [Micromodem](INFO): Starting
2019-03-14T21:26:33.532Z,1552598793.532 [Micromodem](INFO): Starting
2019-03-14T21:26:33.935Z,1552598793.935 [Micromodem](INFO): Starting
2019-03-14T21:26:34.339Z,1552598794.339 [Micromodem](INFO): Starting
2019-03-14T21:26:34.741Z,1552598794.741 [Micromodem](INFO): Starting
2019-03-14T21:26:35.147Z,1552598795.147 [Micromodem](INFO): Starting
2019-03-14T21:26:35.552Z,1552598795.552 [Micromodem](INFO): Starting
2019-03-14T21:26:35.952Z,1552598795.952 [Micromodem](INFO): Starting
2019-03-14T21:26:36.360Z,1552598796.360 [Micromodem](INFO): Starting
2019-03-14T21:26:36.763Z,1552598796.763 [Micromodem](INFO): Starting
2019-03-14T21:26:36.764Z,1552598796.764 [Micromodem](IMPORTANT): CACFG stsring$CACFG,ALL,0*31

2019-03-14T21:26:36.764Z,1552598796.764 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-14T21:26:36.765Z,1552598796.765 [NAL9602](INFO): Powering up NAL9602
2019-03-14T21:26:37.167Z,1552598797.167 [Micromodem](INFO): Starting
2019-03-14T21:26:37.571Z,1552598797.571 [Micromodem](INFO): Starting
2019-03-14T21:26:37.973Z,1552598797.973 [Micromodem](INFO): Starting
2019-03-14T21:26:38.379Z,1552598798.379 [Micromodem](INFO): Starting
2019-03-14T21:26:38.783Z,1552598798.783 [Micromodem](INFO): Starting
2019-03-14T21:26:39.187Z,1552598799.187 [Micromodem](INFO): Starting
2019-03-14T21:26:39.591Z,1552598799.591 [Micromodem](INFO): Starting
2019-03-14T21:26:39.665Z,1552598799.665 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-14T21:26:39.677Z,1552598799.677 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-14T21:26:39.996Z,1552598799.996 [Micromodem](INFO): Starting
2019-03-14T21:26:40.399Z,1552598800.399 [Micromodem](INFO): Starting
2019-03-14T21:26:40.803Z,1552598800.803 [Micromodem](INFO): Starting
2019-03-14T21:26:41.207Z,1552598801.207 [Micromodem](INFO): Starting
2019-03-14T21:26:41.620Z,1552598801.620 [Micromodem](INFO): Starting
2019-03-14T21:26:42.116Z,1552598802.116 [Micromodem](INFO): Starting
2019-03-14T21:26:42.530Z,1552598802.530 [Micromodem](INFO): Starting
2019-03-14T21:26:42.936Z,1552598802.936 [Micromodem](INFO): Starting
2019-03-14T21:26:43.327Z,1552598803.327 [Micromodem](INFO): Starting
2019-03-14T21:26:43.731Z,1552598803.731 [Micromodem](INFO): Starting
2019-03-14T21:26:44.135Z,1552598804.135 [Micromodem](INFO): Starting
2019-03-14T21:26:44.539Z,1552598804.539 [Micromodem](INFO): Starting
2019-03-14T21:26:44.943Z,1552598804.943 [Micromodem](INFO): Starting
2019-03-14T21:26:45.347Z,1552598805.347 [Micromodem](INFO): Starting
2019-03-14T21:26:45.751Z,1552598805.751 [Micromodem](INFO): Starting
2019-03-14T21:26:46.155Z,1552598806.155 [Micromodem](INFO): Starting
2019-03-14T21:26:46.559Z,1552598806.559 [Micromodem](INFO): Starting
2019-03-14T21:26:46.560Z,1552598806.560 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33

2019-03-14T21:26:46.560Z,1552598806.560 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T21:26:46.963Z,1552598806.963 [Micromodem](INFO): Starting
2019-03-14T21:26:47.367Z,1552598807.367 [Micromodem](INFO): Starting
2019-03-14T21:26:47.771Z,1552598807.771 [Micromodem](INFO): Starting
2019-03-14T21:26:47.772Z,1552598807.772 [NAL9602](INFO): NAL9602 initialized
2019-03-14T21:26:48.175Z,1552598808.175 [Micromodem](INFO): Starting
2019-03-14T21:26:48.579Z,1552598808.579 [Micromodem](INFO): Starting
2019-03-14T21:26:48.595Z,1552598808.595 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:26:48.983Z,1552598808.983 [Micromodem](INFO): Starting
2019-03-14T21:26:49.387Z,1552598809.387 [Micromodem](INFO): Starting
2019-03-14T21:26:49.791Z,1552598809.791 [Micromodem](INFO): Starting
2019-03-14T21:26:50.195Z,1552598810.195 [Micromodem](INFO): Starting
2019-03-14T21:26:50.599Z,1552598810.599 [Micromodem](INFO): Starting
2019-03-14T21:26:50.703Z,1552598810.703 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.011802
CHAN A1 (24V): -0.026763
CHAN A2 (12V): -0.007445
CHAN A3 (5V): -0.002155
CHAN B0 (3.3V): 0.000176
CHAN B1 (3.15aV): 0.000008
CHAN B2 (3.15bV): 0.000287
CHAN B3 (GND): 0.001944
OPEN: 0.006433
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-14T21:26:51.003Z,1552598811.003 [Micromodem](INFO): Starting
2019-03-14T21:26:51.408Z,1552598811.408 [Micromodem](INFO): Starting
2019-03-14T21:26:51.811Z,1552598811.811 [Micromodem](INFO): Starting
2019-03-14T21:26:52.215Z,1552598812.215 [Micromodem](INFO): Starting
2019-03-14T21:26:52.619Z,1552598812.619 [Micromodem](INFO): Starting
2019-03-14T21:26:53.023Z,1552598813.023 [Micromodem](INFO): Starting
2019-03-14T21:26:53.433Z,1552598813.433 [Micromodem](INFO): Starting
2019-03-14T21:26:53.831Z,1552598813.831 [Micromodem](INFO): Starting
2019-03-14T21:26:54.235Z,1552598814.235 [Micromodem](INFO): Starting
2019-03-14T21:26:54.639Z,1552598814.639 [Micromodem](INFO): Starting
2019-03-14T21:26:55.045Z,1552598815.045 [Micromodem](INFO): Starting
2019-03-14T21:26:55.523Z,1552598815.523 [Micromodem](INFO): Starting
2019-03-14T21:26:55.923Z,1552598815.923 [Micromodem](INFO): Starting
2019-03-14T21:26:56.327Z,1552598816.327 [Micromodem](INFO): Starting
2019-03-14T21:26:56.731Z,1552598816.731 [Micromodem](INFO): Starting
2019-03-14T21:26:56.732Z,1552598816.732 [Micromodem](IMPORTANT): CACFG stsring$CACFG,nav.dt.txtrig_gpio4,1*66

2019-03-14T21:26:56.733Z,1552598816.733 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-03-14T21:26:57.135Z,1552598817.135 [Micromodem](INFO): Starting
2019-03-14T21:26:57.539Z,1552598817.539 [Micromodem](INFO): Starting
2019-03-14T21:26:57.944Z,1552598817.944 [Micromodem](INFO): Starting
2019-03-14T21:26:58.347Z,1552598818.347 [Micromodem](INFO): Starting
2019-03-14T21:26:58.751Z,1552598818.751 [Micromodem](INFO): Starting
2019-03-14T21:26:59.155Z,1552598819.155 [Micromodem](INFO): Starting
2019-03-14T21:26:59.559Z,1552598819.559 [Micromodem](INFO): Starting
2019-03-14T21:26:59.963Z,1552598819.963 [Micromodem](INFO): Starting
2019-03-14T21:27:00.367Z,1552598820.367 [Micromodem](INFO): Starting
2019-03-14T21:27:00.796Z,1552598820.796 [Micromodem](INFO): Starting
2019-03-14T21:27:01.176Z,1552598821.176 [Micromodem](INFO): Starting
2019-03-14T21:27:01.177Z,1552598821.177 [Micromodem](IMPORTANT): CACFG stsring$CACFG,BND,1*39

2019-03-14T21:27:01.178Z,1552598821.178 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-14T21:27:02Z,0*7C

2019-03-14T21:27:01.178Z,1552598821.178 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-14T21:27:02Z,0*7C

2019-03-14T21:27:01.179Z,1552598821.179 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-14T21:27:02Z,0*7C

2019-03-14T21:27:01.579Z,1552598821.579 [Micromodem](INFO): Starting
2019-03-14T21:27:01.581Z,1552598821.581 [Micromodem](ERROR): Response from modem unexpected: $CADQF,198,1*50

2019-03-14T21:27:01.983Z,1552598821.983 [Micromodem](INFO): Starting
2019-03-14T21:27:01.985Z,1552598821.985 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T21:27:02.396Z,1552598822.396 [Micromodem](INFO): Starting
2019-03-14T21:27:02.412Z,1552598822.412 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314212701.013664,05,169,16,0090,0150,246,00,00,01,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,198,-0.05,-999,9760,4000*76

2019-03-14T21:27:02.791Z,1552598822.791 [Micromodem](INFO): Starting
2019-03-14T21:27:03.204Z,1552598823.204 [Micromodem](INFO): Starting
2019-03-14T21:27:03.600Z,1552598823.600 [Micromodem](INFO): Starting
2019-03-14T21:27:33.212Z,1552598853.212 [SBIT](IMPORTANT): SBIT PASSED
2019-03-14T21:27:33.232Z,1552598853.232 [CommandLine](IMPORTANT): got command configSet list
2019-03-14T21:27:33.232Z,1552598853.232 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-14T21:27:33.233Z,1552598853.233 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-14T21:27:33.233Z,1552598853.233 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-03-14T21:27:33.233Z,1552598853.233 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=30 count;

2019-03-14T21:27:33.616Z,1552598853.616 [MissionManager](IMPORTANT): Started mission Startup
2019-03-14T21:27:33.617Z,1552598853.617 [Startup] Running Loop=1
2019-03-14T21:27:33.617Z,1552598853.617 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-14T21:27:33.617Z,1552598853.617 [Startup:A.GoToSurface] Running Loop=1
2019-03-14T21:27:33.617Z,1552598853.617 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T21:27:33.618Z,1552598853.618 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T21:27:33.618Z,1552598853.618 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T21:27:33.619Z,1552598853.619 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T21:27:33.619Z,1552598853.619 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T21:27:33.619Z,1552598853.619 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T21:27:33.621Z,1552598853.621 [Startup:StartupSatComms] Running Loop=1
2019-03-14T21:27:33.621Z,1552598853.621 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-14T21:27:33.621Z,1552598853.621 [Startup:StartupSatComms:A] Running Loop=1
2019-03-14T21:27:34.016Z,1552598854.016 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-14T21:28:11.181Z,1552598891.181 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:28:11.181Z,1552598891.181 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-14T21:28:11.181Z,1552598891.181 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:28:11.183Z,1552598891.183 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:28:11.183Z,1552598891.183 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-14T21:28:11.183Z,1552598891.183 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:28:11.185Z,1552598891.185 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:28:11.185Z,1552598891.185 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-14T21:28:11.185Z,1552598891.185 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:28:11.196Z,1552598891.196 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:28:11.196Z,1552598891.196 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:28:11.196Z,1552598891.196 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:28:11.600Z,1552598891.600 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:28:11.600Z,1552598891.600 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-14T21:28:11.601Z,1552598891.601 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:28:11.601Z,1552598891.601 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-14T21:28:11.601Z,1552598891.601 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:28:11.601Z,1552598891.601 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-14T21:28:11.993Z,1552598891.993 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:28:33.850Z,1552598913.850 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-14T21:27:33.6Z
2019-03-14T21:28:33.850Z,1552598913.850 [Startup:StartupSatComms:A] Stopped
2019-03-14T21:28:33.850Z,1552598913.850 [Startup:StartupSatComms:B] Running Loop=1
2019-03-14T21:28:34.212Z,1552598914.212 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-14T21:28:37.216Z,1552598917.216 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004865
2019-03-14T21:28:42.162Z,1552598922.162 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190314T211918/Courier0004.lzma
2019-03-14T21:28:42.968Z,1552598922.968 [DataOverHttps](INFO): Moved sent file to Logs/20190314T211918/Courier0004.lzma.bak
2019-03-14T21:28:42.968Z,1552598922.968 [DataOverHttps](INFO): SBD MOMSN=10140032
2019-03-14T21:28:53.738Z,1552598933.738 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20190314T212600/Courier0000.lzma
2019-03-14T21:28:54.544Z,1552598934.544 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Courier0000.lzma.bak
2019-03-14T21:28:54.544Z,1552598934.544 [DataOverHttps](INFO): SBD MOMSN=10140034
2019-03-14T21:29:05.715Z,1552598945.715 [DataOverHttps](INFO): Sending 261 bytes from file Logs/20190314T210538/Express0011.lzma
2019-03-14T21:29:06.520Z,1552598946.520 [DataOverHttps](INFO): Moved sent file to Logs/20190314T210538/Express0011.lzma.bak
2019-03-14T21:29:06.520Z,1552598946.520 [DataOverHttps](INFO): SBD MOMSN=10140038
2019-03-14T21:29:17.764Z,1552598957.764 [DataOverHttps](INFO): Sending 1144 bytes from file Logs/20190314T211918/Express0001.lzma
2019-03-14T21:29:18.568Z,1552598958.568 [DataOverHttps](INFO): Moved sent file to Logs/20190314T211918/Express0001.lzma.bak
2019-03-14T21:29:18.568Z,1552598958.568 [DataOverHttps](INFO): SBD MOMSN=10140048
2019-03-14T21:29:29.463Z,1552598969.463 [DataOverHttps](INFO): Sending 980 bytes from file Logs/20190314T211918/Express0005.lzma
2019-03-14T21:29:31.884Z,1552598971.884 [DataOverHttps](INFO): Moved sent file to Logs/20190314T211918/Express0005.lzma.bak
2019-03-14T21:29:31.884Z,1552598971.884 [DataOverHttps](INFO): SBD MOMSN=10140090
2019-03-14T21:29:34.108Z,1552598974.108 [Startup:StartupSatComms:B](INFO): Timed out from 2019-03-14T21:28:33.8Z
2019-03-14T21:29:34.108Z,1552598974.108 [Startup:StartupSatComms:B] Stopped
2019-03-14T21:29:34.108Z,1552598974.108 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-14T21:29:34.108Z,1552598974.108 [Startup:StartupSatComms] Stopped
2019-03-14T21:29:34.108Z,1552598974.108 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-14T21:29:34.109Z,1552598974.109 [Startup](INFO): Completed Startup
2019-03-14T21:29:34.109Z,1552598974.109 [MissionManager](INFO): Startup is completed.
2019-03-14T21:29:34.109Z,1552598974.109 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-14T21:29:34.109Z,1552598974.109 [Startup] Stopped
2019-03-14T21:29:34.110Z,1552598974.110 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-14T21:29:34.110Z,1552598974.110 [Startup:A.GoToSurface] Stopped
2019-03-14T21:29:34.110Z,1552598974.110 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T21:29:34.483Z,1552598974.483 [MissionManager](IMPORTANT): Started mission Default
2019-03-14T21:29:34.484Z,1552598974.484 [Default] Running Loop=1
2019-03-14T21:29:34.484Z,1552598974.484 [Default](DEBUG): Aggregate::initialize Default
2019-03-14T21:29:34.484Z,1552598974.484 [Default:B.GoToSurface] Running Loop=1
2019-03-14T21:29:34.484Z,1552598974.484 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T21:29:34.484Z,1552598974.484 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T21:29:34.485Z,1552598974.485 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T21:29:34.485Z,1552598974.485 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T21:29:34.485Z,1552598974.485 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T21:29:34.486Z,1552598974.486 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T21:29:34.486Z,1552598974.486 [Default:A.Wait] Running Loop=1
2019-03-14T21:29:34.486Z,1552598974.486 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-14T21:29:42.783Z,1552598982.783 [DataOverHttps](INFO): Sending 1054 bytes from file Logs/20190314T212600/Express0001.lzma
2019-03-14T21:29:43.587Z,1552598983.587 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Express0001.lzma.bak
2019-03-14T21:29:43.588Z,1552598983.588 [DataOverHttps](INFO): SBD MOMSN=10140160
2019-03-14T21:29:47.830Z,1552598987.830 [Default:A.Wait](INFO): Done Waiting.
2019-03-14T21:29:47.830Z,1552598987.830 [Default:A.Wait] Stopped
2019-03-14T21:29:47.830Z,1552598987.830 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T21:29:48.231Z,1552598988.231 [Default:CheckIn] Running Loop=1
2019-03-14T21:29:48.232Z,1552598988.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T21:29:48.232Z,1552598988.232 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T21:29:48.628Z,1552598988.628 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-14T21:30:12.068Z,1552599012.068 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:30:12.068Z,1552599012.068 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-14T21:30:12.068Z,1552599012.068 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:30:12.069Z,1552599012.069 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:30:12.070Z,1552599012.070 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-14T21:30:12.070Z,1552599012.070 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:30:12.071Z,1552599012.071 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:30:12.071Z,1552599012.071 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-14T21:30:12.071Z,1552599012.071 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:30:12.082Z,1552599012.082 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:30:12.083Z,1552599012.083 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:30:12.083Z,1552599012.083 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:30:12.533Z,1552599012.533 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:30:12.533Z,1552599012.533 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-14T21:30:12.533Z,1552599012.533 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:30:12.533Z,1552599012.533 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-14T21:30:12.534Z,1552599012.534 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:30:12.534Z,1552599012.534 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-14T21:30:12.912Z,1552599012.912 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:31:51.416Z,1552599111.416 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-14T21:31:52.234Z,1552599112.234 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:31:55.874Z,1552599115.874 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:31:59.106Z,1552599119.106 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:01.930Z,1552599121.930 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:05.162Z,1552599125.162 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:07.990Z,1552599127.990 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:11.222Z,1552599131.222 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:12.948Z,1552599132.948 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:32:12.948Z,1552599132.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-14T21:32:12.948Z,1552599132.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:32:12.954Z,1552599132.954 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:32:12.954Z,1552599132.954 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-14T21:32:12.954Z,1552599132.954 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:32:12.956Z,1552599132.956 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:32:12.956Z,1552599132.956 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-14T21:32:12.956Z,1552599132.956 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:32:12.991Z,1552599132.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:32:12.991Z,1552599132.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:32:12.992Z,1552599132.992 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:32:13.284Z,1552599133.284 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:32:13.284Z,1552599133.284 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-14T21:32:13.285Z,1552599133.285 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:32:13.285Z,1552599133.285 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-14T21:32:13.285Z,1552599133.285 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:32:13.285Z,1552599133.285 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-14T21:32:13.687Z,1552599133.687 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:32:14.050Z,1552599134.050 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:17.286Z,1552599137.286 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:20.114Z,1552599140.114 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:32:20.508Z,1552599140.508 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212651.00,A,3648.16392,N,12147.28667,W,0.019,23.52,140319,,,A*45
2019-03-14T21:32:20.511Z,1552599140.511 [NAL9602](INFO): GPS fix at 20190314T212651: (36.802732, -121.788111)
2019-03-14T21:32:20.576Z,1552599140.576 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T21:32:20.576Z,1552599140.576 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T21:32:20.969Z,1552599140.969 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-14T21:32:28.970Z,1552599148.970 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190314T212600/Courier0004.lzma
2019-03-14T21:32:29.776Z,1552599149.776 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Courier0004.lzma.bak
2019-03-14T21:32:29.776Z,1552599149.776 [DataOverHttps](INFO): SBD MOMSN=10140190
2019-03-14T21:32:44.082Z,1552599164.082 [DataOverHttps](INFO): Sending 399 bytes from file Logs/20190314T212600/Express0005.lzma
2019-03-14T21:32:44.888Z,1552599164.888 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Express0005.lzma.bak
2019-03-14T21:32:44.888Z,1552599164.888 [DataOverHttps](INFO): SBD MOMSN=10140193
2019-03-14T21:32:46.033Z,1552599166.033 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T21:32:46.033Z,1552599166.033 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-14T21:32:46.033Z,1552599166.033 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T21:32:52.855Z,1552599172.855 [NAL9602](INFO): Powering down
2019-03-14T21:33:07.020Z,1552599187.020 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-03-14T21:34:13.768Z,1552599253.768 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:34:13.768Z,1552599253.768 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-14T21:34:13.768Z,1552599253.768 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:34:13.770Z,1552599253.770 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:34:13.770Z,1552599253.770 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-14T21:34:13.770Z,1552599253.770 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:34:13.772Z,1552599253.772 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:34:13.772Z,1552599253.772 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-14T21:34:13.772Z,1552599253.772 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:34:13.783Z,1552599253.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:34:13.783Z,1552599253.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:34:13.784Z,1552599253.784 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:34:14.086Z,1552599254.086 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-03-14T21:34:14.104Z,1552599254.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:34:14.104Z,1552599254.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-14T21:34:14.105Z,1552599254.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:34:14.105Z,1552599254.105 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-14T21:34:14.105Z,1552599254.105 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:34:14.105Z,1552599254.105 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-14T21:34:14.481Z,1552599254.481 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:36:14.893Z,1552599374.893 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:36:14.893Z,1552599374.893 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-14T21:36:14.893Z,1552599374.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:36:14.895Z,1552599374.895 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:36:14.895Z,1552599374.895 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-14T21:36:14.895Z,1552599374.895 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:36:14.897Z,1552599374.897 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:36:14.897Z,1552599374.897 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-14T21:36:14.897Z,1552599374.897 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:36:14.908Z,1552599374.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:36:14.908Z,1552599374.908 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:36:14.908Z,1552599374.908 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:36:15.295Z,1552599375.295 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:36:15.295Z,1552599375.295 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-14T21:36:15.296Z,1552599375.296 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:36:15.296Z,1552599375.296 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-14T21:36:15.296Z,1552599375.296 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:36:15.296Z,1552599375.296 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-14T21:36:15.712Z,1552599375.712 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:37:46.593Z,1552599466.593 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-14T21:37:46.593Z,1552599466.593 [Default:CheckIn:C.Wait] Stopped
2019-03-14T21:37:46.593Z,1552599466.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T21:37:46.593Z,1552599466.593 [Default:CheckIn:D] Running Loop=1
2019-03-14T21:37:47.008Z,1552599467.008 [Default:CheckIn:D] Stopped
2019-03-14T21:37:47.008Z,1552599467.008 [Default:CheckIn:E] Running Loop=1
2019-03-14T21:37:47.406Z,1552599467.406 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.208738 min 
2019-03-14T21:37:47.406Z,1552599467.406 [Default:CheckIn:E] Stopped
2019-03-14T21:37:47.407Z,1552599467.407 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-14T21:37:47.407Z,1552599467.407 [Default:CheckIn] Stopped
2019-03-14T21:37:47.407Z,1552599467.407 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T21:37:47.407Z,1552599467.407 [Default:CheckIn](INFO): Running loop #2
2019-03-14T21:37:47.407Z,1552599467.407 [Default:CheckIn] Running Loop=2
2019-03-14T21:37:47.407Z,1552599467.407 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T21:37:47.407Z,1552599467.407 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T21:37:48.180Z,1552599468.180 [NAL9602](INFO): Powering up
2019-03-14T21:37:59.097Z,1552599479.097 [NAL9602](INFO): NAL9602 initialized
2019-03-14T21:37:59.910Z,1552599479.910 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:38:16.085Z,1552599496.085 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:38:16.086Z,1552599496.086 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-14T21:38:16.086Z,1552599496.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:38:16.088Z,1552599496.088 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:38:16.088Z,1552599496.088 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-14T21:38:16.088Z,1552599496.088 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:38:16.090Z,1552599496.090 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:38:16.090Z,1552599496.090 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-14T21:38:16.090Z,1552599496.090 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:38:16.101Z,1552599496.101 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:38:16.101Z,1552599496.101 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:38:16.101Z,1552599496.101 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:38:16.495Z,1552599496.495 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:38:16.495Z,1552599496.495 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-14T21:38:16.496Z,1552599496.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:38:16.496Z,1552599496.496 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-14T21:38:16.497Z,1552599496.497 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:38:16.497Z,1552599496.497 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-14T21:38:16.897Z,1552599496.897 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:38:31.004Z,1552599511.004 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-03-14T21:38:31.082Z,1552599511.082 [NAL9602](FAULT): received: 
+CSQ:0

OK
2019-03-14T21:38:31.082Z,1552599511.082 [NAL9602] Data Fault, FailCount= 1
2019-03-14T21:38:31.082Z,1552599511.082 [NAL9602](ERROR): Data Fault
2019-03-14T21:38:31.125Z,1552599511.125 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T21:38:31.408Z,1552599511.408 [NAL9602](INFO): Powering down
2019-03-14T21:38:32.254Z,1552599512.254 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T21:38:32.254Z,1552599512.254 [NAL9602] No Fault, FailCount= 1
2019-03-14T21:39:01.712Z,1552599541.712 [NAL9602](INFO): Powering up NAL9602
2019-03-14T21:39:12.616Z,1552599552.616 [NAL9602](INFO): NAL9602 initialized
2019-03-14T21:39:13.442Z,1552599553.442 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:40:17.292Z,1552599617.292 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:40:17.292Z,1552599617.292 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-14T21:40:17.292Z,1552599617.292 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:40:17.294Z,1552599617.294 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:40:17.295Z,1552599617.295 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-14T21:40:17.295Z,1552599617.295 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:40:17.296Z,1552599617.296 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:40:17.296Z,1552599617.296 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-03-14T21:40:17.296Z,1552599617.296 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:40:17.308Z,1552599617.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:40:17.308Z,1552599617.308 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:40:17.308Z,1552599617.308 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:40:17.733Z,1552599617.733 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:40:17.734Z,1552599617.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-14T21:40:17.735Z,1552599617.735 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:40:17.735Z,1552599617.735 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-14T21:40:17.735Z,1552599617.735 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:40:17.735Z,1552599617.735 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-03-14T21:40:18.101Z,1552599618.101 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:42:18.485Z,1552599738.485 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:42:18.485Z,1552599738.485 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-03-14T21:42:18.485Z,1552599738.485 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:42:18.488Z,1552599738.488 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:42:18.488Z,1552599738.488 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-03-14T21:42:18.488Z,1552599738.488 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:42:18.489Z,1552599738.489 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:42:18.489Z,1552599738.489 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-03-14T21:42:18.490Z,1552599738.490 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:42:18.509Z,1552599738.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:42:18.509Z,1552599738.509 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:42:18.509Z,1552599738.509 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:42:18.924Z,1552599738.924 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:42:18.924Z,1552599738.924 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-03-14T21:42:18.925Z,1552599738.925 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:42:18.925Z,1552599738.925 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-03-14T21:42:18.925Z,1552599738.925 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:42:18.925Z,1552599738.925 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-03-14T21:42:19.293Z,1552599739.293 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:42:47.586Z,1552599767.586 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-14T21:37:47.4Z
2019-03-14T21:42:47.586Z,1552599767.586 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T21:42:47.586Z,1552599767.586 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T21:42:52.910Z,1552599772.910 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20190314T212600/Courier0007.lzma
2019-03-14T21:42:53.716Z,1552599773.716 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Courier0007.lzma.bak
2019-03-14T21:42:53.716Z,1552599773.716 [DataOverHttps](INFO): SBD MOMSN=10140264
2019-03-14T21:43:01.696Z,1552599781.696 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-14T21:43:04.862Z,1552599784.862 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20190314T212600/Express0008.lzma
2019-03-14T21:43:05.671Z,1552599785.671 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Express0008.lzma.bak
2019-03-14T21:43:05.672Z,1552599785.672 [DataOverHttps](INFO): SBD MOMSN=10140266
2019-03-14T21:43:06.565Z,1552599786.565 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T21:43:06.565Z,1552599786.565 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-14T21:43:06.565Z,1552599786.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T21:43:32.401Z,1552599812.401 [NAL9602](INFO): Powering down
2019-03-14T21:44:19.722Z,1552599859.722 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:44:19.722Z,1552599859.722 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-03-14T21:44:19.722Z,1552599859.722 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:44:19.724Z,1552599859.724 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:44:19.724Z,1552599859.724 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-03-14T21:44:19.724Z,1552599859.724 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:44:19.726Z,1552599859.726 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:44:19.726Z,1552599859.726 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-03-14T21:44:19.726Z,1552599859.726 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:44:19.742Z,1552599859.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:44:19.742Z,1552599859.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:44:19.742Z,1552599859.742 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:44:20.097Z,1552599860.097 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:44:20.097Z,1552599860.097 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-03-14T21:44:20.098Z,1552599860.098 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:44:20.098Z,1552599860.098 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-03-14T21:44:20.099Z,1552599860.099 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:44:20.099Z,1552599860.099 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-03-14T21:44:20.497Z,1552599860.497 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:46:18.875Z,1552599978.875 [RDI_Pathfinder](ERROR): Failed to parse:0.00,  1.00
2019-03-14T21:46:20.498Z,1552599980.498 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:46:20.498Z,1552599980.498 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2019-03-14T21:46:20.498Z,1552599980.498 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:46:20.510Z,1552599980.510 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:46:20.921Z,1552599980.921 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:46:20.921Z,1552599980.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-03-14T21:46:20.921Z,1552599980.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:46:20.923Z,1552599980.923 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:46:20.923Z,1552599980.923 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-03-14T21:46:20.923Z,1552599980.923 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:46:20.971Z,1552599980.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:46:20.971Z,1552599980.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:46:20.971Z,1552599980.971 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:46:20.972Z,1552599980.972 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2019-03-14T21:46:21.314Z,1552599981.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:46:21.314Z,1552599981.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-03-14T21:46:21.315Z,1552599981.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:46:21.315Z,1552599981.315 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-03-14T21:46:21.721Z,1552599981.721 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:48:07.152Z,1552600087.152 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-03-14T21:48:07.152Z,1552600087.152 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19031414505799,35.0, -0.1,   0.0  0
2019-03-14T21:48:07.160Z,1552600087.160 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-14T21:48:07.160Z,1552600087.160 [Default:CheckIn:C.Wait] Stopped
2019-03-14T21:48:07.160Z,1552600087.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T21:48:07.160Z,1552600087.160 [Default:CheckIn:D] Running Loop=1
2019-03-14T21:48:07.572Z,1552600087.572 [Default:CheckIn:D] Stopped
2019-03-14T21:48:07.572Z,1552600087.572 [Default:CheckIn:E] Running Loop=1
2019-03-14T21:48:07.997Z,1552600087.997 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.551473 min 
2019-03-14T21:48:07.998Z,1552600087.998 [Default:CheckIn:E] Stopped
2019-03-14T21:48:07.998Z,1552600087.998 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-14T21:48:07.998Z,1552600087.998 [Default:CheckIn] Stopped
2019-03-14T21:48:07.998Z,1552600087.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T21:48:07.998Z,1552600087.998 [Default:CheckIn](INFO): Running loop #3
2019-03-14T21:48:07.999Z,1552600087.999 [Default:CheckIn] Running Loop=3
2019-03-14T21:48:07.999Z,1552600087.999 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T21:48:07.999Z,1552600087.999 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T21:48:08.748Z,1552600088.748 [NAL9602](INFO): Powering up
2019-03-14T21:48:19.657Z,1552600099.657 [NAL9602](INFO): NAL9602 initialized
2019-03-14T21:48:20.474Z,1552600100.474 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-14T21:48:20.474Z,1552600100.474 [NAL9602] Data Fault, FailCount= 2
2019-03-14T21:48:20.474Z,1552600100.474 [NAL9602](ERROR): Data Fault
2019-03-14T21:48:20.507Z,1552600100.507 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T21:48:20.869Z,1552600100.869 [NAL9602](INFO): Powering down
2019-03-14T21:48:21.729Z,1552600101.729 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:48:21.729Z,1552600101.729 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-03-14T21:48:21.729Z,1552600101.729 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:48:21.731Z,1552600101.731 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:48:21.731Z,1552600101.731 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-03-14T21:48:21.731Z,1552600101.731 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:48:21.733Z,1552600101.733 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:48:21.733Z,1552600101.733 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2019-03-14T21:48:21.733Z,1552600101.733 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:48:21.750Z,1552600101.750 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T21:48:21.750Z,1552600101.750 [NAL9602] No Fault, FailCount= 2
2019-03-14T21:48:21.750Z,1552600101.750 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:48:21.751Z,1552600101.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:48:21.751Z,1552600101.751 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:48:22.120Z,1552600102.120 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:48:22.120Z,1552600102.120 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-03-14T21:48:22.121Z,1552600102.121 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:48:22.121Z,1552600102.121 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-03-14T21:48:22.121Z,1552600102.121 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:48:22.121Z,1552600102.121 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2019-03-14T21:48:22.503Z,1552600102.503 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:48:51.172Z,1552600131.172 [NAL9602](INFO): Powering up NAL9602
2019-03-14T21:49:02.083Z,1552600142.083 [NAL9602](INFO): NAL9602 initialized
2019-03-14T21:49:02.902Z,1552600142.902 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:50:22.916Z,1552600222.916 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:50:22.916Z,1552600222.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-03-14T21:50:22.916Z,1552600222.916 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:50:22.922Z,1552600222.922 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:50:22.923Z,1552600222.923 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-03-14T21:50:22.923Z,1552600222.923 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:50:22.924Z,1552600222.924 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:50:22.925Z,1552600222.925 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2019-03-14T21:50:22.925Z,1552600222.925 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:50:22.964Z,1552600222.964 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:50:22.964Z,1552600222.964 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:50:22.964Z,1552600222.964 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:50:23.335Z,1552600223.335 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:50:23.335Z,1552600223.335 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-03-14T21:50:23.336Z,1552600223.336 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:50:23.336Z,1552600223.336 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-03-14T21:50:23.336Z,1552600223.336 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:50:23.337Z,1552600223.337 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2019-03-14T21:50:23.717Z,1552600223.717 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:52:24.105Z,1552600344.105 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:52:24.105Z,1552600344.105 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-03-14T21:52:24.105Z,1552600344.105 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:52:24.107Z,1552600344.107 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:52:24.107Z,1552600344.107 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2019-03-14T21:52:24.107Z,1552600344.107 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:52:24.109Z,1552600344.109 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:52:24.109Z,1552600344.109 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2019-03-14T21:52:24.109Z,1552600344.109 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:52:24.120Z,1552600344.120 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:52:24.120Z,1552600344.120 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:52:24.120Z,1552600344.120 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:52:24.515Z,1552600344.515 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:52:24.515Z,1552600344.515 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-03-14T21:52:24.516Z,1552600344.516 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:52:24.516Z,1552600344.516 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2019-03-14T21:52:24.516Z,1552600344.516 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:52:24.516Z,1552600344.516 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2019-03-14T21:52:24.913Z,1552600344.913 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:52:39.061Z,1552600359.061 [RDI_Pathfinder](ERROR): Failed to parse:
00,   0.00,   0.00,   0.00
2019-03-14T21:53:08.139Z,1552600388.139 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-14T21:48:07.0Z
2019-03-14T21:53:08.139Z,1552600388.139 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T21:53:08.139Z,1552600388.139 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T21:53:13.317Z,1552600393.317 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190314T212600/Courier0010.lzma
2019-03-14T21:53:14.124Z,1552600394.124 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Courier0010.lzma.bak
2019-03-14T21:53:14.124Z,1552600394.124 [DataOverHttps](INFO): SBD MOMSN=10140285
2019-03-14T21:53:19.030Z,1552600399.030 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T21:53:19.030Z,1552600399.030 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T21:53:25.238Z,1552600405.238 [DataOverHttps](INFO): Sending 328 bytes from file Logs/20190314T212600/Express0011.lzma
2019-03-14T21:53:26.044Z,1552600406.044 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Express0011.lzma.bak
2019-03-14T21:53:26.044Z,1552600406.044 [DataOverHttps](INFO): SBD MOMSN=10140287
2019-03-14T21:53:27.133Z,1552600407.133 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T21:53:27.133Z,1552600407.133 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-14T21:53:27.133Z,1552600407.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T21:53:40.445Z,1552600420.445 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T21:53:40.445Z,1552600420.445 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T21:54:04.680Z,1552600444.680 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-14T21:54:25.309Z,1552600465.309 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:54:25.309Z,1552600465.309 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-03-14T21:54:25.309Z,1552600465.309 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:54:25.311Z,1552600465.311 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:54:25.312Z,1552600465.312 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2019-03-14T21:54:25.312Z,1552600465.312 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:54:25.313Z,1552600465.313 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:54:25.313Z,1552600465.313 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11
2019-03-14T21:54:25.313Z,1552600465.313 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:54:25.325Z,1552600465.325 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:54:25.325Z,1552600465.325 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:54:25.325Z,1552600465.325 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:54:25.738Z,1552600465.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:54:25.738Z,1552600465.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-03-14T21:54:25.738Z,1552600465.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:54:25.739Z,1552600465.739 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2019-03-14T21:54:25.739Z,1552600465.739 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:54:25.739Z,1552600465.739 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11
2019-03-14T21:54:26.114Z,1552600466.114 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:54:35.396Z,1552600475.396 [NAL9602](INFO): Powering down
2019-03-14T21:56:26.509Z,1552600586.509 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:56:26.509Z,1552600586.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-03-14T21:56:26.509Z,1552600586.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:56:26.512Z,1552600586.512 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:56:26.512Z,1552600586.512 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2019-03-14T21:56:26.512Z,1552600586.512 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:56:26.514Z,1552600586.514 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:56:26.514Z,1552600586.514 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 12
2019-03-14T21:56:26.514Z,1552600586.514 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:56:26.525Z,1552600586.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:56:26.525Z,1552600586.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:56:26.526Z,1552600586.526 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:56:26.936Z,1552600586.936 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:56:26.936Z,1552600586.936 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-03-14T21:56:26.937Z,1552600586.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:56:26.937Z,1552600586.937 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2019-03-14T21:56:26.937Z,1552600586.937 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:56:26.937Z,1552600586.937 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 12
2019-03-14T21:56:27.311Z,1552600587.311 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:58:27.716Z,1552600707.716 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:58:27.716Z,1552600707.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-03-14T21:58:27.716Z,1552600707.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T21:58:27.718Z,1552600707.718 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:58:27.719Z,1552600707.719 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2019-03-14T21:58:27.719Z,1552600707.719 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T21:58:27.720Z,1552600707.720 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T21:58:27.720Z,1552600707.720 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 13
2019-03-14T21:58:27.721Z,1552600707.721 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T21:58:27.722Z,1552600707.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-14T21:58:27.723Z,1552600707.723 [Default:CheckIn:C.Wait] Stopped
2019-03-14T21:58:27.723Z,1552600707.723 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T21:58:27.723Z,1552600707.723 [Default:CheckIn:D] Running Loop=1
2019-03-14T21:58:27.733Z,1552600707.733 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T21:58:27.733Z,1552600707.733 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T21:58:27.733Z,1552600707.733 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T21:58:28.126Z,1552600708.126 [Default:CheckIn:D] Stopped
2019-03-14T21:58:28.126Z,1552600708.126 [Default:CheckIn:E] Running Loop=1
2019-03-14T21:58:28.136Z,1552600708.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T21:58:28.136Z,1552600708.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-03-14T21:58:28.136Z,1552600708.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T21:58:28.136Z,1552600708.136 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2019-03-14T21:58:28.137Z,1552600708.137 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T21:58:28.137Z,1552600708.137 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 13
2019-03-14T21:58:28.522Z,1552600708.522 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T21:58:28.524Z,1552600708.524 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.894037 min 
2019-03-14T21:58:28.524Z,1552600708.524 [Default:CheckIn:E] Stopped
2019-03-14T21:58:28.525Z,1552600708.525 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-14T21:58:28.525Z,1552600708.525 [Default:CheckIn] Stopped
2019-03-14T21:58:28.525Z,1552600708.525 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T21:58:28.525Z,1552600708.525 [Default:CheckIn](INFO): Running loop #4
2019-03-14T21:58:28.525Z,1552600708.525 [Default:CheckIn] Running Loop=4
2019-03-14T21:58:28.525Z,1552600708.525 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T21:58:28.525Z,1552600708.525 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T21:58:29.300Z,1552600709.300 [NAL9602](INFO): Powering up
2019-03-14T21:58:40.208Z,1552600720.208 [NAL9602](INFO): NAL9602 initialized
2019-03-14T21:58:41.034Z,1552600721.034 [NAL9602](DEBUG): Fix Requested
2019-03-14T21:59:12.129Z,1552600752.129 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-03-14T21:59:12.202Z,1552600752.202 [NAL9602](FAULT): received: 
+CSQ:0

OK79, 2, 0, 0, 0

OK
2019-03-14T21:59:12.202Z,1552600752.202 [NAL9602] Data Fault, FailCount= 3
2019-03-14T21:59:12.202Z,1552600752.202 [NAL9602](ERROR): Data Fault
2019-03-14T21:59:12.242Z,1552600752.242 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T21:59:12.536Z,1552600752.536 [NAL9602](INFO): Powering down
2019-03-14T21:59:13.365Z,1552600753.365 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T21:59:13.365Z,1552600753.365 [NAL9602] No Fault, FailCount= 3
2019-03-14T21:59:42.835Z,1552600782.835 [NAL9602](INFO): Powering up NAL9602
2019-03-14T21:59:53.738Z,1552600793.738 [NAL9602](INFO): NAL9602 initialized
2019-03-14T21:59:54.548Z,1552600794.548 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-14T21:59:54.548Z,1552600794.548 [NAL9602] Data Fault, FailCount= 4
2019-03-14T21:59:54.548Z,1552600794.548 [NAL9602](ERROR): Data Fault
2019-03-14T21:59:54.617Z,1552600794.617 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T21:59:54.948Z,1552600794.948 [NAL9602](INFO): Powering down
2019-03-14T21:59:55.806Z,1552600795.806 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T21:59:55.806Z,1552600795.806 [NAL9602] No Fault, FailCount= 4
2019-03-14T22:00:25.248Z,1552600825.248 [NAL9602](INFO): Powering up NAL9602
2019-03-14T22:00:28.912Z,1552600828.912 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:00:28.912Z,1552600828.912 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-03-14T22:00:28.913Z,1552600828.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:00:28.915Z,1552600828.915 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:00:28.915Z,1552600828.915 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2019-03-14T22:00:28.915Z,1552600828.915 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:00:28.917Z,1552600828.917 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:00:28.917Z,1552600828.917 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 14
2019-03-14T22:00:28.917Z,1552600828.917 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T22:00:28.928Z,1552600828.928 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:00:28.928Z,1552600828.928 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:00:28.928Z,1552600828.928 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:00:29.322Z,1552600829.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:00:29.322Z,1552600829.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-03-14T22:00:29.323Z,1552600829.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:00:29.323Z,1552600829.323 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2019-03-14T22:00:29.324Z,1552600829.324 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T22:00:29.324Z,1552600829.324 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 14
2019-03-14T22:00:29.721Z,1552600829.721 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:00:36.156Z,1552600836.156 [NAL9602](INFO): NAL9602 initialized
2019-03-14T22:00:36.978Z,1552600836.978 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:01:37.570Z,1552600897.570 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T22:01:37.570Z,1552600897.570 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T22:02:02.213Z,1552600922.213 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T22:02:02.213Z,1552600922.213 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T22:02:29.735Z,1552600949.735 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:02:29.735Z,1552600949.735 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-03-14T22:02:29.735Z,1552600949.735 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:02:29.737Z,1552600949.737 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:02:29.737Z,1552600949.737 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2019-03-14T22:02:29.737Z,1552600949.737 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:02:29.739Z,1552600949.739 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:02:29.739Z,1552600949.739 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 15
2019-03-14T22:02:29.739Z,1552600949.739 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T22:02:29.758Z,1552600949.758 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:02:29.759Z,1552600949.759 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:02:29.759Z,1552600949.759 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:02:30.124Z,1552600950.124 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:02:30.124Z,1552600950.124 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-03-14T22:02:30.124Z,1552600950.124 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:02:30.124Z,1552600950.124 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2019-03-14T22:02:30.125Z,1552600950.125 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T22:02:30.125Z,1552600950.125 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 15
2019-03-14T22:02:30.517Z,1552600950.517 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:02:35.348Z,1552600955.348 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T22:02:35.348Z,1552600955.348 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T22:03:01.197Z,1552600981.197 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T22:03:01.198Z,1552600981.198 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T22:03:16.554Z,1552600996.554 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T22:03:16.554Z,1552600996.554 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T22:03:28.697Z,1552601008.697 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-14T21:58:28.5Z
2019-03-14T22:03:28.697Z,1552601008.697 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T22:03:28.698Z,1552601008.698 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T22:03:34.053Z,1552601014.053 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190314T212600/Courier0013.lzma
2019-03-14T22:03:36.072Z,1552601016.072 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Courier0013.lzma.bak
2019-03-14T22:03:36.072Z,1552601016.072 [DataOverHttps](INFO): SBD MOMSN=10140354
2019-03-14T22:03:42.819Z,1552601022.819 [NAL9602](INFO): SBD MO Status=2, MOMSN=2279, MT Status=2, MTMSN=0
2019-03-14T22:03:42.820Z,1552601022.820 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T22:03:43.212Z,1552601023.212 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-14T22:03:52.975Z,1552601032.975 [DataOverHttps](INFO): Sending 367 bytes from file Logs/20190314T212600/Express0014.lzma
2019-03-14T22:03:53.780Z,1552601033.780 [DataOverHttps](INFO): Moved sent file to Logs/20190314T212600/Express0014.lzma.bak
2019-03-14T22:03:53.780Z,1552601033.780 [DataOverHttps](INFO): SBD MOMSN=10140358
2019-03-14T22:03:54.553Z,1552601034.553 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T22:03:54.553Z,1552601034.553 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-14T22:03:54.553Z,1552601034.553 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:04:13.916Z,1552601053.916 [NAL9602](INFO): Powering down
2019-03-14T22:04:30.914Z,1552601070.914 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:04:30.914Z,1552601070.914 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-03-14T22:04:30.914Z,1552601070.914 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:04:30.916Z,1552601070.916 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:04:30.916Z,1552601070.916 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2019-03-14T22:04:30.916Z,1552601070.916 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:04:30.922Z,1552601070.922 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:04:30.922Z,1552601070.922 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 16
2019-03-14T22:04:30.923Z,1552601070.923 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T22:04:30.970Z,1552601070.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:04:30.970Z,1552601070.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:04:30.971Z,1552601070.971 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:04:31.327Z,1552601071.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:04:31.327Z,1552601071.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-03-14T22:04:31.328Z,1552601071.328 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:04:31.328Z,1552601071.328 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2019-03-14T22:04:31.329Z,1552601071.329 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T22:04:31.329Z,1552601071.329 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 16
2019-03-14T22:04:31.721Z,1552601071.721 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:06:32.119Z,1552601192.119 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:06:32.119Z,1552601192.119 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2019-03-14T22:06:32.119Z,1552601192.119 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:06:32.122Z,1552601192.122 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:06:32.122Z,1552601192.122 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2019-03-14T22:06:32.122Z,1552601192.122 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:06:32.124Z,1552601192.124 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:06:32.125Z,1552601192.125 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 17
2019-03-14T22:06:32.125Z,1552601192.125 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T22:06:32.140Z,1552601192.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:06:32.140Z,1552601192.140 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:06:32.140Z,1552601192.140 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:06:32.528Z,1552601192.528 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:06:32.528Z,1552601192.528 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2019-03-14T22:06:32.529Z,1552601192.529 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:06:32.529Z,1552601192.529 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2019-03-14T22:06:32.529Z,1552601192.529 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T22:06:32.529Z,1552601192.529 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 17
2019-03-14T22:06:32.950Z,1552601192.950 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:08:33.309Z,1552601313.309 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:08:33.309Z,1552601313.309 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2019-03-14T22:08:33.309Z,1552601313.309 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:08:33.311Z,1552601313.311 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:08:33.312Z,1552601313.312 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2019-03-14T22:08:33.312Z,1552601313.312 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:08:33.313Z,1552601313.313 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:08:33.313Z,1552601313.313 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 18
2019-03-14T22:08:33.313Z,1552601313.313 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T22:08:33.358Z,1552601313.358 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:08:33.359Z,1552601313.359 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:08:33.359Z,1552601313.359 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:08:33.727Z,1552601313.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:08:33.727Z,1552601313.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2019-03-14T22:08:33.728Z,1552601313.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:08:33.728Z,1552601313.728 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2019-03-14T22:08:33.729Z,1552601313.729 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T22:08:33.729Z,1552601313.729 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 18
2019-03-14T22:08:34.117Z,1552601314.117 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:08:55.133Z,1552601335.133 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-14T22:08:55.133Z,1552601335.133 [Default:CheckIn:C.Wait] Stopped
2019-03-14T22:08:55.133Z,1552601335.133 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:08:55.133Z,1552601335.133 [Default:CheckIn:D] Running Loop=1
2019-03-14T22:08:55.533Z,1552601335.533 [Default:CheckIn:D] Stopped
2019-03-14T22:08:55.533Z,1552601335.533 [Default:CheckIn:E] Running Loop=1
2019-03-14T22:08:55.947Z,1552601335.947 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.350826 min 
2019-03-14T22:08:55.947Z,1552601335.947 [Default:CheckIn:E] Stopped
2019-03-14T22:08:55.948Z,1552601335.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-14T22:08:55.948Z,1552601335.948 [Default:CheckIn] Stopped
2019-03-14T22:08:55.948Z,1552601335.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T22:08:55.948Z,1552601335.948 [Default:CheckIn](INFO): Running loop #5
2019-03-14T22:08:55.948Z,1552601335.948 [Default:CheckIn] Running Loop=5
2019-03-14T22:08:55.948Z,1552601335.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T22:08:55.948Z,1552601335.948 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T22:08:56.716Z,1552601336.716 [NAL9602](INFO): Powering up
2019-03-14T22:09:07.624Z,1552601347.624 [NAL9602](INFO): NAL9602 initialized
2019-03-14T22:09:08.446Z,1552601348.446 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:09:57.722Z,1552601397.722 [NAL9602](INFO): SBD MO Status=0, MOMSN=2279, MT Status=0, MTMSN=0
2019-03-14T22:09:57.722Z,1552601397.722 [NAL9602](INFO): No messages in MT queue
2019-03-14T22:09:58.545Z,1552601398.545 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:00.966Z,1552601400.966 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:03.814Z,1552601403.814 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:06.222Z,1552601406.222 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:09.050Z,1552601409.050 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:11.878Z,1552601411.878 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:14.706Z,1552601414.706 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:17.934Z,1552601417.934 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:20.762Z,1552601420.762 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:23.990Z,1552601423.990 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:26.834Z,1552601426.834 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:30.058Z,1552601430.058 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:32.890Z,1552601432.890 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:34.509Z,1552601434.509 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:10:34.509Z,1552601434.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2019-03-14T22:10:34.509Z,1552601434.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:10:34.511Z,1552601434.511 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:10:34.512Z,1552601434.512 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2019-03-14T22:10:34.512Z,1552601434.512 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:10:34.513Z,1552601434.513 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:10:34.513Z,1552601434.513 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 19
2019-03-14T22:10:34.513Z,1552601434.513 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T22:10:34.525Z,1552601434.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:10:34.525Z,1552601434.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:10:34.525Z,1552601434.525 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:10:34.937Z,1552601434.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:10:34.937Z,1552601434.937 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2019-03-14T22:10:34.937Z,1552601434.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:10:34.938Z,1552601434.938 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2019-03-14T22:10:34.938Z,1552601434.938 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T22:10:34.938Z,1552601434.938 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 19
2019-03-14T22:10:35.328Z,1552601435.328 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:10:36.118Z,1552601436.118 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:10:38.932Z,1552601438.932 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-14T22:10:38.932Z,1552601438.932 [NAL9602] Data Fault, FailCount= 1
2019-03-14T22:10:38.932Z,1552601438.932 [NAL9602](ERROR): Data Fault
2019-03-14T22:10:39.007Z,1552601439.007 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T22:10:39.333Z,1552601439.333 [NAL9602](INFO): Powering down
2019-03-14T22:10:40.177Z,1552601440.177 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T22:10:40.177Z,1552601440.177 [NAL9602] No Fault, FailCount= 1
2019-03-14T22:11:09.644Z,1552601469.644 [NAL9602](INFO): Powering up NAL9602
2019-03-14T22:11:20.544Z,1552601480.544 [NAL9602](INFO): NAL9602 initialized
2019-03-14T22:11:21.362Z,1552601481.362 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:24.190Z,1552601484.190 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:27.018Z,1552601487.018 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:29.846Z,1552601489.846 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:32.682Z,1552601492.682 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:35.906Z,1552601495.906 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:38.738Z,1552601498.738 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:41.966Z,1552601501.966 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:44.798Z,1552601504.798 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:45.221Z,1552601505.221 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-03-14T22:11:45.221Z,1552601505.221 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +5,   -3,A
2019-03-14T22:11:48.028Z,1552601508.028 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:50.862Z,1552601510.862 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:54.086Z,1552601514.086 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:11:56.914Z,1552601516.914 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:00.150Z,1552601520.150 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:02.978Z,1552601522.978 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:05.802Z,1552601525.802 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:09.034Z,1552601529.034 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:11.858Z,1552601531.858 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:14.690Z,1552601534.690 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:17.922Z,1552601537.922 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:20.750Z,1552601540.750 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:23.547Z,1552601543.547 [CommandLine](IMPORTANT): got command failComponent
2019-03-14T22:12:23.547Z,1552601543.547 [CommandLine](IMPORTANT): Failed components:
2019-03-14T22:12:23.547Z,1552601543.547 [CommandLine](IMPORTANT): No failed Components.
2019-03-14T22:12:23.978Z,1552601543.978 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:26.395Z,1552601546.395 [CommandLine](IMPORTANT): got command show variable USBL
2019-03-14T22:12:26.494Z,1552601546.494 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-14T22:12:26.495Z,1552601546.495 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-14T22:12:26.495Z,1552601546.495 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (millisecond)
2019-03-14T22:12:26.495Z,1552601546.495 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-14T22:12:26.496Z,1552601546.496 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-14T22:12:26.496Z,1552601546.496 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-14T22:12:26.497Z,1552601546.497 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-14T22:12:26.497Z,1552601546.497 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-14T22:12:26.497Z,1552601546.497 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-14T22:12:26.498Z,1552601546.498 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-14T22:12:26.498Z,1552601546.498 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-14T22:12:26.498Z,1552601546.498 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-14T22:12:26.508Z,1552601546.508 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-14T22:12:26.508Z,1552601546.508 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-14T22:12:26.508Z,1552601546.508 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-14T22:12:26.511Z,1552601546.511 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-14T22:12:26.512Z,1552601546.512 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-14T22:12:26.512Z,1552601546.512 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-14T22:12:26.512Z,1552601546.512 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-14T22:12:26.513Z,1552601546.513 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-14T22:12:26.513Z,1552601546.513 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-14T22:12:26.513Z,1552601546.513 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-14T22:12:26.514Z,1552601546.514 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-14T22:12:26.514Z,1552601546.514 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-14T22:12:26.515Z,1552601546.515 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-14T22:12:26.815Z,1552601546.815 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:30.042Z,1552601550.042 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:32.874Z,1552601552.874 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:35.716Z,1552601555.716 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:12:35.716Z,1552601555.716 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2019-03-14T22:12:35.716Z,1552601555.716 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:12:35.718Z,1552601555.718 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:12:35.718Z,1552601555.718 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2019-03-14T22:12:35.718Z,1552601555.718 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:12:35.720Z,1552601555.720 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:12:35.720Z,1552601555.720 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 20
2019-03-14T22:12:35.720Z,1552601555.720 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T22:12:35.751Z,1552601555.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:12:35.751Z,1552601555.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:12:35.751Z,1552601555.751 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:12:35.752Z,1552601555.752 [CBIT](FAULT): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T22:12:36.102Z,1552601556.102 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:36.141Z,1552601556.141 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:12:36.142Z,1552601556.142 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2019-03-14T22:12:36.143Z,1552601556.143 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:12:36.143Z,1552601556.143 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2019-03-14T22:12:36.529Z,1552601556.529 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:12:38.930Z,1552601558.930 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:39.284Z,1552601559.284 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.range
2019-03-14T22:12:42.158Z,1552601562.158 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:44.079Z,1552601564.079 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-14T22:12:44.079Z,1552601564.079 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-14T22:12:44.105Z,1552601564.105 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-14T22:12:44.109Z,1552601564.109 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-14T22:12:44.113Z,1552601564.113 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-14T22:12:44.116Z,1552601564.116 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-14T22:12:44.120Z,1552601564.120 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-14T22:12:44.121Z,1552601564.121 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-14T22:12:44.140Z,1552601564.140 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-14T22:12:44.148Z,1552601564.148 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-14T22:12:44.224Z,1552601564.224 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>90</Value></DefineArg>

    <DefineArg Name="NumberOfRequests"><Description>
        Number of pings to requst.
    </Description><Units:count/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>   

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>      
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Science:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    <ReadData>
        <While>
            <Arg Name="EnabledDUSBL"/>
        </While>
        <Sensor:DUSBL_Hydroid.range/>
        <Sensor:DUSBL_Hydroid.direction/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

        <Sequence Repeat="NumberOfRequests"/>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.transponderCode/><Arg Name="TransponderCode"/></Assign>
        <Assign><Parallel/><Sensor:DUSBL_Hydroid.numberOfPingsRequested/><Arg Name="NumberOfPings"/></Assign>
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:second/><Value>10</Value></Setting>
          </Guidance:Wait>
      </Aggregate>    
</Mission>


2019-03-14T22:12:44.239Z,1552601564.239 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-14T22:12:44.600Z,1552601564.600 [Default] Stopped
2019-03-14T22:12:44.600Z,1552601564.600 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-14T22:12:44.600Z,1552601564.600 [Default:B.GoToSurface] Stopped
2019-03-14T22:12:44.600Z,1552601564.600 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T22:12:44.601Z,1552601564.601 [Default:CheckIn] Stopped
2019-03-14T22:12:44.601Z,1552601564.601 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T22:12:44.601Z,1552601564.601 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T22:12:44.601Z,1552601564.601 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-14T22:12:44.601Z,1552601564.601 [DUSBL] Running Loop=1
2019-03-14T22:12:44.601Z,1552601564.601 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-14T22:12:44.601Z,1552601564.601 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T22:12:44.601Z,1552601564.601 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-14T22:12:44.602Z,1552601564.602 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T22:12:44.602Z,1552601564.602 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-14T22:12:44.602Z,1552601564.602 [DUSBL:C] Running Loop=1
2019-03-14T22:12:44.602Z,1552601564.602 [DUSBL:RequestRepeater] Running Loop=1
2019-03-14T22:12:44.602Z,1552601564.602 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:12:44.603Z,1552601564.603 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:12:44.603Z,1552601564.603 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:12:44.603Z,1552601564.603 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:12:44.603Z,1552601564.603 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:12:44.603Z,1552601564.603 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:12:44.603Z,1552601564.603 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:12:44.604Z,1552601564.604 [DUSBL:C] Running Loop=1
2019-03-14T22:12:44.604Z,1552601564.604 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-14T22:12:44.605Z,1552601564.605 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-14T22:12:44.605Z,1552601564.605 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T22:12:44.605Z,1552601564.605 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T22:12:44.990Z,1552601564.990 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:12:46.188Z,1552601566.188 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:12:46.193Z,1552601566.193 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:12:46.194Z,1552601566.194 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:46.194Z,1552601566.194 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:46.194Z,1552601566.194 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:50.228Z,1552601570.228 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:12:50.230Z,1552601570.230 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:12:50.232Z,1552601570.232 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:50.233Z,1552601570.233 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:50.233Z,1552601570.233 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:50.633Z,1552601570.633 [DUSBL_Hydroid](INFO): TAT:50.000000
2019-03-14T22:12:50.679Z,1552601570.679 [Reporter](INFO): DUSBL_Hydroid.range -37457.761719 m
2019-03-14T22:12:54.273Z,1552601574.273 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:12:54.275Z,1552601574.275 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:12:54.277Z,1552601574.277 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:54.277Z,1552601574.277 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:54.277Z,1552601574.277 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:54.704Z,1552601574.704 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:12:54.704Z,1552601574.704 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:12:54.704Z,1552601574.704 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:12:54.705Z,1552601574.705 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:12:54.705Z,1552601574.705 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:12:54.705Z,1552601574.705 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:12:54.705Z,1552601574.705 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:12:54.705Z,1552601574.705 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:12:54.706Z,1552601574.706 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-14T22:12:54.706Z,1552601574.706 [DUSBL:RequestRepeater] Running Loop=2
2019-03-14T22:12:54.706Z,1552601574.706 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:12:54.706Z,1552601574.706 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:12:54.706Z,1552601574.706 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:12:54.706Z,1552601574.706 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:12:54.706Z,1552601574.706 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:12:58.312Z,1552601578.312 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:12:58.317Z,1552601578.317 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:12:58.317Z,1552601578.317 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:58.317Z,1552601578.317 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:12:58.317Z,1552601578.317 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:02.347Z,1552601582.347 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:02.348Z,1552601582.348 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:02.348Z,1552601582.348 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:02.349Z,1552601582.349 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:02.349Z,1552601582.349 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:05.213Z,1552601585.213 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:13:05.213Z,1552601585.213 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:13:05.213Z,1552601585.213 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:13:05.214Z,1552601585.214 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:13:05.214Z,1552601585.214 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:13:05.214Z,1552601585.214 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:13:05.214Z,1552601585.214 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater](INFO): Running loop #3
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater] Running Loop=3
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:13:05.215Z,1552601585.215 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:13:06.388Z,1552601586.388 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:06.391Z,1552601586.391 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:06.393Z,1552601586.393 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:06.393Z,1552601586.393 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:06.393Z,1552601586.393 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:06.801Z,1552601586.801 [DUSBL_Hydroid](INFO): TAT:50.000000
2019-03-14T22:13:06.891Z,1552601586.891 [Reporter](INFO): DUSBL_Hydroid.range -37363.320312 m
2019-03-14T22:13:10.434Z,1552601590.434 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:10.439Z,1552601590.439 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:10.439Z,1552601590.439 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:10.440Z,1552601590.440 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:10.440Z,1552601590.440 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:10.843Z,1552601590.843 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314221309.648114,06,81,13,0106,0150,246,00,00,01,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,203,0.00,-999,9760,4000*64

2019-03-14T22:13:14.472Z,1552601594.472 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:14.474Z,1552601594.474 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:14.476Z,1552601594.476 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:14.477Z,1552601594.477 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:14.477Z,1552601594.477 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:15.714Z,1552601595.714 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:13:15.714Z,1552601595.714 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:13:15.714Z,1552601595.714 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater](INFO): Running loop #4
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater] Running Loop=4
2019-03-14T22:13:15.715Z,1552601595.715 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:13:15.716Z,1552601595.716 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:13:15.716Z,1552601595.716 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:13:15.716Z,1552601595.716 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:13:15.716Z,1552601595.716 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:13:17.701Z,1552601597.701 [NAL9602](INFO): Powering down
2019-03-14T22:13:18.508Z,1552601598.508 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:18.509Z,1552601598.509 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:18.510Z,1552601598.510 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:18.511Z,1552601598.511 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:18.512Z,1552601598.512 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:18.913Z,1552601598.913 [Micromodem](ERROR): Response from modem unexpected: $CADQF,193,1*5B

2019-03-14T22:13:18.915Z,1552601598.915 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T22:13:19.321Z,1552601599.321 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314221318.186915,06,564,15,0115,0150,246,00,00,02,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,193,-0.05,-999,9760,4000*70

2019-03-14T22:13:22.548Z,1552601602.548 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:22.553Z,1552601602.553 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:22.553Z,1552601602.553 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:22.553Z,1552601602.553 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:22.553Z,1552601602.553 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:26.213Z,1552601606.213 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:13:26.213Z,1552601606.213 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:13:26.213Z,1552601606.213 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:13:26.214Z,1552601606.214 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:13:26.214Z,1552601606.214 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:13:26.214Z,1552601606.214 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:13:26.214Z,1552601606.214 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:13:26.214Z,1552601606.214 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:13:26.215Z,1552601606.215 [DUSBL:RequestRepeater](INFO): Running loop #5
2019-03-14T22:13:26.215Z,1552601606.215 [DUSBL:RequestRepeater] Running Loop=5
2019-03-14T22:13:26.215Z,1552601606.215 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:13:26.215Z,1552601606.215 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:13:26.215Z,1552601606.215 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:13:26.215Z,1552601606.215 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:13:26.215Z,1552601606.215 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:13:26.587Z,1552601606.587 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:26.588Z,1552601606.588 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:26.589Z,1552601606.589 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:26.589Z,1552601606.589 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:26.589Z,1552601606.589 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:30.632Z,1552601610.632 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:30.637Z,1552601610.637 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:30.638Z,1552601610.638 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:30.638Z,1552601610.638 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:30.638Z,1552601610.638 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:31.033Z,1552601611.033 [Micromodem](ERROR): Response from modem unexpected: $CADQF,185,1*5C

2019-03-14T22:13:31.035Z,1552601611.035 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T22:13:31.441Z,1552601611.441 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314221330.311085,06,314,12,0091,0150,246,00,00,02,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,185,-0.10,-999,9760,4000*7D

2019-03-14T22:13:34.672Z,1552601614.672 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:34.677Z,1552601614.677 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:34.678Z,1552601614.678 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:34.678Z,1552601614.678 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:34.678Z,1552601614.678 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:36.716Z,1552601616.716 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:13:36.717Z,1552601616.717 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:13:36.717Z,1552601616.717 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:13:36.717Z,1552601616.717 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:13:36.717Z,1552601616.717 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:13:36.717Z,1552601616.717 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:13:36.717Z,1552601616.717 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater](INFO): Running loop #6
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater] Running Loop=6
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:13:36.718Z,1552601616.718 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:13:38.712Z,1552601618.712 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:38.714Z,1552601618.714 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:38.716Z,1552601618.716 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:38.717Z,1552601618.717 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:38.717Z,1552601618.717 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:42.749Z,1552601622.749 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:42.751Z,1552601622.751 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:42.753Z,1552601622.753 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:42.753Z,1552601622.753 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:42.753Z,1552601622.753 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:46.788Z,1552601626.788 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:46.796Z,1552601626.796 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:46.797Z,1552601626.797 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:46.797Z,1552601626.797 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:46.798Z,1552601626.798 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:47.193Z,1552601627.193 [Micromodem](ERROR): Response from modem unexpected: $CADQF,201,1*53

2019-03-14T22:13:47.194Z,1552601627.194 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T22:13:47.221Z,1552601627.221 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:13:47.221Z,1552601627.221 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:13:47.221Z,1552601627.221 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:13:47.221Z,1552601627.221 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:13:47.221Z,1552601627.221 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater](INFO): Running loop #7
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater] Running Loop=7
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:13:47.222Z,1552601627.222 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:13:47.223Z,1552601627.223 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:13:47.601Z,1552601627.601 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314221346.237219,06,367,13,0090,0150,246,00,00,02,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,201,0.05,-999,9760,4000*5F

2019-03-14T22:13:50.835Z,1552601630.835 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:50.837Z,1552601630.837 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:50.839Z,1552601630.839 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:50.839Z,1552601630.839 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:50.840Z,1552601630.840 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:54.868Z,1552601634.868 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:54.868Z,1552601634.868 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:54.868Z,1552601634.868 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:54.869Z,1552601634.869 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:54.869Z,1552601634.869 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:57.749Z,1552601637.749 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:13:57.749Z,1552601637.749 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:13:57.749Z,1552601637.749 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:13:57.754Z,1552601637.754 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:13:57.754Z,1552601637.754 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:13:57.754Z,1552601637.754 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater](INFO): Running loop #8
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater] Running Loop=8
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:13:57.755Z,1552601637.755 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:13:58.912Z,1552601638.912 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:13:58.917Z,1552601638.917 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:13:58.917Z,1552601638.917 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:58.917Z,1552601638.917 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:13:58.917Z,1552601638.917 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:02.948Z,1552601642.948 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:14:02.950Z,1552601642.950 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:14:02.952Z,1552601642.952 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:02.953Z,1552601642.953 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:02.953Z,1552601642.953 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:06.990Z,1552601646.990 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:14:06.992Z,1552601646.992 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:14:06.994Z,1552601646.994 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:06.995Z,1552601646.995 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:06.995Z,1552601646.995 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:08.228Z,1552601648.228 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:14:08.228Z,1552601648.228 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:14:08.228Z,1552601648.228 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:14:08.229Z,1552601648.229 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:14:08.229Z,1552601648.229 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater](INFO): Running loop #9
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater] Running Loop=9
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:14:08.230Z,1552601648.230 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:14:08.231Z,1552601648.231 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:14:11.028Z,1552601651.028 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:14:11.033Z,1552601651.033 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:14:11.033Z,1552601651.033 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:11.034Z,1552601651.034 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:11.034Z,1552601651.034 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:15.068Z,1552601655.068 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:14:15.070Z,1552601655.070 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:14:15.072Z,1552601655.072 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:15.073Z,1552601655.073 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:15.073Z,1552601655.073 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:18.736Z,1552601658.736 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:14:18.736Z,1552601658.736 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:14:18.736Z,1552601658.736 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:14:18.737Z,1552601658.737 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:14:18.737Z,1552601658.737 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:14:18.737Z,1552601658.737 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:14:18.737Z,1552601658.737 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:14:18.737Z,1552601658.737 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:14:18.738Z,1552601658.738 [DUSBL:RequestRepeater](INFO): Running loop #10
2019-03-14T22:14:18.738Z,1552601658.738 [DUSBL:RequestRepeater] Running Loop=10
2019-03-14T22:14:18.738Z,1552601658.738 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T22:14:18.738Z,1552601658.738 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T22:14:18.738Z,1552601658.738 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T22:14:18.738Z,1552601658.738 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T22:14:18.738Z,1552601658.738 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:14:19.113Z,1552601659.113 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:14:19.115Z,1552601659.115 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:14:19.116Z,1552601659.116 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:19.117Z,1552601659.117 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:19.117Z,1552601659.117 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:23.152Z,1552601663.152 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:14:23.157Z,1552601663.157 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:14:23.157Z,1552601663.157 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:23.157Z,1552601663.157 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:23.158Z,1552601663.158 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:27.188Z,1552601667.188 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T22:14:27.190Z,1552601667.190 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T22:14:27.192Z,1552601667.192 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:27.193Z,1552601667.193 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:27.193Z,1552601667.193 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T22:14:29.241Z,1552601669.241 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T22:14:29.241Z,1552601669.241 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T22:14:29.241Z,1552601669.241 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:14:29.241Z,1552601669.241 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T22:14:29.241Z,1552601669.241 [DUSBL:RequestRepeater] Stopped
2019-03-14T22:14:29.242Z,1552601669.242 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T22:14:29.242Z,1552601669.242 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T22:14:29.242Z,1552601669.242 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T22:14:29.242Z,1552601669.242 [DUSBL](INFO): Completed DUSBL
2019-03-14T22:14:29.243Z,1552601669.243 [MissionManager](INFO): DUSBL is completed.
2019-03-14T22:14:29.243Z,1552601669.243 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-14T22:14:29.243Z,1552601669.243 [DUSBL] Stopped
2019-03-14T22:14:29.243Z,1552601669.243 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-14T22:14:29.243Z,1552601669.243 [DUSBL:A.Pitch] Stopped
2019-03-14T22:14:29.243Z,1552601669.243 [DUSBL:B.SetSpeed] Stopped
2019-03-14T22:14:29.243Z,1552601669.243 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-14T22:14:29.243Z,1552601669.243 [DUSBL:C] Stopped
2019-03-14T22:14:29.667Z,1552601669.667 [MissionManager](IMPORTANT): Started mission Default
2019-03-14T22:14:29.668Z,1552601669.668 [Default] Running Loop=1
2019-03-14T22:14:29.668Z,1552601669.668 [Default](DEBUG): Aggregate::initialize Default
2019-03-14T22:14:29.668Z,1552601669.668 [Default:B.GoToSurface] Running Loop=1
2019-03-14T22:14:29.668Z,1552601669.668 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T22:14:29.669Z,1552601669.669 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T22:14:29.674Z,1552601669.674 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T22:14:29.674Z,1552601669.674 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T22:14:29.675Z,1552601669.675 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T22:14:29.675Z,1552601669.675 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T22:14:29.675Z,1552601669.675 [Default:A.Wait] Running Loop=1
2019-03-14T22:14:29.675Z,1552601669.675 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-14T22:14:36.921Z,1552601676.921 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:14:36.921Z,1552601676.921 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2019-03-14T22:14:36.921Z,1552601676.921 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T22:14:36.924Z,1552601676.924 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T22:14:36.924Z,1552601676.924 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21
2019-03-14T22:14:36.924Z,1552601676.924 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T22:14:36.935Z,1552601676.935 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T22:14:36.936Z,1552601676.936 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T22:14:37.323Z,1552601677.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T22:14:37.323Z,1552601677.323 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2019-03-14T22:14:37.324Z,1552601677.324 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T22:14:37.324Z,1552601677.324 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21
2019-03-14T22:14:37.732Z,1552601677.732 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T22:14:42.981Z,1552601682.981 [Default:A.Wait](INFO): Done Waiting.
2019-03-14T22:14:42.981Z,1552601682.981 [Default:A.Wait] Stopped
2019-03-14T22:14:42.981Z,1552601682.981 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T22:14:43.377Z,1552601683.377 [Default:CheckIn] Running Loop=1
2019-03-14T22:14:43.377Z,1552601683.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T22:14:43.377Z,1552601683.377 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T22:14:44.158Z,1552601684.158 [NAL9602](INFO): Powering up
2019-03-14T22:14:55.068Z,1552601695.068 [NAL9602](INFO): NAL9602 initialized
2019-03-14T22:14:55.894Z,1552601695.894 [NAL9602](DEBUG): Fix Requested
2019-03-14T22:15:12.053Z,1552601712.053 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-03-14T22:15:12.053Z,1552601712.053 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,   0.00,   0.00,   0.00
2019-03-14T22:16:26.040Z,1552601786.040 [CommandLine](IMPORTANT): got command restart application
2019-03-14T22:16:27.046Z,1552601787.046 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-03-14T22:16:27.046Z,1552601787.046 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:27.047Z,1552601787.047 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.195Z,1552601787.195 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-14T22:16:27.195Z,1552601787.195 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.196Z,1552601787.196 [CommandLine](INFO): Join timeout helper Thread ID is 1526
2019-03-14T22:16:27.196Z,1552601787.196 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-14T22:16:27.198Z,1552601787.198 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.206Z,1552601787.206 [NavChartDb](INFO): Join timeout helper Thread ID is 1527
2019-03-14T22:16:27.326Z,1552601787.326 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:27.327Z,1552601787.327 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.334Z,1552601787.334 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-14T22:16:27.334Z,1552601787.334 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.335Z,1552601787.335 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1528
2019-03-14T22:16:27.366Z,1552601787.366 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:27.366Z,1552601787.366 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-14T22:16:27.367Z,1552601787.367 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.374Z,1552601787.374 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-14T22:16:27.374Z,1552601787.374 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.375Z,1552601787.375 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1529
2019-03-14T22:16:27.570Z,1552601787.570 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:27.571Z,1552601787.571 [CTD_NeilBrown](INFO): Powering down
2019-03-14T22:16:27.586Z,1552601787.586 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.600Z,1552601787.600 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-14T22:16:27.600Z,1552601787.600 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.600Z,1552601787.600 [Radio_Surface](INFO): Join timeout helper Thread ID is 1530
2019-03-14T22:16:27.822Z,1552601787.822 [Radio_Surface](INFO): Powering down
2019-03-14T22:16:27.823Z,1552601787.823 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:27.823Z,1552601787.823 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.839Z,1552601787.839 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-14T22:16:27.839Z,1552601787.839 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.840Z,1552601787.840 [DataOverHttps](INFO): Join timeout helper Thread ID is 1531
2019-03-14T22:16:27.922Z,1552601787.922 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:27.923Z,1552601787.923 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.939Z,1552601787.939 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-14T22:16:27.939Z,1552601787.939 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:27.939Z,1552601787.939 [logger](INFO): Join timeout helper Thread ID is 1532
2019-03-14T22:16:28.006Z,1552601788.006 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:28.006Z,1552601788.006 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.019Z,1552601788.019 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-14T22:16:28.019Z,1552601788.019 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.019Z,1552601788.019 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-14T22:16:28.019Z,1552601788.019 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.020Z,1552601788.020 [controlThread](INFO): Join timeout helper Thread ID is 1533
2019-03-14T22:16:28.038Z,1552601788.038 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T22:16:28.039Z,1552601788.039 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-14T22:16:28.039Z,1552601788.039 [DUSBL_Hydroid](INFO): Powering down
2019-03-14T22:16:28.111Z,1552601788.111 [Micromodem](INFO): uninitialize
2019-03-14T22:16:28.111Z,1552601788.111 [Micromodem](INFO): Powering down
2019-03-14T22:16:28.206Z,1552601788.206 [NAL9602](INFO): Powering down
2019-03-14T22:16:28.278Z,1552601788.278 [RDI_Pathfinder](INFO): Powering down
2019-03-14T22:16:28.279Z,1552601788.279 [RDI_PathfinderUp](INFO): Powering down
2019-03-14T22:16:28.280Z,1552601788.280 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-14T22:16:28.281Z,1552601788.281 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-14T22:16:28.282Z,1552601788.282 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-14T22:16:28.283Z,1552601788.283 [MissionManager](INFO): Uninitializing Mission Default
2019-03-14T22:16:28.283Z,1552601788.283 [Default] Stopped
2019-03-14T22:16:28.283Z,1552601788.283 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-14T22:16:28.283Z,1552601788.283 [Default:B.GoToSurface] Stopped
2019-03-14T22:16:28.283Z,1552601788.283 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T22:16:28.283Z,1552601788.283 [Default:CheckIn] Stopped
2019-03-14T22:16:28.283Z,1552601788.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T22:16:28.283Z,1552601788.283 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T22:16:28.286Z,1552601788.286 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-14T22:16:28.286Z,1552601788.286 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-14T22:16:28.286Z,1552601788.286 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-14T22:16:28.287Z,1552601788.287 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-14T22:16:28.287Z,1552601788.287 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-14T22:16:28.287Z,1552601788.287 [BuoyancyServo](INFO): Powering down
2019-03-14T22:16:28.298Z,1552601788.298 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-14T22:16:28.298Z,1552601788.298 [ElevatorServo](INFO): Powering down
2019-03-14T22:16:28.299Z,1552601788.299 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-14T22:16:28.299Z,1552601788.299 [MassServo](INFO): Powering down
2019-03-14T22:16:28.300Z,1552601788.300 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-14T22:16:28.300Z,1552601788.300 [RudderServo](INFO): Powering down
2019-03-14T22:16:28.301Z,1552601788.301 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-14T22:16:28.301Z,1552601788.301 [ThrusterServo](INFO): Powering down
2019-03-14T22:16:28.302Z,1552601788.302 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-14T22:16:28.302Z,1552601788.302 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-14T22:16:28.303Z,1552601788.303 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-14T22:16:28.303Z,1552601788.303 [CBIT](DEBUG): Powering off loads.
2019-03-14T22:16:28.314Z,1552601788.314 [CBIT](DEBUG): Disabling WDT.
2019-03-14T22:16:28.326Z,1552601788.326 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-14T22:16:28.327Z,1552601788.327 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.391Z,1552601788.391 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.400Z,1552601788.400 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.444Z,1552601788.444 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.446Z,1552601788.446 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.499Z,1552601788.499 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-14T22:16:28.568Z,1552601788.568 [logger ThreadHandler](INFO): Thread cancelled.