2019-03-06T21:13:57.531Z,1551906837.531 [Supervisor](DEBUG): Initializing supervisor.
2019-03-06T21:13:57.534Z,1551906837.534 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-06T21:13:57.535Z,1551906837.535 [SyncHandler](INFO): Protected caller Thread ID is 8553
2019-03-06T21:13:57.535Z,1551906837.535 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-06T21:13:57.536Z,1551906837.536 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-06T21:13:57.537Z,1551906837.537 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8554
2019-03-06T21:13:57.540Z,1551906837.540 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-06T21:13:57.552Z,1551906837.552 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-06T21:13:57.553Z,1551906837.553 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-06T21:13:57.553Z,1551906837.553 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8555
2019-03-06T21:13:57.554Z,1551906837.554 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-06T21:13:57.555Z,1551906837.555 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-06T21:13:57.556Z,1551906837.556 [logger ThreadHandler](INFO): Protected caller Thread ID is 8556
2019-03-06T21:13:57.558Z,1551906837.558 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-06T21:13:57.558Z,1551906837.558 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-06T21:13:57.560Z,1551906837.560 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-06T21:13:57.998Z,1551906837.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-06T21:13:57.999Z,1551906837.999 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-06T21:13:58.096Z,1551906838.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-06T21:13:58.097Z,1551906838.097 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-06T21:13:58.436Z,1551906838.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-06T21:13:58.436Z,1551906838.436 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-06T21:13:58.581Z,1551906838.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-06T21:13:58.582Z,1551906838.582 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-06T21:13:58.779Z,1551906838.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-06T21:13:58.780Z,1551906838.780 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-06T21:13:59.258Z,1551906839.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-06T21:13:59.258Z,1551906839.258 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-06T21:13:59.558Z,1551906839.558 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-06T21:13:59.559Z,1551906839.559 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-06T21:13:59.707Z,1551906839.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-06T21:13:59.707Z,1551906839.707 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-06T21:13:59.908Z,1551906839.908 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-06T21:13:59.909Z,1551906839.909 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-06T21:14:00.005Z,1551906840.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-06T21:14:00.005Z,1551906840.005 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-06T21:14:00.324Z,1551906840.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-06T21:14:00.325Z,1551906840.325 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-06T21:14:00.404Z,1551906840.404 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-06T21:14:00.507Z,1551906840.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-06T21:14:00.508Z,1551906840.508 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-06T21:14:01.021Z,1551906841.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-06T21:14:01.022Z,1551906841.022 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-06T21:14:01.427Z,1551906841.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-06T21:14:01.429Z,1551906841.429 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-06T21:14:01.430Z,1551906841.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-06T21:14:01.644Z,1551906841.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-06T21:14:01.745Z,1551906841.745 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-06T21:14:01.844Z,1551906841.844 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-06T21:14:02.075Z,1551906842.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-06T21:14:02.076Z,1551906842.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-06T21:14:02.160Z,1551906842.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-06T21:14:02.320Z,1551906842.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-06T21:14:02.589Z,1551906842.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-06T21:14:02.724Z,1551906842.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-06T21:14:03.040Z,1551906843.040 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-06T21:14:03.426Z,1551906843.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-06T21:14:03.565Z,1551906843.565 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-06T21:14:03.566Z,1551906843.566 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-06T21:14:03.567Z,1551906843.567 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-06T21:14:04.000Z,1551906844.000 [AHRS_M2] Loaded
2019-03-06T21:14:04.001Z,1551906844.001 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-03-06T21:14:04.076Z,1551906844.076 [DataOverHttps] Loaded
2019-03-06T21:14:04.077Z,1551906844.077 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-06T21:14:04.078Z,1551906844.078 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-06T21:14:04.078Z,1551906844.078 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8636
2019-03-06T21:14:04.092Z,1551906844.092 [Depth_Keller] Loaded
2019-03-06T21:14:04.092Z,1551906844.092 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-06T21:14:04.097Z,1551906844.097 [DropWeight] Loaded
2019-03-06T21:14:04.097Z,1551906844.097 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-06T21:14:04.146Z,1551906844.146 [DUSBL_Hydroid] Loaded
2019-03-06T21:14:04.146Z,1551906844.146 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-06T21:14:04.187Z,1551906844.187 [Micromodem] Loaded
2019-03-06T21:14:04.187Z,1551906844.187 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-06T21:14:04.287Z,1551906844.287 [NAL9602] Loaded
2019-03-06T21:14:04.287Z,1551906844.287 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-06T21:14:04.303Z,1551906844.303 [Onboard] Loaded
2019-03-06T21:14:04.304Z,1551906844.304 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-06T21:14:04.310Z,1551906844.310 [Radio_Surface] Loaded
2019-03-06T21:14:04.311Z,1551906844.311 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-06T21:14:04.312Z,1551906844.312 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-06T21:14:04.312Z,1551906844.312 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8637
2019-03-06T21:14:04.357Z,1551906844.357 [RDI_Pathfinder] Loaded
2019-03-06T21:14:04.357Z,1551906844.357 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-06T21:14:04.379Z,1551906844.379 [RDI_PathfinderUp] Loaded
2019-03-06T21:14:04.379Z,1551906844.379 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-06T21:14:06.199Z,1551906846.199 [BPC1] Loaded
2019-03-06T21:14:06.200Z,1551906846.200 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-06T21:14:06.200Z,1551906846.200 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-06T21:14:06.201Z,1551906846.201 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-06T21:14:06.224Z,1551906846.224 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-06T21:14:06.224Z,1551906846.224 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-06T21:14:06.348Z,1551906846.348 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-06T21:14:06.348Z,1551906846.348 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-06T21:14:06.409Z,1551906846.409 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-06T21:14:06.410Z,1551906846.410 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-06T21:14:06.471Z,1551906846.471 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-06T21:14:06.471Z,1551906846.471 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-06T21:14:06.492Z,1551906846.492 [NavChart] Loaded
2019-03-06T21:14:06.492Z,1551906846.492 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-06T21:14:06.497Z,1551906846.497 [UniversalFixResidualReporter] Loaded
2019-03-06T21:14:06.497Z,1551906846.497 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-06T21:14:06.497Z,1551906846.497 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-06T21:14:06.498Z,1551906846.498 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-06T21:14:06.621Z,1551906846.621 [BuoyancyServo] Loaded
2019-03-06T21:14:06.621Z,1551906846.621 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-06T21:14:06.636Z,1551906846.636 [ElevatorServo] Loaded
2019-03-06T21:14:06.637Z,1551906846.637 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-06T21:14:06.652Z,1551906846.652 [MassServo] Loaded
2019-03-06T21:14:06.653Z,1551906846.653 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-06T21:14:06.668Z,1551906846.668 [RudderServo] Loaded
2019-03-06T21:14:06.669Z,1551906846.669 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-06T21:14:06.683Z,1551906846.683 [ThrusterServo] Loaded
2019-03-06T21:14:06.684Z,1551906846.684 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-06T21:14:06.684Z,1551906846.684 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-06T21:14:06.685Z,1551906846.685 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-06T21:14:06.945Z,1551906846.945 [Aanderaa_O2] Loaded
2019-03-06T21:14:06.945Z,1551906846.945 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-03-06T21:14:07.033Z,1551906847.033 [CTD_NeilBrown] Loaded
2019-03-06T21:14:07.034Z,1551906847.034 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-06T21:14:07.035Z,1551906847.035 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-06T21:14:07.035Z,1551906847.035 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 8638
2019-03-06T21:14:07.081Z,1551906847.081 [WetLabsSeaOWL_UV_A] Loaded
2019-03-06T21:14:07.081Z,1551906847.081 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-06T21:14:07.082Z,1551906847.082 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-06T21:14:07.083Z,1551906847.083 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 8639
2019-03-06T21:14:07.083Z,1551906847.083 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-06T21:14:07.084Z,1551906847.084 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-06T21:14:07.371Z,1551906847.371 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-06T21:14:07.372Z,1551906847.372 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-06T21:14:07.487Z,1551906847.487 [DepthRateCalculator] Loaded
2019-03-06T21:14:07.487Z,1551906847.487 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-06T21:14:07.501Z,1551906847.501 [PitchRateCalculator] Loaded
2019-03-06T21:14:07.501Z,1551906847.501 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-06T21:14:07.540Z,1551906847.540 [SpeedCalculator] Loaded
2019-03-06T21:14:07.540Z,1551906847.540 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-06T21:14:07.651Z,1551906847.651 [TempGradientCalculator] Loaded
2019-03-06T21:14:07.652Z,1551906847.652 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-06T21:14:07.708Z,1551906847.708 [YawRateCalculator] Loaded
2019-03-06T21:14:07.708Z,1551906847.708 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-06T21:14:07.750Z,1551906847.750 [ElevatorOffsetCalculator] Loaded
2019-03-06T21:14:07.750Z,1551906847.750 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-06T21:14:07.750Z,1551906847.750 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-06T21:14:07.751Z,1551906847.751 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-06T21:14:07.965Z,1551906847.965 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-06T21:14:07.988Z,1551906847.988 [SBIT] Loaded
2019-03-06T21:14:07.988Z,1551906847.988 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-06T21:14:07.989Z,1551906847.989 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-06T21:14:08.000Z,1551906848.000 [IBIT] Loaded
2019-03-06T21:14:08.000Z,1551906848.000 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-06T21:14:08.004Z,1551906848.004 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-06T21:14:08.148Z,1551906848.148 [CBIT] Loaded
2019-03-06T21:14:08.149Z,1551906848.149 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-06T21:14:08.149Z,1551906848.149 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-06T21:14:08.150Z,1551906848.150 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-06T21:14:08.254Z,1551906848.254 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-06T21:14:08.254Z,1551906848.254 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-06T21:14:08.378Z,1551906848.378 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-06T21:14:08.379Z,1551906848.379 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-06T21:14:08.468Z,1551906848.468 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-06T21:14:08.554Z,1551906848.554 [VerticalControl] Loaded
2019-03-06T21:14:08.555Z,1551906848.555 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-06T21:14:08.555Z,1551906848.555 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-06T21:14:08.614Z,1551906848.614 [HorizontalControl] Loaded
2019-03-06T21:14:08.615Z,1551906848.615 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-06T21:14:08.615Z,1551906848.615 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-06T21:14:08.617Z,1551906848.617 [SpeedControl] Loaded
2019-03-06T21:14:08.617Z,1551906848.617 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-06T21:14:08.618Z,1551906848.618 [LoopControl](DEBUG): Construct LoopControl.
2019-03-06T21:14:08.618Z,1551906848.618 [LoopControl] Loaded
2019-03-06T21:14:08.619Z,1551906848.619 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-06T21:14:08.619Z,1551906848.619 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-06T21:14:08.620Z,1551906848.620 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-06T21:14:08.663Z,1551906848.663 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-06T21:14:08.667Z,1551906848.667 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-06T21:14:08.668Z,1551906848.668 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-06T21:14:08.675Z,1551906848.675 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-06T21:14:08.676Z,1551906848.676 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-06T21:14:08.676Z,1551906848.676 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8640
2019-03-06T21:14:08.681Z,1551906848.681 [Supervisor](INFO): Main Thread ID is 8552
2019-03-06T21:14:08.681Z,1551906848.681 [Supervisor](DEBUG): Running supervisor.
2019-03-06T21:14:08.682Z,1551906848.682 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8641
2019-03-06T21:14:08.685Z,1551906848.685 [controlThread ThreadHandler](INFO): Handler Thread ID is 8642
2019-03-06T21:14:08.685Z,1551906848.685 [controlThread](DEBUG): Initializing ControlThread
2019-03-06T21:14:08.692Z,1551906848.692 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:14:08.693Z,1551906848.693 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-06T21:14:08.693Z,1551906848.693 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-06T21:14:08.695Z,1551906848.695 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-06T21:14:08.695Z,1551906848.695 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-06T21:14:08.695Z,1551906848.695 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-06T21:14:08.696Z,1551906848.696 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-06T21:14:08.696Z,1551906848.696 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-06T21:14:08.696Z,1551906848.696 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-06T21:14:08.697Z,1551906848.697 [SBIT](INFO): Initialize SBIT Component.
2019-03-06T21:14:08.698Z,1551906848.698 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-06T21:14:08.698Z,1551906848.698 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-06T21:14:08.698Z,1551906848.698 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-06T21:14:08.698Z,1551906848.698 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-06T21:14:08.699Z,1551906848.699 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-06T21:14:08.700Z,1551906848.700 [IBIT](INFO): Initialize IBIT Component.
2019-03-06T21:14:08.701Z,1551906848.701 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-06T21:14:08.702Z,1551906848.702 [logger ThreadHandler](INFO): Handler Thread ID is 8643
2019-03-06T21:14:08.713Z,1551906848.713 [CBIT](DEBUG): Initialized mux pins.
2019-03-06T21:14:08.713Z,1551906848.713 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-06T21:14:08.721Z,1551906848.721 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8644
2019-03-06T21:14:08.722Z,1551906848.722 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-06T21:14:08.733Z,1551906848.733 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8645
2019-03-06T21:14:08.737Z,1551906848.737 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-06T21:14:08.737Z,1551906848.737 [CBIT](DEBUG): Initializing heartbeat.
2019-03-06T21:14:08.745Z,1551906848.745 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 8646
2019-03-06T21:14:08.746Z,1551906848.746 [CTD_NeilBrown](INFO): Powering down
2019-03-06T21:14:08.774Z,1551906848.774 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 8647
2019-03-06T21:14:08.775Z,1551906848.775 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-06T21:14:08.809Z,1551906848.809 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-06T21:14:08.809Z,1551906848.809 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-06T21:14:08.810Z,1551906848.810 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8648
2019-03-06T21:14:08.813Z,1551906848.813 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-06T21:14:08.813Z,1551906848.813 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-06T21:14:08.814Z,1551906848.814 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-06T21:14:08.814Z,1551906848.814 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-06T21:14:08.814Z,1551906848.814 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-06T21:14:08.814Z,1551906848.814 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-06T21:14:08.814Z,1551906848.814 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-06T21:14:08.814Z,1551906848.814 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-06T21:14:08.815Z,1551906848.815 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-06T21:14:08.815Z,1551906848.815 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-06T21:14:08.815Z,1551906848.815 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-06T21:14:08.815Z,1551906848.815 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-06T21:14:08.815Z,1551906848.815 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-06T21:14:08.816Z,1551906848.816 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-06T21:14:08.816Z,1551906848.816 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-06T21:14:08.816Z,1551906848.816 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-06T21:14:08.845Z,1551906848.845 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-06T21:14:08.847Z,1551906848.847 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-06T21:14:08.847Z,1551906848.847 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-06T21:14:08.848Z,1551906848.848 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-06T21:14:08.849Z,1551906848.849 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-06T21:14:08.859Z,1551906848.859 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T21:14:08.890Z,1551906848.890 [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-06T21:14:08.891Z,1551906848.891 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-06T21:14:08.957Z,1551906848.957 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-06T21:14:08.958Z,1551906848.958 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-06T21:14:08.960Z,1551906848.960 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T21:14:08.980Z,1551906848.980 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-06T21:14:09.007Z,1551906849.007 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-06T21:14:09.012Z,1551906849.012 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-06T21:14:09.043Z,1551906849.043 [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-06T21:14:09.048Z,1551906849.048 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Aanderaa_O2,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-06T21:14:09.077Z,1551906849.077 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:14:09.137Z,1551906849.137 [Radio_Surface](INFO): Powering up
2019-03-06T21:14:09.666Z,1551906849.666 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-06T21:14:09.666Z,1551906849.667 [DUSBL_Hydroid](INFO): Powering up
2019-03-06T21:14:09.667Z,1551906849.667 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-06T21:14:09.757Z,1551906849.757 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T21:14:09.802Z,1551906849.802 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-06T21:14:09.813Z,1551906849.813 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-06T21:14:09.814Z,1551906849.814 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-06T21:14:09.825Z,1551906849.825 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-06T21:14:09.826Z,1551906849.826 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-06T21:14:09.837Z,1551906849.837 [MassServo](DEBUG): Initializing MassServo.
2019-03-06T21:14:09.838Z,1551906849.838 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-06T21:14:09.849Z,1551906849.849 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-06T21:14:09.850Z,1551906849.850 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-06T21:14:09.861Z,1551906849.861 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-06T21:14:09.947Z,1551906849.947 [Micromodem](INFO): Powering up
2019-03-06T21:14:09.947Z,1551906849.947 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-06T21:14:10.057Z,1551906850.057 [RDI_PathfinderUp](INFO): Powering down
2019-03-06T21:14:10.125Z,1551906850.125 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T21:14:10.297Z,1551906850.297 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T21:14:13.021Z,1551906853.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:13.425Z,1551906853.425 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:13.565Z,1551906853.565 [Aanderaa_O2](INFO): Powering down
2019-03-06T21:14:14.129Z,1551906854.129 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:14.136Z,1551906854.136 [Micromodem](INFO): Nmea in: $CAREV,211414,INIT,2.0.27690*4E

2019-03-06T21:14:14.741Z,1551906854.741 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:14.747Z,1551906854.747 [Micromodem](INFO): Nmea in: $CAREV,211414,SLOT1,2.0.27690*61

2019-03-06T21:14:14.749Z,1551906854.749 [Micromodem](INFO): Nmea in: $CAREV,211414,AUV,2.0.27690*16

2019-03-06T21:14:14.749Z,1551906854.749 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-06T21:14:14.749Z,1551906854.749 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-06T21:14:14.750Z,1551906854.750 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-06T21:14:14.750Z,1551906854.750 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-06T21:14:14.750Z,1551906854.750 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-06T21:14:15.325Z,1551906855.325 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:15.331Z,1551906855.331 [Micromodem](INFO): Nmea in: $CAREV,211414,COPROC,0.10.0.46*43

2019-03-06T21:14:15.793Z,1551906855.793 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:15.799Z,1551906855.799 [Micromodem](INFO): Nmea in: $CATMG,2019-03-06T21:14:14Z,RTC,RTC*72

2019-03-06T21:14:16.445Z,1551906856.445 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:16.873Z,1551906856.873 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:17.273Z,1551906857.273 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:17.729Z,1551906857.729 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:18.141Z,1551906858.141 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:18.573Z,1551906858.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:19.061Z,1551906859.061 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:19.477Z,1551906859.477 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:19.881Z,1551906859.881 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:20.285Z,1551906860.285 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:20.689Z,1551906860.689 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:20.694Z,1551906860.694 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008527
2019-03-06T21:14:21.093Z,1551906861.093 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:21.497Z,1551906861.497 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:21.901Z,1551906861.901 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:22.305Z,1551906862.305 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:22.305Z,1551906862.305 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:14:22.305Z,1551906862.305 [AHRS_M2] Communications Fault, FailCount= 1
2019-03-06T21:14:22.305Z,1551906862.305 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:14:22.358Z,1551906862.358 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:14:22.385Z,1551906862.385 [AHRS_M2](INFO): Powering down
2019-03-06T21:14:23.240Z,1551906863.240 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-06T21:14:23.282Z,1551906863.282 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:14:23.282Z,1551906863.282 [AHRS_M2] No Fault, FailCount= 1
2019-03-06T21:14:23.597Z,1551906863.597 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:14:24.007Z,1551906864.007 [Micromodem](INFO): Nmea in: $CACFG,CST,1*35

2019-03-06T21:14:24.007Z,1551906864.007 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-06T21:14:24.034Z,1551906864.034 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +0,    +2,    +1,-32768,A
2019-03-06T21:14:24.411Z,1551906864.411 [Micromodem](INFO): Nmea in: $CAREV,211424,AUV,2.0.27690*15

2019-03-06T21:14:24.815Z,1551906864.815 [Micromodem](INFO): Nmea in: $CAREV,211424,COPROC,0.10.0.46*40

2019-03-06T21:14:25.237Z,1551906865.237 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +7,    -5,    +5,-32768,A
2019-03-06T21:14:26.045Z,1551906866.045 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +3,    -6,    +2,-32768,A
2019-03-06T21:14:27.153Z,1551906867.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:27.179Z,1551906867.179 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -1,    -6,    +2,-32768,A
2019-03-06T21:14:27.569Z,1551906867.569 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:27.966Z,1551906867.966 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:27.971Z,1551906867.971 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-06T21:14:28.004Z,1551906868.004 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -3,    -6,    +1,-32768,A
2019-03-06T21:14:28.389Z,1551906868.389 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:28.785Z,1551906868.785 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:29.189Z,1551906869.189 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:29.213Z,1551906869.213 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -6,    -3,    -1,-32768,A
2019-03-06T21:14:29.577Z,1551906869.577 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:29.993Z,1551906869.993 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:30.018Z,1551906870.018 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -12,    -3,    -4,-32768,A
2019-03-06T21:14:30.429Z,1551906870.429 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:30.833Z,1551906870.833 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:31.225Z,1551906871.225 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:31.249Z,1551906871.249 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -16,    +0,    -6,-32768,A
2019-03-06T21:14:31.625Z,1551906871.625 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:32.033Z,1551906872.033 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:32.053Z,1551906872.053 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-03-06T21:14:32.053Z,1551906872.053 [RDI_Pathfinder] Communications Fault, FailCount= 1
2019-03-06T21:14:32.053Z,1551906872.053 [RDI_Pathfinder](ERROR): Communications Fault
2019-03-06T21:14:32.092Z,1551906872.092 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-03-06T21:14:32.429Z,1551906872.429 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:32.549Z,1551906872.549 [RDI_Pathfinder](INFO): Powering down
2019-03-06T21:14:32.917Z,1551906872.917 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:33.317Z,1551906873.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:33.370Z,1551906873.370 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-03-06T21:14:33.370Z,1551906873.370 [RDI_Pathfinder] No Fault, FailCount= 1
2019-03-06T21:14:33.713Z,1551906873.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:34.149Z,1551906874.149 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:34.155Z,1551906874.155 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-03-06T21:14:34.155Z,1551906874.155 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-06T21:14:34.573Z,1551906874.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:34.579Z,1551906874.579 [Micromodem](INFO): Nmea in: $CAREV,211434,AUV,2.0.27690*14

2019-03-06T21:14:34.580Z,1551906874.580 [NAL9602](INFO): Powering up NAL9602
2019-03-06T21:14:34.965Z,1551906874.965 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:34.971Z,1551906874.971 [Micromodem](INFO): Nmea in: $CAREV,211434,COPROC,0.10.0.46*41

2019-03-06T21:14:35.365Z,1551906875.365 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:35.777Z,1551906875.777 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:36.169Z,1551906876.169 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:36.577Z,1551906876.577 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:36.577Z,1551906876.577 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:14:36.577Z,1551906876.577 [AHRS_M2] Communications Fault, FailCount= 2
2019-03-06T21:14:36.577Z,1551906876.577 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:14:36.634Z,1551906876.634 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:14:36.665Z,1551906876.665 [AHRS_M2](INFO): Powering down
2019-03-06T21:14:37.116Z,1551906877.116 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-06T21:14:37.121Z,1551906877.121 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-06T21:14:37.578Z,1551906877.578 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:14:37.578Z,1551906877.578 [AHRS_M2] No Fault, FailCount= 2
2019-03-06T21:14:37.877Z,1551906877.877 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:14:41.217Z,1551906881.217 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:41.873Z,1551906881.873 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:42.357Z,1551906882.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:43.001Z,1551906883.001 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:43.437Z,1551906883.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:43.873Z,1551906883.873 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:43.880Z,1551906883.880 [Micromodem](INFO): Nmea in: $CACFG,REV,1*30

2019-03-06T21:14:43.880Z,1551906883.880 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-03-06T21:14:44.325Z,1551906884.325 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:44.331Z,1551906884.331 [Micromodem](INFO): Nmea in: $CAREV,211444,AUV,2.0.27690*13

2019-03-06T21:14:44.849Z,1551906884.849 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:44.855Z,1551906884.855 [Micromodem](INFO): Nmea in: $CAREV,211444,COPROC,0.10.0.46*46

2019-03-06T21:14:45.317Z,1551906885.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:45.753Z,1551906885.753 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:45.759Z,1551906885.759 [NAL9602](INFO): NAL9602 initialized
2019-03-06T21:14:46.269Z,1551906886.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:46.753Z,1551906886.753 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:46.773Z,1551906886.773 [NAL9602](DEBUG): Fix Requested
2019-03-06T21:14:46.789Z,1551906886.789 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-06T21:14:47.093Z,1551906887.093 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:14:47.095Z,1551906887.095 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:14:47.095Z,1551906887.095 [CommandLine](IMPORTANT): No failed Components.
2019-03-06T21:14:47.213Z,1551906887.213 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:47.713Z,1551906887.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:47.760Z,1551906887.760 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -3,    +2,    +0,-32768,A
2019-03-06T21:14:48.185Z,1551906888.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:48.593Z,1551906888.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:49.157Z,1551906889.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:49.196Z,1551906889.196 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -8,    -1,    -2,-32768,A
2019-03-06T21:14:49.333Z,1551906889.333 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010451
CHAN A1 (24V): -0.025088
CHAN A2 (12V): -0.007171
CHAN A3 (5V): -0.002262
CHAN B0 (3.3V): -0.000536
CHAN B1 (3.15aV): -0.000637
CHAN B2 (3.15bV): -0.000182
CHAN B3 (GND): 0.000571
OPEN: 0.009558
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-06T21:14:49.685Z,1551906889.685 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:49.710Z,1551906889.710 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -10,    +6,    +0,-32768,A
2019-03-06T21:14:50.129Z,1551906890.129 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:50.545Z,1551906890.545 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:50.545Z,1551906890.545 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:14:50.545Z,1551906890.545 [AHRS_M2] Communications Fault, FailCount= 3
2019-03-06T21:14:50.545Z,1551906890.545 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:14:50.634Z,1551906890.634 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:14:50.678Z,1551906890.678 [AHRS_M2](INFO): Powering down
2019-03-06T21:14:50.775Z,1551906890.775 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   -10,   +10,    -4,-32768,A
2019-03-06T21:14:51.494Z,1551906891.494 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:14:51.494Z,1551906891.494 [AHRS_M2] No Fault, FailCount= 3
2019-03-06T21:14:51.861Z,1551906891.861 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:14:51.890Z,1551906891.890 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -6,    +3,    -1,-32768,A
2019-03-06T21:14:52.764Z,1551906892.764 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   +17,    -1,    +3,-32768,A
2019-03-06T21:14:53.951Z,1551906893.951 [Micromodem](INFO): Nmea in: $CACFG,RXP,1*2B

2019-03-06T21:14:53.951Z,1551906893.951 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-06T21:14:54Z,0*7C

2019-03-06T21:14:53.952Z,1551906893.952 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-06T21:14:54Z,0*7C

2019-03-06T21:14:53.979Z,1551906893.979 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +5,    +3,    +0,-32768,A
2019-03-06T21:14:54.355Z,1551906894.355 [Micromodem](INFO): Nmea in: $CAREV,211454,AUV,2.0.27690*12

2019-03-06T21:14:54.759Z,1551906894.759 [Micromodem](INFO): Nmea in: $CAREV,211454,COPROC,0.10.0.46*47

2019-03-06T21:14:54.778Z,1551906894.778 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -7,   +16,    -5,-32768,A
2019-03-06T21:14:55.277Z,1551906895.277 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:55.283Z,1551906895.283 [Micromodem](INFO): Nmea in: $CATMS,0,2019-03-06T21:14:54Z*7E

2019-03-06T21:14:55.283Z,1551906895.283 [Micromodem](DEBUG): Nmea buf: $CCCFQ,TOP*33

2019-03-06T21:14:55.284Z,1551906895.284 [Micromodem](INFO): Nmea out: $CCCFQ,TOP*33

2019-03-06T21:14:55.769Z,1551906895.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:55.783Z,1551906895.783 [Micromodem](INFO): Nmea in: $CATMG,2019-03-06T21:14:54Z,USER_CMD,RTC*37

2019-03-06T21:14:55.857Z,1551906895.857 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-03-06T21:14:55.857Z,1551906895.857 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-03-06T21:14:55.857Z,1551906895.857 [RDI_Pathfinder](ERROR): Communications Fault
2019-03-06T21:14:56.041Z,1551906896.041 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-03-06T21:14:56.197Z,1551906896.197 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:56.223Z,1551906896.223 [Micromodem](INFO): Nmea in: $CACFG,AGC,1*34

2019-03-06T21:14:56.238Z,1551906896.238 [Micromodem](INFO): Nmea in: $CACFG,AGN,250*3F

2019-03-06T21:14:56.248Z,1551906896.248 [Micromodem](INFO): Nmea in: $CACFG,AGX,1*2F

2019-03-06T21:14:56.341Z,1551906896.341 [RDI_Pathfinder](INFO): Powering down
2019-03-06T21:14:56.613Z,1551906896.613 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:56.634Z,1551906896.634 [Micromodem](INFO): Nmea in: $CACFG,ASD,0*26

2019-03-06T21:14:56.650Z,1551906896.650 [Micromodem](INFO): Nmea in: $CACFG,BBD,0*34

2019-03-06T21:14:57.017Z,1551906897.017 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:57.031Z,1551906897.031 [Micromodem](INFO): Nmea in: $CACFG,BND,3*3B

2019-03-06T21:14:57.146Z,1551906897.146 [Micromodem](INFO): Nmea in: $CACFG,BR1,3*52

2019-03-06T21:14:57.424Z,1551906897.424 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-03-06T21:14:57.424Z,1551906897.424 [RDI_Pathfinder] No Fault, FailCount= 2
2019-03-06T21:14:57.577Z,1551906897.577 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:57.613Z,1551906897.613 [Micromodem](INFO): Nmea in: $CACFG,BR2,3*51

2019-03-06T21:14:57.631Z,1551906897.631 [Micromodem](INFO): Nmea in: $CACFG,BR3,3*50

2019-03-06T21:14:58.185Z,1551906898.185 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:58.200Z,1551906898.200 [Micromodem](INFO): Nmea in: $CACFG,BR4,3*57

2019-03-06T21:14:58.282Z,1551906898.282 [Micromodem](INFO): Nmea in: $CACFG,BRN,0*2E

2019-03-06T21:14:58.573Z,1551906898.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:58.590Z,1551906898.590 [Micromodem](INFO): Nmea in: $CACFG,BSP,1*30

2019-03-06T21:14:58.606Z,1551906898.606 [Micromodem](INFO): Nmea in: $CACFG,BW0,5000*60

2019-03-06T21:14:58.973Z,1551906898.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:58.984Z,1551906898.984 [Micromodem](INFO): Nmea in: $CACFG,CPR,1*30

2019-03-06T21:14:58.999Z,1551906898.999 [Micromodem](INFO): Nmea in: $CACFG,CRL,50*18

2019-03-06T21:14:59.377Z,1551906899.377 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-03-06T21:14:59.393Z,1551906899.393 [Micromodem](INFO): Nmea in: $CACFG,CST,1*35

2019-03-06T21:14:59.403Z,1551906899.403 [Micromodem](INFO): Nmea in: $CACFG,CTO,10*19

2019-03-06T21:14:59.739Z,1551906899.739 [Micromodem](INFO): Nmea in: $CACFG,DBG,0*31

2019-03-06T21:14:59.761Z,1551906899.761 [Micromodem](INFO): Nmea in: $CACFG,DGM,0*3E

2019-03-06T21:15:00.140Z,1551906900.140 [Micromodem](INFO): Nmea in: $CACFG,DOP,0*2B

2019-03-06T21:15:00.161Z,1551906900.161 [Micromodem](INFO): Nmea in: $CACFG,DQF,1*22

2019-03-06T21:15:00.530Z,1551906900.530 [Micromodem](INFO): Nmea in: $CACFG,DTH,90*11

2019-03-06T21:15:00.537Z,1551906900.537 [Micromodem](INFO): Nmea in: $CACFG,DTO,2*2D

2019-03-06T21:15:00.969Z,1551906900.969 [Micromodem](INFO): Nmea in: $CACFG,DTP,90*09

2019-03-06T21:15:00.980Z,1551906900.980 [Micromodem](INFO): Nmea in: $CACFG,ECD,50*07

2019-03-06T21:15:01.340Z,1551906901.340 [Micromodem](INFO): Nmea in: $CACFG,EFB,10*00

2019-03-06T21:15:01.367Z,1551906901.367 [Micromodem](INFO): Nmea in: $CACFG,EFF,20*07

2019-03-06T21:15:01.746Z,1551906901.746 [Micromodem](INFO): Nmea in: $CACFG,FC0,25000*42

2019-03-06T21:15:01.777Z,1551906901.777 [Micromodem](INFO): Nmea in: $CACFG,FMD,1*3E

2019-03-06T21:15:02.153Z,1551906902.153 [Micromodem](INFO): Nmea in: $CACFG,FML,200*35

2019-03-06T21:15:02.164Z,1551906902.164 [Micromodem](INFO): Nmea in: $CACFG,GPS,0*34

2019-03-06T21:15:02.552Z,1551906902.552 [Micromodem](INFO): Nmea in: $CACFG,HFC,0*3D

2019-03-06T21:15:02.563Z,1551906902.563 [Micromodem](INFO): Nmea in: $CACFG,IRE,0*2E

2019-03-06T21:15:02.957Z,1551906902.957 [Micromodem](INFO): Nmea in: $CACFG,MCM,1*32

2019-03-06T21:15:02.967Z,1551906902.967 [Micromodem](INFO): Nmea in: $CACFG,MFD,1*3E

2019-03-06T21:15:03.360Z,1551906903.360 [Micromodem](INFO): Nmea in: $CACFG,MOD,0*36

2019-03-06T21:15:03.375Z,1551906903.375 [Micromodem](INFO): Nmea in: $CACFG,MPR,0*3F

2019-03-06T21:15:03.764Z,1551906903.764 [Micromodem](INFO): Nmea in: $CACFG,MSE,0*2B

2019-03-06T21:15:03.779Z,1551906903.779 [Micromodem](INFO): Nmea in: $CACFG,MVM,0*26

2019-03-06T21:15:04.173Z,1551906904.173 [Micromodem](INFO): Nmea in: $CACFG,NDT,120*2D

2019-03-06T21:15:04.184Z,1551906904.184 [Micromodem](INFO): Nmea in: $CACFG,NRL,25*17

2019-03-06T21:15:04.578Z,1551906904.578 [Micromodem](INFO): Nmea in: $CACFG,NRV,0*3A

2019-03-06T21:15:04.593Z,1551906904.593 [Micromodem](INFO): Nmea in: $CACFG,PAD,0*25

2019-03-06T21:15:05.036Z,1551906905.036 [Micromodem](INFO): Nmea in: $CACFG,PCM,0*2E

2019-03-06T21:15:05.052Z,1551906905.052 [Micromodem](INFO): Nmea in: $CACFG,POW,-20*27

2019-03-06T21:15:05.373Z,1551906905.373 [Micromodem](INFO): Nmea in: $CACFG,PRL,50*0B

2019-03-06T21:15:05.384Z,1551906905.384 [Micromodem](INFO): Nmea in: $CACFG,PTH,50*09

2019-03-06T21:15:05.777Z,1551906905.777 [Micromodem](INFO): Nmea in: $CACFG,PTO,14*0E

2019-03-06T21:15:05.787Z,1551906905.787 [Micromodem](INFO): Nmea in: $CACFG,REV,1*30

2019-03-06T21:15:06.176Z,1551906906.176 [Micromodem](INFO): Nmea in: $CACFG,RXA,0*3B

2019-03-06T21:15:06.191Z,1551906906.191 [Micromodem](INFO): Nmea in: $CACFG,RXD,1*3F

2019-03-06T21:15:06.580Z,1551906906.580 [Micromodem](INFO): Nmea in: $CACFG,RXP,1*2B

2019-03-06T21:15:06.595Z,1551906906.595 [Micromodem](INFO): Nmea in: $CACFG,SCG,0*27

2019-03-06T21:15:06.984Z,1551906906.984 [Micromodem](INFO): Nmea in: $CACFG,SGP,0*34

2019-03-06T21:15:06.999Z,1551906906.999 [Micromodem](INFO): Nmea in: $CACFG,SHF,0*2D

2019-03-06T21:15:07.388Z,1551906907.388 [Micromodem](INFO): Nmea in: $CACFG,SNR,0*3F

2019-03-06T21:15:07.403Z,1551906907.403 [Micromodem](INFO): Nmea in: $CACFG,SNV,0*3B

2019-03-06T21:15:07.796Z,1551906907.796 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-03-06T21:15:07.808Z,1551906907.808 [Micromodem](INFO): Nmea in: $CACFG,TAT,50*04

2019-03-06T21:15:08.196Z,1551906908.196 [Micromodem](INFO): Nmea in: $CACFG,TOA,0*2A

2019-03-06T21:15:08.228Z,1551906908.228 [Micromodem](INFO): Nmea in: $CACFG,TXD,600*3E

2019-03-06T21:15:08.600Z,1551906908.600 [Micromodem](INFO): Nmea in: $CACFG,TXP,1*2D

2019-03-06T21:15:08.615Z,1551906908.615 [Micromodem](INFO): Nmea in: $CACFG,TXF,1*3B

2019-03-06T21:15:09.004Z,1551906909.004 [Micromodem](INFO): Nmea in: $CACFG,XST,1*2E

2019-03-06T21:15:09.028Z,1551906909.028 [Micromodem](INFO): Nmea in: $CACFG,uart1.bitrate,19200*0A

2019-03-06T21:15:09.413Z,1551906909.413 [Micromodem](INFO): Nmea in: $CACFG,uart2.bitrate,19200*09

2019-03-06T21:15:09.429Z,1551906909.429 [Micromodem](INFO): Nmea in: $CACFG,uart3.bitrate,19200*08

2019-03-06T21:15:09.826Z,1551906909.826 [Micromodem](INFO): Nmea in: $CACFG,uart4.bitrate,19200*0F

2019-03-06T21:15:09.843Z,1551906909.843 [Micromodem](INFO): Nmea in: $CACFG,uart1.task,1*71

2019-03-06T21:15:10.223Z,1551906910.223 [Micromodem](INFO): Nmea in: $CACFG,uart2.task,1*72

2019-03-06T21:15:10.240Z,1551906910.240 [Micromodem](INFO): Nmea in: $CACFG,uart3.task,1*73

2019-03-06T21:15:10.631Z,1551906910.631 [Micromodem](INFO): Nmea in: $CACFG,uart4.task,1*74

2019-03-06T21:15:10.654Z,1551906910.654 [Micromodem](INFO): Nmea in: $CACFG,uart1.parse_gps,0*33

2019-03-06T21:15:10.684Z,1551906910.684 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-06T21:15:11.031Z,1551906911.031 [Micromodem](INFO): Nmea in: $CACFG,uart2.parse_gps,0*30

2019-03-06T21:15:11.046Z,1551906911.046 [Micromodem](INFO): Nmea in: $CACFG,uart3.parse_gps,0*31

2019-03-06T21:15:11.437Z,1551906911.437 [Micromodem](INFO): Nmea in: $CACFG,uart4.parse_gps,0*36

2019-03-06T21:15:11.483Z,1551906911.483 [Micromodem](INFO): Nmea in: $CACFG,uart1.show_gps,0*45

2019-03-06T21:15:11.840Z,1551906911.840 [Micromodem](INFO): Nmea in: $CACFG,uart2.show_gps,0*46

2019-03-06T21:15:11.879Z,1551906911.879 [Micromodem](INFO): Nmea in: $CACFG,uart3.show_gps,0*47

2019-03-06T21:15:11.900Z,1551906911.900 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +2,   -10,    +2,-32768,A
2019-03-06T21:15:12.244Z,1551906912.244 [Micromodem](INFO): Nmea in: $CACFG,uart4.show_gps,0*40

2019-03-06T21:15:12.268Z,1551906912.268 [Micromodem](INFO): Nmea in: $CACFG,uart1.set_clk_GPS,0*3F

2019-03-06T21:15:12.650Z,1551906912.650 [Micromodem](INFO): Nmea in: $CACFG,uart2.set_clk_GPS,0*3C

2019-03-06T21:15:12.674Z,1551906912.674 [Micromodem](INFO): Nmea in: $CACFG,uart3.set_clk_GPS,0*3D

2019-03-06T21:15:12.731Z,1551906912.731 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -2,    -2,    +0,-32768,A
2019-03-06T21:15:13.058Z,1551906913.058 [Micromodem](INFO): Nmea in: $CACFG,uart4.set_clk_GPS,0*3A

2019-03-06T21:15:13.082Z,1551906913.082 [Micromodem](INFO): Nmea in: $CACFG,uart1.flowcontrol,0*08

2019-03-06T21:15:13.461Z,1551906913.461 [Micromodem](INFO): Nmea in: $CACFG,uart2.flowcontrol,0*0B

2019-03-06T21:15:13.485Z,1551906913.485 [Micromodem](INFO): Nmea in: $CACFG,uart3.flowcontrol,0*0A

2019-03-06T21:15:13.877Z,1551906913.877 [Micromodem](INFO): Nmea in: $CACFG,uart4.flowcontrol,0*0D

2019-03-06T21:15:13.896Z,1551906913.896 [Micromodem](INFO): Nmea in: $CACFG,uart4.rs485,0*40

2019-03-06T21:15:13.922Z,1551906913.922 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -6,    -6,    -1,-32768,A
2019-03-06T21:15:14.263Z,1551906914.263 [Micromodem](INFO): Nmea in: $CACFG,uart1.crc32,0*0E

2019-03-06T21:15:14.272Z,1551906914.272 [Micromodem](INFO): Nmea in: $CACFG,uart2.crc32,0*0D

2019-03-06T21:15:14.649Z,1551906914.649 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-03-06T21:15:14.649Z,1551906914.649 [AHRS_M2] Data Fault, FailCount= 4
2019-03-06T21:15:14.649Z,1551906914.649 [AHRS_M2](ERROR): Data Fault
2019-03-06T21:15:14.664Z,1551906914.664 [Micromodem](INFO): Nmea in: $CACFG,uart3.crc32,0*0C

2019-03-06T21:15:14.718Z,1551906914.718 [Micromodem](INFO): Nmea in: $CACFG,uart4.crc32,0*0B

2019-03-06T21:15:14.751Z,1551906914.751 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -6,    -3,    +0,-32768,A
2019-03-06T21:15:14.799Z,1551906914.799 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-03-06T21:15:15.042Z,1551906915.042 [AHRS_M2](INFO): Powering down
2019-03-06T21:15:15.146Z,1551906915.146 [Micromodem](INFO): Nmea in: $CACFG,uart1.iridium_hangup_minutes,0*62

2019-03-06T21:15:15.174Z,1551906915.174 [Micromodem](INFO): Nmea in: $CACFG,sport3.bitrate,19200*70

2019-03-06T21:15:15.460Z,1551906915.460 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.level,0*65

2019-03-06T21:15:15.472Z,1551906915.472 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.location,1*0F

2019-03-06T21:15:15.867Z,1551906915.867 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.lastretrieval,0*7F

2019-03-06T21:15:15.880Z,1551906915.880 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.currentdbSize,0*59

2019-03-06T21:15:15.899Z,1551906915.899 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -7,    +0,    +0,-32768,A
2019-03-06T21:15:15.966Z,1551906915.966 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:15:15.967Z,1551906915.967 [AHRS_M2] No Fault, FailCount= 4
2019-03-06T21:15:16.253Z,1551906916.253 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:15:16.275Z,1551906916.275 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.output_rate,0*51

2019-03-06T21:15:16.284Z,1551906916.284 [Micromodem](INFO): Nmea in: $CACFG,rec.state,0*5D

2019-03-06T21:15:16.673Z,1551906916.673 [Micromodem](INFO): Nmea in: $CACFG,rec.start_time,-1*2C

2019-03-06T21:15:16.683Z,1551906916.683 [Micromodem](INFO): Nmea in: $CACFG,rec.end_time,-1*23

2019-03-06T21:15:16.702Z,1551906916.702 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +6,    +0,    +0,-32768,A
2019-03-06T21:15:17.085Z,1551906917.085 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.modulation,0*04

2019-03-06T21:15:17.099Z,1551906917.099 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.bandwidth_Hz,4000*30

2019-03-06T21:15:17.484Z,1551906917.484 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.carrier_Hz,25120*13

2019-03-06T21:15:17.511Z,1551906917.511 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.nulltime_ms,200*4B

2019-03-06T21:15:17.890Z,1551906917.890 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.legacy_addressing,0*5A

2019-03-06T21:15:17.919Z,1551906917.919 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.mod_hdr_version,0*72

2019-03-06T21:15:17.939Z,1551906917.939 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +0,    -3,    +0,-32768,A
2019-03-06T21:15:18.295Z,1551906918.295 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.modulation,0*12

2019-03-06T21:15:18.310Z,1551906918.310 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.bandwidth_Hz,5000*27

2019-03-06T21:15:18.700Z,1551906918.700 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.carrier_Hz,25000*06

2019-03-06T21:15:18.715Z,1551906918.715 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.bandwidth0_Hz,5000*17

2019-03-06T21:15:18.770Z,1551906918.770 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -4,    +5,    +0,-32768,A
2019-03-06T21:15:19.101Z,1551906919.101 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.carrier0_Hz,25000*36

2019-03-06T21:15:19.115Z,1551906919.115 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.nulltime_ms,250*58

2019-03-06T21:15:19.681Z,1551906919.681 [Micromodem](INFO): Nmea in: $CACFG,detector.power_thresh,-20*6B

2019-03-06T21:15:19.696Z,1551906919.696 [Micromodem](INFO): Nmea in: $CACFG,detector.noise_thresh,50*40

2019-03-06T21:15:19.727Z,1551906919.727 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -3,    +0,    -1,-32768,A
2019-03-06T21:15:20.124Z,1551906920.124 [Micromodem](INFO): Nmea in: $CACFG,detector1.recv,0*77

2019-03-06T21:15:20.154Z,1551906920.154 [Micromodem](INFO): Nmea in: $CACFG,detector1.channel_mask,1*5C

2019-03-06T21:15:20.172Z,1551906920.172 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-03-06T21:15:20.172Z,1551906920.172 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-03-06T21:15:20.172Z,1551906920.172 [RDI_Pathfinder](ERROR): Communications Fault
2019-03-06T21:15:20.330Z,1551906920.330 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-03-06T21:15:20.330Z,1551906920.330 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-03-06T21:15:20.461Z,1551906920.461 [Micromodem](INFO): Nmea in: $CACFG,detector1.type,0*6D

2019-03-06T21:15:20.486Z,1551906920.486 [Micromodem](INFO): Nmea in: $CACFG,detector1.fm_dir,0*5E

2019-03-06T21:15:20.589Z,1551906920.589 [RDI_Pathfinder](INFO): Powering down
2019-03-06T21:15:20.719Z,1551906920.719 [CommandLine](FAULT): Scheduling is paused
2019-03-06T21:15:20.719Z,1551906920.719 [CBIT](INFO): Critical error at 20190306T211520
2019-03-06T21:15:20.720Z,1551906920.720 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-06T21:15:20.868Z,1551906920.868 [Micromodem](INFO): Nmea in: $CACFG,detector1.length_sym,40*65

2019-03-06T21:15:20.897Z,1551906920.897 [Micromodem](INFO): Nmea in: $CACFG,detector1.fm_bw_Hz,4000*6D

2019-03-06T21:15:21.276Z,1551906921.276 [Micromodem](INFO): Nmea in: $CACFG,detector1.carrier_Hz,25120*60

2019-03-06T21:15:21.299Z,1551906921.299 [Micromodem](INFO): Nmea in: $CACFG,detector1.thresh,90*5C

2019-03-06T21:15:21.672Z,1551906921.672 [Micromodem](INFO): Nmea in: $CACFG,detector2.recv,1*75

2019-03-06T21:15:21.701Z,1551906921.701 [Micromodem](INFO): Nmea in: $CACFG,detector2.channel_mask,1*5F

2019-03-06T21:15:22.082Z,1551906922.082 [Micromodem](INFO): Nmea in: $CACFG,detector2.type,0*6E

2019-03-06T21:15:22.106Z,1551906922.106 [Micromodem](INFO): Nmea in: $CACFG,detector2.fm_dir,1*5C

2019-03-06T21:15:22.488Z,1551906922.488 [Micromodem](INFO): Nmea in: $CACFG,detector2.length_sym,200*50

2019-03-06T21:15:22.517Z,1551906922.517 [Micromodem](INFO): Nmea in: $CACFG,detector2.fm_bw_Hz,4000*6E

2019-03-06T21:15:22.892Z,1551906922.892 [Micromodem](INFO): Nmea in: $CACFG,detector2.carrier_Hz,25120*63

2019-03-06T21:15:22.931Z,1551906922.931 [Micromodem](INFO): Nmea in: $CACFG,detector2.thresh,90*5F

2019-03-06T21:15:23.293Z,1551906923.293 [Micromodem](INFO): Nmea in: $CACFG,detector3.recv,2*77

2019-03-06T21:15:23.342Z,1551906923.342 [Micromodem](INFO): Nmea in: $CACFG,detector3.channel_mask,1*5E

2019-03-06T21:15:23.688Z,1551906923.688 [Micromodem](INFO): Nmea in: $CACFG,detector3.type,1*6E

2019-03-06T21:15:23.707Z,1551906923.707 [Micromodem](INFO): Nmea in: $CACFG,detector3.fm_dir,0*5C

2019-03-06T21:15:24.104Z,1551906924.104 [Micromodem](INFO): Nmea in: $CACFG,detector3.length_sym,200*51

2019-03-06T21:15:24.128Z,1551906924.128 [Micromodem](INFO): Nmea in: $CACFG,detector3.fm_bw_Hz,4000*6F

2019-03-06T21:15:24.504Z,1551906924.504 [Micromodem](INFO): Nmea in: $CACFG,detector3.carrier_Hz,25120*62

2019-03-06T21:15:24.516Z,1551906924.516 [Micromodem](INFO): Nmea in: $CACFG,detector3.thresh,120*64

2019-03-06T21:15:24.904Z,1551906924.904 [Micromodem](INFO): Nmea in: $CACFG,recv.mca.gain,250*1B

2019-03-06T21:15:24.917Z,1551906924.917 [Micromodem](INFO): Nmea in: $CACFG,recv.onboard_gain,250*7E

2019-03-06T21:15:25.311Z,1551906925.311 [Micromodem](INFO): Nmea in: $CACFG,recv.onboard_gain_dB,32*31

2019-03-06T21:15:25.324Z,1551906925.324 [Micromodem](INFO): Nmea in: $CACFG,recv.onboard_equalizer,0*10

2019-03-06T21:15:25.714Z,1551906925.714 [Micromodem](INFO): Nmea in: $CACFG,recv.agn_legacy,1*7F

2019-03-06T21:15:25.725Z,1551906925.725 [Micromodem](INFO): Nmea in: $CACFG,recv.p2b_chmask,1*3D

2019-03-06T21:15:26.122Z,1551906926.122 [Micromodem](INFO): Nmea in: $CACFG,recv.agc_length_ms,100*3A

2019-03-06T21:15:26.133Z,1551906926.133 [Micromodem](INFO): Nmea in: $CACFG,recv.agc_legacy,1*72

2019-03-06T21:15:26.521Z,1551906926.521 [Micromodem](INFO): Nmea in: $CACFG,recv.digital_rcvr,0*6C

2019-03-06T21:15:26.532Z,1551906926.532 [Micromodem](INFO): Nmea in: $CACFG,recv.base64data,0*5B

2019-03-06T21:15:26.926Z,1551906926.926 [Micromodem](INFO): Nmea in: $CACFG,xmit.poweramp_gating,0*18

2019-03-06T21:15:26.937Z,1551906926.937 [Micromodem](INFO): Nmea in: $CACFG,xmit.txinhibit,0*23

2019-03-06T21:15:27.331Z,1551906927.331 [Micromodem](INFO): Nmea in: $CACFG,xmit.uart_disable_mask,15*10

2019-03-06T21:15:27.343Z,1551906927.343 [Micromodem](INFO): Nmea in: $CACFG,timing.clock_source,2*66

2019-03-06T21:15:27.733Z,1551906927.733 [Micromodem](INFO): Nmea in: $CACFG,timing.PPS_source,1*5E

2019-03-06T21:15:27.744Z,1551906927.744 [Micromodem](INFO): Nmea in: $CACFG,timing.syncnav,0*30

2019-03-06T21:15:28.138Z,1551906928.138 [Micromodem](INFO): Nmea in: $CACFG,timing.scg_timeout,0*1D

2019-03-06T21:15:28.150Z,1551906928.150 [Micromodem](INFO): Nmea in: $CACFG,timing.pps_timeout,3*1A

2019-03-06T21:15:28.542Z,1551906928.542 [Micromodem](INFO): Nmea in: $CACFG,timing.extpps_enable,1*0B

2019-03-06T21:15:28.556Z,1551906928.556 [Micromodem](INFO): Nmea in: $CACFG,timing.extpps_en_after_n,0*0A

2019-03-06T21:15:28.948Z,1551906928.948 [Micromodem](INFO): Nmea in: $CACFG,timing.setclk_after_n,0*42

2019-03-06T21:15:28.964Z,1551906928.964 [Micromodem](INFO): Nmea in: $CACFG,timing.extpps_disable_after_n,0*75

2019-03-06T21:15:29.347Z,1551906929.347 [Micromodem](INFO): Nmea in: $CACFG,timing.debug,0*3F

2019-03-06T21:15:29.379Z,1551906929.379 [Micromodem](INFO): Nmea in: $CACFG,hibernate.wake_interval,0*6C

2019-03-06T21:15:29.733Z,1551906929.733 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-03-06T21:15:29.733Z,1551906929.733 [AHRS_M2] Data Fault, FailCount= 5
2019-03-06T21:15:29.733Z,1551906929.733 [AHRS_M2](ERROR): Data Fault
2019-03-06T21:15:29.755Z,1551906929.755 [Micromodem](INFO): Nmea in: $CACFG,hibernate.wake_reference,0*18

2019-03-06T21:15:29.804Z,1551906929.804 [Micromodem](INFO): Nmea in: $CACFG,hibernate.hibernate_after,0*65

2019-03-06T21:15:29.836Z,1551906929.836 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-03-06T21:15:29.837Z,1551906929.837 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-03-06T21:15:30.125Z,1551906930.125 [AHRS_M2](INFO): Powering down
2019-03-06T21:15:30.222Z,1551906930.222 [Micromodem](INFO): Nmea in: $CACFG,nav.soundspeed_mps,1430*24

2019-03-06T21:15:30.230Z,1551906930.230 [Micromodem](INFO): Nmea in: $CACFG,nav.nst,0*4E

2019-03-06T21:15:30.410Z,1551906930.410 [CBIT](INFO): Critical error at 20190306T211529
2019-03-06T21:15:30.547Z,1551906930.547 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.mode,0*02

2019-03-06T21:15:30.556Z,1551906930.556 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.dbg,0*60

2019-03-06T21:15:30.643Z,1551906930.643 [SBIT](IMPORTANT): SBIT PASSED
2019-03-06T21:15:30.670Z,1551906930.670 [CommandLine](IMPORTANT): got command configSet list
2019-03-06T21:15:30.670Z,1551906930.670 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-06T21:15:30.670Z,1551906930.670 [CommandLine](IMPORTANT): No configSet variables persisted
2019-03-06T21:15:30.949Z,1551906930.949 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_type,0*3F

2019-03-06T21:15:30.963Z,1551906930.963 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs1_x_mm,-15*64

2019-03-06T21:15:31.034Z,1551906931.034 [MissionManager](IMPORTANT): Started mission Startup
2019-03-06T21:15:31.035Z,1551906931.035 [Startup] Running Loop=1
2019-03-06T21:15:31.035Z,1551906931.035 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-06T21:15:31.035Z,1551906931.035 [Startup:A.GoToSurface] Running Loop=1
2019-03-06T21:15:31.035Z,1551906931.035 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-06T21:15:31.035Z,1551906931.035 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-06T21:15:31.036Z,1551906931.036 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-06T21:15:31.036Z,1551906931.036 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-06T21:15:31.037Z,1551906931.037 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-06T21:15:31.037Z,1551906931.037 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-06T21:15:31.039Z,1551906931.039 [Startup:StartupSatComms] Running Loop=1
2019-03-06T21:15:31.039Z,1551906931.039 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-06T21:15:31.039Z,1551906931.039 [Startup:StartupSatComms:A] Running Loop=1
2019-03-06T21:15:31.359Z,1551906931.359 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs1_y_mm,15*48

2019-03-06T21:15:31.372Z,1551906931.372 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs1_z_mm,0*7F

2019-03-06T21:15:31.455Z,1551906931.455 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-06T21:15:31.763Z,1551906931.763 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs2_x_mm,15*4A

2019-03-06T21:15:31.776Z,1551906931.776 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs2_y_mm,15*4B

2019-03-06T21:15:32.165Z,1551906932.165 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs2_z_mm,0*7C

2019-03-06T21:15:32.178Z,1551906932.178 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs3_x_mm,15*4B

2019-03-06T21:15:32.574Z,1551906932.574 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs3_y_mm,-15*67

2019-03-06T21:15:32.586Z,1551906932.586 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs3_z_mm,0*7D

2019-03-06T21:15:32.974Z,1551906932.974 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs4_x_mm,-15*61

2019-03-06T21:15:33.004Z,1551906933.004 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs4_y_mm,-15*60

2019-03-06T21:15:33.373Z,1551906933.373 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs4_z_mm,0*7A

2019-03-06T21:15:33.384Z,1551906933.384 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.channel_mask,30*1A

2019-03-06T21:15:33.776Z,1551906933.776 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.mode,0*75

2019-03-06T21:15:33.787Z,1551906933.787 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.carrier_Hz,13500*60

2019-03-06T21:15:34.182Z,1551906934.182 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.cycles_per_sym,7*72

2019-03-06T21:15:34.192Z,1551906934.192 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.data,0*66

2019-03-06T21:15:34.585Z,1551906934.585 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.sequence_len,47*62

2019-03-06T21:15:34.596Z,1551906934.596 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.timing_mode,0*3A

2019-03-06T21:15:34.992Z,1551906934.992 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.period,1*72

2019-03-06T21:15:35.008Z,1551906935.008 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.reference_time,2015-12-04T00:00:00Z*68

2019-03-06T21:15:35.399Z,1551906935.399 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.bandwidth_Hz,4000*40

2019-03-06T21:15:35.411Z,1551906935.411 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.duration_msx10,35*66

2019-03-06T21:15:35.793Z,1551906935.793 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.mode,0*1A

2019-03-06T21:15:35.799Z,1551906935.799 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.type,1*00

2019-03-06T21:15:36.199Z,1551906936.199 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-03-06T21:15:36.208Z,1551906936.208 [Micromodem](INFO): Nmea in: $CACFG,info.part_number,201005*2D

2019-03-06T21:15:36.606Z,1551906936.606 [Micromodem](INFO): Nmea in: $CACFG,info.serial_number,673*0E

2019-03-06T21:15:36.613Z,1551906936.613 [Micromodem](INFO): Nmea in: $CACFG,info.board_rev,D*60

2019-03-06T21:15:37.002Z,1551906937.002 [Micromodem](INFO): Nmea in: $CACFG,info.bom_variant,M*65

2019-03-06T21:15:37.009Z,1551906937.009 [Micromodem](INFO): Nmea in: $CACFG,info.bom_rev,1*0F

2019-03-06T21:15:37.416Z,1551906937.416 [Micromodem](INFO): Nmea in: $CACFG,info.fpga_version,2.0.18017*68

2019-03-06T21:15:37.424Z,1551906937.424 [Micromodem](INFO): Nmea in: $CACFG,info.fpga_api_level,3*4D

2019-03-06T21:15:37.815Z,1551906937.815 [Micromodem](INFO): Nmea in: $CACFG,info.fpga_flashrom_version,2*38

2019-03-06T21:15:37.822Z,1551906937.822 [Micromodem](INFO): Nmea in: $CACFG,info.build_oem,A*6F

2019-03-06T21:15:38.217Z,1551906938.217 [Micromodem](INFO): Nmea in: $CACFG,info.build_year,2016*43

2019-03-06T21:15:38.224Z,1551906938.224 [Micromodem](INFO): Nmea in: $CACFG,info.build_month,5*0C

2019-03-06T21:15:38.627Z,1551906938.627 [Micromodem](INFO): Nmea in: $CACFG,info.build_options,0*09

2019-03-06T21:15:38.637Z,1551906938.637 [Micromodem](INFO): Nmea in: $CACFG,info.coproc_version,0.10.0.46.0 *54

2019-03-06T21:15:39.027Z,1551906939.027 [Micromodem](INFO): Nmea in: $CACFG,info.firmware_version,2.0.27690*6C

2019-03-06T21:15:39.048Z,1551906939.048 [Micromodem](INFO): Nmea in: $CACFG,info.loader_version,17175*61

2019-03-06T21:15:39.428Z,1551906939.428 [Micromodem](INFO): Nmea in: $CACFG,info.booted_slot,1*1D

2019-03-06T21:15:39.434Z,1551906939.434 [Micromodem](INFO): Nmea in: $CACFG,info.debugio,6*21

2019-03-06T21:15:39.833Z,1551906939.833 [Micromodem](INFO): Nmea in: $CACFG,hwd.batt_v,23.611503*10

2019-03-06T21:15:39.840Z,1551906939.840 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux_adc_v,0.082000*7D

2019-03-06T21:15:40.236Z,1551906940.236 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux_adc_gain,1*2F

2019-03-06T21:15:40.242Z,1551906940.242 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux_adc_res,12*78

2019-03-06T21:15:40.639Z,1551906940.639 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux33V_on,1*40

2019-03-06T21:15:40.649Z,1551906940.649 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux33V_fault,0*2A

2019-03-06T21:15:41.044Z,1551906941.044 [Micromodem](INFO): Nmea in: $CACFG,hwd.stack33V_on,1*42

2019-03-06T21:15:41.050Z,1551906941.050 [Micromodem](INFO): Nmea in: $CACFG,hwd.stack33V_fault,0*28

2019-03-06T21:15:41.455Z,1551906941.455 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpwramp_on,1*25

2019-03-06T21:15:41.462Z,1551906941.462 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpwramp_fault,0*4F

2019-03-06T21:15:41.853Z,1551906941.853 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpreamp_on,1*37

2019-03-06T21:15:41.859Z,1551906941.859 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpreamp_fault,0*5D

2019-03-06T21:15:42.259Z,1551906942.259 [Micromodem](INFO): Nmea in: $CACFG,hwd.VSDcard_on,0*2E

2019-03-06T21:15:42.277Z,1551906942.277 [Micromodem](INFO): Nmea in: $CACFG,hwd.VSDcard_fault,0*45

2019-03-06T21:15:42.658Z,1551906942.658 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio1,0*05

2019-03-06T21:15:42.663Z,1551906942.663 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio2,0*06

2019-03-06T21:15:43.066Z,1551906943.066 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio3,0*07

2019-03-06T21:15:43.070Z,1551906943.070 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio4,1*01

2019-03-06T21:15:43.466Z,1551906943.466 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio5,0*01

2019-03-06T21:15:43.471Z,1551906943.471 [Micromodem](INFO): Nmea in: $CACFG,hwd.txinhibit,0*50

2019-03-06T21:15:43.877Z,1551906943.877 [Micromodem](INFO): Nmea in: $CACFG,hwd.temp,19.500000*3A

2019-03-06T21:15:43.885Z,1551906943.885 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.mm_rom_valid,0*35

2019-03-06T21:15:44.275Z,1551906944.275 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.mm_rom,0*1C

2019-03-06T21:15:44.281Z,1551906944.281 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.pa_rom_valid,0*24

2019-03-06T21:15:44.679Z,1551906944.679 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.pa_rom,0*0D

2019-03-06T21:15:44.686Z,1551906944.686 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.pa_rom_boot_search,0*15

2019-03-06T21:15:45.084Z,1551906945.084 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux1_rom_valid,0*68

2019-03-06T21:15:45.089Z,1551906945.089 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux1_rom,0*41

2019-03-06T21:15:45.490Z,1551906945.490 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux1_rom_boot_search,0*59

2019-03-06T21:15:45.495Z,1551906945.495 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux2_rom_valid,0*6B

2019-03-06T21:15:45.895Z,1551906945.895 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux2_rom,0*42

2019-03-06T21:15:45.916Z,1551906945.916 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux2_rom_boot_search,0*5A

2019-03-06T21:15:46.299Z,1551906946.299 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux3_rom_valid,0*6A

2019-03-06T21:15:46.304Z,1551906946.304 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux3_rom,0*43

2019-03-06T21:15:46.653Z,1551906946.653 [CommandLine](IMPORTANT): got command 
2019-03-06T21:15:46.653Z,1551906946.653 [CommandLine](FAULT): Incomplete syntax. Try: help
2019-03-06T21:15:46.700Z,1551906946.700 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux3_rom_boot_search,0*5B

2019-03-06T21:15:46.705Z,1551906946.705 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux4_rom_valid,0*6D

2019-03-06T21:15:47.102Z,1551906947.102 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux4_rom,0*44

2019-03-06T21:15:47.107Z,1551906947.107 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux4_rom_boot_search,0*5C

2019-03-06T21:15:47.507Z,1551906947.507 [Micromodem](INFO): Nmea in: $CACFG,pwramp.temperature_degC,18.437500*53

2019-03-06T21:15:47.511Z,1551906947.511 [Micromodem](INFO): Nmea in: $CACFG,pwramp.vbat,24.136003*49

2019-03-06T21:15:47.906Z,1551906947.906 [Micromodem](INFO): Nmea in: $CACFG,pwramp.vtransmit,0.000000*05

2019-03-06T21:15:47.911Z,1551906947.911 [Micromodem](INFO): Nmea in: $CACFG,pwramp.iout_sense_amps,0.000000*1F

2019-03-06T21:15:48.321Z,1551906948.321 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,3*2E

2019-03-06T21:15:48.325Z,1551906948.325 [Micromodem](INFO): Nmea in: $CACFG,pwramp.stackpower_conf,1*1C

2019-03-06T21:15:48.721Z,1551906948.721 [Micromodem](INFO): Nmea in: $CACFG,pwramp.VPS_on,1*5D

2019-03-06T21:15:48.724Z,1551906948.724 [Micromodem](INFO): Nmea in: $CACFG,pwramp.gpio5,3*70

2019-03-06T21:15:48.843Z,1551906948.843 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:15:48.843Z,1551906948.843 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:15:48.843Z,1551906948.843 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-03-06T21:15:48.843Z,1551906948.843 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2019-03-06T21:15:49.120Z,1551906949.120 [Micromodem](INFO): Nmea in: $CACFG,pwramp.gpio6,3*73

2019-03-06T21:15:49.123Z,1551906949.123 [Micromodem](INFO): Nmea in: $CACFG,pwramp.fault,0*3D

2019-03-06T21:15:49.524Z,1551906949.524 [Micromodem](INFO): Nmea in: $CACFG,script.active,0*5D

2019-03-06T21:15:49.527Z,1551906949.527 [Micromodem](INFO): Nmea in: $CACFG,script.comports,15*6E

2019-03-06T21:15:49.928Z,1551906949.928 [Micromodem](INFO): Nmea in: $CACFG,script.v0,0*17

2019-03-06T21:15:49.930Z,1551906949.930 [Micromodem](INFO): Nmea in: $CACFG,script.v1,0*16

2019-03-06T21:15:50.328Z,1551906950.328 [Micromodem](INFO): Nmea in: $CACFG,script.v2,0*15

2019-03-06T21:15:50.330Z,1551906950.330 [Micromodem](INFO): Nmea in: $CACFG,script.v3,0*14

2019-03-06T21:15:50.740Z,1551906950.740 [Micromodem](INFO): Nmea in: $CACFG,script.v4,0*13

2019-03-06T21:15:50.742Z,1551906950.742 [Micromodem](INFO): Nmea in: $CACFG,script.v5,0*12

2019-03-06T21:15:51.144Z,1551906951.144 [Micromodem](INFO): Nmea in: $CACFG,script.v6,0*11

2019-03-06T21:15:51.146Z,1551906951.146 [Micromodem](INFO): Nmea in: $CACFG,script.v7,0*10

2019-03-06T21:15:51.544Z,1551906951.544 [Micromodem](INFO): Nmea in: $CACFG,script.v8,0*1F

2019-03-06T21:15:51.545Z,1551906951.545 [Micromodem](INFO): Nmea in: $CACFG,script.v9,0*1E

2019-03-06T21:15:51.952Z,1551906951.952 [Micromodem](INFO): Nmea in: $CACFG,script.vA,0*66

2019-03-06T21:15:51.953Z,1551906951.953 [Micromodem](INFO): Nmea in: $CACFG,script.vB,0*65

2019-03-06T21:15:52.351Z,1551906952.351 [Micromodem](INFO): Nmea in: $CACFG,script.vC,0*64

2019-03-06T21:15:52.353Z,1551906952.353 [Micromodem](INFO): Nmea in: $CACFG,script.vD,0*63

2019-03-06T21:15:52.759Z,1551906952.759 [Micromodem](INFO): Nmea in: $CACFG,script.vE,0*62

2019-03-06T21:15:52.761Z,1551906952.761 [Micromodem](INFO): Nmea in: $CACFG,script.vF,0*61

2019-03-06T21:15:53.164Z,1551906953.164 [Micromodem](INFO): Nmea in: $CAREV,211503,AUV,2.0.27690*11

2019-03-06T21:15:53.166Z,1551906953.166 [Micromodem](INFO): Nmea in: $CAREV,211503,COPROC,0.10.0.46*44

2019-03-06T21:15:53.564Z,1551906953.564 [Micromodem](INFO): Nmea in: $CAREV,211513,AUV,2.0.27690*10

2019-03-06T21:15:53.566Z,1551906953.566 [Micromodem](INFO): Nmea in: $CAREV,211513,COPROC,0.10.0.46*45

2019-03-06T21:15:53.968Z,1551906953.968 [Micromodem](INFO): Nmea in: $CAREV,211523,AUV,2.0.27690*13

2019-03-06T21:15:53.969Z,1551906953.969 [Micromodem](INFO): Nmea in: $CAREV,211523,COPROC,0.10.0.46*46

2019-03-06T21:15:54.372Z,1551906954.372 [Micromodem](INFO): Nmea in: $CAREV,211533,AUV,2.0.27690*12

2019-03-06T21:15:54.373Z,1551906954.373 [Micromodem](INFO): Nmea in: $CAREV,211533,COPROC,0.10.0.46*47

2019-03-06T21:15:54.775Z,1551906954.775 [Micromodem](INFO): Nmea in: $CAREV,211543,AUV,2.0.27690*15

2019-03-06T21:15:54.777Z,1551906954.777 [Micromodem](INFO): Nmea in: $CAREV,211543,COPROC,0.10.0.46*40

2019-03-06T21:15:55.179Z,1551906955.179 [Micromodem](INFO): Nmea in: $CAREV,211553,AUV,2.0.27690*14

2019-03-06T21:16:04.071Z,1551906964.071 [Micromodem](INFO): Nmea in: $CAREV,211553,COPROC,0.10.0.46*41

2019-03-06T21:16:04.072Z,1551906964.072 [Micromodem](INFO): Nmea in: $CAREV,211603,AUV,2.0.27690*12

2019-03-06T21:16:06.691Z,1551906966.691 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:16:06.691Z,1551906966.691 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:16:06.691Z,1551906966.691 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-03-06T21:16:06.691Z,1551906966.691 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2019-03-06T21:16:08.956Z,1551906968.956 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:16:08.956Z,1551906968.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-06T21:16:08.956Z,1551906968.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T21:16:08.958Z,1551906968.958 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:16:08.958Z,1551906968.958 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-06T21:16:08.958Z,1551906968.958 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T21:16:08.959Z,1551906968.959 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:16:08.959Z,1551906968.959 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-06T21:16:08.960Z,1551906968.960 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T21:16:08.971Z,1551906968.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T21:16:08.971Z,1551906968.971 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T21:16:08.971Z,1551906968.971 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T21:16:09.374Z,1551906969.374 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T21:16:09.374Z,1551906969.374 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-06T21:16:09.374Z,1551906969.374 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T21:16:09.374Z,1551906969.374 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-06T21:16:09.375Z,1551906969.375 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T21:16:09.375Z,1551906969.375 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-06T21:16:09.750Z,1551906969.750 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:16:14.171Z,1551906974.171 [Micromodem](INFO): Nmea in: $CAREV,211603,COPROC,0.10.0.46*47

2019-03-06T21:16:14.173Z,1551906974.173 [Micromodem](INFO): Nmea in: $CAREV,211613,AUV,2.0.27690*13

2019-03-06T21:16:23.868Z,1551906983.868 [Micromodem](INFO): Nmea in: $CAREV,211613,COPROC,0.10.0.46*46

2019-03-06T21:16:24.267Z,1551906984.267 [Micromodem](INFO): Nmea in: $CAREV,211623,AUV,2.0.27690*10

2019-03-06T21:16:31.161Z,1551906991.161 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-06T21:15:31.0Z
2019-03-06T21:16:31.161Z,1551906991.161 [Startup:StartupSatComms:A] Stopped
2019-03-06T21:16:31.161Z,1551906991.161 [Startup:StartupSatComms:B] Running Loop=1
2019-03-06T21:16:31.583Z,1551906991.583 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-06T21:16:34.075Z,1551906994.075 [Micromodem](INFO): Nmea in: $CAREV,211623,COPROC,0.10.0.46*45

2019-03-06T21:16:34.076Z,1551906994.076 [Micromodem](INFO): Nmea in: $CAREV,211633,AUV,2.0.27690*11

2019-03-06T21:16:34.950Z,1551906994.950 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190228T224651/Courier0004.lzma
2019-03-06T21:16:35.734Z,1551906995.734 [DataOverHttps](INFO): Moved sent file to Logs/20190228T224651/Courier0004.lzma.bak
2019-03-06T21:16:35.735Z,1551906995.735 [DataOverHttps](INFO): SBD MOMSN=10089292
2019-03-06T21:16:44.175Z,1551907004.175 [Micromodem](INFO): Nmea in: $CAREV,211633,COPROC,0.10.0.46*44

2019-03-06T21:16:44.176Z,1551907004.176 [Micromodem](INFO): Nmea in: $CAREV,211643,AUV,2.0.27690*16

2019-03-06T21:16:46.212Z,1551907006.212 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20190306T211357/Courier0000.lzma
2019-03-06T21:16:47.019Z,1551907007.019 [DataOverHttps](INFO): Moved sent file to Logs/20190306T211357/Courier0000.lzma.bak
2019-03-06T21:16:47.019Z,1551907007.019 [DataOverHttps](INFO): SBD MOMSN=10089294
2019-03-06T21:16:53.915Z,1551907013.915 [Micromodem](INFO): Nmea in: $CAREV,211643,COPROC,0.10.0.46*43

2019-03-06T21:16:53.917Z,1551907013.917 [Micromodem](INFO): Nmea in: $CAREV,211653,AUV,2.0.27690*17

2019-03-06T21:16:57.422Z,1551907017.422 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20190228T224651/Express0005.lzma
2019-03-06T21:16:58.226Z,1551907018.226 [DataOverHttps](INFO): Moved sent file to Logs/20190228T224651/Express0005.lzma.bak
2019-03-06T21:16:58.227Z,1551907018.227 [DataOverHttps](INFO): SBD MOMSN=10089299
2019-03-06T21:17:03.975Z,1551907023.975 [Micromodem](INFO): Nmea in: $CAREV,211653,COPROC,0.10.0.46*42

2019-03-06T21:17:03.976Z,1551907023.976 [Micromodem](INFO): Nmea in: $CAREV,211703,AUV,2.0.27690*13

2019-03-06T21:17:08.642Z,1551907028.642 [DataOverHttps](INFO): Sending 1032 bytes from file Logs/20190306T211357/Express0001.lzma
2019-03-06T21:17:09.446Z,1551907029.446 [DataOverHttps](INFO): Moved sent file to Logs/20190306T211357/Express0001.lzma.bak
2019-03-06T21:17:09.447Z,1551907029.447 [DataOverHttps](INFO): SBD MOMSN=10089311
2019-03-06T21:17:10.056Z,1551907030.056 [Startup:StartupSatComms:B] Stopped
2019-03-06T21:17:10.056Z,1551907030.056 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-06T21:17:10.056Z,1551907030.056 [Startup:StartupSatComms] Stopped
2019-03-06T21:17:10.056Z,1551907030.056 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-06T21:17:10.057Z,1551907030.057 [Startup](INFO): Completed Startup
2019-03-06T21:17:10.057Z,1551907030.057 [MissionManager](INFO): Startup is completed.
2019-03-06T21:17:10.058Z,1551907030.058 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-06T21:17:10.058Z,1551907030.058 [Startup] Stopped
2019-03-06T21:17:10.058Z,1551907030.058 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-06T21:17:10.058Z,1551907030.058 [Startup:A.GoToSurface] Stopped
2019-03-06T21:17:10.058Z,1551907030.058 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-06T21:17:10.467Z,1551907030.467 [MissionManager](IMPORTANT): Started mission Default
2019-03-06T21:17:10.468Z,1551907030.468 [Default] Running Loop=1
2019-03-06T21:17:10.468Z,1551907030.468 [Default](DEBUG): Aggregate::initialize Default
2019-03-06T21:17:10.468Z,1551907030.468 [Default:B.GoToSurface] Running Loop=1
2019-03-06T21:17:10.468Z,1551907030.468 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-06T21:17:10.468Z,1551907030.468 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-06T21:17:10.469Z,1551907030.469 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-06T21:17:10.469Z,1551907030.469 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-06T21:17:10.469Z,1551907030.469 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-06T21:17:10.470Z,1551907030.470 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-06T21:17:10.470Z,1551907030.470 [Default:A.Wait] Running Loop=1
2019-03-06T21:17:10.470Z,1551907030.470 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:17:14.071Z,1551907034.071 [Micromodem](INFO): Nmea in: $CAREV,211703,COPROC,0.10.0.46*46

2019-03-06T21:17:14.072Z,1551907034.072 [Micromodem](INFO): Nmea in: $CAREV,211713,AUV,2.0.27690*12

2019-03-06T21:17:18.807Z,1551907038.807 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:17:18.807Z,1551907038.807 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:17:18.807Z,1551907038.807 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-03-06T21:17:18.807Z,1551907038.807 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2019-03-06T21:17:22.965Z,1551907042.965 [NAL9602](INFO): SBD MO Status=2, MOMSN=2145, MT Status=2, MTMSN=0
2019-03-06T21:17:22.965Z,1551907042.965 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-06T21:17:23.796Z,1551907043.796 [Default:A.Wait](INFO): Done Waiting.
2019-03-06T21:17:23.796Z,1551907043.796 [Default:A.Wait] Stopped
2019-03-06T21:17:23.796Z,1551907043.796 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:17:24.171Z,1551907044.171 [Micromodem](INFO): Nmea in: $CAREV,211713,COPROC,0.10.0.46*47

2019-03-06T21:17:24.173Z,1551907044.173 [Micromodem](INFO): Nmea in: $CAREV,211723,AUV,2.0.27690*11

2019-03-06T21:17:24.198Z,1551907044.198 [Default:CheckIn] Running Loop=1
2019-03-06T21:17:24.198Z,1551907044.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-06T21:17:24.198Z,1551907044.198 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-06T21:17:24.603Z,1551907044.603 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-06T21:17:34.275Z,1551907054.275 [Micromodem](INFO): Nmea in: $CAREV,211723,COPROC,0.10.0.46*44

2019-03-06T21:17:34.276Z,1551907054.276 [Micromodem](INFO): Nmea in: $CAREV,211733,AUV,2.0.27690*10

2019-03-06T21:17:38.721Z,1551907058.721 [NAL9602](INFO): SBD MO Status=2, MOMSN=2145, MT Status=2, MTMSN=0
2019-03-06T21:17:38.721Z,1551907058.721 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-06T21:17:43.971Z,1551907063.971 [Micromodem](INFO): Nmea in: $CAREV,211733,COPROC,0.10.0.46*45

2019-03-06T21:17:43.972Z,1551907063.972 [Micromodem](INFO): Nmea in: $CAREV,211743,AUV,2.0.27690*17

2019-03-06T21:17:54.067Z,1551907074.067 [Micromodem](INFO): Nmea in: $CAREV,211743,COPROC,0.10.0.46*42

2019-03-06T21:17:54.068Z,1551907074.068 [Micromodem](INFO): Nmea in: $CAREV,211753,AUV,2.0.27690*16

2019-03-06T21:18:01.340Z,1551907081.340 [NAL9602](INFO): SBD MO Status=2, MOMSN=2145, MT Status=2, MTMSN=0
2019-03-06T21:18:01.340Z,1551907081.340 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-06T21:18:04.171Z,1551907084.171 [Micromodem](INFO): Nmea in: $CAREV,211753,COPROC,0.10.0.46*43

2019-03-06T21:18:04.173Z,1551907084.173 [Micromodem](INFO): Nmea in: $CAREV,211803,AUV,2.0.27690*1C

2019-03-06T21:18:09.851Z,1551907089.851 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:18:09.851Z,1551907089.851 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-06T21:18:09.851Z,1551907089.851 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T21:18:09.852Z,1551907089.852 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:18:09.852Z,1551907089.852 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-06T21:18:09.852Z,1551907089.852 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T21:18:09.854Z,1551907089.854 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:18:09.854Z,1551907089.854 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-06T21:18:09.854Z,1551907089.854 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T21:18:09.865Z,1551907089.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T21:18:09.866Z,1551907089.866 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T21:18:09.866Z,1551907089.866 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T21:18:10.282Z,1551907090.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T21:18:10.282Z,1551907090.282 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-06T21:18:10.282Z,1551907090.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T21:18:10.282Z,1551907090.282 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-06T21:18:10.283Z,1551907090.283 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T21:18:10.283Z,1551907090.283 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-06T21:18:10.656Z,1551907090.656 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:18:14.271Z,1551907094.271 [Micromodem](INFO): Nmea in: $CAREV,211803,COPROC,0.10.0.46*49

2019-03-06T21:18:14.272Z,1551907094.272 [Micromodem](INFO): Nmea in: $CAREV,211813,AUV,2.0.27690*1D

2019-03-06T21:18:16.119Z,1551907096.119 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:18:16.119Z,1551907096.119 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:18:16.119Z,1551907096.119 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-03-06T21:18:16.119Z,1551907096.119 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2019-03-06T21:18:20.365Z,1551907100.365 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-03-06T21:18:20.365Z,1551907100.365 [RDI_Pathfinder] No Fault, FailCount= 3
2019-03-06T21:18:23.963Z,1551907103.963 [Micromodem](INFO): Nmea in: $CAREV,211813,COPROC,0.10.0.46*48

2019-03-06T21:18:23.964Z,1551907103.964 [Micromodem](INFO): Nmea in: $CAREV,211823,AUV,2.0.27690*1E

2019-03-06T21:18:33.274Z,1551907113.274 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2019-03-06T21:18:33.274Z,1551907113.274 [RDI_Pathfinder] Data Fault, FailCount= 1
2019-03-06T21:18:33.274Z,1551907113.274 [RDI_Pathfinder](ERROR): Data Fault
2019-03-06T21:18:33.291Z,1551907113.291 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2019-03-06T21:18:33.749Z,1551907113.749 [RDI_Pathfinder](INFO): Powering down
2019-03-06T21:18:34.067Z,1551907114.067 [Micromodem](INFO): Nmea in: $CAREV,211823,COPROC,0.10.0.46*4B

2019-03-06T21:18:34.068Z,1551907114.068 [Micromodem](INFO): Nmea in: $CAREV,211833,AUV,2.0.27690*1F

2019-03-06T21:18:34.513Z,1551907114.513 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-03-06T21:18:34.514Z,1551907114.514 [RDI_Pathfinder] No Fault, FailCount= 1
2019-03-06T21:18:44.163Z,1551907124.163 [Micromodem](INFO): Nmea in: $CAREV,211833,COPROC,0.10.0.46*4A

2019-03-06T21:18:44.165Z,1551907124.165 [Micromodem](INFO): Nmea in: $CAREV,211843,AUV,2.0.27690*18

2019-03-06T21:18:54.265Z,1551907134.265 [Micromodem](INFO): Nmea in: $CAREV,211843,COPROC,0.10.0.46*4D

2019-03-06T21:18:54.266Z,1551907134.266 [Micromodem](INFO): Nmea in: $CAREV,211853,AUV,2.0.27690*19

2019-03-06T21:18:55.091Z,1551907135.091 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   +10,    -7,    +4,-32768,A
2019-03-06T21:18:55.902Z,1551907135.902 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,   +14,   -13,    +6,-32768,A
2019-03-06T21:18:57.106Z,1551907137.106 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-03-06T21:18:57.106Z,1551907137.106 [RDI_Pathfinder] Communications Fault, FailCount= 2
2019-03-06T21:18:57.106Z,1551907137.106 [RDI_Pathfinder](ERROR): Communications Fault
2019-03-06T21:18:57.128Z,1551907137.128 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-03-06T21:18:57.581Z,1551907137.581 [RDI_Pathfinder](INFO): Powering down
2019-03-06T21:18:58.353Z,1551907138.353 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2019-03-06T21:18:58.353Z,1551907138.353 [RDI_Pathfinder] No Fault, FailCount= 2
2019-03-06T21:19:03.963Z,1551907143.963 [Micromodem](INFO): Nmea in: $CAREV,211853,COPROC,0.10.0.46*4C

2019-03-06T21:19:03.964Z,1551907143.964 [Micromodem](INFO): Nmea in: $CAREV,211903,AUV,2.0.27690*1D

2019-03-06T21:19:14.059Z,1551907154.059 [Micromodem](INFO): Nmea in: $CAREV,211903,COPROC,0.10.0.46*48

2019-03-06T21:19:14.060Z,1551907154.060 [Micromodem](INFO): Nmea in: $CAREV,211913,AUV,2.0.27690*1C

2019-03-06T21:19:14.465Z,1551907154.465 [NAL9602](INFO): SBD MO Status=2, MOMSN=2145, MT Status=2, MTMSN=0
2019-03-06T21:19:14.465Z,1551907154.465 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-06T21:19:20.942Z,1551907160.942 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2019-03-06T21:19:20.942Z,1551907160.942 [RDI_Pathfinder] Communications Fault, FailCount= 3
2019-03-06T21:19:20.942Z,1551907160.942 [RDI_Pathfinder](ERROR): Communications Fault
2019-03-06T21:19:20.963Z,1551907160.963 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2019-03-06T21:19:20.964Z,1551907160.964 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2019-03-06T21:19:21.349Z,1551907161.349 [RDI_Pathfinder](INFO): Powering down
2019-03-06T21:19:21.369Z,1551907161.369 [CBIT](INFO): Critical error at 20190306T211920
2019-03-06T21:19:24.163Z,1551907164.163 [Micromodem](INFO): Nmea in: $CAREV,211913,COPROC,0.10.0.46*49

2019-03-06T21:19:24.165Z,1551907164.165 [Micromodem](INFO): Nmea in: $CAREV,211923,AUV,2.0.27690*1F

2019-03-06T21:19:27.594Z,1551907167.594 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:19:27.594Z,1551907167.594 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:19:27.594Z,1551907167.594 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-03-06T21:19:27.594Z,1551907167.594 [CommandLine](IMPORTANT): RDI_Pathfinder: Communications Fault
2019-03-06T21:19:32.850Z,1551907172.850 [CommandLine](IMPORTANT): got command failComponent none RDI_Pathfinder
2019-03-06T21:19:32.850Z,1551907172.850 [RDI_Pathfinder] No Fault, FailCount= 3
2019-03-06T21:19:32.850Z,1551907172.850 [CommandLine](IMPORTANT): RDI_Pathfinder failureMode is No Fault
2019-03-06T21:19:34.259Z,1551907174.259 [Micromodem](INFO): Nmea in: $CAREV,211923,COPROC,0.10.0.46*4A

2019-03-06T21:19:34.260Z,1551907174.260 [Micromodem](INFO): Nmea in: $CAREV,211933,AUV,2.0.27690*1E

2019-03-06T21:19:43.959Z,1551907183.959 [Micromodem](INFO): Nmea in: $CAREV,211933,COPROC,0.10.0.46*4B

2019-03-06T21:19:43.960Z,1551907183.960 [Micromodem](INFO): Nmea in: $CAREV,211943,AUV,2.0.27690*19

2019-03-06T21:19:46.398Z,1551907186.398 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-06T21:19:48.000Z,1551907188.000 [NAL9602](INFO): SBD MO Status=0, MOMSN=2145, MT Status=0, MTMSN=0
2019-03-06T21:19:48.000Z,1551907188.000 [NAL9602](INFO): No messages in MT queue
2019-03-06T21:19:48.424Z,1551907188.424 [RDI_Pathfinder](INFO): Failure count cleared after critical for RDI_Pathfinder
2019-03-06T21:19:48.817Z,1551907188.817 [NAL9602](DEBUG): Fix Requested
2019-03-06T21:19:51.645Z,1551907191.645 [NAL9602](DEBUG): Fix Requested
2019-03-06T21:19:54.059Z,1551907194.059 [Micromodem](INFO): Nmea in: $CAREV,211943,COPROC,0.10.0.46*4C

2019-03-06T21:19:54.060Z,1551907194.060 [Micromodem](INFO): Nmea in: $CAREV,211953,AUV,2.0.27690*18

2019-03-06T21:19:54.877Z,1551907194.877 [NAL9602](DEBUG): Fix Requested
2019-03-06T21:19:57.705Z,1551907197.705 [NAL9602](DEBUG): Fix Requested
2019-03-06T21:19:58.101Z,1551907198.101 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211717.00,A,3648.17109,N,12147.29403,W,1.108,144.42,060319,,,A*77
2019-03-06T21:19:58.105Z,1551907198.105 [NAL9602](INFO): GPS fix at 20190306T211717: (36.802852, -121.788234)
2019-03-06T21:19:58.134Z,1551907198.134 [Default:CheckIn:Read_GPS] Stopped
2019-03-06T21:19:58.134Z,1551907198.134 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-06T21:19:58.651Z,1551907198.651 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-06T21:20:02.574Z,1551907202.574 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:20:02.574Z,1551907202.574 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:20:02.574Z,1551907202.574 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-03-06T21:20:04.151Z,1551907204.151 [Micromodem](INFO): Nmea in: $CAREV,211953,COPROC,0.10.0.46*4D

2019-03-06T21:20:04.152Z,1551907204.152 [Micromodem](INFO): Nmea in: $CAREV,212003,AUV,2.0.27690*17

2019-03-06T21:20:06.717Z,1551907206.717 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20190306T211357/Courier0004.lzma
2019-03-06T21:20:07.363Z,1551907207.363 [CommandLine](IMPORTANT): got command show best height_above_sea_floor
2019-03-06T21:20:07.363Z,1551907207.363 [CommandLine](IMPORTANT): height_above_sea_floor best is RDI_Pathfinder.height_above_sea_floor
2019-03-06T21:20:07.522Z,1551907207.522 [DataOverHttps](INFO): Moved sent file to Logs/20190306T211357/Courier0004.lzma.bak
2019-03-06T21:20:07.522Z,1551907207.522 [DataOverHttps](INFO): SBD MOMSN=10089355
2019-03-06T21:20:11.040Z,1551907211.040 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:20:11.040Z,1551907211.040 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-06T21:20:11.040Z,1551907211.040 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T21:20:11.042Z,1551907211.042 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:20:11.042Z,1551907211.042 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-06T21:20:11.043Z,1551907211.043 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T21:20:11.044Z,1551907211.044 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:20:11.044Z,1551907211.044 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-06T21:20:11.044Z,1551907211.044 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T21:20:11.073Z,1551907211.073 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T21:20:11.073Z,1551907211.073 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T21:20:11.073Z,1551907211.073 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T21:20:11.479Z,1551907211.479 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T21:20:11.479Z,1551907211.479 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-06T21:20:11.479Z,1551907211.479 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T21:20:11.479Z,1551907211.479 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-06T21:20:11.480Z,1551907211.480 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T21:20:11.480Z,1551907211.480 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-06T21:20:11.962Z,1551907211.962 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:20:14.256Z,1551907214.256 [Micromodem](INFO): Nmea in: $CAREV,212003,COPROC,0.10.0.46*42

2019-03-06T21:20:14.257Z,1551907214.257 [Micromodem](INFO): Nmea in: $CAREV,212013,AUV,2.0.27690*16

2019-03-06T21:20:17.367Z,1551907217.367 [CommandLine](IMPORTANT): got command get height_above_sea_floor
2019-03-06T21:20:17.367Z,1551907217.367 [CommandLine](IMPORTANT): height_above_sea_floor 9.510000 m
2019-03-06T21:20:19.889Z,1551907219.889 [DataOverHttps](INFO): Sending 500 bytes from file Logs/20190306T211357/Express0005.lzma
2019-03-06T21:20:20.694Z,1551907220.694 [DataOverHttps](INFO): Moved sent file to Logs/20190306T211357/Express0005.lzma.bak
2019-03-06T21:20:20.694Z,1551907220.694 [DataOverHttps](INFO): SBD MOMSN=10089359
2019-03-06T21:20:21.141Z,1551907221.141 [Default:CheckIn:Read_Iridium] Stopped
2019-03-06T21:20:21.141Z,1551907221.141 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-06T21:20:21.141Z,1551907221.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:20:23.947Z,1551907223.947 [Micromodem](INFO): Nmea in: $CAREV,212013,COPROC,0.10.0.46*43

2019-03-06T21:20:23.948Z,1551907223.948 [Micromodem](INFO): Nmea in: $CAREV,212023,AUV,2.0.27690*15

2019-03-06T21:20:24.095Z,1551907224.095 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-06T21:20:24.330Z,1551907224.330 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-06T21:20:24.331Z,1551907224.331 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-06T21:20:24.331Z,1551907224.331 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-06T21:20:24.331Z,1551907224.331 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-06T21:20:24.332Z,1551907224.332 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-06T21:20:24.332Z,1551907224.332 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-06T21:20:24.333Z,1551907224.333 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-06T21:20:24.405Z,1551907224.405 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-06T21:20:24.406Z,1551907224.406 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-06T21:20:24.406Z,1551907224.406 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-06T21:20:24.406Z,1551907224.406 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-06T21:20:24.407Z,1551907224.407 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-06T21:20:24.459Z,1551907224.459 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-06T21:20:24.459Z,1551907224.459 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-06T21:20:24.460Z,1551907224.460 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-06T21:20:24.472Z,1551907224.472 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-06T21:20:24.481Z,1551907224.481 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-06T21:20:24.481Z,1551907224.481 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-06T21:20:24.482Z,1551907224.482 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-06T21:20:24.482Z,1551907224.482 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-06T21:20:24.482Z,1551907224.482 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-06T21:20:24.483Z,1551907224.483 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-06T21:20:24.483Z,1551907224.483 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-06T21:20:24.483Z,1551907224.483 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-06T21:20:24.484Z,1551907224.484 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-06T21:20:30.045Z,1551907230.045 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:20:30.046Z,1551907230.046 [AHRS_M2] No Fault, FailCount= 5
2019-03-06T21:20:30.405Z,1551907230.405 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:20:30.418Z,1551907230.418 [NAL9602](INFO): Powering down
2019-03-06T21:20:33.961Z,1551907233.961 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:33.983Z,1551907233.983 [Micromodem](INFO): Nmea in: $CAREV,212023,COPROC,0.10.0.46*40

2019-03-06T21:20:33.984Z,1551907233.984 [Micromodem](INFO): Nmea in: $CAREV,212033,AUV,2.0.27690*14

2019-03-06T21:20:34.357Z,1551907234.357 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:34.761Z,1551907234.761 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:35.165Z,1551907235.165 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:35.577Z,1551907235.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:35.985Z,1551907235.985 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:36.389Z,1551907236.389 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:36.797Z,1551907236.797 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:37.218Z,1551907237.218 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:37.637Z,1551907237.637 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:38.041Z,1551907238.041 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:38.449Z,1551907238.449 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:38.893Z,1551907238.893 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:39.301Z,1551907239.301 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:39.729Z,1551907239.729 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:40.129Z,1551907240.129 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:40.597Z,1551907240.597 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:40.997Z,1551907240.997 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:41.401Z,1551907241.401 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:41.837Z,1551907241.837 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:42.225Z,1551907242.225 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:42.374Z,1551907242.374 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.range
2019-03-06T21:20:42.633Z,1551907242.633 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:43.041Z,1551907243.041 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:43.453Z,1551907243.453 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:43.453Z,1551907243.453 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:20:43.453Z,1551907243.453 [AHRS_M2] Communications Fault, FailCount= 1
2019-03-06T21:20:43.453Z,1551907243.453 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:20:43.503Z,1551907243.503 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:20:43.554Z,1551907243.554 [AHRS_M2](INFO): Powering down
2019-03-06T21:20:43.942Z,1551907243.942 [Micromodem](INFO): Nmea in: $CAREV,212033,COPROC,0.10.0.46*41

2019-03-06T21:20:43.943Z,1551907243.943 [Micromodem](INFO): Nmea in: $CAREV,212043,AUV,2.0.27690*13

2019-03-06T21:20:44.392Z,1551907244.392 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:20:44.392Z,1551907244.392 [AHRS_M2] No Fault, FailCount= 1
2019-03-06T21:20:44.737Z,1551907244.737 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:20:48.293Z,1551907248.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:48.693Z,1551907248.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:49.101Z,1551907249.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:49.505Z,1551907249.505 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:49.534Z,1551907249.534 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-06T21:20:49.636Z,1551907249.636 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-06T21:20:49.643Z,1551907249.643 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-06T21:20:49.644Z,1551907249.644 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-06T21:20:49.644Z,1551907249.644 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-06T21:20:49.644Z,1551907249.644 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-06T21:20:49.649Z,1551907249.649 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-06T21:20:49.649Z,1551907249.649 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-06T21:20:49.650Z,1551907249.650 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-06T21:20:49.650Z,1551907249.650 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-06T21:20:49.650Z,1551907249.650 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-06T21:20:49.651Z,1551907249.651 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-06T21:20:49.652Z,1551907249.652 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-06T21:20:49.685Z,1551907249.685 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-06T21:20:49.686Z,1551907249.686 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-06T21:20:49.686Z,1551907249.686 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-06T21:20:49.706Z,1551907249.706 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-06T21:20:49.706Z,1551907249.706 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-06T21:20:49.707Z,1551907249.707 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-06T21:20:49.707Z,1551907249.707 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-06T21:20:49.707Z,1551907249.707 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-06T21:20:49.708Z,1551907249.708 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-06T21:20:49.708Z,1551907249.708 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-06T21:20:49.708Z,1551907249.708 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-06T21:20:49.709Z,1551907249.709 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-06T21:20:49.709Z,1551907249.709 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-06T21:20:49.969Z,1551907249.969 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:50.357Z,1551907250.357 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:50.765Z,1551907250.765 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:51.169Z,1551907251.169 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:51.605Z,1551907251.605 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:52.005Z,1551907252.005 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:52.421Z,1551907252.421 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:52.873Z,1551907252.873 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:53.281Z,1551907253.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:53.697Z,1551907253.697 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:54.181Z,1551907254.181 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:54.191Z,1551907254.191 [Micromodem](INFO): Nmea in: $CAREV,212043,COPROC,0.10.0.46*46

2019-03-06T21:20:54.192Z,1551907254.192 [Micromodem](INFO): Nmea in: $CAREV,212053,AUV,2.0.27690*12

2019-03-06T21:20:54.621Z,1551907254.621 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:55.021Z,1551907255.021 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:55.433Z,1551907255.433 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:55.849Z,1551907255.849 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:56.249Z,1551907256.249 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:56.637Z,1551907256.637 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:57.049Z,1551907257.049 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:57.445Z,1551907257.445 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:57.865Z,1551907257.865 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:20:57.865Z,1551907257.865 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:20:57.865Z,1551907257.865 [AHRS_M2] Communications Fault, FailCount= 2
2019-03-06T21:20:57.865Z,1551907257.865 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:20:57.958Z,1551907257.958 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:20:57.967Z,1551907257.967 [AHRS_M2](INFO): Powering down
2019-03-06T21:20:58.818Z,1551907258.818 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:20:58.818Z,1551907258.818 [AHRS_M2] No Fault, FailCount= 2
2019-03-06T21:20:59.173Z,1551907259.173 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:21:01.755Z,1551907261.755 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.direction
2019-03-06T21:21:02.729Z,1551907262.729 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:03.145Z,1551907263.145 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:03.557Z,1551907263.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:03.949Z,1551907263.949 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:03.959Z,1551907263.959 [Micromodem](INFO): Nmea in: $CAREV,212053,COPROC,0.10.0.46*47

2019-03-06T21:21:03.960Z,1551907263.960 [Micromodem](INFO): Nmea in: $CAREV,212103,AUV,2.0.27690*16

2019-03-06T21:21:04.333Z,1551907264.333 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:04.737Z,1551907264.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:05.145Z,1551907265.145 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:05.565Z,1551907265.565 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:06.025Z,1551907266.025 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:06.405Z,1551907266.405 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:06.805Z,1551907266.805 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:07.213Z,1551907267.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:07.613Z,1551907267.613 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:08.045Z,1551907268.045 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:08.449Z,1551907268.449 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:08.865Z,1551907268.865 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:09.277Z,1551907269.277 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:09.689Z,1551907269.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:10.089Z,1551907270.089 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:10.481Z,1551907270.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:10.881Z,1551907270.881 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:11.289Z,1551907271.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:11.693Z,1551907271.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:12.101Z,1551907272.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:12.101Z,1551907272.101 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:21:12.101Z,1551907272.101 [AHRS_M2] Communications Fault, FailCount= 3
2019-03-06T21:21:12.101Z,1551907272.101 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:21:12.182Z,1551907272.182 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:21:12.191Z,1551907272.191 [AHRS_M2](INFO): Powering down
2019-03-06T21:21:13.037Z,1551907273.037 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:21:13.037Z,1551907273.037 [AHRS_M2] No Fault, FailCount= 3
2019-03-06T21:21:13.286Z,1551907273.286 [CommandLine](IMPORTANT): got command gfscan
2019-03-06T21:21:13.397Z,1551907273.397 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:21:13.452Z,1551907273.452 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-06T21:21:14.211Z,1551907274.211 [Micromodem](INFO): Nmea in: $CAREV,212103,COPROC,0.10.0.46*43

2019-03-06T21:21:14.215Z,1551907274.215 [Micromodem](INFO): Nmea in: $CAREV,212113,AUV,2.0.27690*17

2019-03-06T21:21:16.953Z,1551907276.953 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:17.389Z,1551907277.389 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:17.853Z,1551907277.853 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:18.249Z,1551907278.249 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:18.661Z,1551907278.661 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:19.133Z,1551907279.133 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:19.629Z,1551907279.629 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:20.017Z,1551907280.017 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:20.425Z,1551907280.425 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:20.921Z,1551907280.921 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:21.361Z,1551907281.361 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:21.789Z,1551907281.789 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:22.273Z,1551907282.273 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:22.709Z,1551907282.709 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:23.105Z,1551907283.105 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:23.561Z,1551907283.561 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:23.953Z,1551907283.953 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:23.963Z,1551907283.963 [Micromodem](INFO): Nmea in: $CAREV,212113,COPROC,0.10.0.46*42

2019-03-06T21:21:23.964Z,1551907283.964 [Micromodem](INFO): Nmea in: $CAREV,212123,AUV,2.0.27690*14

2019-03-06T21:21:24.373Z,1551907284.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:24.869Z,1551907284.869 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:24.916Z,1551907284.916 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006601
CHAN A1 (24V): -0.025477
CHAN A2 (12V): -0.007517
CHAN A3 (5V): -0.003178
CHAN B0 (3.3V): -0.000698
CHAN B1 (3.15aV): -0.000551
CHAN B2 (3.15bV): 0.000396
CHAN B3 (GND): 0.000670
OPEN: 0.009437
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-06T21:21:25.293Z,1551907285.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:25.689Z,1551907285.689 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:26.081Z,1551907286.081 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:26.485Z,1551907286.485 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:26.485Z,1551907286.485 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:21:26.485Z,1551907286.485 [AHRS_M2] Communications Fault, FailCount= 4
2019-03-06T21:21:26.485Z,1551907286.485 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:21:26.609Z,1551907286.609 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:21:26.615Z,1551907286.615 [AHRS_M2](INFO): Powering down
2019-03-06T21:21:27.481Z,1551907287.481 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:21:27.481Z,1551907287.481 [AHRS_M2] No Fault, FailCount= 4
2019-03-06T21:21:27.825Z,1551907287.825 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:21:31.153Z,1551907291.153 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:31.557Z,1551907291.557 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:31.961Z,1551907291.961 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:32.369Z,1551907292.369 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:32.769Z,1551907292.769 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:33.193Z,1551907293.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:33.577Z,1551907293.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:33.635Z,1551907293.635 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6.
2019-03-06T21:21:33.981Z,1551907293.981 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:33.987Z,1551907293.987 [Micromodem](INFO): Nmea in: $CAREV,212123,COPROC,0.10.0.46*41

2019-03-06T21:21:33.988Z,1551907293.988 [Micromodem](INFO): Nmea in: $CAREV,212133,AUV,2.0.27690*15

2019-03-06T21:21:34.385Z,1551907294.385 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:34.789Z,1551907294.789 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:35.193Z,1551907295.193 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:35.597Z,1551907295.597 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:21:44.016Z,1551907304.016 [Micromodem](INFO): Nmea in: $CAREV,212133,COPROC,0.10.0.46*40

2019-03-06T21:21:44.018Z,1551907304.018 [Micromodem](INFO): Nmea in: $CAREV,212143,AUV,2.0.27690*12

2019-03-06T21:21:46.025Z,1551907306.025 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-03-06T21:21:46.025Z,1551907306.025 [AHRS_M2] Data Fault, FailCount= 5
2019-03-06T21:21:46.025Z,1551907306.025 [AHRS_M2](ERROR): Data Fault
2019-03-06T21:21:46.068Z,1551907306.068 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-03-06T21:21:46.068Z,1551907306.068 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2019-03-06T21:21:46.409Z,1551907306.409 [AHRS_M2](INFO): Powering down
2019-03-06T21:21:46.542Z,1551907306.542 [CBIT](INFO): Critical error at 20190306T212146
2019-03-06T21:21:54.091Z,1551907314.091 [Micromodem](INFO): Nmea in: $CAREV,212143,COPROC,0.10.0.46*47

2019-03-06T21:21:54.092Z,1551907314.092 [Micromodem](INFO): Nmea in: $CAREV,212153,AUV,2.0.27690*13

2019-03-06T21:22:04.197Z,1551907324.197 [Micromodem](INFO): Nmea in: $CAREV,212153,COPROC,0.10.0.46*46

2019-03-06T21:22:04.199Z,1551907324.199 [Micromodem](INFO): Nmea in: $CAREV,212203,AUV,2.0.27690*15

2019-03-06T21:22:12.310Z,1551907332.310 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:22:12.310Z,1551907332.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-06T21:22:12.310Z,1551907332.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T21:22:12.312Z,1551907332.312 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:22:12.312Z,1551907332.312 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-06T21:22:12.312Z,1551907332.312 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T21:22:12.342Z,1551907332.342 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:22:12.343Z,1551907332.343 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-06T21:22:12.343Z,1551907332.343 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T21:22:12.373Z,1551907332.373 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T21:22:12.374Z,1551907332.374 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T21:22:12.374Z,1551907332.374 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T21:22:12.708Z,1551907332.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T21:22:12.708Z,1551907332.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-06T21:22:12.709Z,1551907332.709 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T21:22:12.709Z,1551907332.709 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-06T21:22:12.710Z,1551907332.710 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T21:22:12.710Z,1551907332.710 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-06T21:22:13.108Z,1551907333.108 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:22:14.291Z,1551907334.291 [Micromodem](INFO): Nmea in: $CAREV,212203,COPROC,0.10.0.46*40

2019-03-06T21:22:14.292Z,1551907334.292 [Micromodem](INFO): Nmea in: $CAREV,212213,AUV,2.0.27690*14

2019-03-06T21:22:23.987Z,1551907343.987 [Micromodem](INFO): Nmea in: $CAREV,212213,COPROC,0.10.0.46*41

2019-03-06T21:22:23.988Z,1551907343.988 [Micromodem](INFO): Nmea in: $CAREV,212223,AUV,2.0.27690*17

2019-03-06T21:22:34.088Z,1551907354.088 [Micromodem](INFO): Nmea in: $CAREV,212223,COPROC,0.10.0.46*42

2019-03-06T21:22:34.090Z,1551907354.090 [Micromodem](INFO): Nmea in: $CAREV,212233,AUV,2.0.27690*16

2019-03-06T21:22:44.187Z,1551907364.187 [Micromodem](INFO): Nmea in: $CAREV,212233,COPROC,0.10.0.46*43

2019-03-06T21:22:44.188Z,1551907364.188 [Micromodem](INFO): Nmea in: $CAREV,212243,AUV,2.0.27690*11

2019-03-06T21:22:54.287Z,1551907374.287 [Micromodem](INFO): Nmea in: $CAREV,212243,COPROC,0.10.0.46*44

2019-03-06T21:22:54.288Z,1551907374.288 [Micromodem](INFO): Nmea in: $CAREV,212253,AUV,2.0.27690*10

2019-03-06T21:23:03.983Z,1551907383.983 [Micromodem](INFO): Nmea in: $CAREV,212253,COPROC,0.10.0.46*45

2019-03-06T21:23:03.984Z,1551907383.984 [Micromodem](INFO): Nmea in: $CAREV,212303,AUV,2.0.27690*14

2019-03-06T21:23:14.083Z,1551907394.083 [Micromodem](INFO): Nmea in: $CAREV,212303,COPROC,0.10.0.46*41

2019-03-06T21:23:14.084Z,1551907394.084 [Micromodem](INFO): Nmea in: $CAREV,212313,AUV,2.0.27690*15

2019-03-06T21:23:14.966Z,1551907394.966 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T21:23:14.967Z,1551907394.967 [CommandLine](IMPORTANT): Failed components:
2019-03-06T21:23:14.967Z,1551907394.967 [CommandLine](IMPORTANT): AHRS_M2: Data Fault
2019-03-06T21:23:24.187Z,1551907404.187 [Micromodem](INFO): Nmea in: $CAREV,212313,COPROC,0.10.0.46*40

2019-03-06T21:23:24.188Z,1551907404.188 [Micromodem](INFO): Nmea in: $CAREV,212323,AUV,2.0.27690*16

2019-03-06T21:23:34.283Z,1551907414.283 [Micromodem](INFO): Nmea in: $CAREV,212323,COPROC,0.10.0.46*43

2019-03-06T21:23:34.284Z,1551907414.284 [Micromodem](INFO): Nmea in: $CAREV,212333,AUV,2.0.27690*17

2019-03-06T21:23:43.980Z,1551907423.980 [Micromodem](INFO): Nmea in: $CAREV,212333,COPROC,0.10.0.46*42

2019-03-06T21:23:43.981Z,1551907423.981 [Micromodem](INFO): Nmea in: $CAREV,212343,AUV,2.0.27690*10

2019-03-06T21:23:54.075Z,1551907434.075 [Micromodem](INFO): Nmea in: $CAREV,212343,COPROC,0.10.0.46*45

2019-03-06T21:23:54.076Z,1551907434.076 [Micromodem](INFO): Nmea in: $CAREV,212353,AUV,2.0.27690*11

2019-03-06T21:24:04.183Z,1551907444.183 [Micromodem](INFO): Nmea in: $CAREV,212353,COPROC,0.10.0.46*44

2019-03-06T21:24:04.184Z,1551907444.184 [Micromodem](INFO): Nmea in: $CAREV,212403,AUV,2.0.27690*13

2019-03-06T21:24:13.511Z,1551907453.511 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:24:13.511Z,1551907453.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-06T21:24:13.511Z,1551907453.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T21:24:13.513Z,1551907453.513 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:24:13.514Z,1551907453.514 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-06T21:24:13.514Z,1551907453.514 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T21:24:13.515Z,1551907453.515 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:24:13.515Z,1551907453.515 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-06T21:24:13.515Z,1551907453.515 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T21:24:13.527Z,1551907453.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T21:24:13.527Z,1551907453.527 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T21:24:13.527Z,1551907453.527 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T21:24:13.910Z,1551907453.910 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T21:24:13.910Z,1551907453.910 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-06T21:24:13.911Z,1551907453.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T21:24:13.911Z,1551907453.911 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-06T21:24:13.911Z,1551907453.911 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T21:24:13.911Z,1551907453.911 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-06T21:24:14.279Z,1551907454.279 [Micromodem](INFO): Nmea in: $CAREV,212403,COPROC,0.10.0.46*46

2019-03-06T21:24:14.280Z,1551907454.280 [Micromodem](INFO): Nmea in: $CAREV,212413,AUV,2.0.27690*12

2019-03-06T21:24:14.312Z,1551907454.312 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:24:23.976Z,1551907463.976 [Micromodem](INFO): Nmea in: $CAREV,212413,COPROC,0.10.0.46*47

2019-03-06T21:24:23.977Z,1551907463.977 [Micromodem](INFO): Nmea in: $CAREV,212423,AUV,2.0.27690*11

2019-03-06T21:24:34.079Z,1551907474.079 [Micromodem](INFO): Nmea in: $CAREV,212423,COPROC,0.10.0.46*44

2019-03-06T21:24:34.080Z,1551907474.080 [Micromodem](INFO): Nmea in: $CAREV,212433,AUV,2.0.27690*10

2019-03-06T21:24:44.179Z,1551907484.179 [Micromodem](INFO): Nmea in: $CAREV,212433,COPROC,0.10.0.46*45

2019-03-06T21:24:44.180Z,1551907484.180 [Micromodem](INFO): Nmea in: $CAREV,212443,AUV,2.0.27690*17

2019-03-06T21:24:54.275Z,1551907494.275 [Micromodem](INFO): Nmea in: $CAREV,212443,COPROC,0.10.0.46*42

2019-03-06T21:24:54.276Z,1551907494.276 [Micromodem](INFO): Nmea in: $CAREV,212453,AUV,2.0.27690*16

2019-03-06T21:25:03.980Z,1551907503.980 [Micromodem](INFO): Nmea in: $CAREV,212453,COPROC,0.10.0.46*43

2019-03-06T21:25:04.375Z,1551907504.375 [Micromodem](INFO): Nmea in: $CAREV,212503,AUV,2.0.27690*12

2019-03-06T21:25:14.071Z,1551907514.071 [Micromodem](INFO): Nmea in: $CAREV,212503,COPROC,0.10.0.46*47

2019-03-06T21:25:14.072Z,1551907514.072 [Micromodem](INFO): Nmea in: $CAREV,212513,AUV,2.0.27690*13

2019-03-06T21:25:21.774Z,1551907521.774 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-03-06T21:25:21.774Z,1551907521.774 [Default:CheckIn:C.Wait] Stopped
2019-03-06T21:25:21.774Z,1551907521.774 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:25:21.774Z,1551907521.774 [Default:CheckIn:D] Running Loop=1
2019-03-06T21:25:22.174Z,1551907522.174 [Default:CheckIn:D] Stopped
2019-03-06T21:25:22.174Z,1551907522.174 [Default:CheckIn:E] Running Loop=1
2019-03-06T21:25:22.592Z,1551907522.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.195100 min 
2019-03-06T21:25:22.592Z,1551907522.592 [Default:CheckIn:E] Stopped
2019-03-06T21:25:22.592Z,1551907522.592 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-03-06T21:25:22.592Z,1551907522.592 [Default:CheckIn] Stopped
2019-03-06T21:25:22.593Z,1551907522.593 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-06T21:25:22.593Z,1551907522.593 [Default:CheckIn](INFO): Running loop #2
2019-03-06T21:25:22.593Z,1551907522.593 [Default:CheckIn] Running Loop=2
2019-03-06T21:25:22.593Z,1551907522.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-06T21:25:22.593Z,1551907522.593 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-06T21:25:23.362Z,1551907523.362 [NAL9602](INFO): Powering up
2019-03-06T21:25:24.175Z,1551907524.175 [Micromodem](INFO): Nmea in: $CAREV,212513,COPROC,0.10.0.46*46

2019-03-06T21:25:24.176Z,1551907524.176 [Micromodem](INFO): Nmea in: $CAREV,212523,AUV,2.0.27690*10

2019-03-06T21:25:34.283Z,1551907534.283 [Micromodem](INFO): Nmea in: $CAREV,212523,COPROC,0.10.0.46*45

2019-03-06T21:25:34.284Z,1551907534.284 [Micromodem](INFO): Nmea in: $CAREV,212533,AUV,2.0.27690*11

2019-03-06T21:25:34.284Z,1551907534.284 [NAL9602](INFO): NAL9602 initialized
2019-03-06T21:25:35.109Z,1551907535.109 [NAL9602](DEBUG): Fix Requested
2019-03-06T21:25:43.988Z,1551907543.988 [Micromodem](INFO): Nmea in: $CAREV,212533,COPROC,0.10.0.46*44

2019-03-06T21:25:44.383Z,1551907544.383 [Micromodem](INFO): Nmea in: $CAREV,212543,AUV,2.0.27690*16

2019-03-06T21:25:54.079Z,1551907554.079 [Micromodem](INFO): Nmea in: $CAREV,212543,COPROC,0.10.0.46*43

2019-03-06T21:25:54.080Z,1551907554.080 [Micromodem](INFO): Nmea in: $CAREV,212553,AUV,2.0.27690*17

2019-03-06T21:26:04.183Z,1551907564.183 [Micromodem](INFO): Nmea in: $CAREV,212553,COPROC,0.10.0.46*42

2019-03-06T21:26:04.184Z,1551907564.184 [Micromodem](INFO): Nmea in: $CAREV,212603,AUV,2.0.27690*11

2019-03-06T21:26:08.779Z,1551907568.779 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/DUSBL.xml
2019-03-06T21:26:08.779Z,1551907568.779 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-06T21:26:08.805Z,1551907568.805 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-06T21:26:08.808Z,1551907568.808 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-06T21:26:08.812Z,1551907568.812 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-06T21:26:08.816Z,1551907568.816 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-06T21:26:08.820Z,1551907568.820 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-06T21:26:08.821Z,1551907568.821 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-06T21:26:08.828Z,1551907568.828 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-06T21:26:08.861Z,1551907568.861 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-06T21:26:08.873Z,1551907568.873 [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-06T21:26:08.874Z,1551907568.874 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/DUSBL.xml
2019-03-06T21:26:13.922Z,1551907573.922 [CommandLine](IMPORTANT): got command show variable range
2019-03-06T21:26:14.135Z,1551907574.135 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-06T21:26:14.136Z,1551907574.136 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2019-03-06T21:26:14.141Z,1551907574.141 [CommandLine](IMPORTANT): Micromodem.range (meter)
2019-03-06T21:26:14.144Z,1551907574.144 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam1Range (meter)
2019-03-06T21:26:14.144Z,1551907574.144 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam2Range (meter)
2019-03-06T21:26:14.149Z,1551907574.149 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam3Range (meter)
2019-03-06T21:26:14.149Z,1551907574.149 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam4Range (meter)
2019-03-06T21:26:14.295Z,1551907574.295 [Micromodem](INFO): Nmea in: $CAREV,212603,COPROC,0.10.0.46*44

2019-03-06T21:26:14.296Z,1551907574.296 [Micromodem](INFO): Nmea in: $CAREV,212613,AUV,2.0.27690*10

2019-03-06T21:26:14.354Z,1551907574.354 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:26:14.354Z,1551907574.354 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-06T21:26:14.354Z,1551907574.354 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T21:26:14.356Z,1551907574.356 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:26:14.356Z,1551907574.356 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-06T21:26:14.356Z,1551907574.356 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T21:26:14.365Z,1551907574.365 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:26:14.366Z,1551907574.366 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-03-06T21:26:14.366Z,1551907574.366 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T21:26:14.418Z,1551907574.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T21:26:14.418Z,1551907574.418 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T21:26:14.418Z,1551907574.418 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T21:26:14.727Z,1551907574.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T21:26:14.727Z,1551907574.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-06T21:26:14.727Z,1551907574.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T21:26:14.728Z,1551907574.728 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-06T21:26:14.728Z,1551907574.728 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T21:26:14.728Z,1551907574.728 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-03-06T21:26:15.112Z,1551907575.112 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:26:24.379Z,1551907584.379 [Micromodem](INFO): Nmea in: $CAREV,212613,COPROC,0.10.0.46*45

2019-03-06T21:26:24.380Z,1551907584.380 [Micromodem](INFO): Nmea in: $CAREV,212623,AUV,2.0.27690*13

2019-03-06T21:26:28.437Z,1551907588.437 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-03-06T21:26:28.438Z,1551907588.438 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19030614320014,35.   -1.58,        +0.38,   9.49,  0.00
2019-03-06T21:26:31.899Z,1551907591.899 [CommandLine](IMPORTANT): got command report touch Micromodem.range
2019-03-06T21:26:33.586Z,1551907593.586 [CommandLine](IMPORTANT): got command report
2019-03-06T21:26:33.587Z,1551907593.587 [Reporter](INFO): DUSBL_Hydroid.range reporting when touched
2019-03-06T21:26:33.587Z,1551907593.587 [Reporter](INFO): DUSBL_Hydroid.direction reporting when touched
2019-03-06T21:26:33.587Z,1551907593.587 [Reporter](INFO): Micromodem.range reporting when touched
2019-03-06T21:26:34.075Z,1551907594.075 [Micromodem](INFO): Nmea in: $CAREV,212623,COPROC,0.10.0.46*46

2019-03-06T21:26:34.076Z,1551907594.076 [Micromodem](INFO): Nmea in: $CAREV,212633,AUV,2.0.27690*12

2019-03-06T21:26:37.547Z,1551907597.547 [CommandLine](IMPORTANT): got command show variable direction
2019-03-06T21:26:37.550Z,1551907597.550 [CommandLine](IMPORTANT): direction_of_sea_water_velocity (unknown)
2019-03-06T21:26:37.632Z,1551907597.632 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-06T21:26:44.179Z,1551907604.179 [Micromodem](INFO): Nmea in: $CAREV,212633,COPROC,0.10.0.46*47

2019-03-06T21:26:44.180Z,1551907604.180 [Micromodem](INFO): Nmea in: $CAREV,212643,AUV,2.0.27690*15

2019-03-06T21:26:45.318Z,1551907605.318 [CommandLine](IMPORTANT): got command run
2019-03-06T21:26:45.326Z,1551907605.326 [CommandLine](IMPORTANT): Running 
2019-03-06T21:26:45.441Z,1551907605.441 [Default] Stopped
2019-03-06T21:26:45.441Z,1551907605.441 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-06T21:26:45.442Z,1551907605.442 [Default:B.GoToSurface] Stopped
2019-03-06T21:26:45.442Z,1551907605.442 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-06T21:26:45.442Z,1551907605.442 [Default:CheckIn] Stopped
2019-03-06T21:26:45.442Z,1551907605.442 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-06T21:26:45.442Z,1551907605.442 [Default:CheckIn:Read_GPS] Stopped
2019-03-06T21:26:45.442Z,1551907605.442 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-06T21:26:45.442Z,1551907605.442 [DUSBL] Running Loop=1
2019-03-06T21:26:45.442Z,1551907605.442 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:A.Pitch] Running Loop=1
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:C] Running Loop=1
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:RequestRepeater] Running Loop=1
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T21:26:45.443Z,1551907605.443 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:26:45.444Z,1551907605.444 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:26:45.444Z,1551907605.444 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T21:26:45.444Z,1551907605.444 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:26:45.444Z,1551907605.444 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:26:45.444Z,1551907605.444 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:26:45.445Z,1551907605.445 [DUSBL:C] Running Loop=1
2019-03-06T21:26:45.446Z,1551907605.446 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-06T21:26:45.446Z,1551907605.446 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-06T21:26:45.446Z,1551907605.446 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-06T21:26:45.446Z,1551907605.446 [DUSBL:A.Pitch] Running Loop=1
2019-03-06T21:26:46.263Z,1551907606.263 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:26:46.263Z,1551907606.263 [AHRS_M2] No Fault, FailCount= 5
2019-03-06T21:26:46.593Z,1551907606.593 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:26:47.004Z,1551907607.004 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T21:26:47.007Z,1551907607.007 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,4A1C0370,0,0,0,1000,0*67

2019-03-06T21:26:47.008Z,1551907607.008 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,4A1C0370,0,0,0,1000,0*67

2019-03-06T21:26:47.407Z,1551907607.407 [Micromodem](INFO): Nmea in: $CAREV,212643,COPROC,0.10.0.46*40

2019-03-06T21:26:47.410Z,1551907607.410 [Micromodem](INFO): Nmea in: $SNPGT,26000,28,4A1C0370,54,24000,4A1C0370,0,0,0,1000,0*7A

2019-03-06T21:26:47.821Z,1551907607.821 [Micromodem](INFO): Nmea in: $CATXP,280*48

2019-03-06T21:26:47.822Z,1551907607.822 [Micromodem](INFO): Nmea in: $CATXF,280*5E

2019-03-06T21:26:48.224Z,1551907608.224 [Micromodem](INFO): Nmea in: $SNTTA,,,,,212646.43*5C

2019-03-06T21:26:48.227Z,1551907608.227 [Micromodem](INFO): Nmea in: $CAREV,212646,AUV,2.0.27690*10

2019-03-06T21:26:50.149Z,1551907610.149 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:50.581Z,1551907610.581 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:50.586Z,1551907610.586 [DUSBL_Hydroid](FAULT): unknown deviceResponse_: !U1,P
2019-03-06T21:26:50.586Z,1551907610.586 [DUSBL_Hydroid] Communications Fault, FailCount= 1
2019-03-06T21:26:50.586Z,1551907610.586 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-06T21:26:50.724Z,1551907610.724 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-06T21:26:51.085Z,1551907611.085 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:51.090Z,1551907611.090 [DUSBL_Hydroid](INFO): Powering down
2019-03-06T21:26:51.577Z,1551907611.577 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:51.993Z,1551907611.993 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:52.073Z,1551907612.073 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-06T21:26:52.074Z,1551907612.074 [DUSBL_Hydroid] No Fault, FailCount= 1
2019-03-06T21:26:52.401Z,1551907612.401 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:52.837Z,1551907612.837 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:53.229Z,1551907613.229 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:53.645Z,1551907613.645 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:54.069Z,1551907614.069 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:54.437Z,1551907614.437 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:54.451Z,1551907614.451 [DUSBL_Hydroid](INFO): Powering up
2019-03-06T21:26:54.451Z,1551907614.451 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-06T21:26:54.837Z,1551907614.837 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:55.235Z,1551907615.235 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:55.641Z,1551907615.641 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:55.674Z,1551907615.674 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T21:26:55.674Z,1551907615.674 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T21:26:55.675Z,1551907615.675 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:26:55.675Z,1551907615.675 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T21:26:55.675Z,1551907615.675 [DUSBL:RequestRepeater] Stopped
2019-03-06T21:26:55.675Z,1551907615.675 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T21:26:55.675Z,1551907615.675 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T21:26:55.675Z,1551907615.675 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T21:26:55.676Z,1551907615.676 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-06T21:26:55.676Z,1551907615.676 [DUSBL:RequestRepeater] Running Loop=2
2019-03-06T21:26:55.676Z,1551907615.676 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T21:26:55.676Z,1551907615.676 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:26:55.676Z,1551907615.676 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:26:55.676Z,1551907615.676 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T21:26:55.676Z,1551907615.676 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:26:56.057Z,1551907616.057 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:56.445Z,1551907616.445 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:56.850Z,1551907616.850 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:57.289Z,1551907617.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:57.300Z,1551907617.300 [Micromodem](INFO): Nmea in: $CAREV,212646,COPROC,0.10.0.46*45

2019-03-06T21:26:57.301Z,1551907617.301 [Micromodem](INFO): Nmea in: $CAREV,212656,AUV,2.0.27690*11

2019-03-06T21:26:57.693Z,1551907617.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:58.077Z,1551907618.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:58.481Z,1551907618.481 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:58.877Z,1551907618.877 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:59.289Z,1551907619.289 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:59.697Z,1551907619.697 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:26:59.697Z,1551907619.697 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:26:59.697Z,1551907619.697 [AHRS_M2] Communications Fault, FailCount= 1
2019-03-06T21:26:59.697Z,1551907619.697 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:26:59.740Z,1551907619.740 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:26:59.785Z,1551907619.785 [AHRS_M2](INFO): Powering down
2019-03-06T21:27:00.592Z,1551907620.592 [NAL9602](INFO): SBD MO Status=2, MOMSN=2146, MT Status=2, MTMSN=0
2019-03-06T21:27:00.592Z,1551907620.592 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-06T21:27:00.641Z,1551907620.641 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:27:00.641Z,1551907620.641 [AHRS_M2] No Fault, FailCount= 1
2019-03-06T21:27:00.985Z,1551907620.985 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:27:04.553Z,1551907624.553 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:04.941Z,1551907624.941 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:05.349Z,1551907625.349 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:05.773Z,1551907625.773 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:06.153Z,1551907626.153 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:06.186Z,1551907626.186 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T21:27:06.186Z,1551907626.186 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T21:27:06.186Z,1551907626.186 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater] Stopped
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater](INFO): Running loop #3
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater] Running Loop=3
2019-03-06T21:27:06.187Z,1551907626.187 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T21:27:06.188Z,1551907626.188 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:27:06.188Z,1551907626.188 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:27:06.188Z,1551907626.188 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T21:27:06.188Z,1551907626.188 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:27:06.585Z,1551907626.585 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:06.973Z,1551907626.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:07.373Z,1551907627.373 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:07.383Z,1551907627.383 [Micromodem](INFO): Nmea in: $CAREV,212656,COPROC,0.10.0.46*44

2019-03-06T21:27:07.384Z,1551907627.384 [Micromodem](INFO): Nmea in: $CAREV,212706,AUV,2.0.27690*15

2019-03-06T21:27:07.809Z,1551907627.809 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:08.213Z,1551907628.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:08.641Z,1551907628.641 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:09.049Z,1551907629.049 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:09.473Z,1551907629.473 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:09.901Z,1551907629.901 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:10.309Z,1551907630.309 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:10.753Z,1551907630.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:11.145Z,1551907631.145 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:11.569Z,1551907631.569 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:11.973Z,1551907631.973 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:12.381Z,1551907632.381 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:12.390Z,1551907632.390 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-06T21:27:12.797Z,1551907632.797 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:12.807Z,1551907632.807 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T21:27:13.205Z,1551907633.205 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:13.641Z,1551907633.641 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:14.033Z,1551907634.033 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:14.033Z,1551907634.033 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:27:14.033Z,1551907634.033 [AHRS_M2] Communications Fault, FailCount= 2
2019-03-06T21:27:14.033Z,1551907634.033 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:27:14.094Z,1551907634.094 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:27:14.121Z,1551907634.121 [AHRS_M2](INFO): Powering down
2019-03-06T21:27:14.973Z,1551907634.973 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:27:14.973Z,1551907634.973 [AHRS_M2] No Fault, FailCount= 2
2019-03-06T21:27:15.333Z,1551907635.333 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:27:16.955Z,1551907636.955 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T21:27:16.988Z,1551907636.988 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T21:27:16.988Z,1551907636.988 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T21:27:16.988Z,1551907636.988 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:27:16.989Z,1551907636.989 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T21:27:16.989Z,1551907636.989 [DUSBL:RequestRepeater] Stopped
2019-03-06T21:27:16.989Z,1551907636.989 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T21:27:16.989Z,1551907636.989 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T21:27:16.989Z,1551907636.989 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T21:27:16.990Z,1551907636.990 [DUSBL:RequestRepeater](INFO): Running loop #4
2019-03-06T21:27:16.990Z,1551907636.990 [DUSBL:RequestRepeater] Running Loop=4
2019-03-06T21:27:16.990Z,1551907636.990 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T21:27:16.990Z,1551907636.990 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:27:16.990Z,1551907636.990 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:27:16.990Z,1551907636.990 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T21:27:16.990Z,1551907636.990 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:27:17.367Z,1551907637.367 [Micromodem](INFO): Nmea in: $CAREV,212706,COPROC,0.10.0.46*40

2019-03-06T21:27:17.368Z,1551907637.368 [Micromodem](INFO): Nmea in: $CAREV,212716,AUV,2.0.27690*14

2019-03-06T21:27:18.889Z,1551907638.889 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:19.293Z,1551907639.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:19.697Z,1551907639.697 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:20.101Z,1551907640.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:20.505Z,1551907640.505 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:20.909Z,1551907640.909 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:21.325Z,1551907641.325 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:21.335Z,1551907641.335 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T21:27:21.753Z,1551907641.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:21.755Z,1551907641.755 [DUSBL_Hydroid](FAULT): unknown deviceResponse_: !U1,P
2019-03-06T21:27:21.755Z,1551907641.755 [DUSBL_Hydroid] Communications Fault, FailCount= 2
2019-03-06T21:27:21.755Z,1551907641.755 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-06T21:27:21.790Z,1551907641.790 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-06T21:27:22.117Z,1551907642.117 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:22.134Z,1551907642.134 [DUSBL_Hydroid](INFO): Powering down
2019-03-06T21:27:22.601Z,1551907642.601 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:23.009Z,1551907643.009 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:23.051Z,1551907643.051 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-06T21:27:23.051Z,1551907643.051 [DUSBL_Hydroid] No Fault, FailCount= 2
2019-03-06T21:27:23.405Z,1551907643.405 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:23.825Z,1551907643.825 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:24.233Z,1551907644.233 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:24.669Z,1551907644.669 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:25.077Z,1551907645.077 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:25.457Z,1551907645.457 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:25.462Z,1551907645.462 [DUSBL_Hydroid](INFO): Powering up
2019-03-06T21:27:25.462Z,1551907645.462 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-06T21:27:25.917Z,1551907645.917 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:26.321Z,1551907646.321 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:26.725Z,1551907646.725 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:27.109Z,1551907647.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:27.533Z,1551907647.533 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:27.539Z,1551907647.539 [Micromodem](INFO): Nmea in: $CAREV,212716,COPROC,0.10.0.46*41

2019-03-06T21:27:27.540Z,1551907647.540 [Micromodem](INFO): Nmea in: $CAREV,212726,AUV,2.0.27690*17

2019-03-06T21:27:27.631Z,1551907647.631 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T21:27:27.631Z,1551907647.631 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T21:27:27.631Z,1551907647.631 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater] Stopped
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater](INFO): Running loop #5
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater] Running Loop=5
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:27:27.639Z,1551907647.639 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:27:27.640Z,1551907647.640 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T21:27:27.640Z,1551907647.640 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:27:28.013Z,1551907648.013 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:28.425Z,1551907648.425 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:28.425Z,1551907648.425 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:27:28.425Z,1551907648.425 [AHRS_M2] Communications Fault, FailCount= 3
2019-03-06T21:27:28.425Z,1551907648.425 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:27:28.478Z,1551907648.478 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:27:28.515Z,1551907648.515 [AHRS_M2](INFO): Powering down
2019-03-06T21:27:29.353Z,1551907649.353 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:27:29.353Z,1551907649.353 [AHRS_M2] No Fault, FailCount= 3
2019-03-06T21:27:29.701Z,1551907649.701 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:27:33.257Z,1551907653.257 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:33.661Z,1551907653.661 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:34.057Z,1551907654.057 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:34.469Z,1551907654.469 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:34.481Z,1551907654.481 [NAL9602](INFO): SBD MO Status=2, MOMSN=2146, MT Status=2, MTMSN=0
2019-03-06T21:27:34.481Z,1551907654.481 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-06T21:27:34.901Z,1551907654.901 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:35.293Z,1551907655.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:35.701Z,1551907655.701 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:36.109Z,1551907656.109 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:36.533Z,1551907656.533 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:36.929Z,1551907656.929 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:37.317Z,1551907657.317 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:37.327Z,1551907657.327 [Micromodem](INFO): Nmea in: $CAREV,212726,COPROC,0.10.0.46*42

2019-03-06T21:27:37.328Z,1551907657.328 [Micromodem](INFO): Nmea in: $CAREV,212736,AUV,2.0.27690*16

2019-03-06T21:27:37.725Z,1551907657.725 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:38.133Z,1551907658.133 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:38.251Z,1551907658.251 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T21:27:38.251Z,1551907658.251 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T21:27:38.251Z,1551907658.251 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:27:38.251Z,1551907658.251 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T21:27:38.251Z,1551907658.251 [DUSBL:RequestRepeater] Stopped
2019-03-06T21:27:38.252Z,1551907658.252 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T21:27:38.252Z,1551907658.252 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T21:27:38.252Z,1551907658.252 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T21:27:38.252Z,1551907658.252 [DUSBL:RequestRepeater](INFO): Running loop #6
2019-03-06T21:27:38.252Z,1551907658.252 [DUSBL:RequestRepeater] Running Loop=6
2019-03-06T21:27:38.253Z,1551907658.253 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T21:27:38.253Z,1551907658.253 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:27:38.253Z,1551907658.253 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:27:38.253Z,1551907658.253 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T21:27:38.253Z,1551907658.253 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:27:38.665Z,1551907658.665 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:39.065Z,1551907659.065 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:39.465Z,1551907659.465 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:39.877Z,1551907659.877 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:40.281Z,1551907660.281 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:40.693Z,1551907660.693 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:41.101Z,1551907661.101 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:41.493Z,1551907661.493 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:41.893Z,1551907661.893 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:42.293Z,1551907662.293 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:42.709Z,1551907662.709 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:42.709Z,1551907662.709 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:27:42.709Z,1551907662.709 [AHRS_M2] Communications Fault, FailCount= 4
2019-03-06T21:27:42.709Z,1551907662.709 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:27:42.758Z,1551907662.758 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:27:42.797Z,1551907662.797 [AHRS_M2](INFO): Powering down
2019-03-06T21:27:43.197Z,1551907663.197 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-06T21:27:43.604Z,1551907663.604 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T21:27:43.659Z,1551907663.659 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-03-06T21:27:43.659Z,1551907663.659 [AHRS_M2] No Fault, FailCount= 4
2019-03-06T21:27:43.997Z,1551907663.997 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-06T21:27:47.565Z,1551907667.565 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:47.575Z,1551907667.575 [Micromodem](INFO): Nmea in: $CAREV,212736,COPROC,0.10.0.46*43

2019-03-06T21:27:47.577Z,1551907667.577 [Micromodem](INFO): Nmea in: $CAREV,212746,AUV,2.0.27690*11

2019-03-06T21:27:47.953Z,1551907667.953 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:47.956Z,1551907667.956 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T21:27:48.361Z,1551907668.361 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:48.757Z,1551907668.757 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:48.790Z,1551907668.790 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T21:27:48.791Z,1551907668.791 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T21:27:48.791Z,1551907668.791 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:27:48.791Z,1551907668.791 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T21:27:48.791Z,1551907668.791 [DUSBL:RequestRepeater] Stopped
2019-03-06T21:27:48.791Z,1551907668.791 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T21:27:48.791Z,1551907668.791 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater](INFO): Running loop #7
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater] Running Loop=7
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T21:27:48.792Z,1551907668.792 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:27:49.161Z,1551907669.161 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:49.585Z,1551907669.585 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:49.989Z,1551907669.989 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:50.397Z,1551907670.397 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:50.789Z,1551907670.789 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:51.189Z,1551907671.189 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:51.597Z,1551907671.597 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:52.005Z,1551907672.005 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:52.015Z,1551907672.015 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T21:27:52.409Z,1551907672.409 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:52.418Z,1551907672.418 [DUSBL_Hydroid](FAULT): unknown deviceResponse_: !U1,P
2019-03-06T21:27:52.418Z,1551907672.418 [DUSBL_Hydroid] Communications Fault, FailCount= 3
2019-03-06T21:27:52.418Z,1551907672.418 [DUSBL_Hydroid](ERROR): Communications Fault
2019-03-06T21:27:52.515Z,1551907672.515 [CBIT](ERROR): Communications Fault in component: DUSBL_Hydroid
2019-03-06T21:27:52.841Z,1551907672.841 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:52.850Z,1551907672.850 [DUSBL_Hydroid](INFO): Powering down
2019-03-06T21:27:53.297Z,1551907673.297 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:53.681Z,1551907673.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:53.733Z,1551907673.733 [CBIT](INFO): Clearing failed state for component DUSBL_Hydroid
2019-03-06T21:27:53.733Z,1551907673.733 [DUSBL_Hydroid] No Fault, FailCount= 3
2019-03-06T21:27:54.089Z,1551907674.089 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:54.497Z,1551907674.497 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:54.893Z,1551907674.893 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:55.305Z,1551907675.305 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:55.709Z,1551907675.709 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:56.121Z,1551907676.121 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:56.130Z,1551907676.130 [DUSBL_Hydroid](INFO): Powering up
2019-03-06T21:27:56.130Z,1551907676.130 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-06T21:27:56.573Z,1551907676.573 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:56.989Z,1551907676.989 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-03-06T21:27:56.989Z,1551907676.989 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-03-06T21:27:56.989Z,1551907676.989 [AHRS_M2] Communications Fault, FailCount= 5
2019-03-06T21:27:56.989Z,1551907676.989 [AHRS_M2](ERROR): Communications Fault
2019-03-06T21:27:57.057Z,1551907677.057 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-03-06T21:27:57.057Z,1551907677.057 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-03-06T21:27:57.067Z,1551907677.067 [AHRS_M2](INFO): Powering down
2019-03-06T21:27:57.196Z,1551907677.196 [CBIT](INFO): Critical error at 20190306T212757
2019-03-06T21:27:57.197Z,1551907677.197 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-03-06T21:27:57.471Z,1551907677.471 [Micromodem](INFO): Nmea in: $CAREV,212746,COPROC,0.10.0.46*44

2019-03-06T21:27:57.472Z,1551907677.472 [Micromodem](INFO): Nmea in: $CAREV,212756,AUV,2.0.27690*10

2019-03-06T21:27:57.506Z,1551907677.506 [MissionManager](INFO): MissionManager is completed.
2019-03-06T21:27:57.506Z,1551907677.506 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-06T21:27:57.506Z,1551907677.506 [DUSBL] Stopped
2019-03-06T21:27:57.506Z,1551907677.506 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-06T21:27:57.506Z,1551907677.506 [DUSBL:A.Pitch] Stopped
2019-03-06T21:27:57.506Z,1551907677.506 [DUSBL:B.SetSpeed] Stopped
2019-03-06T21:27:57.506Z,1551907677.506 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-06T21:27:57.507Z,1551907677.507 [DUSBL:C] Stopped
2019-03-06T21:27:57.507Z,1551907677.507 [DUSBL:RequestRepeater] Stopped
2019-03-06T21:27:57.507Z,1551907677.507 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T21:27:57.507Z,1551907677.507 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T21:27:57.507Z,1551907677.507 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T21:27:57.507Z,1551907677.507 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T21:27:57.507Z,1551907677.507 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:27:57.904Z,1551907677.904 [MissionManager](IMPORTANT): Started mission Default
2019-03-06T21:27:57.904Z,1551907677.904 [Default] Running Loop=1
2019-03-06T21:27:57.904Z,1551907677.904 [Default](DEBUG): Aggregate::initialize Default
2019-03-06T21:27:57.904Z,1551907677.904 [Default:B.GoToSurface] Running Loop=1
2019-03-06T21:27:57.904Z,1551907677.904 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-06T21:27:57.905Z,1551907677.905 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-06T21:27:57.905Z,1551907677.905 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-06T21:27:57.905Z,1551907677.905 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-06T21:27:57.906Z,1551907677.906 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-06T21:27:57.906Z,1551907677.906 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-06T21:27:57.906Z,1551907677.906 [Default:A.Wait] Running Loop=1
2019-03-06T21:27:57.907Z,1551907677.907 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-06T21:28:07.571Z,1551907687.571 [Micromodem](INFO): Nmea in: $CAREV,212756,COPROC,0.10.0.46*45

2019-03-06T21:28:07.572Z,1551907687.572 [Micromodem](INFO): Nmea in: $CAREV,212806,AUV,2.0.27690*1A

2019-03-06T21:28:11.296Z,1551907691.296 [Default:A.Wait](INFO): Done Waiting.
2019-03-06T21:28:11.296Z,1551907691.296 [Default:A.Wait] Stopped
2019-03-06T21:28:11.296Z,1551907691.296 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T21:28:11.648Z,1551907691.648 [Default:CheckIn] Running Loop=1
2019-03-06T21:28:11.648Z,1551907691.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-06T21:28:11.648Z,1551907691.648 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-06T21:28:14.036Z,1551907694.036 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-06T21:28:15.306Z,1551907695.306 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:28:15.306Z,1551907695.306 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-03-06T21:28:15.306Z,1551907695.306 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T21:28:15.308Z,1551907695.308 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:28:15.309Z,1551907695.309 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2019-03-06T21:28:15.309Z,1551907695.309 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T21:28:15.310Z,1551907695.310 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T21:28:15.311Z,1551907695.311 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5
2019-03-06T21:28:15.311Z,1551907695.311 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T21:28:15.322Z,1551907695.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T21:28:15.322Z,1551907695.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T21:28:15.322Z,1551907695.322 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T21:28:15.699Z,1551907695.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T21:28:15.699Z,1551907695.699 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-03-06T21:28:15.699Z,1551907695.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T21:28:15.700Z,1551907695.700 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2019-03-06T21:28:15.700Z,1551907695.700 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T21:28:15.700Z,1551907695.700 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5
2019-03-06T21:28:16.083Z,1551907696.083 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T21:28:17.267Z,1551907697.267 [Micromodem](INFO): Nmea in: $CAREV,212806,COPROC,0.10.0.46*4F

2019-03-06T21:28:17.268Z,1551907697.268 [Micromodem](INFO): Nmea in: $CAREV,212816,AUV,2.0.27690*1B

2019-03-06T21:28:27.367Z,1551907707.367 [Micromodem](INFO): Nmea in: $CAREV,212816,COPROC,0.10.0.46*4E

2019-03-06T21:28:27.368Z,1551907707.368 [Micromodem](INFO): Nmea in: $CAREV,212826,AUV,2.0.27690*18

2019-03-06T21:28:35.593Z,1551907715.593 [CommandLine](IMPORTANT): got command quit
2019-03-06T21:28:36.597Z,1551907716.597 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:36.597Z,1551907716.597 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:36.625Z,1551907716.625 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-06T21:28:36.625Z,1551907716.625 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:36.626Z,1551907716.626 [CommandLine](INFO): Join timeout helper Thread ID is 8715
2019-03-06T21:28:36.633Z,1551907716.633 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-06T21:28:36.633Z,1551907716.633 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:36.633Z,1551907716.633 [NavChartDb](INFO): Join timeout helper Thread ID is 8716
2019-03-06T21:28:36.749Z,1551907716.749 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:36.749Z,1551907716.749 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:36.753Z,1551907716.753 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-06T21:28:36.753Z,1551907716.753 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:36.753Z,1551907716.753 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 8717
2019-03-06T21:28:37.042Z,1551907717.042 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:37.042Z,1551907717.042 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-06T21:28:37.042Z,1551907717.042 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:37.057Z,1551907717.057 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-06T21:28:37.057Z,1551907717.057 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:37.058Z,1551907717.058 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 8718
2019-03-06T21:28:37.238Z,1551907717.238 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:37.239Z,1551907717.239 [CTD_NeilBrown](INFO): Powering down
2019-03-06T21:28:37.253Z,1551907717.253 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:37.265Z,1551907717.265 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-06T21:28:37.265Z,1551907717.265 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:37.265Z,1551907717.265 [Radio_Surface](INFO): Join timeout helper Thread ID is 8719
2019-03-06T21:28:37.445Z,1551907717.445 [Radio_Surface](INFO): Powering down
2019-03-06T21:28:37.446Z,1551907717.446 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:37.446Z,1551907717.446 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:37.462Z,1551907717.462 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-06T21:28:37.462Z,1551907717.462 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:37.463Z,1551907717.463 [DataOverHttps](INFO): Join timeout helper Thread ID is 8720
2019-03-06T21:28:37.467Z,1551907717.467 [Micromodem](INFO): Nmea in: $CAREV,212826,COPROC,0.10.0.46*4D

2019-03-06T21:28:37.468Z,1551907717.468 [Micromodem](INFO): Nmea in: $CAREV,212836,AUV,2.0.27690*19

2019-03-06T21:28:38.015Z,1551907718.015 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:38.019Z,1551907718.019 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.034Z,1551907718.034 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-06T21:28:38.034Z,1551907718.034 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.034Z,1551907718.034 [logger](INFO): Join timeout helper Thread ID is 8721
2019-03-06T21:28:38.133Z,1551907718.133 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:38.133Z,1551907718.133 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.154Z,1551907718.154 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-06T21:28:38.154Z,1551907718.154 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.154Z,1551907718.154 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-06T21:28:38.154Z,1551907718.154 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.154Z,1551907718.154 [controlThread](INFO): Join timeout helper Thread ID is 8722
2019-03-06T21:28:38.265Z,1551907718.265 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T21:28:38.265Z,1551907718.265 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-06T21:28:38.266Z,1551907718.266 [AHRS_M2](INFO): Powering down
2019-03-06T21:28:38.267Z,1551907718.267 [DUSBL_Hydroid](INFO): Powering down
2019-03-06T21:28:38.337Z,1551907718.337 [Micromodem](INFO): Powering down
2019-03-06T21:28:38.433Z,1551907718.433 [NAL9602](INFO): Powering down
2019-03-06T21:28:38.505Z,1551907718.505 [RDI_Pathfinder](INFO): Powering down
2019-03-06T21:28:38.506Z,1551907718.506 [RDI_PathfinderUp](INFO): Powering down
2019-03-06T21:28:38.507Z,1551907718.507 [Aanderaa_O2](INFO): Powering down
2019-03-06T21:28:38.508Z,1551907718.508 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-06T21:28:38.509Z,1551907718.509 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-06T21:28:38.510Z,1551907718.510 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-06T21:28:38.510Z,1551907718.510 [MissionManager](INFO): Uninitializing Mission Default
2019-03-06T21:28:38.510Z,1551907718.510 [Default] Stopped
2019-03-06T21:28:38.510Z,1551907718.510 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-06T21:28:38.511Z,1551907718.511 [Default:B.GoToSurface] Stopped
2019-03-06T21:28:38.511Z,1551907718.511 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-06T21:28:38.511Z,1551907718.511 [Default:CheckIn] Stopped
2019-03-06T21:28:38.511Z,1551907718.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-06T21:28:38.511Z,1551907718.511 [Default:CheckIn:Read_GPS] Stopped
2019-03-06T21:28:38.513Z,1551907718.513 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-06T21:28:38.514Z,1551907718.514 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-06T21:28:38.514Z,1551907718.514 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-06T21:28:38.514Z,1551907718.514 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-06T21:28:38.515Z,1551907718.515 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-06T21:28:38.515Z,1551907718.515 [BuoyancyServo](INFO): Powering down
2019-03-06T21:28:38.529Z,1551907718.529 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-06T21:28:38.529Z,1551907718.529 [ElevatorServo](INFO): Powering down
2019-03-06T21:28:38.530Z,1551907718.530 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-06T21:28:38.530Z,1551907718.530 [MassServo](INFO): Powering down
2019-03-06T21:28:38.531Z,1551907718.531 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T21:28:38.531Z,1551907718.531 [RudderServo](INFO): Powering down
2019-03-06T21:28:38.532Z,1551907718.532 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-06T21:28:38.532Z,1551907718.532 [ThrusterServo](INFO): Powering down
2019-03-06T21:28:38.533Z,1551907718.533 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-06T21:28:38.533Z,1551907718.533 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-06T21:28:38.533Z,1551907718.533 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-06T21:28:38.534Z,1551907718.534 [CBIT](DEBUG): Powering off loads.
2019-03-06T21:28:38.545Z,1551907718.545 [CBIT](DEBUG): Disabling WDT.
2019-03-06T21:28:38.557Z,1551907718.557 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-06T21:28:38.558Z,1551907718.558 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.623Z,1551907718.623 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.632Z,1551907718.632 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.675Z,1551907718.675 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.677Z,1551907718.677 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.733Z,1551907718.733 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T21:28:38.803Z,1551907718.803 [logger ThreadHandler](INFO): Thread cancelled.