2019-03-27T17:12:11.192Z,1553706731.192 [Supervisor](DEBUG): Initializing supervisor.
2019-03-27T17:12:11.195Z,1553706731.195 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-03-27T17:12:11.195Z,1553706731.195 [SyncHandler](INFO): Protected caller Thread ID is 7789
2019-03-27T17:12:11.196Z,1553706731.196 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-03-27T17:12:11.196Z,1553706731.196 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-03-27T17:12:11.197Z,1553706731.197 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7790
2019-03-27T17:12:11.200Z,1553706731.200 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-03-27T17:12:11.212Z,1553706731.212 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-03-27T17:12:11.214Z,1553706731.214 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-03-27T17:12:11.214Z,1553706731.214 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7791
2019-03-27T17:12:11.215Z,1553706731.215 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-03-27T17:12:11.216Z,1553706731.216 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-03-27T17:12:11.216Z,1553706731.216 [logger ThreadHandler](INFO): Protected caller Thread ID is 7792
2019-03-27T17:12:11.218Z,1553706731.218 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-03-27T17:12:11.218Z,1553706731.218 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-03-27T17:12:11.220Z,1553706731.220 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-03-27T17:12:11.643Z,1553706731.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-03-27T17:12:11.643Z,1553706731.644 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-03-27T17:12:11.740Z,1553706731.740 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-03-27T17:12:11.740Z,1553706731.740 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-03-27T17:12:12.066Z,1553706732.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-03-27T17:12:12.066Z,1553706732.066 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-03-27T17:12:12.207Z,1553706732.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-03-27T17:12:12.207Z,1553706732.207 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-03-27T17:12:12.398Z,1553706732.398 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-03-27T17:12:12.399Z,1553706732.399 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-03-27T17:12:12.858Z,1553706732.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-03-27T17:12:12.859Z,1553706732.859 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-03-27T17:12:13.140Z,1553706733.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-03-27T17:12:13.141Z,1553706733.141 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-03-27T17:12:13.285Z,1553706733.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-03-27T17:12:13.286Z,1553706733.286 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-03-27T17:12:13.479Z,1553706733.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-03-27T17:12:13.479Z,1553706733.479 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-03-27T17:12:13.574Z,1553706733.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-03-27T17:12:13.575Z,1553706733.575 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-03-27T17:12:13.896Z,1553706733.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-03-27T17:12:13.896Z,1553706733.896 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-03-27T17:12:13.975Z,1553706733.975 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-03-27T17:12:14.077Z,1553706734.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-03-27T17:12:14.077Z,1553706734.077 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-03-27T17:12:14.578Z,1553706734.578 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-03-27T17:12:14.579Z,1553706734.579 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-03-27T17:12:14.974Z,1553706734.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-03-27T17:12:14.976Z,1553706734.976 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-03-27T17:12:14.977Z,1553706734.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-03-27T17:12:15.184Z,1553706735.184 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-03-27T17:12:15.283Z,1553706735.283 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-03-27T17:12:15.380Z,1553706735.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-03-27T17:12:15.607Z,1553706735.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-03-27T17:12:15.608Z,1553706735.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-03-27T17:12:15.691Z,1553706735.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-03-27T17:12:15.783Z,1553706735.783 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-03-27T17:12:15.878Z,1553706735.878 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-03-27T17:12:15.971Z,1553706735.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-03-27T17:12:16.241Z,1553706736.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-03-27T17:12:16.579Z,1553706736.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-03-27T17:12:16.923Z,1553706736.923 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-03-27T17:12:16.924Z,1553706736.924 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-03-27T17:12:16.930Z,1553706736.930 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-03-27T17:12:17.298Z,1553706737.298 [AHRS_M2] Loaded
2019-03-27T17:12:17.298Z,1553706737.298 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-03-27T17:12:17.371Z,1553706737.371 [DataOverHttps] Loaded
2019-03-27T17:12:17.372Z,1553706737.372 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-03-27T17:12:17.373Z,1553706737.373 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4075B4E0
2019-03-27T17:12:17.373Z,1553706737.373 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7872
2019-03-27T17:12:17.387Z,1553706737.387 [Depth_Keller] Loaded
2019-03-27T17:12:17.387Z,1553706737.387 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-03-27T17:12:17.392Z,1553706737.392 [DropWeight] Loaded
2019-03-27T17:12:17.392Z,1553706737.392 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-03-27T17:12:17.444Z,1553706737.444 [DUSBL_Hydroid] Loaded
2019-03-27T17:12:17.445Z,1553706737.445 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-03-27T17:12:17.485Z,1553706737.485 [Micromodem] Loaded
2019-03-27T17:12:17.485Z,1553706737.485 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-03-27T17:12:17.584Z,1553706737.584 [NAL9602] Loaded
2019-03-27T17:12:17.584Z,1553706737.584 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-03-27T17:12:17.600Z,1553706737.600 [Onboard] Loaded
2019-03-27T17:12:17.600Z,1553706737.600 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-03-27T17:12:17.606Z,1553706737.606 [PowerOnly] Loaded
2019-03-27T17:12:17.606Z,1553706737.606 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-03-27T17:12:17.613Z,1553706737.613 [Radio_Surface] Loaded
2019-03-27T17:12:17.613Z,1553706737.613 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-03-27T17:12:17.614Z,1553706737.614 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078B4E0
2019-03-27T17:12:17.615Z,1553706737.615 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7873
2019-03-27T17:12:17.675Z,1553706737.675 [RDI_Pathfinder] Loaded
2019-03-27T17:12:17.675Z,1553706737.675 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-03-27T17:12:17.696Z,1553706737.696 [RDI_PathfinderUp] Loaded
2019-03-27T17:12:17.697Z,1553706737.697 [ComponentRegistry](DEBUG): SyncComponent "RDI_PathfinderUp" handled in the control thread.
2019-03-27T17:12:19.201Z,1553706739.201 [BPC1] Loaded
2019-03-27T17:12:19.201Z,1553706739.201 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-03-27T17:12:19.201Z,1553706739.201 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-03-27T17:12:19.202Z,1553706739.202 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-03-27T17:12:19.216Z,1553706739.216 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-03-27T17:12:19.216Z,1553706739.216 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-03-27T17:12:19.324Z,1553706739.324 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-03-27T17:12:19.325Z,1553706739.325 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-03-27T17:12:19.384Z,1553706739.384 [DeadReckonUsingSpeedCalculator] Loaded
2019-03-27T17:12:19.384Z,1553706739.384 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-03-27T17:12:19.443Z,1553706739.443 [DeadReckonWithRespectToSeafloor] Loaded
2019-03-27T17:12:19.444Z,1553706739.444 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-03-27T17:12:19.464Z,1553706739.464 [NavChart] Loaded
2019-03-27T17:12:19.464Z,1553706739.464 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-03-27T17:12:19.468Z,1553706739.468 [UniversalFixResidualReporter] Loaded
2019-03-27T17:12:19.468Z,1553706739.468 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-03-27T17:12:19.469Z,1553706739.469 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-03-27T17:12:19.469Z,1553706739.469 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-03-27T17:12:19.574Z,1553706739.574 [BuoyancyServo] Loaded
2019-03-27T17:12:19.574Z,1553706739.574 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-03-27T17:12:19.589Z,1553706739.589 [ElevatorServo] Loaded
2019-03-27T17:12:19.590Z,1553706739.590 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-03-27T17:12:19.604Z,1553706739.604 [MassServo] Loaded
2019-03-27T17:12:19.605Z,1553706739.605 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-03-27T17:12:19.620Z,1553706739.620 [RudderServo] Loaded
2019-03-27T17:12:19.620Z,1553706739.620 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-03-27T17:12:19.635Z,1553706739.635 [ThrusterServo] Loaded
2019-03-27T17:12:19.635Z,1553706739.635 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-03-27T17:12:19.636Z,1553706739.636 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-03-27T17:12:19.636Z,1553706739.636 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-03-27T17:12:19.896Z,1553706739.896 [CTD_NeilBrown] Loaded
2019-03-27T17:12:19.897Z,1553706739.897 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-03-27T17:12:19.898Z,1553706739.898 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408D04E0
2019-03-27T17:12:19.898Z,1553706739.898 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 7874
2019-03-27T17:12:19.944Z,1553706739.944 [WetLabsSeaOWL_UV_A] Loaded
2019-03-27T17:12:19.944Z,1553706739.944 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-03-27T17:12:19.945Z,1553706739.945 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2019-03-27T17:12:19.945Z,1553706739.945 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 7875
2019-03-27T17:12:19.946Z,1553706739.946 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-03-27T17:12:19.947Z,1553706739.947 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-03-27T17:12:20.227Z,1553706740.227 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-03-27T17:12:20.227Z,1553706740.227 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-03-27T17:12:20.270Z,1553706740.270 [DepthRateCalculator] Loaded
2019-03-27T17:12:20.270Z,1553706740.270 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-03-27T17:12:20.276Z,1553706740.276 [PitchRateCalculator] Loaded
2019-03-27T17:12:20.276Z,1553706740.276 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-03-27T17:12:20.289Z,1553706740.289 [SpeedCalculator] Loaded
2019-03-27T17:12:20.289Z,1553706740.289 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-03-27T17:12:20.310Z,1553706740.310 [TempGradientCalculator] Loaded
2019-03-27T17:12:20.310Z,1553706740.310 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-03-27T17:12:20.316Z,1553706740.316 [YawRateCalculator] Loaded
2019-03-27T17:12:20.316Z,1553706740.316 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-03-27T17:12:20.356Z,1553706740.356 [ElevatorOffsetCalculator] Loaded
2019-03-27T17:12:20.356Z,1553706740.356 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-03-27T17:12:20.357Z,1553706740.357 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-03-27T17:12:20.357Z,1553706740.357 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-03-27T17:12:20.497Z,1553706740.497 [SBIT](DEBUG): Construct Startup Built In Test.
2019-03-27T17:12:20.518Z,1553706740.518 [SBIT] Loaded
2019-03-27T17:12:20.519Z,1553706740.519 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-03-27T17:12:20.519Z,1553706740.519 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-03-27T17:12:20.531Z,1553706740.531 [IBIT] Loaded
2019-03-27T17:12:20.531Z,1553706740.531 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-03-27T17:12:20.534Z,1553706740.534 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-03-27T17:12:20.674Z,1553706740.674 [CBIT] Loaded
2019-03-27T17:12:20.674Z,1553706740.674 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-03-27T17:12:20.674Z,1553706740.674 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-03-27T17:12:20.675Z,1553706740.675 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-03-27T17:12:20.751Z,1553706740.751 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-03-27T17:12:20.751Z,1553706740.751 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-03-27T17:12:20.855Z,1553706740.855 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-03-27T17:12:20.855Z,1553706740.855 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-03-27T17:12:20.925Z,1553706740.925 [VerticalControl](DEBUG): Construct VerticalControl.
2019-03-27T17:12:21.010Z,1553706741.010 [VerticalControl] Loaded
2019-03-27T17:12:21.010Z,1553706741.010 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-03-27T17:12:21.011Z,1553706741.011 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-03-27T17:12:21.069Z,1553706741.069 [HorizontalControl] Loaded
2019-03-27T17:12:21.069Z,1553706741.069 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-03-27T17:12:21.070Z,1553706741.070 [SpeedControl](DEBUG): Construct SpeedControl.
2019-03-27T17:12:21.071Z,1553706741.071 [SpeedControl] Loaded
2019-03-27T17:12:21.072Z,1553706741.072 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-03-27T17:12:21.072Z,1553706741.072 [LoopControl](DEBUG): Construct LoopControl.
2019-03-27T17:12:21.073Z,1553706741.073 [LoopControl] Loaded
2019-03-27T17:12:21.073Z,1553706741.073 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-03-27T17:12:21.074Z,1553706741.074 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-03-27T17:12:21.074Z,1553706741.074 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-03-27T17:12:21.119Z,1553706741.119 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-03-27T17:12:21.123Z,1553706741.123 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-03-27T17:12:21.124Z,1553706741.124 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-03-27T17:12:21.130Z,1553706741.130 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-03-27T17:12:21.131Z,1553706741.131 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACF4E0
2019-03-27T17:12:21.132Z,1553706741.132 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7876
2019-03-27T17:12:21.137Z,1553706741.137 [Supervisor](INFO): Main Thread ID is 7788
2019-03-27T17:12:21.137Z,1553706741.137 [Supervisor](DEBUG): Running supervisor.
2019-03-27T17:12:21.138Z,1553706741.138 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7877
2019-03-27T17:12:21.140Z,1553706741.140 [controlThread ThreadHandler](INFO): Handler Thread ID is 7878
2019-03-27T17:12:21.140Z,1553706741.140 [controlThread](DEBUG): Initializing ControlThread
2019-03-27T17:12:21.149Z,1553706741.149 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-27T17:12:21.150Z,1553706741.150 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-03-27T17:12:21.150Z,1553706741.150 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-03-27T17:12:21.152Z,1553706741.152 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-03-27T17:12:21.152Z,1553706741.152 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-03-27T17:12:21.152Z,1553706741.152 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-03-27T17:12:21.153Z,1553706741.153 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-03-27T17:12:21.153Z,1553706741.153 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-03-27T17:12:21.154Z,1553706741.154 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-03-27T17:12:21.154Z,1553706741.154 [SBIT](INFO): Initialize SBIT Component.
2019-03-27T17:12:21.155Z,1553706741.155 [SBIT](IMPORTANT): git: 2019-03-18-26-gc8399b8
2019-03-27T17:12:21.155Z,1553706741.155 [SBIT](INFO): git hash: c8399b8e53db2b8366337064c7df5542bc2caf88
2019-03-27T17:12:21.155Z,1553706741.155 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-03-27T17:12:21.155Z,1553706741.155 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2019-03-27T17:12:21.157Z,1553706741.157 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-03-27T17:12:21.157Z,1553706741.157 [IBIT](INFO): Initialize IBIT Component.
2019-03-27T17:12:21.158Z,1553706741.158 [CBIT](DEBUG): Initialize CBIT Component.
2019-03-27T17:12:21.159Z,1553706741.159 [logger ThreadHandler](INFO): Handler Thread ID is 7879
2019-03-27T17:12:21.169Z,1553706741.169 [CBIT](DEBUG): Initialized mux pins.
2019-03-27T17:12:21.169Z,1553706741.169 [CBIT](DEBUG): Initializing the watchdog timer.
2019-03-27T17:12:21.177Z,1553706741.177 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7880
2019-03-27T17:12:21.178Z,1553706741.178 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-03-27T17:12:21.189Z,1553706741.189 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7881
2019-03-27T17:12:21.193Z,1553706741.193 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-03-27T17:12:21.193Z,1553706741.193 [CBIT](DEBUG): Initializing heartbeat.
2019-03-27T17:12:21.209Z,1553706741.209 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 7882
2019-03-27T17:12:21.210Z,1553706741.210 [CTD_NeilBrown](INFO): Powering down
2019-03-27T17:12:21.245Z,1553706741.245 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 7883
2019-03-27T17:12:21.246Z,1553706741.246 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-27T17:12:21.265Z,1553706741.265 [CBIT](DEBUG): Deactivating GF circuits.
2019-03-27T17:12:21.265Z,1553706741.265 [CBIT](DEBUG): Deactivating emergency mode.
2019-03-27T17:12:21.273Z,1553706741.273 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7884
2019-03-27T17:12:21.277Z,1553706741.277 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-03-27T17:12:21.277Z,1553706741.277 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-03-27T17:12:21.277Z,1553706741.277 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-03-27T17:12:21.278Z,1553706741.278 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-03-27T17:12:21.278Z,1553706741.278 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-03-27T17:12:21.278Z,1553706741.278 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-03-27T17:12:21.278Z,1553706741.278 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-03-27T17:12:21.278Z,1553706741.278 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-03-27T17:12:21.278Z,1553706741.278 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-03-27T17:12:21.279Z,1553706741.279 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-03-27T17:12:21.279Z,1553706741.279 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-03-27T17:12:21.279Z,1553706741.279 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-03-27T17:12:21.279Z,1553706741.279 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-03-27T17:12:21.279Z,1553706741.279 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-03-27T17:12:21.280Z,1553706741.280 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-03-27T17:12:21.280Z,1553706741.280 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-03-27T17:12:21.301Z,1553706741.301 [CBIT](DEBUG): Backplane powered.
2019-03-27T17:12:21.301Z,1553706741.301 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-03-27T17:12:21.303Z,1553706741.303 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-03-27T17:12:21.303Z,1553706741.303 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-03-27T17:12:21.304Z,1553706741.304 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-03-27T17:12:21.305Z,1553706741.305 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-03-27T17:12:21.315Z,1553706741.315 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-27T17:12:21.334Z,1553706741.334 [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-27T17:12:21.335Z,1553706741.335 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-03-27T17:12:21.409Z,1553706741.409 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-03-27T17:12:21.410Z,1553706741.410 [Default:A.Wait](DEBUG): Construct Wait.
2019-03-27T17:12:21.412Z,1553706741.412 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-03-27T17:12:21.451Z,1553706741.451 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-03-27T17:12:21.453Z,1553706741.453 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-03-27T17:12:21.479Z,1553706741.479 [Default:E.Execute](DEBUG): Construct Execute.
2019-03-27T17:12:21.482Z,1553706741.482 [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-27T17:12:21.493Z,1553706741.493 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,RDI_PathfinderUp,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-03-27T17:12:21.505Z,1553706741.505 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-03-27T17:12:21.544Z,1553706741.544 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-03-27T17:12:21.545Z,1553706741.545 [DUSBL_Hydroid](INFO): Start
2019-03-27T17:12:21.545Z,1553706741.545 [DUSBL_Hydroid](INFO): Powering up
2019-03-27T17:12:21.545Z,1553706741.545 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-03-27T17:12:21.593Z,1553706741.593 [Radio_Surface](INFO): Powering up
2019-03-27T17:12:21.649Z,1553706741.649 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-27T17:12:21.663Z,1553706741.663 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-03-27T17:12:21.669Z,1553706741.669 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-03-27T17:12:21.670Z,1553706741.670 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-03-27T17:12:21.677Z,1553706741.677 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-03-27T17:12:21.678Z,1553706741.678 [MassServo](DEBUG): Initializing EZServoServo.
2019-03-27T17:12:21.685Z,1553706741.685 [MassServo](DEBUG): Initializing MassServo.
2019-03-27T17:12:21.686Z,1553706741.686 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-27T17:12:21.693Z,1553706741.693 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-27T17:12:21.694Z,1553706741.694 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-03-27T17:12:21.701Z,1553706741.701 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-03-27T17:12:21.922Z,1553706741.922 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:21.923Z,1553706741.923 [Micromodem](INFO): Powering up
2019-03-27T17:12:21.923Z,1553706741.923 [Micromodem](DEBUG): Initializing Micromodem.
2019-03-27T17:12:22.025Z,1553706742.025 [RDI_PathfinderUp](INFO): Powering down
2019-03-27T17:12:22.054Z,1553706742.054 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-27T17:12:22.309Z,1553706742.309 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:22.329Z,1553706742.329 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-03-27T17:12:22.649Z,1553706742.649 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-03-27T17:12:22.649Z,1553706742.649 [RudderServo](FAULT): Rudder failed to initialize
2019-03-27T17:12:22.649Z,1553706742.649 [RudderServo] Communications Fault, FailCount= 1
2019-03-27T17:12:22.649Z,1553706742.649 [RudderServo](ERROR): Communications Fault
2019-03-27T17:12:22.760Z,1553706742.760 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-03-27T17:12:22.771Z,1553706742.771 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:22.934Z,1553706742.934 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-27T17:12:22.934Z,1553706742.934 [RudderServo](INFO): Powering down
2019-03-27T17:12:23.174Z,1553706743.174 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:23.575Z,1553706743.575 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:23.602Z,1553706743.602 [RudderServo](DEBUG): Initializing EZServoServo.
2019-03-27T17:12:23.722Z,1553706743.722 [RudderServo](DEBUG): Initializing RudderServo.
2019-03-27T17:12:23.726Z,1553706743.726 [CBIT](INFO): Clearing failed state for component RudderServo
2019-03-27T17:12:23.726Z,1553706743.726 [RudderServo] No Fault, FailCount= 1
2019-03-27T17:12:23.982Z,1553706743.982 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:24.386Z,1553706744.386 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:24.862Z,1553706744.862 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:25.218Z,1553706745.218 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:25.622Z,1553706745.622 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:26.090Z,1553706746.090 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:26.442Z,1553706746.442 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:26.846Z,1553706746.846 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:26.849Z,1553706746.849 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-03-27T17:12:27.250Z,1553706747.250 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:27.659Z,1553706747.659 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:28.058Z,1553706748.058 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:28.462Z,1553706748.462 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:28.866Z,1553706748.866 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:29.286Z,1553706749.286 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:29.662Z,1553706749.662 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:30.070Z,1553706750.070 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:30.461Z,1553706750.461 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:30.866Z,1553706750.866 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:31.266Z,1553706751.266 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:31.666Z,1553706751.666 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:32.078Z,1553706752.078 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:32.470Z,1553706752.470 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:32.870Z,1553706752.870 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:33.126Z,1553706753.126 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004370
2019-03-27T17:12:33.274Z,1553706753.274 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:33.678Z,1553706753.678 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:34.094Z,1553706754.094 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:34.490Z,1553706754.490 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:34.894Z,1553706754.894 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:34.920Z,1553706754.920 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-03-27T17:12:35.294Z,1553706755.294 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:35.702Z,1553706755.702 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:36.102Z,1553706756.102 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:36.104Z,1553706756.104 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-03-27T17:12:36.506Z,1553706756.506 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:36.910Z,1553706756.910 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:37.314Z,1553706757.314 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:37.718Z,1553706757.718 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:38.122Z,1553706758.122 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:38.526Z,1553706758.526 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:38.930Z,1553706758.930 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:39.338Z,1553706759.338 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:39.738Z,1553706759.738 [DUSBL_Hydroid](INFO): Starting
2019-03-27T17:12:39.738Z,1553706759.738 [DUSBL_Hydroid](DEBUG): !U1,V,O
2019-03-27T17:12:39.739Z,1553706759.739 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-03-27T17:12:40.142Z,1553706760.142 [DUSBL_Hydroid](INFO): Runnable
2019-03-27T17:12:40.160Z,1553706760.160 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-03-27T17:12:40.160Z,1553706760.160 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19032710140612,35.0, -0.1, 48.9,  0
2019-03-27T17:12:40.550Z,1553706760.550 [DUSBL_Hydroid](INFO): Pause
2019-03-27T17:12:40.954Z,1553706760.954 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:41.362Z,1553706761.362 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:41.764Z,1553706761.764 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:42.166Z,1553706762.166 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:42.566Z,1553706762.566 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:42.974Z,1553706762.974 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:43.374Z,1553706763.374 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:43.797Z,1553706763.797 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:44.186Z,1553706764.186 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:44.590Z,1553706764.590 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:44.994Z,1553706764.994 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:45.398Z,1553706765.398 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:45.810Z,1553706765.810 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:46.202Z,1553706766.202 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:46.203Z,1553706766.203 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-03-27T17:12:46.606Z,1553706766.606 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:47.010Z,1553706767.010 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:47.414Z,1553706767.414 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:47.830Z,1553706767.830 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:47.831Z,1553706767.831 [NAL9602](INFO): Powering up NAL9602
2019-03-27T17:12:48.222Z,1553706768.222 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:48.626Z,1553706768.626 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:49.030Z,1553706769.030 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:49.434Z,1553706769.434 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:49.842Z,1553706769.842 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:49.935Z,1553706769.935 [SBIT](IMPORTANT): Beginning Startup BIT
2019-03-27T17:12:49.940Z,1553706769.940 [CBIT](IMPORTANT): Beginning ground fault scan
2019-03-27T17:12:50.246Z,1553706770.246 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:50.646Z,1553706770.646 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:51.050Z,1553706771.050 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:51.454Z,1553706771.454 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:51.858Z,1553706771.858 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:52.406Z,1553706772.406 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:52.806Z,1553706772.806 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:53.194Z,1553706773.194 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:53.602Z,1553706773.602 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:54.010Z,1553706774.010 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:54.418Z,1553706774.418 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:54.826Z,1553706774.826 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:55.214Z,1553706775.214 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:55.618Z,1553706775.618 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:56.030Z,1553706776.030 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:56.031Z,1553706776.031 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-03-27T17:12:56.438Z,1553706776.438 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:56.834Z,1553706776.834 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:57.234Z,1553706777.234 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:57.638Z,1553706777.638 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:58.068Z,1553706778.068 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:58.446Z,1553706778.446 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:58.850Z,1553706778.850 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:58.851Z,1553706778.851 [NAL9602](INFO): NAL9602 initialized
2019-03-27T17:12:59.254Z,1553706779.254 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:59.658Z,1553706779.658 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:12:59.673Z,1553706779.673 [NAL9602](DEBUG): Fix Requested
2019-03-27T17:13:00.062Z,1553706780.062 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:00.466Z,1553706780.466 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:00.874Z,1553706780.874 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:01.006Z,1553706781.006 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.019305
CHAN A1 (24V): -0.028822
CHAN A2 (12V): -0.007980
CHAN A3 (5V): -0.001774
CHAN B0 (3.3V): 0.000809
CHAN B1 (3.15aV): 0.000314
CHAN B2 (3.15bV): 0.000325
CHAN B3 (GND): 0.002507
OPEN: 0.006916
Full Scale Calc: 4.765 mA, -1.589 mA
2019-03-27T17:13:01.290Z,1553706781.290 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:01.678Z,1553706781.678 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:02.082Z,1553706782.082 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:02.490Z,1553706782.490 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:02.894Z,1553706782.894 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:03.294Z,1553706783.294 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:03.702Z,1553706783.702 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:04.102Z,1553706784.102 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:04.506Z,1553706784.506 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:04.922Z,1553706784.922 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:05.512Z,1553706785.512 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:05.914Z,1553706785.914 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:05.915Z,1553706785.915 [Micromodem](INFO): Nmea out: $CCTMS,2019-03-27T17:13:06Z,0*7A

2019-03-27T17:13:06.318Z,1553706786.318 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:06.726Z,1553706786.726 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:07.126Z,1553706787.126 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:07.558Z,1553706787.558 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:07.970Z,1553706787.970 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:08.370Z,1553706788.370 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:08.782Z,1553706788.782 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:09.178Z,1553706789.178 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:09.570Z,1553706789.570 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:09.974Z,1553706789.974 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:10.378Z,1553706790.378 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:10.802Z,1553706790.802 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:11.182Z,1553706791.182 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:11.590Z,1553706791.590 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:11.994Z,1553706791.994 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:12.410Z,1553706792.410 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:12.814Z,1553706792.814 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:13.210Z,1553706793.210 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:13.610Z,1553706793.610 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:14.014Z,1553706794.014 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:14.414Z,1553706794.414 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:14.838Z,1553706794.838 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:15.238Z,1553706795.238 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:15.654Z,1553706795.654 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:16.030Z,1553706796.030 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:16.438Z,1553706796.438 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:16.860Z,1553706796.860 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:17.254Z,1553706797.254 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:17.650Z,1553706797.650 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:18.054Z,1553706798.054 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:18.458Z,1553706798.458 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:18.862Z,1553706798.862 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:19.262Z,1553706799.262 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:19.666Z,1553706799.666 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:20.078Z,1553706800.078 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:20.474Z,1553706800.474 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:20.878Z,1553706800.878 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:21.282Z,1553706801.282 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:21.686Z,1553706801.686 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:22.090Z,1553706802.090 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:22.498Z,1553706802.498 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:22.898Z,1553706802.898 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:23.306Z,1553706803.306 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:23.722Z,1553706803.722 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:24.110Z,1553706804.110 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:24.514Z,1553706804.514 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:24.922Z,1553706804.922 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:25.322Z,1553706805.322 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:25.726Z,1553706805.726 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:26.134Z,1553706806.134 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:26.534Z,1553706806.534 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:26.942Z,1553706806.942 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:27.358Z,1553706807.358 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:27.750Z,1553706807.750 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:27.797Z,1553706807.797 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-03-27T17:13:27.797Z,1553706807.797 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19032710145412,35.0, -0.1,   0.,  0
2019-03-27T17:13:28.158Z,1553706808.158 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:28.566Z,1553706808.566 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:28.958Z,1553706808.958 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:29.362Z,1553706809.362 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:29.780Z,1553706809.780 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:30.170Z,1553706810.170 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:30.586Z,1553706810.586 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:30.997Z,1553706810.997 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:31.382Z,1553706811.382 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:31.798Z,1553706811.798 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:32.342Z,1553706812.342 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:32.750Z,1553706812.750 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:33.142Z,1553706813.142 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:33.546Z,1553706813.546 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:33.950Z,1553706813.950 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:34.362Z,1553706814.362 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:34.781Z,1553706814.781 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:35.180Z,1553706815.180 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:35.582Z,1553706815.582 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:35.982Z,1553706815.982 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:36.378Z,1553706816.378 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:36.798Z,1553706816.798 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:37.182Z,1553706817.182 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:37.586Z,1553706817.586 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:37.994Z,1553706817.994 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:38.403Z,1553706818.403 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:38.807Z,1553706818.807 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:39.202Z,1553706819.202 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:39.606Z,1553706819.606 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:40.010Z,1553706820.010 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:40.414Z,1553706820.414 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:40.822Z,1553706820.822 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:41.222Z,1553706821.222 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:41.630Z,1553706821.630 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:42.035Z,1553706822.035 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:42.434Z,1553706822.434 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:42.838Z,1553706822.838 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:43.242Z,1553706823.242 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:43.285Z,1553706823.285 [SBIT](IMPORTANT): SBIT PASSED
2019-03-27T17:13:43.354Z,1553706823.354 [CommandLine](IMPORTANT): got command configSet list
2019-03-27T17:13:43.354Z,1553706823.354 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-03-27T17:13:43.355Z,1553706823.355 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold=20 count;

2019-03-27T17:13:43.355Z,1553706823.355 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.range 2.000000 meter;

2019-03-27T17:13:43.355Z,1553706823.355 [CommandLine](IMPORTANT): Express linearApproximation DUSBL_Hydroid.xAngle 2.000000 angular_degree;

2019-03-27T17:13:43.355Z,1553706823.355 [CommandLine](IMPORTANT): NAL9602.fastGPSFix=1 bool;

2019-03-27T17:13:43.646Z,1553706823.646 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:43.676Z,1553706823.676 [MissionManager](IMPORTANT): Started mission Startup
2019-03-27T17:13:43.676Z,1553706823.676 [Startup] Running Loop=1
2019-03-27T17:13:43.677Z,1553706823.677 [Startup](DEBUG): Aggregate::initialize Startup
2019-03-27T17:13:43.677Z,1553706823.677 [Startup:A.GoToSurface] Running Loop=1
2019-03-27T17:13:43.677Z,1553706823.677 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-27T17:13:43.678Z,1553706823.678 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-27T17:13:43.678Z,1553706823.678 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-27T17:13:43.678Z,1553706823.678 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-27T17:13:43.679Z,1553706823.679 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-27T17:13:43.679Z,1553706823.679 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-27T17:13:43.682Z,1553706823.682 [Startup:StartupSatComms] Running Loop=1
2019-03-27T17:13:43.682Z,1553706823.682 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-03-27T17:13:43.682Z,1553706823.682 [Startup:StartupSatComms:A] Running Loop=1
2019-03-27T17:13:44.050Z,1553706824.050 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:44.085Z,1553706824.085 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-03-27T17:13:44.458Z,1553706824.458 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:44.858Z,1553706824.858 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:45.271Z,1553706825.271 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:45.670Z,1553706825.670 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:46.074Z,1553706826.074 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:46.474Z,1553706826.474 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:46.882Z,1553706826.882 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:47.282Z,1553706827.282 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:47.686Z,1553706827.686 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:48.094Z,1553706828.094 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:48.500Z,1553706828.500 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:48.902Z,1553706828.902 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:49.306Z,1553706829.306 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:49.710Z,1553706829.710 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:50.110Z,1553706830.110 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:50.518Z,1553706830.518 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:50.922Z,1553706830.922 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:51.326Z,1553706831.326 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:51.735Z,1553706831.735 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:52.130Z,1553706832.130 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:52.534Z,1553706832.534 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:52.938Z,1553706832.938 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:53.342Z,1553706833.342 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:53.758Z,1553706833.758 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:54.150Z,1553706834.150 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:54.554Z,1553706834.554 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:54.962Z,1553706834.962 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:55.370Z,1553706835.370 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:55.791Z,1553706835.791 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:56.179Z,1553706836.179 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:56.578Z,1553706836.578 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:56.978Z,1553706836.978 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:57.382Z,1553706837.382 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:57.806Z,1553706837.806 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:58.194Z,1553706838.194 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:58.594Z,1553706838.594 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:59.007Z,1553706839.007 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:59.402Z,1553706839.402 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:13:59.806Z,1553706839.806 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:00.210Z,1553706840.210 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:00.614Z,1553706840.614 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:01.029Z,1553706841.029 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:01.427Z,1553706841.427 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:01.826Z,1553706841.826 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:02.241Z,1553706842.241 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:02.638Z,1553706842.638 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:03.042Z,1553706843.042 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:03.446Z,1553706843.446 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:03.846Z,1553706843.846 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:04.250Z,1553706844.250 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:04.654Z,1553706844.654 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:05.058Z,1553706845.058 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:05.462Z,1553706845.462 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:05.866Z,1553706845.866 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:06.274Z,1553706846.274 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:06.674Z,1553706846.674 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:07.078Z,1553706847.078 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:07.486Z,1553706847.486 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:07.895Z,1553706847.895 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:08.299Z,1553706848.299 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:08.698Z,1553706848.698 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:09.098Z,1553706849.098 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:09.502Z,1553706849.502 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:09.910Z,1553706849.910 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:10.310Z,1553706850.310 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:10.730Z,1553706850.730 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:11.130Z,1553706851.130 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:11.526Z,1553706851.526 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:11.926Z,1553706851.926 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:12.339Z,1553706852.339 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:12.740Z,1553706852.740 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:13.142Z,1553706853.142 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:13.542Z,1553706853.542 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:13.946Z,1553706853.946 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:14.354Z,1553706854.354 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:14.762Z,1553706854.762 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:15.158Z,1553706855.158 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:15.566Z,1553706855.566 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:15.966Z,1553706855.966 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:16.370Z,1553706856.370 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:16.794Z,1553706856.794 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:17.178Z,1553706857.178 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:17.582Z,1553706857.582 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:17.990Z,1553706857.990 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:18.394Z,1553706858.394 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:18.803Z,1553706858.803 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:19.207Z,1553706859.207 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:19.606Z,1553706859.606 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:20.006Z,1553706860.006 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:20.414Z,1553706860.414 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:20.815Z,1553706860.815 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:21.222Z,1553706861.222 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:21.626Z,1553706861.626 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:22.030Z,1553706862.030 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:22.436Z,1553706862.436 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:22.838Z,1553706862.838 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:23.242Z,1553706863.242 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:23.646Z,1553706863.646 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:24.050Z,1553706864.050 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:24.450Z,1553706864.450 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:24.854Z,1553706864.854 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:25.262Z,1553706865.262 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:25.671Z,1553706865.671 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:26.066Z,1553706866.066 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:26.470Z,1553706866.470 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:26.874Z,1553706866.874 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:27.278Z,1553706867.278 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:27.686Z,1553706867.686 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:28.086Z,1553706868.086 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:28.490Z,1553706868.490 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:28.898Z,1553706868.898 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:29.307Z,1553706869.307 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:29.702Z,1553706869.702 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:30.106Z,1553706870.106 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:30.514Z,1553706870.514 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:30.914Z,1553706870.914 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:31.318Z,1553706871.318 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:31.726Z,1553706871.726 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:32.135Z,1553706872.135 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:32.541Z,1553706872.541 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:32.938Z,1553706872.938 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:33.338Z,1553706873.338 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:33.746Z,1553706873.746 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:34.146Z,1553706874.146 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:34.550Z,1553706874.550 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:34.954Z,1553706874.954 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:35.362Z,1553706875.362 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:35.786Z,1553706875.786 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:36.174Z,1553706876.174 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:36.574Z,1553706876.574 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:36.978Z,1553706876.978 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:37.378Z,1553706877.378 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:37.802Z,1553706877.802 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:38.190Z,1553706878.190 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:38.594Z,1553706878.594 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:39.006Z,1553706879.006 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:39.398Z,1553706879.398 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:39.810Z,1553706879.810 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:40.206Z,1553706880.206 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:40.610Z,1553706880.610 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:41.014Z,1553706881.014 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:41.422Z,1553706881.422 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:41.822Z,1553706881.822 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:42.237Z,1553706882.237 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:42.634Z,1553706882.634 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:43.047Z,1553706883.047 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:43.438Z,1553706883.438 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:43.858Z,1553706883.858 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:43.928Z,1553706883.928 [Startup:StartupSatComms:A](INFO): Timed out from 2019-03-27T17:13:43.7Z
2019-03-27T17:14:43.928Z,1553706883.928 [Startup:StartupSatComms:A] Stopped
2019-03-27T17:14:43.934Z,1553706883.934 [Startup:StartupSatComms:B] Running Loop=1
2019-03-27T17:14:44.258Z,1553706884.258 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:44.288Z,1553706884.288 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-03-27T17:14:44.654Z,1553706884.654 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:45.070Z,1553706885.070 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:45.462Z,1553706885.462 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:45.886Z,1553706885.886 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:46.274Z,1553706886.274 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:46.682Z,1553706886.682 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:47.078Z,1553706887.078 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:47.204Z,1553706887.204 [DataOverHttps](INFO): Sending 43 bytes from file Logs/20190326T232131/Courier0012.lzma
2019-03-27T17:14:47.482Z,1553706887.482 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:47.882Z,1553706887.882 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:48.038Z,1553706888.038 [DataOverHttps](INFO): Moved sent file to Logs/20190326T232131/Courier0012.lzma.bak
2019-03-27T17:14:48.039Z,1553706888.039 [DataOverHttps](INFO): SBD MOMSN=10256831
2019-03-27T17:14:48.290Z,1553706888.290 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:48.694Z,1553706888.694 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:49.100Z,1553706889.100 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:49.498Z,1553706889.498 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:49.902Z,1553706889.902 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:50.306Z,1553706890.306 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:50.710Z,1553706890.710 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:51.118Z,1553706891.118 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:51.518Z,1553706891.518 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:51.922Z,1553706891.922 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:52.330Z,1553706892.330 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:52.730Z,1553706892.730 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:53.134Z,1553706893.134 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:53.542Z,1553706893.542 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:53.942Z,1553706893.942 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:54.350Z,1553706894.350 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:54.750Z,1553706894.750 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:55.154Z,1553706895.154 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:55.562Z,1553706895.562 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:55.974Z,1553706895.974 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:56.378Z,1553706896.378 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:56.809Z,1553706896.809 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:57.186Z,1553706897.186 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:57.578Z,1553706897.578 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:57.986Z,1553706897.986 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:58.390Z,1553706898.390 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:58.802Z,1553706898.802 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:59.198Z,1553706899.198 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:14:59.602Z,1553706899.602 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:00.002Z,1553706900.002 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:00.256Z,1553706900.256 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190326T232131/Express0013.lzma
2019-03-27T17:15:00.406Z,1553706900.406 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:00.810Z,1553706900.810 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:01.063Z,1553706901.063 [DataOverHttps](INFO): Moved sent file to Logs/20190326T232131/Express0013.lzma.bak
2019-03-27T17:15:01.063Z,1553706901.063 [DataOverHttps](INFO): SBD MOMSN=10256834
2019-03-27T17:15:01.214Z,1553706901.214 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:01.618Z,1553706901.618 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:02.026Z,1553706902.026 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:02.426Z,1553706902.426 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:02.830Z,1553706902.830 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:03.238Z,1553706903.238 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:03.638Z,1553706903.638 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:04.042Z,1553706904.042 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:04.450Z,1553706904.450 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:04.850Z,1553706904.850 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:05.254Z,1553706905.254 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:05.662Z,1553706905.662 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:06.062Z,1553706906.062 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:06.466Z,1553706906.466 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:06.870Z,1553706906.870 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:07.274Z,1553706907.274 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:07.678Z,1553706907.678 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:08.086Z,1553706908.086 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:08.486Z,1553706908.486 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:08.898Z,1553706908.898 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:09.306Z,1553706909.306 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:09.698Z,1553706909.698 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:10.106Z,1553706910.106 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:10.518Z,1553706910.518 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:10.910Z,1553706910.910 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:11.314Z,1553706911.314 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:11.722Z,1553706911.722 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:12.129Z,1553706912.129 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:12.526Z,1553706912.526 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:12.660Z,1553706912.660 [DataOverHttps](INFO): Sending 778 bytes from file Logs/20190327T171211/Express0001.lzma
2019-03-27T17:15:12.934Z,1553706912.934 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:13.338Z,1553706913.338 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:13.446Z,1553706913.446 [DataOverHttps](INFO): Moved sent file to Logs/20190327T171211/Express0001.lzma.bak
2019-03-27T17:15:13.446Z,1553706913.446 [DataOverHttps](INFO): SBD MOMSN=10256836
2019-03-27T17:15:13.742Z,1553706913.742 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:14.154Z,1553706914.154 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:14.546Z,1553706914.546 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:14.950Z,1553706914.950 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:15.003Z,1553706915.003 [Startup:StartupSatComms:B] Stopped
2019-03-27T17:15:15.004Z,1553706915.004 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-03-27T17:15:15.004Z,1553706915.004 [Startup:StartupSatComms] Stopped
2019-03-27T17:15:15.004Z,1553706915.004 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-03-27T17:15:15.009Z,1553706915.009 [Startup](INFO): Completed Startup
2019-03-27T17:15:15.009Z,1553706915.009 [MissionManager](INFO): Startup is completed.
2019-03-27T17:15:15.009Z,1553706915.009 [MissionManager](INFO): Uninitializing Mission Startup
2019-03-27T17:15:15.009Z,1553706915.009 [Startup] Stopped
2019-03-27T17:15:15.009Z,1553706915.009 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-03-27T17:15:15.009Z,1553706915.009 [Startup:A.GoToSurface] Stopped
2019-03-27T17:15:15.010Z,1553706915.010 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-27T17:15:15.358Z,1553706915.358 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:15.400Z,1553706915.400 [MissionManager](IMPORTANT): Started mission Default
2019-03-27T17:15:15.400Z,1553706915.400 [Default] Running Loop=1
2019-03-27T17:15:15.400Z,1553706915.400 [Default](DEBUG): Aggregate::initialize Default
2019-03-27T17:15:15.400Z,1553706915.400 [Default:B.GoToSurface] Running Loop=1
2019-03-27T17:15:15.400Z,1553706915.400 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-03-27T17:15:15.413Z,1553706915.413 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-03-27T17:15:15.413Z,1553706915.413 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-03-27T17:15:15.413Z,1553706915.413 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-03-27T17:15:15.414Z,1553706915.414 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-03-27T17:15:15.414Z,1553706915.414 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-03-27T17:15:15.414Z,1553706915.414 [Default:A.Wait] Running Loop=1
2019-03-27T17:15:15.414Z,1553706915.414 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-03-27T17:15:15.793Z,1553706915.793 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:16.174Z,1553706916.174 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:16.570Z,1553706916.570 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:16.970Z,1553706916.970 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:17.374Z,1553706917.374 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:17.802Z,1553706917.802 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:18.194Z,1553706918.194 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:18.594Z,1553706918.594 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:18.998Z,1553706918.998 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:19.394Z,1553706919.394 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:19.806Z,1553706919.806 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:20.202Z,1553706920.202 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:20.623Z,1553706920.623 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:21.063Z,1553706921.063 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:21.194Z,1553706921.194 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-03-27T17:15:21.194Z,1553706921.194 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-03-27T17:15:21.196Z,1553706921.196 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-03-27T17:15:21.196Z,1553706921.196 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-03-27T17:15:21.198Z,1553706921.198 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-03-27T17:15:21.198Z,1553706921.198 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-03-27T17:15:21.354Z,1553706921.354 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-03-27T17:15:21.354Z,1553706921.354 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-03-27T17:15:21.354Z,1553706921.354 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-03-27T17:15:21.426Z,1553706921.426 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:21.496Z,1553706921.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-03-27T17:15:21.496Z,1553706921.496 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-03-27T17:15:21.496Z,1553706921.496 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-03-27T17:15:21.496Z,1553706921.496 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-03-27T17:15:21.501Z,1553706921.501 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-03-27T17:15:21.501Z,1553706921.501 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-03-27T17:15:21.826Z,1553706921.826 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:21.878Z,1553706921.878 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-03-27T17:15:22.234Z,1553706922.234 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:22.630Z,1553706922.630 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:23.030Z,1553706923.030 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:23.434Z,1553706923.434 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:23.842Z,1553706923.842 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:24.242Z,1553706924.242 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:24.650Z,1553706924.650 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:25.066Z,1553706925.066 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:25.454Z,1553706925.454 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:25.858Z,1553706925.858 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:26.262Z,1553706926.262 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:26.670Z,1553706926.670 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:27.070Z,1553706927.070 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:27.478Z,1553706927.478 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:27.882Z,1553706927.882 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:28.290Z,1553706928.290 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:28.686Z,1553706928.686 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:28.728Z,1553706928.728 [Default:A.Wait](INFO): Done Waiting.
2019-03-27T17:15:28.728Z,1553706928.728 [Default:A.Wait] Stopped
2019-03-27T17:15:28.728Z,1553706928.728 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-03-27T17:15:29.090Z,1553706929.090 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:29.210Z,1553706929.210 [Default:CheckIn] Running Loop=1
2019-03-27T17:15:29.210Z,1553706929.210 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-03-27T17:15:29.210Z,1553706929.210 [Default:CheckIn:Read_GPS] Running Loop=1
2019-03-27T17:15:29.494Z,1553706929.494 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:29.496Z,1553706929.496 [NAL9602](INFO): SBD MO Status=2, MOMSN=2454, MT Status=2, MTMSN=0
2019-03-27T17:15:29.496Z,1553706929.496 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-03-27T17:15:29.527Z,1553706929.527 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-03-27T17:15:29.902Z,1553706929.902 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:30.306Z,1553706930.306 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:30.706Z,1553706930.706 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:31.114Z,1553706931.114 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:31.518Z,1553706931.518 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:31.932Z,1553706931.932 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:32.326Z,1553706932.326 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:32.726Z,1553706932.726 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:33.130Z,1553706933.130 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:33.534Z,1553706933.534 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:33.942Z,1553706933.942 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:34.346Z,1553706934.346 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:34.750Z,1553706934.750 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:35.156Z,1553706935.156 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:35.558Z,1553706935.558 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:35.962Z,1553706935.962 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:36.362Z,1553706936.362 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:36.786Z,1553706936.786 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:37.170Z,1553706937.170 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:37.574Z,1553706937.574 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:37.982Z,1553706937.982 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:38.386Z,1553706938.386 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:38.806Z,1553706938.806 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:39.194Z,1553706939.194 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:39.598Z,1553706939.598 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:40.002Z,1553706940.002 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:40.402Z,1553706940.402 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:40.810Z,1553706940.810 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:41.214Z,1553706941.214 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:41.614Z,1553706941.614 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:42.022Z,1553706942.022 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:42.422Z,1553706942.422 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:42.830Z,1553706942.830 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:43.238Z,1553706943.238 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:43.634Z,1553706943.634 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:44.038Z,1553706944.038 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:44.446Z,1553706944.446 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:44.846Z,1553706944.846 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:45.254Z,1553706945.254 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:45.662Z,1553706945.662 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:46.058Z,1553706946.058 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:46.466Z,1553706946.466 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:46.866Z,1553706946.866 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:47.270Z,1553706947.270 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:47.678Z,1553706947.678 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:48.078Z,1553706948.078 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:48.482Z,1553706948.482 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:48.890Z,1553706948.890 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:49.290Z,1553706949.290 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:49.694Z,1553706949.694 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:50.102Z,1553706950.102 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:50.502Z,1553706950.502 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:50.910Z,1553706950.910 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:51.310Z,1553706951.310 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:51.714Z,1553706951.714 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:52.122Z,1553706952.122 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:52.522Z,1553706952.522 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:52.926Z,1553706952.926 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:53.331Z,1553706953.331 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:53.736Z,1553706953.736 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:54.142Z,1553706954.142 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:54.546Z,1553706954.546 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:54.950Z,1553706954.950 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:55.354Z,1553706955.354 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:55.779Z,1553706955.779 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:56.158Z,1553706956.158 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:56.569Z,1553706956.569 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:56.971Z,1553706956.971 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:57.378Z,1553706957.378 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:57.798Z,1553706957.798 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:58.178Z,1553706958.178 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:58.586Z,1553706958.586 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:59.002Z,1553706959.002 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:59.390Z,1553706959.390 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:15:59.802Z,1553706959.802 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:00.202Z,1553706960.202 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:00.602Z,1553706960.602 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:01.006Z,1553706961.006 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:01.414Z,1553706961.414 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:01.814Z,1553706961.814 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:02.226Z,1553706962.226 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:02.626Z,1553706962.626 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:03.026Z,1553706963.026 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:03.434Z,1553706963.434 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:03.834Z,1553706963.834 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:04.238Z,1553706964.238 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:04.646Z,1553706964.646 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:05.050Z,1553706965.050 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:05.450Z,1553706965.450 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:05.854Z,1553706965.854 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:06.262Z,1553706966.262 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:06.662Z,1553706966.662 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:07.066Z,1553706967.066 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:07.474Z,1553706967.474 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:07.878Z,1553706967.878 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:08.278Z,1553706968.278 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:08.686Z,1553706968.686 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:09.086Z,1553706969.086 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:09.490Z,1553706969.490 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:09.898Z,1553706969.898 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:10.302Z,1553706970.302 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:10.702Z,1553706970.702 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:11.106Z,1553706971.106 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:11.510Z,1553706971.510 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:11.923Z,1553706971.923 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:12.318Z,1553706972.318 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:12.726Z,1553706972.726 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:13.126Z,1553706973.126 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:13.534Z,1553706973.534 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:13.934Z,1553706973.934 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:14.342Z,1553706974.342 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:14.750Z,1553706974.750 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:15.146Z,1553706975.146 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:15.554Z,1553706975.554 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:15.958Z,1553706975.958 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:16.358Z,1553706976.358 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:16.781Z,1553706976.781 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:17.166Z,1553706977.166 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:17.574Z,1553706977.574 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:17.974Z,1553706977.974 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:18.382Z,1553706978.382 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:18.802Z,1553706978.802 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:19.190Z,1553706979.190 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:19.590Z,1553706979.590 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:19.994Z,1553706979.994 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:20.402Z,1553706980.402 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:20.802Z,1553706980.802 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:21.210Z,1553706981.210 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:21.614Z,1553706981.614 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:22.014Z,1553706982.014 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:22.418Z,1553706982.418 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:22.826Z,1553706982.826 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:23.230Z,1553706983.230 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:23.634Z,1553706983.634 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:24.038Z,1553706984.038 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:24.438Z,1553706984.438 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:24.842Z,1553706984.842 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:25.254Z,1553706985.254 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:25.650Z,1553706985.650 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:26.054Z,1553706986.054 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:26.458Z,1553706986.458 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:26.862Z,1553706986.862 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:27.266Z,1553706987.266 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:27.670Z,1553706987.670 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:28.082Z,1553706988.082 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:28.484Z,1553706988.484 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:28.886Z,1553706988.886 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:29.306Z,1553706989.306 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:29.690Z,1553706989.690 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:30.098Z,1553706990.098 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:30.506Z,1553706990.506 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:30.921Z,1553706990.921 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:31.306Z,1553706991.306 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:31.730Z,1553706991.730 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:32.119Z,1553706992.119 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:32.526Z,1553706992.526 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:32.926Z,1553706992.926 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:33.326Z,1553706993.326 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:33.730Z,1553706993.730 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:34.138Z,1553706994.138 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:34.538Z,1553706994.538 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:34.946Z,1553706994.946 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:35.354Z,1553706995.354 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:35.750Z,1553706995.750 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:36.162Z,1553706996.162 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:36.566Z,1553706996.566 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:36.966Z,1553706996.966 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:37.370Z,1553706997.370 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:37.792Z,1553706997.792 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:38.174Z,1553706998.174 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:38.578Z,1553706998.578 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:38.982Z,1553706998.982 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:39.386Z,1553706999.386 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:39.798Z,1553706999.798 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:40.194Z,1553707000.194 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:40.598Z,1553707000.598 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:41.002Z,1553707001.002 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:41.410Z,1553707001.410 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:41.810Z,1553707001.810 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:42.222Z,1553707002.222 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:42.622Z,1553707002.622 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:43.022Z,1553707003.022 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:43.426Z,1553707003.426 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:43.830Z,1553707003.830 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:44.234Z,1553707004.234 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:44.638Z,1553707004.638 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:45.046Z,1553707005.046 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:45.456Z,1553707005.456 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:45.854Z,1553707005.854 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:46.254Z,1553707006.254 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:46.662Z,1553707006.662 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:47.062Z,1553707007.062 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:47.470Z,1553707007.470 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:47.870Z,1553707007.870 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:48.274Z,1553707008.274 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:48.678Z,1553707008.678 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:49.086Z,1553707009.086 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:49.486Z,1553707009.486 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:49.890Z,1553707009.890 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:50.294Z,1553707010.294 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:50.699Z,1553707010.699 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:51.106Z,1553707011.106 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:51.506Z,1553707011.506 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:51.919Z,1553707011.919 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:52.322Z,1553707012.322 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:52.718Z,1553707012.718 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:53.122Z,1553707013.122 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:53.530Z,1553707013.530 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:53.930Z,1553707013.930 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:54.338Z,1553707014.338 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:54.746Z,1553707014.746 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:55.146Z,1553707015.146 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:55.550Z,1553707015.550 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:55.970Z,1553707015.970 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:56.354Z,1553707016.354 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:56.794Z,1553707016.794 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:57.174Z,1553707017.174 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:57.570Z,1553707017.570 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:57.974Z,1553707017.974 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:58.390Z,1553707018.390 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:58.795Z,1553707018.795 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:59.186Z,1553707019.186 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:59.598Z,1553707019.598 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:16:59.994Z,1553707019.994 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:17:00.035Z,1553707020.035 [CommandLine](IMPORTANT): got command restart application
2019-03-27T17:17:00.398Z,1553707020.398 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:17:00.822Z,1553707020.822 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:17:01.041Z,1553707021.041 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-03-27T17:17:01.041Z,1553707021.041 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:01.041Z,1553707021.041 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.053Z,1553707021.053 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-03-27T17:17:01.053Z,1553707021.053 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.054Z,1553707021.054 [CommandLine](INFO): Join timeout helper Thread ID is 7915
2019-03-27T17:17:01.054Z,1553707021.054 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-03-27T17:17:01.054Z,1553707021.054 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.055Z,1553707021.055 [NavChartDb](INFO): Join timeout helper Thread ID is 7916
2019-03-27T17:17:01.206Z,1553707021.206 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:17:01.333Z,1553707021.333 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:01.333Z,1553707021.333 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.341Z,1553707021.341 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-03-27T17:17:01.341Z,1553707021.341 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.341Z,1553707021.341 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 7917
2019-03-27T17:17:01.614Z,1553707021.614 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:17:01.649Z,1553707021.649 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:01.649Z,1553707021.649 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-03-27T17:17:01.650Z,1553707021.650 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.651Z,1553707021.651 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-03-27T17:17:01.651Z,1553707021.651 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.651Z,1553707021.651 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 7918
2019-03-27T17:17:01.717Z,1553707021.717 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:01.717Z,1553707021.717 [CTD_NeilBrown](INFO): Powering down
2019-03-27T17:17:01.729Z,1553707021.729 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.741Z,1553707021.741 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-03-27T17:17:01.741Z,1553707021.741 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:01.741Z,1553707021.741 [Radio_Surface](INFO): Join timeout helper Thread ID is 7919
2019-03-27T17:17:02.014Z,1553707022.014 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:17:02.037Z,1553707022.037 [Radio_Surface](INFO): Powering down
2019-03-27T17:17:02.038Z,1553707022.038 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:02.038Z,1553707022.038 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.046Z,1553707022.046 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-03-27T17:17:02.046Z,1553707022.046 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.046Z,1553707022.046 [DataOverHttps](INFO): Join timeout helper Thread ID is 7920
2019-03-27T17:17:02.397Z,1553707022.397 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:02.397Z,1553707022.397 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.422Z,1553707022.422 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-03-27T17:17:02.422Z,1553707022.422 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.422Z,1553707022.422 [logger](INFO): Join timeout helper Thread ID is 7921
2019-03-27T17:17:02.424Z,1553707022.424 [DUSBL_Hydroid](INFO): Paused
2019-03-27T17:17:02.438Z,1553707022.438 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:02.438Z,1553707022.438 [logger ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.446Z,1553707022.446 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-03-27T17:17:02.446Z,1553707022.446 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.446Z,1553707022.446 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-03-27T17:17:02.446Z,1553707022.446 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.446Z,1553707022.446 [controlThread](INFO): Join timeout helper Thread ID is 7922
2019-03-27T17:17:02.461Z,1553707022.461 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-03-27T17:17:02.461Z,1553707022.461 [controlThread](DEBUG): Uninitializing ControlThread
2019-03-27T17:17:02.462Z,1553707022.462 [AHRS_M2](INFO): Powering down
2019-03-27T17:17:02.534Z,1553707022.534 [DUSBL_Hydroid](INFO): uninitialize
2019-03-27T17:17:02.534Z,1553707022.534 [DUSBL_Hydroid](INFO): Powering down
2019-03-27T17:17:02.625Z,1553707022.625 [Micromodem](INFO): Powering down
2019-03-27T17:17:02.721Z,1553707022.721 [NAL9602](INFO): Powering down
2019-03-27T17:17:02.793Z,1553707022.793 [RDI_Pathfinder](INFO): Powering down
2019-03-27T17:17:02.794Z,1553707022.794 [RDI_PathfinderUp](INFO): Powering down
2019-03-27T17:17:02.795Z,1553707022.795 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-03-27T17:17:02.796Z,1553707022.796 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-03-27T17:17:02.797Z,1553707022.797 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-03-27T17:17:02.798Z,1553707022.798 [MissionManager](INFO): Uninitializing Mission Default
2019-03-27T17:17:02.798Z,1553707022.798 [Default] Stopped
2019-03-27T17:17:02.798Z,1553707022.798 [Default](DEBUG): Aggregate::uninitialize Default
2019-03-27T17:17:02.798Z,1553707022.798 [Default:B.GoToSurface] Stopped
2019-03-27T17:17:02.798Z,1553707022.798 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-03-27T17:17:02.798Z,1553707022.798 [Default:CheckIn] Stopped
2019-03-27T17:17:02.798Z,1553707022.798 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-03-27T17:17:02.798Z,1553707022.798 [Default:CheckIn:Read_GPS] Stopped
2019-03-27T17:17:02.801Z,1553707022.801 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-03-27T17:17:02.801Z,1553707022.801 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-03-27T17:17:02.802Z,1553707022.802 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-03-27T17:17:02.802Z,1553707022.802 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-03-27T17:17:02.802Z,1553707022.802 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-03-27T17:17:02.802Z,1553707022.802 [BuoyancyServo](INFO): Powering down
2019-03-27T17:17:02.817Z,1553707022.817 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-03-27T17:17:02.817Z,1553707022.817 [ElevatorServo](INFO): Powering down
2019-03-27T17:17:02.818Z,1553707022.818 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-03-27T17:17:02.818Z,1553707022.818 [MassServo](INFO): Powering down
2019-03-27T17:17:02.819Z,1553707022.819 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-03-27T17:17:02.819Z,1553707022.819 [RudderServo](INFO): Powering down
2019-03-27T17:17:02.819Z,1553707022.819 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-03-27T17:17:02.820Z,1553707022.820 [ThrusterServo](INFO): Powering down
2019-03-27T17:17:02.820Z,1553707022.820 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-03-27T17:17:02.821Z,1553707022.821 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-03-27T17:17:02.821Z,1553707022.821 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-03-27T17:17:02.821Z,1553707022.821 [CBIT](DEBUG): Powering off loads.
2019-03-27T17:17:02.833Z,1553707022.833 [CBIT](DEBUG): Disabling WDT.
2019-03-27T17:17:02.845Z,1553707022.845 [CBIT](DEBUG): Opening all GF detection circuits.
2019-03-27T17:17:02.846Z,1553707022.846 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.901Z,1553707022.901 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.910Z,1553707022.910 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.956Z,1553707022.956 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:02.958Z,1553707022.958 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:03.015Z,1553707023.015 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-03-27T17:17:03.072Z,1553707023.072 [logger ThreadHandler](INFO): Thread cancelled.