2019-03-14T19:41:00.914Z,1552592460.914 [Supervisor](DEBUG): Initializing supervisor.
2019-03-14T19:41:00.917Z,1552592460.917 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-14T19:41:00.918Z,1552592460.918 [SyncHandler](INFO): Protected caller Thread ID is 8675
2019-03-14T19:41:00.918Z,1552592460.918 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-14T19:41:00.919Z,1552592460.919 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-14T19:41:00.920Z,1552592460.920 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8676
2019-03-14T19:41:00.923Z,1552592460.923 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-14T19:41:00.935Z,1552592460.935 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-14T19:41:00.936Z,1552592460.936 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-14T19:41:00.936Z,1552592460.936 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8677
2019-03-14T19:41:00.937Z,1552592460.937 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-14T19:41:00.938Z,1552592460.938 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-14T19:41:00.938Z,1552592460.938 [logger ThreadHandler](INFO): Protected caller Thread ID is 8678
2019-03-14T19:41:00.940Z,1552592460.940 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-14T19:41:00.941Z,1552592460.941 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-14T19:41:00.942Z,1552592460.942 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-14T19:41:01.376Z,1552592461.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-14T19:41:01.377Z,1552592461.377 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-14T19:41:01.475Z,1552592461.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-14T19:41:01.475Z,1552592461.475 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-14T19:41:01.810Z,1552592461.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-14T19:41:01.811Z,1552592461.811 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-14T19:41:01.955Z,1552592461.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-14T19:41:01.956Z,1552592461.956 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-14T19:41:02.152Z,1552592462.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-14T19:41:02.152Z,1552592462.152 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-14T19:41:02.626Z,1552592462.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-14T19:41:02.626Z,1552592462.626 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-14T19:41:02.842Z,1552592462.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-14T19:41:02.842Z,1552592462.842 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-14T19:41:02.991Z,1552592462.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-14T19:41:02.991Z,1552592462.991 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-14T19:41:03.192Z,1552592463.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-14T19:41:03.193Z,1552592463.193 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-14T19:41:03.289Z,1552592463.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-14T19:41:03.290Z,1552592463.290 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-14T19:41:03.607Z,1552592463.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-14T19:41:03.608Z,1552592463.608 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-14T19:41:03.687Z,1552592463.687 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-14T19:41:03.790Z,1552592463.790 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-14T19:41:03.791Z,1552592463.791 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-14T19:41:04.300Z,1552592464.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-14T19:41:04.301Z,1552592464.301 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-14T19:41:04.718Z,1552592464.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-14T19:41:04.720Z,1552592464.720 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-14T19:41:04.721Z,1552592464.721 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-14T19:41:05.235Z,1552592465.235 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-14T19:41:05.448Z,1552592465.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-14T19:41:05.553Z,1552592465.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-14T19:41:05.783Z,1552592465.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-14T19:41:05.784Z,1552592465.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-14T19:41:05.869Z,1552592465.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-14T19:41:05.962Z,1552592465.962 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-14T19:41:06.060Z,1552592466.060 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-14T19:41:06.143Z,1552592466.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-14T19:41:06.253Z,1552592466.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-14T19:41:06.430Z,1552592466.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-14T19:41:06.565Z,1552592466.565 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-14T19:41:06.565Z,1552592466.565 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-14T19:41:06.570Z,1552592466.570 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-14T19:41:06.904Z,1552592466.904 [DataOverHttps] Loaded
2019-03-14T19:41:06.905Z,1552592466.905 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-14T19:41:06.906Z,1552592466.906 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-14T19:41:06.906Z,1552592466.906 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8757
2019-03-14T19:41:06.945Z,1552592466.945 [Depth_Keller] Loaded
2019-03-14T19:41:06.945Z,1552592466.945 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-14T19:41:06.950Z,1552592466.950 [DropWeight] Loaded
2019-03-14T19:41:06.950Z,1552592466.950 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-14T19:41:06.999Z,1552592466.999 [DUSBL_Hydroid] Loaded
2019-03-14T19:41:06.000Z,1552592467.000 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-14T19:41:07.041Z,1552592467.041 [Micromodem] Loaded
2019-03-14T19:41:07.041Z,1552592467.041 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-14T19:41:07.140Z,1552592467.140 [NAL9602] Loaded
2019-03-14T19:41:07.140Z,1552592467.140 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-14T19:41:07.156Z,1552592467.156 [Onboard] Loaded
2019-03-14T19:41:07.156Z,1552592467.156 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-14T19:41:07.163Z,1552592467.163 [Radio_Surface] Loaded
2019-03-14T19:41:07.163Z,1552592467.163 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-14T19:41:07.164Z,1552592467.164 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-14T19:41:07.164Z,1552592467.164 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8758
2019-03-14T19:41:07.226Z,1552592467.226 [RDI_Pathfinder] Loaded
2019-03-14T19:41:07.226Z,1552592467.226 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-14T19:41:07.248Z,1552592467.248 [RDI_PathfinderUp] Loaded
2019-03-14T19:41:07.248Z,1552592467.248 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-14T19:41:09.062Z,1552592469.062 [BPC1] Loaded
2019-03-14T19:41:09.062Z,1552592469.062 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-14T19:41:09.062Z,1552592469.062 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-14T19:41:09.063Z,1552592469.063 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-14T19:41:09.076Z,1552592469.076 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-14T19:41:09.077Z,1552592469.077 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-14T19:41:09.184Z,1552592469.184 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-14T19:41:09.185Z,1552592469.185 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-14T19:41:09.244Z,1552592469.244 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-14T19:41:09.244Z,1552592469.244 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-14T19:41:09.305Z,1552592469.305 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-14T19:41:09.306Z,1552592469.306 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-14T19:41:09.327Z,1552592469.327 [NavChart] Loaded
2019-03-14T19:41:09.327Z,1552592469.327 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-14T19:41:09.331Z,1552592469.331 [UniversalFixResidualReporter] Loaded
2019-03-14T19:41:09.331Z,1552592469.331 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-14T19:41:09.331Z,1552592469.331 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-14T19:41:09.332Z,1552592469.332 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-14T19:41:09.436Z,1552592469.436 [BuoyancyServo] Loaded
2019-03-14T19:41:09.437Z,1552592469.437 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-14T19:41:09.452Z,1552592469.452 [ElevatorServo] Loaded
2019-03-14T19:41:09.452Z,1552592469.452 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-14T19:41:09.467Z,1552592469.467 [MassServo] Loaded
2019-03-14T19:41:09.468Z,1552592469.468 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-14T19:41:09.483Z,1552592469.483 [RudderServo] Loaded
2019-03-14T19:41:09.484Z,1552592469.484 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-14T19:41:09.498Z,1552592469.498 [ThrusterServo] Loaded
2019-03-14T19:41:09.499Z,1552592469.499 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-14T19:41:09.499Z,1552592469.499 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-14T19:41:09.500Z,1552592469.500 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-14T19:41:09.754Z,1552592469.754 [CTD_NeilBrown] Loaded
2019-03-14T19:41:09.755Z,1552592469.755 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-14T19:41:09.756Z,1552592469.756 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-14T19:41:09.756Z,1552592469.756 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8759
2019-03-14T19:41:09.801Z,1552592469.801 [WetLabsSeaOWL_UV_A] Loaded
2019-03-14T19:41:09.802Z,1552592469.802 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-14T19:41:09.803Z,1552592469.803 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-14T19:41:09.803Z,1552592469.803 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8760
2019-03-14T19:41:09.804Z,1552592469.804 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-14T19:41:09.804Z,1552592469.804 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-14T19:41:10.372Z,1552592470.372 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-14T19:41:10.372Z,1552592470.372 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-14T19:41:10.413Z,1552592470.413 [DepthRateCalculator] Loaded
2019-03-14T19:41:10.414Z,1552592470.414 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-14T19:41:10.420Z,1552592470.420 [PitchRateCalculator] Loaded
2019-03-14T19:41:10.420Z,1552592470.420 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-14T19:41:10.432Z,1552592470.432 [SpeedCalculator] Loaded
2019-03-14T19:41:10.433Z,1552592470.433 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-14T19:41:10.454Z,1552592470.454 [TempGradientCalculator] Loaded
2019-03-14T19:41:10.454Z,1552592470.454 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-14T19:41:10.460Z,1552592470.460 [YawRateCalculator] Loaded
2019-03-14T19:41:10.461Z,1552592470.461 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-14T19:41:10.502Z,1552592470.502 [ElevatorOffsetCalculator] Loaded
2019-03-14T19:41:10.502Z,1552592470.502 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-14T19:41:10.502Z,1552592470.502 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-14T19:41:10.503Z,1552592470.503 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-14T19:41:10.641Z,1552592470.641 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-14T19:41:10.664Z,1552592470.664 [SBIT] Loaded
2019-03-14T19:41:10.664Z,1552592470.664 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-14T19:41:10.665Z,1552592470.665 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-14T19:41:10.677Z,1552592470.677 [IBIT] Loaded
2019-03-14T19:41:10.677Z,1552592470.677 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-14T19:41:10.680Z,1552592470.680 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-14T19:41:10.820Z,1552592470.820 [CBIT] Loaded
2019-03-14T19:41:10.820Z,1552592470.820 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-14T19:41:10.821Z,1552592470.821 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-14T19:41:10.821Z,1552592470.821 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-14T19:41:10.886Z,1552592470.886 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-14T19:41:10.886Z,1552592470.886 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-14T19:41:10.986Z,1552592470.986 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-14T19:41:10.986Z,1552592470.986 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-14T19:41:11.054Z,1552592471.054 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-14T19:41:11.139Z,1552592471.139 [VerticalControl] Loaded
2019-03-14T19:41:11.139Z,1552592471.139 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-14T19:41:11.140Z,1552592471.140 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-14T19:41:11.199Z,1552592471.199 [HorizontalControl] Loaded
2019-03-14T19:41:11.200Z,1552592471.200 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-14T19:41:11.200Z,1552592471.200 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-14T19:41:11.202Z,1552592471.202 [SpeedControl] Loaded
2019-03-14T19:41:11.202Z,1552592471.202 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-14T19:41:11.203Z,1552592471.203 [LoopControl](DEBUG): Construct LoopControl.
2019-03-14T19:41:11.203Z,1552592471.203 [LoopControl] Loaded
2019-03-14T19:41:11.204Z,1552592471.204 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-14T19:41:11.204Z,1552592471.204 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-14T19:41:11.205Z,1552592471.205 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-14T19:41:11.232Z,1552592471.232 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-14T19:41:11.236Z,1552592471.236 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-14T19:41:11.236Z,1552592471.236 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-14T19:41:11.243Z,1552592471.243 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-14T19:41:11.244Z,1552592471.244 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-14T19:41:11.245Z,1552592471.245 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8761
2019-03-14T19:41:11.249Z,1552592471.249 [Supervisor](INFO): Main Thread ID is 8082
2019-03-14T19:41:11.250Z,1552592471.250 [Supervisor](DEBUG): Running supervisor.
2019-03-14T19:41:11.250Z,1552592471.250 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8762
2019-03-14T19:41:11.253Z,1552592471.253 [controlThread ThreadHandler](INFO): Handler Thread ID is 8763
2019-03-14T19:41:11.253Z,1552592471.253 [controlThread](DEBUG): Initializing ControlThread
2019-03-14T19:41:11.260Z,1552592471.260 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:41:11.261Z,1552592471.261 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-14T19:41:11.261Z,1552592471.261 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-14T19:41:11.262Z,1552592471.262 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-14T19:41:11.263Z,1552592471.263 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-14T19:41:11.263Z,1552592471.263 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-14T19:41:11.263Z,1552592471.263 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-14T19:41:11.264Z,1552592471.264 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-14T19:41:11.264Z,1552592471.264 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-14T19:41:11.265Z,1552592471.265 [SBIT](INFO): Initialize SBIT Component.
2019-03-14T19:41:11.265Z,1552592471.265 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-14T19:41:11.265Z,1552592471.265 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-14T19:41:11.266Z,1552592471.266 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-14T19:41:11.266Z,1552592471.266 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-14T19:41:11.267Z,1552592471.267 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-14T19:41:11.268Z,1552592471.268 [IBIT](INFO): Initialize IBIT Component.
2019-03-14T19:41:11.268Z,1552592471.268 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-14T19:41:11.270Z,1552592471.270 [logger ThreadHandler](INFO): Handler Thread ID is 8764
2019-03-14T19:41:11.281Z,1552592471.281 [CBIT](DEBUG): Initialized mux pins.
2019-03-14T19:41:11.281Z,1552592471.281 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-14T19:41:11.289Z,1552592471.289 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8765
2019-03-14T19:41:11.290Z,1552592471.290 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-14T19:41:11.301Z,1552592471.301 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8766
2019-03-14T19:41:11.305Z,1552592471.305 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-14T19:41:11.305Z,1552592471.305 [CBIT](DEBUG): Initializing heartbeat.
2019-03-14T19:41:11.313Z,1552592471.313 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8767
2019-03-14T19:41:11.314Z,1552592471.314 [CTD_NeilBrown](INFO): Powering down
2019-03-14T19:41:11.341Z,1552592471.341 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8768
2019-03-14T19:41:11.342Z,1552592471.342 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-14T19:41:11.377Z,1552592471.377 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-14T19:41:11.377Z,1552592471.377 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-14T19:41:11.378Z,1552592471.378 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8769
2019-03-14T19:41:11.381Z,1552592471.381 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-14T19:41:11.381Z,1552592471.381 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-14T19:41:11.381Z,1552592471.381 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-14T19:41:11.382Z,1552592471.382 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-14T19:41:11.382Z,1552592471.382 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-14T19:41:11.382Z,1552592471.382 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-14T19:41:11.382Z,1552592471.382 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-14T19:41:11.382Z,1552592471.382 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-14T19:41:11.382Z,1552592471.382 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-14T19:41:11.383Z,1552592471.383 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-14T19:41:11.383Z,1552592471.383 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-14T19:41:11.383Z,1552592471.383 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-14T19:41:11.383Z,1552592471.383 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-14T19:41:11.383Z,1552592471.383 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-14T19:41:11.384Z,1552592471.384 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-14T19:41:11.384Z,1552592471.384 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-14T19:41:11.413Z,1552592471.413 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-14T19:41:11.415Z,1552592471.415 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-14T19:41:11.415Z,1552592471.415 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-14T19:41:11.416Z,1552592471.416 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-14T19:41:11.417Z,1552592471.417 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-14T19:41:11.426Z,1552592471.426 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-14T19:41:11.457Z,1552592471.457 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-03-14T19:41:11.458Z,1552592471.458 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-14T19:41:11.512Z,1552592471.512 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-14T19:41:11.526Z,1552592471.526 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-14T19:41:11.527Z,1552592471.527 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-14T19:41:11.564Z,1552592471.564 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-14T19:41:11.566Z,1552592471.566 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-14T19:41:11.597Z,1552592471.597 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-14T19:41:11.603Z,1552592471.603 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-03-14T19:41:11.608Z,1552592471.608 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-14T19:41:11.635Z,1552592471.635 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-14T19:41:11.635Z,1552592471.635 [DUSBL_Hydroid](INFO): Powering up
2019-03-14T19:41:11.636Z,1552592471.636 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-14T19:41:11.653Z,1552592471.653 [Micromodem](INFO): Start
2019-03-14T19:41:11.705Z,1552592471.705 [Radio_Surface](INFO): Powering up
2019-03-14T19:41:11.717Z,1552592471.717 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-14T19:41:11.750Z,1552592471.750 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-14T19:41:11.765Z,1552592471.765 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-14T19:41:11.766Z,1552592471.766 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-14T19:41:11.773Z,1552592471.773 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-14T19:41:11.774Z,1552592471.774 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-14T19:41:11.781Z,1552592471.781 [MassServo](DEBUG): Initializing MassServo.
2019-03-14T19:41:11.782Z,1552592471.782 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-14T19:41:11.789Z,1552592471.789 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-14T19:41:11.790Z,1552592471.790 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-14T19:41:11.797Z,1552592471.797 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-14T19:41:12.030Z,1552592472.030 [Micromodem](INFO): Starting
2019-03-14T19:41:12.031Z,1552592472.031 [Micromodem](INFO): Powering up
2019-03-14T19:41:12.031Z,1552592472.031 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-14T19:41:12.137Z,1552592472.137 [RDI_PathfinderUp](INFO): Powering down
2019-03-14T19:41:12.189Z,1552592472.189 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-14T19:41:12.422Z,1552592472.422 [Micromodem](INFO): Starting
2019-03-14T19:41:12.441Z,1552592472.441 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-14T19:41:12.827Z,1552592472.827 [Micromodem](INFO): Starting
2019-03-14T19:41:13.230Z,1552592473.230 [Micromodem](INFO): Starting
2019-03-14T19:41:13.634Z,1552592473.634 [Micromodem](INFO): Starting
2019-03-14T19:41:14.038Z,1552592474.038 [Micromodem](INFO): Starting
2019-03-14T19:41:14.442Z,1552592474.442 [Micromodem](INFO): Starting
2019-03-14T19:41:14.846Z,1552592474.846 [Micromodem](INFO): Starting
2019-03-14T19:41:15.251Z,1552592475.251 [Micromodem](INFO): Starting
2019-03-14T19:41:15.654Z,1552592475.654 [Micromodem](INFO): Starting
2019-03-14T19:41:16.058Z,1552592476.058 [Micromodem](INFO): Starting
2019-03-14T19:41:16.462Z,1552592476.462 [Micromodem](INFO): Starting
2019-03-14T19:41:16.866Z,1552592476.866 [Micromodem](INFO): Starting
2019-03-14T19:41:16.867Z,1552592476.867 [Micromodem](INFO): Starting
2019-03-14T19:41:16.869Z,1552592476.869 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T19:41:16.869Z,1552592476.869 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T19:41:16.869Z,1552592476.869 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-03-14T19:41:16.870Z,1552592476.870 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T19:41:16.871Z,1552592476.871 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T19:41:16.872Z,1552592476.872 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:41:16.873Z,1552592476.873 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:41:16.874Z,1552592476.874 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T19:41:16.875Z,1552592476.875 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T19:41:17.270Z,1552592477.270 [Micromodem](INFO): Starting
2019-03-14T19:41:17.674Z,1552592477.674 [Micromodem](INFO): Starting
2019-03-14T19:41:18.078Z,1552592478.078 [Micromodem](INFO): Starting
2019-03-14T19:41:18.482Z,1552592478.482 [Micromodem](INFO): Starting
2019-03-14T19:41:18.887Z,1552592478.887 [Micromodem](INFO): Starting
2019-03-14T19:41:19.290Z,1552592479.290 [Micromodem](INFO): Starting
2019-03-14T19:41:19.694Z,1552592479.694 [Micromodem](INFO): Starting
2019-03-14T19:41:20.098Z,1552592480.098 [Micromodem](INFO): Starting
2019-03-14T19:41:20.502Z,1552592480.502 [Micromodem](INFO): Starting
2019-03-14T19:41:20.907Z,1552592480.907 [Micromodem](INFO): Starting
2019-03-14T19:41:21.310Z,1552592481.310 [Micromodem](INFO): Starting
2019-03-14T19:41:21.714Z,1552592481.714 [Micromodem](INFO): Starting
2019-03-14T19:41:22.118Z,1552592482.118 [Micromodem](INFO): Starting
2019-03-14T19:41:22.522Z,1552592482.522 [Micromodem](INFO): Starting
2019-03-14T19:41:22.927Z,1552592482.927 [Micromodem](INFO): Starting
2019-03-14T19:41:23.330Z,1552592483.330 [Micromodem](INFO): Starting
2019-03-14T19:41:23.734Z,1552592483.734 [Micromodem](INFO): Starting
2019-03-14T19:41:24.138Z,1552592484.138 [Micromodem](INFO): Starting
2019-03-14T19:41:24.542Z,1552592484.542 [Micromodem](INFO): Starting
2019-03-14T19:41:24.946Z,1552592484.946 [Micromodem](INFO): Starting
2019-03-14T19:41:24.972Z,1552592484.972 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-14T19:41:25.350Z,1552592485.350 [Micromodem](INFO): Starting
2019-03-14T19:41:25.757Z,1552592485.757 [Micromodem](INFO): Starting
2019-03-14T19:41:26.159Z,1552592486.159 [Micromodem](INFO): Starting
2019-03-14T19:41:26.159Z,1552592486.159 [Micromodem](IMPORTANT): CACFG stsring$CACFG,ALL,0*31

2019-03-14T19:41:26.160Z,1552592486.160 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-14T19:41:26.562Z,1552592486.562 [Micromodem](INFO): Starting
2019-03-14T19:41:26.967Z,1552592486.967 [Micromodem](INFO): Starting
2019-03-14T19:41:27.370Z,1552592487.370 [Micromodem](INFO): Starting
2019-03-14T19:41:27.786Z,1552592487.786 [Micromodem](INFO): Starting
2019-03-14T19:41:28.179Z,1552592488.179 [Micromodem](INFO): Starting
2019-03-14T19:41:28.582Z,1552592488.582 [Micromodem](INFO): Starting
2019-03-14T19:41:28.987Z,1552592488.987 [Micromodem](INFO): Starting
2019-03-14T19:41:29.391Z,1552592489.391 [Micromodem](INFO): Starting
2019-03-14T19:41:29.805Z,1552592489.805 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-14T19:41:29.818Z,1552592489.818 [Micromodem](INFO): Starting
2019-03-14T19:41:30.198Z,1552592490.198 [Micromodem](INFO): Starting
2019-03-14T19:41:30.602Z,1552592490.602 [Micromodem](INFO): Starting
2019-03-14T19:41:31.006Z,1552592491.006 [Micromodem](INFO): Starting
2019-03-14T19:41:31.410Z,1552592491.410 [Micromodem](INFO): Starting
2019-03-14T19:41:31.814Z,1552592491.814 [Micromodem](INFO): Starting
2019-03-14T19:41:32.218Z,1552592492.218 [Micromodem](INFO): Starting
2019-03-14T19:41:32.622Z,1552592492.622 [Micromodem](INFO): Starting
2019-03-14T19:41:33.026Z,1552592493.026 [Micromodem](INFO): Starting
2019-03-14T19:41:33.430Z,1552592493.430 [Micromodem](INFO): Starting
2019-03-14T19:41:33.834Z,1552592493.834 [Micromodem](INFO): Starting
2019-03-14T19:41:34.238Z,1552592494.238 [Micromodem](INFO): Starting
2019-03-14T19:41:34.642Z,1552592494.642 [Micromodem](INFO): Starting
2019-03-14T19:41:35.046Z,1552592495.046 [Micromodem](INFO): Starting
2019-03-14T19:41:35.450Z,1552592495.450 [Micromodem](INFO): Starting
2019-03-14T19:41:35.854Z,1552592495.854 [Micromodem](INFO): Starting
2019-03-14T19:41:36.258Z,1552592496.258 [Micromodem](INFO): Starting
2019-03-14T19:41:36.259Z,1552592496.259 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33

2019-03-14T19:41:36.259Z,1552592496.259 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:41:36.662Z,1552592496.662 [Micromodem](INFO): Starting
2019-03-14T19:41:37.063Z,1552592497.063 [Micromodem](INFO): Starting
2019-03-14T19:41:37.470Z,1552592497.470 [Micromodem](INFO): Starting
2019-03-14T19:41:37.471Z,1552592497.471 [NAL9602](INFO): Powering up NAL9602
2019-03-14T19:41:37.874Z,1552592497.874 [Micromodem](INFO): Starting
2019-03-14T19:41:38.278Z,1552592498.278 [Micromodem](INFO): Starting
2019-03-14T19:41:38.682Z,1552592498.682 [Micromodem](INFO): Starting
2019-03-14T19:41:39.086Z,1552592499.086 [Micromodem](INFO): Starting
2019-03-14T19:41:39.490Z,1552592499.490 [Micromodem](INFO): Starting
2019-03-14T19:41:39.892Z,1552592499.892 [Micromodem](INFO): Starting
2019-03-14T19:41:39.940Z,1552592499.940 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-14T19:41:39.953Z,1552592499.953 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-14T19:41:40.298Z,1552592500.298 [Micromodem](INFO): Starting
2019-03-14T19:41:40.702Z,1552592500.702 [Micromodem](INFO): Starting
2019-03-14T19:41:41.103Z,1552592501.103 [Micromodem](INFO): Starting
2019-03-14T19:41:41.511Z,1552592501.511 [Micromodem](INFO): Starting
2019-03-14T19:41:41.914Z,1552592501.914 [Micromodem](INFO): Starting
2019-03-14T19:41:42.318Z,1552592502.318 [Micromodem](INFO): Starting
2019-03-14T19:41:42.932Z,1552592502.932 [Micromodem](INFO): Starting
2019-03-14T19:41:43.334Z,1552592503.334 [Micromodem](INFO): Starting
2019-03-14T19:41:43.746Z,1552592503.746 [Micromodem](INFO): Starting
2019-03-14T19:41:44.146Z,1552592504.146 [Micromodem](INFO): Starting
2019-03-14T19:41:44.546Z,1552592504.546 [Micromodem](INFO): Starting
2019-03-14T19:41:44.950Z,1552592504.950 [Micromodem](INFO): Starting
2019-03-14T19:41:45.354Z,1552592505.354 [Micromodem](INFO): Starting
2019-03-14T19:41:45.758Z,1552592505.758 [Micromodem](INFO): Starting
2019-03-14T19:41:46.170Z,1552592506.170 [Micromodem](INFO): Starting
2019-03-14T19:41:46.171Z,1552592506.171 [Micromodem](IMPORTANT): CACFG stsring$CACFG,nav.dt.txtrig_gpio4,1*66

2019-03-14T19:41:46.171Z,1552592506.171 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-03-14T19:41:46.574Z,1552592506.574 [Micromodem](INFO): Starting
2019-03-14T19:41:46.967Z,1552592506.967 [Micromodem](INFO): Starting
2019-03-14T19:41:47.374Z,1552592507.374 [Micromodem](INFO): Starting
2019-03-14T19:41:47.778Z,1552592507.778 [Micromodem](INFO): Starting
2019-03-14T19:41:48.182Z,1552592508.182 [Micromodem](INFO): Starting
2019-03-14T19:41:48.586Z,1552592508.586 [Micromodem](INFO): Starting
2019-03-14T19:41:48.587Z,1552592508.587 [NAL9602](INFO): NAL9602 initialized
2019-03-14T19:41:48.990Z,1552592508.990 [Micromodem](INFO): Starting
2019-03-14T19:41:49.394Z,1552592509.394 [Micromodem](INFO): Starting
2019-03-14T19:41:49.409Z,1552592509.409 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:41:49.810Z,1552592509.810 [Micromodem](INFO): Starting
2019-03-14T19:41:50.202Z,1552592510.202 [Micromodem](INFO): Starting
2019-03-14T19:41:50.203Z,1552592510.203 [Micromodem](IMPORTANT): CACFG stsring$CACFG,BND,1*39

2019-03-14T19:41:50.204Z,1552592510.204 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-14T19:41:51Z,0*71

2019-03-14T19:41:50.204Z,1552592510.204 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-14T19:41:51Z,0*71

2019-03-14T19:41:50.204Z,1552592510.204 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-14T19:41:51Z,0*71

2019-03-14T19:41:50.608Z,1552592510.608 [Micromodem](INFO): Starting
2019-03-14T19:41:50.609Z,1552592510.609 [Micromodem](ERROR): Response from modem unexpected: $CADQF,193,1*5B

2019-03-14T19:41:50.689Z,1552592510.689 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.012352
CHAN A1 (24V): -0.026915
CHAN A2 (12V): -0.007308
CHAN A3 (5V): -0.001171
CHAN B0 (3.3V): 0.000255
CHAN B1 (3.15aV): 0.000163
CHAN B2 (3.15bV): 0.000181
CHAN B3 (GND): 0.002197
OPEN: 0.006383
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-14T19:41:51.010Z,1552592511.010 [Micromodem](INFO): Starting
2019-03-14T19:41:51.011Z,1552592511.011 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T19:41:51.414Z,1552592511.414 [Micromodem](INFO): Starting
2019-03-14T19:41:51.419Z,1552592511.419 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314194149.650148,05,475,14,0094,0150,246,00,00,02,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,193,-0.10,-999,9760,4000*7B

2019-03-14T19:41:51.818Z,1552592511.818 [Micromodem](INFO): Starting
2019-03-14T19:41:52.222Z,1552592512.222 [Micromodem](INFO): Starting
2019-03-14T19:41:52.626Z,1552592512.626 [Micromodem](INFO): Starting
2019-03-14T19:41:53.444Z,1552592513.444 [Micromodem](FAULT): LCB 2 fault: 
2019-03-14T19:41:53.444Z,1552592513.444 [Micromodem] Hardware Fault, FailCount= 1
2019-03-14T19:41:53.444Z,1552592513.444 [Micromodem](ERROR): Hardware Fault
2019-03-14T19:41:53.444Z,1552592513.444 [Micromodem](INFO): Stop
2019-03-14T19:41:53.444Z,1552592513.444 [Micromodem](INFO): uninitialize
2019-03-14T19:41:53.445Z,1552592513.445 [Micromodem](INFO): Powering down
2019-03-14T19:41:53.596Z,1552592513.596 [CBIT](ERROR): Hardware Fault in component: Micromodem
2019-03-14T19:41:53.838Z,1552592513.838 [Micromodem](INFO): Stop
2019-03-14T19:41:53.838Z,1552592513.838 [Micromodem](INFO): uninitialize
2019-03-14T19:41:53.838Z,1552592513.838 [Micromodem](INFO): Powering down
2019-03-14T19:41:54.250Z,1552592514.250 [Micromodem](INFO): Stopping
2019-03-14T19:41:54.251Z,1552592514.251 [Micromodem](FAULT): LCB 2 fault:  Software Overcurrent.
2019-03-14T19:41:54.646Z,1552592514.646 [Micromodem](INFO): Stopped
2019-03-14T19:41:54.737Z,1552592514.737 [CBIT](INFO): Clearing failed state for component Micromodem
2019-03-14T19:41:54.737Z,1552592514.737 [Micromodem] No Fault, FailCount= 1
2019-03-14T19:41:55.050Z,1552592515.050 [Micromodem](INFO): Start
2019-03-14T19:41:55.642Z,1552592515.642 [Micromodem](INFO): Start
2019-03-14T19:41:56.046Z,1552592516.046 [Micromodem](INFO): Start
2019-03-14T19:41:56.450Z,1552592516.450 [Micromodem](INFO): Start
2019-03-14T19:41:56.854Z,1552592516.854 [Micromodem](INFO): Start
2019-03-14T19:41:57.258Z,1552592517.258 [Micromodem](INFO): Starting
2019-03-14T19:41:57.258Z,1552592517.258 [Micromodem](INFO): Powering up
2019-03-14T19:41:57.258Z,1552592517.258 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-14T19:41:57.662Z,1552592517.662 [Micromodem](INFO): Starting
2019-03-14T19:41:58.066Z,1552592518.066 [Micromodem](INFO): Starting
2019-03-14T19:41:58.470Z,1552592518.470 [Micromodem](INFO): Starting
2019-03-14T19:41:58.874Z,1552592518.874 [Micromodem](INFO): Starting
2019-03-14T19:41:59.278Z,1552592519.278 [Micromodem](INFO): Starting
2019-03-14T19:41:59.682Z,1552592519.682 [Micromodem](INFO): Starting
2019-03-14T19:42:00.094Z,1552592520.094 [Micromodem](INFO): Starting
2019-03-14T19:42:00.491Z,1552592520.491 [Micromodem](INFO): Starting
2019-03-14T19:42:00.894Z,1552592520.894 [Micromodem](INFO): Starting
2019-03-14T19:42:01.298Z,1552592521.298 [Micromodem](INFO): Starting
2019-03-14T19:42:01.702Z,1552592521.702 [Micromodem](INFO): Starting
2019-03-14T19:42:02.106Z,1552592522.106 [Micromodem](INFO): Starting
2019-03-14T19:42:02.106Z,1552592522.106 [Micromodem](INFO): Starting
2019-03-14T19:42:02.107Z,1552592522.107 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T19:42:02.107Z,1552592522.107 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T19:42:02.107Z,1552592522.107 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-03-14T19:42:02.107Z,1552592522.107 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T19:42:02.107Z,1552592522.107 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T19:42:02.108Z,1552592522.108 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:42:02.108Z,1552592522.108 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:42:02.108Z,1552592522.108 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T19:42:02.108Z,1552592522.108 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T19:42:02.518Z,1552592522.518 [Micromodem](INFO): Starting
2019-03-14T19:42:02.914Z,1552592522.914 [Micromodem](INFO): Starting
2019-03-14T19:42:03.320Z,1552592523.320 [Micromodem](INFO): Starting
2019-03-14T19:42:03.722Z,1552592523.722 [Micromodem](INFO): Starting
2019-03-14T19:42:04.126Z,1552592524.126 [Micromodem](INFO): Starting
2019-03-14T19:42:04.530Z,1552592524.530 [Micromodem](INFO): Starting
2019-03-14T19:42:04.934Z,1552592524.934 [Micromodem](INFO): Starting
2019-03-14T19:42:05.338Z,1552592525.338 [Micromodem](INFO): Starting
2019-03-14T19:42:05.742Z,1552592525.742 [Micromodem](INFO): Starting
2019-03-14T19:42:06.146Z,1552592526.146 [Micromodem](INFO): Starting
2019-03-14T19:42:06.550Z,1552592526.550 [Micromodem](INFO): Starting
2019-03-14T19:42:06.954Z,1552592526.954 [Micromodem](INFO): Starting
2019-03-14T19:42:07.358Z,1552592527.358 [Micromodem](INFO): Starting
2019-03-14T19:42:07.768Z,1552592527.768 [Micromodem](INFO): Starting
2019-03-14T19:42:08.166Z,1552592528.166 [Micromodem](INFO): Starting
2019-03-14T19:42:08.574Z,1552592528.574 [Micromodem](INFO): Starting
2019-03-14T19:42:08.974Z,1552592528.974 [Micromodem](INFO): Starting
2019-03-14T19:42:09.378Z,1552592529.378 [Micromodem](INFO): Starting
2019-03-14T19:42:09.790Z,1552592529.790 [Micromodem](INFO): Starting
2019-03-14T19:42:10.186Z,1552592530.186 [Micromodem](INFO): Starting
2019-03-14T19:42:10.594Z,1552592530.594 [Micromodem](INFO): Starting
2019-03-14T19:42:10.998Z,1552592530.998 [Micromodem](INFO): Starting
2019-03-14T19:42:11.399Z,1552592531.399 [Micromodem](INFO): Starting
2019-03-14T19:42:11.808Z,1552592531.808 [Micromodem](INFO): Starting
2019-03-14T19:42:12.206Z,1552592532.206 [Micromodem](INFO): Starting
2019-03-14T19:42:12.611Z,1552592532.611 [Micromodem](INFO): Starting
2019-03-14T19:42:13.018Z,1552592533.018 [Micromodem](INFO): Starting
2019-03-14T19:42:13.424Z,1552592533.424 [Micromodem](INFO): Starting
2019-03-14T19:42:13.425Z,1552592533.425 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-14T19:42:13.822Z,1552592533.822 [Micromodem](INFO): Starting
2019-03-14T19:42:14.226Z,1552592534.226 [Micromodem](INFO): Starting
2019-03-14T19:42:14.634Z,1552592534.634 [Micromodem](INFO): Starting
2019-03-14T19:42:15.038Z,1552592535.038 [Micromodem](INFO): Starting
2019-03-14T19:42:15.438Z,1552592535.438 [Micromodem](INFO): Starting
2019-03-14T19:42:15.846Z,1552592535.846 [Micromodem](INFO): Starting
2019-03-14T19:42:16.250Z,1552592536.250 [Micromodem](INFO): Starting
2019-03-14T19:42:16.650Z,1552592536.650 [Micromodem](INFO): Starting
2019-03-14T19:42:17.058Z,1552592537.058 [Micromodem](INFO): Starting
2019-03-14T19:42:17.458Z,1552592537.458 [Micromodem](INFO): Starting
2019-03-14T19:42:17.866Z,1552592537.866 [Micromodem](INFO): Starting
2019-03-14T19:42:18.266Z,1552592538.266 [Micromodem](INFO): Starting
2019-03-14T19:42:18.670Z,1552592538.670 [Micromodem](INFO): Starting
2019-03-14T19:42:19.074Z,1552592539.074 [Micromodem](INFO): Starting
2019-03-14T19:42:19.478Z,1552592539.478 [Micromodem](INFO): Starting
2019-03-14T19:42:19.882Z,1552592539.882 [Micromodem](INFO): Starting
2019-03-14T19:42:20.287Z,1552592540.287 [Micromodem](INFO): Starting
2019-03-14T19:42:20.690Z,1552592540.690 [Micromodem](INFO): Starting
2019-03-14T19:42:21.094Z,1552592541.094 [Micromodem](INFO): Starting
2019-03-14T19:42:21.498Z,1552592541.498 [Micromodem](INFO): Starting
2019-03-14T19:42:21.902Z,1552592541.902 [Micromodem](INFO): Starting
2019-03-14T19:42:22.310Z,1552592542.310 [Micromodem](INFO): Starting
2019-03-14T19:42:22.822Z,1552592542.822 [Micromodem](INFO): Starting
2019-03-14T19:42:22.823Z,1552592542.823 [Micromodem](IMPORTANT): CACFG stsring$CACFG,ALL,0*31

2019-03-14T19:42:22.823Z,1552592542.823 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-14T19:42:23.222Z,1552592543.222 [Micromodem](INFO): Starting
2019-03-14T19:42:23.626Z,1552592543.626 [Micromodem](INFO): Starting
2019-03-14T19:42:24.026Z,1552592544.026 [Micromodem](INFO): Starting
2019-03-14T19:42:24.434Z,1552592544.434 [Micromodem](INFO): Starting
2019-03-14T19:42:24.836Z,1552592544.836 [Micromodem](INFO): Starting
2019-03-14T19:42:25.238Z,1552592545.238 [Micromodem](INFO): Starting
2019-03-14T19:42:25.646Z,1552592545.646 [Micromodem](INFO): Starting
2019-03-14T19:42:26.046Z,1552592546.046 [Micromodem](INFO): Starting
2019-03-14T19:42:26.450Z,1552592546.450 [Micromodem](INFO): Starting
2019-03-14T19:42:26.854Z,1552592546.854 [Micromodem](INFO): Starting
2019-03-14T19:42:27.264Z,1552592547.264 [Micromodem](INFO): Starting
2019-03-14T19:42:27.666Z,1552592547.666 [Micromodem](INFO): Starting
2019-03-14T19:42:28.070Z,1552592548.070 [Micromodem](INFO): Starting
2019-03-14T19:42:28.470Z,1552592548.470 [Micromodem](INFO): Starting
2019-03-14T19:42:28.878Z,1552592548.878 [Micromodem](INFO): Starting
2019-03-14T19:42:29.282Z,1552592549.282 [Micromodem](INFO): Starting
2019-03-14T19:42:29.682Z,1552592549.682 [Micromodem](INFO): Starting
2019-03-14T19:42:30.087Z,1552592550.087 [Micromodem](INFO): Starting
2019-03-14T19:42:30.496Z,1552592550.496 [Micromodem](INFO): Starting
2019-03-14T19:42:30.894Z,1552592550.894 [Micromodem](INFO): Starting
2019-03-14T19:42:31.298Z,1552592551.298 [Micromodem](INFO): Starting
2019-03-14T19:42:31.706Z,1552592551.706 [Micromodem](INFO): Starting
2019-03-14T19:42:32.110Z,1552592552.110 [Micromodem](INFO): Starting
2019-03-14T19:42:32.507Z,1552592552.507 [Micromodem](INFO): Starting
2019-03-14T19:42:32.914Z,1552592552.914 [Micromodem](INFO): Starting
2019-03-14T19:42:32.915Z,1552592552.915 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33

2019-03-14T19:42:32.915Z,1552592552.915 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:42:33.318Z,1552592553.318 [Micromodem](INFO): Starting
2019-03-14T19:42:33.724Z,1552592553.724 [Micromodem](INFO): Starting
2019-03-14T19:42:33.754Z,1552592553.754 [SBIT](IMPORTANT): SBIT PASSED
2019-03-14T19:42:33.825Z,1552592553.825 [CommandLine](IMPORTANT): got command configSet list
2019-03-14T19:42:33.825Z,1552592553.825 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-14T19:42:33.826Z,1552592553.826 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-14T19:42:33.826Z,1552592553.826 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-03-14T19:42:33.826Z,1552592553.826 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=30 count;

2019-03-14T19:42:34.126Z,1552592554.126 [Micromodem](INFO): Starting
2019-03-14T19:42:34.171Z,1552592554.171 [MissionManager](IMPORTANT): Started mission Startup
2019-03-14T19:42:34.172Z,1552592554.172 [Startup] Running Loop=1
2019-03-14T19:42:34.172Z,1552592554.172 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-14T19:42:34.172Z,1552592554.172 [Startup:A.GoToSurface] Running Loop=1
2019-03-14T19:42:34.172Z,1552592554.172 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T19:42:34.172Z,1552592554.172 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T19:42:34.173Z,1552592554.173 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T19:42:34.173Z,1552592554.173 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T19:42:34.174Z,1552592554.174 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T19:42:34.174Z,1552592554.174 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T19:42:34.176Z,1552592554.176 [Startup:StartupSatComms] Running Loop=1
2019-03-14T19:42:34.176Z,1552592554.176 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-14T19:42:34.176Z,1552592554.176 [Startup:StartupSatComms:A] Running Loop=1
2019-03-14T19:42:34.539Z,1552592554.539 [Micromodem](INFO): Starting
2019-03-14T19:42:34.575Z,1552592554.575 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-14T19:42:34.934Z,1552592554.934 [Micromodem](INFO): Starting
2019-03-14T19:42:35.338Z,1552592555.338 [Micromodem](INFO): Starting
2019-03-14T19:42:35.742Z,1552592555.742 [Micromodem](INFO): Starting
2019-03-14T19:42:36.146Z,1552592556.146 [Micromodem](INFO): Starting
2019-03-14T19:42:36.554Z,1552592556.554 [Micromodem](INFO): Starting
2019-03-14T19:42:36.956Z,1552592556.956 [Micromodem](INFO): Starting
2019-03-14T19:42:37.358Z,1552592557.358 [Micromodem](INFO): Starting
2019-03-14T19:42:37.766Z,1552592557.766 [Micromodem](INFO): Starting
2019-03-14T19:42:38.170Z,1552592558.170 [Micromodem](INFO): Starting
2019-03-14T19:42:38.567Z,1552592558.567 [Micromodem](INFO): Starting
2019-03-14T19:42:38.978Z,1552592558.978 [Micromodem](INFO): Starting
2019-03-14T19:42:39.378Z,1552592559.378 [Micromodem](INFO): Starting
2019-03-14T19:42:39.782Z,1552592559.782 [Micromodem](INFO): Starting
2019-03-14T19:42:40.190Z,1552592560.190 [Micromodem](INFO): Starting
2019-03-14T19:42:40.596Z,1552592560.596 [Micromodem](INFO): Starting
2019-03-14T19:42:40.998Z,1552592560.998 [Micromodem](INFO): Starting
2019-03-14T19:42:41.402Z,1552592561.402 [Micromodem](INFO): Starting
2019-03-14T19:42:41.807Z,1552592561.807 [Micromodem](INFO): Starting
2019-03-14T19:42:42.206Z,1552592562.206 [Micromodem](INFO): Starting
2019-03-14T19:42:42.611Z,1552592562.611 [Micromodem](INFO): Starting
2019-03-14T19:42:42.612Z,1552592562.612 [Micromodem](IMPORTANT): CACFG stsring$CACFG,nav.dt.txtrig_gpio4,1*66

2019-03-14T19:42:42.612Z,1552592562.612 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-03-14T19:42:43.014Z,1552592563.014 [Micromodem](INFO): Starting
2019-03-14T19:42:43.015Z,1552592563.015 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,194243,AUV,2.0.2760*1C

2019-03-14T19:42:43.418Z,1552592563.418 [Micromodem](INFO): Starting
2019-03-14T19:42:43.834Z,1552592563.834 [Micromodem](INFO): Starting
2019-03-14T19:42:43.835Z,1552592563.835 [Micromodem](IMPORTANT): CACFG stsring$CACFG,BND,1*39

2019-03-14T19:42:43.835Z,1552592563.835 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-14T19:42:44Z,0*76

2019-03-14T19:42:43.835Z,1552592563.835 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-14T19:42:44Z,0*76

2019-03-14T19:42:43.835Z,1552592563.835 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-14T19:42:44Z,0*76

2019-03-14T19:42:44.226Z,1552592564.226 [Micromodem](INFO): Starting
2019-03-14T19:42:44.227Z,1552592564.227 [Micromodem](ERROR): Response from modem unexpected: $CADQF,201,1*53

2019-03-14T19:42:44.630Z,1552592564.630 [Micromodem](INFO): Starting
2019-03-14T19:42:44.631Z,1552592564.631 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T19:42:45.038Z,1552592565.038 [Micromodem](INFO): Starting
2019-03-14T19:42:45.044Z,1552592565.044 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314194243.395162,05,480,14,0106,0150,246,00,00,02,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,201,-0.05,-999,9760,4000*7A

2019-03-14T19:42:45.438Z,1552592565.438 [Micromodem](INFO): Starting
2019-03-14T19:42:45.839Z,1552592565.839 [Micromodem](INFO): Starting
2019-03-14T19:42:46.250Z,1552592566.250 [Micromodem](INFO): Starting
2019-03-14T19:43:11.340Z,1552592591.340 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:43:11.340Z,1552592591.340 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-14T19:43:11.340Z,1552592591.340 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:43:11.342Z,1552592591.342 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:43:11.342Z,1552592591.342 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-14T19:43:11.342Z,1552592591.342 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:43:11.344Z,1552592591.344 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:43:11.344Z,1552592591.344 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-14T19:43:11.344Z,1552592591.344 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:43:11.355Z,1552592591.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:43:11.355Z,1552592591.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:43:11.355Z,1552592591.355 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:43:11.757Z,1552592591.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:43:11.757Z,1552592591.757 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-14T19:43:11.758Z,1552592591.758 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:43:11.758Z,1552592591.758 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-14T19:43:11.759Z,1552592591.759 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:43:11.759Z,1552592591.759 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-14T19:43:12.293Z,1552592592.293 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:43:34.351Z,1552592614.351 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-14T19:42:34.2Z
2019-03-14T19:43:34.351Z,1552592614.351 [Startup:StartupSatComms:A] Stopped
2019-03-14T19:43:34.351Z,1552592614.351 [Startup:StartupSatComms:B] Running Loop=1
2019-03-14T19:43:34.751Z,1552592614.751 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-14T19:43:37.490Z,1552592617.490 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004524
2019-03-14T19:43:42.692Z,1552592622.692 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190314T173012/Courier0043.lzma
2019-03-14T19:43:43.499Z,1552592623.499 [DataOverHttps](INFO): Moved sent file to Logs/20190314T173012/Courier0043.lzma.bak
2019-03-14T19:43:43.499Z,1552592623.499 [DataOverHttps](INFO): SBD MOMSN=10139060
2019-03-14T19:43:54.816Z,1552592634.816 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20190314T173012/Express0044.lzma
2019-03-14T19:43:55.614Z,1552592635.614 [DataOverHttps](INFO): Moved sent file to Logs/20190314T173012/Express0044.lzma.bak
2019-03-14T19:43:55.615Z,1552592635.615 [DataOverHttps](INFO): SBD MOMSN=10139062
2019-03-14T19:44:06.971Z,1552592646.971 [DataOverHttps](INFO): Sending 1091 bytes from file Logs/20190314T194100/Express0001.lzma
2019-03-14T19:44:07.774Z,1552592647.774 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Express0001.lzma.bak
2019-03-14T19:44:07.774Z,1552592647.774 [DataOverHttps](INFO): SBD MOMSN=10139069
2019-03-14T19:44:08.706Z,1552592648.706 [Startup:StartupSatComms:B] Stopped
2019-03-14T19:44:08.706Z,1552592648.706 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-14T19:44:08.706Z,1552592648.706 [Startup:StartupSatComms] Stopped
2019-03-14T19:44:08.707Z,1552592648.707 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-14T19:44:08.707Z,1552592648.707 [Startup](INFO): Completed Startup
2019-03-14T19:44:08.707Z,1552592648.707 [MissionManager](INFO): Startup is completed.
2019-03-14T19:44:08.708Z,1552592648.708 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-14T19:44:08.708Z,1552592648.708 [Startup] Stopped
2019-03-14T19:44:08.708Z,1552592648.708 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-14T19:44:08.708Z,1552592648.708 [Startup:A.GoToSurface] Stopped
2019-03-14T19:44:08.708Z,1552592648.708 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T19:44:09.118Z,1552592649.118 [MissionManager](IMPORTANT): Started mission Default
2019-03-14T19:44:09.118Z,1552592649.118 [Default] Running Loop=1
2019-03-14T19:44:09.118Z,1552592649.118 [Default](DEBUG): Aggregate::initialize Default
2019-03-14T19:44:09.118Z,1552592649.118 [Default:B.GoToSurface] Running Loop=1
2019-03-14T19:44:09.118Z,1552592649.118 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T19:44:09.119Z,1552592649.119 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T19:44:09.119Z,1552592649.119 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T19:44:09.119Z,1552592649.119 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T19:44:09.120Z,1552592649.120 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T19:44:09.120Z,1552592649.120 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T19:44:09.120Z,1552592649.120 [Default:A.Wait] Running Loop=1
2019-03-14T19:44:09.120Z,1552592649.120 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-14T19:44:22.443Z,1552592662.443 [Default:A.Wait](INFO): Done Waiting.
2019-03-14T19:44:22.443Z,1552592662.443 [Default:A.Wait] Stopped
2019-03-14T19:44:22.443Z,1552592662.443 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T19:44:22.854Z,1552592662.854 [Default:CheckIn] Running Loop=1
2019-03-14T19:44:22.854Z,1552592662.854 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T19:44:22.854Z,1552592662.854 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T19:44:23.247Z,1552592663.247 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-14T19:45:12.532Z,1552592712.532 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:45:12.532Z,1552592712.532 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-14T19:45:12.532Z,1552592712.532 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:45:12.534Z,1552592712.534 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:45:12.534Z,1552592712.534 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-14T19:45:12.534Z,1552592712.534 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:45:12.535Z,1552592712.535 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:45:12.535Z,1552592712.535 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-14T19:45:12.535Z,1552592712.535 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:45:12.546Z,1552592712.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:45:12.547Z,1552592712.547 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:45:12.547Z,1552592712.547 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:45:12.958Z,1552592712.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:45:12.958Z,1552592712.958 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-14T19:45:12.958Z,1552592712.958 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:45:12.958Z,1552592712.958 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-14T19:45:12.959Z,1552592712.959 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:45:12.959Z,1552592712.959 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-14T19:45:13.408Z,1552592713.408 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:45:45.223Z,1552592745.223 [Micromodem](FAULT): LCB 2 fault: 
2019-03-14T19:45:45.223Z,1552592745.223 [Micromodem] Hardware Fault, FailCount= 2
2019-03-14T19:45:45.223Z,1552592745.223 [Micromodem](ERROR): Hardware Fault
2019-03-14T19:45:45.223Z,1552592745.223 [Micromodem](INFO): Stop
2019-03-14T19:45:45.223Z,1552592745.223 [Micromodem](INFO): uninitialize
2019-03-14T19:45:45.223Z,1552592745.223 [Micromodem](INFO): Powering down
2019-03-14T19:45:45.366Z,1552592745.366 [CBIT](ERROR): Hardware Fault in component: Micromodem
2019-03-14T19:45:45.626Z,1552592745.626 [Micromodem](INFO): Stop
2019-03-14T19:45:45.626Z,1552592745.626 [Micromodem](INFO): uninitialize
2019-03-14T19:45:45.626Z,1552592745.626 [Micromodem](INFO): Powering down
2019-03-14T19:45:46.026Z,1552592746.026 [Micromodem](INFO): Stopping
2019-03-14T19:45:46.026Z,1552592746.026 [Micromodem](FAULT): LCB 2 fault:  Software Overcurrent.
2019-03-14T19:45:46.430Z,1552592746.430 [Micromodem](INFO): Stopped
2019-03-14T19:45:46.462Z,1552592746.462 [CBIT](INFO): Clearing failed state for component Micromodem
2019-03-14T19:45:46.462Z,1552592746.462 [Micromodem] No Fault, FailCount= 2
2019-03-14T19:45:46.840Z,1552592746.840 [Micromodem](INFO): Start
2019-03-14T19:45:47.238Z,1552592747.238 [Micromodem](INFO): Start
2019-03-14T19:45:47.646Z,1552592747.646 [Micromodem](INFO): Start
2019-03-14T19:45:48.050Z,1552592748.050 [Micromodem](INFO): Start
2019-03-14T19:45:48.450Z,1552592748.450 [Micromodem](INFO): Start
2019-03-14T19:45:48.854Z,1552592748.854 [Micromodem](INFO): Start
2019-03-14T19:45:49.258Z,1552592749.258 [Micromodem](INFO): Starting
2019-03-14T19:45:49.259Z,1552592749.259 [Micromodem](INFO): Powering up
2019-03-14T19:45:49.259Z,1552592749.259 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-14T19:45:49.662Z,1552592749.662 [Micromodem](INFO): Starting
2019-03-14T19:45:50.066Z,1552592750.066 [Micromodem](INFO): Starting
2019-03-14T19:45:50.470Z,1552592750.470 [Micromodem](INFO): Starting
2019-03-14T19:45:50.878Z,1552592750.878 [Micromodem](INFO): Starting
2019-03-14T19:45:51.278Z,1552592751.278 [Micromodem](INFO): Starting
2019-03-14T19:45:51.682Z,1552592751.682 [Micromodem](INFO): Starting
2019-03-14T19:45:52.086Z,1552592752.086 [Micromodem](INFO): Starting
2019-03-14T19:45:52.494Z,1552592752.494 [Micromodem](INFO): Starting
2019-03-14T19:45:52.898Z,1552592752.898 [Micromodem](INFO): Starting
2019-03-14T19:45:53.298Z,1552592753.298 [Micromodem](INFO): Starting
2019-03-14T19:45:53.708Z,1552592753.708 [Micromodem](INFO): Starting
2019-03-14T19:45:54.114Z,1552592754.114 [Micromodem](INFO): Starting
2019-03-14T19:45:54.114Z,1552592754.114 [Micromodem](INFO): Starting
2019-03-14T19:45:54.115Z,1552592754.115 [Micromodem](DEBUG): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T19:45:54.115Z,1552592754.115 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,ALL,0*33

2019-03-14T19:45:54.115Z,1552592754.115 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-03-14T19:45:54.116Z,1552592754.116 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T19:45:54.117Z,1552592754.117 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,SRC,1*31

2019-03-14T19:45:54.118Z,1552592754.118 [Micromodem](DEBUG): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:45:54.119Z,1552592754.119 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:45:54.120Z,1552592754.120 [Micromodem](DEBUG): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T19:45:54.132Z,1552592754.132 [Micromodem](IMPORTANT): Nmea buf: $CCCFG,BND,1*3B

2019-03-14T19:45:54.510Z,1552592754.510 [Micromodem](INFO): Starting
2019-03-14T19:45:54.918Z,1552592754.918 [Micromodem](INFO): Starting
2019-03-14T19:45:55.318Z,1552592755.318 [Micromodem](INFO): Starting
2019-03-14T19:45:55.722Z,1552592755.722 [Micromodem](INFO): Starting
2019-03-14T19:45:56.127Z,1552592756.127 [Micromodem](INFO): Starting
2019-03-14T19:45:56.535Z,1552592756.535 [Micromodem](INFO): Starting
2019-03-14T19:45:56.936Z,1552592756.936 [Micromodem](INFO): Starting
2019-03-14T19:45:57.338Z,1552592757.338 [Micromodem](INFO): Starting
2019-03-14T19:45:57.742Z,1552592757.742 [Micromodem](INFO): Starting
2019-03-14T19:45:58.150Z,1552592758.150 [Micromodem](INFO): Starting
2019-03-14T19:45:58.554Z,1552592758.554 [Micromodem](INFO): Starting
2019-03-14T19:45:58.954Z,1552592758.954 [Micromodem](INFO): Starting
2019-03-14T19:45:59.358Z,1552592759.358 [Micromodem](INFO): Starting
2019-03-14T19:45:59.759Z,1552592759.759 [Micromodem](INFO): Starting
2019-03-14T19:46:00.166Z,1552592760.166 [Micromodem](INFO): Starting
2019-03-14T19:46:00.570Z,1552592760.570 [Micromodem](INFO): Starting
2019-03-14T19:46:00.978Z,1552592760.978 [Micromodem](INFO): Starting
2019-03-14T19:46:01.378Z,1552592761.378 [Micromodem](INFO): Starting
2019-03-14T19:46:01.785Z,1552592761.785 [Micromodem](INFO): Starting
2019-03-14T19:46:02.186Z,1552592762.186 [Micromodem](INFO): Starting
2019-03-14T19:46:02.590Z,1552592762.590 [Micromodem](INFO): Starting
2019-03-14T19:46:02.995Z,1552592762.995 [Micromodem](INFO): Starting
2019-03-14T19:46:03.398Z,1552592763.398 [Micromodem](INFO): Starting
2019-03-14T19:46:03.813Z,1552592763.813 [Micromodem](INFO): Starting
2019-03-14T19:46:04.206Z,1552592764.206 [Micromodem](INFO): Starting
2019-03-14T19:46:04.611Z,1552592764.611 [Micromodem](INFO): Starting
2019-03-14T19:46:05.014Z,1552592765.014 [Micromodem](INFO): Starting
2019-03-14T19:46:05.418Z,1552592765.418 [Micromodem](INFO): Starting
2019-03-14T19:46:05.419Z,1552592765.419 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-14T19:46:05.832Z,1552592765.832 [Micromodem](INFO): Starting
2019-03-14T19:46:06.230Z,1552592766.230 [Micromodem](INFO): Starting
2019-03-14T19:46:06.630Z,1552592766.630 [Micromodem](INFO): Starting
2019-03-14T19:46:07.034Z,1552592767.034 [Micromodem](INFO): Starting
2019-03-14T19:46:07.440Z,1552592767.440 [Micromodem](INFO): Starting
2019-03-14T19:46:07.842Z,1552592767.842 [Micromodem](INFO): Starting
2019-03-14T19:46:08.246Z,1552592768.246 [Micromodem](INFO): Starting
2019-03-14T19:46:08.654Z,1552592768.654 [Micromodem](INFO): Starting
2019-03-14T19:46:09.058Z,1552592769.058 [Micromodem](INFO): Starting
2019-03-14T19:46:09.458Z,1552592769.458 [Micromodem](INFO): Starting
2019-03-14T19:46:09.862Z,1552592769.862 [Micromodem](INFO): Starting
2019-03-14T19:46:10.266Z,1552592770.266 [Micromodem](INFO): Starting
2019-03-14T19:46:10.670Z,1552592770.670 [Micromodem](INFO): Starting
2019-03-14T19:46:11.074Z,1552592771.074 [Micromodem](INFO): Starting
2019-03-14T19:46:11.478Z,1552592771.478 [Micromodem](INFO): Starting
2019-03-14T19:46:11.882Z,1552592771.882 [Micromodem](INFO): Starting
2019-03-14T19:46:12.286Z,1552592772.286 [Micromodem](INFO): Starting
2019-03-14T19:46:12.694Z,1552592772.694 [Micromodem](INFO): Starting
2019-03-14T19:46:13.091Z,1552592773.091 [Micromodem](INFO): Starting
2019-03-14T19:46:13.502Z,1552592773.502 [Micromodem](INFO): Starting
2019-03-14T19:46:13.902Z,1552592773.902 [Micromodem](INFO): Starting
2019-03-14T19:46:14.308Z,1552592774.308 [Micromodem](INFO): Starting
2019-03-14T19:46:14.715Z,1552592774.715 [Micromodem](INFO): Starting
2019-03-14T19:46:15.114Z,1552592775.114 [Micromodem](INFO): Starting
2019-03-14T19:46:15.522Z,1552592775.522 [Micromodem](INFO): Starting
2019-03-14T19:46:15.926Z,1552592775.926 [Micromodem](INFO): Starting
2019-03-14T19:46:16.326Z,1552592776.326 [Micromodem](INFO): Starting
2019-03-14T19:46:16.734Z,1552592776.734 [Micromodem](INFO): Starting
2019-03-14T19:46:16.734Z,1552592776.734 [Micromodem](ERROR): Nmea resend: $CCCFG,ALL,0*33

2019-03-14T19:46:17.142Z,1552592777.142 [Micromodem](INFO): Starting
2019-03-14T19:46:17.540Z,1552592777.540 [Micromodem](INFO): Starting
2019-03-14T19:46:17.946Z,1552592777.946 [Micromodem](INFO): Starting
2019-03-14T19:46:18.350Z,1552592778.350 [Micromodem](INFO): Starting
2019-03-14T19:46:18.754Z,1552592778.754 [Micromodem](INFO): Starting
2019-03-14T19:46:19.154Z,1552592779.154 [Micromodem](INFO): Starting
2019-03-14T19:46:19.562Z,1552592779.562 [Micromodem](INFO): Starting
2019-03-14T19:46:19.963Z,1552592779.963 [Micromodem](INFO): Starting
2019-03-14T19:46:19.964Z,1552592779.964 [Micromodem](IMPORTANT): CACFG stsring$CACFG,ALL,0*31

2019-03-14T19:46:19.964Z,1552592779.964 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-14T19:46:20.366Z,1552592780.366 [Micromodem](INFO): Starting
2019-03-14T19:46:20.776Z,1552592780.776 [Micromodem](INFO): Starting
2019-03-14T19:46:21.174Z,1552592781.174 [Micromodem](INFO): Starting
2019-03-14T19:46:21.578Z,1552592781.578 [Micromodem](INFO): Starting
2019-03-14T19:46:21.986Z,1552592781.986 [Micromodem](INFO): Starting
2019-03-14T19:46:22.386Z,1552592782.386 [Micromodem](INFO): Starting
2019-03-14T19:46:22.797Z,1552592782.797 [Micromodem](INFO): Starting
2019-03-14T19:46:23.194Z,1552592783.194 [Micromodem](INFO): Starting
2019-03-14T19:46:23.598Z,1552592783.598 [Micromodem](INFO): Starting
2019-03-14T19:46:23.999Z,1552592783.999 [Micromodem](INFO): Starting
2019-03-14T19:46:24.412Z,1552592784.412 [Micromodem](INFO): Starting
2019-03-14T19:46:24.810Z,1552592784.810 [Micromodem](INFO): Starting
2019-03-14T19:46:25.211Z,1552592785.211 [Micromodem](INFO): Starting
2019-03-14T19:46:25.618Z,1552592785.618 [Micromodem](INFO): Starting
2019-03-14T19:46:26.022Z,1552592786.022 [Micromodem](INFO): Starting
2019-03-14T19:46:26.426Z,1552592786.426 [Micromodem](INFO): Starting
2019-03-14T19:46:26.838Z,1552592786.838 [Micromodem](INFO): Starting
2019-03-14T19:46:27.234Z,1552592787.234 [Micromodem](INFO): Starting
2019-03-14T19:46:27.640Z,1552592787.640 [Micromodem](INFO): Starting
2019-03-14T19:46:28.042Z,1552592788.042 [Micromodem](INFO): Starting
2019-03-14T19:46:28.446Z,1552592788.446 [Micromodem](INFO): Starting
2019-03-14T19:46:28.854Z,1552592788.854 [Micromodem](INFO): Starting
2019-03-14T19:46:29.254Z,1552592789.254 [Micromodem](INFO): Starting
2019-03-14T19:46:29.658Z,1552592789.658 [Micromodem](INFO): Starting
2019-03-14T19:46:30.062Z,1552592790.062 [Micromodem](INFO): Starting
2019-03-14T19:46:30.063Z,1552592790.063 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,194619,COPROC,010.0464
$CACFG,SRC,1*33

2019-03-14T19:46:30.474Z,1552592790.474 [Micromodem](INFO): Starting
2019-03-14T19:46:30.885Z,1552592790.885 [Micromodem](INFO): Starting
2019-03-14T19:46:31.274Z,1552592791.274 [Micromodem](INFO): Starting
2019-03-14T19:46:31.274Z,1552592791.274 [Micromodem](ERROR): Nmea resend: $CCCFG,SRC,1*31

2019-03-14T19:46:31.682Z,1552592791.682 [Micromodem](INFO): Starting
2019-03-14T19:46:32.082Z,1552592792.082 [Micromodem](INFO): Starting
2019-03-14T19:46:32.486Z,1552592792.486 [Micromodem](INFO): Starting
2019-03-14T19:46:32.894Z,1552592792.894 [Micromodem](INFO): Starting
2019-03-14T19:46:33.294Z,1552592793.294 [Micromodem](INFO): Starting
2019-03-14T19:46:33.698Z,1552592793.698 [Micromodem](INFO): Starting
2019-03-14T19:46:34.106Z,1552592794.106 [Micromodem](INFO): Starting
2019-03-14T19:46:34.506Z,1552592794.506 [Micromodem](INFO): Starting
2019-03-14T19:46:34.910Z,1552592794.910 [Micromodem](INFO): Starting
2019-03-14T19:46:35.318Z,1552592795.318 [Micromodem](INFO): Starting
2019-03-14T19:46:35.722Z,1552592795.722 [Micromodem](INFO): Starting
2019-03-14T19:46:36.122Z,1552592796.122 [Micromodem](INFO): Starting
2019-03-14T19:46:36.526Z,1552592796.526 [Micromodem](INFO): Starting
2019-03-14T19:46:36.930Z,1552592796.930 [Micromodem](INFO): Starting
2019-03-14T19:46:37.334Z,1552592797.334 [Micromodem](INFO): Starting
2019-03-14T19:46:37.738Z,1552592797.738 [Micromodem](INFO): Starting
2019-03-14T19:46:38.144Z,1552592798.144 [Micromodem](INFO): Starting
2019-03-14T19:46:38.546Z,1552592798.546 [Micromodem](INFO): Starting
2019-03-14T19:46:38.950Z,1552592798.950 [Micromodem](INFO): Starting
2019-03-14T19:46:39.354Z,1552592799.354 [Micromodem](INFO): Starting
2019-03-14T19:46:39.759Z,1552592799.759 [Micromodem](INFO): Starting
2019-03-14T19:46:40.166Z,1552592800.166 [Micromodem](INFO): Starting
2019-03-14T19:46:40.167Z,1552592800.167 [Micromodem](IMPORTANT): CACFG stsring$CACFG,SRC,1*33

2019-03-14T19:46:40.167Z,1552592800.167 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-14T19:46:40.566Z,1552592800.566 [Micromodem](INFO): Starting
2019-03-14T19:46:40.970Z,1552592800.970 [Micromodem](INFO): Starting
2019-03-14T19:46:41.378Z,1552592801.378 [Micromodem](INFO): Starting
2019-03-14T19:46:41.789Z,1552592801.789 [Micromodem](INFO): Starting
2019-03-14T19:46:42.182Z,1552592802.182 [Micromodem](INFO): Starting
2019-03-14T19:46:42.590Z,1552592802.590 [Micromodem](INFO): Starting
2019-03-14T19:46:42.994Z,1552592802.994 [Micromodem](INFO): Starting
2019-03-14T19:46:43.394Z,1552592803.394 [Micromodem](INFO): Starting
2019-03-14T19:46:43.798Z,1552592803.798 [Micromodem](INFO): Starting
2019-03-14T19:46:44.206Z,1552592804.206 [Micromodem](INFO): Starting
2019-03-14T19:46:44.614Z,1552592804.614 [Micromodem](INFO): Starting
2019-03-14T19:46:45.012Z,1552592805.012 [Micromodem](INFO): Starting
2019-03-14T19:46:45.414Z,1552592805.414 [Micromodem](INFO): Starting
2019-03-14T19:46:45.825Z,1552592805.825 [Micromodem](INFO): Starting
2019-03-14T19:46:46.222Z,1552592806.222 [Micromodem](INFO): Starting
2019-03-14T19:46:46.626Z,1552592806.626 [Micromodem](INFO): Starting
2019-03-14T19:46:47.034Z,1552592807.034 [Micromodem](INFO): Starting
2019-03-14T19:46:47.438Z,1552592807.438 [Micromodem](INFO): Starting
2019-03-14T19:46:47.842Z,1552592807.842 [Micromodem](INFO): Starting
2019-03-14T19:46:48.242Z,1552592808.242 [Micromodem](INFO): Starting
2019-03-14T19:46:48.652Z,1552592808.652 [Micromodem](INFO): Starting
2019-03-14T19:46:49.050Z,1552592809.050 [Micromodem](INFO): Starting
2019-03-14T19:46:49.458Z,1552592809.458 [Micromodem](INFO): Starting
2019-03-14T19:46:49.859Z,1552592809.859 [Micromodem](INFO): Starting
2019-03-14T19:46:50.263Z,1552592810.263 [Micromodem](INFO): Starting
2019-03-14T19:46:50.264Z,1552592810.264 [Micromodem](IMPORTANT): CACFG stsring$CACFG,nav.dt.txtrig_gpio4,1*66

2019-03-14T19:46:50.264Z,1552592810.264 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-03-14T19:46:50.666Z,1552592810.666 [Micromodem](INFO): Starting
2019-03-14T19:46:51.074Z,1552592811.074 [Micromodem](INFO): Starting
2019-03-14T19:46:51.474Z,1552592811.474 [Micromodem](INFO): Starting
2019-03-14T19:46:51.880Z,1552592811.880 [Micromodem](INFO): Starting
2019-03-14T19:46:51.880Z,1552592811.880 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-14T19:46:52.286Z,1552592812.286 [Micromodem](INFO): Starting
2019-03-14T19:46:52.686Z,1552592812.686 [Micromodem](INFO): Starting
2019-03-14T19:46:52.701Z,1552592812.701 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:46:53.094Z,1552592813.094 [Micromodem](INFO): Starting
2019-03-14T19:46:53.494Z,1552592813.494 [Micromodem](INFO): Starting
2019-03-14T19:46:53.902Z,1552592813.902 [Micromodem](INFO): Starting
2019-03-14T19:46:54.302Z,1552592814.302 [Micromodem](INFO): Starting
2019-03-14T19:46:54.710Z,1552592814.710 [Micromodem](INFO): Starting
2019-03-14T19:46:54.711Z,1552592814.711 [Micromodem](IMPORTANT): CACFG stsring$CACFG,BND,1*39

2019-03-14T19:46:54.712Z,1552592814.712 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-14T19:46:55Z,0*72

2019-03-14T19:46:54.712Z,1552592814.712 [Micromodem](IMPORTANT): Nmea buf: $CCTMS,2019-03-14T19:46:55Z,0*72

2019-03-14T19:46:54.712Z,1552592814.712 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-14T19:46:55Z,0*72

2019-03-14T19:46:55.110Z,1552592815.110 [Micromodem](INFO): Starting
2019-03-14T19:46:55.111Z,1552592815.111 [Micromodem](ERROR): Response from modem unexpected: $CADQF,192,1*5A

2019-03-14T19:46:55.125Z,1552592815.125 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:46:55.514Z,1552592815.514 [Micromodem](INFO): Starting
2019-03-14T19:46:55.515Z,1552592815.515 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T19:46:55.922Z,1552592815.922 [Micromodem](INFO): Starting
2019-03-14T19:46:55.927Z,1552592815.927 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314194653.222178,05,145,15,0096,0150,246,00,00,01,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,192,-0.05,-999,9760,4000*76

2019-03-14T19:46:56.322Z,1552592816.322 [Micromodem](INFO): Starting
2019-03-14T19:46:56.730Z,1552592816.730 [Micromodem](INFO): Starting
2019-03-14T19:46:57.130Z,1552592817.130 [Micromodem](INFO): Starting
2019-03-14T19:46:57.953Z,1552592817.953 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:46:58.354Z,1552592818.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194232.00,A,3648.16098,N,12147.28457,W,0.797,0.00,140319,,,A*76
2019-03-14T19:46:58.357Z,1552592818.357 [NAL9602](INFO): GPS fix at 20190314T194232: (36.802683, -121.788076)
2019-03-14T19:46:58.403Z,1552592818.403 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T19:46:58.403Z,1552592818.403 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T19:46:58.804Z,1552592818.804 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-14T19:47:06.424Z,1552592826.424 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190314T194100/Courier0004.lzma
2019-03-14T19:47:07.230Z,1552592827.230 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Courier0004.lzma.bak
2019-03-14T19:47:07.231Z,1552592827.231 [DataOverHttps](INFO): SBD MOMSN=10139109
2019-03-14T19:47:13.850Z,1552592833.850 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:47:13.850Z,1552592833.850 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-14T19:47:13.850Z,1552592833.850 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:47:13.852Z,1552592833.852 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:47:13.852Z,1552592833.852 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-14T19:47:13.852Z,1552592833.852 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:47:13.855Z,1552592833.855 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:47:13.855Z,1552592833.855 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-14T19:47:13.855Z,1552592833.855 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:47:13.866Z,1552592833.866 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:47:13.866Z,1552592833.866 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:47:13.867Z,1552592833.867 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:47:14.272Z,1552592834.272 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:47:14.272Z,1552592834.272 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-14T19:47:14.281Z,1552592834.281 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:47:14.281Z,1552592834.281 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-14T19:47:14.282Z,1552592834.282 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:47:14.282Z,1552592834.282 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-14T19:47:14.592Z,1552592834.592 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:47:19.529Z,1552592839.529 [DataOverHttps](INFO): Sending 584 bytes from file Logs/20190314T194100/Express0005.lzma
2019-03-14T19:47:20.334Z,1552592840.334 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Express0005.lzma.bak
2019-03-14T19:47:20.334Z,1552592840.334 [DataOverHttps](INFO): SBD MOMSN=10139112
2019-03-14T19:47:21.456Z,1552592841.456 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T19:47:21.456Z,1552592841.456 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-14T19:47:21.456Z,1552592841.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T19:47:31.127Z,1552592851.127 [NAL9602](INFO): Powering down
2019-03-14T19:48:34.186Z,1552592914.186 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2019-03-14T19:49:14.987Z,1552592954.987 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:49:14.987Z,1552592954.987 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-14T19:49:14.987Z,1552592954.987 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:49:14.989Z,1552592954.989 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:49:14.989Z,1552592954.989 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-14T19:49:14.989Z,1552592954.989 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:49:14.991Z,1552592954.991 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:49:14.991Z,1552592954.991 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-14T19:49:14.991Z,1552592954.991 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:49:15.075Z,1552592955.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:49:15.075Z,1552592955.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:49:15.075Z,1552592955.075 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:49:15.386Z,1552592955.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:49:15.386Z,1552592955.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-14T19:49:15.387Z,1552592955.387 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:49:15.387Z,1552592955.387 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-14T19:49:15.387Z,1552592955.387 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:49:15.387Z,1552592955.387 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-14T19:49:15.777Z,1552592955.777 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:51:16.182Z,1552593076.182 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:51:16.182Z,1552593076.182 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-14T19:51:16.182Z,1552593076.182 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:51:16.184Z,1552593076.184 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:51:16.184Z,1552593076.184 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-14T19:51:16.184Z,1552593076.184 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:51:16.186Z,1552593076.186 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:51:16.186Z,1552593076.186 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-14T19:51:16.186Z,1552593076.186 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:51:16.199Z,1552593076.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:51:16.199Z,1552593076.199 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:51:16.199Z,1552593076.199 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:51:16.594Z,1552593076.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:51:16.594Z,1552593076.594 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-14T19:51:16.594Z,1552593076.594 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:51:16.594Z,1552593076.594 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-14T19:51:16.595Z,1552593076.595 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:51:16.595Z,1552593076.595 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-14T19:51:16.000Z,1552593077.000 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:52:22.039Z,1552593142.039 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-14T19:52:22.039Z,1552593142.039 [Default:CheckIn:C.Wait] Stopped
2019-03-14T19:52:22.039Z,1552593142.039 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T19:52:22.039Z,1552593142.039 [Default:CheckIn:D] Running Loop=1
2019-03-14T19:52:22.439Z,1552593142.439 [Default:CheckIn:D] Stopped
2019-03-14T19:52:22.439Z,1552593142.439 [Default:CheckIn:E] Running Loop=1
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.222019 min 
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn:E] Stopped
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn] Stopped
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn](INFO): Running loop #2
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn] Running Loop=2
2019-03-14T19:52:22.863Z,1552593142.863 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T19:52:22.864Z,1552593142.864 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T19:52:23.628Z,1552593143.628 [NAL9602](INFO): Powering up
2019-03-14T19:52:34.535Z,1552593154.535 [NAL9602](INFO): NAL9602 initialized
2019-03-14T19:52:35.353Z,1552593155.353 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:53:06.451Z,1552593186.451 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-03-14T19:53:06.525Z,1552593186.525 [NAL9602](FAULT): received: 
+CSQ:1

OK
2019-03-14T19:53:06.525Z,1552593186.525 [NAL9602] Data Fault, FailCount= 1
2019-03-14T19:53:06.525Z,1552593186.525 [NAL9602](ERROR): Data Fault
2019-03-14T19:53:06.628Z,1552593186.628 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T19:53:06.855Z,1552593186.855 [NAL9602](INFO): Powering down
2019-03-14T19:53:07.699Z,1552593187.699 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T19:53:07.699Z,1552593187.699 [NAL9602] No Fault, FailCount= 1
2019-03-14T19:53:17.373Z,1552593197.373 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:53:17.373Z,1552593197.373 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-14T19:53:17.373Z,1552593197.373 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:53:17.375Z,1552593197.375 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:53:17.375Z,1552593197.375 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-14T19:53:17.375Z,1552593197.375 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:53:17.377Z,1552593197.377 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:53:17.377Z,1552593197.377 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-03-14T19:53:17.377Z,1552593197.377 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:53:17.388Z,1552593197.388 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:53:17.388Z,1552593197.388 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:53:17.388Z,1552593197.388 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:53:17.818Z,1552593197.818 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:53:17.819Z,1552593197.819 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-14T19:53:17.819Z,1552593197.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:53:17.819Z,1552593197.819 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-14T19:53:17.820Z,1552593197.820 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:53:17.820Z,1552593197.820 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-03-14T19:53:18.195Z,1552593198.195 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:53:37.155Z,1552593217.155 [NAL9602](INFO): Powering up NAL9602
2019-03-14T19:53:48.063Z,1552593228.063 [NAL9602](INFO): NAL9602 initialized
2019-03-14T19:53:48.884Z,1552593228.884 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:55:11.692Z,1552593311.692 [NAL9602](INFO): SBD MO Status=2, MOMSN=2273, MT Status=2, MTMSN=0
2019-03-14T19:55:11.692Z,1552593311.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T19:55:18.580Z,1552593318.580 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:55:18.580Z,1552593318.580 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-03-14T19:55:18.580Z,1552593318.580 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:55:18.582Z,1552593318.582 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:55:18.582Z,1552593318.582 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-03-14T19:55:18.582Z,1552593318.582 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:55:18.584Z,1552593318.584 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:55:18.584Z,1552593318.584 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-03-14T19:55:18.584Z,1552593318.584 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:55:18.596Z,1552593318.596 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:55:18.596Z,1552593318.596 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:55:18.596Z,1552593318.596 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:55:19.004Z,1552593319.004 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:55:19.004Z,1552593319.004 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-03-14T19:55:19.005Z,1552593319.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:55:19.005Z,1552593319.005 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-03-14T19:55:19.006Z,1552593319.006 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:55:19.006Z,1552593319.006 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-03-14T19:55:19.392Z,1552593319.392 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:56:34.130Z,1552593394.130 [NAL9602](INFO): SBD MO Status=0, MOMSN=2273, MT Status=0, MTMSN=0
2019-03-14T19:56:34.130Z,1552593394.130 [NAL9602](INFO): No messages in MT queue
2019-03-14T19:56:34.929Z,1552593394.929 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:38.157Z,1552593398.157 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:40.989Z,1552593400.989 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:44.221Z,1552593404.221 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:47.045Z,1552593407.045 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:49.873Z,1552593409.873 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:53.105Z,1552593413.105 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:55.937Z,1552593415.937 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:56:59.165Z,1552593419.165 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:01.993Z,1552593421.993 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:05.225Z,1552593425.225 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:08.057Z,1552593428.057 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:10.885Z,1552593430.885 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:14.109Z,1552593434.109 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:14.719Z,1552593434.719 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.xCenter
2019-03-14T19:57:14.720Z,1552593434.720 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.range
2019-03-14T19:57:16.945Z,1552593436.945 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:17.791Z,1552593437.791 [RDI_Pathfinder](ERROR): only read 3 of 5 data items
2019-03-14T19:57:17.791Z,1552593437.791 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,32768,-32768,-32768,V
2019-03-14T19:57:19.534Z,1552593439.534 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-14T19:57:19.534Z,1552593439.534 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-14T19:57:19.560Z,1552593439.560 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-14T19:57:19.564Z,1552593439.564 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-14T19:57:19.568Z,1552593439.568 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-14T19:57:19.571Z,1552593439.571 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-14T19:57:19.575Z,1552593439.575 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-14T19:57:19.577Z,1552593439.577 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-14T19:57:19.583Z,1552593439.583 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-14T19:57:19.591Z,1552593439.591 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-14T19:57:19.595Z,1552593439.595 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

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

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

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

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


2019-03-14T19:57:19.597Z,1552593439.597 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-14T19:57:19.808Z,1552593439.808 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:57:19.808Z,1552593439.808 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-03-14T19:57:19.808Z,1552593439.808 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:57:19.814Z,1552593439.814 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:57:19.814Z,1552593439.814 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2019-03-14T19:57:19.814Z,1552593439.814 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:57:19.816Z,1552593439.816 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:57:19.816Z,1552593439.816 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6
2019-03-14T19:57:19.816Z,1552593439.816 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:57:19.822Z,1552593439.822 [Default] Stopped
2019-03-14T19:57:19.822Z,1552593439.822 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-14T19:57:19.822Z,1552593439.822 [Default:B.GoToSurface] Stopped
2019-03-14T19:57:19.822Z,1552593439.822 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T19:57:19.822Z,1552593439.822 [Default:CheckIn] Stopped
2019-03-14T19:57:19.822Z,1552593439.822 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T19:57:19.822Z,1552593439.822 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T19:57:19.822Z,1552593439.822 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL] Running Loop=1
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL:C] Running Loop=1
2019-03-14T19:57:19.823Z,1552593439.823 [DUSBL:RequestRepeater] Running Loop=1
2019-03-14T19:57:19.824Z,1552593439.824 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T19:57:19.824Z,1552593439.824 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T19:57:19.824Z,1552593439.824 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T19:57:19.824Z,1552593439.824 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T19:57:19.824Z,1552593439.824 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T19:57:19.824Z,1552593439.824 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T19:57:19.841Z,1552593439.841 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T19:57:19.842Z,1552593439.842 [DUSBL:C] Running Loop=1
2019-03-14T19:57:19.842Z,1552593439.842 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-14T19:57:19.843Z,1552593439.843 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-14T19:57:19.843Z,1552593439.843 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T19:57:19.843Z,1552593439.843 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T19:57:19.865Z,1552593439.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:57:19.865Z,1552593439.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:57:19.866Z,1552593439.866 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:57:20.173Z,1552593440.173 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:57:20.209Z,1552593440.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:57:20.209Z,1552593440.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-03-14T19:57:20.210Z,1552593440.210 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:57:20.210Z,1552593440.210 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2019-03-14T19:57:20.210Z,1552593440.210 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:57:20.210Z,1552593440.210 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6
2019-03-14T19:57:20.604Z,1552593440.604 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T19:57:21.375Z,1552593441.375 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:21.380Z,1552593441.380 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:21.381Z,1552593441.381 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:21.381Z,1552593441.381 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:21.381Z,1552593441.381 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:25.415Z,1552593445.415 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:25.417Z,1552593445.417 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:25.419Z,1552593445.419 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:25.419Z,1552593445.419 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:25.420Z,1552593445.420 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:26.636Z,1552593446.636 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CATRC,RECV,IDEM, 523,Missed start of FSK packet

2019-03-14T19:57:29.450Z,1552593449.450 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:29.451Z,1552593449.451 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:29.452Z,1552593449.452 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:29.452Z,1552593449.452 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:29.452Z,1552593449.452 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:29.896Z,1552593449.896 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T19:57:29.896Z,1552593449.896 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T19:57:29.896Z,1552593449.896 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T19:57:29.901Z,1552593449.901 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T19:57:29.901Z,1552593449.901 [DUSBL:RequestRepeater] Stopped
2019-03-14T19:57:29.901Z,1552593449.901 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T19:57:29.901Z,1552593449.901 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater] Running Loop=2
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T19:57:29.902Z,1552593449.902 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T19:57:29.920Z,1552593449.920 [Reporter](INFO): DUSBL_Hydroid.range -74957.281250 m
2019-03-14T19:57:33.490Z,1552593453.490 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:33.491Z,1552593453.491 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:33.491Z,1552593453.491 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:33.491Z,1552593453.491 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:33.491Z,1552593453.491 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:37.535Z,1552593457.535 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:37.537Z,1552593457.537 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:37.539Z,1552593457.539 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:37.539Z,1552593457.539 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:37.540Z,1552593457.540 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:40.392Z,1552593460.392 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T19:57:40.392Z,1552593460.392 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T19:57:40.392Z,1552593460.392 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T19:57:40.392Z,1552593460.392 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T19:57:40.393Z,1552593460.393 [DUSBL:RequestRepeater] Stopped
2019-03-14T19:57:40.393Z,1552593460.393 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T19:57:40.393Z,1552593460.393 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T19:57:40.393Z,1552593460.393 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T19:57:40.393Z,1552593460.393 [DUSBL:RequestRepeater](INFO): Running loop #3
2019-03-14T19:57:40.393Z,1552593460.393 [DUSBL:RequestRepeater] Running Loop=3
2019-03-14T19:57:40.393Z,1552593460.393 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T19:57:40.394Z,1552593460.394 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T19:57:40.394Z,1552593460.394 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T19:57:40.394Z,1552593460.394 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T19:57:40.394Z,1552593460.394 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T19:57:41.571Z,1552593461.571 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:41.575Z,1552593461.575 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:41.576Z,1552593461.576 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:41.576Z,1552593461.576 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:41.576Z,1552593461.576 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:45.611Z,1552593465.611 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:45.613Z,1552593465.613 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:45.615Z,1552593465.615 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:45.616Z,1552593465.616 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:45.616Z,1552593465.616 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:49.651Z,1552593469.651 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:49.653Z,1552593469.653 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:49.655Z,1552593469.655 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:49.655Z,1552593469.655 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:49.656Z,1552593469.656 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:50.904Z,1552593470.904 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T19:57:50.904Z,1552593470.904 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T19:57:50.904Z,1552593470.904 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T19:57:50.904Z,1552593470.904 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T19:57:50.905Z,1552593470.905 [DUSBL:RequestRepeater] Stopped
2019-03-14T19:57:50.905Z,1552593470.905 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T19:57:50.905Z,1552593470.905 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T19:57:50.905Z,1552593470.905 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T19:57:50.905Z,1552593470.905 [DUSBL:RequestRepeater](INFO): Running loop #4
2019-03-14T19:57:50.905Z,1552593470.905 [DUSBL:RequestRepeater] Running Loop=4
2019-03-14T19:57:50.905Z,1552593470.905 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T19:57:50.906Z,1552593470.906 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T19:57:50.906Z,1552593470.906 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T19:57:50.906Z,1552593470.906 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T19:57:50.906Z,1552593470.906 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T19:57:52.887Z,1552593472.887 [NAL9602](INFO): Powering down
2019-03-14T19:57:53.695Z,1552593473.695 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:53.698Z,1552593473.698 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:53.700Z,1552593473.700 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:53.700Z,1552593473.700 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:53.700Z,1552593473.700 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:57.736Z,1552593477.736 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T19:57:57.769Z,1552593477.769 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T19:57:57.769Z,1552593477.769 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:57.770Z,1552593477.770 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:57.770Z,1552593477.770 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T19:57:57.919Z,1552593477.919 [CommandLine](IMPORTANT): got command stop
2019-03-14T19:57:57.919Z,1552593477.919 [CommandLine](IMPORTANT): Scheduling is paused
2019-03-14T19:57:57.919Z,1552593477.919 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-03-14T19:57:58.143Z,1552593478.143 [Micromodem](ERROR): Response from modem unexpected: $CADQF,192,1*5A

2019-03-14T19:57:58.144Z,1552593478.144 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T19:57:58.199Z,1552593478.199 [MissionManager](INFO): MissionManager is completed.
2019-03-14T19:57:58.199Z,1552593478.199 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-14T19:57:58.199Z,1552593478.199 [DUSBL] Stopped
2019-03-14T19:57:58.199Z,1552593478.199 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-14T19:57:58.199Z,1552593478.199 [DUSBL:A.Pitch] Stopped
2019-03-14T19:57:58.199Z,1552593478.199 [DUSBL:B.SetSpeed] Stopped
2019-03-14T19:57:58.199Z,1552593478.199 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-14T19:57:58.199Z,1552593478.199 [DUSBL:C] Stopped
2019-03-14T19:57:58.200Z,1552593478.200 [DUSBL:RequestRepeater] Stopped
2019-03-14T19:57:58.200Z,1552593478.200 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T19:57:58.200Z,1552593478.200 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T19:57:58.200Z,1552593478.200 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T19:57:58.200Z,1552593478.200 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T19:57:58.200Z,1552593478.200 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T19:57:58.545Z,1552593478.545 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CACST,6,1,20190314195756.448925,06,447,14,0091,0150,246,00,00,02,01,0,-01,-01,3,201

2019-03-14T19:57:58.575Z,1552593478.575 [MissionManager](IMPORTANT): Started mission Default
2019-03-14T19:57:58.576Z,1552593478.576 [Default] Running Loop=1
2019-03-14T19:57:58.576Z,1552593478.576 [Default](DEBUG): Aggregate::initialize Default
2019-03-14T19:57:58.576Z,1552593478.576 [Default:B.GoToSurface] Running Loop=1
2019-03-14T19:57:58.576Z,1552593478.576 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T19:57:58.576Z,1552593478.576 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T19:57:58.576Z,1552593478.576 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T19:57:58.577Z,1552593478.577 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T19:57:58.577Z,1552593478.577 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T19:57:58.577Z,1552593478.577 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T19:57:58.578Z,1552593478.578 [Default:A.Wait] Running Loop=1
2019-03-14T19:57:58.578Z,1552593478.578 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-14T19:58:11.907Z,1552593491.907 [Default:A.Wait](INFO): Done Waiting.
2019-03-14T19:58:11.907Z,1552593491.907 [Default:A.Wait] Stopped
2019-03-14T19:58:11.907Z,1552593491.907 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T19:58:12.304Z,1552593492.304 [Default:CheckIn] Running Loop=1
2019-03-14T19:58:12.304Z,1552593492.304 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T19:58:12.304Z,1552593492.304 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T19:58:13.088Z,1552593493.088 [NAL9602](INFO): Powering up
2019-03-14T19:58:23.995Z,1552593503.995 [NAL9602](INFO): NAL9602 initialized
2019-03-14T19:58:24.813Z,1552593504.813 [NAL9602](DEBUG): Fix Requested
2019-03-14T19:59:05.609Z,1552593545.609 [NAL9602](INFO): SBD MO Status=2, MOMSN=2274, MT Status=2, MTMSN=0
2019-03-14T19:59:05.609Z,1552593545.609 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T19:59:20.980Z,1552593560.980 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:59:20.980Z,1552593560.980 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2019-03-14T19:59:20.981Z,1552593560.981 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T19:59:20.982Z,1552593560.982 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:59:20.983Z,1552593560.983 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2019-03-14T19:59:20.983Z,1552593560.983 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T19:59:20.984Z,1552593560.984 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T19:59:20.985Z,1552593560.985 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7
2019-03-14T19:59:20.985Z,1552593560.985 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T19:59:21.035Z,1552593561.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T19:59:21.035Z,1552593561.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T19:59:21.036Z,1552593561.036 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T19:59:21.395Z,1552593561.395 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T19:59:21.395Z,1552593561.395 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2019-03-14T19:59:21.396Z,1552593561.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T19:59:21.396Z,1552593561.396 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2019-03-14T19:59:21.396Z,1552593561.396 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T19:59:21.397Z,1552593561.397 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 7
2019-03-14T19:59:21.813Z,1552593561.813 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:00:04.202Z,1552593604.202 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-03-14T20:00:04.203Z,1552593604.203 [RDI_Pathfinder](ERROR): Failed to parse:
:RA, 96.59,  95.92,   0.00,  95.26
2019-03-14T20:01:22.189Z,1552593682.189 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:01:22.189Z,1552593682.189 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2019-03-14T20:01:22.189Z,1552593682.189 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:01:22.191Z,1552593682.191 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:01:22.191Z,1552593682.191 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2019-03-14T20:01:22.191Z,1552593682.191 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:01:22.193Z,1552593682.193 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:01:22.193Z,1552593682.193 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 8
2019-03-14T20:01:22.193Z,1552593682.193 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:01:22.204Z,1552593682.204 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:01:22.204Z,1552593682.204 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:01:22.204Z,1552593682.204 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:01:22.606Z,1552593682.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:01:22.606Z,1552593682.606 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2019-03-14T20:01:22.607Z,1552593682.607 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:01:22.607Z,1552593682.607 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2019-03-14T20:01:22.607Z,1552593682.607 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:01:22.607Z,1552593682.607 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 8
2019-03-14T20:01:23.002Z,1552593683.002 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:03:12.476Z,1552593792.476 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-14T19:58:12.3Z
2019-03-14T20:03:12.476Z,1552593792.476 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T20:03:12.476Z,1552593792.476 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T20:03:17.792Z,1552593797.792 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20190314T194100/Courier0007.lzma
2019-03-14T20:03:18.598Z,1552593798.598 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Courier0007.lzma.bak
2019-03-14T20:03:18.599Z,1552593798.599 [DataOverHttps](INFO): SBD MOMSN=10139164
2019-03-14T20:03:23.392Z,1552593803.392 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:03:23.392Z,1552593803.392 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2019-03-14T20:03:23.392Z,1552593803.392 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:03:23.394Z,1552593803.394 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:03:23.394Z,1552593803.394 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2019-03-14T20:03:23.394Z,1552593803.394 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:03:23.396Z,1552593803.396 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:03:23.396Z,1552593803.396 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 9
2019-03-14T20:03:23.396Z,1552593803.396 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:03:23.406Z,1552593803.406 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:03:23.407Z,1552593803.407 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:03:23.407Z,1552593803.407 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:03:23.806Z,1552593803.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:03:23.806Z,1552593803.806 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2019-03-14T20:03:23.806Z,1552593803.806 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:03:23.806Z,1552593803.806 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2019-03-14T20:03:23.807Z,1552593803.807 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:03:23.807Z,1552593803.807 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 9
2019-03-14T20:03:24.221Z,1552593804.221 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:03:26.596Z,1552593806.596 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-14T20:03:29.645Z,1552593809.645 [DataOverHttps](INFO): Sending 595 bytes from file Logs/20190314T194100/Express0008.lzma
2019-03-14T20:03:30.450Z,1552593810.450 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Express0008.lzma.bak
2019-03-14T20:03:30.450Z,1552593810.450 [DataOverHttps](INFO): SBD MOMSN=10139166
2019-03-14T20:03:31.468Z,1552593811.468 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T20:03:31.468Z,1552593811.468 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-14T20:03:31.468Z,1552593811.468 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:03:57.299Z,1552593837.299 [NAL9602](INFO): Powering down
2019-03-14T20:05:24.584Z,1552593924.584 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:05:24.584Z,1552593924.584 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2019-03-14T20:05:24.584Z,1552593924.584 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:05:24.586Z,1552593924.586 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:05:24.586Z,1552593924.586 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2019-03-14T20:05:24.586Z,1552593924.586 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:05:24.588Z,1552593924.588 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:05:24.588Z,1552593924.588 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 10
2019-03-14T20:05:24.588Z,1552593924.588 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:05:24.607Z,1552593924.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:05:24.607Z,1552593924.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:05:24.607Z,1552593924.607 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:05:25.002Z,1552593925.002 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:05:25.002Z,1552593925.002 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2019-03-14T20:05:25.003Z,1552593925.003 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:05:25.003Z,1552593925.003 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2019-03-14T20:05:25.003Z,1552593925.003 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:05:25.003Z,1552593925.003 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 10
2019-03-14T20:05:25.409Z,1552593925.409 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:07:25.802Z,1552594045.802 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:07:25.803Z,1552594045.803 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2019-03-14T20:07:25.803Z,1552594045.803 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:07:25.805Z,1552594045.805 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:07:25.805Z,1552594045.805 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2019-03-14T20:07:25.805Z,1552594045.805 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:07:25.807Z,1552594045.807 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:07:25.807Z,1552594045.807 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 11
2019-03-14T20:07:25.807Z,1552594045.807 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:07:25.817Z,1552594045.817 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:07:25.818Z,1552594045.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:07:25.818Z,1552594045.818 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:07:26.202Z,1552594046.202 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:07:26.202Z,1552594046.202 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2019-03-14T20:07:26.202Z,1552594046.202 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:07:26.202Z,1552594046.202 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2019-03-14T20:07:26.203Z,1552594046.203 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:07:26.203Z,1552594046.203 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 11
2019-03-14T20:07:26.596Z,1552594046.596 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:08:32.052Z,1552594112.052 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-14T20:08:32.052Z,1552594112.052 [Default:CheckIn:C.Wait] Stopped
2019-03-14T20:08:32.052Z,1552594112.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:08:32.052Z,1552594112.052 [Default:CheckIn:D] Running Loop=1
2019-03-14T20:08:32.462Z,1552594112.462 [Default:CheckIn:D] Stopped
2019-03-14T20:08:32.462Z,1552594112.462 [Default:CheckIn:E] Running Loop=1
2019-03-14T20:08:32.860Z,1552594112.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.564780 min 
2019-03-14T20:08:32.860Z,1552594112.860 [Default:CheckIn:E] Stopped
2019-03-14T20:08:32.860Z,1552594112.860 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-14T20:08:32.877Z,1552594112.877 [Default:CheckIn] Stopped
2019-03-14T20:08:32.877Z,1552594112.877 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T20:08:32.877Z,1552594112.877 [Default:CheckIn](INFO): Running loop #2
2019-03-14T20:08:32.877Z,1552594112.877 [Default:CheckIn] Running Loop=2
2019-03-14T20:08:32.877Z,1552594112.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T20:08:32.877Z,1552594112.877 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T20:08:33.635Z,1552594113.635 [NAL9602](INFO): Powering up
2019-03-14T20:08:44.548Z,1552594124.548 [NAL9602](INFO): NAL9602 initialized
2019-03-14T20:08:45.356Z,1552594125.356 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-14T20:08:45.356Z,1552594125.356 [NAL9602] Data Fault, FailCount= 1
2019-03-14T20:08:45.356Z,1552594125.356 [NAL9602](ERROR): Data Fault
2019-03-14T20:08:45.393Z,1552594125.393 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T20:08:45.759Z,1552594125.759 [NAL9602](INFO): Powering down
2019-03-14T20:08:46.598Z,1552594126.598 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T20:08:46.598Z,1552594126.598 [NAL9602] No Fault, FailCount= 1
2019-03-14T20:09:16.060Z,1552594156.060 [NAL9602](INFO): Powering up NAL9602
2019-03-14T20:09:26.965Z,1552594166.965 [NAL9602](INFO): NAL9602 initialized
2019-03-14T20:09:26.993Z,1552594166.993 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:09:26.993Z,1552594166.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2019-03-14T20:09:26.993Z,1552594166.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:09:26.996Z,1552594166.996 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:09:26.996Z,1552594166.996 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2019-03-14T20:09:26.996Z,1552594166.996 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:09:27.019Z,1552594167.019 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:09:27.019Z,1552594167.019 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 12
2019-03-14T20:09:27.019Z,1552594167.019 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:09:27.062Z,1552594167.062 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:09:27.062Z,1552594167.062 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:09:27.063Z,1552594167.063 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:09:27.442Z,1552594167.442 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:09:27.442Z,1552594167.442 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2019-03-14T20:09:27.443Z,1552594167.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:09:27.443Z,1552594167.443 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2019-03-14T20:09:27.443Z,1552594167.443 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:09:27.443Z,1552594167.443 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 12
2019-03-14T20:09:27.793Z,1552594167.793 [NAL9602](DEBUG): Fix Requested
2019-03-14T20:09:27.824Z,1552594167.824 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:11:28.193Z,1552594288.193 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:11:28.193Z,1552594288.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2019-03-14T20:11:28.193Z,1552594288.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:11:28.195Z,1552594288.195 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:11:28.195Z,1552594288.195 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2019-03-14T20:11:28.195Z,1552594288.195 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:11:28.197Z,1552594288.197 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:11:28.197Z,1552594288.197 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 13
2019-03-14T20:11:28.197Z,1552594288.197 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:11:28.208Z,1552594288.208 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:11:28.209Z,1552594288.209 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:11:28.209Z,1552594288.209 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:11:28.603Z,1552594288.603 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:11:28.604Z,1552594288.604 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2019-03-14T20:11:28.604Z,1552594288.604 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:11:28.604Z,1552594288.604 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2019-03-14T20:11:28.605Z,1552594288.605 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:11:28.605Z,1552594288.605 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 13
2019-03-14T20:11:29.010Z,1552594289.010 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:11:41.092Z,1552594301.092 [NAL9602](INFO): SBD MO Status=2, MOMSN=2274, MT Status=2, MTMSN=0
2019-03-14T20:11:41.092Z,1552594301.092 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T20:12:41.692Z,1552594361.692 [NAL9602](INFO): SBD MO Status=2, MOMSN=2274, MT Status=2, MTMSN=0
2019-03-14T20:12:41.692Z,1552594361.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T20:13:07.553Z,1552594387.553 [NAL9602](INFO): SBD MO Status=2, MOMSN=2274, MT Status=2, MTMSN=0
2019-03-14T20:13:07.553Z,1552594387.553 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T20:13:29.387Z,1552594409.387 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:13:29.387Z,1552594409.387 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2019-03-14T20:13:29.387Z,1552594409.387 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:13:29.389Z,1552594409.389 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:13:29.389Z,1552594409.389 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2019-03-14T20:13:29.389Z,1552594409.389 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:13:29.391Z,1552594409.391 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:13:29.391Z,1552594409.391 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 14
2019-03-14T20:13:29.391Z,1552594409.391 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:13:29.402Z,1552594409.402 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:13:29.402Z,1552594409.402 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:13:29.403Z,1552594409.403 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:13:29.811Z,1552594409.811 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:13:29.811Z,1552594409.811 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2019-03-14T20:13:29.811Z,1552594409.811 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:13:29.811Z,1552594409.811 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2019-03-14T20:13:29.812Z,1552594409.812 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:13:29.812Z,1552594409.812 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 14
2019-03-14T20:13:30.232Z,1552594410.232 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:13:33.044Z,1552594413.044 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-14T20:08:32.9Z
2019-03-14T20:13:33.044Z,1552594413.044 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T20:13:33.045Z,1552594413.045 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T20:13:38.212Z,1552594418.212 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190314T194100/Courier0010.lzma
2019-03-14T20:13:39.018Z,1552594419.018 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Courier0010.lzma.bak
2019-03-14T20:13:39.018Z,1552594419.018 [DataOverHttps](INFO): SBD MOMSN=10139229
2019-03-14T20:13:42.744Z,1552594422.744 [NAL9602](INFO): SBD MO Status=0, MOMSN=2274, MT Status=0, MTMSN=0
2019-03-14T20:13:42.744Z,1552594422.744 [NAL9602](INFO): No messages in MT queue
2019-03-14T20:13:50.101Z,1552594430.101 [DataOverHttps](INFO): Sending 346 bytes from file Logs/20190314T194100/Express0011.lzma
2019-03-14T20:13:50.906Z,1552594430.906 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Express0011.lzma.bak
2019-03-14T20:13:50.907Z,1552594430.907 [DataOverHttps](INFO): SBD MOMSN=10139231
2019-03-14T20:13:52.056Z,1552594432.056 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T20:13:52.056Z,1552594432.056 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-14T20:13:52.056Z,1552594432.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:14:13.443Z,1552594453.443 [NAL9602](INFO): Powering down
2019-03-14T20:15:30.627Z,1552594530.627 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:15:30.627Z,1552594530.627 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2019-03-14T20:15:30.627Z,1552594530.627 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:15:30.629Z,1552594530.629 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:15:30.629Z,1552594530.629 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2019-03-14T20:15:30.629Z,1552594530.629 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:15:30.631Z,1552594530.631 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:15:30.631Z,1552594530.631 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 15
2019-03-14T20:15:30.631Z,1552594530.631 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:15:30.642Z,1552594530.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:15:30.642Z,1552594530.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:15:30.642Z,1552594530.642 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:15:31.052Z,1552594531.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:15:31.052Z,1552594531.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2019-03-14T20:15:31.053Z,1552594531.053 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:15:31.053Z,1552594531.053 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2019-03-14T20:15:31.054Z,1552594531.054 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:15:31.054Z,1552594531.054 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 15
2019-03-14T20:15:31.444Z,1552594531.444 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:16:41.567Z,1552594601.567 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-14T20:16:41.567Z,1552594601.567 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-14T20:16:41.607Z,1552594601.607 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-14T20:16:41.610Z,1552594601.610 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-14T20:16:41.612Z,1552594601.612 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-14T20:16:41.614Z,1552594601.614 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-14T20:16:41.616Z,1552594601.616 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-14T20:16:41.618Z,1552594601.618 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-14T20:16:41.622Z,1552594601.622 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-14T20:16:41.631Z,1552594601.631 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-14T20:16:41.635Z,1552594601.635 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

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

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

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

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


2019-03-14T20:16:41.638Z,1552594601.638 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-14T20:16:41.784Z,1552594601.784 [Default] Stopped
2019-03-14T20:16:41.784Z,1552594601.784 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-14T20:16:41.784Z,1552594601.784 [Default:B.GoToSurface] Stopped
2019-03-14T20:16:41.784Z,1552594601.784 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T20:16:41.784Z,1552594601.784 [Default:CheckIn] Stopped
2019-03-14T20:16:41.784Z,1552594601.784 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T20:16:41.784Z,1552594601.784 [Default:CheckIn:C.Wait] Stopped
2019-03-14T20:16:41.784Z,1552594601.784 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:16:41.793Z,1552594601.793 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-14T20:16:41.793Z,1552594601.793 [DUSBL] Running Loop=1
2019-03-14T20:16:41.793Z,1552594601.793 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-14T20:16:41.793Z,1552594601.793 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T20:16:41.793Z,1552594601.793 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-14T20:16:41.794Z,1552594601.794 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T20:16:41.794Z,1552594601.794 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-14T20:16:41.794Z,1552594601.794 [DUSBL:C] Running Loop=1
2019-03-14T20:16:41.794Z,1552594601.794 [DUSBL:RequestRepeater] Running Loop=1
2019-03-14T20:16:41.794Z,1552594601.794 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:16:41.794Z,1552594601.794 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:16:41.794Z,1552594601.794 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:16:41.795Z,1552594601.795 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:16:41.795Z,1552594601.795 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:16:41.795Z,1552594601.795 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:16:41.795Z,1552594601.795 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:16:41.796Z,1552594601.796 [DUSBL:C] Running Loop=1
2019-03-14T20:16:41.796Z,1552594601.796 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-14T20:16:41.809Z,1552594601.809 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-14T20:16:41.809Z,1552594601.809 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T20:16:41.809Z,1552594601.809 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T20:16:43.327Z,1552594603.327 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:16:43.332Z,1552594603.332 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:16:43.333Z,1552594603.333 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:43.333Z,1552594603.333 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:43.333Z,1552594603.333 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:43.737Z,1552594603.737 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314201641.519354,06,197,16,0092,0150,246,00,00,01,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,204,-0.05,-999,9760,4000*7E

2019-03-14T20:16:47.371Z,1552594607.371 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:16:47.376Z,1552594607.376 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:16:47.376Z,1552594607.376 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:47.376Z,1552594607.376 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:47.376Z,1552594607.376 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:47.797Z,1552594607.797 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314201645.824451,06,77,12,0090,0150,246,00,00,01,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,195,0.05,-999,9760,4000*67

2019-03-14T20:16:47.887Z,1552594607.887 [Reporter](INFO): DUSBL_Hydroid.range -74957.820312 m
2019-03-14T20:16:51.407Z,1552594611.407 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:16:51.409Z,1552594611.409 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:16:51.411Z,1552594611.411 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:51.411Z,1552594611.411 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:51.411Z,1552594611.411 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:51.851Z,1552594611.851 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:16:51.852Z,1552594611.852 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:16:51.852Z,1552594611.852 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:16:51.852Z,1552594611.852 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:16:51.852Z,1552594611.852 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:16:51.852Z,1552594611.852 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:16:51.853Z,1552594611.853 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:16:51.853Z,1552594611.853 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:16:51.853Z,1552594611.853 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-14T20:16:51.853Z,1552594611.853 [DUSBL:RequestRepeater] Running Loop=2
2019-03-14T20:16:51.853Z,1552594611.853 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:16:51.853Z,1552594611.853 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:16:51.853Z,1552594611.853 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:16:51.854Z,1552594611.854 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:16:51.854Z,1552594611.854 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:16:55.446Z,1552594615.446 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:16:55.447Z,1552594615.447 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:16:55.447Z,1552594615.447 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:55.448Z,1552594615.448 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:55.448Z,1552594615.448 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:59.490Z,1552594619.490 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:16:59.495Z,1552594619.495 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:16:59.496Z,1552594619.496 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:59.496Z,1552594619.496 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:59.496Z,1552594619.496 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:16:59.891Z,1552594619.891 [Micromodem](ERROR): Response from modem unexpected: $CADQF,186,1*5F

2019-03-14T20:16:59.893Z,1552594619.893 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T20:17:00.300Z,1552594620.300 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314201658.153990,06,449,14,0099,0150,246,00,00,02,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,186,0.05,-999,9760,4000*56

2019-03-14T20:17:00.703Z,1552594620.703 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAR,159CORO,0.10.0.6*49

2019-03-14T20:17:02.348Z,1552594622.348 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:17:02.348Z,1552594622.348 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:17:02.348Z,1552594622.348 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:17:02.348Z,1552594622.348 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:17:02.349Z,1552594622.349 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:17:02.349Z,1552594622.349 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:17:02.349Z,1552594622.349 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:17:02.349Z,1552594622.349 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:17:02.349Z,1552594622.349 [DUSBL:RequestRepeater](INFO): Running loop #3
2019-03-14T20:17:02.349Z,1552594622.349 [DUSBL:RequestRepeater] Running Loop=3
2019-03-14T20:17:02.349Z,1552594622.349 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:17:02.350Z,1552594622.350 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:17:02.350Z,1552594622.350 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:17:02.350Z,1552594622.350 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:17:02.350Z,1552594622.350 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:17:03.531Z,1552594623.531 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:03.533Z,1552594623.533 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:03.535Z,1552594623.535 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:03.535Z,1552594623.535 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:03.536Z,1552594623.536 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:07.567Z,1552594627.567 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:07.571Z,1552594627.571 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:07.572Z,1552594627.572 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:07.572Z,1552594627.572 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:07.572Z,1552594627.572 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:11.611Z,1552594631.611 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:11.613Z,1552594631.613 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:11.615Z,1552594631.615 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:11.616Z,1552594631.616 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:11.616Z,1552594631.616 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:12.858Z,1552594632.858 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:17:12.858Z,1552594632.858 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:17:12.858Z,1552594632.858 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:17:12.859Z,1552594632.859 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:17:12.859Z,1552594632.859 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:17:12.859Z,1552594632.859 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:17:12.859Z,1552594632.859 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:17:12.859Z,1552594632.859 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:17:12.859Z,1552594632.859 [DUSBL:RequestRepeater](INFO): Running loop #4
2019-03-14T20:17:12.860Z,1552594632.860 [DUSBL:RequestRepeater] Running Loop=4
2019-03-14T20:17:12.860Z,1552594632.860 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:17:12.860Z,1552594632.860 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:17:12.860Z,1552594632.860 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:17:12.860Z,1552594632.860 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:17:12.860Z,1552594632.860 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:17:15.651Z,1552594635.651 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:15.653Z,1552594635.653 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:15.655Z,1552594635.655 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:15.655Z,1552594635.655 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:15.656Z,1552594635.656 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:19.687Z,1552594639.687 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:19.697Z,1552594639.697 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:19.698Z,1552594639.698 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:19.698Z,1552594639.698 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:19.698Z,1552594639.698 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:23.355Z,1552594643.355 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:17:23.356Z,1552594643.356 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:17:23.356Z,1552594643.356 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:17:23.356Z,1552594643.356 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater](INFO): Running loop #5
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater] Running Loop=5
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:17:23.357Z,1552594643.357 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:17:23.358Z,1552594643.358 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:17:23.358Z,1552594643.358 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:17:23.726Z,1552594643.726 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:23.727Z,1552594643.727 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:23.727Z,1552594643.727 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:23.727Z,1552594643.727 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:23.728Z,1552594643.728 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:27.773Z,1552594647.773 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:27.780Z,1552594647.780 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:27.781Z,1552594647.781 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:27.781Z,1552594647.781 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:27.782Z,1552594647.782 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:28.172Z,1552594648.172 [Micromodem](ERROR): Response from modem unexpected: $CADQF,193,1*5B

2019-03-14T20:17:28.173Z,1552594648.173 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T20:17:28.580Z,1552594648.580 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314201726.402455,06,102,14,0097,0150,246,00,00,01,01,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,193,0.00,-999,9760,4000*5D

2019-03-14T20:17:31.807Z,1552594651.807 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:31.807Z,1552594651.807 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:31.808Z,1552594651.808 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:31.808Z,1552594651.808 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:31.808Z,1552594651.808 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:31.912Z,1552594651.912 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:17:31.912Z,1552594651.912 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2019-03-14T20:17:31.912Z,1552594651.912 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:17:31.930Z,1552594651.930 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:17:31.930Z,1552594651.930 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2019-03-14T20:17:31.930Z,1552594651.930 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:17:31.932Z,1552594651.932 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:17:31.932Z,1552594651.932 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 16
2019-03-14T20:17:31.932Z,1552594651.932 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:17:31.970Z,1552594651.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:17:31.970Z,1552594651.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:17:31.971Z,1552594651.971 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:17:32.258Z,1552594652.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:17:32.258Z,1552594652.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2019-03-14T20:17:32.259Z,1552594652.259 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:17:32.259Z,1552594652.259 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2019-03-14T20:17:32.259Z,1552594652.259 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:17:32.259Z,1552594652.259 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 16
2019-03-14T20:17:32.636Z,1552594652.636 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:17:33.872Z,1552594653.872 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:17:33.872Z,1552594653.872 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:17:33.872Z,1552594653.872 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:17:33.872Z,1552594653.872 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:17:33.872Z,1552594653.872 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater](INFO): Running loop #6
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater] Running Loop=6
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:17:33.873Z,1552594653.873 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:17:33.874Z,1552594653.874 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:17:33.874Z,1552594653.874 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:17:35.851Z,1552594655.851 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:35.853Z,1552594655.853 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:35.855Z,1552594655.855 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:35.856Z,1552594655.856 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:35.856Z,1552594655.856 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:39.887Z,1552594659.887 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:39.889Z,1552594659.889 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:39.891Z,1552594659.891 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:39.891Z,1552594659.891 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:39.891Z,1552594659.891 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:43.928Z,1552594663.928 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:43.932Z,1552594663.932 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:43.933Z,1552594663.933 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:43.933Z,1552594663.933 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:43.934Z,1552594663.934 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:44.364Z,1552594664.364 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:17:44.364Z,1552594664.364 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:17:44.364Z,1552594664.364 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:17:44.365Z,1552594664.365 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:17:44.365Z,1552594664.365 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:17:44.365Z,1552594664.365 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:17:44.365Z,1552594664.365 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:17:44.365Z,1552594664.365 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:17:44.365Z,1552594664.365 [DUSBL:RequestRepeater](INFO): Running loop #7
2019-03-14T20:17:44.366Z,1552594664.366 [DUSBL:RequestRepeater] Running Loop=7
2019-03-14T20:17:44.366Z,1552594664.366 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:17:44.366Z,1552594664.366 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:17:44.366Z,1552594664.366 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:17:44.366Z,1552594664.366 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:17:44.366Z,1552594664.366 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:17:47.971Z,1552594667.971 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:47.973Z,1552594667.973 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:47.975Z,1552594667.975 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:47.976Z,1552594667.976 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:47.976Z,1552594667.976 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:52.011Z,1552594672.011 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:52.015Z,1552594672.015 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:52.016Z,1552594672.016 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:52.016Z,1552594672.016 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:52.016Z,1552594672.016 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:54.867Z,1552594674.867 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:17:54.868Z,1552594674.868 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:17:54.868Z,1552594674.868 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:17:54.868Z,1552594674.868 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater](INFO): Running loop #8
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater] Running Loop=8
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:17:54.869Z,1552594674.869 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:17:54.870Z,1552594674.870 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:17:56.047Z,1552594676.047 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:17:56.051Z,1552594676.051 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:17:56.052Z,1552594676.052 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:56.052Z,1552594676.052 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:56.053Z,1552594676.053 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:17:56.501Z,1552594676.501 [Reporter](INFO): DUSBL_Hydroid.range -74957.882812 m
2019-03-14T20:18:00.087Z,1552594680.087 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:18:00.089Z,1552594680.089 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:18:00.091Z,1552594680.091 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:00.091Z,1552594680.091 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:00.092Z,1552594680.092 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:04.131Z,1552594684.131 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:18:04.136Z,1552594684.136 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:18:04.136Z,1552594684.136 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:04.136Z,1552594684.136 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:04.137Z,1552594684.137 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:04.533Z,1552594684.533 [Micromodem](ERROR): Response from modem unexpected: $CADQF,200,1*52

2019-03-14T20:18:04.535Z,1552594684.535 [Micromodem](ERROR): Response from modem unexpected: $CAMSG,BAD_CRC,0*21

2019-03-14T20:18:04.961Z,1552594684.961 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314201802.764318,06,489,14,0092,0150,246,00,00,02,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,200,0.00,-999,9760,4000*53

2019-03-14T20:18:05.372Z,1552594685.372 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-14T20:18:05.372Z,1552594685.372 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:18:05.372Z,1552594685.372 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:18:05.373Z,1552594685.373 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-14T20:18:05.373Z,1552594685.373 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:18:05.373Z,1552594685.373 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:18:05.373Z,1552594685.373 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:18:05.373Z,1552594685.373 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:18:05.374Z,1552594685.374 [DUSBL:RequestRepeater](INFO): Running loop #9
2019-03-14T20:18:05.374Z,1552594685.374 [DUSBL:RequestRepeater] Running Loop=9
2019-03-14T20:18:05.374Z,1552594685.374 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:18:05.374Z,1552594685.374 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:18:05.374Z,1552594685.374 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:18:05.374Z,1552594685.374 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:18:05.374Z,1552594685.374 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:18:08.030Z,1552594688.030 [CommandLine](IMPORTANT): got command stop
2019-03-14T20:18:08.031Z,1552594688.031 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-03-14T20:18:08.166Z,1552594688.166 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:18:08.167Z,1552594688.167 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:18:08.168Z,1552594688.168 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:08.168Z,1552594688.168 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:08.168Z,1552594688.168 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:18:08.248Z,1552594688.248 [MissionManager](INFO): MissionManager is completed.
2019-03-14T20:18:08.248Z,1552594688.248 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-14T20:18:08.248Z,1552594688.248 [DUSBL] Stopped
2019-03-14T20:18:08.248Z,1552594688.248 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-14T20:18:08.248Z,1552594688.248 [DUSBL:A.Pitch] Stopped
2019-03-14T20:18:08.248Z,1552594688.248 [DUSBL:B.SetSpeed] Stopped
2019-03-14T20:18:08.248Z,1552594688.248 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-14T20:18:08.249Z,1552594688.249 [DUSBL:C] Stopped
2019-03-14T20:18:08.249Z,1552594688.249 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:18:08.249Z,1552594688.249 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:18:08.249Z,1552594688.249 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:18:08.249Z,1552594688.249 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:18:08.249Z,1552594688.249 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:18:08.249Z,1552594688.249 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:18:08.603Z,1552594688.603 [MissionManager](IMPORTANT): Started mission Default
2019-03-14T20:18:08.603Z,1552594688.603 [Default] Running Loop=1
2019-03-14T20:18:08.604Z,1552594688.604 [Default](DEBUG): Aggregate::initialize Default
2019-03-14T20:18:08.604Z,1552594688.604 [Default:B.GoToSurface] Running Loop=1
2019-03-14T20:18:08.604Z,1552594688.604 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T20:18:08.604Z,1552594688.604 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T20:18:08.604Z,1552594688.604 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T20:18:08.605Z,1552594688.605 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T20:18:08.605Z,1552594688.605 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T20:18:08.605Z,1552594688.605 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T20:18:08.606Z,1552594688.606 [Default:A.Wait] Running Loop=1
2019-03-14T20:18:08.606Z,1552594688.606 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:18:21.945Z,1552594701.945 [Default:A.Wait](INFO): Done Waiting.
2019-03-14T20:18:21.945Z,1552594701.945 [Default:A.Wait] Stopped
2019-03-14T20:18:21.945Z,1552594701.945 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:18:22.340Z,1552594702.340 [Default:CheckIn] Running Loop=1
2019-03-14T20:18:22.340Z,1552594702.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-14T20:18:22.340Z,1552594702.340 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-14T20:18:23.119Z,1552594703.119 [NAL9602](INFO): Powering up
2019-03-14T20:18:34.023Z,1552594714.023 [NAL9602](INFO): NAL9602 initialized
2019-03-14T20:18:34.845Z,1552594714.845 [NAL9602](DEBUG): Fix Requested
2019-03-14T20:19:33.061Z,1552594773.061 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:19:33.061Z,1552594773.061 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2019-03-14T20:19:33.061Z,1552594773.061 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:19:33.063Z,1552594773.063 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:19:33.063Z,1552594773.063 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2019-03-14T20:19:33.063Z,1552594773.063 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:19:33.065Z,1552594773.065 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:19:33.065Z,1552594773.065 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 17
2019-03-14T20:19:33.065Z,1552594773.065 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:19:33.076Z,1552594773.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:19:33.076Z,1552594773.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:19:33.077Z,1552594773.077 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:19:33.459Z,1552594773.459 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:19:33.459Z,1552594773.459 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2019-03-14T20:19:33.460Z,1552594773.460 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:19:33.460Z,1552594773.460 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2019-03-14T20:19:33.460Z,1552594773.460 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:19:33.460Z,1552594773.460 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 17
2019-03-14T20:19:33.860Z,1552594773.860 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:21:26.940Z,1552594886.940 [NAL9602](INFO): SBD MO Status=2, MOMSN=2275, MT Status=2, MTMSN=0
2019-03-14T20:21:26.940Z,1552594886.940 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T20:21:33.862Z,1552594893.862 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:21:33.862Z,1552594893.862 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2019-03-14T20:21:33.862Z,1552594893.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:21:33.864Z,1552594893.864 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:21:33.864Z,1552594893.864 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2019-03-14T20:21:33.864Z,1552594893.864 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:21:33.866Z,1552594893.866 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:21:33.866Z,1552594893.866 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 18
2019-03-14T20:21:33.866Z,1552594893.866 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:21:33.877Z,1552594893.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:21:33.877Z,1552594893.877 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:21:33.877Z,1552594893.877 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:21:34.262Z,1552594894.262 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:21:34.262Z,1552594894.262 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2019-03-14T20:21:34.263Z,1552594894.263 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:21:34.263Z,1552594894.263 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2019-03-14T20:21:34.263Z,1552594894.263 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:21:34.263Z,1552594894.263 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 18
2019-03-14T20:21:34.652Z,1552594894.652 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:21:53.606Z,1552594913.606 [NAL9602](INFO): SBD MO Status=2, MOMSN=2275, MT Status=2, MTMSN=0
2019-03-14T20:21:53.606Z,1552594913.606 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T20:22:46.202Z,1552594966.202 [CommandLine](IMPORTANT): got command show variable travelTime
2019-03-14T20:22:46.441Z,1552594966.441 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-14T20:22:54.111Z,1552594974.111 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.travelTime
2019-03-14T20:22:55.882Z,1552594975.882 [CommandLine](IMPORTANT): got command report
2019-03-14T20:22:55.883Z,1552594975.883 [Reporter](INFO): DUSBL_Hydroid.xCenter reporting when touched
2019-03-14T20:22:55.883Z,1552594975.883 [Reporter](INFO): DUSBL_Hydroid.range reporting when touched
2019-03-14T20:22:55.883Z,1552594975.883 [Reporter](INFO): DUSBL_Hydroid.travelTime reporting when touched
2019-03-14T20:23:03.355Z,1552594983.355 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-14T20:23:03.401Z,1552594983.401 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-14T20:23:03.402Z,1552594983.402 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-14T20:23:03.402Z,1552594983.402 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-14T20:23:03.403Z,1552594983.403 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-14T20:23:03.403Z,1552594983.403 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-14T20:23:03.403Z,1552594983.403 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-14T20:23:03.404Z,1552594983.404 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-14T20:23:03.404Z,1552594983.404 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-14T20:23:03.404Z,1552594983.404 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-14T20:23:03.405Z,1552594983.405 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-14T20:23:03.405Z,1552594983.405 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-14T20:23:03.405Z,1552594983.405 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-14T20:23:03.417Z,1552594983.417 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-14T20:23:03.417Z,1552594983.417 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-14T20:23:03.418Z,1552594983.418 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-14T20:23:03.421Z,1552594983.421 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-14T20:23:03.421Z,1552594983.421 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-14T20:23:03.422Z,1552594983.422 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-14T20:23:03.422Z,1552594983.422 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-14T20:23:03.422Z,1552594983.422 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-14T20:23:03.423Z,1552594983.423 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-14T20:23:03.423Z,1552594983.423 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-14T20:23:03.423Z,1552594983.423 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-14T20:23:03.424Z,1552594983.424 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-14T20:23:03.424Z,1552594983.424 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-14T20:23:03.451Z,1552594983.451 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_voltage (volt)
2019-03-14T20:23:03.451Z,1552594983.451 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgVoltage (volt)
2019-03-14T20:23:03.451Z,1552594983.451 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_current (milliampere)
2019-03-14T20:23:03.452Z,1552594983.452 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgCurrent (milliampere)
2019-03-14T20:23:11.580Z,1552594991.580 [NAL9602](INFO): SBD MO Status=2, MOMSN=2275, MT Status=2, MTMSN=0
2019-03-14T20:23:11.580Z,1552594991.580 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-14T20:23:16.950Z,1552594996.950 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.xAngle
2019-03-14T20:23:20.872Z,1552595000.872 [NAL9602](INFO): SBD MO Status=0, MOMSN=2275, MT Status=0, MTMSN=0
2019-03-14T20:23:20.872Z,1552595000.872 [NAL9602](INFO): No messages in MT queue
2019-03-14T20:23:21.675Z,1552595001.675 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-03-14T20:23:21.675Z,1552595001.675 [NAL9602] Data Fault, FailCount= 1
2019-03-14T20:23:21.675Z,1552595001.675 [NAL9602](ERROR): Data Fault
2019-03-14T20:23:21.714Z,1552595001.714 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-14T20:23:22.079Z,1552595002.079 [NAL9602](INFO): Powering down
2019-03-14T20:23:22.508Z,1552595002.508 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-14T20:18:22.3Z
2019-03-14T20:23:22.508Z,1552595002.508 [Default:CheckIn:Read_GPS] Stopped
2019-03-14T20:23:22.508Z,1552595002.508 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-14T20:23:22.969Z,1552595002.969 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-14T20:23:22.969Z,1552595002.969 [NAL9602] No Fault, FailCount= 1
2019-03-14T20:23:23.529Z,1552595003.529 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.xAngle
2019-03-14T20:23:23.530Z,1552595003.530 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle -4.300000 arcdeg
2019-03-14T20:23:25.721Z,1552595005.721 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190314T194100/Courier0013.lzma
2019-03-14T20:23:26.334Z,1552595006.334 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Courier0013.lzma.bak
2019-03-14T20:23:26.334Z,1552595006.334 [DataOverHttps](INFO): SBD MOMSN=10139256
2019-03-14T20:23:34.439Z,1552595014.439 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-14T20:23:34.439Z,1552595014.439 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-14T20:23:34.491Z,1552595014.491 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-14T20:23:34.498Z,1552595014.498 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-14T20:23:34.500Z,1552595014.500 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-14T20:23:34.507Z,1552595014.507 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-14T20:23:34.513Z,1552595014.513 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-14T20:23:34.514Z,1552595014.514 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-14T20:23:34.523Z,1552595014.523 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-14T20:23:34.539Z,1552595014.539 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-14T20:23:34.547Z,1552595014.547 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

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

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

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

    <Timeout Duration="MissionTimeout"/>

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

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

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

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


2019-03-14T20:23:34.591Z,1552595014.591 [Default] Stopped
2019-03-14T20:23:34.591Z,1552595014.591 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-14T20:23:34.592Z,1552595014.592 [Default:B.GoToSurface] Stopped
2019-03-14T20:23:34.592Z,1552595014.592 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T20:23:34.592Z,1552595014.592 [Default:CheckIn] Stopped
2019-03-14T20:23:34.592Z,1552595014.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-14T20:23:34.592Z,1552595014.592 [Default:CheckIn:Read_Iridium] Stopped
2019-03-14T20:23:34.592Z,1552595014.592 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-14T20:23:34.592Z,1552595014.592 [DUSBL] Running Loop=1
2019-03-14T20:23:34.592Z,1552595014.592 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:C] Running Loop=1
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:RequestRepeater] Running Loop=1
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:23:34.593Z,1552595014.593 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:23:34.594Z,1552595014.594 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-14T20:23:34.594Z,1552595014.594 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:23:34.594Z,1552595014.594 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-14T20:23:34.594Z,1552595014.594 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-14T20:23:34.594Z,1552595014.594 [DUSBL:C] Running Loop=1
2019-03-14T20:23:34.595Z,1552595014.595 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-14T20:23:34.595Z,1552595014.595 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-14T20:23:34.596Z,1552595014.596 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-14T20:23:34.596Z,1552595014.596 [DUSBL:A.Pitch] Running Loop=1
2019-03-14T20:23:34.605Z,1552595014.605 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-14T20:23:35.007Z,1552595015.007 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:23:35.007Z,1552595015.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2019-03-14T20:23:35.007Z,1552595015.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-14T20:23:35.014Z,1552595015.014 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:23:35.014Z,1552595015.014 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2019-03-14T20:23:35.014Z,1552595015.014 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-14T20:23:35.015Z,1552595015.015 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-14T20:23:35.016Z,1552595015.016 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 19
2019-03-14T20:23:35.016Z,1552595015.016 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-14T20:23:35.043Z,1552595015.043 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-14T20:23:35.043Z,1552595015.043 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-14T20:23:35.043Z,1552595015.043 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-14T20:23:35.422Z,1552595015.422 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-14T20:23:35.422Z,1552595015.422 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2019-03-14T20:23:35.423Z,1552595015.423 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-14T20:23:35.423Z,1552595015.423 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2019-03-14T20:23:35.423Z,1552595015.423 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-14T20:23:35.423Z,1552595015.423 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 19
2019-03-14T20:23:35.809Z,1552595015.809 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-14T20:23:36.190Z,1552595016.190 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:23:36.195Z,1552595016.195 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:23:36.195Z,1552595016.195 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:23:36.195Z,1552595016.195 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:23:36.196Z,1552595016.196 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:23:36.340Z,1552595016.340 [Reporter](INFO): DUSBL_Hydroid.range -74957.882812 m
2019-03-14T20:23:36.349Z,1552595016.349 [Reporter](INFO): DUSBL_Hydroid.travelTime 56160.000000 us
2019-03-14T20:23:36.350Z,1552595016.350 [Reporter](INFO): DUSBL_Hydroid.xAngle 60.849998 arcdeg
2019-03-14T20:23:36.596Z,1552595016.596 [Micromodem](ERROR): Response from modem unexpected: $CACST,6,1,20190314202334.451382,06,370,13,0092,0150,246,00,00,02,02,0,-01,-01,3,2,1,1,-999,-99.9,-9.99,-999,-9.99,193,-0.05,-999,9760,4000*7F

2019-03-14T20:23:36.679Z,1552595016.679 [Reporter](INFO): DUSBL_Hydroid.range -74957.882812 m
2019-03-14T20:23:36.680Z,1552595016.680 [Reporter](INFO): DUSBL_Hydroid.travelTime 56160.000000 us
2019-03-14T20:23:36.680Z,1552595016.680 [Reporter](INFO): DUSBL_Hydroid.xAngle -3.200000 arcdeg
2019-03-14T20:23:38.542Z,1552595018.542 [DataOverHttps](INFO): Sending 985 bytes from file Logs/20190314T194100/Express0014.lzma
2019-03-14T20:23:39.346Z,1552595019.346 [DataOverHttps](INFO): Moved sent file to Logs/20190314T194100/Express0014.lzma.bak
2019-03-14T20:23:39.346Z,1552595019.346 [DataOverHttps](INFO): SBD MOMSN=10139258
2019-03-14T20:23:40.227Z,1552595020.227 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-14T20:23:40.229Z,1552595020.229 [Micromodem](INFO): **** IS COMMANDED  IN Runnable **** 
2019-03-14T20:23:40.231Z,1552595020.231 [Micromodem](DEBUG): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:23:40.232Z,1552595020.232 [Micromodem](IMPORTANT): Nmea buf: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:23:40.232Z,1552595020.232 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-03-14T20:23:42.634Z,1552595022.634 [CommandLine](IMPORTANT): got command stop
2019-03-14T20:23:42.634Z,1552595022.634 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-03-14T20:23:42.742Z,1552595022.742 [MissionManager](INFO): MissionManager is completed.
2019-03-14T20:23:42.742Z,1552595022.742 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:A.Pitch] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:B.SetSpeed] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:C] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:RequestRepeater] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:RequestRepeater:A] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:RequestRepeater:B] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-14T20:23:42.743Z,1552595022.743 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:23:43.112Z,1552595023.112 [MissionManager](IMPORTANT): Started mission Default
2019-03-14T20:23:43.112Z,1552595023.112 [Default] Running Loop=1
2019-03-14T20:23:43.112Z,1552595023.112 [Default](DEBUG): Aggregate::initialize Default
2019-03-14T20:23:43.112Z,1552595023.112 [Default:B.GoToSurface] Running Loop=1
2019-03-14T20:23:43.112Z,1552595023.112 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-14T20:23:43.112Z,1552595023.112 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-14T20:23:43.121Z,1552595023.121 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-14T20:23:43.121Z,1552595023.121 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-14T20:23:43.122Z,1552595023.122 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-14T20:23:43.122Z,1552595023.122 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-14T20:23:43.122Z,1552595023.122 [Default:A.Wait] Running Loop=1
2019-03-14T20:23:43.122Z,1552595023.122 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-14T20:23:46.778Z,1552595026.778 [CommandLine](IMPORTANT): got command restart application
2019-03-14T20:23:47.785Z,1552595027.785 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:47.786Z,1552595027.786 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:47.841Z,1552595027.841 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-14T20:23:47.841Z,1552595027.841 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:47.842Z,1552595027.842 [CommandLine](INFO): Join timeout helper Thread ID is 8835
2019-03-14T20:23:47.842Z,1552595027.842 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-14T20:23:47.842Z,1552595027.842 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:47.843Z,1552595027.843 [NavChartDb](INFO): Join timeout helper Thread ID is 8836
2019-03-14T20:23:48.105Z,1552595028.105 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:48.105Z,1552595028.105 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.113Z,1552595028.113 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-14T20:23:48.113Z,1552595028.113 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.113Z,1552595028.113 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8837
2019-03-14T20:23:48.481Z,1552595028.481 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:48.481Z,1552595028.481 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-14T20:23:48.482Z,1552595028.482 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.493Z,1552595028.493 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-14T20:23:48.493Z,1552595028.493 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.493Z,1552595028.493 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8838
2019-03-14T20:23:48.589Z,1552595028.589 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:48.589Z,1552595028.589 [CTD_NeilBrown](INFO): Powering down
2019-03-14T20:23:48.601Z,1552595028.601 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.613Z,1552595028.613 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-14T20:23:48.613Z,1552595028.613 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.613Z,1552595028.613 [Radio_Surface](INFO): Join timeout helper Thread ID is 8839
2019-03-14T20:23:48.893Z,1552595028.893 [Radio_Surface](INFO): Powering down
2019-03-14T20:23:48.894Z,1552595028.894 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:48.894Z,1552595028.894 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.902Z,1552595028.902 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-14T20:23:48.902Z,1552595028.902 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:48.902Z,1552595028.902 [DataOverHttps](INFO): Join timeout helper Thread ID is 8840
2019-03-14T20:23:49.045Z,1552595029.045 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:49.045Z,1552595029.045 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.062Z,1552595029.062 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-14T20:23:49.062Z,1552595029.062 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.062Z,1552595029.062 [logger](INFO): Join timeout helper Thread ID is 8841
2019-03-14T20:23:49.141Z,1552595029.141 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:49.141Z,1552595029.141 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.142Z,1552595029.142 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-14T20:23:49.142Z,1552595029.142 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.143Z,1552595029.143 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-14T20:23:49.143Z,1552595029.143 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.143Z,1552595029.143 [controlThread](INFO): Join timeout helper Thread ID is 8842
2019-03-14T20:23:49.165Z,1552595029.165 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-14T20:23:49.165Z,1552595029.165 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-14T20:23:49.166Z,1552595029.166 [DUSBL_Hydroid](INFO): Powering down
2019-03-14T20:23:49.237Z,1552595029.237 [Micromodem](INFO): uninitialize
2019-03-14T20:23:49.237Z,1552595029.237 [Micromodem](INFO): Powering down
2019-03-14T20:23:49.333Z,1552595029.333 [NAL9602](INFO): Powering down
2019-03-14T20:23:49.405Z,1552595029.405 [RDI_Pathfinder](INFO): Powering down
2019-03-14T20:23:49.406Z,1552595029.406 [RDI_PathfinderUp](INFO): Powering down
2019-03-14T20:23:49.407Z,1552595029.407 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-14T20:23:49.408Z,1552595029.408 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-14T20:23:49.409Z,1552595029.409 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-14T20:23:49.409Z,1552595029.409 [MissionManager](INFO): Uninitializing Mission Default
2019-03-14T20:23:49.410Z,1552595029.410 [Default] Stopped
2019-03-14T20:23:49.410Z,1552595029.410 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-14T20:23:49.410Z,1552595029.410 [Default:A.Wait] Stopped
2019-03-14T20:23:49.410Z,1552595029.410 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-14T20:23:49.410Z,1552595029.410 [Default:B.GoToSurface] Stopped
2019-03-14T20:23:49.410Z,1552595029.410 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-14T20:23:49.412Z,1552595029.412 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-14T20:23:49.412Z,1552595029.412 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-14T20:23:49.413Z,1552595029.413 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-14T20:23:49.413Z,1552595029.413 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-14T20:23:49.413Z,1552595029.413 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-14T20:23:49.414Z,1552595029.414 [BuoyancyServo](INFO): Powering down
2019-03-14T20:23:49.425Z,1552595029.425 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-14T20:23:49.425Z,1552595029.425 [ElevatorServo](INFO): Powering down
2019-03-14T20:23:49.426Z,1552595029.426 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-14T20:23:49.426Z,1552595029.426 [MassServo](INFO): Powering down
2019-03-14T20:23:49.427Z,1552595029.427 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-14T20:23:49.427Z,1552595029.427 [RudderServo](INFO): Powering down
2019-03-14T20:23:49.427Z,1552595029.427 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-14T20:23:49.428Z,1552595029.428 [ThrusterServo](INFO): Powering down
2019-03-14T20:23:49.428Z,1552595029.428 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-14T20:23:49.428Z,1552595029.428 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-14T20:23:49.429Z,1552595029.429 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-14T20:23:49.429Z,1552595029.429 [CBIT](DEBUG): Powering off loads.
2019-03-14T20:23:49.441Z,1552595029.441 [CBIT](DEBUG): Disabling WDT.
2019-03-14T20:23:49.453Z,1552595029.453 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-14T20:23:49.454Z,1552595029.454 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.518Z,1552595029.518 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.527Z,1552595029.527 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.570Z,1552595029.570 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.572Z,1552595029.572 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.625Z,1552595029.625 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-14T20:23:49.696Z,1552595029.696 [logger ThreadHandler](INFO): Thread cancelled.