2017-11-03T00:12:48.111Z,1509667968.111 [Supervisor](DEBUG): Initializing supervisor.
2017-11-03T00:12:48.114Z,1509667968.114 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2017-11-03T00:12:48.115Z,1509667968.115 [SyncHandler](INFO): Protected caller Thread ID is 4962
2017-11-03T00:12:48.115Z,1509667968.115 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-11-03T00:12:48.116Z,1509667968.116 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2017-11-03T00:12:48.116Z,1509667968.116 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4963
2017-11-03T00:12:48.119Z,1509667968.119 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-11-03T00:12:48.129Z,1509667968.129 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-11-03T00:12:48.130Z,1509667968.130 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2017-11-03T00:12:48.131Z,1509667968.131 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4964
2017-11-03T00:12:48.131Z,1509667968.131 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-11-03T00:12:48.132Z,1509667968.132 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2017-11-03T00:12:48.133Z,1509667968.133 [logger ThreadHandler](INFO): Protected caller Thread ID is 4965
2017-11-03T00:12:48.135Z,1509667968.135 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-11-03T00:12:48.135Z,1509667968.135 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-11-03T00:12:48.137Z,1509667968.137 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-11-03T00:12:48.230Z,1509667968.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-11-03T00:12:48.231Z,1509667968.231 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-11-03T00:12:48.328Z,1509667968.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-11-03T00:12:48.328Z,1509667968.328 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-11-03T00:12:48.504Z,1509667968.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-11-03T00:12:48.505Z,1509667968.505 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-11-03T00:12:48.751Z,1509667968.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-11-03T00:12:48.751Z,1509667968.751 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-11-03T00:12:49.055Z,1509667969.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-11-03T00:12:49.056Z,1509667969.056 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-11-03T00:12:49.475Z,1509667969.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-11-03T00:12:49.476Z,1509667969.476 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-11-03T00:12:49.862Z,1509667969.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-11-03T00:12:49.863Z,1509667969.863 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-11-03T00:12:50.254Z,1509667970.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-11-03T00:12:50.254Z,1509667970.254 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-11-03T00:12:50.332Z,1509667970.332 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-11-03T00:12:50.603Z,1509667970.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-11-03T00:12:50.604Z,1509667970.604 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-11-03T00:12:50.728Z,1509667970.728 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-11-03T00:12:50.728Z,1509667970.728 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-11-03T00:12:50.919Z,1509667970.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-11-03T00:12:50.920Z,1509667970.920 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-11-03T00:12:51.106Z,1509667971.106 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-11-03T00:12:51.107Z,1509667971.107 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-11-03T00:12:51.201Z,1509667971.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-11-03T00:12:51.201Z,1509667971.201 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-11-03T00:12:51.334Z,1509667971.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-11-03T00:12:51.335Z,1509667971.335 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-11-03T00:12:51.336Z,1509667971.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-11-03T00:12:51.554Z,1509667971.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-11-03T00:12:51.554Z,1509667971.554 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-11-03T00:12:51.630Z,1509667971.630 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-11-03T00:12:51.764Z,1509667971.764 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-11-03T00:12:51.853Z,1509667971.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-11-03T00:12:51.936Z,1509667971.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-11-03T00:12:52.066Z,1509667972.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-11-03T00:12:52.219Z,1509667972.219 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-11-03T00:12:52.313Z,1509667972.313 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-11-03T00:12:52.403Z,1509667972.403 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-11-03T00:12:52.497Z,1509667972.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-11-03T00:12:52.578Z,1509667972.578 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2017-11-03T00:12:52.578Z,1509667972.578 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-11-03T00:12:52.582Z,1509667972.582 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-11-03T00:12:52.677Z,1509667972.677 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-11-03T00:12:52.678Z,1509667972.678 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-11-03T00:12:52.690Z,1509667972.690 [Module Loader](CRITICAL): Could not find element LcmListener.loadAtStartup
2017-11-03T00:12:52.690Z,1509667972.690 [Module Loader](CRITICAL): Could not find element LcmPublisher.loadAtStartup
2017-11-03T00:12:52.690Z,1509667972.690 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-11-03T00:12:52.691Z,1509667972.691 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-11-03T00:12:52.715Z,1509667972.715 [DepthRateCalculator] Loaded
2017-11-03T00:12:52.715Z,1509667972.715 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-11-03T00:12:52.721Z,1509667972.721 [PitchRateCalculator] Loaded
2017-11-03T00:12:52.721Z,1509667972.721 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-11-03T00:12:52.737Z,1509667972.737 [SpeedCalculator] Loaded
2017-11-03T00:12:52.737Z,1509667972.737 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-11-03T00:12:52.757Z,1509667972.757 [TempGradientCalculator] Loaded
2017-11-03T00:12:52.758Z,1509667972.758 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-11-03T00:12:52.773Z,1509667972.773 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-11-03T00:12:52.773Z,1509667972.773 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-11-03T00:12:52.779Z,1509667972.779 [YawRateCalculator] Loaded
2017-11-03T00:12:52.779Z,1509667972.779 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-11-03T00:12:52.779Z,1509667972.779 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-11-03T00:12:52.780Z,1509667972.780 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-11-03T00:12:52.804Z,1509667972.804 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-11-03T00:12:52.804Z,1509667972.804 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-11-03T00:12:53.042Z,1509667973.042 [AcousticModem_Benthos_ATM900] Loaded
2017-11-03T00:12:53.042Z,1509667973.042 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2017-11-03T00:12:53.114Z,1509667973.114 [DataOverHttps] Loaded
2017-11-03T00:12:53.114Z,1509667973.114 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-11-03T00:12:53.127Z,1509667973.127 [Depth_Keller] Loaded
2017-11-03T00:12:53.127Z,1509667973.127 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-11-03T00:12:53.132Z,1509667973.132 [DropWeight] Loaded
2017-11-03T00:12:53.132Z,1509667973.132 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-11-03T00:12:53.227Z,1509667973.227 [NAL9602] Loaded
2017-11-03T00:12:53.227Z,1509667973.227 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-11-03T00:12:53.242Z,1509667973.242 [Onboard] Loaded
2017-11-03T00:12:53.242Z,1509667973.242 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-11-03T00:12:53.252Z,1509667973.252 [Radio_Surface] Loaded
2017-11-03T00:12:53.253Z,1509667973.253 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-11-03T00:12:53.254Z,1509667973.254 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0
2017-11-03T00:12:53.254Z,1509667973.254 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5045
2017-11-03T00:12:53.513Z,1509667973.513 [PNI_TCM] Loaded
2017-11-03T00:12:53.513Z,1509667973.513 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-11-03T00:12:53.615Z,1509667973.615 [Rowe_600LCM] Loaded
2017-11-03T00:12:53.616Z,1509667973.616 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-11-03T00:12:53.649Z,1509667973.649 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0
2017-11-03T00:12:53.649Z,1509667973.649 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 5046
2017-11-03T00:12:53.650Z,1509667973.650 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-11-03T00:12:53.650Z,1509667973.650 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-11-03T00:12:54.034Z,1509667974.034 [SBIT](DEBUG): Construct Startup Built In Test.
2017-11-03T00:12:54.065Z,1509667974.065 [SBIT] Loaded
2017-11-03T00:12:54.065Z,1509667974.065 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-11-03T00:12:54.066Z,1509667974.066 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-11-03T00:12:54.077Z,1509667974.077 [IBIT] Loaded
2017-11-03T00:12:54.078Z,1509667974.078 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-11-03T00:12:54.081Z,1509667974.081 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-11-03T00:12:54.223Z,1509667974.223 [CBIT] Loaded
2017-11-03T00:12:54.223Z,1509667974.223 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-11-03T00:12:54.224Z,1509667974.224 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-11-03T00:12:54.224Z,1509667974.224 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-11-03T00:12:54.352Z,1509667974.352 [Aanderaa_O2] Loaded
2017-11-03T00:12:54.352Z,1509667974.352 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-11-03T00:12:54.427Z,1509667974.427 [CTD_NeilBrown] Loaded
2017-11-03T00:12:54.428Z,1509667974.428 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-11-03T00:12:54.429Z,1509667974.429 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408944E0
2017-11-03T00:12:54.429Z,1509667974.429 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 5047
2017-11-03T00:12:54.445Z,1509667974.445 [ESPComponent] Loaded
2017-11-03T00:12:54.445Z,1509667974.445 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2017-11-03T00:12:54.459Z,1509667974.459 [PAR_Licor] Loaded
2017-11-03T00:12:54.459Z,1509667974.459 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-11-03T00:12:54.506Z,1509667974.506 [WetLabsBB2FL] Loaded
2017-11-03T00:12:54.507Z,1509667974.507 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-11-03T00:12:54.508Z,1509667974.508 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C44E0
2017-11-03T00:12:54.508Z,1509667974.508 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5048
2017-11-03T00:12:54.509Z,1509667974.509 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-11-03T00:12:54.509Z,1509667974.509 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-11-03T00:12:54.739Z,1509667974.739 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-11-03T00:12:54.739Z,1509667974.739 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-11-03T00:12:54.804Z,1509667974.804 [VerticalControl](DEBUG): Construct VerticalControl.
2017-11-03T00:12:54.898Z,1509667974.898 [VerticalControl] Loaded
2017-11-03T00:12:54.899Z,1509667974.899 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-11-03T00:12:54.899Z,1509667974.899 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-11-03T00:12:54.958Z,1509667974.958 [HorizontalControl] Loaded
2017-11-03T00:12:54.958Z,1509667974.958 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-11-03T00:12:54.959Z,1509667974.959 [SpeedControl](DEBUG): Construct SpeedControl.
2017-11-03T00:12:54.964Z,1509667974.964 [SpeedControl] Loaded
2017-11-03T00:12:54.964Z,1509667974.964 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-11-03T00:12:54.965Z,1509667974.965 [LoopControl](DEBUG): Construct LoopControl.
2017-11-03T00:12:54.965Z,1509667974.965 [LoopControl] Loaded
2017-11-03T00:12:54.966Z,1509667974.966 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-11-03T00:12:54.966Z,1509667974.966 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-11-03T00:12:54.967Z,1509667974.967 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-11-03T00:12:55.046Z,1509667975.046 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-11-03T00:12:55.046Z,1509667975.046 [StratificationFrontDetector](DEBUG): (re)initializing
2017-11-03T00:12:55.046Z,1509667975.046 [StratificationFrontDetector] Loaded
2017-11-03T00:12:55.046Z,1509667975.046 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-11-03T00:12:55.047Z,1509667975.047 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-11-03T00:12:55.047Z,1509667975.047 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-11-03T00:12:55.141Z,1509667975.141 [BuoyancyServo] Loaded
2017-11-03T00:12:55.141Z,1509667975.141 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-11-03T00:12:55.152Z,1509667975.152 [ElevatorServo] Loaded
2017-11-03T00:12:55.152Z,1509667975.152 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-11-03T00:12:55.162Z,1509667975.162 [MassServo] Loaded
2017-11-03T00:12:55.163Z,1509667975.163 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-11-03T00:12:55.173Z,1509667975.173 [RudderServo] Loaded
2017-11-03T00:12:55.174Z,1509667975.174 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-11-03T00:12:55.184Z,1509667975.184 [ThrusterServo] Loaded
2017-11-03T00:12:55.184Z,1509667975.184 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-11-03T00:12:55.185Z,1509667975.185 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-11-03T00:12:55.185Z,1509667975.185 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-11-03T00:12:55.279Z,1509667975.279 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-11-03T00:12:55.279Z,1509667975.279 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-11-03T00:12:55.332Z,1509667975.332 [DeadReckonUsingSpeedCalculator] Loaded
2017-11-03T00:12:55.332Z,1509667975.332 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-11-03T00:12:55.348Z,1509667975.348 [NavChart] Loaded
2017-11-03T00:12:55.348Z,1509667975.348 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-11-03T00:12:55.352Z,1509667975.352 [UniversalFixResidualReporter] Loaded
2017-11-03T00:12:55.352Z,1509667975.352 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-11-03T00:12:55.352Z,1509667975.352 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-11-03T00:12:55.356Z,1509667975.356 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-11-03T00:12:55.357Z,1509667975.357 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-11-03T00:12:55.363Z,1509667975.363 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-11-03T00:12:55.364Z,1509667975.364 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A154E0
2017-11-03T00:12:55.365Z,1509667975.365 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5049
2017-11-03T00:12:55.369Z,1509667975.369 [Supervisor](INFO): Main Thread ID is 4961
2017-11-03T00:12:55.369Z,1509667975.369 [Supervisor](DEBUG): Running supervisor.
2017-11-03T00:12:55.370Z,1509667975.370 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5050
2017-11-03T00:12:55.372Z,1509667975.372 [controlThread ThreadHandler](INFO): Handler Thread ID is 5051
2017-11-03T00:12:55.373Z,1509667975.373 [controlThread](DEBUG): Initializing ControlThread
2017-11-03T00:12:55.374Z,1509667975.374 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-11-03T00:12:55.374Z,1509667975.374 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-11-03T00:12:55.374Z,1509667975.374 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-11-03T00:12:55.375Z,1509667975.375 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-11-03T00:12:55.375Z,1509667975.375 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-11-03T00:12:55.376Z,1509667975.376 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-11-03T00:12:55.383Z,1509667975.383 [SBIT](INFO): Initialize SBIT Component.
2017-11-03T00:12:55.383Z,1509667975.383 [SBIT](IMPORTANT): git: 2017-10-16-91-g1cda494
2017-11-03T00:12:55.383Z,1509667975.383 [SBIT](INFO): git hash: 1cda4942dadce3e3c6eb9510eb040eaf1a2b49d6
2017-11-03T00:12:55.384Z,1509667975.384 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-11-03T00:12:55.385Z,1509667975.385 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #17 PREEMPT Fri Oct 6 17:22:04 PDT 2017
2017-11-03T00:12:55.386Z,1509667975.386 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-11-03T00:12:55.386Z,1509667975.386 [IBIT](INFO): Initialize IBIT Component.
2017-11-03T00:12:55.387Z,1509667975.387 [CBIT](DEBUG): Initialize CBIT Component.
2017-11-03T00:12:55.387Z,1509667975.387 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-11-03T00:12:55.388Z,1509667975.388 [logger ThreadHandler](INFO): Handler Thread ID is 5052
2017-11-03T00:12:55.405Z,1509667975.405 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5053
2017-11-03T00:12:55.411Z,1509667975.411 [Radio_Surface](INFO): Powering up
2017-11-03T00:12:55.417Z,1509667975.417 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 5054
2017-11-03T00:12:55.418Z,1509667975.418 [Rowe_600LCM](INFO): Initializing
2017-11-03T00:12:55.418Z,1509667975.418 [Rowe_600LCM](INFO): Checking LCM
2017-11-03T00:12:55.427Z,1509667975.427 [Rowe_600LCM](INFO): LCM OK
2017-11-03T00:12:55.427Z,1509667975.427 [Rowe_600LCM](INFO): Powering up
2017-11-03T00:12:55.433Z,1509667975.433 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 5055
2017-11-03T00:12:55.434Z,1509667975.434 [CTD_NeilBrown](INFO): Powering down
2017-11-03T00:12:55.453Z,1509667975.453 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5056
2017-11-03T00:12:55.454Z,1509667975.454 [WetLabsBB2FL](INFO): Powering down
2017-11-03T00:12:55.473Z,1509667975.473 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-11-03T00:12:55.475Z,1509667975.475 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-11-03T00:12:55.475Z,1509667975.475 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-11-03T00:12:55.476Z,1509667975.476 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-11-03T00:12:55.478Z,1509667975.478 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-11-03T00:12:55.479Z,1509667975.479 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-11-03T00:12:55.479Z,1509667975.479 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-11-03T00:12:55.479Z,1509667975.479 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-11-03T00:12:55.480Z,1509667975.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-11-03T00:12:55.480Z,1509667975.480 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-11-03T00:12:55.481Z,1509667975.481 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-11-03T00:12:55.481Z,1509667975.481 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-11-03T00:12:55.482Z,1509667975.482 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-11-03T00:12:55.505Z,1509667975.505 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-11-03T00:12:55.511Z,1509667975.511 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5057
2017-11-03T00:12:55.514Z,1509667975.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-11-03T00:12:55.514Z,1509667975.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-11-03T00:12:55.514Z,1509667975.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-11-03T00:12:55.514Z,1509667975.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-11-03T00:12:55.514Z,1509667975.514 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-11-03T00:12:55.515Z,1509667975.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-11-03T00:12:55.515Z,1509667975.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-11-03T00:12:55.515Z,1509667975.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-11-03T00:12:55.515Z,1509667975.515 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-11-03T00:12:55.515Z,1509667975.515 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-11-03T00:12:55.516Z,1509667975.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-11-03T00:12:55.516Z,1509667975.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-11-03T00:12:55.516Z,1509667975.516 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-11-03T00:12:55.516Z,1509667975.516 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-11-03T00:12:55.517Z,1509667975.517 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-11-03T00:12:55.517Z,1509667975.517 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-11-03T00:12:55.544Z,1509667975.544 [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>


2017-11-03T00:12:55.545Z,1509667975.545 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-11-03T00:12:55.638Z,1509667975.638 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-11-03T00:12:55.640Z,1509667975.640 [Default:A.Wait](DEBUG): Construct Wait.
2017-11-03T00:12:55.641Z,1509667975.641 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-11-03T00:12:55.650Z,1509667975.650 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-11-03T00:12:55.669Z,1509667975.669 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-11-03T00:12:55.674Z,1509667975.674 [Default:E.Execute](DEBUG): Construct Execute.
2017-11-03T00:12:55.694Z,1509667975.694 [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>


2017-11-03T00:12:55.714Z,1509667975.714 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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,
2017-11-03T00:12:55.730Z,1509667975.730 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:12:55.730Z,1509667975.730 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:12:55.754Z,1509667975.754 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-11-03T00:12:55.891Z,1509667975.891 [ESPComponent](INFO): powering down ESP
2017-11-03T00:12:56.301Z,1509667976.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:56.329Z,1509667976.329 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:56.429Z,1509667976.429 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-11-03T00:12:56.437Z,1509667976.437 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-11-03T00:12:56.459Z,1509667976.459 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-11-03T00:12:56.465Z,1509667976.465 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-11-03T00:12:56.478Z,1509667976.478 [MassServo](DEBUG): Initializing EZServoServo.
2017-11-03T00:12:56.485Z,1509667976.485 [MassServo](DEBUG): Initializing MassServo.
2017-11-03T00:12:56.498Z,1509667976.498 [RudderServo](DEBUG): Initializing EZServoServo.
2017-11-03T00:12:56.505Z,1509667976.505 [RudderServo](DEBUG): Initializing RudderServo.
2017-11-03T00:12:56.511Z,1509667976.511 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-11-03T00:12:56.517Z,1509667976.517 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-11-03T00:12:56.554Z,1509667976.554 [CommandLine](FAULT): Scheduling is paused
2017-11-03T00:12:56.554Z,1509667976.554 [CBIT](INFO): Critical error at 20171103T001252
2017-11-03T00:12:56.554Z,1509667976.554 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-11-03T00:12:56.727Z,1509667976.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:56.728Z,1509667976.728 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:57.070Z,1509667977.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:57.072Z,1509667977.072 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:57.527Z,1509667977.527 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:57.528Z,1509667977.528 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:57.927Z,1509667977.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:57.937Z,1509667977.937 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:58.357Z,1509667978.357 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:58.358Z,1509667978.358 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:58.749Z,1509667978.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:58.750Z,1509667978.750 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:59.237Z,1509667979.237 [Aanderaa_O2](INFO): Powering down
2017-11-03T00:12:59.298Z,1509667979.298 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:59.299Z,1509667979.299 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-11-03T00:12:59.835Z,1509667979.835 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-11-03T00:12:59.972Z,1509667979.972 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-11-03T00:13:00.026Z,1509667980.026 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-11-03T00:13:00.027Z,1509667980.027 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-11-03T00:13:00.027Z,1509667980.027 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-11-03T00:13:15.880Z,1509667995.880 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:13:15.880Z,1509667995.880 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2017-11-03T00:13:15.880Z,1509667995.880 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:13:16.065Z,1509667996.065 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:13:16.279Z,1509667996.279 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:13:17.194Z,1509667997.194 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-11-03T00:13:17.194Z,1509667997.194 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2017-11-03T00:13:19.480Z,1509667999.480 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:13:19.480Z,1509667999.480 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:13:23.486Z,1509668003.486 [NAL9602](INFO): Powering up NAL9602
2017-11-03T00:13:34.686Z,1509668014.686 [NAL9602](INFO): NAL9602 initialized
2017-11-03T00:13:39.880Z,1509668019.880 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:13:39.880Z,1509668019.880 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2017-11-03T00:13:39.880Z,1509668019.880 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:13:39.999Z,1509668019.999 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:13:40.279Z,1509668020.279 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:13:41.175Z,1509668021.175 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-11-03T00:13:41.175Z,1509668021.175 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2017-11-03T00:13:43.480Z,1509668023.480 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:13:43.480Z,1509668023.480 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:13:59.148Z,1509668039.148 [SBIT](IMPORTANT): Beginning Startup BIT
2017-11-03T00:13:59.184Z,1509668039.184 [CBIT](IMPORTANT): Beginning ground fault scan
2017-11-03T00:14:03.596Z,1509668043.596 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:14:03.596Z,1509668043.596 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2017-11-03T00:14:03.596Z,1509668043.596 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:14:03.797Z,1509668043.797 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:14:03.996Z,1509668043.996 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:14:04.914Z,1509668044.914 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-11-03T00:14:04.914Z,1509668044.914 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2017-11-03T00:14:07.196Z,1509668047.196 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:14:07.196Z,1509668047.196 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:14:10.051Z,1509668050.051 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.008612
CHAN A1 (24V): 0.123316
CHAN A2 (12V): -0.005605
CHAN A3 (5V): -0.002710
CHAN B0 (3.3V): -0.001388
CHAN B1 (3.15aV): -0.000893
CHAN B2 (3.15bV): -0.001400
CHAN B3 (GND): 0.001059
OPEN: 0.005315
Full Scale Calc: 4.765 mA, -1.589 mA
2017-11-03T00:14:27.384Z,1509668067.384 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:14:27.384Z,1509668067.384 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2017-11-03T00:14:27.384Z,1509668067.384 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:14:27.462Z,1509668067.462 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:14:27.784Z,1509668067.784 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:14:28.658Z,1509668068.658 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-11-03T00:14:28.658Z,1509668068.658 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2017-11-03T00:14:30.984Z,1509668070.984 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:14:30.984Z,1509668070.984 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:14:51.100Z,1509668091.100 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:14:51.100Z,1509668091.100 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2017-11-03T00:14:51.100Z,1509668091.100 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:14:51.194Z,1509668091.194 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:14:51.500Z,1509668091.500 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:14:52.361Z,1509668092.361 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-11-03T00:14:52.361Z,1509668092.361 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2017-11-03T00:14:53.157Z,1509668093.157 [SBIT](IMPORTANT): SBIT PASSED
2017-11-03T00:14:53.557Z,1509668093.557 [MissionManager](IMPORTANT): Started mission Startup
2017-11-03T00:14:53.557Z,1509668093.557 [Startup] Running Loop=1
2017-11-03T00:14:53.558Z,1509668093.558 [Startup](DEBUG): Aggregate::initialize Startup
2017-11-03T00:14:53.558Z,1509668093.558 [Startup:A.GoToSurface] Running Loop=1
2017-11-03T00:14:53.558Z,1509668093.558 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-11-03T00:14:53.558Z,1509668093.558 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-11-03T00:14:53.559Z,1509668093.559 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-11-03T00:14:53.559Z,1509668093.559 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-11-03T00:14:53.559Z,1509668093.559 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-11-03T00:14:53.560Z,1509668093.560 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-11-03T00:14:53.565Z,1509668093.565 [Startup:StartupSatComms] Running Loop=1
2017-11-03T00:14:53.566Z,1509668093.566 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-11-03T00:14:53.566Z,1509668093.566 [Startup:StartupSatComms:A] Running Loop=1
2017-11-03T00:14:53.951Z,1509668093.951 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-11-03T00:14:54.700Z,1509668094.700 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:14:54.700Z,1509668094.700 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:14:55.708Z,1509668095.708 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-11-03T00:14:55.708Z,1509668095.708 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-11-03T00:14:55.709Z,1509668095.709 [Rowe_600LCM](ERROR): Communications Fault
2017-11-03T00:14:55.978Z,1509668095.978 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-11-03T00:14:56.113Z,1509668096.113 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-11-03T00:14:56.198Z,1509668096.198 [Rowe_600LCM](INFO): Powering down
2017-11-03T00:14:57.570Z,1509668097.570 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-11-03T00:14:57.570Z,1509668097.570 [Rowe_600LCM] No Fault, FailCount= 1
2017-11-03T00:14:57.833Z,1509668097.833 [Rowe_600LCM](INFO): Initializing
2017-11-03T00:14:57.833Z,1509668097.833 [Rowe_600LCM](INFO): Checking LCM
2017-11-03T00:14:57.833Z,1509668097.833 [Rowe_600LCM](INFO): LCM OK
2017-11-03T00:14:57.833Z,1509668097.833 [Rowe_600LCM](INFO): Powering up
2017-11-03T00:15:02.051Z,1509668102.051 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-11-03T00:15:02.115Z,1509668102.115 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-11-03T00:15:02.116Z,1509668102.116 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-11-03T00:15:02.117Z,1509668102.117 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-11-03T00:15:02.118Z,1509668102.118 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-11-03T00:15:15.100Z,1509668115.100 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:15:15.100Z,1509668115.100 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2017-11-03T00:15:15.100Z,1509668115.100 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:15:15.190Z,1509668115.190 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:15:15.500Z,1509668115.500 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:15:16.391Z,1509668116.391 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-11-03T00:15:16.391Z,1509668116.391 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2017-11-03T00:15:18.700Z,1509668118.700 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:15:18.700Z,1509668118.700 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:15:31.226Z,1509668131.226 [CBIT](FAULT): WATER ALARM STERN.
2017-11-03T00:15:31.227Z,1509668131.227 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 1
2017-11-03T00:15:31.679Z,1509668131.679 [CBIT](FAULT): WATER ALARM STERN.
2017-11-03T00:15:31.680Z,1509668131.680 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 2
2017-11-03T00:15:32.020Z,1509668132.020 [CBIT](FAULT): WATER ALARM STERN.
2017-11-03T00:15:32.029Z,1509668132.029 [CBIT](ERROR): WATER DETECTED IN PRESSURE HULL. Alarm Count: 3
2017-11-03T00:15:32.405Z,1509668132.405 [CBIT](FAULT): WATER ALARM STERN.
2017-11-03T00:15:32.432Z,1509668132.432 [CBIT](CRITICAL): WATER DETECTED IN PRESSURE HULL. BURNWIRE ACTIVATED
2017-11-03T00:15:32.435Z,1509668132.435 [CBIT](IMPORTANT): Powered off ESP with command: ! echo 0 > /dev/loadA6
2017-11-03T00:15:32.517Z,1509668132.517 [CommandLine](IMPORTANT): got command ! echo 0 > /dev/loadA6
2017-11-03T00:15:32.807Z,1509668132.807 [CBIT](INFO): Critical error at 20171103T001532
2017-11-03T00:15:39.100Z,1509668139.100 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:15:39.100Z,1509668139.100 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2017-11-03T00:15:39.100Z,1509668139.100 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:15:39.204Z,1509668139.204 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:15:39.500Z,1509668139.500 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:15:40.387Z,1509668140.387 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2017-11-03T00:15:40.387Z,1509668140.387 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2017-11-03T00:15:42.700Z,1509668142.700 [AcousticModem_Benthos_ATM900](INFO): Powering up
2017-11-03T00:15:42.700Z,1509668142.700 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2017-11-03T00:15:53.957Z,1509668153.957 [Startup:StartupSatComms:A](INFO): Timed out from 2017-11-03T00:14:53.6Z
2017-11-03T00:15:53.957Z,1509668153.957 [Startup:StartupSatComms:A] Stopped
2017-11-03T00:15:53.957Z,1509668153.957 [Startup:StartupSatComms:B] Running Loop=1
2017-11-03T00:15:54.359Z,1509668154.359 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-11-03T00:15:55.557Z,1509668155.557 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-11-03T00:15:55.558Z,1509668155.558 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-11-03T00:15:55.558Z,1509668155.558 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-11-03T00:15:55.559Z,1509668155.559 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-11-03T00:15:55.559Z,1509668155.559 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-11-03T00:15:55.559Z,1509668155.559 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-11-03T00:15:55.572Z,1509668155.572 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-11-03T00:15:55.572Z,1509668155.572 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-11-03T00:15:55.970Z,1509668155.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-11-03T00:15:55.970Z,1509668155.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-11-03T00:15:55.970Z,1509668155.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-11-03T00:15:55.970Z,1509668155.970 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-11-03T00:15:56.352Z,1509668156.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-11-03T00:15:56.353Z,1509668156.353 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-11-03T00:15:56.353Z,1509668156.353 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-11-03T00:15:56.353Z,1509668156.353 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-11-03T00:15:56.354Z,1509668156.354 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-11-03T00:15:56.354Z,1509668156.354 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-11-03T00:16:03.100Z,1509668163.100 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2017-11-03T00:16:03.100Z,1509668163.100 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2017-11-03T00:16:03.100Z,1509668163.100 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2017-11-03T00:16:03.217Z,1509668163.217 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:16:03.217Z,1509668163.217 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2017-11-03T00:16:03.500Z,1509668163.500 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:16:53.962Z,1509668213.962 [Startup:StartupSatComms:B](INFO): Timed out from 2017-11-03T00:15:53.0Z
2017-11-03T00:16:53.962Z,1509668213.962 [Startup:StartupSatComms:B] Stopped
2017-11-03T00:16:53.962Z,1509668213.962 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-11-03T00:16:53.962Z,1509668213.963 [Startup:StartupSatComms] Stopped
2017-11-03T00:16:53.963Z,1509668213.963 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-11-03T00:16:53.964Z,1509668213.964 [Startup](INFO): Completed Startup
2017-11-03T00:16:53.969Z,1509668213.969 [MissionManager](INFO): Startup is completed.
2017-11-03T00:16:53.969Z,1509668213.969 [MissionManager](INFO): Uninitializing Mission Startup
2017-11-03T00:16:53.969Z,1509668213.969 [Startup] Stopped
2017-11-03T00:16:53.970Z,1509668213.970 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-11-03T00:16:53.970Z,1509668213.970 [Startup:A.GoToSurface] Stopped
2017-11-03T00:16:53.970Z,1509668213.970 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-11-03T00:16:54.367Z,1509668214.367 [MissionManager](IMPORTANT): Started mission Default
2017-11-03T00:16:54.367Z,1509668214.367 [Default] Running Loop=1
2017-11-03T00:16:54.367Z,1509668214.367 [Default](DEBUG): Aggregate::initialize Default
2017-11-03T00:16:54.367Z,1509668214.367 [Default:B.GoToSurface] Running Loop=1
2017-11-03T00:16:54.367Z,1509668214.367 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-11-03T00:16:54.367Z,1509668214.367 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-11-03T00:16:54.368Z,1509668214.368 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-11-03T00:16:54.368Z,1509668214.368 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-11-03T00:16:54.369Z,1509668214.369 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-11-03T00:16:54.369Z,1509668214.369 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-11-03T00:16:54.369Z,1509668214.369 [Default:A.Wait] Running Loop=1
2017-11-03T00:16:54.369Z,1509668214.369 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-11-03T00:16:58.528Z,1509668218.528 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-11-03T00:16:58.528Z,1509668218.528 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-11-03T00:16:58.529Z,1509668218.529 [Rowe_600LCM](ERROR): Communications Fault
2017-11-03T00:16:58.762Z,1509668218.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-11-03T00:16:58.782Z,1509668218.782 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-11-03T00:16:58.933Z,1509668218.933 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-11-03T00:16:58.963Z,1509668218.963 [CommandLine](IMPORTANT): got command restart application
2017-11-03T00:16:59.041Z,1509668219.041 [Rowe_600LCM](INFO): Powering down
2017-11-03T00:16:59.146Z,1509668219.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-11-03T00:16:59.570Z,1509668219.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-11-03T00:16:59.951Z,1509668219.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-11-03T00:16:59.972Z,1509668219.972 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:16:59.972Z,1509668219.972 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.024Z,1509668220.024 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-11-03T00:17:00.025Z,1509668220.025 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.025Z,1509668220.025 [CommandLine](INFO): Join timeout helper Thread ID is 5096
2017-11-03T00:17:00.026Z,1509668220.026 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-11-03T00:17:00.026Z,1509668220.026 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.026Z,1509668220.026 [NavChartDb](INFO): Join timeout helper Thread ID is 5097
2017-11-03T00:17:00.081Z,1509668220.081 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:17:00.081Z,1509668220.081 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.084Z,1509668220.084 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-11-03T00:17:00.085Z,1509668220.085 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.085Z,1509668220.085 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5098
2017-11-03T00:17:00.372Z,1509668220.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-11-03T00:17:00.385Z,1509668220.385 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-11-03T00:17:00.385Z,1509668220.385 [Rowe_600LCM] No Fault, FailCount= 2
2017-11-03T00:17:00.477Z,1509668220.477 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:17:00.477Z,1509668220.477 [WetLabsBB2FL](INFO): Powering down
2017-11-03T00:17:00.478Z,1509668220.478 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.488Z,1509668220.489 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-11-03T00:17:00.489Z,1509668220.489 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.489Z,1509668220.489 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 5099
2017-11-03T00:17:00.657Z,1509668220.657 [Rowe_600LCM](INFO): Initializing
2017-11-03T00:17:00.657Z,1509668220.657 [Rowe_600LCM](INFO): Checking LCM
2017-11-03T00:17:00.657Z,1509668220.657 [Rowe_600LCM](INFO): LCM OK
2017-11-03T00:17:00.657Z,1509668220.657 [Rowe_600LCM](INFO): Powering up
2017-11-03T00:17:00.768Z,1509668220.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-11-03T00:17:00.889Z,1509668220.889 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:17:00.889Z,1509668220.889 [CTD_NeilBrown](INFO): Powering down
2017-11-03T00:17:00.890Z,1509668220.890 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.907Z,1509668220.907 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-11-03T00:17:00.907Z,1509668220.907 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:00.908Z,1509668220.908 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 5100
2017-11-03T00:17:01.061Z,1509668221.061 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:17:01.061Z,1509668221.061 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-11-03T00:17:01.195Z,1509668221.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-11-03T00:17:01.230Z,1509668221.230 [Rowe_600LCM](INFO): Powering down
2017-11-03T00:17:01.231Z,1509668221.231 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.234Z,1509668221.234 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-11-03T00:17:01.234Z,1509668221.234 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.234Z,1509668221.234 [Radio_Surface](INFO): Join timeout helper Thread ID is 5102
2017-11-03T00:17:01.245Z,1509668221.245 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:17:01.245Z,1509668221.245 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.253Z,1509668221.253 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-11-03T00:17:01.253Z,1509668221.253 [logger ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.254Z,1509668221.254 [logger](INFO): Join timeout helper Thread ID is 5103
2017-11-03T00:17:01.254Z,1509668221.254 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:17:01.254Z,1509668221.254 [logger ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.256Z,1509668221.256 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-11-03T00:17:01.256Z,1509668221.256 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.256Z,1509668221.256 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-11-03T00:17:01.256Z,1509668221.256 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.257Z,1509668221.257 [controlThread](INFO): Join timeout helper Thread ID is 5104
2017-11-03T00:17:01.497Z,1509668221.497 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-11-03T00:17:01.497Z,1509668221.497 [controlThread](DEBUG): Uninitializing ControlThread
2017-11-03T00:17:01.497Z,1509668221.497 [AcousticModem_Benthos_ATM900](INFO): Powering down
2017-11-03T00:17:01.499Z,1509668221.499 [NAL9602](INFO): Powering down
2017-11-03T00:17:01.500Z,1509668221.500 [PNI_TCM](INFO): Powering down
2017-11-03T00:17:01.589Z,1509668221.589 [Aanderaa_O2](INFO): Powering down
2017-11-03T00:17:01.591Z,1509668221.591 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-11-03T00:17:01.593Z,1509668221.593 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-11-03T00:17:01.594Z,1509668221.594 [MissionManager](INFO): Uninitializing Mission Default
2017-11-03T00:17:01.594Z,1509668221.594 [Default] Stopped
2017-11-03T00:17:01.594Z,1509668221.594 [Default](DEBUG): Aggregate::uninitialize Default
2017-11-03T00:17:01.594Z,1509668221.594 [Default:A.Wait] Stopped
2017-11-03T00:17:01.594Z,1509668221.594 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-11-03T00:17:01.594Z,1509668221.594 [Default:B.GoToSurface] Stopped
2017-11-03T00:17:01.594Z,1509668221.594 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-11-03T00:17:01.597Z,1509668221.597 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-11-03T00:17:01.598Z,1509668221.598 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-11-03T00:17:01.598Z,1509668221.598 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-11-03T00:17:01.598Z,1509668221.598 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-11-03T00:17:01.598Z,1509668221.598 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-11-03T00:17:01.599Z,1509668221.599 [BuoyancyServo](INFO): Powering down
2017-11-03T00:17:01.613Z,1509668221.613 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-11-03T00:17:01.613Z,1509668221.613 [ElevatorServo](INFO): Powering down
2017-11-03T00:17:01.614Z,1509668221.614 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-11-03T00:17:01.614Z,1509668221.614 [MassServo](INFO): Powering down
2017-11-03T00:17:01.614Z,1509668221.614 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-11-03T00:17:01.615Z,1509668221.615 [RudderServo](INFO): Powering down
2017-11-03T00:17:01.615Z,1509668221.615 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-11-03T00:17:01.615Z,1509668221.615 [ThrusterServo](INFO): Powering down
2017-11-03T00:17:01.617Z,1509668221.617 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-11-03T00:17:01.617Z,1509668221.617 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-11-03T00:17:01.617Z,1509668221.617 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-11-03T00:17:01.618Z,1509668221.618 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.664Z,1509668221.664 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.670Z,1509668221.670 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.704Z,1509668221.704 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.708Z,1509668221.708 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.753Z,1509668221.753 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-11-03T00:17:01.802Z,1509668221.802 [logger ThreadHandler](INFO): Thread cancelled.