2019-08-09T19:26:44.492Z,1565378804.492 [Supervisor](DEBUG): Initializing supervisor.
2019-08-09T19:26:44.495Z,1565378804.495 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-08-09T19:26:44.495Z,1565378804.495 [SyncHandler](INFO): Protected caller Thread ID is 4141
2019-08-09T19:26:44.496Z,1565378804.496 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-08-09T19:26:44.497Z,1565378804.497 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-08-09T19:26:44.497Z,1565378804.497 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4142
2019-08-09T19:26:44.500Z,1565378804.500 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-08-09T19:26:44.511Z,1565378804.511 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-08-09T19:26:44.512Z,1565378804.512 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-08-09T19:26:44.513Z,1565378804.513 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4143
2019-08-09T19:26:44.514Z,1565378804.514 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-08-09T19:26:44.515Z,1565378804.515 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-08-09T19:26:44.515Z,1565378804.515 [logger ThreadHandler](INFO): Protected caller Thread ID is 4144
2019-08-09T19:26:44.517Z,1565378804.517 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-08-09T19:26:44.517Z,1565378804.517 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-08-09T19:26:44.519Z,1565378804.519 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-08-09T19:26:44.933Z,1565378804.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-08-09T19:26:44.933Z,1565378804.933 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-08-09T19:26:45.030Z,1565378805.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-08-09T19:26:45.030Z,1565378805.030 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-08-09T19:26:45.341Z,1565378805.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-08-09T19:26:45.341Z,1565378805.341 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-08-09T19:26:45.479Z,1565378805.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-08-09T19:26:45.479Z,1565378805.479 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-08-09T19:26:45.668Z,1565378805.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-08-09T19:26:45.669Z,1565378805.669 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-08-09T19:26:46.187Z,1565378806.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-08-09T19:26:46.188Z,1565378806.188 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-08-09T19:26:46.390Z,1565378806.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-08-09T19:26:46.390Z,1565378806.390 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-08-09T19:26:46.531Z,1565378806.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-08-09T19:26:46.531Z,1565378806.531 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-08-09T19:26:46.719Z,1565378806.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-08-09T19:26:46.720Z,1565378806.720 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-08-09T19:26:46.814Z,1565378806.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-08-09T19:26:46.814Z,1565378806.814 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-08-09T19:26:47.108Z,1565378807.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-08-09T19:26:47.108Z,1565378807.108 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-08-09T19:26:47.186Z,1565378807.186 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-08-09T19:26:47.286Z,1565378807.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-08-09T19:26:47.287Z,1565378807.287 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-08-09T19:26:47.867Z,1565378807.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-08-09T19:26:47.868Z,1565378807.868 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-08-09T19:26:48.260Z,1565378808.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-08-09T19:26:48.262Z,1565378808.262 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-08-09T19:26:48.263Z,1565378808.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-08-09T19:26:48.466Z,1565378808.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-08-09T19:26:48.564Z,1565378808.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-08-09T19:26:48.661Z,1565378808.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-08-09T19:26:48.886Z,1565378808.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-08-09T19:26:48.887Z,1565378808.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-08-09T19:26:48.970Z,1565378808.970 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-08-09T19:26:49.060Z,1565378809.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-08-09T19:26:49.154Z,1565378809.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-08-09T19:26:49.266Z,1565378809.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-08-09T19:26:49.606Z,1565378809.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-08-09T19:26:49.915Z,1565378809.915 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-08-09T19:26:50.348Z,1565378810.348 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-08-09T19:26:50.348Z,1565378810.348 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-08-09T19:26:50.358Z,1565378810.358 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-08-09T19:26:50.752Z,1565378810.752 [AHRS_M2] Loaded
2019-08-09T19:26:50.753Z,1565378810.753 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-08-09T19:26:50.827Z,1565378810.827 [DataOverHttps] Loaded
2019-08-09T19:26:50.828Z,1565378810.828 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-08-09T19:26:50.829Z,1565378810.829 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407664E0
2019-08-09T19:26:50.829Z,1565378810.829 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4224
2019-08-09T19:26:50.842Z,1565378810.842 [Depth_Keller] Loaded
2019-08-09T19:26:50.843Z,1565378810.843 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-08-09T19:26:50.847Z,1565378810.847 [DropWeight] Loaded
2019-08-09T19:26:50.848Z,1565378810.848 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-08-09T19:26:50.905Z,1565378810.905 [DUSBL_Hydroid] Loaded
2019-08-09T19:26:50.905Z,1565378810.905 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-08-09T19:26:50.954Z,1565378810.954 [Micromodem] Loaded
2019-08-09T19:26:50.954Z,1565378810.954 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-08-09T19:26:51.054Z,1565378811.054 [NAL9602] Loaded
2019-08-09T19:26:51.054Z,1565378811.054 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-08-09T19:26:51.071Z,1565378811.071 [Onboard] Loaded
2019-08-09T19:26:51.071Z,1565378811.071 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-08-09T19:26:51.077Z,1565378811.077 [PowerOnly] Loaded
2019-08-09T19:26:51.077Z,1565378811.077 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-08-09T19:26:51.084Z,1565378811.084 [Radio_Surface] Loaded
2019-08-09T19:26:51.084Z,1565378811.084 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-08-09T19:26:51.085Z,1565378811.085 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407964E0
2019-08-09T19:26:51.085Z,1565378811.085 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4225
2019-08-09T19:26:51.131Z,1565378811.131 [RDI_Pathfinder] Loaded
2019-08-09T19:26:51.131Z,1565378811.131 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-08-09T19:26:52.661Z,1565378812.661 [BPC1] Loaded
2019-08-09T19:26:52.662Z,1565378812.662 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-08-09T19:26:52.662Z,1565378812.662 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-08-09T19:26:52.663Z,1565378812.663 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-08-09T19:26:52.677Z,1565378812.677 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-08-09T19:26:52.677Z,1565378812.677 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-08-09T19:26:52.787Z,1565378812.787 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-08-09T19:26:52.788Z,1565378812.788 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-08-09T19:26:52.808Z,1565378812.808 [NavChart] Loaded
2019-08-09T19:26:52.809Z,1565378812.809 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-08-09T19:26:52.813Z,1565378812.813 [UniversalFixResidualReporter] Loaded
2019-08-09T19:26:52.813Z,1565378812.813 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-08-09T19:26:52.813Z,1565378812.813 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-08-09T19:26:52.814Z,1565378812.814 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-08-09T19:26:52.926Z,1565378812.926 [BuoyancyServo] Loaded
2019-08-09T19:26:52.926Z,1565378812.926 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-08-09T19:26:52.941Z,1565378812.941 [ElevatorServo] Loaded
2019-08-09T19:26:52.941Z,1565378812.941 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-08-09T19:26:52.956Z,1565378812.956 [MassServo] Loaded
2019-08-09T19:26:52.956Z,1565378812.956 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-08-09T19:26:52.971Z,1565378812.971 [RudderServo] Loaded
2019-08-09T19:26:52.972Z,1565378812.972 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-08-09T19:26:52.987Z,1565378812.987 [ThrusterServo] Loaded
2019-08-09T19:26:52.987Z,1565378812.987 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-08-09T19:26:52.987Z,1565378812.987 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-08-09T19:26:52.988Z,1565378812.988 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-08-09T19:26:53.251Z,1565378813.251 [CTD_NeilBrown] Loaded
2019-08-09T19:26:53.251Z,1565378813.251 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-08-09T19:26:53.252Z,1565378813.252 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0
2019-08-09T19:26:53.252Z,1565378813.252 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4226
2019-08-09T19:26:53.283Z,1565378813.283 [ESPComponent] Loaded
2019-08-09T19:26:53.283Z,1565378813.283 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-08-09T19:26:53.328Z,1565378813.328 [WetLabsSeaOWL_UV_A] Loaded
2019-08-09T19:26:53.328Z,1565378813.328 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-08-09T19:26:53.329Z,1565378813.329 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409114E0
2019-08-09T19:26:53.329Z,1565378813.329 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 4227
2019-08-09T19:26:53.330Z,1565378813.330 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-08-09T19:26:53.331Z,1565378813.331 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-08-09T19:26:53.638Z,1565378813.638 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-08-09T19:26:53.638Z,1565378813.638 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-08-09T19:26:53.683Z,1565378813.683 [DepthRateCalculator] Loaded
2019-08-09T19:26:53.683Z,1565378813.683 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-08-09T19:26:53.689Z,1565378813.689 [PitchRateCalculator] Loaded
2019-08-09T19:26:53.689Z,1565378813.689 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-08-09T19:26:53.702Z,1565378813.702 [SpeedCalculator] Loaded
2019-08-09T19:26:53.702Z,1565378813.702 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-08-09T19:26:53.723Z,1565378813.723 [TempGradientCalculator] Loaded
2019-08-09T19:26:53.724Z,1565378813.724 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-08-09T19:26:53.729Z,1565378813.729 [YawRateCalculator] Loaded
2019-08-09T19:26:53.730Z,1565378813.730 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-08-09T19:26:53.770Z,1565378813.770 [ElevatorOffsetCalculator] Loaded
2019-08-09T19:26:53.770Z,1565378813.770 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-08-09T19:26:53.771Z,1565378813.771 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-08-09T19:26:53.771Z,1565378813.771 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-08-09T19:26:53.912Z,1565378813.912 [SBIT](DEBUG): Construct Startup Built In Test.
2019-08-09T19:26:53.935Z,1565378813.935 [SBIT] Loaded
2019-08-09T19:26:53.935Z,1565378813.935 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-08-09T19:26:53.936Z,1565378813.936 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-08-09T19:26:53.947Z,1565378813.947 [IBIT] Loaded
2019-08-09T19:26:53.947Z,1565378813.947 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-08-09T19:26:53.950Z,1565378813.950 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-08-09T19:26:54.090Z,1565378814.090 [CBIT] Loaded
2019-08-09T19:26:54.090Z,1565378814.090 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-08-09T19:26:54.090Z,1565378814.090 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-08-09T19:26:54.091Z,1565378814.091 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-08-09T19:26:54.177Z,1565378814.177 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-08-09T19:26:54.177Z,1565378814.177 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-08-09T19:26:54.283Z,1565378814.283 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-08-09T19:26:54.284Z,1565378814.284 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-08-09T19:26:54.371Z,1565378814.371 [VerticalControl](DEBUG): Construct VerticalControl.
2019-08-09T19:26:54.455Z,1565378814.455 [VerticalControl] Loaded
2019-08-09T19:26:54.455Z,1565378814.455 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-08-09T19:26:54.456Z,1565378814.456 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-08-09T19:26:54.513Z,1565378814.513 [HorizontalControl] Loaded
2019-08-09T19:26:54.514Z,1565378814.514 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-08-09T19:26:54.514Z,1565378814.514 [SpeedControl](DEBUG): Construct SpeedControl.
2019-08-09T19:26:54.516Z,1565378814.516 [SpeedControl] Loaded
2019-08-09T19:26:54.516Z,1565378814.516 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-08-09T19:26:54.517Z,1565378814.517 [LoopControl](DEBUG): Construct LoopControl.
2019-08-09T19:26:54.518Z,1565378814.518 [LoopControl] Loaded
2019-08-09T19:26:54.518Z,1565378814.518 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-08-09T19:26:54.518Z,1565378814.518 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-08-09T19:26:54.519Z,1565378814.519 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-08-09T19:26:54.552Z,1565378814.552 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-08-09T19:26:54.556Z,1565378814.556 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-08-09T19:26:54.557Z,1565378814.557 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-08-09T19:26:54.564Z,1565378814.564 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-08-09T19:26:54.565Z,1565378814.565 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AED4E0
2019-08-09T19:26:54.566Z,1565378814.566 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4228
2019-08-09T19:26:54.570Z,1565378814.570 [Supervisor](INFO): Main Thread ID is 4140
2019-08-09T19:26:54.570Z,1565378814.570 [Supervisor](DEBUG): Running supervisor.
2019-08-09T19:26:54.571Z,1565378814.571 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4229
2019-08-09T19:26:54.573Z,1565378814.573 [controlThread ThreadHandler](INFO): Handler Thread ID is 4230
2019-08-09T19:26:54.574Z,1565378814.574 [controlThread](DEBUG): Initializing ControlThread
2019-08-09T19:26:54.580Z,1565378814.580 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-08-09T19:26:54.580Z,1565378814.580 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-08-09T19:26:54.581Z,1565378814.581 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-08-09T19:26:54.582Z,1565378814.582 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-08-09T19:26:54.582Z,1565378814.582 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-08-09T19:26:54.582Z,1565378814.582 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-08-09T19:26:54.583Z,1565378814.583 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-08-09T19:26:54.583Z,1565378814.583 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-08-09T19:26:54.584Z,1565378814.584 [SBIT](INFO): Initialize SBIT Component.
2019-08-09T19:26:54.584Z,1565378814.584 [SBIT](IMPORTANT): git: 2019-07-16A-4-gbc3a532
2019-08-09T19:26:54.585Z,1565378814.585 [SBIT](INFO): git hash: bc3a532330c34a530d393d20f0a16563b6db3de2
2019-08-09T19:26:54.585Z,1565378814.585 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-08-09T19:26:54.586Z,1565378814.586 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2019-08-09T19:26:54.587Z,1565378814.587 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-08-09T19:26:54.588Z,1565378814.588 [IBIT](INFO): Initialize IBIT Component.
2019-08-09T19:26:54.589Z,1565378814.589 [CBIT](DEBUG): Initialize CBIT Component.
2019-08-09T19:26:54.590Z,1565378814.590 [logger ThreadHandler](INFO): Handler Thread ID is 4231
2019-08-09T19:26:54.600Z,1565378814.600 [CBIT](DEBUG): Initialized mux pins.
2019-08-09T19:26:54.601Z,1565378814.601 [CBIT](DEBUG): Initializing the watchdog timer.
2019-08-09T19:26:54.609Z,1565378814.609 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4232
2019-08-09T19:26:54.610Z,1565378814.610 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-08-09T19:26:54.621Z,1565378814.621 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4233
2019-08-09T19:26:54.625Z,1565378814.625 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-08-09T19:26:54.625Z,1565378814.625 [CBIT](DEBUG): Initializing heartbeat.
2019-08-09T19:26:54.633Z,1565378814.633 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4234
2019-08-09T19:26:54.634Z,1565378814.634 [CTD_NeilBrown](INFO): Powering down
2019-08-09T19:26:54.663Z,1565378814.663 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 4235
2019-08-09T19:26:54.664Z,1565378814.664 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-08-09T19:26:54.691Z,1565378814.691 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4236
2019-08-09T19:26:54.694Z,1565378814.694 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-08-09T19:26:54.695Z,1565378814.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-08-09T19:26:54.695Z,1565378814.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-08-09T19:26:54.695Z,1565378814.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-08-09T19:26:54.695Z,1565378814.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-08-09T19:26:54.695Z,1565378814.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-08-09T19:26:54.695Z,1565378814.695 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-08-09T19:26:54.696Z,1565378814.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-08-09T19:26:54.696Z,1565378814.696 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-08-09T19:26:54.696Z,1565378814.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-08-09T19:26:54.696Z,1565378814.696 [CBIT](DEBUG): Deactivating GF circuits.
2019-08-09T19:26:54.697Z,1565378814.697 [CBIT](DEBUG): Deactivating emergency mode.
2019-08-09T19:26:54.697Z,1565378814.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-08-09T19:26:54.697Z,1565378814.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-08-09T19:26:54.697Z,1565378814.697 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-08-09T19:26:54.697Z,1565378814.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-08-09T19:26:54.698Z,1565378814.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-08-09T19:26:54.698Z,1565378814.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-08-09T19:26:54.732Z,1565378814.732 [CBIT](DEBUG): Backplane powered.
2019-08-09T19:26:54.733Z,1565378814.733 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-08-09T19:26:54.735Z,1565378814.735 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-08-09T19:26:54.735Z,1565378814.735 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-08-09T19:26:54.736Z,1565378814.736 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-08-09T19:26:54.737Z,1565378814.737 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-08-09T19:26:54.748Z,1565378814.748 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-09T19:26:54.767Z,1565378814.767 [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-08-09T19:26:54.768Z,1565378814.768 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-08-09T19:26:54.842Z,1565378814.842 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-08-09T19:26:54.843Z,1565378814.843 [Default:A.Wait](DEBUG): Construct Wait.
2019-08-09T19:26:54.861Z,1565378814.861 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-08-09T19:26:54.891Z,1565378814.891 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-08-09T19:26:54.894Z,1565378814.894 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-08-09T19:26:54.916Z,1565378814.916 [Default:E.Execute](DEBUG): Construct Execute.
2019-08-09T19:26:54.919Z,1565378814.919 [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-08-09T19:26:54.929Z,1565378814.929 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,ESPComponent,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-08-09T19:26:54.943Z,1565378814.943 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-08-09T19:26:55.025Z,1565378815.025 [Radio_Surface](INFO): Powering up
2019-08-09T19:26:55.071Z,1565378815.071 [DUSBL_Hydroid](INFO): Powering up
2019-08-09T19:26:55.071Z,1565378815.071 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-08-09T19:26:55.159Z,1565378815.159 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-08-09T19:26:55.169Z,1565378815.169 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-08-09T19:26:55.170Z,1565378815.170 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-08-09T19:26:55.177Z,1565378815.177 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-08-09T19:26:55.178Z,1565378815.178 [MassServo](DEBUG): Initializing EZServoServo.
2019-08-09T19:26:55.190Z,1565378815.190 [MassServo](DEBUG): Initializing MassServo.
2019-08-09T19:26:55.191Z,1565378815.191 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-09T19:26:55.199Z,1565378815.199 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-09T19:26:55.199Z,1565378815.199 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-08-09T19:26:55.211Z,1565378815.211 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-08-09T19:26:55.358Z,1565378815.358 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-08-09T19:26:55.358Z,1565378815.358 [DropWeight] Hardware Fault, FailCount= 1
2019-08-09T19:26:55.358Z,1565378815.358 [DropWeight](ERROR): Hardware Fault
2019-08-09T19:26:55.360Z,1565378815.360 [Micromodem](INFO): Powering up
2019-08-09T19:26:55.360Z,1565378815.360 [Micromodem](DEBUG): Initializing Micromodem.
2019-08-09T19:26:55.446Z,1565378815.446 [CommandLine](FAULT): Scheduling is paused
2019-08-09T19:26:55.446Z,1565378815.446 [CBIT](INFO): Critical error at 20190809T192655
2019-08-09T19:26:55.447Z,1565378815.447 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-08-09T19:26:55.465Z,1565378815.465 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-08-09T19:26:55.465Z,1565378815.465 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-08-09T19:26:56.125Z,1565378816.125 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-08-09T19:26:56.125Z,1565378816.125 [RudderServo](FAULT): Rudder failed to initialize
2019-08-09T19:26:56.125Z,1565378816.125 [RudderServo] Communications Fault, FailCount= 1
2019-08-09T19:26:56.125Z,1565378816.125 [RudderServo](ERROR): Communications Fault
2019-08-09T19:26:56.234Z,1565378816.234 [CBIT](INFO): Critical error at 20190809T192655
2019-08-09T19:26:56.237Z,1565378816.237 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-08-09T19:26:56.394Z,1565378816.394 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-09T19:26:56.394Z,1565378816.394 [RudderServo](INFO): Powering down
2019-08-09T19:26:57.085Z,1565378817.085 [RudderServo](DEBUG): Initializing EZServoServo.
2019-08-09T19:26:57.205Z,1565378817.205 [RudderServo](DEBUG): Initializing RudderServo.
2019-08-09T19:26:57.210Z,1565378817.210 [CBIT](INFO): Clearing failed state for component RudderServo
2019-08-09T19:26:57.210Z,1565378817.210 [RudderServo] No Fault, FailCount= 1
2019-08-09T19:27:00.323Z,1565378820.323 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-08-09T19:27:00.727Z,1565378820.727 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-08-09T19:27:00.727Z,1565378820.727 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-08-09T19:27:01.258Z,1565378821.258 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-08-09T19:27:01.259Z,1565378821.259 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-08-09T19:27:01.663Z,1565378821.663 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-08-09T19:27:01.663Z,1565378821.663 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-08-09T19:27:02.067Z,1565378822.067 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-08-09T19:27:02.067Z,1565378822.067 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-08-09T19:27:02.471Z,1565378822.471 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-08-09T19:27:02.471Z,1565378822.471 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2019-08-09T19:27:02.883Z,1565378822.883 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2019-08-09T19:27:02.883Z,1565378822.883 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2019-08-09T19:27:03.267Z,1565378823.267 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2019-08-09T19:27:03.267Z,1565378823.267 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2019-08-09T19:27:03.675Z,1565378823.675 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2019-08-09T19:27:03.675Z,1565378823.675 [Micromodem](INFO): Nmea out: $CCCLK,2019,08,09,19,27,04*46

2019-08-09T19:27:04.063Z,1565378824.063 [Micromodem](INFO): Nmea in: $CACLK,2019,8,9,19,27,4*74

2019-08-09T19:27:04.513Z,1565378824.513 [Micromodem](INFO): Nmea in: $CATMS,0,2019-08-09T19:27:05Z*75

2019-08-09T19:27:04.514Z,1565378824.514 [Micromodem](INFO): Nmea in: $CATMG,2019-08-09T19:27:05.027401Z,USER_CMD,RTC*12

2019-08-09T19:27:06.734Z,1565378826.734 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004526
2019-08-09T19:27:08.525Z,1565378828.525 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-08-09T19:27:12.942Z,1565378832.942 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-08-09T19:27:21.439Z,1565378841.439 [NAL9602](INFO): Powering up NAL9602
2019-08-09T19:27:23.067Z,1565378843.067 [SBIT](IMPORTANT): Beginning Startup BIT
2019-08-09T19:27:23.075Z,1565378843.075 [CBIT](IMPORTANT): Beginning ground fault scan
2019-08-09T19:27:24.373Z,1565378844.373 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2019-08-09T19:27:24.373Z,1565378844.373 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-08-09T19:27:24.373Z,1565378844.373 [RDI_Pathfinder](ERROR): Communications Fault
2019-08-09T19:27:24.373Z,1565378844.373 [RDI_Pathfinder](ERROR): Failed to parse:
2019-08-09T19:27:24.414Z,1565378844.414 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-08-09T19:27:24.753Z,1565378844.753 [RDI_Pathfinder](INFO): Powering down
2019-08-09T19:27:25.685Z,1565378845.685 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-08-09T19:27:25.686Z,1565378845.686 [RDI_Pathfinder] No Fault, FailCount= 1
2019-08-09T19:27:32.426Z,1565378852.426 [NAL9602](INFO): NAL9602 initialized
2019-08-09T19:27:33.245Z,1565378853.245 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:27:34.133Z,1565378854.133 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010681
CHAN A1 (24V): -0.028185
CHAN A2 (12V): -0.006697
CHAN A3 (5V): -0.002154
CHAN B0 (3.3V): 0.000269
CHAN B1 (3.15aV): 0.000096
CHAN B2 (3.15bV): 0.000204
CHAN B3 (GND): 0.001878
OPEN: 0.006978
Full Scale Calc: 4.765 mA, -1.589 mA
2019-08-09T19:27:38.998Z,1565378858.998 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-08-09T19:27:44.249Z,1565378864.249 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-08-09T19:27:44.249Z,1565378864.249 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19080912352438,35.0, -0.1,   .9,  0
2019-08-09T19:27:45.422Z,1565378865.422 [CommandLine](IMPORTANT): got command show variable range
2019-08-09T19:27:45.633Z,1565378865.633 [CommandLine](IMPORTANT): acoustic_contact_range (unknown)
2019-08-09T19:27:45.792Z,1565378865.792 [CommandLine](IMPORTANT): BR_Ping1D.minrange (meter)
2019-08-09T19:27:45.796Z,1565378865.796 [CommandLine](IMPORTANT): BR_Ping1D.maxrange (meter)
2019-08-09T19:27:45.924Z,1565378865.924 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_range (meter)
2019-08-09T19:27:45.929Z,1565378865.929 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2019-08-09T19:27:45.930Z,1565378865.930 [CommandLine](IMPORTANT): Micromodem.range (meter)
2019-08-09T19:27:45.941Z,1565378865.941 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam1Range (meter)
2019-08-09T19:27:45.942Z,1565378865.942 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam2Range (meter)
2019-08-09T19:27:45.942Z,1565378865.942 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam3Range (meter)
2019-08-09T19:27:45.942Z,1565378865.942 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam4Range (meter)
2019-08-09T19:27:55.527Z,1565378875.527 [CommandLine](IMPORTANT): got command report touch acoustic_contact_range
2019-08-09T19:28:01.611Z,1565378881.611 [CommandLine](IMPORTANT): got command report touch Micromodem.range
2019-08-09T19:28:05.344Z,1565378885.344 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-09T19:28:05.345Z,1565378885.345 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.92,  95.92,   0.00
2019-08-09T19:28:06.747Z,1565378886.747 [CommandLine](IMPORTANT): got command show variable xangle
2019-08-09T19:28:06.830Z,1565378886.830 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-08-09T19:28:08.250Z,1565378888.250 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-09T19:28:08.250Z,1565378888.250 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.92,  95.92,   0.00
2019-08-09T19:28:13.496Z,1565378893.496 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.xAngle
2019-08-09T19:28:15.929Z,1565378895.929 [CommandLine](IMPORTANT): got command show stack
2019-08-09T19:28:15.929Z,1565378895.929 [CommandLine](IMPORTANT): Behavior Stack:

2019-08-09T19:28:15.929Z,1565378895.929 [MissionManager](IMPORTANT): Mission loaded, but not running.
2019-08-09T19:28:16.767Z,1565378896.767 [SBIT](IMPORTANT): SBIT PASSED
2019-08-09T19:28:16.830Z,1565378896.830 [CommandLine](IMPORTANT): got command configSet list
2019-08-09T19:28:16.831Z,1565378896.831 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-08-09T19:28:16.832Z,1565378896.832 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=10 count;

2019-08-09T19:28:16.833Z,1565378896.833 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2019-08-09T19:28:16.833Z,1565378896.833 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=10 minute;

2019-08-09T19:28:16.834Z,1565378896.834 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2019-08-09T19:28:16.837Z,1565378896.837 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.acoustic_contact_range 2.000000 meter;

2019-08-09T19:28:16.837Z,1565378896.837 [CommandLine](IMPORTANT): HorizontalControl.kpHeading=0.8 none;

2019-08-09T19:28:16.837Z,1565378896.837 [CommandLine](IMPORTANT): HorizontalControl.rudDeadband=0.05 degree;

2019-08-09T19:28:16.837Z,1565378896.837 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold=2 meter;

2019-08-09T19:28:16.837Z,1565378896.837 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-08-09T19:28:17.214Z,1565378897.214 [MissionManager](IMPORTANT): Started mission Startup
2019-08-09T19:28:17.214Z,1565378897.214 [Startup] Running Loop=1
2019-08-09T19:28:17.214Z,1565378897.214 [Startup](DEBUG): Aggregate::initialize Startup
2019-08-09T19:28:17.214Z,1565378897.214 [Startup:A.GoToSurface] Running Loop=1
2019-08-09T19:28:17.214Z,1565378897.214 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-09T19:28:17.215Z,1565378897.215 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-09T19:28:17.215Z,1565378897.215 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-09T19:28:17.216Z,1565378897.216 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-09T19:28:17.216Z,1565378897.216 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-09T19:28:17.217Z,1565378897.217 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-09T19:28:17.218Z,1565378897.218 [Startup:StartupSatComms] Running Loop=1
2019-08-09T19:28:17.218Z,1565378897.218 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-08-09T19:28:17.218Z,1565378897.218 [Startup:StartupSatComms:A] Running Loop=1
2019-08-09T19:28:17.582Z,1565378897.582 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-08-09T19:29:17.370Z,1565378957.370 [Startup:StartupSatComms:A](INFO): Timed out from 2019-08-09T19:28:17.2Z
2019-08-09T19:29:17.370Z,1565378957.370 [Startup:StartupSatComms:A] Stopped
2019-08-09T19:29:17.370Z,1565378957.370 [Startup:StartupSatComms:B] Running Loop=1
2019-08-09T19:29:17.750Z,1565378957.750 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-09T19:29:19.776Z,1565378959.776 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20190809T192644/Courier0000.lzma
2019-08-09T19:29:20.522Z,1565378960.522 [DataOverHttps](INFO): Moved sent file to Logs/20190809T192644/Courier0000.lzma.bak
2019-08-09T19:29:20.522Z,1565378960.522 [DataOverHttps](INFO): SBD MOMSN=11547758
2019-08-09T19:29:31.705Z,1565378971.705 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190807T205425/Express0020.lzma
2019-08-09T19:29:32.510Z,1565378972.510 [DataOverHttps](INFO): Moved sent file to Logs/20190807T205425/Express0020.lzma.bak
2019-08-09T19:29:32.510Z,1565378972.510 [DataOverHttps](INFO): SBD MOMSN=11547763
2019-08-09T19:29:43.662Z,1565378983.662 [DataOverHttps](INFO): Sending 1128 bytes from file Logs/20190809T192644/Express0001.lzma
2019-08-09T19:29:44.466Z,1565378984.466 [DataOverHttps](INFO): Moved sent file to Logs/20190809T192644/Express0001.lzma.bak
2019-08-09T19:29:44.466Z,1565378984.466 [DataOverHttps](INFO): SBD MOMSN=11547766
2019-08-09T19:29:45.333Z,1565378985.333 [Startup:StartupSatComms:B] Stopped
2019-08-09T19:29:45.333Z,1565378985.333 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-08-09T19:29:45.333Z,1565378985.333 [Startup:StartupSatComms] Stopped
2019-08-09T19:29:45.334Z,1565378985.334 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-08-09T19:29:45.334Z,1565378985.334 [Startup](INFO): Completed Startup
2019-08-09T19:29:45.334Z,1565378985.334 [MissionManager](INFO): Startup is completed.
2019-08-09T19:29:45.335Z,1565378985.335 [MissionManager](INFO): Uninitializing Mission Startup
2019-08-09T19:29:45.335Z,1565378985.335 [Startup] Stopped
2019-08-09T19:29:45.335Z,1565378985.335 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-08-09T19:29:45.335Z,1565378985.335 [Startup:A.GoToSurface] Stopped
2019-08-09T19:29:45.335Z,1565378985.335 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-09T19:29:45.750Z,1565378985.750 [MissionManager](IMPORTANT): Started mission Default
2019-08-09T19:29:45.750Z,1565378985.750 [Default] Running Loop=1
2019-08-09T19:29:45.750Z,1565378985.750 [Default](DEBUG): Aggregate::initialize Default
2019-08-09T19:29:45.750Z,1565378985.750 [Default:B.GoToSurface] Running Loop=1
2019-08-09T19:29:45.750Z,1565378985.750 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-09T19:29:45.750Z,1565378985.750 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-09T19:29:45.751Z,1565378985.751 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-09T19:29:45.751Z,1565378985.751 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-09T19:29:45.751Z,1565378985.751 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-09T19:29:45.752Z,1565378985.752 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-09T19:29:45.752Z,1565378985.752 [Default:A.Wait] Running Loop=1
2019-08-09T19:29:45.752Z,1565378985.752 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-09T19:29:54.637Z,1565378994.637 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-08-09T19:29:54.637Z,1565378994.637 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-09T19:29:54.651Z,1565378994.651 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-09T19:29:55.071Z,1565378995.071 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-09T19:29:55.071Z,1565378995.071 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-08-09T19:29:59.084Z,1565378999.084 [Default:A.Wait](INFO): Done Waiting.
2019-08-09T19:29:59.084Z,1565378999.084 [Default:A.Wait] Stopped
2019-08-09T19:29:59.085Z,1565378999.085 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-09T19:29:59.494Z,1565378999.494 [Default:CheckIn] Running Loop=1
2019-08-09T19:29:59.494Z,1565378999.494 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-09T19:29:59.494Z,1565378999.494 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-09T19:29:59.904Z,1565378999.904 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-08-09T19:30:54.788Z,1565379054.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=8306, MT Status=2, MTMSN=0
2019-08-09T19:30:54.788Z,1565379054.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-08-09T19:31:49.698Z,1565379109.698 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-08-09T19:31:49.699Z,1565379109.699 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-08-09T19:31:49.740Z,1565379109.740 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-08-09T19:31:49.756Z,1565379109.756 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-08-09T19:31:49.759Z,1565379109.759 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-08-09T19:31:49.763Z,1565379109.763 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-08-09T19:31:49.775Z,1565379109.775 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-08-09T19:31:49.776Z,1565379109.776 [DUSBL:A.Pitch](DEBUG): Construct.
2019-08-09T19:31:49.831Z,1565379109.831 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-08-09T19:31:49.893Z,1565379109.893 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-08-09T19:31:49.906Z,1565379109.906 [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><Sensor: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>
        <When>
            <Arg Name="EnabledDUSBL"/>
        </When>
            <Universal:acoustic_contact_range/>
            <Universal:acoustic_contact_direction_vehicle_frame/>
    </ReadData>
    
      <Aggregate Id="RequestRepeater">

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


2019-08-09T19:31:49.908Z,1565379109.908 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-08-09T19:31:50.162Z,1565379110.162 [Default] Stopped
2019-08-09T19:31:50.162Z,1565379110.162 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-09T19:31:50.162Z,1565379110.162 [Default:B.GoToSurface] Stopped
2019-08-09T19:31:50.162Z,1565379110.162 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-09T19:31:50.162Z,1565379110.162 [Default:CheckIn] Stopped
2019-08-09T19:31:50.162Z,1565379110.162 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-09T19:31:50.163Z,1565379110.163 [Default:CheckIn:Read_GPS] Stopped
2019-08-09T19:31:50.163Z,1565379110.163 [MissionManager](IMPORTANT): Started mission DUSBL
2019-08-09T19:31:50.163Z,1565379110.163 [DUSBL] Running Loop=1
2019-08-09T19:31:50.163Z,1565379110.163 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-08-09T19:31:50.163Z,1565379110.163 [DUSBL:A.Pitch] Running Loop=1
2019-08-09T19:31:50.163Z,1565379110.163 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-08-09T19:31:50.163Z,1565379110.163 [DUSBL:B.SetSpeed] Running Loop=1
2019-08-09T19:31:50.164Z,1565379110.164 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-08-09T19:31:50.164Z,1565379110.164 [DUSBL:C] Running Loop=1
2019-08-09T19:31:50.164Z,1565379110.164 [DUSBL:RequestRepeater] Running Loop=1
2019-08-09T19:31:50.164Z,1565379110.164 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-08-09T19:31:50.164Z,1565379110.164 [DUSBL:RequestRepeater:A] Running Loop=1
2019-08-09T19:31:50.164Z,1565379110.164 [DUSBL:RequestRepeater:B] Running Loop=1
2019-08-09T19:31:50.164Z,1565379110.164 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-08-09T19:31:50.165Z,1565379110.165 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-08-09T19:31:50.165Z,1565379110.165 [DUSBL:RequestRepeater:B] Running Loop=1
2019-08-09T19:31:50.165Z,1565379110.165 [DUSBL:RequestRepeater:A] Running Loop=1
2019-08-09T19:31:50.166Z,1565379110.166 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_range
2019-08-09T19:31:50.167Z,1565379110.167 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense acoustic_contact_direction_vehicle_frame
2019-08-09T19:31:50.167Z,1565379110.167 [DUSBL:B.SetSpeed] Running Loop=1
2019-08-09T19:31:50.167Z,1565379110.167 [DUSBL:A.Pitch] Running Loop=1
2019-08-09T19:31:51.746Z,1565379111.746 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-09T19:31:51.746Z,1565379111.746 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-09T19:31:51.746Z,1565379111.746 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 10
2019-08-09T19:31:51.747Z,1565379111.747 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-09T19:31:52.158Z,1565379112.158 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-08-09T19:31:52.158Z,1565379112.158 [DUSBL_Hydroid](INFO): Command Ack
2019-08-09T19:31:52.158Z,1565379112.158 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-09T19:31:52.160Z,1565379112.160 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:31:57.817Z,1565379117.817 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.069506
2019-08-09T19:31:57.817Z,1565379117.817 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-09T19:31:57.817Z,1565379117.817 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-09T19:31:57.817Z,1565379117.817 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 10
2019-08-09T19:31:57.817Z,1565379117.817 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-09T19:31:58.214Z,1565379118.214 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-08-09T19:31:58.214Z,1565379118.214 [DUSBL_Hydroid](INFO): Command Ack
2019-08-09T19:31:58.214Z,1565379118.214 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-09T19:31:58.250Z,1565379118.250 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.92,  95.92,  95.92,   0.00
2019-08-09T19:32:03.466Z,1565379123.466 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:03.892Z,1565379123.892 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.074920
2019-08-09T19:32:03.893Z,1565379123.893 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-09T19:32:03.893Z,1565379123.893 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-09T19:32:03.893Z,1565379123.893 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 10
2019-08-09T19:32:03.894Z,1565379123.894 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-09T19:32:03.899Z,1565379123.899 [Micromodem](INFO): Nmea in: $AERR1920,NI  ,17,Argumet value ut of range*0B

2019-08-09T19:32:03.899Z,1565379123.899 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AERR1920,NI  ,17,Argumet value ut of range*0B
 expected 62
2019-08-09T19:32:03.900Z,1565379123.900 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:04.274Z,1565379124.274 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-08-09T19:32:04.274Z,1565379124.274 [DUSBL_Hydroid](INFO): Command Ack
2019-08-09T19:32:04.274Z,1565379124.274 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-09T19:32:09.936Z,1565379129.936 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.042785
2019-08-09T19:32:09.937Z,1565379129.937 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-09T19:32:09.937Z,1565379129.937 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-09T19:32:09.937Z,1565379129.937 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 10
2019-08-09T19:32:09.938Z,1565379129.938 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-09T19:32:10.334Z,1565379130.334 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-08-09T19:32:10.334Z,1565379130.334 [DUSBL_Hydroid](INFO): Command Ack
2019-08-09T19:32:10.334Z,1565379130.334 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-09T19:32:15.195Z,1565379135.195 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:15.595Z,1565379135.595 [Micromodem](INFO): Nmea in: $AE,19321,N  ,17,Argument value utof range0

2019-08-09T19:32:15.596Z,1565379135.596 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AE,19321,N  ,17,Argument value utof range0
 expected 4C
2019-08-09T19:32:15.596Z,1565379135.596 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:16.005Z,1565379136.005 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.066870
2019-08-09T19:32:16.005Z,1565379136.005 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-09T19:32:16.005Z,1565379136.005 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-09T19:32:16.005Z,1565379136.005 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 10
2019-08-09T19:32:16.005Z,1565379136.005 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-09T19:32:16.406Z,1565379136.406 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-08-09T19:32:16.406Z,1565379136.406 [DUSBL_Hydroid](INFO): Command Ack
2019-08-09T19:32:16.406Z,1565379136.406 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-09T19:32:22.056Z,1565379142.056 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.050895
2019-08-09T19:32:22.057Z,1565379142.057 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-09T19:32:22.057Z,1565379142.057 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-09T19:32:22.057Z,1565379142.057 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 10
2019-08-09T19:32:22.058Z,1565379142.058 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-09T19:32:22.458Z,1565379142.458 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-08-09T19:32:22.458Z,1565379142.458 [DUSBL_Hydroid](INFO): Command Ack
2019-08-09T19:32:22.458Z,1565379142.458 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-09T19:32:26.894Z,1565379146.894 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:27.307Z,1565379147.307 [Micromodem](INFO): Nmea in: $AE,19327N  17Arumnt valueout of rne0A

2019-08-09T19:32:27.307Z,1565379147.307 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AE,19327N  17Arumnt valueout of rne0A
 expected 4C
2019-08-09T19:32:27.307Z,1565379147.307 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:28.120Z,1565379148.120 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.062556
2019-08-09T19:32:28.121Z,1565379148.121 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-08-09T19:32:28.121Z,1565379148.121 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-08-09T19:32:28.121Z,1565379148.121 [DUSBL_Hydroid](INFO): Arming DUSBL. detectThresh: 10
2019-08-09T19:32:28.122Z,1565379148.122 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-08-09T19:32:28.518Z,1565379148.518 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-08-09T19:32:28.518Z,1565379148.518 [DUSBL_Hydroid](INFO): Command Ack
2019-08-09T19:32:28.518Z,1565379148.518 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-08-09T19:32:33.226Z,1565379153.226 [CommandLine](IMPORTANT): got command stop
2019-08-09T19:32:33.226Z,1565379153.226 [CommandLine](IMPORTANT): Scheduling is paused
2019-08-09T19:32:33.226Z,1565379153.226 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-08-09T19:32:33.402Z,1565379153.402 [MissionManager](INFO): MissionManager is completed.
2019-08-09T19:32:33.402Z,1565379153.402 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-08-09T19:32:33.402Z,1565379153.402 [DUSBL] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:A.Pitch] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:B.SetSpeed] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:C] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:RequestRepeater] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:RequestRepeater:A] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:RequestRepeater:B] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-08-09T19:32:33.403Z,1565379153.403 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-09T19:32:33.789Z,1565379153.789 [MissionManager](IMPORTANT): Started mission Default
2019-08-09T19:32:33.789Z,1565379153.789 [Default] Running Loop=1
2019-08-09T19:32:33.790Z,1565379153.790 [Default](DEBUG): Aggregate::initialize Default
2019-08-09T19:32:33.790Z,1565379153.790 [Default:B.GoToSurface] Running Loop=1
2019-08-09T19:32:33.790Z,1565379153.790 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-08-09T19:32:33.790Z,1565379153.790 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-08-09T19:32:33.790Z,1565379153.790 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-08-09T19:32:33.791Z,1565379153.791 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-08-09T19:32:33.791Z,1565379153.791 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-08-09T19:32:33.791Z,1565379153.791 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-08-09T19:32:33.792Z,1565379153.792 [Default:A.Wait] Running Loop=1
2019-08-09T19:32:33.792Z,1565379153.792 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-08-09T19:32:36.191Z,1565379156.191 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-09T19:32:37.016Z,1565379157.016 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:32:38.614Z,1565379158.614 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:44.684Z,1565379164.684 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:32:45.076Z,1565379165.076 [Micromodem](INFO): Nmea in: $CAERR,19323NI  ,1,Argment value out of range*05
$CAREV,193245,AUV,2.0.32731*13

2019-08-09T19:32:45.077Z,1565379165.077 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,19323NI  ,1,Argment value out of range*05
$CAREV,193245,AUV,2.0.32731*13
 expected 52
2019-08-09T19:32:45.077Z,1565379165.077 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:47.126Z,1565379167.126 [Default:A.Wait](INFO): Done Waiting.
2019-08-09T19:32:47.126Z,1565379167.126 [Default:A.Wait] Stopped
2019-08-09T19:32:47.126Z,1565379167.126 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-08-09T19:32:47.516Z,1565379167.516 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:32:47.542Z,1565379167.542 [Default:CheckIn] Running Loop=1
2019-08-09T19:32:47.542Z,1565379167.542 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-09T19:32:47.543Z,1565379167.543 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-09T19:32:50.745Z,1565379170.745 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:32:53.573Z,1565379173.573 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:32:55.670Z,1565379175.670 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-08-09T19:32:55.670Z,1565379175.670 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-09T19:32:55.709Z,1565379175.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-09T19:32:56.049Z,1565379176.049 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-09T19:32:56.049Z,1565379176.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-08-09T19:32:56.390Z,1565379176.390 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:32:56.804Z,1565379176.804 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:32:59.632Z,1565379179.632 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:02.468Z,1565379182.468 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:04.885Z,1565379184.885 [Micromodem](INFO): Nmea in: $CAERR,193257,NI  ,17,Argumntvalue ou of rane*0
$CAREV,193305,AUV,2.0.32731*16

2019-08-09T19:33:04.885Z,1565379184.885 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193257,NI  ,17,Argumntvalue ou of rane*0
$CAREV,193305,AUV,2.0.32731*16
 expected 5B
2019-08-09T19:33:04.885Z,1565379184.885 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:05.696Z,1565379185.696 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:08.524Z,1565379188.524 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:11.760Z,1565379191.760 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:14.588Z,1565379194.588 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:16.182Z,1565379196.182 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:17.816Z,1565379197.816 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:20.648Z,1565379200.648 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:23.469Z,1565379203.469 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:25.076Z,1565379205.076 [Micromodem](INFO): Nmea in: $CAER,193317,NI  ,17,Argument value ut of range*0
$CAREV,193325,AUV,2.0.32731*14

2019-08-09T19:33:25.076Z,1565379205.076 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,193317,NI  ,17,Argument value ut of range*0
$CAREV,193325,AUV,2.0.32731*14
 expected 35
2019-08-09T19:33:25.076Z,1565379205.076 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:26.704Z,1565379206.704 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:29.549Z,1565379209.549 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:32.768Z,1565379212.768 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:35.588Z,1565379215.588 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:36.390Z,1565379216.390 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:36.787Z,1565379216.787 [Micromodem](INFO): Nmea in: $AERR,193337,NI  ,17,Argument vale out of ane0A

2019-08-09T19:33:36.788Z,1565379216.788 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AERR,193337,NI  ,17,Argument vale out of ane0A
 expected 5F
2019-08-09T19:33:36.788Z,1565379216.788 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:38.829Z,1565379218.829 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:41.652Z,1565379221.652 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:44.476Z,1565379224.476 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:47.708Z,1565379227.708 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:48.106Z,1565379228.106 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:48.511Z,1565379228.511 [Micromodem](INFO): Nmea in: $CAERR,193349,NI  ,17,Argument value ot f ange*03

2019-08-09T19:33:48.511Z,1565379228.511 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193349,NI  ,17,Argument value ot f ange*03
 expected 6B
2019-08-09T19:33:48.512Z,1565379228.512 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:50.540Z,1565379230.540 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:53.772Z,1565379233.772 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:56.596Z,1565379236.596 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:33:59.815Z,1565379239.815 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:33:59.829Z,1565379239.829 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:00.223Z,1565379240.223 [Micromodem](INFO): Nmea in: AERR,193400I  ,17,Aruent value out of range*09

2019-08-09T19:34:00.223Z,1565379240.223 [Micromodem](ERROR): Response from modem failed NMEA checksum: AERR,193400I  ,17,Aruent value out of range*09
 expected 0
2019-08-09T19:34:00.223Z,1565379240.223 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:02.663Z,1565379242.663 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:05.504Z,1565379245.504 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:08.716Z,1565379248.716 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:11.534Z,1565379251.534 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:11.549Z,1565379251.549 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:11.947Z,1565379251.947 [Micromodem](INFO): Nmea in: $CAERR,193412,NI  ,17,Arguent value out ofrane*0A

2019-08-09T19:34:11.948Z,1565379251.948 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193412,NI  ,17,Arguent value out ofrane*0A
 expected 20
2019-08-09T19:34:11.948Z,1565379251.948 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:14.776Z,1565379254.776 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:17.608Z,1565379257.608 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:20.845Z,1565379260.845 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:22.469Z,1565379262.469 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-08-09T19:34:22.472Z,1565379262.472 [BPC1](INFO): Received data from all battery sticks.
2019-08-09T19:34:23.246Z,1565379263.246 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:23.659Z,1565379263.659 [Micromodem](INFO): Nmea in: $CAERR,93424,NI ,7,Argument value ou o rng*0F

2019-08-09T19:34:23.659Z,1565379263.659 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,93424,NI ,7,Argument value ou o rng*0F
 expected 39
2019-08-09T19:34:23.659Z,1565379263.659 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:23.677Z,1565379263.677 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:26.495Z,1565379266.495 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:29.725Z,1565379269.725 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:32.552Z,1565379272.552 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:34.967Z,1565379274.967 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:35.796Z,1565379275.796 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:38.612Z,1565379278.612 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:41.861Z,1565379281.861 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:44.672Z,1565379284.672 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:45.064Z,1565379285.064 [Micromodem](INFO): Nmea in: $CAERR,193435,NI  ,17,Argument value out of range*0F
$CAREV,193445,AUV,2.0.32731*15

2019-08-09T19:34:45.065Z,1565379285.065 [Micromodem](ERROR): Got error from modem: $CAERR,193435,NI  ,17,Argument value out of range*0F
$CAREV,193445,AUV,2.0.32731*15

2019-08-09T19:34:46.283Z,1565379286.283 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:47.501Z,1565379287.501 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:50.736Z,1565379290.736 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:53.560Z,1565379293.560 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:56.796Z,1565379296.796 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:34:57.594Z,1565379297.594 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:57.995Z,1565379297.995 [Micromodem](INFO): Nmea in: $CAERR,193458,NI  ,17,Argument valu out of rane*4

2019-08-09T19:34:57.995Z,1565379297.995 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193458,NI  ,17,Argument valu out of rane*4
 expected 6
2019-08-09T19:34:57.996Z,1565379297.996 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:34:59.624Z,1565379299.624 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:02.873Z,1565379302.873 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:05.680Z,1565379305.680 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:08.521Z,1565379308.521 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:09.306Z,1565379309.306 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:11.744Z,1565379311.744 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:14.576Z,1565379314.576 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:14.985Z,1565379314.985 [Micromodem](INFO): Nmea in: $CAERR,193510,NI  ,17Argument value out ofrane*9
$CAREV,193515,AUV,2.0.32731*11

2019-08-09T19:35:14.985Z,1565379314.985 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193510,NI  ,17Argument value out ofrane*9
$CAREV,193515,AUV,2.0.32731*11
 expected 62
2019-08-09T19:35:14.986Z,1565379314.986 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:17.804Z,1565379317.804 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:20.632Z,1565379320.632 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:23.881Z,1565379323.881 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:26.272Z,1565379326.272 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:26.693Z,1565379326.693 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:29.516Z,1565379329.516 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:32.748Z,1565379332.748 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:35.168Z,1565379335.168 [Micromodem](INFO): Nmea in: $CAERR,193527NI 1,Agmenvue u o range*0D
$CAREV,193535,AUV,2.0.32731*13

2019-08-09T19:35:35.168Z,1565379335.168 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193527NI 1,Agmenvue u o range*0D
$CAREV,193535,AUV,2.0.32731*13
 expected 39
2019-08-09T19:35:35.168Z,1565379335.168 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:35.576Z,1565379335.576 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:38.816Z,1565379338.816 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:41.640Z,1565379341.640 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:44.468Z,1565379344.468 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:46.470Z,1565379346.470 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:46.891Z,1565379346.891 [Micromodem](INFO): Nmea in: $CAERR,193547,NI ,1,Agment vle utof ange*B

2019-08-09T19:35:46.891Z,1565379346.891 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193547,NI ,1,Agment vle utof ange*B
 expected 32
2019-08-09T19:35:46.892Z,1565379346.892 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:47.696Z,1565379347.696 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:50.528Z,1565379350.528 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:53.760Z,1565379353.760 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:56.584Z,1565379356.584 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:35:56.609Z,1565379356.609 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-08-09T19:35:56.609Z,1565379356.609 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-08-09T19:35:56.619Z,1565379356.619 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-08-09T19:35:57.031Z,1565379357.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-08-09T19:35:57.031Z,1565379357.031 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-08-09T19:35:58.186Z,1565379358.186 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:58.591Z,1565379358.591 [Micromodem](INFO): Nmea in: $CAER,13559,NI ,1Agument vlue outof range*04

2019-08-09T19:35:58.591Z,1565379358.591 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,13559,NI ,1Agument vlue outof range*04
 expected 67
2019-08-09T19:35:58.592Z,1565379358.592 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:35:59.816Z,1565379359.816 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:36:00.209Z,1565379360.209 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193306.00,A,3648.16859,N,12147.27944,W,0.194,347.18,090819,,,A*79
2019-08-09T19:36:00.242Z,1565379360.242 [NAL9602](INFO): GPS fix at 20190809T193306: (36.802810, -121.787991)
2019-08-09T19:36:00.276Z,1565379360.276 [Default:CheckIn:Read_GPS] Stopped
2019-08-09T19:36:00.276Z,1565379360.276 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-09T19:36:00.663Z,1565379360.663 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-08-09T19:36:08.788Z,1565379368.788 [DataOverHttps](INFO): Sending 303 bytes from file Logs/20190809T192644/Courier0004.lzma
2019-08-09T19:36:09.594Z,1565379369.594 [DataOverHttps](INFO): Moved sent file to Logs/20190809T192644/Courier0004.lzma.bak
2019-08-09T19:36:09.594Z,1565379369.594 [DataOverHttps](INFO): SBD MOMSN=11547817
2019-08-09T19:36:09.910Z,1565379369.910 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:36:10.315Z,1565379370.315 [Micromodem](INFO): Nmea in: $CAERR19360,I  ,17,Agument ve u f ange*0A

2019-08-09T19:36:10.315Z,1565379370.315 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR19360,I  ,17,Agument ve u f ange*0A
 expected 55
2019-08-09T19:36:10.315Z,1565379370.315 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:36:21.626Z,1565379381.626 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:36:22.031Z,1565379382.031 [Micromodem](INFO): Nmea in: $CAERR,193622NI  ,7,rgument vlue out of ange*0B

2019-08-09T19:36:22.031Z,1565379382.031 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193622NI  ,7,rgument vlue out of ange*0B
 expected 44
2019-08-09T19:36:22.031Z,1565379382.031 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:36:29.169Z,1565379389.169 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20190809T192644/Express0005.lzma
2019-08-09T19:36:29.974Z,1565379389.974 [DataOverHttps](INFO): Moved sent file to Logs/20190809T192644/Express0005.lzma.bak
2019-08-09T19:36:29.974Z,1565379389.974 [DataOverHttps](INFO): SBD MOMSN=11547852
2019-08-09T19:36:30.983Z,1565379390.983 [Default:CheckIn:Read_Iridium] Stopped
2019-08-09T19:36:30.983Z,1565379390.983 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-09T19:36:30.983Z,1565379390.983 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-09T19:36:32.534Z,1565379392.534 [NAL9602](INFO): Not Powering down - fast GPS
2019-08-09T19:36:33.350Z,1565379393.350 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:36:33.747Z,1565379393.747 [Micromodem](INFO): Nmea in: $CAERR,193634,NI  ,17Argumentvalue out of rage0C

2019-08-09T19:36:33.747Z,1565379393.747 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193634,NI  ,17Argumentvalue out of rage0C
 expected 1A
2019-08-09T19:36:33.747Z,1565379393.747 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:36:40.256Z,1565379400.256 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-08-09T19:36:40.256Z,1565379400.256 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.92,  95.92,  95.92,   I,    +1,    +4,    +0,-32768,A
2019-08-09T19:36:45.078Z,1565379405.078 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:36:55.176Z,1565379415.176 [Micromodem](INFO): Nmea in: $CAERR,13646,N  ,7,rgument ale out of range09
$CAREV,193655,AUV,2.0.32731*16

2019-08-09T19:36:55.176Z,1565379415.176 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,13646,N  ,7,rgument ale out of range09
$CAREV,193655,AUV,2.0.32731*16
 expected 3B
2019-08-09T19:36:55.176Z,1565379415.176 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:06.483Z,1565379426.483 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:14.971Z,1565379434.971 [Micromodem](INFO): Nmea in: $CAER,930,NI  ,17,rgmet alouto rage*0D
$CAREV,193715,AUV,2.0.32731*13

2019-08-09T19:37:14.972Z,1565379434.972 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,930,NI  ,17,rgmet alouto rage*0D
$CAREV,193715,AUV,2.0.32731*13
 expected 5A
2019-08-09T19:37:14.972Z,1565379434.972 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:26.286Z,1565379446.286 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:35.179Z,1565379455.179 [Micromodem](INFO): Nmea in: $CAER,19327NI ,17,Arumntvalue ou ofrng*0F
$CAREV,193735,AUV,2.0.32731*11

2019-08-09T19:37:35.180Z,1565379455.180 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,19327NI ,17,Arumntvalue ou ofrng*0F
$CAREV,193735,AUV,2.0.32731*11
 expected 14
2019-08-09T19:37:35.180Z,1565379455.180 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:46.479Z,1565379466.479 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:46.890Z,1565379466.890 [Micromodem](INFO): Nmea in: CER,19374,NI  ,17,rguet vlu ut of rne*09

2019-08-09T19:37:46.890Z,1565379466.890 [Micromodem](ERROR): Response from modem failed NMEA checksum: CER,19374,NI  ,17,rguet vlu ut of rne*09
 expected 0
2019-08-09T19:37:46.890Z,1565379466.890 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:58.206Z,1565379478.206 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:37:58.600Z,1565379478.600 [Micromodem](INFO): Nmea in: $CAERR,1379,  ,17,Aguet valueou of range*06

2019-08-09T19:37:58.601Z,1565379478.601 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,1379,  ,17,Aguet valueou of range*06
 expected 28
2019-08-09T19:37:58.601Z,1565379478.601 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:09.918Z,1565379489.918 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:10.335Z,1565379490.335 [Micromodem](INFO): Nmea in: $CERR,310N  ,17,Agumn vlu out of ange*04

2019-08-09T19:38:10.335Z,1565379490.335 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CERR,310N  ,17,Agumn vlu out of ange*04
 expected 5
2019-08-09T19:38:10.335Z,1565379490.335 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:21.634Z,1565379501.634 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:22.035Z,1565379502.035 [Micromodem](INFO): Nmea in: $AER,1322,I  17,rgum vlue ou ofrage*05

2019-08-09T19:38:22.035Z,1565379502.035 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AER,1322,I  17,rgum vlue ou ofrage*05
 expected 12
2019-08-09T19:38:22.035Z,1565379502.035 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:22.894Z,1565379502.894 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-08-09T19:38:22.894Z,1565379502.894 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +0,    -1,  :BE,    +0,    -1,    +0,A
2019-08-09T19:38:33.344Z,1565379513.344 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:34.964Z,1565379514.964 [Micromodem](INFO): Nmea in: CER1934,NI ,7,rumntvalue out oag*2
$CAREV,193835,AUV,2.0.32731*1E

2019-08-09T19:38:34.964Z,1565379514.964 [Micromodem](ERROR): Response from modem failed NMEA checksum: CER1934,NI ,7,rumntvalue out oag*2
$CAREV,193835,AUV,2.0.32731*1E
 expected 0
2019-08-09T19:38:34.965Z,1565379514.965 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:46.278Z,1565379526.278 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:46.697Z,1565379526.697 [Micromodem](INFO): Nmea in: $CAER,19387,I  ,7,Agmet vaue o ornge*06

2019-08-09T19:38:46.698Z,1565379526.698 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,19387,I  ,7,Agmet vaue o ornge*06
 expected 3C
2019-08-09T19:38:46.698Z,1565379526.698 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:57.994Z,1565379537.994 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:38:58.411Z,1565379538.411 [Micromodem](INFO): Nmea in: $CER,13858,N  1,Arumnt value ot of range*08

2019-08-09T19:38:58.411Z,1565379538.411 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CER,13858,N  1,Arumnt value ot of range*08
 expected 7
2019-08-09T19:38:58.411Z,1565379538.411 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:09.706Z,1565379549.706 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:10.111Z,1565379550.111 [Micromodem](INFO): Nmea in: $CAERR,193910,NI  ,17,Argument value out of range*05

2019-08-09T19:39:10.111Z,1565379550.111 [Micromodem](ERROR): Got error from modem: $CAERR,193910,NI  ,17,Argument value out of range*05

2019-08-09T19:39:21.022Z,1565379561.022 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:32.334Z,1565379572.334 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:32.738Z,1565379572.738 [Micromodem](INFO): Nmea in: $AE193933N ,1,Aguen ale ut of range*04

2019-08-09T19:39:32.739Z,1565379572.739 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AE193933N ,1,Aguen ale ut of range*04
 expected 1E
2019-08-09T19:39:32.739Z,1565379572.739 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:44.056Z,1565379584.056 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:44.455Z,1565379584.455 [Micromodem](INFO): Nmea in: $AER,93945,NI ,,rumntvalue out of range*05

2019-08-09T19:39:44.455Z,1565379584.455 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AER,93945,NI ,,rumntvalue out of range*05
 expected 60
2019-08-09T19:39:44.455Z,1565379584.455 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:55.762Z,1565379595.762 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:39:56.167Z,1565379596.167 [Micromodem](INFO): Nmea in: $CAERR,193956,NI  ,7Argmen vle out of range*07

2019-08-09T19:39:56.167Z,1565379596.167 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,193956,NI  ,7Argmen vle out of range*07
 expected F
2019-08-09T19:39:56.167Z,1565379596.167 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:07.482Z,1565379607.482 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:07.888Z,1565379607.888 [Micromodem](INFO): Nmea in: $CAERR,19408,I ,1,Aument value otof rage*2

2019-08-09T19:40:07.888Z,1565379607.888 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,19408,I ,1,Aument value otof rage*2
 expected 45
2019-08-09T19:40:07.888Z,1565379607.888 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:19.194Z,1565379619.194 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:19.603Z,1565379619.603 [Micromodem](INFO): Nmea in: $CAERR,194020,NI  ,1,Aguentvalue out of rnge*08

2019-08-09T19:40:19.603Z,1565379619.603 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,194020,NI  ,1,Aguentvalue out of rnge*08
 expected 61
2019-08-09T19:40:19.603Z,1565379619.603 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:30.908Z,1565379630.908 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:31.313Z,1565379631.313 [Micromodem](INFO): Nmea in: $CAERR,19031,NI  ,17,Argment vlu utof ange*08

2019-08-09T19:40:31.313Z,1565379631.313 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,19031,NI  ,17,Argment vlu utof ange*08
 expected 70
2019-08-09T19:40:31.313Z,1565379631.313 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:42.625Z,1565379642.625 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:43.035Z,1565379643.035 [Micromodem](INFO): Nmea in: CERR,19404,I ,17,Argument vlu ot of range*0D

2019-08-09T19:40:43.035Z,1565379643.035 [Micromodem](ERROR): Response from modem failed NMEA checksum: CERR,19404,I ,17,Argument vlu ot of range*0D
 expected 0
2019-08-09T19:40:43.035Z,1565379643.035 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:54.339Z,1565379654.339 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:40:55.155Z,1565379655.155 [Micromodem](INFO): Nmea in: $CAERR,1405,NI  ,1,Agumntvlue out f ag*0A
$CAREV,194055,AUV,2.0.32731*17

2019-08-09T19:40:55.156Z,1565379655.156 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,1405,NI  ,1,Agumntvlue out f ag*0A
$CAREV,194055,AUV,2.0.32731*17
 expected 71
2019-08-09T19:40:55.156Z,1565379655.156 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:06.459Z,1565379666.459 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:06.882Z,1565379666.882 [Micromodem](INFO): Nmea in: $AER,1410,I ,17,Argument value out o rnge*0C

2019-08-09T19:41:06.883Z,1565379666.883 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AER,1410,I ,17,Argument value out o rnge*0C
 expected 7A
2019-08-09T19:41:06.883Z,1565379666.883 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:18.178Z,1565379678.178 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:18.587Z,1565379678.587 [Micromodem](INFO): Nmea in: CAERR,9411,N  17,Argumntvau ot of range*03

2019-08-09T19:41:18.587Z,1565379678.587 [Micromodem](ERROR): Response from modem failed NMEA checksum: CAERR,9411,N  17,Argumntvau ot of range*03
 expected 0
2019-08-09T19:41:18.587Z,1565379678.587 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:23.880Z,1565379683.880 [RDI_Pathfinder](ERROR): only read 1 of 4 data items
2019-08-09T19:41:23.880Z,1565379683.880 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,    +0    +0,A
2019-08-09T19:41:29.894Z,1565379689.894 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:30.303Z,1565379690.303 [Micromodem](INFO): Nmea in: $CAER,9410NI ,7,rgme alue out of range*08

2019-08-09T19:41:30.303Z,1565379690.303 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,9410NI ,7,rgme alue out of range*08
 expected 3D
2019-08-09T19:41:30.303Z,1565379690.303 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:31.531Z,1565379691.531 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-08-09T19:41:31.531Z,1565379691.531 [Default:CheckIn:C.Wait] Stopped
2019-08-09T19:41:31.531Z,1565379691.531 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-09T19:41:31.532Z,1565379691.532 [Default:CheckIn:D] Running Loop=1
2019-08-09T19:41:31.951Z,1565379691.951 [Default:CheckIn:D] Stopped
2019-08-09T19:41:31.951Z,1565379691.951 [Default:CheckIn:E] Running Loop=1
2019-08-09T19:41:32.352Z,1565379692.352 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.969361 min 
2019-08-09T19:41:32.352Z,1565379692.352 [Default:CheckIn:E] Stopped
2019-08-09T19:41:32.352Z,1565379692.352 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-08-09T19:41:32.352Z,1565379692.352 [Default:CheckIn] Stopped
2019-08-09T19:41:32.352Z,1565379692.352 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-09T19:41:32.352Z,1565379692.352 [Default:CheckIn](INFO): Running loop #2
2019-08-09T19:41:32.352Z,1565379692.352 [Default:CheckIn] Running Loop=2
2019-08-09T19:41:32.353Z,1565379692.353 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-08-09T19:41:32.353Z,1565379692.353 [Default:CheckIn:Read_GPS] Running Loop=1
2019-08-09T19:41:33.948Z,1565379693.948 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:41:41.614Z,1565379701.614 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:45.259Z,1565379705.259 [Micromodem](INFO): Nmea in: $CAERR194142NI  17,rgumet ale ut of rnge*0D
$CAREV,194145,AUV,2.0.32731*17

2019-08-09T19:41:45.260Z,1565379705.260 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR194142NI  17,rgumet ale ut of rnge*0D
$CAREV,194145,AUV,2.0.32731*17
 expected 3
2019-08-09T19:41:45.260Z,1565379705.260 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:41:55.389Z,1565379715.389 [CBIT](INFO): Clearing failed state for component DropWeight
2019-08-09T19:41:55.389Z,1565379715.389 [DropWeight] No Fault, FailCount= 1
2019-08-09T19:41:56.562Z,1565379716.562 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:05.044Z,1565379725.044 [Micromodem](INFO): Nmea in: CAERR,19457NI 17Arumtvlu out of range*
$CAREV,194205,AUV,2.0.32731*10

2019-08-09T19:42:05.044Z,1565379725.044 [Micromodem](ERROR): Response from modem failed NMEA checksum: CAERR,19457NI 17Arumtvlu out of range*
$CAREV,194205,AUV,2.0.32731*10
 expected 0
2019-08-09T19:42:05.044Z,1565379725.044 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:05.053Z,1565379725.053 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-08-09T19:42:05.137Z,1565379725.137 [NAL9602](FAULT): received: 
+CSQ:1

OK06, 2, 0, 0, 0

OK
2019-08-09T19:42:05.137Z,1565379725.137 [NAL9602] Data Fault, FailCount= 1
2019-08-09T19:42:05.137Z,1565379725.137 [NAL9602](ERROR): Data Fault
2019-08-09T19:42:05.206Z,1565379725.206 [CBIT](ERROR): Data Fault in component: NAL9602
2019-08-09T19:42:05.447Z,1565379725.447 [NAL9602](INFO): Powering down
2019-08-09T19:42:06.296Z,1565379726.296 [CBIT](INFO): Clearing failed state for component NAL9602
2019-08-09T19:42:06.296Z,1565379726.296 [NAL9602] No Fault, FailCount= 1
2019-08-09T19:42:16.358Z,1565379736.358 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:16.763Z,1565379736.763 [Micromodem](INFO): Nmea in: $CAERR,194217,NI  17,Argumntvaue out o rnge*0E

2019-08-09T19:42:16.763Z,1565379736.763 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,194217,NI  17,Argumntvaue out o rnge*0E
 expected C
2019-08-09T19:42:16.764Z,1565379736.764 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:23.553Z,1565379743.553 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2019-08-09T19:42:23.957Z,1565379743.957 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.195652
2019-08-09T19:42:28.074Z,1565379748.074 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:28.479Z,1565379748.479 [Micromodem](INFO): Nmea in: $AER,9429NI ,7,Argument value out of range*03

2019-08-09T19:42:28.479Z,1565379748.479 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AER,9429NI ,7,Argument value out of range*03
 expected 2C
2019-08-09T19:42:28.479Z,1565379748.479 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:35.747Z,1565379755.747 [NAL9602](INFO): Powering up NAL9602
2019-08-09T19:42:37.165Z,1565379757.165 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003244
2019-08-09T19:42:39.790Z,1565379759.790 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:40.195Z,1565379760.195 [Micromodem](INFO): Nmea in: $CAER,19440NI  ,17,ruen ueot f range*0C

2019-08-09T19:42:40.195Z,1565379760.195 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,19440NI  ,17,ruen ueot f range*0C
 expected 3E
2019-08-09T19:42:40.195Z,1565379760.195 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:46.659Z,1565379766.659 [NAL9602](INFO): NAL9602 initialized
2019-08-09T19:42:47.480Z,1565379767.480 [NAL9602](DEBUG): Fix Requested
2019-08-09T19:42:51.506Z,1565379771.506 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:42:51.905Z,1565379771.905 [Micromodem](INFO): Nmea in: $CAERR1922,NI  17,Argument value out of range*0

2019-08-09T19:42:51.905Z,1565379771.905 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR1922,NI  17,Argument value out of range*0
 expected E
2019-08-09T19:42:51.905Z,1565379771.905 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:03.222Z,1565379783.222 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:05.240Z,1565379785.240 [Micromodem](INFO): Nmea in: $CAERR19404,NI 1,Argumen vlu ot ange*0D
$CAREV,194305,AUV,2.0.32731*11

2019-08-09T19:43:05.240Z,1565379785.240 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR19404,NI 1,Argumen vlu ot ange*0D
$CAREV,194305,AUV,2.0.32731*11
 expected 77
2019-08-09T19:43:05.244Z,1565379785.244 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:16.554Z,1565379796.554 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:16.955Z,1565379796.955 [Micromodem](INFO): Nmea in: $ERR,9417N,17,rgument le ut of range*0F

2019-08-09T19:43:16.956Z,1565379796.956 [Micromodem](ERROR): Response from modem failed NMEA checksum: $ERR,9417N,17,rgument le ut of range*0F
 expected 26
2019-08-09T19:43:16.956Z,1565379796.956 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:28.270Z,1565379808.270 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:28.687Z,1565379808.687 [Micromodem](INFO): Nmea in: $CAERR,194329,NI  ,7,rgun value out of range*02

2019-08-09T19:43:28.687Z,1565379808.687 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,194329,NI  ,7,rgun value out of range*02
 expected E
2019-08-09T19:43:28.688Z,1565379808.688 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:39.982Z,1565379819.982 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:45.234Z,1565379825.234 [Micromodem](INFO): Nmea in: $CAER,19NI  ,1rgument valu ot of rang*0
$CAREV,194345,AUV,2.0.32731*15

2019-08-09T19:43:45.234Z,1565379825.234 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAER,19NI  ,1rgument valu ot of rang*0
$CAREV,194345,AUV,2.0.32731*15
 expected 5F
2019-08-09T19:43:45.234Z,1565379825.234 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:56.546Z,1565379836.546 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:43:56.955Z,1565379836.955 [Micromodem](INFO): Nmea in: $CAERR,194357,I ,7,rgment vale utof rnge*0B

2019-08-09T19:43:56.955Z,1565379836.955 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,194357,I ,7,rgment vale utof rnge*0B
 expected 3B
2019-08-09T19:43:56.956Z,1565379836.956 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:44:08.270Z,1565379848.270 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:44:15.132Z,1565379855.132 [Micromodem](INFO): Nmea in: $CAERR1940,N ,17,Argument valueout of range*
$CAREV,194415,AUV,2.0.32731*17

2019-08-09T19:44:15.132Z,1565379855.132 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR1940,N ,17,Argument valueout of range*
$CAREV,194415,AUV,2.0.32731*17
 expected 6F
2019-08-09T19:44:15.132Z,1565379855.132 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:44:26.440Z,1565379866.440 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:44:35.336Z,1565379875.336 [Micromodem](INFO): Nmea in: $CAERR,1927,I  ,17,Argunt value out of range*B
$CAREV,194435,AUV,2.0.32731*15

2019-08-09T19:44:35.336Z,1565379875.336 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,1927,I  ,17,Argunt value out of range*B
$CAREV,194435,AUV,2.0.32731*15
 expected 4D
2019-08-09T19:44:35.337Z,1565379875.337 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:44:46.646Z,1565379886.646 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:44:55.132Z,1565379895.132 [Micromodem](INFO): Nmea in: $CAERR,194447,NI  ,17,Argumet ale to range0D
$CAREV,194455,AUV,2.0.32731*13

2019-08-09T19:44:55.132Z,1565379895.132 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,194447,NI  ,17,Argumet ale to range0D
$CAREV,194455,AUV,2.0.32731*13
 expected 75
2019-08-09T19:44:55.133Z,1565379895.133 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:06.442Z,1565379906.442 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:06.866Z,1565379906.866 [Micromodem](INFO): Nmea in: $CAERR,19507I ,17,rgument vue ut of rage*08

2019-08-09T19:45:06.867Z,1565379906.867 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,19507I ,17,rgument vue ut of rage*08
 expected 33
2019-08-09T19:45:06.868Z,1565379906.868 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:18.170Z,1565379918.170 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:18.566Z,1565379918.566 [Micromodem](INFO): Nmea in: $CAERR,194519,NI ,7guet valueout frange*07

2019-08-09T19:45:18.566Z,1565379918.566 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,194519,NI ,7guet valueout frange*07
 expected 65
2019-08-09T19:45:18.566Z,1565379918.566 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:29.882Z,1565379929.882 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:30.279Z,1565379930.279 [Micromodem](INFO): Nmea in: $CAERR,194530,NI  ,17,Argument value out of range*0C

2019-08-09T19:45:30.280Z,1565379930.280 [Micromodem](ERROR): Got error from modem: $CAERR,194530,NI  ,17,Argument value out of range*0C

2019-08-09T19:45:41.180Z,1565379941.180 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:52.507Z,1565379952.507 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:52.919Z,1565379952.919 [Micromodem](INFO): Nmea in: $AERR,953NI ,7,rget value ut of range*09

2019-08-09T19:45:52.919Z,1565379952.919 [Micromodem](ERROR): Response from modem failed NMEA checksum: $AERR,953NI ,7,rget value ut of range*09
 expected 1F
2019-08-09T19:45:52.919Z,1565379952.919 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:45:56.217Z,1565379956.217 [CommandLine](IMPORTANT): got command stop
2019-08-09T19:46:04.208Z,1565379964.208 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:46:04.615Z,1565379964.615 [Micromodem](INFO): Nmea in: $CAERR,19605,NI  ,17,rgument e oto rag*09

2019-08-09T19:46:04.615Z,1565379964.615 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAERR,19605,NI  ,17,rgument e oto rag*09
 expected 4A
2019-08-09T19:46:04.615Z,1565379964.615 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:46:15.930Z,1565379975.930 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:46:16.335Z,1565379976.335 [Micromodem](INFO): Nmea in: $CERR,194616,NI  ,17,Argumentvalue ou of ang*0B

2019-08-09T19:46:16.335Z,1565379976.335 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CERR,194616,NI  ,17,Argumentvalue ou of ang*0B
 expected 9
2019-08-09T19:46:16.336Z,1565379976.336 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:46:27.658Z,1565379987.658 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:46:32.526Z,1565379992.526 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-08-09T19:41:32.4Z
2019-08-09T19:46:32.526Z,1565379992.526 [Default:CheckIn:Read_GPS] Stopped
2019-08-09T19:46:32.527Z,1565379992.527 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-08-09T19:46:35.320Z,1565379995.320 [Micromodem](INFO): Nmea in: $CARR,9462,N ,17,Argment value out of rage06
$CAREV,194635,AUV,2.0.32731*17

2019-08-09T19:46:35.320Z,1565379995.320 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CARR,9462,N ,17,Argment value out of rage06
$CAREV,194635,AUV,2.0.32731*17
 expected 7
2019-08-09T19:46:35.320Z,1565379995.320 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:46:36.547Z,1565379996.547 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-08-09T19:46:40.760Z,1565380000.760 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20190809T192644/Express0008.lzma
2019-08-09T19:46:41.566Z,1565380001.566 [DataOverHttps](INFO): Moved sent file to Logs/20190809T192644/Express0008.lzma.bak
2019-08-09T19:46:41.566Z,1565380001.566 [DataOverHttps](INFO): SBD MOMSN=11547896
2019-08-09T19:46:42.636Z,1565380002.636 [Default:CheckIn:Read_Iridium] Stopped
2019-08-09T19:46:42.636Z,1565380002.636 [Default:CheckIn:C.Wait] Running Loop=1
2019-08-09T19:46:42.636Z,1565380002.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-08-09T19:46:46.642Z,1565380006.642 [Micromodem](ERROR): Nmea resend: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0*6B

2019-08-09T19:46:49.943Z,1565380009.943 [CommandLine](IMPORTANT): got command restart application
2019-08-09T19:46:50.949Z,1565380010.949 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:50.949Z,1565380010.949 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.056Z,1565380011.056 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-08-09T19:46:51.057Z,1565380011.057 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.057Z,1565380011.057 [CommandLine](INFO): Join timeout helper Thread ID is 4284
2019-08-09T19:46:51.099Z,1565380011.099 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-08-09T19:46:51.099Z,1565380011.099 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.102Z,1565380011.102 [NavChartDb](INFO): Join timeout helper Thread ID is 4285
2019-08-09T19:46:51.245Z,1565380011.245 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:51.245Z,1565380011.245 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.249Z,1565380011.249 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-08-09T19:46:51.249Z,1565380011.249 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.249Z,1565380011.249 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 4286
2019-08-09T19:46:51.357Z,1565380011.357 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:51.357Z,1565380011.357 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-08-09T19:46:51.358Z,1565380011.358 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.377Z,1565380011.377 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-08-09T19:46:51.377Z,1565380011.377 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.377Z,1565380011.377 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 4287
2019-08-09T19:46:51.573Z,1565380011.573 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:51.573Z,1565380011.573 [CTD_NeilBrown](INFO): Powering down
2019-08-09T19:46:51.584Z,1565380011.584 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.589Z,1565380011.589 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-08-09T19:46:51.589Z,1565380011.589 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.589Z,1565380011.589 [Radio_Surface](INFO): Join timeout helper Thread ID is 4288
2019-08-09T19:46:51.732Z,1565380011.732 [Radio_Surface](INFO): Powering down
2019-08-09T19:46:51.733Z,1565380011.733 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:51.734Z,1565380011.734 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.741Z,1565380011.741 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-08-09T19:46:51.742Z,1565380011.742 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:51.742Z,1565380011.742 [DataOverHttps](INFO): Join timeout helper Thread ID is 4289
2019-08-09T19:46:53.017Z,1565380013.017 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:53.020Z,1565380013.020 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.037Z,1565380013.037 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-08-09T19:46:53.037Z,1565380013.037 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.038Z,1565380013.038 [logger](INFO): Join timeout helper Thread ID is 4290
2019-08-09T19:46:53.061Z,1565380013.061 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:53.061Z,1565380013.061 [logger ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.077Z,1565380013.077 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-08-09T19:46:53.077Z,1565380013.077 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.077Z,1565380013.077 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-08-09T19:46:53.077Z,1565380013.077 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.078Z,1565380013.078 [controlThread](INFO): Join timeout helper Thread ID is 4291
2019-08-09T19:46:53.085Z,1565380013.085 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-08-09T19:46:53.085Z,1565380013.085 [controlThread](DEBUG): Uninitializing ControlThread
2019-08-09T19:46:53.085Z,1565380013.085 [AHRS_M2](INFO): Powering down
2019-08-09T19:46:53.157Z,1565380013.157 [DUSBL_Hydroid](INFO): Powering down
2019-08-09T19:46:53.229Z,1565380013.229 [Micromodem](INFO): Powering down
2019-08-09T19:46:53.325Z,1565380013.325 [NAL9602](INFO): Powering down
2019-08-09T19:46:53.397Z,1565380013.397 [RDI_Pathfinder](INFO): Powering down
2019-08-09T19:46:53.398Z,1565380013.398 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-08-09T19:46:53.399Z,1565380013.399 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-08-09T19:46:53.399Z,1565380013.399 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-08-09T19:46:53.400Z,1565380013.400 [MissionManager](INFO): Uninitializing Mission Default
2019-08-09T19:46:53.400Z,1565380013.400 [Default] Stopped
2019-08-09T19:46:53.400Z,1565380013.400 [Default](DEBUG): Aggregate::uninitialize Default
2019-08-09T19:46:53.400Z,1565380013.400 [Default:B.GoToSurface] Stopped
2019-08-09T19:46:53.400Z,1565380013.400 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-08-09T19:46:53.400Z,1565380013.400 [Default:CheckIn] Stopped
2019-08-09T19:46:53.401Z,1565380013.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-08-09T19:46:53.401Z,1565380013.401 [Default:CheckIn:C.Wait] Stopped
2019-08-09T19:46:53.401Z,1565380013.401 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-08-09T19:46:53.403Z,1565380013.403 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-08-09T19:46:53.403Z,1565380013.403 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-08-09T19:46:53.404Z,1565380013.404 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-08-09T19:46:53.404Z,1565380013.404 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-08-09T19:46:53.404Z,1565380013.404 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-08-09T19:46:53.404Z,1565380013.404 [BuoyancyServo](INFO): Powering down
2019-08-09T19:46:53.417Z,1565380013.417 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-08-09T19:46:53.417Z,1565380013.417 [ElevatorServo](INFO): Powering down
2019-08-09T19:46:53.417Z,1565380013.417 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-08-09T19:46:53.418Z,1565380013.418 [MassServo](INFO): Powering down
2019-08-09T19:46:53.418Z,1565380013.418 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-08-09T19:46:53.418Z,1565380013.418 [RudderServo](INFO): Powering down
2019-08-09T19:46:53.419Z,1565380013.419 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-08-09T19:46:53.419Z,1565380013.419 [ThrusterServo](INFO): Powering down
2019-08-09T19:46:53.420Z,1565380013.420 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-08-09T19:46:53.421Z,1565380013.421 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-08-09T19:46:53.421Z,1565380013.421 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-08-09T19:46:53.421Z,1565380013.421 [CBIT](DEBUG): Powering off loads.
2019-08-09T19:46:53.432Z,1565380013.432 [CBIT](DEBUG): Disabling WDT.
2019-08-09T19:46:53.444Z,1565380013.444 [CBIT](DEBUG): Opening all GF detection circuits.
2019-08-09T19:46:53.445Z,1565380013.445 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.494Z,1565380013.494 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.504Z,1565380013.504 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.549Z,1565380013.549 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.552Z,1565380013.552 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.609Z,1565380013.609 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-08-09T19:46:53.682Z,1565380013.682 [logger ThreadHandler](INFO): Thread cancelled.