2016-05-10T20:28:10.304Z,1462912090.304 [Supervisor](DEBUG): Initializing supervisor.
2016-05-10T20:28:10.307Z,1462912090.307 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2016-05-10T20:28:10.307Z,1462912090.307 [SyncHandler](INFO): Protected caller Thread ID is 5383
2016-05-10T20:28:10.308Z,1462912090.308 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2016-05-10T20:28:10.309Z,1462912090.309 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2016-05-10T20:28:10.309Z,1462912090.309 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5384
2016-05-10T20:28:10.312Z,1462912090.312 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2016-05-10T20:28:10.324Z,1462912090.324 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2016-05-10T20:28:10.325Z,1462912090.325 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2016-05-10T20:28:10.325Z,1462912090.325 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5385
2016-05-10T20:28:10.326Z,1462912090.326 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2016-05-10T20:28:10.327Z,1462912090.327 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2016-05-10T20:28:10.327Z,1462912090.327 [logger ThreadHandler](INFO): Protected caller Thread ID is 5386
2016-05-10T20:28:10.330Z,1462912090.330 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2016-05-10T20:28:10.330Z,1462912090.330 [Supervisor](INFO): Looking for Config files in directory: Config/
2016-05-10T20:28:10.331Z,1462912090.331 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2016-05-10T20:28:10.691Z,1462912090.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2016-05-10T20:28:10.692Z,1462912090.692 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2016-05-10T20:28:10.862Z,1462912090.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2016-05-10T20:28:10.863Z,1462912090.863 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2016-05-10T20:28:10.991Z,1462912090.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2016-05-10T20:28:10.991Z,1462912090.991 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2016-05-10T20:28:11.125Z,1462912091.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2016-05-10T20:28:11.125Z,1462912091.125 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2016-05-10T20:28:11.212Z,1462912091.212 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2016-05-10T20:28:11.407Z,1462912091.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2016-05-10T20:28:11.407Z,1462912091.407 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2016-05-10T20:28:11.494Z,1462912091.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2016-05-10T20:28:11.494Z,1462912091.494 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2016-05-10T20:28:11.777Z,1462912091.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2016-05-10T20:28:11.777Z,1462912091.777 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2016-05-10T20:28:12.136Z,1462912092.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2016-05-10T20:28:12.137Z,1462912092.137 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2016-05-10T20:28:12.449Z,1462912092.449 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2016-05-10T20:28:12.450Z,1462912092.450 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2016-05-10T20:28:12.965Z,1462912092.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2016-05-10T20:28:12.966Z,1462912092.966 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2016-05-10T20:28:13.175Z,1462912093.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2016-05-10T20:28:13.175Z,1462912093.175 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2016-05-10T20:28:13.280Z,1462912093.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2016-05-10T20:28:13.280Z,1462912093.280 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2016-05-10T20:28:13.754Z,1462912093.754 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2016-05-10T20:28:13.754Z,1462912093.754 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2016-05-10T20:28:13.868Z,1462912093.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2016-05-10T20:28:13.870Z,1462912093.870 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2016-05-10T20:28:13.871Z,1462912093.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2016-05-10T20:28:14.117Z,1462912094.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2016-05-10T20:28:14.117Z,1462912094.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2016-05-10T20:28:14.236Z,1462912094.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2016-05-10T20:28:14.335Z,1462912094.335 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2016-05-10T20:28:14.434Z,1462912094.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2016-05-10T20:28:14.525Z,1462912094.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2016-05-10T20:28:14.676Z,1462912094.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2016-05-10T20:28:14.836Z,1462912094.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2016-05-10T20:28:14.940Z,1462912094.940 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2016-05-10T20:28:15.022Z,1462912095.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2016-05-10T20:28:15.113Z,1462912095.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2016-05-10T20:28:15.366Z,1462912095.366 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2016-05-10T20:28:15.367Z,1462912095.367 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2016-05-10T20:28:15.484Z,1462912095.484 [SBIT](DEBUG): Construct Startup Built In Test.
2016-05-10T20:28:15.516Z,1462912095.516 [SBIT] Loaded
2016-05-10T20:28:15.517Z,1462912095.517 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2016-05-10T20:28:15.517Z,1462912095.517 [IBIT](DEBUG): Construct Initiated Built In Test.
2016-05-10T20:28:15.546Z,1462912095.546 [IBIT] Loaded
2016-05-10T20:28:15.547Z,1462912095.547 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2016-05-10T20:28:15.549Z,1462912095.549 [CBIT](DEBUG): Construct Continuous Built In Test.
2016-05-10T20:28:15.694Z,1462912095.694 [CBIT] Loaded
2016-05-10T20:28:15.694Z,1462912095.694 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2016-05-10T20:28:15.694Z,1462912095.694 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2016-05-10T20:28:15.695Z,1462912095.695 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2016-05-10T20:28:15.743Z,1462912095.743 [VerticalControl](DEBUG): Construct VerticalControl.
2016-05-10T20:28:15.847Z,1462912095.847 [VerticalControl] Loaded
2016-05-10T20:28:15.847Z,1462912095.847 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2016-05-10T20:28:15.848Z,1462912095.848 [HorizontalControl](DEBUG): Construct HorizontalControl.
2016-05-10T20:28:15.913Z,1462912095.913 [HorizontalControl] Loaded
2016-05-10T20:28:15.913Z,1462912095.913 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2016-05-10T20:28:15.914Z,1462912095.914 [SpeedControl](DEBUG): Construct SpeedControl.
2016-05-10T20:28:15.920Z,1462912095.920 [SpeedControl] Loaded
2016-05-10T20:28:15.920Z,1462912095.920 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2016-05-10T20:28:15.921Z,1462912095.921 [LoopControl](DEBUG): Construct LoopControl.
2016-05-10T20:28:15.921Z,1462912095.921 [LoopControl] Loaded
2016-05-10T20:28:15.922Z,1462912095.922 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2016-05-10T20:28:15.922Z,1462912095.922 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2016-05-10T20:28:15.923Z,1462912095.923 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2016-05-10T20:28:15.945Z,1462912095.945 [DepthRateCalculator] Loaded
2016-05-10T20:28:15.946Z,1462912095.946 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2016-05-10T20:28:15.952Z,1462912095.952 [PitchRateCalculator] Loaded
2016-05-10T20:28:15.952Z,1462912095.952 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2016-05-10T20:28:15.963Z,1462912095.963 [SpeedCalculator] Loaded
2016-05-10T20:28:15.964Z,1462912095.964 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2016-05-10T20:28:15.980Z,1462912095.980 [TempGradientCalculator] Loaded
2016-05-10T20:28:15.981Z,1462912095.981 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2016-05-10T20:28:15.998Z,1462912095.998 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2016-05-10T20:28:15.998Z,1462912095.998 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2016-05-10T20:28:16.004Z,1462912096.004 [YawRateCalculator] Loaded
2016-05-10T20:28:16.004Z,1462912096.004 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2016-05-10T20:28:16.004Z,1462912096.004 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2016-05-10T20:28:16.005Z,1462912096.005 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2016-05-10T20:28:16.079Z,1462912096.079 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2016-05-10T20:28:16.080Z,1462912096.080 [StratificationFrontDetector](DEBUG): (re)initializing
2016-05-10T20:28:16.080Z,1462912096.080 [StratificationFrontDetector] Loaded
2016-05-10T20:28:16.080Z,1462912096.080 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2016-05-10T20:28:16.081Z,1462912096.081 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2016-05-10T20:28:16.081Z,1462912096.081 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2016-05-10T20:28:16.305Z,1462912096.305 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2016-05-10T20:28:16.305Z,1462912096.305 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2016-05-10T20:28:16.405Z,1462912096.405 [DeadReckonUsingMultipleVelocitySources] Loaded
2016-05-10T20:28:16.405Z,1462912096.405 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2016-05-10T20:28:16.462Z,1462912096.462 [DeadReckonUsingSpeedCalculator] Loaded
2016-05-10T20:28:16.462Z,1462912096.462 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2016-05-10T20:28:16.479Z,1462912096.479 [NavChart] Loaded
2016-05-10T20:28:16.479Z,1462912096.479 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2016-05-10T20:28:16.484Z,1462912096.484 [UniversalFixResidualReporter] Loaded
2016-05-10T20:28:16.484Z,1462912096.484 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2016-05-10T20:28:16.484Z,1462912096.484 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2016-05-10T20:28:16.485Z,1462912096.485 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2016-05-10T20:28:16.491Z,1462912096.491 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2016-05-10T20:28:16.491Z,1462912096.491 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2016-05-10T20:28:16.674Z,1462912096.674 [Aanderaa_O2] Loaded
2016-05-10T20:28:16.674Z,1462912096.674 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2016-05-10T20:28:16.752Z,1462912096.752 [CTD_NeilBrown] Loaded
2016-05-10T20:28:16.752Z,1462912096.752 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2016-05-10T20:28:16.754Z,1462912096.754 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059E4E0
2016-05-10T20:28:16.754Z,1462912096.754 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5467
2016-05-10T20:28:16.756Z,1462912096.756 [ESPComponent](CRITICAL): No configuration setting for: ESPComponent.espSimulator
2016-05-10T20:28:16.764Z,1462912096.764 [ESPComponent](CRITICAL): No configuration setting for: ESPComponent.stopCompleteTimeout
2016-05-10T20:28:16.776Z,1462912096.776 [ESPComponent] Loaded
2016-05-10T20:28:16.776Z,1462912096.776 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2016-05-10T20:28:16.792Z,1462912096.792 [PAR_Licor] Loaded
2016-05-10T20:28:16.792Z,1462912096.792 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2016-05-10T20:28:16.843Z,1462912096.843 [WetLabsBB2FL] Loaded
2016-05-10T20:28:16.843Z,1462912096.843 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2016-05-10T20:28:16.844Z,1462912096.844 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CE4E0
2016-05-10T20:28:16.845Z,1462912096.845 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5468
2016-05-10T20:28:16.845Z,1462912096.845 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2016-05-10T20:28:16.846Z,1462912096.846 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2016-05-10T20:28:17.099Z,1462912097.099 [AcousticModem_Benthos_ATM900] Loaded
2016-05-10T20:28:17.099Z,1462912097.099 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2016-05-10T20:28:17.176Z,1462912097.176 [DataOverHttps] Loaded
2016-05-10T20:28:17.176Z,1462912097.176 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2016-05-10T20:28:17.190Z,1462912097.190 [Depth_Keller] Loaded
2016-05-10T20:28:17.190Z,1462912097.190 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2016-05-10T20:28:17.196Z,1462912097.196 [DropWeight] Loaded
2016-05-10T20:28:17.196Z,1462912097.196 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2016-05-10T20:28:17.291Z,1462912097.291 [NAL9602] Loaded
2016-05-10T20:28:17.291Z,1462912097.291 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2016-05-10T20:28:17.335Z,1462912097.335 [Onboard] Loaded
2016-05-10T20:28:17.335Z,1462912097.335 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2016-05-10T20:28:17.343Z,1462912097.343 [Radio_Surface] Loaded
2016-05-10T20:28:17.343Z,1462912097.343 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2016-05-10T20:28:17.344Z,1462912097.344 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E54E0
2016-05-10T20:28:17.345Z,1462912097.345 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5469
2016-05-10T20:28:17.394Z,1462912097.394 [PNI_TCM] Loaded
2016-05-10T20:28:17.394Z,1462912097.394 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2016-05-10T20:28:17.409Z,1462912097.409 [Rowe_600LCM] Loaded
2016-05-10T20:28:17.409Z,1462912097.409 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2016-05-10T20:28:17.410Z,1462912097.410 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2016-05-10T20:28:17.411Z,1462912097.411 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 5470
2016-05-10T20:28:19.281Z,1462912099.281 [BPC1] Loaded
2016-05-10T20:28:19.281Z,1462912099.281 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2016-05-10T20:28:19.281Z,1462912099.281 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2016-05-10T20:28:19.282Z,1462912099.282 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2016-05-10T20:28:19.402Z,1462912099.402 [BuoyancyServo] Loaded
2016-05-10T20:28:19.402Z,1462912099.402 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2016-05-10T20:28:19.414Z,1462912099.414 [ElevatorServo] Loaded
2016-05-10T20:28:19.415Z,1462912099.415 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2016-05-10T20:28:19.426Z,1462912099.426 [MassServo] Loaded
2016-05-10T20:28:19.427Z,1462912099.427 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2016-05-10T20:28:19.439Z,1462912099.439 [RudderServo] Loaded
2016-05-10T20:28:19.439Z,1462912099.439 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2016-05-10T20:28:19.450Z,1462912099.450 [ThrusterServo] Loaded
2016-05-10T20:28:19.451Z,1462912099.451 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2016-05-10T20:28:19.451Z,1462912099.451 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2016-05-10T20:28:19.452Z,1462912099.452 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2016-05-10T20:28:19.549Z,1462912099.549 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2016-05-10T20:28:19.550Z,1462912099.550 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2016-05-10T20:28:19.574Z,1462912099.574 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2016-05-10T20:28:19.578Z,1462912099.578 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2016-05-10T20:28:19.579Z,1462912099.579 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2016-05-10T20:28:19.586Z,1462912099.586 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2016-05-10T20:28:19.587Z,1462912099.587 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC34E0
2016-05-10T20:28:19.588Z,1462912099.588 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5471
2016-05-10T20:28:19.592Z,1462912099.592 [Supervisor](INFO): Main Thread ID is 5380
2016-05-10T20:28:19.593Z,1462912099.593 [Supervisor](DEBUG): Running supervisor.
2016-05-10T20:28:19.593Z,1462912099.593 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5472
2016-05-10T20:28:19.596Z,1462912099.596 [controlThread ThreadHandler](INFO): Handler Thread ID is 5473
2016-05-10T20:28:19.597Z,1462912099.597 [controlThread](DEBUG): Initializing ControlThread
2016-05-10T20:28:19.597Z,1462912099.597 [SBIT](INFO): Initialize SBIT Component.
2016-05-10T20:28:19.598Z,1462912099.598 [SBIT](IMPORTANT): git: 2015-11-13-11-g036008f
2016-05-10T20:28:19.598Z,1462912099.598 [SBIT](INFO): git hash: 036008fb27dd1443c9c6898c567944386f491f02
2016-05-10T20:28:19.599Z,1462912099.599 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2016-05-10T20:28:19.599Z,1462912099.599 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2016-05-10T20:28:19.600Z,1462912099.600 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2016-05-10T20:28:19.600Z,1462912099.600 [IBIT](INFO): Initialize IBIT Component.
2016-05-10T20:28:19.601Z,1462912099.601 [CBIT](DEBUG): Initialize CBIT Component.
2016-05-10T20:28:19.601Z,1462912099.601 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2016-05-10T20:28:19.601Z,1462912099.601 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2016-05-10T20:28:19.602Z,1462912099.602 [logger ThreadHandler](INFO): Handler Thread ID is 5474
2016-05-10T20:28:19.623Z,1462912099.623 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5475
2016-05-10T20:28:19.624Z,1462912099.624 [CTD_NeilBrown](INFO): Powering down
2016-05-10T20:28:19.627Z,1462912099.627 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2016-05-10T20:28:19.629Z,1462912099.629 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2016-05-10T20:28:19.629Z,1462912099.629 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2016-05-10T20:28:19.630Z,1462912099.630 [LoopControl](DEBUG): Initialize LoopControlComponent.
2016-05-10T20:28:19.630Z,1462912099.630 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-05-10T20:28:19.630Z,1462912099.630 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2016-05-10T20:28:19.631Z,1462912099.631 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2016-05-10T20:28:19.631Z,1462912099.631 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2016-05-10T20:28:19.632Z,1462912099.632 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2016-05-10T20:28:19.632Z,1462912099.632 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2016-05-10T20:28:19.633Z,1462912099.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-05-10T20:28:19.634Z,1462912099.634 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-05-10T20:28:19.634Z,1462912099.634 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-05-10T20:28:19.634Z,1462912099.634 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-05-10T20:28:19.639Z,1462912099.639 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-05-10T20:28:19.639Z,1462912099.639 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-05-10T20:28:19.640Z,1462912099.640 [NavChart](DEBUG): Initialize NavChart Navigation.
2016-05-10T20:28:19.640Z,1462912099.640 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2016-05-10T20:28:19.655Z,1462912099.655 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2016-05-10T20:28:19.675Z,1462912099.675 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5476
2016-05-10T20:28:19.676Z,1462912099.676 [WetLabsBB2FL](INFO): Powering down
2016-05-10T20:28:19.701Z,1462912099.701 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2016-05-10T20:28:19.703Z,1462912099.703 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5477
2016-05-10T20:28:19.711Z,1462912099.711 [Radio_Surface](INFO): Powering up
2016-05-10T20:28:19.727Z,1462912099.727 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 5478
2016-05-10T20:28:19.728Z,1462912099.728 [Rowe_600LCM](INFO): Powering down
2016-05-10T20:28:19.779Z,1462912099.779 [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>


2016-05-10T20:28:19.780Z,1462912099.780 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2016-05-10T20:28:19.783Z,1462912099.783 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5480
2016-05-10T20:28:19.786Z,1462912099.786 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources/ElectronicNavigationCharts
2016-05-10T20:28:19.787Z,1462912099.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2016-05-10T20:28:19.787Z,1462912099.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2016-05-10T20:28:19.787Z,1462912099.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2016-05-10T20:28:19.787Z,1462912099.787 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2016-05-10T20:28:19.788Z,1462912099.788 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2016-05-10T20:28:19.788Z,1462912099.788 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2016-05-10T20:28:19.788Z,1462912099.788 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2016-05-10T20:28:19.788Z,1462912099.788 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2016-05-10T20:28:19.896Z,1462912099.896 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2016-05-10T20:28:19.897Z,1462912099.897 [Default:A.Wait](DEBUG): Construct Wait.
2016-05-10T20:28:19.899Z,1462912099.899 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2016-05-10T20:28:19.926Z,1462912099.926 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2016-05-10T20:28:19.953Z,1462912099.953 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2016-05-10T20:28:19.959Z,1462912099.959 [Default:E.Execute](DEBUG): Construct Execute.
2016-05-10T20:28:19.986Z,1462912099.986 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
	<Description>Wait a moment to see if the scheduler starts a new mission before starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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>


2016-05-10T20:28:19.992Z,1462912099.992 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2016-05-10T20:28:20.126Z,1462912100.126 [AcousticModem_Benthos_ATM900](INFO): Powering up
2016-05-10T20:28:20.126Z,1462912100.126 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2016-05-10T20:28:20.565Z,1462912100.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:20.592Z,1462912100.592 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:20.717Z,1462912100.717 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-05-10T20:28:20.725Z,1462912100.725 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-05-10T20:28:20.740Z,1462912100.740 [ElevatorServo](DEBUG): Initializing EZServoServo.
2016-05-10T20:28:20.749Z,1462912100.749 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2016-05-10T20:28:20.759Z,1462912100.759 [MassServo](DEBUG): Initializing EZServoServo.
2016-05-10T20:28:20.765Z,1462912100.765 [MassServo](DEBUG): Initializing MassServo.
2016-05-10T20:28:20.781Z,1462912100.781 [RudderServo](DEBUG): Initializing EZServoServo.
2016-05-10T20:28:20.785Z,1462912100.785 [RudderServo](DEBUG): Initializing RudderServo.
2016-05-10T20:28:20.791Z,1462912100.791 [ThrusterServo](DEBUG): Initializing EZServoServo.
2016-05-10T20:28:20.797Z,1462912100.797 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2016-05-10T20:28:20.850Z,1462912100.850 [CommandLine](FAULT): Scheduling is paused
2016-05-10T20:28:20.870Z,1462912100.870 [Rowe_600LCM](INFO): Initializing
2016-05-10T20:28:20.871Z,1462912100.871 [Rowe_600LCM](IMPORTANT): Checking LCM
2016-05-10T20:28:20.871Z,1462912100.871 [Rowe_600LCM](IMPORTANT): LCM OK
2016-05-10T20:28:20.871Z,1462912100.871 [Rowe_600LCM](INFO): Powering up
2016-05-10T20:28:21.138Z,1462912101.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:21.139Z,1462912101.139 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:21.447Z,1462912101.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:21.448Z,1462912101.448 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:21.896Z,1462912101.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:21.897Z,1462912101.897 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:22.159Z,1462912102.159 [Aanderaa_O2](DEBUG): Error parsing data: 4831F	241		235.167		84.628		21.083		30.086		30.086		39.084		8.998		595.3		808.2		62.0	

2016-05-10T20:28:22.159Z,1462912102.159 [Aanderaa_O2] Data Fault, FailCount= 1
2016-05-10T20:28:22.159Z,1462912102.159 [Aanderaa_O2](ERROR): Data Fault
2016-05-10T20:28:22.432Z,1462912102.432 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:22.433Z,1462912102.433 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:22.463Z,1462912102.463 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2016-05-10T20:28:22.679Z,1462912102.679 [Aanderaa_O2](INFO): Powering down
2016-05-10T20:28:22.786Z,1462912102.786 [NAL9602](INFO): Powering up NAL9602
2016-05-10T20:28:22.851Z,1462912102.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:22.852Z,1462912102.852 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:22.996Z,1462912102.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:22.997Z,1462912102.997 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:23.623Z,1462912103.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:23.625Z,1462912103.625 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:23.646Z,1462912103.646 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2016-05-10T20:28:23.646Z,1462912103.646 [Aanderaa_O2] No Fault, FailCount= 1
2016-05-10T20:28:23.837Z,1462912103.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:23.838Z,1462912103.838 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:24.227Z,1462912104.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:24.228Z,1462912104.228 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:24.781Z,1462912104.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:24.782Z,1462912104.782 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:25.177Z,1462912105.177 [Rowe_600LCM](INFO): Stopping previous instance(s) of Rowe LCM interface
2016-05-10T20:28:25.228Z,1462912105.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:25.230Z,1462912105.230 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:25.325Z,1462912105.325 [Rowe_600LCM](INFO): Starting Rowe LCM interface
2016-05-10T20:28:25.390Z,1462912105.390 [Rowe_600LCM](IMPORTANT): LCM subscribed
2016-05-10T20:28:25.558Z,1462912105.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:25.560Z,1462912105.560 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:25.954Z,1462912105.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:25.955Z,1462912105.955 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:26.364Z,1462912106.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:26.378Z,1462912106.378 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:26.767Z,1462912106.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:26.768Z,1462912106.768 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:27.102Z,1462912107.102 [Aanderaa_O2](INFO): Powering down
2016-05-10T20:28:27.150Z,1462912107.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:27.151Z,1462912107.151 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:27.524Z,1462912107.524 [AcousticModem_Benthos_ATM900](DEBUG): 
2016-05-10T20:28:27.577Z,1462912107.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:27.578Z,1462912107.578 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:27.964Z,1462912107.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:27.965Z,1462912107.965 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:28.448Z,1462912108.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:28.449Z,1462912108.449 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:28.707Z,1462912108.707 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2016-05-10T20:28:28.708Z,1462912108.708 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2016-05-10T20:28:28.709Z,1462912108.709 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2016-05-10T20:28:28.777Z,1462912108.777 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:28.778Z,1462912108.778 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:29.171Z,1462912109.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:29.172Z,1462912109.172 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:29.508Z,1462912109.508 [AcousticModem_Benthos_ATM900](DEBUG): May 10 2016 20:21:01 
2016-05-10T20:28:29.634Z,1462912109.634 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:29.638Z,1462912109.638 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:30.047Z,1462912110.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:30.059Z,1462912110.059 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:30.320Z,1462912110.320 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2016-05-10T20:28:30.365Z,1462912110.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:30.366Z,1462912110.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:30.705Z,1462912110.705 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2016-05-10T20:28:30.706Z,1462912110.706 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2016-05-10T20:28:30.706Z,1462912110.706 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2016-05-10T20:28:30.741Z,1462912110.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:30.742Z,1462912110.742 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:31.105Z,1462912111.105 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2016-05-10T20:28:31.133Z,1462912111.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:31.134Z,1462912111.134 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:31.506Z,1462912111.506 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2016-05-10T20:28:31.507Z,1462912111.507 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2016-05-10T20:28:31.558Z,1462912111.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:31.560Z,1462912111.560 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:31.907Z,1462912111.907 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2016-05-10T20:28:31.939Z,1462912111.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:31.940Z,1462912111.940 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:32.348Z,1462912112.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:32.349Z,1462912112.349 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:32.742Z,1462912112.742 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2016-05-10T20:28:32.742Z,1462912112.742 [PNI_TCM] Data Fault, FailCount= 1
2016-05-10T20:28:32.742Z,1462912112.742 [PNI_TCM](ERROR): Data Fault
2016-05-10T20:28:32.831Z,1462912112.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:32.832Z,1462912112.832 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:32.883Z,1462912112.883 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-05-10T20:28:33.130Z,1462912113.130 [PNI_TCM](INFO): Powering down
2016-05-10T20:28:33.203Z,1462912113.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:33.212Z,1462912113.212 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:33.573Z,1462912113.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:33.574Z,1462912113.574 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:33.927Z,1462912113.927 [NAL9602](INFO): NAL9602 initialized
2016-05-10T20:28:33.947Z,1462912113.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:33.948Z,1462912113.948 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:34.395Z,1462912114.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:34.396Z,1462912114.396 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:34.462Z,1462912114.462 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-05-10T20:28:34.462Z,1462912114.462 [PNI_TCM] No Fault, FailCount= 1
2016-05-10T20:28:34.767Z,1462912114.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:34.768Z,1462912114.768 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:35.227Z,1462912115.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:35.228Z,1462912115.228 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:35.600Z,1462912115.600 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:35.601Z,1462912115.601 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:36.009Z,1462912116.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:36.010Z,1462912116.010 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:36.356Z,1462912116.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:36.366Z,1462912116.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:36.885Z,1462912116.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:36.897Z,1462912116.897 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:37.259Z,1462912117.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:37.260Z,1462912117.260 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:37.681Z,1462912117.681 [PNI_TCM](ERROR): No parameter response UART error: serial timeout
2016-05-10T20:28:37.682Z,1462912117.682 [PNI_TCM](ERROR): Received 0xF700FF
2016-05-10T20:28:37.729Z,1462912117.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:37.748Z,1462912117.748 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:38.039Z,1462912118.039 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:38.050Z,1462912118.050 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:38.564Z,1462912118.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:38.576Z,1462912118.576 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:38.869Z,1462912118.869 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:38.881Z,1462912118.881 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:39.290Z,1462912119.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:39.302Z,1462912119.302 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:39.683Z,1462912119.683 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:39.711Z,1462912119.711 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:40.034Z,1462912120.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:40.036Z,1462912120.036 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:40.379Z,1462912120.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:40.380Z,1462912120.380 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:40.751Z,1462912120.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:40.760Z,1462912120.760 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:41.217Z,1462912121.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:41.218Z,1462912121.218 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:41.569Z,1462912121.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:41.570Z,1462912121.570 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:41.988Z,1462912121.988 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:41.989Z,1462912121.989 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:42.544Z,1462912122.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:42.545Z,1462912122.545 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:42.824Z,1462912122.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:42.825Z,1462912122.825 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:43.193Z,1462912123.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:43.194Z,1462912123.194 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:43.595Z,1462912123.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:43.600Z,1462912123.600 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:43.975Z,1462912123.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:43.977Z,1462912123.977 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:44.356Z,1462912124.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:44.366Z,1462912124.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:44.765Z,1462912124.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:44.770Z,1462912124.770 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:45.174Z,1462912125.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:45.183Z,1462912125.183 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:45.582Z,1462912125.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:45.591Z,1462912125.591 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:45.959Z,1462912125.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:45.968Z,1462912125.968 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:46.401Z,1462912126.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:46.402Z,1462912126.402 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:46.769Z,1462912126.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:46.770Z,1462912126.770 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:47.212Z,1462912127.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:47.217Z,1462912127.217 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:47.626Z,1462912127.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:47.627Z,1462912127.627 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:47.982Z,1462912127.982 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:47.991Z,1462912127.991 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:48.381Z,1462912128.381 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-05-10T20:28:48.381Z,1462912128.381 [PNI_TCM] Data Fault, FailCount= 2
2016-05-10T20:28:48.381Z,1462912128.381 [PNI_TCM](ERROR): Data Fault
2016-05-10T20:28:48.388Z,1462912128.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:48.389Z,1462912128.389 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:48.405Z,1462912128.405 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-05-10T20:28:48.719Z,1462912128.719 [PNI_TCM](INFO): Powering down
2016-05-10T20:28:48.864Z,1462912128.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:48.865Z,1462912128.865 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:49.137Z,1462912129.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:49.138Z,1462912129.138 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:49.561Z,1462912129.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:49.562Z,1462912129.562 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:49.938Z,1462912129.938 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:49.939Z,1462912129.939 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:49.977Z,1462912129.977 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-05-10T20:28:49.977Z,1462912129.977 [PNI_TCM] No Fault, FailCount= 2
2016-05-10T20:28:50.378Z,1462912130.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:50.379Z,1462912130.379 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:50.815Z,1462912130.815 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:50.827Z,1462912130.827 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:51.175Z,1462912131.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:51.177Z,1462912131.177 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:51.598Z,1462912131.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:51.599Z,1462912131.599 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:51.977Z,1462912131.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:51.978Z,1462912131.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:52.378Z,1462912132.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:52.379Z,1462912132.379 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:52.750Z,1462912132.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:52.751Z,1462912132.751 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:53.215Z,1462912133.215 [PNI_TCM](ERROR): No parameter response UART error: serial timeout
2016-05-10T20:28:53.215Z,1462912133.215 [PNI_TCM](ERROR): Received 0xFF00DC00
2016-05-10T20:28:53.226Z,1462912133.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:53.239Z,1462912133.239 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:53.604Z,1462912133.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:53.605Z,1462912133.605 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:53.971Z,1462912133.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:53.972Z,1462912133.972 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:54.374Z,1462912134.374 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:54.375Z,1462912134.375 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:54.778Z,1462912134.778 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:54.779Z,1462912134.779 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:55.150Z,1462912135.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:55.151Z,1462912135.151 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:55.583Z,1462912135.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:55.584Z,1462912135.584 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:55.955Z,1462912135.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:55.956Z,1462912135.956 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:56.356Z,1462912136.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:56.357Z,1462912136.357 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:56.811Z,1462912136.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:56.821Z,1462912136.821 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:57.157Z,1462912137.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:57.158Z,1462912137.158 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:57.561Z,1462912137.561 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:57.580Z,1462912137.580 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:57.979Z,1462912137.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:57.980Z,1462912137.980 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:58.385Z,1462912138.385 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:58.386Z,1462912138.386 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:58.776Z,1462912138.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:58.777Z,1462912138.777 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:59.155Z,1462912139.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:59.176Z,1462912139.176 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:59.577Z,1462912139.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:59.578Z,1462912139.578 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:59.957Z,1462912139.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:28:59.977Z,1462912139.977 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:00.390Z,1462912140.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:00.391Z,1462912140.391 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:00.754Z,1462912140.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:00.759Z,1462912140.759 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:01.154Z,1462912141.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:01.175Z,1462912141.175 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:01.618Z,1462912141.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:01.619Z,1462912141.619 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:01.995Z,1462912141.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:02.004Z,1462912142.004 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:02.375Z,1462912142.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:02.376Z,1462912142.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:02.826Z,1462912142.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:02.827Z,1462912142.827 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:03.182Z,1462912143.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:03.187Z,1462912143.187 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:03.558Z,1462912143.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:03.576Z,1462912143.576 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:03.972Z,1462912143.972 [PNI_TCM](FAULT): Failed to acquire valid data within timeout.
2016-05-10T20:29:03.972Z,1462912143.972 [PNI_TCM] Data Fault, FailCount= 3
2016-05-10T20:29:03.972Z,1462912143.972 [PNI_TCM](ERROR): Data Fault
2016-05-10T20:29:04.035Z,1462912144.035 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:04.037Z,1462912144.037 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:04.055Z,1462912144.055 [CBIT](ERROR): Data Fault in component: PNI_TCM
2016-05-10T20:29:04.331Z,1462912144.331 [PNI_TCM](INFO): Powering down
2016-05-10T20:29:04.428Z,1462912144.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:04.429Z,1462912144.429 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:04.771Z,1462912144.771 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:04.772Z,1462912144.772 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:05.201Z,1462912145.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:05.202Z,1462912145.202 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:05.542Z,1462912145.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:05.543Z,1462912145.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:05.561Z,1462912145.561 [CBIT](INFO): Clearing failed state for component PNI_TCM
2016-05-10T20:29:05.561Z,1462912145.561 [PNI_TCM] No Fault, FailCount= 3
2016-05-10T20:29:05.947Z,1462912145.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:05.948Z,1462912145.948 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:06.363Z,1462912146.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:06.376Z,1462912146.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:06.773Z,1462912146.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:06.774Z,1462912146.774 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:07.183Z,1462912147.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:07.184Z,1462912147.184 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:07.557Z,1462912147.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:07.558Z,1462912147.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:08.002Z,1462912148.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:08.003Z,1462912148.003 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:08.347Z,1462912148.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:08.348Z,1462912148.348 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:08.751Z,1462912148.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:08.752Z,1462912148.752 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:09.216Z,1462912149.216 [PNI_TCM](ERROR): No parameter response UART error: serial timeout
2016-05-10T20:29:09.216Z,1462912149.216 [PNI_TCM](ERROR): Received 0xEE00
2016-05-10T20:29:09.245Z,1462912149.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:09.246Z,1462912149.246 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:09.272Z,1462912149.272 [CommandLine](IMPORTANT): got command quit
2016-05-10T20:29:09.553Z,1462912149.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:09.554Z,1462912149.554 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:09.974Z,1462912149.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:09.975Z,1462912149.975 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:10.280Z,1462912150.280 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:10.281Z,1462912150.281 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:10.364Z,1462912150.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:10.382Z,1462912150.382 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:10.429Z,1462912150.429 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2016-05-10T20:29:10.429Z,1462912150.429 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:10.430Z,1462912150.430 [CommandLine](INFO): Join timeout helper Thread ID is 5520
2016-05-10T20:29:10.442Z,1462912150.442 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2016-05-10T20:29:10.442Z,1462912150.442 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:10.442Z,1462912150.442 [NavChartDb](INFO): Join timeout helper Thread ID is 5521
2016-05-10T20:29:10.612Z,1462912150.612 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:10.612Z,1462912150.612 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:10.614Z,1462912150.614 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2016-05-10T20:29:10.614Z,1462912150.614 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:10.614Z,1462912150.614 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 5522
2016-05-10T20:29:10.741Z,1462912150.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:10.743Z,1462912150.743 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:11.011Z,1462912151.011 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:11.011Z,1462912151.011 [Rowe_600LCM](INFO): Powering down
2016-05-10T20:29:11.140Z,1462912151.140 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.151Z,1462912151.151 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2016-05-10T20:29:11.151Z,1462912151.151 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.151Z,1462912151.151 [Radio_Surface](INFO): Join timeout helper Thread ID is 5524
2016-05-10T20:29:11.160Z,1462912151.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:11.161Z,1462912151.161 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:11.495Z,1462912151.495 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:11.496Z,1462912151.496 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.503Z,1462912151.503 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2016-05-10T20:29:11.503Z,1462912151.503 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.504Z,1462912151.504 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5525
2016-05-10T20:29:11.550Z,1462912151.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:11.551Z,1462912151.551 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-05-10T20:29:11.588Z,1462912151.588 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:11.588Z,1462912151.588 [WetLabsBB2FL](INFO): Powering down
2016-05-10T20:29:11.589Z,1462912151.589 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.608Z,1462912151.608 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2016-05-10T20:29:11.609Z,1462912151.609 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.609Z,1462912151.609 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5526
2016-05-10T20:29:11.644Z,1462912151.644 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:11.644Z,1462912151.644 [CTD_NeilBrown](INFO): Powering down
2016-05-10T20:29:11.645Z,1462912151.645 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.648Z,1462912151.648 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2016-05-10T20:29:11.648Z,1462912151.648 [logger ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.649Z,1462912151.649 [logger](INFO): Join timeout helper Thread ID is 5527
2016-05-10T20:29:11.663Z,1462912151.663 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:11.664Z,1462912151.664 [logger ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.668Z,1462912151.668 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2016-05-10T20:29:11.668Z,1462912151.668 [CommandLine ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.668Z,1462912151.668 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2016-05-10T20:29:11.669Z,1462912151.669 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:11.669Z,1462912151.669 [controlThread](INFO): Join timeout helper Thread ID is 5528
2016-05-10T20:29:11.900Z,1462912151.900 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2016-05-10T20:29:11.900Z,1462912151.900 [controlThread](DEBUG): Uninitializing ControlThread
2016-05-10T20:29:11.901Z,1462912151.901 [Aanderaa_O2](INFO): Powering down
2016-05-10T20:29:11.902Z,1462912151.902 [AcousticModem_Benthos_ATM900](INFO): Powering down
2016-05-10T20:29:12.021Z,1462912152.021 [NAL9602](INFO): Powering down
2016-05-10T20:29:12.022Z,1462912152.022 [PNI_TCM](INFO): Powering down
2016-05-10T20:29:12.109Z,1462912152.109 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-05-10T20:29:12.111Z,1462912152.111 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2016-05-10T20:29:12.115Z,1462912152.115 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2016-05-10T20:29:12.115Z,1462912152.115 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2016-05-10T20:29:12.115Z,1462912152.115 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2016-05-10T20:29:12.116Z,1462912152.116 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2016-05-10T20:29:12.116Z,1462912152.116 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-05-10T20:29:12.116Z,1462912152.116 [BuoyancyServo](INFO): Powering down
2016-05-10T20:29:12.128Z,1462912152.128 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2016-05-10T20:29:12.128Z,1462912152.128 [ElevatorServo](INFO): Powering down
2016-05-10T20:29:12.148Z,1462912152.148 [MassServo](DEBUG): Uninitialize Mass Servo.
2016-05-10T20:29:12.148Z,1462912152.148 [MassServo](INFO): Powering down
2016-05-10T20:29:12.149Z,1462912152.149 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2016-05-10T20:29:12.149Z,1462912152.149 [RudderServo](INFO): Powering down
2016-05-10T20:29:12.150Z,1462912152.150 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2016-05-10T20:29:12.150Z,1462912152.150 [ThrusterServo](INFO): Powering down
2016-05-10T20:29:12.151Z,1462912152.151 [SBIT](DEBUG): Uninitialize SBIT Component.
2016-05-10T20:29:12.151Z,1462912152.151 [IBIT](DEBUG): Uninitialize IBIT Component.
2016-05-10T20:29:12.152Z,1462912152.152 [CBIT](DEBUG): Uninitialize CBIT Component.
2016-05-10T20:29:12.185Z,1462912152.185 [controlThread ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:12.235Z,1462912152.235 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:12.241Z,1462912152.241 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:12.336Z,1462912152.336 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:12.373Z,1462912152.373 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:12.573Z,1462912152.573 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2016-05-10T20:29:12.627Z,1462912152.627 [logger ThreadHandler](INFO): Thread cancelled.