2019-03-06T22:16:49.245Z,1551910609.245 [Supervisor](DEBUG): Initializing supervisor.
2019-03-06T22:16:49.248Z,1551910609.248 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-06T22:16:49.249Z,1551910609.249 [SyncHandler](INFO): Protected caller Thread ID is 944
2019-03-06T22:16:49.249Z,1551910609.249 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-06T22:16:49.250Z,1551910609.250 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-06T22:16:49.251Z,1551910609.251 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 945
2019-03-06T22:16:49.254Z,1551910609.254 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-06T22:16:49.266Z,1551910609.266 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-06T22:16:49.267Z,1551910609.267 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-06T22:16:49.267Z,1551910609.267 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 946
2019-03-06T22:16:49.268Z,1551910609.268 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-06T22:16:49.269Z,1551910609.269 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-06T22:16:49.269Z,1551910609.269 [logger ThreadHandler](INFO): Protected caller Thread ID is 947
2019-03-06T22:16:49.271Z,1551910609.271 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-06T22:16:49.272Z,1551910609.272 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-06T22:16:49.273Z,1551910609.273 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-06T22:16:49.706Z,1551910609.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-06T22:16:49.707Z,1551910609.707 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-06T22:16:49.805Z,1551910609.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-06T22:16:49.806Z,1551910609.806 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-06T22:16:50.142Z,1551910610.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-06T22:16:50.143Z,1551910610.143 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-06T22:16:50.304Z,1551910610.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-06T22:16:50.304Z,1551910610.304 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-06T22:16:50.542Z,1551910610.542 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-06T22:16:50.542Z,1551910610.542 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-06T22:16:51.014Z,1551910611.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-06T22:16:51.014Z,1551910611.014 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-06T22:16:51.230Z,1551910611.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-06T22:16:51.231Z,1551910611.231 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-06T22:16:51.379Z,1551910611.379 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-06T22:16:51.380Z,1551910611.380 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-06T22:16:51.582Z,1551910611.582 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-06T22:16:51.583Z,1551910611.583 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-06T22:16:51.680Z,1551910611.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-06T22:16:51.680Z,1551910611.680 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-06T22:16:51.999Z,1551910611.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-06T22:16:51.000Z,1551910612.000 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-06T22:16:52.080Z,1551910612.080 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-06T22:16:52.186Z,1551910612.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-06T22:16:52.186Z,1551910612.186 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-06T22:16:52.696Z,1551910612.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-06T22:16:52.696Z,1551910612.696 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-06T22:16:53.128Z,1551910613.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-06T22:16:53.130Z,1551910613.130 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-06T22:16:53.131Z,1551910613.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-06T22:16:53.346Z,1551910613.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-06T22:16:53.448Z,1551910613.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-06T22:16:53.547Z,1551910613.547 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-06T22:16:53.778Z,1551910613.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-06T22:16:53.778Z,1551910613.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-06T22:16:53.863Z,1551910613.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-06T22:16:53.958Z,1551910613.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-06T22:16:54.057Z,1551910614.057 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-06T22:16:54.139Z,1551910614.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-06T22:16:54.250Z,1551910614.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-06T22:16:54.428Z,1551910614.428 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-06T22:16:54.563Z,1551910614.563 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-06T22:16:54.563Z,1551910614.563 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-06T22:16:54.567Z,1551910614.567 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-06T22:16:54.881Z,1551910614.881 [DataOverHttps] Loaded
2019-03-06T22:16:54.881Z,1551910614.881 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-06T22:16:54.882Z,1551910614.882 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4074D4E0
2019-03-06T22:16:54.882Z,1551910614.882 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1026
2019-03-06T22:16:54.921Z,1551910614.921 [Depth_Keller] Loaded
2019-03-06T22:16:54.921Z,1551910614.921 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-06T22:16:54.926Z,1551910614.926 [DropWeight] Loaded
2019-03-06T22:16:54.926Z,1551910614.926 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-06T22:16:54.974Z,1551910614.974 [DUSBL_Hydroid] Loaded
2019-03-06T22:16:54.974Z,1551910614.974 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-06T22:16:55.015Z,1551910615.015 [Micromodem] Loaded
2019-03-06T22:16:55.015Z,1551910615.015 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-06T22:16:55.112Z,1551910615.112 [NAL9602] Loaded
2019-03-06T22:16:55.112Z,1551910615.112 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-06T22:16:55.127Z,1551910615.127 [Onboard] Loaded
2019-03-06T22:16:55.128Z,1551910615.128 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-06T22:16:55.134Z,1551910615.134 [Radio_Surface] Loaded
2019-03-06T22:16:55.135Z,1551910615.135 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-06T22:16:55.136Z,1551910615.136 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4077D4E0
2019-03-06T22:16:55.136Z,1551910615.136 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1027
2019-03-06T22:16:55.180Z,1551910615.180 [RDI_Pathfinder] Loaded
2019-03-06T22:16:55.181Z,1551910615.181 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-06T22:16:55.203Z,1551910615.203 [RDI_PathfinderUp] Loaded
2019-03-06T22:16:55.203Z,1551910615.203 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-06T22:16:57.569Z,1551910617.569 [BPC1] Loaded
2019-03-06T22:16:57.569Z,1551910617.569 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-06T22:16:57.570Z,1551910617.570 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-06T22:16:57.570Z,1551910617.570 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-06T22:16:57.583Z,1551910617.583 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-06T22:16:57.584Z,1551910617.584 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-06T22:16:57.690Z,1551910617.690 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-06T22:16:57.690Z,1551910617.690 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-06T22:16:57.750Z,1551910617.750 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-06T22:16:57.750Z,1551910617.750 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-06T22:16:57.810Z,1551910617.810 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-06T22:16:57.810Z,1551910617.810 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-06T22:16:57.830Z,1551910617.830 [NavChart] Loaded
2019-03-06T22:16:57.831Z,1551910617.831 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-06T22:16:57.835Z,1551910617.835 [UniversalFixResidualReporter] Loaded
2019-03-06T22:16:57.835Z,1551910617.835 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-06T22:16:57.835Z,1551910617.835 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-06T22:16:57.836Z,1551910617.836 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-06T22:16:57.938Z,1551910617.938 [BuoyancyServo] Loaded
2019-03-06T22:16:57.938Z,1551910617.938 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-06T22:16:57.954Z,1551910617.954 [ElevatorServo] Loaded
2019-03-06T22:16:57.954Z,1551910617.954 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-06T22:16:57.968Z,1551910617.968 [MassServo] Loaded
2019-03-06T22:16:57.969Z,1551910617.969 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-06T22:16:57.984Z,1551910617.984 [RudderServo] Loaded
2019-03-06T22:16:57.984Z,1551910617.984 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-06T22:16:57.999Z,1551910617.999 [ThrusterServo] Loaded
2019-03-06T22:16:57.999Z,1551910617.999 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-06T22:16:57.000Z,1551910618.000 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-06T22:16:58.000Z,1551910618.000 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-06T22:16:58.183Z,1551910618.183 [Aanderaa_O2] Loaded
2019-03-06T22:16:58.183Z,1551910618.183 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-03-06T22:16:58.268Z,1551910618.268 [CTD_NeilBrown] Loaded
2019-03-06T22:16:58.269Z,1551910618.269 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-06T22:16:58.270Z,1551910618.270 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408C24E0
2019-03-06T22:16:58.270Z,1551910618.270 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1028
2019-03-06T22:16:58.315Z,1551910618.315 [WetLabsSeaOWL_UV_A] Loaded
2019-03-06T22:16:58.316Z,1551910618.316 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-06T22:16:58.316Z,1551910618.316 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2019-03-06T22:16:58.317Z,1551910618.317 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1029
2019-03-06T22:16:58.318Z,1551910618.318 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-06T22:16:58.318Z,1551910618.318 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-06T22:16:58.558Z,1551910618.558 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-06T22:16:58.558Z,1551910618.558 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-06T22:16:58.599Z,1551910618.599 [DepthRateCalculator] Loaded
2019-03-06T22:16:58.599Z,1551910618.599 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-06T22:16:58.605Z,1551910618.605 [PitchRateCalculator] Loaded
2019-03-06T22:16:58.605Z,1551910618.605 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-06T22:16:58.618Z,1551910618.618 [SpeedCalculator] Loaded
2019-03-06T22:16:58.618Z,1551910618.618 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-06T22:16:58.639Z,1551910618.639 [TempGradientCalculator] Loaded
2019-03-06T22:16:58.639Z,1551910618.639 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-06T22:16:58.645Z,1551910618.645 [YawRateCalculator] Loaded
2019-03-06T22:16:58.645Z,1551910618.645 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-06T22:16:58.686Z,1551910618.686 [ElevatorOffsetCalculator] Loaded
2019-03-06T22:16:58.686Z,1551910618.686 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-06T22:16:58.687Z,1551910618.687 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-06T22:16:58.687Z,1551910618.687 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-06T22:16:58.822Z,1551910618.822 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-06T22:16:58.844Z,1551910618.844 [SBIT] Loaded
2019-03-06T22:16:58.845Z,1551910618.845 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-06T22:16:58.845Z,1551910618.845 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-06T22:16:58.857Z,1551910618.857 [IBIT] Loaded
2019-03-06T22:16:58.857Z,1551910618.857 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-06T22:16:58.860Z,1551910618.860 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-06T22:16:58.000Z,1551910619.000 [CBIT] Loaded
2019-03-06T22:16:59.000Z,1551910619.000 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-06T22:16:59.001Z,1551910619.001 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-06T22:16:59.001Z,1551910619.001 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-06T22:16:59.064Z,1551910619.064 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-06T22:16:59.064Z,1551910619.064 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-06T22:16:59.162Z,1551910619.162 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-06T22:16:59.163Z,1551910619.163 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-06T22:16:59.229Z,1551910619.229 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-06T22:16:59.314Z,1551910619.314 [VerticalControl] Loaded
2019-03-06T22:16:59.314Z,1551910619.314 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-06T22:16:59.315Z,1551910619.315 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-06T22:16:59.372Z,1551910619.372 [HorizontalControl] Loaded
2019-03-06T22:16:59.373Z,1551910619.373 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-06T22:16:59.373Z,1551910619.373 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-06T22:16:59.375Z,1551910619.375 [SpeedControl] Loaded
2019-03-06T22:16:59.375Z,1551910619.375 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-06T22:16:59.376Z,1551910619.376 [LoopControl](DEBUG): Construct LoopControl.
2019-03-06T22:16:59.376Z,1551910619.376 [LoopControl] Loaded
2019-03-06T22:16:59.377Z,1551910619.377 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-06T22:16:59.377Z,1551910619.377 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-06T22:16:59.378Z,1551910619.378 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-06T22:16:59.405Z,1551910619.405 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-06T22:16:59.408Z,1551910619.408 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-06T22:16:59.409Z,1551910619.409 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-06T22:16:59.416Z,1551910619.416 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-06T22:16:59.417Z,1551910619.417 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB04E0
2019-03-06T22:16:59.417Z,1551910619.417 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1030
2019-03-06T22:16:59.422Z,1551910619.422 [Supervisor](INFO): Main Thread ID is 800
2019-03-06T22:16:59.422Z,1551910619.422 [Supervisor](DEBUG): Running supervisor.
2019-03-06T22:16:59.423Z,1551910619.423 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1031
2019-03-06T22:16:59.425Z,1551910619.425 [controlThread ThreadHandler](INFO): Handler Thread ID is 1032
2019-03-06T22:16:59.426Z,1551910619.426 [controlThread](DEBUG): Initializing ControlThread
2019-03-06T22:16:59.432Z,1551910619.432 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:16:59.433Z,1551910619.433 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-06T22:16:59.433Z,1551910619.433 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-06T22:16:59.435Z,1551910619.435 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-06T22:16:59.435Z,1551910619.435 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-06T22:16:59.435Z,1551910619.435 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-06T22:16:59.435Z,1551910619.435 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-06T22:16:59.436Z,1551910619.436 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-06T22:16:59.436Z,1551910619.436 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-06T22:16:59.437Z,1551910619.437 [SBIT](INFO): Initialize SBIT Component.
2019-03-06T22:16:59.438Z,1551910619.438 [SBIT](IMPORTANT): git: 2019-03-04-36-g743def3
2019-03-06T22:16:59.438Z,1551910619.438 [SBIT](INFO): git hash: 743def31acf12ef8e20e672f0fefb031590b97fa
2019-03-06T22:16:59.438Z,1551910619.438 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-06T22:16:59.438Z,1551910619.438 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-06T22:16:59.439Z,1551910619.439 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-06T22:16:59.440Z,1551910619.440 [IBIT](INFO): Initialize IBIT Component.
2019-03-06T22:16:59.441Z,1551910619.441 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-06T22:16:59.442Z,1551910619.442 [logger ThreadHandler](INFO): Handler Thread ID is 1033
2019-03-06T22:16:59.453Z,1551910619.453 [CBIT](DEBUG): Initialized mux pins.
2019-03-06T22:16:59.453Z,1551910619.453 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-06T22:16:59.461Z,1551910619.461 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1034
2019-03-06T22:16:59.462Z,1551910619.462 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-06T22:16:59.473Z,1551910619.473 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1035
2019-03-06T22:16:59.477Z,1551910619.477 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-06T22:16:59.477Z,1551910619.477 [CBIT](DEBUG): Initializing heartbeat.
2019-03-06T22:16:59.485Z,1551910619.485 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1036
2019-03-06T22:16:59.486Z,1551910619.486 [CTD_NeilBrown](INFO): Powering down
2019-03-06T22:16:59.513Z,1551910619.513 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1037
2019-03-06T22:16:59.514Z,1551910619.514 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-06T22:16:59.549Z,1551910619.549 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-06T22:16:59.549Z,1551910619.549 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-06T22:16:59.550Z,1551910619.550 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1038
2019-03-06T22:16:59.553Z,1551910619.553 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-06T22:16:59.553Z,1551910619.553 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-06T22:16:59.553Z,1551910619.553 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-06T22:16:59.553Z,1551910619.553 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-06T22:16:59.554Z,1551910619.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-06T22:16:59.554Z,1551910619.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-06T22:16:59.554Z,1551910619.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-06T22:16:59.554Z,1551910619.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-06T22:16:59.554Z,1551910619.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-06T22:16:59.554Z,1551910619.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-06T22:16:59.555Z,1551910619.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-06T22:16:59.555Z,1551910619.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-06T22:16:59.555Z,1551910619.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-06T22:16:59.555Z,1551910619.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-06T22:16:59.555Z,1551910619.555 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-06T22:16:59.555Z,1551910619.555 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-06T22:16:59.585Z,1551910619.585 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-06T22:16:59.587Z,1551910619.587 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-06T22:16:59.587Z,1551910619.587 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-06T22:16:59.588Z,1551910619.588 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-06T22:16:59.589Z,1551910619.589 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-06T22:16:59.599Z,1551910619.599 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T22:16:59.630Z,1551910619.630 [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-06T22:16:59.631Z,1551910619.631 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-06T22:16:59.697Z,1551910619.697 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-06T22:16:59.698Z,1551910619.698 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-06T22:16:59.700Z,1551910619.700 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-06T22:16:59.719Z,1551910619.719 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-06T22:16:59.746Z,1551910619.746 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-06T22:16:59.751Z,1551910619.751 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-06T22:16:59.771Z,1551910619.771 [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-06T22:16:59.776Z,1551910619.776 [controlThread](DEBUG): Component order: CycleStarter,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-06T22:16:59.807Z,1551910619.807 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-06T22:16:59.808Z,1551910619.808 [DUSBL_Hydroid](INFO): Powering up
2019-03-06T22:16:59.808Z,1551910619.808 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-06T22:16:59.877Z,1551910619.877 [Radio_Surface](INFO): Powering up
2019-03-06T22:16:59.897Z,1551910619.897 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T22:16:59.943Z,1551910619.943 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:16:59.949Z,1551910619.949 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-06T22:16:59.950Z,1551910619.950 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:16:59.961Z,1551910619.961 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-06T22:16:59.962Z,1551910619.962 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:16:59.969Z,1551910619.969 [MassServo](DEBUG): Initializing MassServo.
2019-03-06T22:16:59.970Z,1551910619.970 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:16:59.981Z,1551910619.981 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-06T22:16:59.982Z,1551910619.982 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:16:59.989Z,1551910619.989 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-06T22:17:00.199Z,1551910620.199 [Micromodem](INFO): Powering up
2019-03-06T22:17:00.199Z,1551910620.199 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-06T22:17:00.305Z,1551910620.305 [RDI_PathfinderUp](INFO): Powering down
2019-03-06T22:17:00.361Z,1551910620.361 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T22:17:00.609Z,1551910620.609 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-06T22:17:00.929Z,1551910620.929 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-06T22:17:00.929Z,1551910620.929 [RudderServo](FAULT): Rudder failed to initialize
2019-03-06T22:17:00.929Z,1551910620.929 [RudderServo] Communications Fault, FailCount= 1
2019-03-06T22:17:00.929Z,1551910620.929 [RudderServo](ERROR): Communications Fault
2019-03-06T22:17:01.040Z,1551910621.040 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-06T22:17:01.218Z,1551910621.218 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T22:17:01.218Z,1551910621.218 [RudderServo](INFO): Powering down
2019-03-06T22:17:01.886Z,1551910621.886 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-06T22:17:02.006Z,1551910622.006 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-06T22:17:02.010Z,1551910622.010 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-06T22:17:02.010Z,1551910622.010 [RudderServo] No Fault, FailCount= 1
2019-03-06T22:17:03.209Z,1551910623.209 [Aanderaa_O2](INFO): Powering down
2019-03-06T22:17:05.091Z,1551910625.091 [Micromodem](DEBUG): Nmea buf: $CCCFG,CST,1*37

2019-03-06T22:17:05.091Z,1551910625.091 [Micromodem](INFO): Nmea out: $CCCFG,CST,1*37

2019-03-06T22:17:05.091Z,1551910625.091 [Micromodem](DEBUG): Nmea buf: $CCCFG,SRC,1*31

2019-03-06T22:17:05.092Z,1551910625.092 [Micromodem](DEBUG): Nmea buf: $CCCFG,REV,1*32

2019-03-06T22:17:05.092Z,1551910625.092 [Micromodem](DEBUG): Nmea buf: $CCCFG,RXP,1*29

2019-03-06T22:17:05.496Z,1551910625.496 [Micromodem](INFO): Nmea in: $CAREV,221705,INIT,2.0.27690*4E

2019-03-06T22:17:05.900Z,1551910625.900 [Micromodem](INFO): Nmea in: $CAREV,221705,SLOT1,2.0.27690*61

2019-03-06T22:17:06.303Z,1551910626.303 [Micromodem](INFO): Nmea in: $CAREV,221705,AUV,2.0.27690*16

2019-03-06T22:17:06.707Z,1551910626.707 [Micromodem](INFO): Nmea in: $CAREV,221705,COPROC,0.10.0.46*43

2019-03-06T22:17:13.196Z,1551910633.196 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-06T22:17:15.191Z,1551910635.191 [Micromodem](INFO): Nmea in: $CATMG,2019-03-06T22:17:05Z,RTC,RTC*72

2019-03-06T22:17:15.595Z,1551910635.595 [Micromodem](INFO): Nmea in: $CAREV,221715,AUV,2.0.27690*17

2019-03-06T22:17:16.403Z,1551910636.403 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37

2019-03-06T22:17:16.807Z,1551910636.807 [Micromodem](INFO): Nmea in: $CAREV,221715,COPROC,0.10.0.46*42

2019-03-06T22:17:18.018Z,1551910638.018 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-06T22:17:25.291Z,1551910645.291 [Micromodem](INFO): Nmea in: $CAERR,221716,NI  ,12,Unknown command*4D

2019-03-06T22:17:25.292Z,1551910645.292 [NAL9602](INFO): Powering up NAL9602
2019-03-06T22:17:25.695Z,1551910645.695 [Micromodem](INFO): Nmea in: $CAREV,221725,AUV,2.0.27690*14

2019-03-06T22:17:27.714Z,1551910647.714 [Micromodem](ERROR): Nmea resend: $CCCFG,CST,1*37

2019-03-06T22:17:28.119Z,1551910648.119 [Micromodem](INFO): Nmea in: $CAREV,221725,COPROC,0.10.0.46*41

2019-03-06T22:17:28.191Z,1551910648.191 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-06T22:17:28.200Z,1551910648.200 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-06T22:17:35.175Z,1551910655.175 [Micromodem](INFO): Nmea in: $CACFG,CST,1*35

2019-03-06T22:17:35.175Z,1551910655.175 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-06T22:17:35.579Z,1551910655.579 [Micromodem](INFO): Nmea in: $CAREV,221735,AUV,2.0.27690*15

2019-03-06T22:17:35.995Z,1551910655.995 [Micromodem](INFO): Nmea in: $CAREV,221735,COPROC,0.10.0.46*40

2019-03-06T22:17:36.387Z,1551910656.387 [NAL9602](INFO): NAL9602 initialized
2019-03-06T22:17:37.209Z,1551910657.209 [NAL9602](DEBUG): Fix Requested
2019-03-06T22:17:38.890Z,1551910658.890 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.021642
CHAN A1 (24V): -0.026062
CHAN A2 (12V): -0.006749
CHAN A3 (5V): -0.001522
CHAN B0 (3.3V): -0.000870
CHAN B1 (3.15aV): -0.000691
CHAN B2 (3.15bV): -0.000122
CHAN B3 (GND): 0.000418
OPEN: 0.009405
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-06T22:17:45.351Z,1551910665.351 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-03-06T22:17:45.351Z,1551910665.351 [Micromodem](INFO): Nmea out: $CCCFG,REV,1*32

2019-03-06T22:17:45.755Z,1551910665.755 [Micromodem](INFO): Nmea in: $CAREV,221745,AUV,2.0.27690*12

2019-03-06T22:17:46.159Z,1551910666.159 [Micromodem](INFO): Nmea in: $CAREV,221745,COPROC,0.10.0.46*47

2019-03-06T22:17:55.455Z,1551910675.455 [Micromodem](INFO): Nmea in: $CACFG,REV,1*30

2019-03-06T22:17:55.455Z,1551910675.455 [Micromodem](INFO): Nmea out: $CCCFG,RXP,1*29

2019-03-06T22:17:55.859Z,1551910675.859 [Micromodem](INFO): Nmea in: $CAREV,221755,AUV,2.0.27690*13

2019-03-06T22:17:56.260Z,1551910676.260 [Micromodem](INFO): Nmea in: $CAREV,221755,COPROC,0.10.0.46*46

2019-03-06T22:18:05.151Z,1551910685.151 [Micromodem](INFO): Nmea in: $CACFG,RXP,1*2B

2019-03-06T22:18:05.152Z,1551910685.152 [Micromodem](DEBUG): Nmea buf: $CCTMS,2019-03-06T22:18:06Z,0*74

2019-03-06T22:18:05.153Z,1551910685.153 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-06T22:18:06Z,0*74

2019-03-06T22:18:05.551Z,1551910685.551 [Micromodem](INFO): Nmea in: $CAREV,221805,AUV,2.0.27690*19

2019-03-06T22:18:05.961Z,1551910685.961 [Micromodem](INFO): Nmea in: $CAREV,221805COPROC,0.10.0.4*4C
$CATMS,0,2019-03-06T22:18:06Z*76

2019-03-06T22:18:05.961Z,1551910685.961 [Micromodem](ERROR): Response from modem failed NMEA checksum: $CAREV,221805COPROC,0.10.0.4*4C
$CATMS,0,2019-03-06T22:18:06Z*76

2019-03-06T22:18:12.842Z,1551910692.842 [CommandLine](IMPORTANT): got command failComponent
2019-03-06T22:18:12.843Z,1551910692.843 [CommandLine](IMPORTANT): Failed components:
2019-03-06T22:18:12.843Z,1551910692.843 [CommandLine](IMPORTANT): No failed Components.
2019-03-06T22:18:15.335Z,1551910695.335 [Micromodem](INFO): Nmea in: $CATMG,2019-03-06T22:18:06Z,USER_CMD,RTC*3F

2019-03-06T22:18:15.336Z,1551910695.336 [Micromodem](ERROR): Response from modem unexpected: $CATMG,2019-03-06T22:18:06Z,USER_CMD,RTC*3F

2019-03-06T22:18:15.363Z,1551910695.363 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-06T22:18:15.458Z,1551910695.458 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-06T22:18:15.458Z,1551910695.458 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-06T22:18:15.459Z,1551910695.459 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-06T22:18:15.459Z,1551910695.459 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-06T22:18:15.459Z,1551910695.459 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-06T22:18:15.460Z,1551910695.460 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-06T22:18:15.460Z,1551910695.460 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-06T22:18:15.460Z,1551910695.460 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-06T22:18:15.461Z,1551910695.461 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-06T22:18:15.461Z,1551910695.461 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-06T22:18:15.461Z,1551910695.461 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-06T22:18:15.462Z,1551910695.462 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-06T22:18:15.494Z,1551910695.494 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-06T22:18:15.494Z,1551910695.494 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-06T22:18:15.494Z,1551910695.494 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-06T22:18:15.498Z,1551910695.498 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-06T22:18:15.499Z,1551910695.499 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-06T22:18:15.499Z,1551910695.499 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-06T22:18:15.500Z,1551910695.500 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-06T22:18:15.500Z,1551910695.500 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-06T22:18:15.545Z,1551910695.545 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-06T22:18:15.545Z,1551910695.545 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-06T22:18:15.546Z,1551910695.546 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-06T22:18:15.546Z,1551910695.546 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-06T22:18:15.546Z,1551910695.546 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-06T22:18:15.735Z,1551910695.735 [Micromodem](INFO): Nmea in: $CAREV,221815,AUV,2.0.27690*18

2019-03-06T22:18:16.542Z,1551910696.542 [Micromodem](ERROR): Nmea resend: $CCTMS,2019-03-06T22:18:06Z,0*74

2019-03-06T22:18:16.951Z,1551910696.951 [Micromodem](INFO): Nmea in: $CAREV,221815,COPROC,0.10.0.46*4D

2019-03-06T22:18:17.355Z,1551910697.355 [Micromodem](INFO): Nmea in: $CATMS,0,2019-03-06T22:18:06Z*76

2019-03-06T22:18:17.355Z,1551910697.355 [Micromodem](DEBUG): Nmea buf: $CCCFQ,TOP*33

2019-03-06T22:18:17.355Z,1551910697.355 [Micromodem](INFO): Nmea out: $CCCFQ,TOP*33

2019-03-06T22:18:17.760Z,1551910697.760 [Micromodem](INFO): Nmea in: $CATMG,2019-03-06T22:18:06Z,USER_CMD,RTC*3F

2019-03-06T22:18:18.160Z,1551910698.160 [Micromodem](INFO): Nmea in: $CACFG,AGC,1*34

2019-03-06T22:18:18.164Z,1551910698.164 [Micromodem](INFO): Nmea in: $CACFG,AGN,250*3F

2019-03-06T22:18:18.168Z,1551910698.168 [Micromodem](INFO): Nmea in: $CACFG,AGX,1*2F

2019-03-06T22:18:18.571Z,1551910698.571 [Micromodem](INFO): Nmea in: $CACFG,ASD,0*26

2019-03-06T22:18:18.575Z,1551910698.575 [Micromodem](INFO): Nmea in: $CACFG,BBD,0*34

2019-03-06T22:18:18.988Z,1551910698.988 [Micromodem](INFO): Nmea in: $CACFG,BND,3*3B

2019-03-06T22:18:19.013Z,1551910699.013 [Micromodem](INFO): Nmea in: $CACFG,BR1,3*52

2019-03-06T22:18:19.380Z,1551910699.380 [Micromodem](INFO): Nmea in: $CACFG,BR2,3*51

2019-03-06T22:18:19.387Z,1551910699.387 [Micromodem](INFO): Nmea in: $CACFG,BR3,3*50

2019-03-06T22:18:19.790Z,1551910699.790 [Micromodem](INFO): Nmea in: $CACFG,BR4,3*57

2019-03-06T22:18:19.798Z,1551910699.798 [Micromodem](INFO): Nmea in: $CACFG,BRN,0*2E

2019-03-06T22:18:20.191Z,1551910700.191 [Micromodem](INFO): Nmea in: $CACFG,BSP,1*30

2019-03-06T22:18:20.238Z,1551910700.238 [Micromodem](INFO): Nmea in: $CACFG,BW0,5000*60

2019-03-06T22:18:20.595Z,1551910700.595 [Micromodem](INFO): Nmea in: $CACFG,CPR,1*30

2019-03-06T22:18:20.613Z,1551910700.613 [Micromodem](INFO): Nmea in: $CACFG,CRL,50*18

2019-03-06T22:18:20.000Z,1551910701.000 [Micromodem](INFO): Nmea in: $CACFG,CST,1*35

2019-03-06T22:18:21.007Z,1551910701.007 [Micromodem](INFO): Nmea in: $CACFG,CTO,10*19

2019-03-06T22:18:21.402Z,1551910701.402 [Micromodem](INFO): Nmea in: $CACFG,DBG,0*31

2019-03-06T22:18:21.411Z,1551910701.411 [Micromodem](INFO): Nmea in: $CACFG,DGM,0*3E

2019-03-06T22:18:21.806Z,1551910701.806 [Micromodem](INFO): Nmea in: $CACFG,DOP,0*2B

2019-03-06T22:18:21.870Z,1551910701.870 [Micromodem](INFO): Nmea in: $CACFG,DQF,1*22

2019-03-06T22:18:21.924Z,1551910701.924 [SBIT](IMPORTANT): SBIT PASSED
2019-03-06T22:18:22.017Z,1551910702.017 [CommandLine](IMPORTANT): got command configSet list
2019-03-06T22:18:22.018Z,1551910702.018 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-06T22:18:22.019Z,1551910702.019 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-03-06T22:18:22.207Z,1551910702.207 [Micromodem](INFO): Nmea in: $CACFG,DTH,90*11

2019-03-06T22:18:22.215Z,1551910702.215 [Micromodem](INFO): Nmea in: $CACFG,DTO,2*2D

2019-03-06T22:18:22.300Z,1551910702.300 [MissionManager](IMPORTANT): Started mission Startup
2019-03-06T22:18:22.309Z,1551910702.309 [Startup] Running Loop=1
2019-03-06T22:18:22.309Z,1551910702.309 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-06T22:18:22.309Z,1551910702.309 [Startup:A.GoToSurface] Running Loop=1
2019-03-06T22:18:22.310Z,1551910702.310 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-06T22:18:22.310Z,1551910702.310 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-06T22:18:22.311Z,1551910702.311 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-06T22:18:22.311Z,1551910702.311 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-06T22:18:22.312Z,1551910702.312 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-06T22:18:22.312Z,1551910702.312 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-06T22:18:22.323Z,1551910702.323 [Startup:StartupSatComms] Running Loop=1
2019-03-06T22:18:22.323Z,1551910702.323 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-06T22:18:22.323Z,1551910702.323 [Startup:StartupSatComms:A] Running Loop=1
2019-03-06T22:18:22.609Z,1551910702.609 [Micromodem](INFO): Nmea in: $CACFG,DTP,90*09

2019-03-06T22:18:22.616Z,1551910702.616 [Micromodem](INFO): Nmea in: $CACFG,ECD,50*07

2019-03-06T22:18:22.655Z,1551910702.655 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-06T22:18:23.023Z,1551910703.023 [Micromodem](INFO): Nmea in: $CACFG,EFB,10*00

2019-03-06T22:18:23.030Z,1551910703.030 [Micromodem](INFO): Nmea in: $CACFG,EFF,20*07

2019-03-06T22:18:23.421Z,1551910703.421 [Micromodem](INFO): Nmea in: $CACFG,FC0,25000*42

2019-03-06T22:18:23.427Z,1551910703.427 [Micromodem](INFO): Nmea in: $CACFG,FMD,1*3E

2019-03-06T22:18:23.825Z,1551910703.825 [Micromodem](INFO): Nmea in: $CACFG,FML,200*35

2019-03-06T22:18:23.832Z,1551910703.832 [Micromodem](INFO): Nmea in: $CACFG,GPS,0*34

2019-03-06T22:18:24.224Z,1551910704.224 [Micromodem](INFO): Nmea in: $CACFG,HFC,0*3D

2019-03-06T22:18:24.231Z,1551910704.231 [Micromodem](INFO): Nmea in: $CACFG,IRE,0*2E

2019-03-06T22:18:24.628Z,1551910704.628 [Micromodem](INFO): Nmea in: $CACFG,MCM,1*32

2019-03-06T22:18:24.635Z,1551910704.635 [Micromodem](INFO): Nmea in: $CACFG,MFD,1*3E

2019-03-06T22:18:25.032Z,1551910705.032 [Micromodem](INFO): Nmea in: $CACFG,MOD,0*36

2019-03-06T22:18:25.039Z,1551910705.039 [Micromodem](INFO): Nmea in: $CACFG,MPR,0*3F

2019-03-06T22:18:25.436Z,1551910705.436 [Micromodem](INFO): Nmea in: $CACFG,MSE,0*2B

2019-03-06T22:18:25.447Z,1551910705.447 [Micromodem](INFO): Nmea in: $CACFG,MVM,0*26

2019-03-06T22:18:25.656Z,1551910705.656 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.direction
2019-03-06T22:18:25.845Z,1551910705.845 [Micromodem](INFO): Nmea in: $CACFG,NDT,120*2D

2019-03-06T22:18:25.852Z,1551910705.852 [Micromodem](INFO): Nmea in: $CACFG,NRL,25*17

2019-03-06T22:18:26.244Z,1551910706.244 [Micromodem](INFO): Nmea in: $CACFG,NRV,0*3A

2019-03-06T22:18:26.251Z,1551910706.251 [Micromodem](INFO): Nmea in: $CACFG,PAD,0*25

2019-03-06T22:18:26.650Z,1551910706.650 [Micromodem](INFO): Nmea in: $CACFG,PCM,0*2E

2019-03-06T22:18:26.661Z,1551910706.661 [Micromodem](INFO): Nmea in: $CACFG,POW,-20*27

2019-03-06T22:18:27.052Z,1551910707.052 [Micromodem](INFO): Nmea in: $CACFG,PRL,50*0B

2019-03-06T22:18:27.059Z,1551910707.059 [Micromodem](INFO): Nmea in: $CACFG,PTH,50*09

2019-03-06T22:18:27.456Z,1551910707.456 [Micromodem](INFO): Nmea in: $CACFG,PTO,14*0E

2019-03-06T22:18:27.463Z,1551910707.463 [Micromodem](INFO): Nmea in: $CACFG,REV,1*30

2019-03-06T22:18:27.860Z,1551910707.860 [Micromodem](INFO): Nmea in: $CACFG,RXA,0*3B

2019-03-06T22:18:27.867Z,1551910707.867 [Micromodem](INFO): Nmea in: $CACFG,RXD,1*3F

2019-03-06T22:18:28.264Z,1551910708.264 [Micromodem](INFO): Nmea in: $CACFG,RXP,1*2B

2019-03-06T22:18:28.271Z,1551910708.271 [Micromodem](INFO): Nmea in: $CACFG,SCG,0*27

2019-03-06T22:18:28.668Z,1551910708.668 [Micromodem](INFO): Nmea in: $CACFG,SGP,0*34

2019-03-06T22:18:28.676Z,1551910708.676 [Micromodem](INFO): Nmea in: $CACFG,SHF,0*2D

2019-03-06T22:18:29.072Z,1551910709.072 [Micromodem](INFO): Nmea in: $CACFG,SNR,0*3F

2019-03-06T22:18:29.079Z,1551910709.079 [Micromodem](INFO): Nmea in: $CACFG,SNV,0*3B

2019-03-06T22:18:29.476Z,1551910709.476 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-03-06T22:18:29.483Z,1551910709.483 [Micromodem](INFO): Nmea in: $CACFG,TAT,50*04

2019-03-06T22:18:29.880Z,1551910709.880 [Micromodem](INFO): Nmea in: $CACFG,TOA,0*2A

2019-03-06T22:18:29.888Z,1551910709.888 [Micromodem](INFO): Nmea in: $CACFG,TXD,600*3E

2019-03-06T22:18:30.284Z,1551910710.284 [Micromodem](INFO): Nmea in: $CACFG,TXP,1*2D

2019-03-06T22:18:30.315Z,1551910710.315 [Micromodem](INFO): Nmea in: $CACFG,TXF,1*3B

2019-03-06T22:18:30.688Z,1551910710.688 [Micromodem](INFO): Nmea in: $CACFG,XST,1*2E

2019-03-06T22:18:30.728Z,1551910710.728 [Micromodem](INFO): Nmea in: $CACFG,uart1.bitrate,19200*0A

2019-03-06T22:18:30.898Z,1551910710.898 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.range
2019-03-06T22:18:31.097Z,1551910711.097 [Micromodem](INFO): Nmea in: $CACFG,uart2.bitrate,19200*09

2019-03-06T22:18:31.109Z,1551910711.109 [Micromodem](INFO): Nmea in: $CACFG,uart3.bitrate,19200*08

2019-03-06T22:18:31.501Z,1551910711.501 [Micromodem](INFO): Nmea in: $CACFG,uart4.bitrate,19200*0F

2019-03-06T22:18:31.510Z,1551910711.510 [Micromodem](INFO): Nmea in: $CACFG,uart1.task,1*71

2019-03-06T22:18:31.903Z,1551910711.903 [Micromodem](INFO): Nmea in: $CACFG,uart2.task,1*72

2019-03-06T22:18:31.912Z,1551910711.912 [Micromodem](INFO): Nmea in: $CACFG,uart3.task,1*73

2019-03-06T22:18:32.307Z,1551910712.307 [Micromodem](INFO): Nmea in: $CACFG,uart4.task,1*74

2019-03-06T22:18:32.318Z,1551910712.318 [Micromodem](INFO): Nmea in: $CACFG,uart1.parse_gps,0*33

2019-03-06T22:18:32.712Z,1551910712.712 [Micromodem](INFO): Nmea in: $CACFG,uart2.parse_gps,0*30

2019-03-06T22:18:32.723Z,1551910712.723 [Micromodem](INFO): Nmea in: $CACFG,uart3.parse_gps,0*31

2019-03-06T22:18:33.117Z,1551910713.117 [Micromodem](INFO): Nmea in: $CACFG,uart4.parse_gps,0*36

2019-03-06T22:18:33.127Z,1551910713.127 [Micromodem](INFO): Nmea in: $CACFG,uart1.show_gps,0*45

2019-03-06T22:18:33.526Z,1551910713.526 [Micromodem](INFO): Nmea in: $CACFG,uart2.show_gps,0*46

2019-03-06T22:18:33.537Z,1551910713.537 [Micromodem](INFO): Nmea in: $CACFG,uart3.show_gps,0*47

2019-03-06T22:18:33.928Z,1551910713.928 [Micromodem](INFO): Nmea in: $CACFG,uart4.show_gps,0*40

2019-03-06T22:18:33.952Z,1551910713.952 [Micromodem](INFO): Nmea in: $CACFG,uart1.set_clk_GPS,0*3F

2019-03-06T22:18:34.330Z,1551910714.330 [Micromodem](INFO): Nmea in: $CACFG,uart2.set_clk_GPS,0*3C

2019-03-06T22:18:34.341Z,1551910714.341 [Micromodem](INFO): Nmea in: $CACFG,uart3.set_clk_GPS,0*3D

2019-03-06T22:18:34.734Z,1551910714.734 [Micromodem](INFO): Nmea in: $CACFG,uart4.set_clk_GPS,0*3A

2019-03-06T22:18:34.746Z,1551910714.746 [Micromodem](INFO): Nmea in: $CACFG,uart1.flowcontrol,0*08

2019-03-06T22:18:35.137Z,1551910715.137 [Micromodem](INFO): Nmea in: $CACFG,uart2.flowcontrol,0*0B

2019-03-06T22:18:35.151Z,1551910715.151 [Micromodem](INFO): Nmea in: $CACFG,uart3.flowcontrol,0*0A

2019-03-06T22:18:35.542Z,1551910715.542 [Micromodem](INFO): Nmea in: $CACFG,uart4.flowcontrol,0*0D

2019-03-06T22:18:35.551Z,1551910715.551 [Micromodem](INFO): Nmea in: $CACFG,uart4.rs485,0*40

2019-03-06T22:18:35.947Z,1551910715.947 [Micromodem](INFO): Nmea in: $CACFG,uart1.crc32,0*0E

2019-03-06T22:18:35.961Z,1551910715.961 [Micromodem](INFO): Nmea in: $CACFG,uart2.crc32,0*0D

2019-03-06T22:18:36.348Z,1551910716.348 [Micromodem](INFO): Nmea in: $CACFG,uart3.crc32,0*0C

2019-03-06T22:18:36.357Z,1551910716.357 [Micromodem](INFO): Nmea in: $CACFG,uart4.crc32,0*0B

2019-03-06T22:18:36.763Z,1551910716.763 [Micromodem](INFO): Nmea in: $CACFG,uart1.iridium_hangup_minutes,0*62

2019-03-06T22:18:36.775Z,1551910716.775 [Micromodem](INFO): Nmea in: $CACFG,sport3.bitrate,19200*70

2019-03-06T22:18:37.164Z,1551910717.164 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.level,0*65

2019-03-06T22:18:37.176Z,1551910717.176 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.location,1*0F

2019-03-06T22:18:37.567Z,1551910717.567 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.lastretrieval,0*7F

2019-03-06T22:18:37.596Z,1551910717.596 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.currentdbSize,0*59

2019-03-06T22:18:37.983Z,1551910717.983 [Micromodem](INFO): Nmea in: $CACFG,log.nmea.output_rate,0*51

2019-03-06T22:18:38.012Z,1551910718.012 [Micromodem](INFO): Nmea in: $CACFG,rec.state,0*5D

2019-03-06T22:18:38.369Z,1551910718.369 [Micromodem](INFO): Nmea in: $CACFG,rec.start_time,-1*2C

2019-03-06T22:18:38.380Z,1551910718.380 [Micromodem](INFO): Nmea in: $CACFG,rec.end_time,-1*23

2019-03-06T22:18:38.778Z,1551910718.778 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.modulation,0*04

2019-03-06T22:18:38.794Z,1551910718.794 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.bandwidth_Hz,4000*30

2019-03-06T22:18:39.184Z,1551910719.184 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.carrier_Hz,25120*13

2019-03-06T22:18:39.198Z,1551910719.198 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.nulltime_ms,200*4B

2019-03-06T22:18:39.585Z,1551910719.585 [Micromodem](INFO): Nmea in: $CACFG,fsk.packet.legacy_addressing,0*5A

2019-03-06T22:18:39.600Z,1551910719.600 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.mod_hdr_version,0*72

2019-03-06T22:18:40.004Z,1551910720.004 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.modulation,0*12

2019-03-06T22:18:40.019Z,1551910720.019 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.bandwidth_Hz,5000*27

2019-03-06T22:18:40.402Z,1551910720.402 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.carrier_Hz,25000*06

2019-03-06T22:18:40.418Z,1551910720.418 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.bandwidth0_Hz,5000*17

2019-03-06T22:18:40.801Z,1551910720.801 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.carrier0_Hz,25000*36

2019-03-06T22:18:40.837Z,1551910720.837 [Micromodem](INFO): Nmea in: $CACFG,psk.packet.nulltime_ms,250*58

2019-03-06T22:18:41.110Z,1551910721.110 [CommandLine](IMPORTANT): got command show stack
2019-03-06T22:18:41.110Z,1551910721.110 [CommandLine](IMPORTANT): Behavior Stack:

2019-03-06T22:18:41.110Z,1551910721.110 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2019-03-06T22:18:41.110Z,1551910721.110 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A
2019-03-06T22:18:41.199Z,1551910721.199 [Micromodem](INFO): Nmea in: $CACFG,detector.power_thresh,-20*6B

2019-03-06T22:18:41.213Z,1551910721.213 [Micromodem](INFO): Nmea in: $CACFG,detector.noise_thresh,50*40

2019-03-06T22:18:41.600Z,1551910721.600 [Micromodem](INFO): Nmea in: $CACFG,detector1.recv,0*77

2019-03-06T22:18:41.613Z,1551910721.613 [Micromodem](INFO): Nmea in: $CACFG,detector1.channel_mask,1*5C

2019-03-06T22:18:42.006Z,1551910722.006 [Micromodem](INFO): Nmea in: $CACFG,detector1.type,0*6D

2019-03-06T22:18:42.018Z,1551910722.018 [Micromodem](INFO): Nmea in: $CACFG,detector1.fm_dir,0*5E

2019-03-06T22:18:42.411Z,1551910722.411 [Micromodem](INFO): Nmea in: $CACFG,detector1.length_sym,40*65

2019-03-06T22:18:42.423Z,1551910722.423 [Micromodem](INFO): Nmea in: $CACFG,detector1.fm_bw_Hz,4000*6D

2019-03-06T22:18:42.816Z,1551910722.816 [Micromodem](INFO): Nmea in: $CACFG,detector1.carrier_Hz,25120*60

2019-03-06T22:18:42.827Z,1551910722.827 [Micromodem](INFO): Nmea in: $CACFG,detector1.thresh,90*5C

2019-03-06T22:18:43.220Z,1551910723.220 [Micromodem](INFO): Nmea in: $CACFG,detector2.recv,1*75

2019-03-06T22:18:43.234Z,1551910723.234 [Micromodem](INFO): Nmea in: $CACFG,detector2.channel_mask,1*5F

2019-03-06T22:18:43.626Z,1551910723.626 [Micromodem](INFO): Nmea in: $CACFG,detector2.type,0*6E

2019-03-06T22:18:43.637Z,1551910723.637 [Micromodem](INFO): Nmea in: $CACFG,detector2.fm_dir,1*5C

2019-03-06T22:18:44.034Z,1551910724.034 [Micromodem](INFO): Nmea in: $CACFG,detector2.length_sym,200*50

2019-03-06T22:18:44.047Z,1551910724.047 [Micromodem](INFO): Nmea in: $CACFG,detector2.fm_bw_Hz,4000*6E

2019-03-06T22:18:44.436Z,1551910724.436 [Micromodem](INFO): Nmea in: $CACFG,detector2.carrier_Hz,25120*63

2019-03-06T22:18:44.464Z,1551910724.464 [Micromodem](INFO): Nmea in: $CACFG,detector2.thresh,90*5F

2019-03-06T22:18:44.832Z,1551910724.832 [Micromodem](INFO): Nmea in: $CACFG,detector3.recv,2*77

2019-03-06T22:18:44.847Z,1551910724.847 [Micromodem](INFO): Nmea in: $CACFG,detector3.channel_mask,1*5E

2019-03-06T22:18:45.244Z,1551910725.244 [Micromodem](INFO): Nmea in: $CACFG,detector3.type,1*6E

2019-03-06T22:18:45.267Z,1551910725.267 [Micromodem](INFO): Nmea in: $CACFG,detector3.fm_dir,0*5C

2019-03-06T22:18:45.643Z,1551910725.643 [Micromodem](INFO): Nmea in: $CACFG,detector3.length_sym,200*51

2019-03-06T22:18:45.656Z,1551910725.656 [Micromodem](INFO): Nmea in: $CACFG,detector3.fm_bw_Hz,4000*6F

2019-03-06T22:18:46.010Z,1551910726.010 [CommandLine](IMPORTANT): got command get depth
2019-03-06T22:18:46.010Z,1551910726.010 [CommandLine](IMPORTANT): depth 0.142125 m
2019-03-06T22:18:46.047Z,1551910726.047 [Micromodem](INFO): Nmea in: $CACFG,detector3.carrier_Hz,25120*62

2019-03-06T22:18:46.059Z,1551910726.059 [Micromodem](INFO): Nmea in: $CACFG,detector3.thresh,120*64

2019-03-06T22:18:46.448Z,1551910726.448 [Micromodem](INFO): Nmea in: $CACFG,recv.mca.gain,250*1B

2019-03-06T22:18:46.460Z,1551910726.460 [Micromodem](INFO): Nmea in: $CACFG,recv.onboard_gain,250*7E

2019-03-06T22:18:46.860Z,1551910726.860 [Micromodem](INFO): Nmea in: $CACFG,recv.onboard_gain_dB,32*31

2019-03-06T22:18:46.874Z,1551910726.874 [Micromodem](INFO): Nmea in: $CACFG,recv.onboard_equalizer,0*10

2019-03-06T22:18:47.256Z,1551910727.256 [Micromodem](INFO): Nmea in: $CACFG,recv.agn_legacy,1*7F

2019-03-06T22:18:47.279Z,1551910727.279 [Micromodem](INFO): Nmea in: $CACFG,recv.p2b_chmask,1*3D

2019-03-06T22:18:47.666Z,1551910727.666 [Micromodem](INFO): Nmea in: $CACFG,recv.agc_length_ms,100*3A

2019-03-06T22:18:47.677Z,1551910727.677 [Micromodem](INFO): Nmea in: $CACFG,recv.agc_legacy,1*72

2019-03-06T22:18:48.069Z,1551910728.069 [Micromodem](INFO): Nmea in: $CACFG,recv.digital_rcvr,0*6C

2019-03-06T22:18:48.080Z,1551910728.080 [Micromodem](INFO): Nmea in: $CACFG,recv.base64data,0*5B

2019-03-06T22:18:48.470Z,1551910728.470 [Micromodem](INFO): Nmea in: $CACFG,xmit.poweramp_gating,0*18

2019-03-06T22:18:48.481Z,1551910728.481 [Micromodem](INFO): Nmea in: $CACFG,xmit.txinhibit,0*23

2019-03-06T22:18:48.879Z,1551910728.879 [Micromodem](INFO): Nmea in: $CACFG,xmit.uart_disable_mask,15*10

2019-03-06T22:18:48.891Z,1551910728.891 [Micromodem](INFO): Nmea in: $CACFG,timing.clock_source,2*66

2019-03-06T22:18:49.281Z,1551910729.281 [Micromodem](INFO): Nmea in: $CACFG,timing.PPS_source,1*5E

2019-03-06T22:18:49.292Z,1551910729.292 [Micromodem](INFO): Nmea in: $CACFG,timing.syncnav,0*30

2019-03-06T22:18:49.685Z,1551910729.685 [Micromodem](INFO): Nmea in: $CACFG,timing.scg_timeout,0*1D

2019-03-06T22:18:49.697Z,1551910729.697 [Micromodem](INFO): Nmea in: $CACFG,timing.pps_timeout,3*1A

2019-03-06T22:18:50.092Z,1551910730.092 [Micromodem](INFO): Nmea in: $CACFG,timing.extpps_enable,1*0B

2019-03-06T22:18:50.106Z,1551910730.106 [Micromodem](INFO): Nmea in: $CACFG,timing.extpps_en_after_n,0*0A

2019-03-06T22:18:50.498Z,1551910730.498 [Micromodem](INFO): Nmea in: $CACFG,timing.setclk_after_n,0*42

2019-03-06T22:18:50.514Z,1551910730.514 [Micromodem](INFO): Nmea in: $CACFG,timing.extpps_disable_after_n,0*75

2019-03-06T22:18:50.895Z,1551910730.895 [Micromodem](INFO): Nmea in: $CACFG,timing.debug,0*3F

2019-03-06T22:18:50.908Z,1551910730.908 [Micromodem](INFO): Nmea in: $CACFG,hibernate.wake_interval,0*6C

2019-03-06T22:18:51.303Z,1551910731.303 [Micromodem](INFO): Nmea in: $CACFG,hibernate.wake_reference,0*18

2019-03-06T22:18:51.317Z,1551910731.317 [Micromodem](INFO): Nmea in: $CACFG,hibernate.hibernate_after,0*65

2019-03-06T22:18:51.702Z,1551910731.702 [Micromodem](INFO): Nmea in: $CACFG,nav.soundspeed_mps,1430*24

2019-03-06T22:18:51.710Z,1551910731.710 [Micromodem](INFO): Nmea in: $CACFG,nav.nst,0*4E

2019-03-06T22:18:52.103Z,1551910732.103 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.mode,0*02

2019-03-06T22:18:52.112Z,1551910732.112 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.dbg,0*60

2019-03-06T22:18:52.513Z,1551910732.513 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_type,0*3F

2019-03-06T22:18:52.527Z,1551910732.527 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs1_x_mm,-15*64

2019-03-06T22:18:52.919Z,1551910732.919 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs1_y_mm,15*48

2019-03-06T22:18:52.932Z,1551910732.932 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs1_z_mm,0*7F

2019-03-06T22:18:53.320Z,1551910733.320 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs2_x_mm,15*4A

2019-03-06T22:18:53.333Z,1551910733.333 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs2_y_mm,15*4B

2019-03-06T22:18:53.725Z,1551910733.725 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs2_z_mm,0*7C

2019-03-06T22:18:53.738Z,1551910733.738 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs3_x_mm,15*4B

2019-03-06T22:18:54.131Z,1551910734.131 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs3_y_mm,-15*67

2019-03-06T22:18:54.165Z,1551910734.165 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs3_z_mm,0*7D

2019-03-06T22:18:54.534Z,1551910734.534 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs4_x_mm,-15*61

2019-03-06T22:18:54.547Z,1551910734.547 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs4_y_mm,-15*60

2019-03-06T22:18:54.939Z,1551910734.939 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.array_locs4_z_mm,0*7A

2019-03-06T22:18:54.951Z,1551910734.951 [Micromodem](INFO): Nmea in: $CACFG,nav.usbl.channel_mask,30*1A

2019-03-06T22:18:55.340Z,1551910735.340 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.mode,0*75

2019-03-06T22:18:55.352Z,1551910735.352 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.carrier_Hz,13500*60

2019-03-06T22:18:55.742Z,1551910735.742 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.cycles_per_sym,7*72

2019-03-06T22:18:55.751Z,1551910735.751 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.data,0*66

2019-03-06T22:18:56.146Z,1551910736.146 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.sequence_len,47*62

2019-03-06T22:18:56.157Z,1551910736.157 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.timing_mode,0*3A

2019-03-06T22:18:56.549Z,1551910736.549 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.period,1*72

2019-03-06T22:18:56.566Z,1551910736.566 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.reference_time,2015-12-04T00:00:00Z*68

2019-03-06T22:18:56.954Z,1551910736.954 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.bandwidth_Hz,4000*40

2019-03-06T22:18:57.006Z,1551910737.006 [Micromodem](INFO): Nmea in: $CACFG,nav.trackping.duration_msx10,35*66

2019-03-06T22:18:57.356Z,1551910737.356 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.mode,0*1A

2019-03-06T22:18:57.363Z,1551910737.363 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.type,1*00

2019-03-06T22:18:57.758Z,1551910737.758 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-03-06T22:18:57.768Z,1551910737.768 [Micromodem](INFO): Nmea in: $CACFG,info.part_number,201005*2D

2019-03-06T22:18:58.162Z,1551910738.162 [Micromodem](INFO): Nmea in: $CACFG,info.serial_number,673*0E

2019-03-06T22:18:58.169Z,1551910738.169 [Micromodem](INFO): Nmea in: $CACFG,info.board_rev,D*60

2019-03-06T22:18:58.565Z,1551910738.565 [Micromodem](INFO): Nmea in: $CACFG,info.bom_variant,M*65

2019-03-06T22:18:58.572Z,1551910738.572 [Micromodem](INFO): Nmea in: $CACFG,info.bom_rev,1*0F

2019-03-06T22:18:58.991Z,1551910738.991 [Micromodem](INFO): Nmea in: $CACFG,info.fpga_version,2.0.18017*68

2019-03-06T22:18:59.002Z,1551910739.002 [Micromodem](INFO): Nmea in: $CACFG,info.fpga_api_level,3*4D

2019-03-06T22:18:59.379Z,1551910739.379 [Micromodem](INFO): Nmea in: $CACFG,info.fpga_flashrom_version,2*38

2019-03-06T22:18:59.386Z,1551910739.386 [Micromodem](INFO): Nmea in: $CACFG,info.build_oem,A*6F

2019-03-06T22:18:59.783Z,1551910739.783 [Micromodem](INFO): Nmea in: $CACFG,info.build_year,2016*43

2019-03-06T22:18:59.790Z,1551910739.790 [Micromodem](INFO): Nmea in: $CACFG,info.build_month,5*0C

2019-03-06T22:18:59.810Z,1551910739.810 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:18:59.810Z,1551910739.810 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-06T22:18:59.810Z,1551910739.810 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T22:18:59.812Z,1551910739.812 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:18:59.812Z,1551910739.812 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-06T22:18:59.812Z,1551910739.812 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T22:18:59.814Z,1551910739.814 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:18:59.814Z,1551910739.814 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-06T22:18:59.814Z,1551910739.814 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T22:18:59.825Z,1551910739.825 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T22:18:59.825Z,1551910739.825 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T22:18:59.825Z,1551910739.825 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T22:19:00.181Z,1551910740.181 [Micromodem](INFO): Nmea in: $CACFG,info.build_options,0*09

2019-03-06T22:19:00.207Z,1551910740.207 [Micromodem](INFO): Nmea in: $CACFG,info.coproc_version,0.10.0.46.0 *54

2019-03-06T22:19:00.248Z,1551910740.248 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T22:19:00.248Z,1551910740.248 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-06T22:19:00.248Z,1551910740.248 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T22:19:00.248Z,1551910740.248 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-06T22:19:00.249Z,1551910740.249 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T22:19:00.249Z,1551910740.249 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-06T22:19:00.587Z,1551910740.587 [Micromodem](INFO): Nmea in: $CACFG,info.firmware_version,2.0.27690*6C

2019-03-06T22:19:00.595Z,1551910740.595 [Micromodem](INFO): Nmea in: $CACFG,info.loader_version,17175*61

2019-03-06T22:19:00.632Z,1551910740.632 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:19:01.001Z,1551910741.001 [Micromodem](INFO): Nmea in: $CACFG,info.booted_slot,1*1D

2019-03-06T22:19:01.006Z,1551910741.006 [Micromodem](INFO): Nmea in: $CACFG,info.debugio,6*21

2019-03-06T22:19:01.393Z,1551910741.393 [Micromodem](INFO): Nmea in: $CACFG,hwd.batt_v,23.628000*1C

2019-03-06T22:19:01.400Z,1551910741.400 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux_adc_v,0.088000*77

2019-03-06T22:19:01.796Z,1551910741.796 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux_adc_gain,1*2F

2019-03-06T22:19:01.802Z,1551910741.802 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux_adc_res,12*78

2019-03-06T22:19:02.203Z,1551910742.203 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux33V_on,1*40

2019-03-06T22:19:02.209Z,1551910742.209 [Micromodem](INFO): Nmea in: $CACFG,hwd.aux33V_fault,0*2A

2019-03-06T22:19:02.608Z,1551910742.608 [Micromodem](INFO): Nmea in: $CACFG,hwd.stack33V_on,1*42

2019-03-06T22:19:02.615Z,1551910742.615 [Micromodem](INFO): Nmea in: $CACFG,hwd.stack33V_fault,0*28

2019-03-06T22:19:03.009Z,1551910743.009 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpwramp_on,1*25

2019-03-06T22:19:03.015Z,1551910743.015 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpwramp_fault,0*4F

2019-03-06T22:19:03.415Z,1551910743.415 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpreamp_on,1*37

2019-03-06T22:19:03.434Z,1551910743.434 [Micromodem](INFO): Nmea in: $CACFG,hwd.Vpreamp_fault,0*5D

2019-03-06T22:19:03.815Z,1551910743.815 [Micromodem](INFO): Nmea in: $CACFG,hwd.VSDcard_on,0*2E

2019-03-06T22:19:03.822Z,1551910743.822 [Micromodem](INFO): Nmea in: $CACFG,hwd.VSDcard_fault,0*45

2019-03-06T22:19:04.222Z,1551910744.222 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio1,0*05

2019-03-06T22:19:04.227Z,1551910744.227 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio2,0*06

2019-03-06T22:19:04.622Z,1551910744.622 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio3,0*07

2019-03-06T22:19:04.626Z,1551910744.626 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio4,1*01

2019-03-06T22:19:05.030Z,1551910745.030 [Micromodem](INFO): Nmea in: $CACFG,hwd.gpio5,0*01

2019-03-06T22:19:05.035Z,1551910745.035 [Micromodem](INFO): Nmea in: $CACFG,hwd.txinhibit,0*50

2019-03-06T22:19:05.435Z,1551910745.435 [Micromodem](INFO): Nmea in: $CACFG,hwd.temp,26.812500*3D

2019-03-06T22:19:05.442Z,1551910745.442 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.mm_rom_valid,0*35

2019-03-06T22:19:05.839Z,1551910745.839 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.mm_rom,0*1C

2019-03-06T22:19:05.845Z,1551910745.845 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.pa_rom_valid,0*24

2019-03-06T22:19:06.239Z,1551910746.239 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.pa_rom,0*0D

2019-03-06T22:19:06.246Z,1551910746.246 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.pa_rom_boot_search,0*15

2019-03-06T22:19:06.653Z,1551910746.653 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux1_rom_valid,0*68

2019-03-06T22:19:06.658Z,1551910746.658 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux1_rom,0*41

2019-03-06T22:19:07.052Z,1551910747.052 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux1_rom_boot_search,0*59

2019-03-06T22:19:07.058Z,1551910747.058 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux2_rom_valid,0*6B

2019-03-06T22:19:07.451Z,1551910747.451 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux2_rom,0*42

2019-03-06T22:19:07.457Z,1551910747.457 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux2_rom_boot_search,0*5A

2019-03-06T22:19:07.855Z,1551910747.855 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux3_rom_valid,0*6A

2019-03-06T22:19:07.859Z,1551910747.859 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux3_rom,0*43

2019-03-06T22:19:08.264Z,1551910748.264 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux3_rom_boot_search,0*5B

2019-03-06T22:19:08.268Z,1551910748.268 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux4_rom_valid,0*6D

2019-03-06T22:19:08.662Z,1551910748.662 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux4_rom,0*44

2019-03-06T22:19:08.668Z,1551910748.668 [Micromodem](INFO): Nmea in: $CACFG,hwd.1wire.aux4_rom_boot_search,0*5C

2019-03-06T22:19:09.067Z,1551910749.067 [Micromodem](INFO): Nmea in: $CACFG,pwramp.temperature_degC,22.062500*5E

2019-03-06T22:19:09.071Z,1551910749.071 [Micromodem](INFO): Nmea in: $CACFG,pwramp.vbat,24.136003*49

2019-03-06T22:19:09.474Z,1551910749.474 [Micromodem](INFO): Nmea in: $CACFG,pwramp.vtransmit,0.000000*05

2019-03-06T22:19:09.479Z,1551910749.479 [Micromodem](INFO): Nmea in: $CACFG,pwramp.iout_sense_amps,0.000000*1F

2019-03-06T22:19:09.879Z,1551910749.879 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,3*2E

2019-03-06T22:19:09.883Z,1551910749.883 [Micromodem](INFO): Nmea in: $CACFG,pwramp.stackpower_conf,1*1C

2019-03-06T22:19:10.277Z,1551910750.277 [Micromodem](INFO): Nmea in: $CACFG,pwramp.VPS_on,1*5D

2019-03-06T22:19:10.279Z,1551910750.279 [Micromodem](INFO): Nmea in: $CACFG,pwramp.gpio5,3*70

2019-03-06T22:19:10.684Z,1551910750.684 [Micromodem](INFO): Nmea in: $CACFG,pwramp.gpio6,3*73

2019-03-06T22:19:10.687Z,1551910750.687 [Micromodem](INFO): Nmea in: $CACFG,pwramp.fault,0*3D

2019-03-06T22:19:11.088Z,1551910751.088 [Micromodem](INFO): Nmea in: $CACFG,script.active,0*5D

2019-03-06T22:19:11.092Z,1551910751.092 [Micromodem](INFO): Nmea in: $CACFG,script.comports,15*6E

2019-03-06T22:19:11.488Z,1551910751.488 [Micromodem](INFO): Nmea in: $CACFG,script.v0,0*17

2019-03-06T22:19:11.490Z,1551910751.490 [Micromodem](INFO): Nmea in: $CACFG,script.v1,0*16

2019-03-06T22:19:11.896Z,1551910751.896 [Micromodem](INFO): Nmea in: $CACFG,script.v2,0*15

2019-03-06T22:19:11.898Z,1551910751.898 [Micromodem](INFO): Nmea in: $CACFG,script.v3,0*14

2019-03-06T22:19:12.296Z,1551910752.296 [Micromodem](INFO): Nmea in: $CACFG,script.v4,0*13

2019-03-06T22:19:12.298Z,1551910752.298 [Micromodem](INFO): Nmea in: $CACFG,script.v5,0*12

2019-03-06T22:19:12.700Z,1551910752.700 [Micromodem](INFO): Nmea in: $CACFG,script.v6,0*11

2019-03-06T22:19:12.702Z,1551910752.702 [Micromodem](INFO): Nmea in: $CACFG,script.v7,0*10

2019-03-06T22:19:13.109Z,1551910753.109 [Micromodem](INFO): Nmea in: $CACFG,script.v8,0*1F

2019-03-06T22:19:13.111Z,1551910753.111 [Micromodem](INFO): Nmea in: $CACFG,script.v9,0*1E

2019-03-06T22:19:13.512Z,1551910753.512 [Micromodem](INFO): Nmea in: $CACFG,script.vA,0*66

2019-03-06T22:19:13.514Z,1551910753.514 [Micromodem](INFO): Nmea in: $CACFG,script.vB,0*65

2019-03-06T22:19:13.915Z,1551910753.915 [Micromodem](INFO): Nmea in: $CACFG,script.vC,0*64

2019-03-06T22:19:13.917Z,1551910753.917 [Micromodem](INFO): Nmea in: $CACFG,script.vD,0*63

2019-03-06T22:19:14.319Z,1551910754.319 [Micromodem](INFO): Nmea in: $CACFG,script.vE,0*62

2019-03-06T22:19:14.321Z,1551910754.321 [Micromodem](INFO): Nmea in: $CACFG,script.vF,0*61

2019-03-06T22:19:14.724Z,1551910754.724 [Micromodem](INFO): Nmea in: $CAREV,221814,AUV,2.0.27690*19

2019-03-06T22:19:14.726Z,1551910754.726 [Micromodem](INFO): Nmea in: $CAREV,221814,COPROC,0.10.0.46*4C

2019-03-06T22:19:15.128Z,1551910755.128 [Micromodem](INFO): Nmea in: $CAREV,221824,AUV,2.0.27690*1A

2019-03-06T22:19:15.130Z,1551910755.130 [Micromodem](INFO): Nmea in: $CAREV,221824,COPROC,0.10.0.46*4F

2019-03-06T22:19:15.531Z,1551910755.531 [Micromodem](INFO): Nmea in: $CAREV,221834,AUV,2.0.27690*1B

2019-03-06T22:19:15.534Z,1551910755.534 [Micromodem](INFO): Nmea in: $CAREV,221834,COPROC,0.10.0.46*4E

2019-03-06T22:19:15.937Z,1551910755.937 [Micromodem](INFO): Nmea in: $CAREV,221844,AUV,2.0.27690*1C

2019-03-06T22:19:15.941Z,1551910755.941 [Micromodem](INFO): Nmea in: $CAREV,221844,COPROC,0.10.0.46*49

2019-03-06T22:19:16.337Z,1551910756.337 [Micromodem](INFO): Nmea in: $CAREV,221854,AUV,2.0.27690*1D

2019-03-06T22:19:16.338Z,1551910756.338 [Micromodem](INFO): Nmea in: $CAREV,221854,COPROC,0.10.0.46*48

2019-03-06T22:19:16.743Z,1551910756.743 [Micromodem](INFO): Nmea in: $CAREV,221904,AUV,2.0.27690*19

2019-03-06T22:19:22.419Z,1551910762.419 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-06T22:18:22.3Z
2019-03-06T22:19:22.419Z,1551910762.419 [Startup:StartupSatComms:A] Stopped
2019-03-06T22:19:22.419Z,1551910762.419 [Startup:StartupSatComms:B] Running Loop=1
2019-03-06T22:19:22.823Z,1551910762.823 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-06T22:19:25.227Z,1551910765.227 [Micromodem](INFO): Nmea in: $CAREV,221904,COPROC,0.10.0.46*4C

2019-03-06T22:19:25.228Z,1551910765.228 [Micromodem](INFO): Nmea in: $CAREV,221914,AUV,2.0.27690*18

2019-03-06T22:19:25.527Z,1551910765.527 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004742
2019-03-06T22:19:30.444Z,1551910770.444 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190306T221327/Courier0004.lzma
2019-03-06T22:19:31.251Z,1551910771.251 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221327/Courier0004.lzma.bak
2019-03-06T22:19:31.251Z,1551910771.251 [DataOverHttps](INFO): SBD MOMSN=10089720
2019-03-06T22:19:32.241Z,1551910772.241 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-03-06T22:19:35.323Z,1551910775.323 [Micromodem](INFO): Nmea in: $CAREV,221914,COPROC,0.10.0.46*4D

2019-03-06T22:19:35.324Z,1551910775.324 [Micromodem](INFO): Nmea in: $CAREV,221924,AUV,2.0.27690*1B

2019-03-06T22:19:41.997Z,1551910781.997 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20190306T214038/Express0014.lzma
2019-03-06T22:19:42.802Z,1551910782.802 [DataOverHttps](INFO): Moved sent file to Logs/20190306T214038/Express0014.lzma.bak
2019-03-06T22:19:42.802Z,1551910782.802 [DataOverHttps](INFO): SBD MOMSN=10089722
2019-03-06T22:19:45.423Z,1551910785.423 [Micromodem](INFO): Nmea in: $CAREV,221924,COPROC,0.10.0.46*4E

2019-03-06T22:19:45.424Z,1551910785.424 [Micromodem](INFO): Nmea in: $CAREV,221934,AUV,2.0.27690*1A

2019-03-06T22:19:53.691Z,1551910793.691 [DataOverHttps](INFO): Sending 1480 bytes from file Logs/20190306T221327/Express0001.lzma
2019-03-06T22:19:54.494Z,1551910794.494 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221327/Express0001.lzma.bak
2019-03-06T22:19:54.494Z,1551910794.494 [DataOverHttps](INFO): SBD MOMSN=10089724
2019-03-06T22:19:55.524Z,1551910795.524 [Micromodem](INFO): Nmea in: $CAREV,221934,COPROC,0.10.0.46*4F

2019-03-06T22:19:55.525Z,1551910795.525 [Micromodem](INFO): Nmea in: $CAREV,221944,AUV,2.0.27690*1D

2019-03-06T22:20:05.219Z,1551910805.219 [Micromodem](INFO): Nmea in: $CAREV,221944,COPROC,0.10.0.46*48

2019-03-06T22:20:05.220Z,1551910805.220 [Micromodem](INFO): Nmea in: $CAREV,221954,AUV,2.0.27690*1C

2019-03-06T22:20:05.297Z,1551910805.297 [DataOverHttps](INFO): Sending 262 bytes from file Logs/20190306T221327/Express0005.lzma
2019-03-06T22:20:06.102Z,1551910806.102 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221327/Express0005.lzma.bak
2019-03-06T22:20:06.103Z,1551910806.103 [DataOverHttps](INFO): SBD MOMSN=10089785
2019-03-06T22:20:15.319Z,1551910815.319 [Micromodem](INFO): Nmea in: $CAREV,221954,COPROC,0.10.0.46*49

2019-03-06T22:20:15.320Z,1551910815.320 [Micromodem](INFO): Nmea in: $CAREV,222004,AUV,2.0.27690*13

2019-03-06T22:20:16.911Z,1551910816.911 [DataOverHttps](INFO): Sending 1385 bytes from file Logs/20190306T221649/Express0001.lzma
2019-03-06T22:20:17.714Z,1551910817.714 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221649/Express0001.lzma.bak
2019-03-06T22:20:17.714Z,1551910817.714 [DataOverHttps](INFO): SBD MOMSN=10089797
2019-03-06T22:20:18.186Z,1551910818.186 [Startup:StartupSatComms:B] Stopped
2019-03-06T22:20:18.186Z,1551910818.186 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-06T22:20:18.186Z,1551910818.186 [Startup:StartupSatComms] Stopped
2019-03-06T22:20:18.186Z,1551910818.186 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-06T22:20:18.187Z,1551910818.187 [Startup](INFO): Completed Startup
2019-03-06T22:20:18.187Z,1551910818.187 [MissionManager](INFO): Startup is completed.
2019-03-06T22:20:18.188Z,1551910818.188 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-06T22:20:18.188Z,1551910818.188 [Startup] Stopped
2019-03-06T22:20:18.188Z,1551910818.188 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-06T22:20:18.188Z,1551910818.188 [Startup:A.GoToSurface] Stopped
2019-03-06T22:20:18.188Z,1551910818.188 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-06T22:20:18.591Z,1551910818.591 [MissionManager](IMPORTANT): Started mission Default
2019-03-06T22:20:18.591Z,1551910818.591 [Default] Running Loop=1
2019-03-06T22:20:18.591Z,1551910818.591 [Default](DEBUG): Aggregate::initialize Default
2019-03-06T22:20:18.591Z,1551910818.591 [Default:B.GoToSurface] Running Loop=1
2019-03-06T22:20:18.591Z,1551910818.591 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-06T22:20:18.592Z,1551910818.592 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-06T22:20:18.592Z,1551910818.592 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-06T22:20:18.592Z,1551910818.592 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-06T22:20:18.592Z,1551910818.592 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-06T22:20:18.593Z,1551910818.593 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-06T22:20:18.593Z,1551910818.593 [Default:A.Wait] Running Loop=1
2019-03-06T22:20:18.593Z,1551910818.593 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:20:25.419Z,1551910825.419 [Micromodem](INFO): Nmea in: $CAREV,222004,COPROC,0.10.0.46*46

2019-03-06T22:20:25.420Z,1551910825.420 [Micromodem](INFO): Nmea in: $CAREV,222014,AUV,2.0.27690*12

2019-03-06T22:20:31.927Z,1551910831.927 [Default:A.Wait](INFO): Done Waiting.
2019-03-06T22:20:31.927Z,1551910831.927 [Default:A.Wait] Stopped
2019-03-06T22:20:31.927Z,1551910831.927 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:20:32.339Z,1551910832.339 [Default:CheckIn] Running Loop=1
2019-03-06T22:20:32.340Z,1551910832.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-06T22:20:32.340Z,1551910832.340 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-06T22:20:32.719Z,1551910832.719 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-06T22:20:35.519Z,1551910835.519 [Micromodem](INFO): Nmea in: $CAREV,222014,COPROC,0.10.0.46*47

2019-03-06T22:20:35.520Z,1551910835.520 [Micromodem](INFO): Nmea in: $CAREV,222024,AUV,2.0.27690*11

2019-03-06T22:20:45.215Z,1551910845.215 [Micromodem](INFO): Nmea in: $CAREV,222024,COPROC,0.10.0.46*44

2019-03-06T22:20:45.216Z,1551910845.216 [Micromodem](INFO): Nmea in: $CAREV,222034,AUV,2.0.27690*10

2019-03-06T22:20:55.315Z,1551910855.315 [Micromodem](INFO): Nmea in: $CAREV,222034,COPROC,0.10.0.46*45

2019-03-06T22:20:55.316Z,1551910855.316 [Micromodem](INFO): Nmea in: $CAREV,222044,AUV,2.0.27690*17

2019-03-06T22:21:01.014Z,1551910861.014 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:21:01.014Z,1551910861.014 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-06T22:21:01.015Z,1551910861.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T22:21:01.016Z,1551910861.016 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:21:01.016Z,1551910861.016 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-06T22:21:01.016Z,1551910861.016 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T22:21:01.018Z,1551910861.018 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:21:01.018Z,1551910861.018 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-06T22:21:01.018Z,1551910861.018 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T22:21:01.029Z,1551910861.029 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T22:21:01.029Z,1551910861.029 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T22:21:01.029Z,1551910861.029 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T22:21:01.410Z,1551910861.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T22:21:01.410Z,1551910861.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-06T22:21:01.410Z,1551910861.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T22:21:01.410Z,1551910861.410 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-06T22:21:01.411Z,1551910861.411 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T22:21:01.411Z,1551910861.411 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-06T22:21:01.808Z,1551910861.808 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:21:05.415Z,1551910865.415 [Micromodem](INFO): Nmea in: $CAREV,222044,COPROC,0.10.0.46*42

2019-03-06T22:21:05.416Z,1551910865.416 [Micromodem](INFO): Nmea in: $CAREV,222054,AUV,2.0.27690*16

2019-03-06T22:21:15.521Z,1551910875.521 [Micromodem](INFO): Nmea in: $CAREV,222054,COPROC,0.10.0.46*43

2019-03-06T22:21:15.522Z,1551910875.522 [Micromodem](INFO): Nmea in: $CAREV,222104,AUV,2.0.27690*12

2019-03-06T22:21:25.211Z,1551910885.211 [Micromodem](INFO): Nmea in: $CAREV,222104,COPROC,0.10.0.46*47

2019-03-06T22:21:25.212Z,1551910885.212 [Micromodem](INFO): Nmea in: $CAREV,222114,AUV,2.0.27690*13

2019-03-06T22:21:35.315Z,1551910895.315 [Micromodem](INFO): Nmea in: $CAREV,222114,COPROC,0.10.0.46*46

2019-03-06T22:21:35.316Z,1551910895.316 [Micromodem](INFO): Nmea in: $CAREV,222124,AUV,2.0.27690*10

2019-03-06T22:21:45.411Z,1551910905.411 [Micromodem](INFO): Nmea in: $CAREV,222124,COPROC,0.10.0.46*45

2019-03-06T22:21:45.412Z,1551910905.412 [Micromodem](INFO): Nmea in: $CAREV,222134,AUV,2.0.27690*11

2019-03-06T22:21:55.511Z,1551910915.511 [Micromodem](INFO): Nmea in: $CAREV,222134,COPROC,0.10.0.46*44

2019-03-06T22:21:55.512Z,1551910915.512 [Micromodem](INFO): Nmea in: $CAREV,222144,AUV,2.0.27690*16

2019-03-06T22:22:05.208Z,1551910925.208 [Micromodem](INFO): Nmea in: $CAREV,222144,COPROC,0.10.0.46*43

2019-03-06T22:22:05.209Z,1551910925.209 [Micromodem](INFO): Nmea in: $CAREV,222154,AUV,2.0.27690*17

2019-03-06T22:22:15.307Z,1551910935.307 [Micromodem](INFO): Nmea in: $CAREV,222154,COPROC,0.10.0.46*42

2019-03-06T22:22:15.309Z,1551910935.309 [Micromodem](INFO): Nmea in: $CAREV,222204,AUV,2.0.27690*11

2019-03-06T22:22:25.411Z,1551910945.411 [Micromodem](INFO): Nmea in: $CAREV,222204,COPROC,0.10.0.46*44

2019-03-06T22:22:25.412Z,1551910945.412 [Micromodem](INFO): Nmea in: $CAREV,222214,AUV,2.0.27690*10

2019-03-06T22:22:35.507Z,1551910955.507 [Micromodem](INFO): Nmea in: $CAREV,222214,COPROC,0.10.0.46*45

2019-03-06T22:22:35.508Z,1551910955.508 [Micromodem](INFO): Nmea in: $CAREV,222224,AUV,2.0.27690*13

2019-03-06T22:22:39.952Z,1551910959.952 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-06T22:22:40.773Z,1551910960.773 [NAL9602](DEBUG): Fix Requested
2019-03-06T22:22:41.167Z,1551910961.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221815.00,A,3648.16412,N,12147.28511,W,0.233,42.25,060319,,,A*48
2019-03-06T22:22:41.174Z,1551910961.174 [NAL9602](INFO): GPS fix at 20190306T221815: (36.802735, -121.788085)
2019-03-06T22:22:41.233Z,1551910961.233 [Default:CheckIn:Read_GPS] Stopped
2019-03-06T22:22:41.233Z,1551910961.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-06T22:22:41.608Z,1551910961.608 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-06T22:22:43.975Z,1551910963.975 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190306T221649/Courier0004.lzma
2019-03-06T22:22:44.774Z,1551910964.774 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221649/Courier0004.lzma.bak
2019-03-06T22:22:44.774Z,1551910964.774 [DataOverHttps](INFO): SBD MOMSN=10089853
2019-03-06T22:22:45.299Z,1551910965.299 [Micromodem](INFO): Nmea in: $CAREV,222224,COPROC,0.10.0.46*46

2019-03-06T22:22:45.300Z,1551910965.300 [Micromodem](INFO): Nmea in: $CAREV,222234,AUV,2.0.27690*12

2019-03-06T22:22:55.399Z,1551910975.399 [Micromodem](INFO): Nmea in: $CAREV,222234,COPROC,0.10.0.46*47

2019-03-06T22:22:55.400Z,1551910975.400 [Micromodem](INFO): Nmea in: $CAREV,222244,AUV,2.0.27690*15

2019-03-06T22:22:56.922Z,1551910976.922 [CommandLine](IMPORTANT): got command show stack
2019-03-06T22:22:56.922Z,1551910976.922 [CommandLine](IMPORTANT): Behavior Stack:

2019-03-06T22:22:56.922Z,1551910976.922 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-03-06T22:22:56.922Z,1551910976.922 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_Iridium
2019-03-06T22:22:57.869Z,1551910977.869 [DataOverHttps](INFO): Sending 383 bytes from file Logs/20190306T221649/Express0005.lzma
2019-03-06T22:22:58.674Z,1551910978.674 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221649/Express0005.lzma.bak
2019-03-06T22:22:58.675Z,1551910978.675 [DataOverHttps](INFO): SBD MOMSN=10089856
2019-03-06T22:22:59.111Z,1551910979.111 [Default:CheckIn:Read_Iridium] Stopped
2019-03-06T22:22:59.111Z,1551910979.111 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-06T22:22:59.111Z,1551910979.111 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:23:01.884Z,1551910981.884 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:23:01.884Z,1551910981.884 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-06T22:23:01.884Z,1551910981.884 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T22:23:01.886Z,1551910981.886 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:23:01.886Z,1551910981.886 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-06T22:23:01.886Z,1551910981.886 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T22:23:01.888Z,1551910981.888 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:23:01.888Z,1551910981.888 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-06T22:23:01.888Z,1551910981.888 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T22:23:01.944Z,1551910981.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T22:23:01.954Z,1551910981.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T22:23:01.954Z,1551910981.954 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T22:23:02.322Z,1551910982.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T22:23:02.322Z,1551910982.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-06T22:23:02.323Z,1551910982.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T22:23:02.323Z,1551910982.323 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-06T22:23:02.323Z,1551910982.323 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T22:23:02.323Z,1551910982.323 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-06T22:23:02.696Z,1551910982.696 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:23:05.503Z,1551910985.503 [Micromodem](INFO): Nmea in: $CAREV,222244,COPROC,0.10.0.46*40

2019-03-06T22:23:05.504Z,1551910985.504 [Micromodem](INFO): Nmea in: $CAREV,222254,AUV,2.0.27690*14

2019-03-06T22:23:06.586Z,1551910986.586 [CommandLine](IMPORTANT): got command report
2019-03-06T22:23:06.587Z,1551910986.587 [Reporter](INFO): DUSBL_Hydroid.direction reporting when touched
2019-03-06T22:23:06.587Z,1551910986.587 [Reporter](INFO): DUSBL_Hydroid.range reporting when touched
2019-03-06T22:23:11.194Z,1551910991.194 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL.xml
2019-03-06T22:23:11.194Z,1551910991.194 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL.xml
2019-03-06T22:23:11.222Z,1551910991.222 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 90.000000 min
2019-03-06T22:23:11.238Z,1551910991.238 [MissionManager](INFO): DefineArg DUSBL.NumberOfRequests = 10.000000 count
2019-03-06T22:23:11.241Z,1551910991.241 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-03-06T22:23:11.245Z,1551910991.245 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-03-06T22:23:11.248Z,1551910991.248 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = 1.000000 bool
2019-03-06T22:23:11.262Z,1551910991.262 [DUSBL:A.Pitch](DEBUG): Construct.
2019-03-06T22:23:11.268Z,1551910991.268 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-03-06T22:23:11.320Z,1551910991.320 [DUSBL:RequestRepeater:C.Wait](DEBUG): Construct Wait.
2019-03-06T22:23:11.324Z,1551910991.324 [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-06T22:23:11.351Z,1551910991.351 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL.xml
2019-03-06T22:23:11.406Z,1551910991.406 [Default] Stopped
2019-03-06T22:23:11.406Z,1551910991.406 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-06T22:23:11.406Z,1551910991.406 [Default:B.GoToSurface] Stopped
2019-03-06T22:23:11.406Z,1551910991.406 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-06T22:23:11.406Z,1551910991.406 [Default:CheckIn] Stopped
2019-03-06T22:23:11.406Z,1551910991.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-06T22:23:11.406Z,1551910991.406 [Default:CheckIn:C.Wait] Stopped
2019-03-06T22:23:11.406Z,1551910991.406 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:23:11.407Z,1551910991.407 [MissionManager](IMPORTANT): Started mission DUSBL
2019-03-06T22:23:11.407Z,1551910991.407 [DUSBL] Running Loop=1
2019-03-06T22:23:11.407Z,1551910991.407 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-03-06T22:23:11.407Z,1551910991.407 [DUSBL:A.Pitch] Running Loop=1
2019-03-06T22:23:11.407Z,1551910991.407 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-03-06T22:23:11.407Z,1551910991.407 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-06T22:23:11.407Z,1551910991.407 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-03-06T22:23:11.408Z,1551910991.408 [DUSBL:C] Running Loop=1
2019-03-06T22:23:11.408Z,1551910991.408 [DUSBL:RequestRepeater] Running Loop=1
2019-03-06T22:23:11.408Z,1551910991.408 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:23:11.408Z,1551910991.408 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:23:11.408Z,1551910991.408 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:23:11.408Z,1551910991.408 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:23:11.408Z,1551910991.408 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:23:11.409Z,1551910991.409 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:23:11.409Z,1551910991.409 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:23:11.409Z,1551910991.409 [DUSBL:C] Running Loop=1
2019-03-06T22:23:11.410Z,1551910991.410 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.range
2019-03-06T22:23:11.410Z,1551910991.410 [DUSBL:C](DEBUG): Initialize ReadDataComponent to sense DUSBL_Hydroid.direction
2019-03-06T22:23:11.411Z,1551910991.411 [DUSBL:B.SetSpeed] Running Loop=1
2019-03-06T22:23:11.411Z,1551910991.411 [DUSBL:A.Pitch] Running Loop=1
2019-03-06T22:23:12.771Z,1551910992.771 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:12.775Z,1551910992.775 [Micromodem](DEBUG): Nmea buf: $CCPGT,26000,28,4A1C0370,54,24000,4A1C0370,0,0,0,1000,0*67

2019-03-06T22:23:12.776Z,1551910992.776 [Micromodem](INFO): Nmea out: $CCPGT,26000,28,4A1C0370,54,24000,4A1C0370,0,0,0,1000,0*67

2019-03-06T22:23:13.175Z,1551910993.175 [Micromodem](INFO): Nmea in: $CAREV,222254,COPROC,0.10.0.46*41

2019-03-06T22:23:13.177Z,1551910993.177 [Micromodem](INFO): Nmea in: $SNPGT,26000,28,4A1C0370,54,24000,4A1C0370,0,0,0,1000,0*7A

2019-03-06T22:23:13.589Z,1551910993.589 [Micromodem](INFO): Nmea in: $CATXP,280*48

2019-03-06T22:23:13.590Z,1551910993.590 [Micromodem](INFO): Nmea in: $CATXF,280*5E

2019-03-06T22:23:13.591Z,1551910993.591 [NAL9602](INFO): Powering down
2019-03-06T22:23:13.993Z,1551910993.993 [Micromodem](INFO): Nmea in: $SNTTA,,,,,222302.03*5E

2019-03-06T22:23:13.995Z,1551910993.995 [Micromodem](INFO): Nmea in: $CAREV,222302,AUV,2.0.27690*16

2019-03-06T22:23:16.815Z,1551910996.815 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:20.851Z,1551911000.851 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:21.692Z,1551911001.692 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:23:21.692Z,1551911001.692 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:23:21.692Z,1551911001.692 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:23:21.693Z,1551911001.693 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:23:21.693Z,1551911001.693 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:23:21.693Z,1551911001.693 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:23:21.693Z,1551911001.693 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:23:21.693Z,1551911001.693 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:23:21.693Z,1551911001.693 [DUSBL:RequestRepeater](INFO): Running loop #2
2019-03-06T22:23:21.693Z,1551911001.693 [DUSBL:RequestRepeater] Running Loop=2
2019-03-06T22:23:21.694Z,1551911001.694 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:23:21.694Z,1551911001.694 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:23:21.694Z,1551911001.694 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:23:21.694Z,1551911001.694 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:23:21.694Z,1551911001.694 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:23:23.279Z,1551911003.279 [Micromodem](INFO): Nmea in: $CAREV,222302,COPROC,0.10.0.46*43

2019-03-06T22:23:23.281Z,1551911003.281 [Micromodem](INFO): Nmea in: $CAREV,222312,AUV,2.0.27690*17

2019-03-06T22:23:24.891Z,1551911004.891 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:28.931Z,1551911008.931 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:32.207Z,1551911012.207 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:23:32.208Z,1551911012.208 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:23:32.208Z,1551911012.208 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:23:32.208Z,1551911012.208 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:23:32.208Z,1551911012.208 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:23:32.208Z,1551911012.208 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:23:32.208Z,1551911012.208 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater](INFO): Running loop #3
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater] Running Loop=3
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:23:32.209Z,1551911012.209 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:23:32.978Z,1551911012.978 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:32.984Z,1551911012.984 [Micromodem](INFO): Nmea in: $CAREV,222312,COPROC,0.10.0.46*42

2019-03-06T22:23:32.987Z,1551911012.987 [Micromodem](INFO): Nmea in: $CAREV,222322,AUV,2.0.27690*14

2019-03-06T22:23:37.010Z,1551911017.010 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:41.051Z,1551911021.051 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:42.694Z,1551911022.694 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:23:42.694Z,1551911022.694 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:23:42.695Z,1551911022.695 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:23:42.695Z,1551911022.695 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:23:42.695Z,1551911022.695 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:23:42.695Z,1551911022.695 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:23:42.695Z,1551911022.695 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:23:42.695Z,1551911022.695 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:23:42.696Z,1551911022.696 [DUSBL:RequestRepeater](INFO): Running loop #4
2019-03-06T22:23:42.696Z,1551911022.696 [DUSBL:RequestRepeater] Running Loop=4
2019-03-06T22:23:42.696Z,1551911022.696 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:23:42.696Z,1551911022.696 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:23:42.696Z,1551911022.696 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:23:42.696Z,1551911022.696 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:23:42.696Z,1551911022.696 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:23:43.071Z,1551911023.071 [Micromodem](INFO): Nmea in: $CAREV,222322,COPROC,0.10.0.46*41

2019-03-06T22:23:43.072Z,1551911023.072 [Micromodem](INFO): Nmea in: $CAREV,222332,AUV,2.0.27690*15

2019-03-06T22:23:45.091Z,1551911025.091 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:49.131Z,1551911029.131 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:53.175Z,1551911033.175 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:23:53.180Z,1551911033.180 [Micromodem](INFO): Nmea in: $CAREV,222332,COPROC,0.10.0.46*40

2019-03-06T22:23:53.180Z,1551911033.180 [Micromodem](INFO): Nmea in: $CAREV,222342,AUV,2.0.27690*12

2019-03-06T22:23:53.270Z,1551911033.270 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:23:53.270Z,1551911033.270 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:23:53.270Z,1551911033.270 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:23:53.273Z,1551911033.273 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater](INFO): Running loop #5
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater] Running Loop=5
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:23:53.274Z,1551911033.274 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:23:57.211Z,1551911037.211 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:01.251Z,1551911041.251 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:03.271Z,1551911043.271 [Micromodem](INFO): Nmea in: $CAREV,222342,COPROC,0.10.0.46*47

2019-03-06T22:24:03.273Z,1551911043.273 [Micromodem](INFO): Nmea in: $CAREV,222352,AUV,2.0.27690*13

2019-03-06T22:24:03.704Z,1551911043.704 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:24:03.704Z,1551911043.704 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:24:03.704Z,1551911043.704 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:24:03.705Z,1551911043.705 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:24:03.705Z,1551911043.705 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:24:03.705Z,1551911043.705 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:24:03.705Z,1551911043.705 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:24:03.705Z,1551911043.705 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:24:03.705Z,1551911043.705 [DUSBL:RequestRepeater](INFO): Running loop #6
2019-03-06T22:24:03.706Z,1551911043.706 [DUSBL:RequestRepeater] Running Loop=6
2019-03-06T22:24:03.706Z,1551911043.706 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:24:03.706Z,1551911043.706 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:24:03.706Z,1551911043.706 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:24:03.706Z,1551911043.706 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:24:03.706Z,1551911043.706 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:24:05.295Z,1551911045.295 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:09.331Z,1551911049.331 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:12.979Z,1551911052.979 [Micromodem](INFO): Nmea in: $CAREV,222352,COPROC,0.10.0.46*46

2019-03-06T22:24:12.983Z,1551911052.983 [Micromodem](INFO): Nmea in: $CAREV,222402,AUV,2.0.27690*11

2019-03-06T22:24:13.370Z,1551911053.370 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:14.199Z,1551911054.199 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    +0,   +12,    +9,-32768,A
2019-03-06T22:24:14.214Z,1551911054.214 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:24:14.214Z,1551911054.214 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:24:14.214Z,1551911054.214 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater](INFO): Running loop #7
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater] Running Loop=7
2019-03-06T22:24:14.215Z,1551911054.215 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:24:14.216Z,1551911054.216 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:24:14.216Z,1551911054.216 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:24:14.216Z,1551911054.216 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:24:14.216Z,1551911054.216 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:24:17.415Z,1551911057.415 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:21.455Z,1551911061.455 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:23.071Z,1551911063.071 [Micromodem](INFO): Nmea in: $CAREV,222402,COPROC,0.10.0.46*44

2019-03-06T22:24:23.072Z,1551911063.072 [Micromodem](INFO): Nmea in: $CAREV,222412,AUV,2.0.27690*10

2019-03-06T22:24:24.712Z,1551911064.712 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:24:24.712Z,1551911064.712 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:24:24.712Z,1551911064.712 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:24:24.713Z,1551911064.713 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:24:24.713Z,1551911064.713 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:24:24.713Z,1551911064.713 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:24:24.713Z,1551911064.713 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:24:24.713Z,1551911064.713 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:24:24.713Z,1551911064.713 [DUSBL:RequestRepeater](INFO): Running loop #8
2019-03-06T22:24:24.714Z,1551911064.714 [DUSBL:RequestRepeater] Running Loop=8
2019-03-06T22:24:24.714Z,1551911064.714 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:24:24.714Z,1551911064.714 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:24:24.714Z,1551911064.714 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:24:24.714Z,1551911064.714 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:24:24.714Z,1551911064.714 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:24:25.492Z,1551911065.492 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:29.531Z,1551911069.531 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:33.167Z,1551911073.167 [Micromodem](INFO): Nmea in: $CAREV,222412,COPROC,0.10.0.46*45

2019-03-06T22:24:33.168Z,1551911073.168 [Micromodem](INFO): Nmea in: $CAREV,222422,AUV,2.0.27690*13

2019-03-06T22:24:33.571Z,1551911073.571 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:35.221Z,1551911075.221 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:24:35.221Z,1551911075.221 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:24:35.221Z,1551911075.221 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:24:35.222Z,1551911075.222 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:24:35.222Z,1551911075.222 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:24:35.222Z,1551911075.222 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:24:35.222Z,1551911075.222 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:24:35.222Z,1551911075.222 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:24:35.223Z,1551911075.223 [DUSBL:RequestRepeater](INFO): Running loop #9
2019-03-06T22:24:35.223Z,1551911075.223 [DUSBL:RequestRepeater] Running Loop=9
2019-03-06T22:24:35.223Z,1551911075.223 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:24:35.223Z,1551911075.223 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:24:35.223Z,1551911075.223 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:24:35.223Z,1551911075.223 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:24:35.223Z,1551911075.223 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:24:36.021Z,1551911076.021 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-06T22:24:36.091Z,1551911076.091 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-06T22:24:36.092Z,1551911076.092 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-06T22:24:36.092Z,1551911076.092 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-06T22:24:36.094Z,1551911076.094 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-06T22:24:36.094Z,1551911076.094 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-06T22:24:36.095Z,1551911076.095 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-06T22:24:36.095Z,1551911076.095 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-06T22:24:36.095Z,1551911076.095 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-06T22:24:36.096Z,1551911076.096 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-06T22:24:36.096Z,1551911076.096 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-06T22:24:36.096Z,1551911076.096 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-06T22:24:36.097Z,1551911076.097 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-06T22:24:36.108Z,1551911076.108 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-06T22:24:36.108Z,1551911076.108 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-06T22:24:36.109Z,1551911076.109 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-06T22:24:36.112Z,1551911076.112 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-06T22:24:36.112Z,1551911076.112 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-06T22:24:36.113Z,1551911076.113 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-06T22:24:36.113Z,1551911076.113 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-06T22:24:36.114Z,1551911076.114 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-06T22:24:36.114Z,1551911076.114 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-06T22:24:36.114Z,1551911076.114 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-06T22:24:36.115Z,1551911076.115 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-06T22:24:36.115Z,1551911076.115 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-06T22:24:36.115Z,1551911076.115 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-06T22:24:36.198Z,1551911076.198 [CommandLine](IMPORTANT): DUSBL.MissionTimeout (minute)
2019-03-06T22:24:36.199Z,1551911076.199 [CommandLine](IMPORTANT): DUSBL.NumberOfRequests (count)
2019-03-06T22:24:36.199Z,1551911076.199 [CommandLine](IMPORTANT): DUSBL.TransponderCode (count)
2019-03-06T22:24:36.200Z,1551911076.200 [CommandLine](IMPORTANT): DUSBL.NumberOfPings (count)
2019-03-06T22:24:36.200Z,1551911076.200 [CommandLine](IMPORTANT): DUSBL.EnabledDUSBL (bool)
2019-03-06T22:24:36.200Z,1551911076.200 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_voltage (volt)
2019-03-06T22:24:36.205Z,1551911076.205 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgVoltage (volt)
2019-03-06T22:24:36.205Z,1551911076.205 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_current (milliampere)
2019-03-06T22:24:36.206Z,1551911076.206 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgCurrent (milliampere)
2019-03-06T22:24:37.612Z,1551911077.612 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:38.850Z,1551911078.850 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-03-06T22:24:41.651Z,1551911081.651 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:43.271Z,1551911083.271 [Micromodem](INFO): Nmea in: $CAREV,222422,COPROC,0.10.0.46*46

2019-03-06T22:24:43.273Z,1551911083.273 [Micromodem](INFO): Nmea in: $CAREV,222432,AUV,2.0.27690*12

2019-03-06T22:24:45.690Z,1551911085.690 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:45.720Z,1551911085.720 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:24:45.720Z,1551911085.720 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:24:45.720Z,1551911085.720 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:24:45.721Z,1551911085.721 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:24:45.721Z,1551911085.721 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:24:45.721Z,1551911085.721 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:24:45.721Z,1551911085.721 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:24:45.721Z,1551911085.721 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:24:45.722Z,1551911085.722 [DUSBL:RequestRepeater](INFO): Running loop #10
2019-03-06T22:24:45.722Z,1551911085.722 [DUSBL:RequestRepeater] Running Loop=10
2019-03-06T22:24:45.722Z,1551911085.722 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-03-06T22:24:45.722Z,1551911085.722 [DUSBL:RequestRepeater:A] Running Loop=1
2019-03-06T22:24:45.722Z,1551911085.722 [DUSBL:RequestRepeater:B] Running Loop=1
2019-03-06T22:24:45.722Z,1551911085.722 [DUSBL:RequestRepeater:C.Wait] Running Loop=1
2019-03-06T22:24:45.722Z,1551911085.722 [DUSBL:RequestRepeater:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:24:49.731Z,1551911089.731 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:52.976Z,1551911092.976 [Micromodem](INFO): Nmea in: $CAREV,222432,COPROC,0.10.0.46*47

2019-03-06T22:24:52.980Z,1551911092.980 [Micromodem](INFO): Nmea in: $CAREV,222442,AUV,2.0.27690*15

2019-03-06T22:24:53.234Z,1551911093.234 [CommandLine](IMPORTANT): got command report mod DUSBL_Hydroid.range
2019-03-06T22:24:53.406Z,1551911093.406 [Reporter](INFO): DUSBL_Hydroid.range no_value
2019-03-06T22:24:53.771Z,1551911093.771 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-03-06T22:24:56.231Z,1551911096.231 [DUSBL:RequestRepeater:C.Wait](INFO): Done Waiting.
2019-03-06T22:24:56.231Z,1551911096.231 [DUSBL:RequestRepeater:C.Wait] Stopped
2019-03-06T22:24:56.231Z,1551911096.231 [DUSBL:RequestRepeater:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:24:56.232Z,1551911096.232 [DUSBL:RequestRepeater](INFO): Completed DUSBL:RequestRepeater
2019-03-06T22:24:56.232Z,1551911096.232 [DUSBL:RequestRepeater] Stopped
2019-03-06T22:24:56.232Z,1551911096.232 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-03-06T22:24:56.232Z,1551911096.232 [DUSBL:RequestRepeater:A] Stopped
2019-03-06T22:24:56.232Z,1551911096.232 [DUSBL:RequestRepeater:B] Stopped
2019-03-06T22:24:56.233Z,1551911096.233 [DUSBL](INFO): Completed DUSBL
2019-03-06T22:24:56.233Z,1551911096.233 [MissionManager](INFO): DUSBL is completed.
2019-03-06T22:24:56.233Z,1551911096.233 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-03-06T22:24:56.233Z,1551911096.233 [DUSBL] Stopped
2019-03-06T22:24:56.233Z,1551911096.233 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-03-06T22:24:56.233Z,1551911096.233 [DUSBL:A.Pitch] Stopped
2019-03-06T22:24:56.234Z,1551911096.234 [DUSBL:B.SetSpeed] Stopped
2019-03-06T22:24:56.234Z,1551911096.234 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-03-06T22:24:56.234Z,1551911096.234 [DUSBL:C] Stopped
2019-03-06T22:24:56.628Z,1551911096.628 [MissionManager](IMPORTANT): Started mission Default
2019-03-06T22:24:56.628Z,1551911096.628 [Default] Running Loop=1
2019-03-06T22:24:56.628Z,1551911096.628 [Default](DEBUG): Aggregate::initialize Default
2019-03-06T22:24:56.628Z,1551911096.628 [Default:B.GoToSurface] Running Loop=1
2019-03-06T22:24:56.628Z,1551911096.628 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-06T22:24:56.629Z,1551911096.629 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-06T22:24:56.629Z,1551911096.629 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-06T22:24:56.629Z,1551911096.629 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-06T22:24:56.630Z,1551911096.630 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-06T22:24:56.630Z,1551911096.630 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-06T22:24:56.630Z,1551911096.630 [Default:A.Wait] Running Loop=1
2019-03-06T22:24:56.630Z,1551911096.630 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:25:03.059Z,1551911103.059 [Micromodem](INFO): Nmea in: $CAREV,222442,COPROC,0.10.0.46*40

2019-03-06T22:25:03.060Z,1551911103.060 [Micromodem](INFO): Nmea in: $CAREV,222452,AUV,2.0.27690*14

2019-03-06T22:25:03.090Z,1551911103.090 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:25:03.090Z,1551911103.090 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-03-06T22:25:03.090Z,1551911103.090 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T22:25:03.092Z,1551911103.092 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:25:03.092Z,1551911103.092 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-03-06T22:25:03.092Z,1551911103.092 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T22:25:03.094Z,1551911103.094 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:25:03.094Z,1551911103.094 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-03-06T22:25:03.094Z,1551911103.094 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T22:25:03.106Z,1551911103.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T22:25:03.106Z,1551911103.106 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T22:25:03.106Z,1551911103.106 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T22:25:03.514Z,1551911103.514 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T22:25:03.514Z,1551911103.514 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-03-06T22:25:03.514Z,1551911103.514 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T22:25:03.514Z,1551911103.514 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-03-06T22:25:03.515Z,1551911103.515 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T22:25:03.515Z,1551911103.515 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-03-06T22:25:03.900Z,1551911103.900 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:25:09.979Z,1551911109.979 [Default:A.Wait](INFO): Done Waiting.
2019-03-06T22:25:09.979Z,1551911109.979 [Default:A.Wait] Stopped
2019-03-06T22:25:09.979Z,1551911109.979 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:25:10.365Z,1551911110.365 [Default:CheckIn] Running Loop=1
2019-03-06T22:25:10.365Z,1551911110.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-06T22:25:10.366Z,1551911110.366 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-06T22:25:11.143Z,1551911111.143 [NAL9602](INFO): Powering up
2019-03-06T22:25:13.163Z,1551911113.163 [Micromodem](INFO): Nmea in: $CAREV,222452,COPROC,0.10.0.46*41

2019-03-06T22:25:13.164Z,1551911113.164 [Micromodem](INFO): Nmea in: $CAREV,222502,AUV,2.0.27690*10

2019-03-06T22:25:22.051Z,1551911122.051 [NAL9602](INFO): NAL9602 initialized
2019-03-06T22:25:22.873Z,1551911122.873 [NAL9602](DEBUG): Fix Requested
2019-03-06T22:25:23.263Z,1551911123.263 [Micromodem](INFO): Nmea in: $CAREV,222502,COPROC,0.10.0.46*45

2019-03-06T22:25:23.264Z,1551911123.264 [Micromodem](INFO): Nmea in: $CAREV,222512,AUV,2.0.27690*11

2019-03-06T22:25:32.970Z,1551911132.970 [Micromodem](INFO): Nmea in: $CAREV,222512,COPROC,0.10.0.46*44

2019-03-06T22:25:32.975Z,1551911132.975 [Micromodem](INFO): Nmea in: $CAREV,222522,AUV,2.0.27690*12

2019-03-06T22:25:43.059Z,1551911143.059 [Micromodem](INFO): Nmea in: $CAREV,222522,COPROC,0.10.0.46*47

2019-03-06T22:25:43.060Z,1551911143.060 [Micromodem](INFO): Nmea in: $CAREV,222532,AUV,2.0.27690*13

2019-03-06T22:25:53.159Z,1551911153.159 [Micromodem](INFO): Nmea in: $CAREV,222532,COPROC,0.10.0.46*46

2019-03-06T22:25:53.160Z,1551911153.160 [Micromodem](INFO): Nmea in: $CAREV,222542,AUV,2.0.27690*14

2019-03-06T22:25:53.979Z,1551911153.979 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-03-06T22:25:54.053Z,1551911154.053 [NAL9602](FAULT): received: 
+CSQ:0

OK
2019-03-06T22:25:54.053Z,1551911154.053 [NAL9602] Data Fault, FailCount= 1
2019-03-06T22:25:54.053Z,1551911154.053 [NAL9602](ERROR): Data Fault
2019-03-06T22:25:54.170Z,1551911154.170 [CBIT](ERROR): Data Fault in component: NAL9602
2019-03-06T22:25:54.370Z,1551911154.370 [NAL9602](INFO): Powering down
2019-03-06T22:25:55.233Z,1551911155.233 [CBIT](INFO): Clearing failed state for component NAL9602
2019-03-06T22:25:55.233Z,1551911155.233 [NAL9602] No Fault, FailCount= 1
2019-03-06T22:26:03.259Z,1551911163.259 [Micromodem](INFO): Nmea in: $CAREV,222542,COPROC,0.10.0.46*41

2019-03-06T22:26:03.261Z,1551911163.261 [Micromodem](INFO): Nmea in: $CAREV,222552,AUV,2.0.27690*15

2019-03-06T22:26:12.958Z,1551911172.958 [Micromodem](INFO): Nmea in: $CAREV,222552,COPROC,0.10.0.46*40

2019-03-06T22:26:13.355Z,1551911173.355 [Micromodem](INFO): Nmea in: $CAREV,222602,AUV,2.0.27690*13

2019-03-06T22:26:23.052Z,1551911183.052 [Micromodem](INFO): Nmea in: $CAREV,222602,COPROC,0.10.0.46*46

2019-03-06T22:26:23.053Z,1551911183.053 [Micromodem](INFO): Nmea in: $CAREV,222612,AUV,2.0.27690*12

2019-03-06T22:26:24.675Z,1551911184.675 [NAL9602](INFO): Powering up NAL9602
2019-03-06T22:26:33.155Z,1551911193.155 [Micromodem](INFO): Nmea in: $CAREV,222612,COPROC,0.10.0.46*47

2019-03-06T22:26:33.156Z,1551911193.156 [Micromodem](INFO): Nmea in: $CAREV,222622,AUV,2.0.27690*11

2019-03-06T22:26:35.583Z,1551911195.583 [NAL9602](INFO): NAL9602 initialized
2019-03-06T22:26:36.405Z,1551911196.405 [NAL9602](DEBUG): Fix Requested
2019-03-06T22:26:43.259Z,1551911203.259 [Micromodem](INFO): Nmea in: $CAREV,222622,COPROC,0.10.0.46*44

2019-03-06T22:26:43.260Z,1551911203.260 [Micromodem](INFO): Nmea in: $CAREV,222632,AUV,2.0.27690*10

2019-03-06T22:26:53.355Z,1551911213.355 [Micromodem](INFO): Nmea in: $CAREV,222632,COPROC,0.10.0.46*45

2019-03-06T22:26:53.356Z,1551911213.356 [Micromodem](INFO): Nmea in: $CAREV,222642,AUV,2.0.27690*17

2019-03-06T22:27:03.051Z,1551911223.051 [Micromodem](INFO): Nmea in: $CAREV,222642,COPROC,0.10.0.46*42

2019-03-06T22:27:03.052Z,1551911223.052 [Micromodem](INFO): Nmea in: $CAREV,222652,AUV,2.0.27690*16

2019-03-06T22:27:04.295Z,1551911224.295 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:27:04.296Z,1551911224.296 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-03-06T22:27:04.296Z,1551911224.296 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T22:27:04.298Z,1551911224.298 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:27:04.298Z,1551911224.298 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2019-03-06T22:27:04.298Z,1551911224.298 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T22:27:04.300Z,1551911224.300 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:27:04.300Z,1551911224.300 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2019-03-06T22:27:04.300Z,1551911224.300 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T22:27:04.311Z,1551911224.311 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T22:27:04.312Z,1551911224.312 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T22:27:04.312Z,1551911224.312 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T22:27:04.702Z,1551911224.702 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T22:27:04.702Z,1551911224.702 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-03-06T22:27:04.702Z,1551911224.702 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T22:27:04.702Z,1551911224.702 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2019-03-06T22:27:04.703Z,1551911224.703 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T22:27:04.703Z,1551911224.703 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2019-03-06T22:27:05.114Z,1551911225.114 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:27:13.155Z,1551911233.155 [Micromodem](INFO): Nmea in: $CAREV,222652,COPROC,0.10.0.46*43

2019-03-06T22:27:13.156Z,1551911233.156 [Micromodem](INFO): Nmea in: $CAREV,222702,AUV,2.0.27690*12

2019-03-06T22:27:23.251Z,1551911243.251 [Micromodem](INFO): Nmea in: $CAREV,222702,COPROC,0.10.0.46*47

2019-03-06T22:27:23.252Z,1551911243.252 [Micromodem](INFO): Nmea in: $CAREV,222712,AUV,2.0.27690*13

2019-03-06T22:27:33.355Z,1551911253.355 [Micromodem](INFO): Nmea in: $CAREV,222712,COPROC,0.10.0.46*46

2019-03-06T22:27:33.356Z,1551911253.356 [Micromodem](INFO): Nmea in: $CAREV,222722,AUV,2.0.27690*10

2019-03-06T22:27:43.047Z,1551911263.047 [Micromodem](INFO): Nmea in: $CAREV,222722,COPROC,0.10.0.46*45

2019-03-06T22:27:43.048Z,1551911263.048 [Micromodem](INFO): Nmea in: $CAREV,222732,AUV,2.0.27690*11

2019-03-06T22:27:53.151Z,1551911273.151 [Micromodem](INFO): Nmea in: $CAREV,222732,COPROC,0.10.0.46*44

2019-03-06T22:27:53.152Z,1551911273.152 [Micromodem](INFO): Nmea in: $CAREV,222742,AUV,2.0.27690*16

2019-03-06T22:28:03.251Z,1551911283.251 [Micromodem](INFO): Nmea in: $CAREV,222742,COPROC,0.10.0.46*43

2019-03-06T22:28:03.252Z,1551911283.252 [Micromodem](INFO): Nmea in: $CAREV,222752,AUV,2.0.27690*17

2019-03-06T22:28:13.351Z,1551911293.351 [Micromodem](INFO): Nmea in: $CAREV,222752,COPROC,0.10.0.46*42

2019-03-06T22:28:13.352Z,1551911293.352 [Micromodem](INFO): Nmea in: $CAREV,222802,AUV,2.0.27690*1D

2019-03-06T22:28:23.043Z,1551911303.043 [Micromodem](INFO): Nmea in: $CAREV,222802,COPROC,0.10.0.46*48

2019-03-06T22:28:23.044Z,1551911303.044 [Micromodem](INFO): Nmea in: $CAREV,222812,AUV,2.0.27690*1C

2019-03-06T22:28:33.147Z,1551911313.147 [Micromodem](INFO): Nmea in: $CAREV,222812,COPROC,0.10.0.46*49

2019-03-06T22:28:33.148Z,1551911313.148 [Micromodem](INFO): Nmea in: $CAREV,222822,AUV,2.0.27690*1F

2019-03-06T22:28:43.247Z,1551911323.247 [Micromodem](INFO): Nmea in: $CAREV,222822,COPROC,0.10.0.46*4A

2019-03-06T22:28:43.248Z,1551911323.248 [Micromodem](INFO): Nmea in: $CAREV,222832,AUV,2.0.27690*1E

2019-03-06T22:28:53.339Z,1551911333.339 [Micromodem](INFO): Nmea in: $CAREV,222832,COPROC,0.10.0.46*4B

2019-03-06T22:28:53.340Z,1551911333.340 [Micromodem](INFO): Nmea in: $CAREV,222842,AUV,2.0.27690*19

2019-03-06T22:29:03.039Z,1551911343.039 [Micromodem](INFO): Nmea in: $CAREV,222842,COPROC,0.10.0.46*4C

2019-03-06T22:29:03.040Z,1551911343.040 [Micromodem](INFO): Nmea in: $CAREV,222852,AUV,2.0.27690*18

2019-03-06T22:29:05.488Z,1551911345.488 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:29:05.488Z,1551911345.488 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-03-06T22:29:05.488Z,1551911345.488 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-06T22:29:05.494Z,1551911345.494 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:29:05.494Z,1551911345.494 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2019-03-06T22:29:05.494Z,1551911345.494 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-06T22:29:05.496Z,1551911345.496 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-03-06T22:29:05.496Z,1551911345.496 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2019-03-06T22:29:05.496Z,1551911345.496 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-06T22:29:05.528Z,1551911345.528 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-06T22:29:05.533Z,1551911345.533 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-06T22:29:05.533Z,1551911345.533 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-06T22:29:05.922Z,1551911345.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-06T22:29:05.922Z,1551911345.922 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-03-06T22:29:05.922Z,1551911345.922 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-06T22:29:05.922Z,1551911345.922 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2019-03-06T22:29:05.923Z,1551911345.923 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-06T22:29:05.923Z,1551911345.923 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2019-03-06T22:29:06.324Z,1551911346.324 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-06T22:29:13.139Z,1551911353.139 [Micromodem](INFO): Nmea in: $CAREV,222852,COPROC,0.10.0.46*4D

2019-03-06T22:29:13.140Z,1551911353.140 [Micromodem](INFO): Nmea in: $CAREV,222902,AUV,2.0.27690*1C

2019-03-06T22:29:23.239Z,1551911363.239 [Micromodem](INFO): Nmea in: $CAREV,222902,COPROC,0.10.0.46*49

2019-03-06T22:29:23.240Z,1551911363.240 [Micromodem](INFO): Nmea in: $CAREV,222912,AUV,2.0.27690*1D

2019-03-06T22:29:33.343Z,1551911373.343 [Micromodem](INFO): Nmea in: $CAREV,222912,COPROC,0.10.0.46*48

2019-03-06T22:29:33.344Z,1551911373.344 [Micromodem](INFO): Nmea in: $CAREV,222922,AUV,2.0.27690*1E

2019-03-06T22:29:43.039Z,1551911383.039 [Micromodem](INFO): Nmea in: $CAREV,222922,COPROC,0.10.0.46*4B

2019-03-06T22:29:43.040Z,1551911383.040 [Micromodem](INFO): Nmea in: $CAREV,222932,AUV,2.0.27690*1F

2019-03-06T22:29:53.135Z,1551911393.135 [Micromodem](INFO): Nmea in: $CAREV,222932,COPROC,0.10.0.46*4A

2019-03-06T22:29:53.136Z,1551911393.136 [Micromodem](INFO): Nmea in: $CAREV,222942,AUV,2.0.27690*18

2019-03-06T22:30:03.235Z,1551911403.235 [Micromodem](INFO): Nmea in: $CAREV,222942,COPROC,0.10.0.46*4D

2019-03-06T22:30:03.236Z,1551911403.236 [Micromodem](INFO): Nmea in: $CAREV,222952,AUV,2.0.27690*19

2019-03-06T22:30:10.548Z,1551911410.548 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-03-06T22:25:10.4Z
2019-03-06T22:30:10.548Z,1551911410.548 [Default:CheckIn:Read_GPS] Stopped
2019-03-06T22:30:10.549Z,1551911410.549 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-03-06T22:30:13.335Z,1551911413.335 [Micromodem](INFO): Nmea in: $CAREV,222952,COPROC,0.10.0.46*4C

2019-03-06T22:30:13.336Z,1551911413.336 [Micromodem](INFO): Nmea in: $CAREV,223002,AUV,2.0.27690*14

2019-03-06T22:30:15.852Z,1551911415.852 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20190306T221649/Courier0007.lzma
2019-03-06T22:30:16.658Z,1551911416.658 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221649/Courier0007.lzma.bak
2019-03-06T22:30:16.658Z,1551911416.658 [DataOverHttps](INFO): SBD MOMSN=10089863
2019-03-06T22:30:23.036Z,1551911423.036 [Micromodem](INFO): Nmea in: $CAREV,223002,COPROC,0.10.0.46*41

2019-03-06T22:30:23.037Z,1551911423.037 [Micromodem](INFO): Nmea in: $CAREV,223012,AUV,2.0.27690*15

2019-03-06T22:30:24.166Z,1551911424.166 [CommandLine](IMPORTANT): got command show variable DUSBL
2019-03-06T22:30:24.224Z,1551911424.224 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadAtStartup (bool)
2019-03-06T22:30:24.224Z,1551911424.224 [CommandLine](IMPORTANT): DUSBL_Hydroid.simulateHardware (bool)
2019-03-06T22:30:24.225Z,1551911424.225 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultTurnAroundTime (meter_per_second)
2019-03-06T22:30:24.225Z,1551911424.225 [CommandLine](IMPORTANT): DUSBL_Hydroid.defaultSoundSpeed (meter_per_second)
2019-03-06T22:30:24.226Z,1551911424.226 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-03-06T22:30:24.226Z,1551911424.226 [CommandLine](IMPORTANT): DUSBL_Hydroid.recieveTimeout (millisecond)
2019-03-06T22:30:24.226Z,1551911424.226 [CommandLine](IMPORTANT): DUSBL_Hydroid.transmitLockout (millisecond)
2019-03-06T22:30:24.227Z,1551911424.227 [CommandLine](IMPORTANT): DUSBL_Hydroid.transponderCode (enum)
2019-03-06T22:30:24.230Z,1551911424.230 [CommandLine](IMPORTANT): DUSBL_Hydroid.numberOfPingsRequested (count)
2019-03-06T22:30:24.231Z,1551911424.231 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity (enum)
2019-03-06T22:30:24.231Z,1551911424.231 [CommandLine](IMPORTANT): DUSBL_Hydroid.xCenter (angular_degree)
2019-03-06T22:30:24.231Z,1551911424.231 [CommandLine](IMPORTANT): DUSBL_Hydroid.yCenter (angular_degree)
2019-03-06T22:30:24.291Z,1551911424.291 [CommandLine](IMPORTANT): DUSBL_Hydroid.loadControl (none)
2019-03-06T22:30:24.291Z,1551911424.291 [CommandLine](IMPORTANT): DUSBL_Hydroid.uart (none)
2019-03-06T22:30:24.292Z,1551911424.292 [CommandLine](IMPORTANT): DUSBL_Hydroid.baud (bit_per_second)
2019-03-06T22:30:24.303Z,1551911424.303 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (enum)
2019-03-06T22:30:24.303Z,1551911424.303 [CommandLine](IMPORTANT): DUSBL_Hydroid.xAngle (angular_degree)
2019-03-06T22:30:24.304Z,1551911424.304 [CommandLine](IMPORTANT): DUSBL_Hydroid.yAngle (angular_degree)
2019-03-06T22:30:24.304Z,1551911424.304 [CommandLine](IMPORTANT): DUSBL_Hydroid.travelTime (microsecond)
2019-03-06T22:30:24.304Z,1551911424.304 [CommandLine](IMPORTANT): DUSBL_Hydroid.latency (microsecond)
2019-03-06T22:30:24.313Z,1551911424.313 [CommandLine](IMPORTANT): DUSBL_Hydroid.gain (ratio)
2019-03-06T22:30:24.313Z,1551911424.313 [CommandLine](IMPORTANT): DUSBL_Hydroid.inBandSignalToNoise (ratio)
2019-03-06T22:30:24.314Z,1551911424.314 [CommandLine](IMPORTANT): DUSBL_Hydroid.outBandSignalToNoise (ratio)
2019-03-06T22:30:24.314Z,1551911424.314 [CommandLine](IMPORTANT): DUSBL_Hydroid.range (meter)
2019-03-06T22:30:24.315Z,1551911424.315 [CommandLine](IMPORTANT): DUSBL_Hydroid.direction (none)
2019-03-06T22:30:24.444Z,1551911424.444 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_voltage (volt)
2019-03-06T22:30:24.445Z,1551911424.445 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgVoltage (volt)
2019-03-06T22:30:24.465Z,1551911424.465 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_current (milliampere)
2019-03-06T22:30:24.466Z,1551911424.466 [CommandLine](IMPORTANT): DUSBL_Hydroid.component_avgCurrent (milliampere)
2019-03-06T22:30:25.858Z,1551911425.858 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-03-06T22:30:28.055Z,1551911428.055 [DataOverHttps](INFO): Sending 942 bytes from file Logs/20190306T221649/Express0008.lzma
2019-03-06T22:30:28.858Z,1551911428.858 [DataOverHttps](INFO): Moved sent file to Logs/20190306T221649/Express0008.lzma.bak
2019-03-06T22:30:28.858Z,1551911428.858 [DataOverHttps](INFO): SBD MOMSN=10089865
2019-03-06T22:30:29.528Z,1551911429.528 [Default:CheckIn:Read_Iridium] Stopped
2019-03-06T22:30:29.528Z,1551911429.528 [Default:CheckIn:C.Wait] Running Loop=1
2019-03-06T22:30:29.528Z,1551911429.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-03-06T22:30:33.131Z,1551911433.131 [Micromodem](INFO): Nmea in: $CAREV,223012,COPROC,0.10.0.46*40

2019-03-06T22:30:33.132Z,1551911433.132 [Micromodem](INFO): Nmea in: $CAREV,223022,AUV,2.0.27690*16

2019-03-06T22:30:41.751Z,1551911441.751 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.verbosity 1.000000 count persist
2019-03-06T22:30:43.232Z,1551911443.232 [Micromodem](INFO): Nmea in: $CAREV,223022,COPROC,0.10.0.46*43

2019-03-06T22:30:43.234Z,1551911443.234 [Micromodem](INFO): Nmea in: $CAREV,223032,AUV,2.0.27690*17

2019-03-06T22:30:53.331Z,1551911453.331 [Micromodem](INFO): Nmea in: $CAREV,223032,COPROC,0.10.0.46*42

2019-03-06T22:30:53.332Z,1551911453.332 [Micromodem](INFO): Nmea in: $CAREV,223042,AUV,2.0.27690*10

2019-03-06T22:30:54.198Z,1551911454.198 [CommandLine](IMPORTANT): got command restart application
2019-03-06T22:30:55.205Z,1551911455.205 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-03-06T22:30:55.205Z,1551911455.205 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:55.206Z,1551911455.206 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.249Z,1551911455.249 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-06T22:30:55.249Z,1551911455.249 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.250Z,1551911455.250 [CommandLine](INFO): Join timeout helper Thread ID is 1073
2019-03-06T22:30:55.250Z,1551911455.250 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-06T22:30:55.250Z,1551911455.250 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.251Z,1551911455.251 [NavChartDb](INFO): Join timeout helper Thread ID is 1074
2019-03-06T22:30:55.605Z,1551911455.605 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:55.605Z,1551911455.605 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.613Z,1551911455.613 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-06T22:30:55.613Z,1551911455.613 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.613Z,1551911455.613 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1075
2019-03-06T22:30:55.817Z,1551911455.817 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:55.817Z,1551911455.817 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-06T22:30:55.818Z,1551911455.818 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.837Z,1551911455.837 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-06T22:30:55.837Z,1551911455.837 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.837Z,1551911455.837 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 1076
2019-03-06T22:30:55.925Z,1551911455.925 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:55.925Z,1551911455.925 [CTD_NeilBrown](INFO): Powering down
2019-03-06T22:30:55.937Z,1551911455.937 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.953Z,1551911455.953 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-06T22:30:55.954Z,1551911455.954 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:55.955Z,1551911455.955 [Radio_Surface](INFO): Join timeout helper Thread ID is 1077
2019-03-06T22:30:56.325Z,1551911456.325 [Radio_Surface](INFO): Powering down
2019-03-06T22:30:56.326Z,1551911456.326 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:56.326Z,1551911456.326 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:56.330Z,1551911456.330 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-06T22:30:56.330Z,1551911456.330 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:56.330Z,1551911456.330 [DataOverHttps](INFO): Join timeout helper Thread ID is 1078
2019-03-06T22:30:56.563Z,1551911456.563 [NAL9602](INFO): Powering down
2019-03-06T22:30:56.701Z,1551911456.701 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:56.701Z,1551911456.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:56.709Z,1551911456.709 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-06T22:30:56.710Z,1551911456.710 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:56.710Z,1551911456.710 [logger](INFO): Join timeout helper Thread ID is 1079
2019-03-06T22:30:56.773Z,1551911456.773 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:56.773Z,1551911456.773 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:56.789Z,1551911456.789 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-06T22:30:56.790Z,1551911456.790 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:56.790Z,1551911456.790 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-06T22:30:56.790Z,1551911456.790 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:56.790Z,1551911456.790 [controlThread](INFO): Join timeout helper Thread ID is 1080
2019-03-06T22:30:56.958Z,1551911456.958 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-06T22:30:56.958Z,1551911456.958 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-06T22:30:56.960Z,1551911456.960 [DUSBL_Hydroid](INFO): Powering down
2019-03-06T22:30:57.033Z,1551911457.033 [Micromodem](INFO): Powering down
2019-03-06T22:30:57.129Z,1551911457.129 [NAL9602](INFO): Powering down
2019-03-06T22:30:57.201Z,1551911457.201 [RDI_Pathfinder](INFO): Powering down
2019-03-06T22:30:57.202Z,1551911457.202 [RDI_PathfinderUp](INFO): Powering down
2019-03-06T22:30:57.203Z,1551911457.203 [Aanderaa_O2](INFO): Powering down
2019-03-06T22:30:57.204Z,1551911457.204 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-06T22:30:57.205Z,1551911457.205 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-06T22:30:57.206Z,1551911457.206 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-06T22:30:57.206Z,1551911457.206 [MissionManager](INFO): Uninitializing Mission Default
2019-03-06T22:30:57.206Z,1551911457.206 [Default] Stopped
2019-03-06T22:30:57.206Z,1551911457.206 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-06T22:30:57.206Z,1551911457.206 [Default:B.GoToSurface] Stopped
2019-03-06T22:30:57.207Z,1551911457.207 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-06T22:30:57.207Z,1551911457.207 [Default:CheckIn] Stopped
2019-03-06T22:30:57.207Z,1551911457.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-06T22:30:57.207Z,1551911457.207 [Default:CheckIn:C.Wait] Stopped
2019-03-06T22:30:57.207Z,1551911457.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-03-06T22:30:57.209Z,1551911457.209 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-06T22:30:57.210Z,1551911457.210 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-06T22:30:57.210Z,1551911457.210 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-06T22:30:57.210Z,1551911457.210 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-06T22:30:57.210Z,1551911457.210 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-06T22:30:57.211Z,1551911457.211 [BuoyancyServo](INFO): Powering down
2019-03-06T22:30:57.225Z,1551911457.225 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-06T22:30:57.225Z,1551911457.225 [ElevatorServo](INFO): Powering down
2019-03-06T22:30:57.226Z,1551911457.226 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-06T22:30:57.226Z,1551911457.226 [MassServo](INFO): Powering down
2019-03-06T22:30:57.227Z,1551911457.227 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-06T22:30:57.227Z,1551911457.227 [RudderServo](INFO): Powering down
2019-03-06T22:30:57.228Z,1551911457.228 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-06T22:30:57.228Z,1551911457.228 [ThrusterServo](INFO): Powering down
2019-03-06T22:30:57.228Z,1551911457.228 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-06T22:30:57.229Z,1551911457.229 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-06T22:30:57.229Z,1551911457.229 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-06T22:30:57.229Z,1551911457.229 [CBIT](DEBUG): Powering off loads.
2019-03-06T22:30:57.241Z,1551911457.241 [CBIT](DEBUG): Disabling WDT.
2019-03-06T22:30:57.253Z,1551911457.253 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-06T22:30:57.254Z,1551911457.254 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:57.319Z,1551911457.319 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:57.327Z,1551911457.327 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:57.367Z,1551911457.367 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:57.369Z,1551911457.369 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:57.422Z,1551911457.422 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-06T22:30:57.490Z,1551911457.490 [logger ThreadHandler](INFO): Thread cancelled.