2017-04-07T20:11:17.406Z,1491595877.406 [Supervisor](DEBUG): Initializing supervisor.
2017-04-07T20:11:17.409Z,1491595877.409 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-04-07T20:11:17.410Z,1491595877.410 [SyncHandler](INFO): Protected caller Thread ID is 319
2017-04-07T20:11:17.410Z,1491595877.410 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-04-07T20:11:17.411Z,1491595877.411 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-04-07T20:11:17.411Z,1491595877.411 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 320
2017-04-07T20:11:17.414Z,1491595877.414 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-04-07T20:11:17.426Z,1491595877.426 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-04-07T20:11:17.428Z,1491595877.428 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-04-07T20:11:17.428Z,1491595877.428 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 321
2017-04-07T20:11:17.429Z,1491595877.429 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-04-07T20:11:17.430Z,1491595877.430 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-04-07T20:11:17.430Z,1491595877.430 [logger ThreadHandler](INFO): Protected caller Thread ID is 322
2017-04-07T20:11:17.432Z,1491595877.432 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-04-07T20:11:17.433Z,1491595877.433 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-04-07T20:11:17.434Z,1491595877.434 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-04-07T20:11:17.517Z,1491595877.517 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-04-07T20:11:17.841Z,1491595877.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-04-07T20:11:17.842Z,1491595877.842 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-04-07T20:11:17.974Z,1491595877.974 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-04-07T20:11:17.975Z,1491595877.975 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-04-07T20:11:18.174Z,1491595878.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-04-07T20:11:18.174Z,1491595878.174 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-04-07T20:11:18.274Z,1491595878.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-04-07T20:11:18.275Z,1491595878.275 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-04-07T20:11:18.470Z,1491595878.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-04-07T20:11:18.470Z,1491595878.470 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-04-07T20:11:18.938Z,1491595878.938 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-04-07T20:11:18.939Z,1491595878.939 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-04-07T20:11:19.225Z,1491595879.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-04-07T20:11:19.226Z,1491595879.226 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-04-07T20:11:19.312Z,1491595879.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-04-07T20:11:19.312Z,1491595879.312 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-04-07T20:11:19.454Z,1491595879.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-04-07T20:11:19.455Z,1491595879.455 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-04-07T20:11:19.650Z,1491595879.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-04-07T20:11:19.651Z,1491595879.651 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-04-07T20:11:20.214Z,1491595880.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-04-07T20:11:20.215Z,1491595880.215 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-04-07T20:11:20.578Z,1491595880.579 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-04-07T20:11:20.579Z,1491595880.579 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-04-07T20:11:20.882Z,1491595880.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-04-07T20:11:20.882Z,1491595880.882 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-04-07T20:11:20.992Z,1491595880.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-04-07T20:11:20.994Z,1491595880.994 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-04-07T20:11:20.996Z,1491595880.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-04-07T20:11:21.097Z,1491595881.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-04-07T20:11:21.189Z,1491595881.189 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-04-07T20:11:21.275Z,1491595881.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-04-07T20:11:21.378Z,1491595881.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-04-07T20:11:21.466Z,1491595881.466 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-04-07T20:11:21.591Z,1491595881.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-04-07T20:11:21.685Z,1491595881.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-04-07T20:11:21.860Z,1491595881.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-04-07T20:11:22.097Z,1491595882.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-04-07T20:11:22.097Z,1491595882.097 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-04-07T20:11:22.240Z,1491595882.240 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-04-07T20:11:22.338Z,1491595882.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-04-07T20:11:22.418Z,1491595882.418 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2017-04-07T20:11:22.418Z,1491595882.418 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-04-07T20:11:22.420Z,1491595882.420 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-04-07T20:11:22.447Z,1491595882.447 [DepthRateCalculator] Loaded
2017-04-07T20:11:22.447Z,1491595882.447 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-04-07T20:11:22.454Z,1491595882.454 [PitchRateCalculator] Loaded
2017-04-07T20:11:22.454Z,1491595882.454 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-04-07T20:11:22.465Z,1491595882.466 [SpeedCalculator] Loaded
2017-04-07T20:11:22.466Z,1491595882.466 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-04-07T20:11:22.488Z,1491595882.489 [TempGradientCalculator] Loaded
2017-04-07T20:11:22.489Z,1491595882.489 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-04-07T20:11:22.505Z,1491595882.505 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-04-07T20:11:22.505Z,1491595882.505 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-04-07T20:11:22.511Z,1491595882.511 [YawRateCalculator] Loaded
2017-04-07T20:11:22.512Z,1491595882.511 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-04-07T20:11:22.512Z,1491595882.512 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-04-07T20:11:22.512Z,1491595882.512 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-04-07T20:11:22.614Z,1491595882.614 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-04-07T20:11:22.614Z,1491595882.614 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-04-07T20:11:22.669Z,1491595882.670 [DeadReckonUsingSpeedCalculator] Loaded
2017-04-07T20:11:22.670Z,1491595882.670 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-04-07T20:11:22.687Z,1491595882.687 [NavChart] Loaded
2017-04-07T20:11:22.687Z,1491595882.687 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-04-07T20:11:22.691Z,1491595882.691 [UniversalFixResidualReporter] Loaded
2017-04-07T20:11:22.691Z,1491595882.691 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-04-07T20:11:22.692Z,1491595882.692 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-04-07T20:11:22.692Z,1491595882.692 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-04-07T20:11:22.839Z,1491595882.839 [Aanderaa_O2] Loaded
2017-04-07T20:11:22.839Z,1491595882.839 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-04-07T20:11:22.919Z,1491595882.920 [CTD_NeilBrown] Loaded
2017-04-07T20:11:22.920Z,1491595882.920 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-04-07T20:11:22.921Z,1491595882.921 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4066F4E0
2017-04-07T20:11:22.921Z,1491595882.921 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 408
2017-04-07T20:11:22.934Z,1491595882.934 [ISUS] Loaded
2017-04-07T20:11:22.934Z,1491595882.934 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2017-04-07T20:11:22.949Z,1491595882.949 [PAR_Licor] Loaded
2017-04-07T20:11:22.950Z,1491595882.950 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-04-07T20:11:23.001Z,1491595883.001 [WetLabsBB2FL] Loaded
2017-04-07T20:11:23.001Z,1491595883.001 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-04-07T20:11:23.002Z,1491595883.002 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069F4E0
2017-04-07T20:11:23.002Z,1491595883.002 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 409
2017-04-07T20:11:23.003Z,1491595883.003 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-04-07T20:11:23.003Z,1491595883.003 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-04-07T20:11:23.028Z,1491595883.028 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-04-07T20:11:23.029Z,1491595883.029 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-04-07T20:11:23.035Z,1491595883.035 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-04-07T20:11:23.036Z,1491595883.036 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-04-07T20:11:23.276Z,1491595883.276 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-04-07T20:11:23.276Z,1491595883.276 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-04-07T20:11:23.406Z,1491595883.406 [SBIT](DEBUG): Construct Startup Built In Test.
2017-04-07T20:11:23.438Z,1491595883.438 [SBIT] Loaded
2017-04-07T20:11:23.438Z,1491595883.438 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-04-07T20:11:23.439Z,1491595883.439 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-04-07T20:11:23.467Z,1491595883.467 [IBIT] Loaded
2017-04-07T20:11:23.467Z,1491595883.467 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-04-07T20:11:23.471Z,1491595883.471 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-04-07T20:11:23.627Z,1491595883.626 [CBIT] Loaded
2017-04-07T20:11:23.627Z,1491595883.627 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-04-07T20:11:23.627Z,1491595883.627 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-04-07T20:11:23.628Z,1491595883.628 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-04-07T20:11:23.896Z,1491595883.896 [DataOverHttps] Loaded
2017-04-07T20:11:23.896Z,1491595883.896 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-04-07T20:11:23.910Z,1491595883.910 [Depth_Keller] Loaded
2017-04-07T20:11:23.910Z,1491595883.910 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-04-07T20:11:23.915Z,1491595883.915 [DropWeight] Loaded
2017-04-07T20:11:23.916Z,1491595883.916 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-04-07T20:11:24.010Z,1491595884.010 [NAL9602] Loaded
2017-04-07T20:11:24.010Z,1491595884.010 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-04-07T20:11:24.020Z,1491595884.020 [Onboard] Loaded
2017-04-07T20:11:24.020Z,1491595884.020 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-04-07T20:11:24.028Z,1491595884.028 [Radio_Surface] Loaded
2017-04-07T20:11:24.029Z,1491595884.029 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-04-07T20:11:24.030Z,1491595884.030 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409904E0
2017-04-07T20:11:24.030Z,1491595884.030 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 410
2017-04-07T20:11:24.167Z,1491595884.167 [DAT] Loaded
2017-04-07T20:11:24.168Z,1491595884.168 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2017-04-07T20:11:24.240Z,1491595884.240 [Rowe_600LCM] Loaded
2017-04-07T20:11:24.240Z,1491595884.240 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-04-07T20:11:24.241Z,1491595884.241 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409C04E0
2017-04-07T20:11:24.242Z,1491595884.242 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 411
2017-04-07T20:11:26.138Z,1491595886.138 [BPC1] Loaded
2017-04-07T20:11:26.138Z,1491595886.138 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-04-07T20:11:26.139Z,1491595886.139 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-04-07T20:11:26.139Z,1491595886.139 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-04-07T20:11:26.243Z,1491595886.243 [BuoyancyServo] Loaded
2017-04-07T20:11:26.243Z,1491595886.243 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-04-07T20:11:26.259Z,1491595886.259 [ElevatorServo] Loaded
2017-04-07T20:11:26.259Z,1491595886.260 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-04-07T20:11:26.275Z,1491595886.275 [MassServo] Loaded
2017-04-07T20:11:26.275Z,1491595886.275 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-04-07T20:11:26.291Z,1491595886.291 [RudderServo] Loaded
2017-04-07T20:11:26.292Z,1491595886.292 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-04-07T20:11:26.307Z,1491595886.307 [ThrusterServo] Loaded
2017-04-07T20:11:26.307Z,1491595886.307 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-04-07T20:11:26.308Z,1491595886.308 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-04-07T20:11:26.308Z,1491595886.308 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-04-07T20:11:26.359Z,1491595886.359 [VerticalControl](DEBUG): Construct VerticalControl.
2017-04-07T20:11:26.452Z,1491595886.452 [VerticalControl] Loaded
2017-04-07T20:11:26.452Z,1491595886.452 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-04-07T20:11:26.453Z,1491595886.453 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-04-07T20:11:26.514Z,1491595886.514 [HorizontalControl] Loaded
2017-04-07T20:11:26.514Z,1491595886.514 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-04-07T20:11:26.515Z,1491595886.515 [SpeedControl](DEBUG): Construct SpeedControl.
2017-04-07T20:11:26.517Z,1491595886.517 [SpeedControl] Loaded
2017-04-07T20:11:26.517Z,1491595886.517 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-04-07T20:11:26.518Z,1491595886.518 [LoopControl](DEBUG): Construct LoopControl.
2017-04-07T20:11:26.518Z,1491595886.518 [LoopControl] Loaded
2017-04-07T20:11:26.518Z,1491595886.519 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-04-07T20:11:26.519Z,1491595886.519 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-04-07T20:11:26.520Z,1491595886.520 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-04-07T20:11:26.601Z,1491595886.601 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-04-07T20:11:26.602Z,1491595886.602 [StratificationFrontDetector](DEBUG): (re)initializing
2017-04-07T20:11:26.602Z,1491595886.602 [StratificationFrontDetector] Loaded
2017-04-07T20:11:26.602Z,1491595886.602 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-04-07T20:11:26.627Z,1491595886.627 [DepAvgTempFrontDetector] Loaded
2017-04-07T20:11:26.628Z,1491595886.628 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread.
2017-04-07T20:11:26.628Z,1491595886.628 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-04-07T20:11:26.629Z,1491595886.629 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-04-07T20:11:26.730Z,1491595886.730 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-04-07T20:11:26.734Z,1491595886.734 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-04-07T20:11:26.735Z,1491595886.735 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-04-07T20:11:26.742Z,1491595886.742 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-04-07T20:11:26.743Z,1491595886.743 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0
2017-04-07T20:11:26.743Z,1491595886.743 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 412
2017-04-07T20:11:26.748Z,1491595886.748 [Supervisor](INFO): Main Thread ID is 318
2017-04-07T20:11:26.748Z,1491595886.749 [Supervisor](DEBUG): Running supervisor.
2017-04-07T20:11:26.749Z,1491595886.749 [CommandLine ThreadHandler](INFO): Handler Thread ID is 413
2017-04-07T20:11:26.752Z,1491595886.752 [controlThread ThreadHandler](INFO): Handler Thread ID is 414
2017-04-07T20:11:26.753Z,1491595886.753 [controlThread](DEBUG): Initializing ControlThread
2017-04-07T20:11:26.754Z,1491595886.754 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-04-07T20:11:26.754Z,1491595886.754 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-04-07T20:11:26.754Z,1491595886.754 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-04-07T20:11:26.755Z,1491595886.755 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-04-07T20:11:26.755Z,1491595886.755 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-04-07T20:11:26.756Z,1491595886.756 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-04-07T20:11:26.757Z,1491595886.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-04-07T20:11:26.757Z,1491595886.757 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-04-07T20:11:26.758Z,1491595886.758 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-04-07T20:11:26.758Z,1491595886.758 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-04-07T20:11:26.759Z,1491595886.759 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-04-07T20:11:26.759Z,1491595886.759 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-04-07T20:11:26.760Z,1491595886.760 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-04-07T20:11:26.760Z,1491595886.760 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-04-07T20:11:26.763Z,1491595886.763 [SBIT](INFO): Initialize SBIT Component.
2017-04-07T20:11:26.764Z,1491595886.764 [SBIT](IMPORTANT): git: 2017-02-07-42-g770a181
2017-04-07T20:11:26.764Z,1491595886.764 [SBIT](INFO): git hash: 770a181585674743d86f29932ec8234b5d7ae45c
2017-04-07T20:11:26.766Z,1491595886.766 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-04-07T20:11:26.766Z,1491595886.766 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-04-07T20:11:26.767Z,1491595886.767 [SBIT](INFO): Beginning SBIT in 32.000000 seconds.
2017-04-07T20:11:26.768Z,1491595886.768 [IBIT](INFO): Initialize IBIT Component.
2017-04-07T20:11:26.768Z,1491595886.768 [CBIT](DEBUG): Initialize CBIT Component.
2017-04-07T20:11:26.769Z,1491595886.769 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-04-07T20:11:26.770Z,1491595886.770 [logger ThreadHandler](INFO): Handler Thread ID is 415
2017-04-07T20:11:26.788Z,1491595886.788 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 416
2017-04-07T20:11:26.789Z,1491595886.789 [CTD_NeilBrown](INFO): Powering down
2017-04-07T20:11:26.816Z,1491595886.816 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 417
2017-04-07T20:11:26.817Z,1491595886.817 [WetLabsBB2FL](INFO): Powering down
2017-04-07T20:11:26.860Z,1491595886.860 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-04-07T20:11:26.861Z,1491595886.861 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-04-07T20:11:26.862Z,1491595886.862 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-04-07T20:11:26.863Z,1491595886.863 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-04-07T20:11:26.864Z,1491595886.864 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-04-07T20:11:26.872Z,1491595886.872 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 418
2017-04-07T20:11:26.878Z,1491595886.878 [Radio_Surface](INFO): Powering up
2017-04-07T20:11:26.883Z,1491595886.883 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-04-07T20:11:26.892Z,1491595886.892 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 419
2017-04-07T20:11:26.892Z,1491595886.892 [Rowe_600LCM](INFO): Initializing
2017-04-07T20:11:26.894Z,1491595886.894 [Rowe_600LCM](INFO): Checking LCM
2017-04-07T20:11:26.894Z,1491595886.894 [Rowe_600LCM](INFO): LCM OK
2017-04-07T20:11:26.894Z,1491595886.894 [Rowe_600LCM](INFO): Powering up
2017-04-07T20:11:26.906Z,1491595886.906 [MissionManager](DEBUG):
2017-04-07T20:11:26.906Z,1491595886.907 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-04-07T20:11:26.908Z,1491595886.908 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 420
2017-04-07T20:11:26.910Z,1491595886.910 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2017-04-07T20:11:26.918Z,1491595886.918 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-04-07T20:11:26.920Z,1491595886.920 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2017-04-07T20:11:26.920Z,1491595886.920 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-04-07T20:11:26.922Z,1491595886.922 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2017-04-07T20:11:26.922Z,1491595886.922 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-04-07T20:11:26.924Z,1491595886.924 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2017-04-07T20:11:26.924Z,1491595886.924 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-04-07T20:11:26.925Z,1491595886.925 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4CA60M.000
2017-04-07T20:11:26.926Z,1491595886.926 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-04-07T20:11:26.927Z,1491595886.927 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2017-04-07T20:11:26.928Z,1491595886.928 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-04-07T20:11:26.929Z,1491595886.929 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA61M.000
2017-04-07T20:11:26.929Z,1491595886.930 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-04-07T20:11:26.931Z,1491595886.931 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA62M.000
2017-04-07T20:11:26.931Z,1491595886.931 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-04-07T20:11:26.933Z,1491595886.933 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA83M.000
2017-04-07T20:11:26.980Z,1491595886.980 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-04-07T20:11:26.982Z,1491595886.982 [Default:A.Wait](DEBUG): Construct Wait.
2017-04-07T20:11:26.983Z,1491595886.983 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-04-07T20:11:27.006Z,1491595887.006 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-04-07T20:11:27.008Z,1491595887.008 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-04-07T20:11:27.014Z,1491595887.014 [Default:E.Execute](DEBUG): Construct Execute.
2017-04-07T20:11:27.020Z,1491595887.020 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5CA83M.000
2017-04-07T20:11:27.061Z,1491595887.061 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2017-04-07T20:11:27.067Z,1491595887.067 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ISUS,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-04-07T20:11:27.295Z,1491595887.295 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-04-07T20:11:27.314Z,1491595887.314 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-04-07T20:11:27.412Z,1491595887.411 [DAT](INFO): Powering up
2017-04-07T20:11:27.412Z,1491595887.412 [DAT](DEBUG): Initializing DAT.
2017-04-07T20:11:27.572Z,1491595887.572 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-04-07T20:11:27.656Z,1491595887.656 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:27.674Z,1491595887.674 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:27.795Z,1491595887.796 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:27.803Z,1491595887.803 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-04-07T20:11:27.810Z,1491595887.810 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:27.815Z,1491595887.815 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-04-07T20:11:27.833Z,1491595887.833 [MassServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:27.840Z,1491595887.839 [MassServo](DEBUG): Initializing MassServo.
2017-04-07T20:11:27.850Z,1491595887.850 [RudderServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:27.856Z,1491595887.856 [RudderServo](DEBUG): Initializing RudderServo.
2017-04-07T20:11:27.872Z,1491595887.872 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:27.879Z,1491595887.879 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-04-07T20:11:28.178Z,1491595888.178 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2017-04-07T20:11:28.178Z,1491595888.178 [DropWeight] Hardware Fault, FailCount= 1
2017-04-07T20:11:28.178Z,1491595888.178 [DropWeight](ERROR): Hardware Fault
2017-04-07T20:11:28.251Z,1491595888.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:28.255Z,1491595888.255 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:28.275Z,1491595888.275 [CommandLine](FAULT): Scheduling is paused
2017-04-07T20:11:28.276Z,1491595888.276 [CBIT](INFO): Critical error at 20170407T201128
2017-04-07T20:11:28.276Z,1491595888.276 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-04-07T20:11:28.278Z,1491595888.278 [CBIT](ERROR): Hardware Fault in component: DropWeight
2017-04-07T20:11:28.279Z,1491595888.279 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2017-04-07T20:11:31.210Z,1491595891.210 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-04-07T20:11:31.350Z,1491595891.350 [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-04-07T20:11:31.445Z,1491595891.445 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-04-07T20:11:31.463Z,1491595891.463 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-04-07T20:11:31.477Z,1491595891.477 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-04-07T20:11:36.909Z,1491595896.909 [NAL9602](INFO): Powering up NAL9602
2017-04-07T20:11:36.973Z,1491595896.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:36.974Z,1491595896.974 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:37.269Z,1491595897.269 [CBIT](INFO): Critical error at 20170407T201128
2017-04-07T20:11:37.277Z,1491595897.277 [Aanderaa_O2](FAULT): Timed out starting
2017-04-07T20:11:37.277Z,1491595897.277 [Aanderaa_O2] Communications Fault, FailCount= 1
2017-04-07T20:11:37.277Z,1491595897.277 [Aanderaa_O2](ERROR): Communications Fault
2017-04-07T20:11:37.352Z,1491595897.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:37.353Z,1491595897.353 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:37.381Z,1491595897.381 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2017-04-07T20:11:37.679Z,1491595897.679 [Aanderaa_O2](INFO): Powering down
2017-04-07T20:11:37.758Z,1491595897.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:37.759Z,1491595897.759 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:38.089Z,1491595898.089 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003800
2017-04-07T20:11:38.147Z,1491595898.147 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:38.148Z,1491595898.148 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:38.515Z,1491595898.515 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:38.516Z,1491595898.516 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:38.644Z,1491595898.644 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-04-07T20:11:38.644Z,1491595898.644 [Aanderaa_O2] No Fault, FailCount= 1
2017-04-07T20:11:38.931Z,1491595898.931 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:38.932Z,1491595898.932 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:39.335Z,1491595899.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:39.336Z,1491595899.336 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:39.739Z,1491595899.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:39.740Z,1491595899.740 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:40.136Z,1491595900.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:40.137Z,1491595900.137 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:40.188Z,1491595900.188 [MassServo](FAULT): Overload Error
2017-04-07T20:11:40.189Z,1491595900.188 [MassServo] Hardware Fault, FailCount= 1
2017-04-07T20:11:40.189Z,1491595900.188 [MassServo](ERROR): Hardware Fault
2017-04-07T20:11:40.270Z,1491595900.270 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-04-07T20:11:40.536Z,1491595900.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:40.537Z,1491595900.537 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:40.561Z,1491595900.561 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-04-07T20:11:40.562Z,1491595900.562 [MassServo](INFO): Powering down
2017-04-07T20:11:40.928Z,1491595900.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:40.929Z,1491595900.929 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:41.349Z,1491595901.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:41.350Z,1491595901.350 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:41.377Z,1491595901.377 [MassServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:41.378Z,1491595901.378 [MassServo](DEBUG): Initializing MassServo.
2017-04-07T20:11:41.416Z,1491595901.416 [CBIT](INFO): Clearing failed state for component MassServo
2017-04-07T20:11:41.416Z,1491595901.416 [MassServo] No Fault, FailCount= 1
2017-04-07T20:11:41.716Z,1491595901.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:41.717Z,1491595901.717 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:42.140Z,1491595902.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:42.141Z,1491595902.141 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:42.544Z,1491595902.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:42.545Z,1491595902.545 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:42.905Z,1491595902.905 [BPC1](FAULT): Failed to initialize
2017-04-07T20:11:42.905Z,1491595902.905 [BPC1] Communications Fault, FailCount= 1
2017-04-07T20:11:42.906Z,1491595902.906 [BPC1](ERROR): Communications Fault
2017-04-07T20:11:42.956Z,1491595902.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:42.957Z,1491595902.957 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:42.983Z,1491595902.983 [CBIT](ERROR): Communications Fault in component: BPC1
2017-04-07T20:11:43.258Z,1491595903.258 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5CA83M.000
2017-04-07T20:11:43.336Z,1491595903.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:43.337Z,1491595903.337 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:43.756Z,1491595903.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:43.757Z,1491595903.757 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:44.124Z,1491595904.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:44.125Z,1491595904.125 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:44.161Z,1491595904.161 [CBIT](INFO): Clearing failed state for component BPC1
2017-04-07T20:11:44.161Z,1491595904.161 [BPC1] No Fault, FailCount= 1
2017-04-07T20:11:44.565Z,1491595904.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:44.566Z,1491595904.566 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:44.920Z,1491595904.920 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:44.921Z,1491595904.921 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:45.354Z,1491595905.354 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:45.371Z,1491595905.371 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:45.424Z,1491595905.424 [MassServo](FAULT): Overload Error
2017-04-07T20:11:45.424Z,1491595905.424 [MassServo] Hardware Fault, FailCount= 2
2017-04-07T20:11:45.424Z,1491595905.424 [MassServo](ERROR): Hardware Fault
2017-04-07T20:11:45.441Z,1491595905.441 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-04-07T20:11:45.716Z,1491595905.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:45.717Z,1491595905.717 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:45.726Z,1491595905.726 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-04-07T20:11:45.726Z,1491595905.726 [MassServo](INFO): Powering down
2017-04-07T20:11:46.157Z,1491595906.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:46.158Z,1491595906.158 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:46.525Z,1491595906.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:46.526Z,1491595906.526 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:46.549Z,1491595906.549 [MassServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:46.549Z,1491595906.549 [MassServo](DEBUG): Initializing MassServo.
2017-04-07T20:11:46.559Z,1491595906.559 [CBIT](INFO): Clearing failed state for component MassServo
2017-04-07T20:11:46.559Z,1491595906.559 [MassServo] No Fault, FailCount= 2
2017-04-07T20:11:46.943Z,1491595906.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:46.945Z,1491595906.944 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:47.336Z,1491595907.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:47.336Z,1491595907.336 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:47.740Z,1491595907.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:47.741Z,1491595907.741 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:48.089Z,1491595908.089 [NAL9602](INFO): NAL9602 initialized
2017-04-07T20:11:48.125Z,1491595908.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:48.147Z,1491595908.147 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:48.546Z,1491595908.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:48.552Z,1491595908.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:48.936Z,1491595908.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:48.938Z,1491595908.938 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:49.279Z,1491595909.279 [Aanderaa_O2](FAULT): Timed out starting
2017-04-07T20:11:49.279Z,1491595909.279 [Aanderaa_O2] Communications Fault, FailCount= 2
2017-04-07T20:11:49.279Z,1491595909.279 [Aanderaa_O2](ERROR): Communications Fault
2017-04-07T20:11:49.368Z,1491595909.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:49.370Z,1491595909.370 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:49.410Z,1491595909.410 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2017-04-07T20:11:49.679Z,1491595909.679 [Aanderaa_O2](INFO): Powering down
2017-04-07T20:11:49.793Z,1491595909.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:49.794Z,1491595909.794 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:50.116Z,1491595910.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:50.117Z,1491595910.117 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:50.539Z,1491595910.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:50.540Z,1491595910.540 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:50.588Z,1491595910.588 [MassServo](FAULT): Overload Error
2017-04-07T20:11:50.588Z,1491595910.588 [MassServo] Hardware Fault, FailCount= 3
2017-04-07T20:11:50.588Z,1491595910.588 [MassServo](ERROR): Hardware Fault
2017-04-07T20:11:50.593Z,1491595910.593 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-04-07T20:11:50.593Z,1491595910.593 [Aanderaa_O2] No Fault, FailCount= 2
2017-04-07T20:11:50.594Z,1491595910.594 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-04-07T20:11:50.928Z,1491595910.928 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:50.929Z,1491595910.929 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:50.952Z,1491595910.952 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-04-07T20:11:50.952Z,1491595910.953 [MassServo](INFO): Powering down
2017-04-07T20:11:51.336Z,1491595911.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:51.337Z,1491595911.337 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:51.728Z,1491595911.728 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:51.729Z,1491595911.729 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:51.752Z,1491595911.752 [MassServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:51.753Z,1491595911.753 [MassServo](DEBUG): Initializing MassServo.
2017-04-07T20:11:51.758Z,1491595911.758 [CBIT](INFO): Clearing failed state for component MassServo
2017-04-07T20:11:51.758Z,1491595911.758 [MassServo] No Fault, FailCount= 3
2017-04-07T20:11:52.144Z,1491595912.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:52.145Z,1491595912.145 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:52.536Z,1491595912.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:52.537Z,1491595912.537 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:52.934Z,1491595912.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:52.939Z,1491595912.940 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:53.340Z,1491595913.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:53.341Z,1491595913.341 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:53.740Z,1491595913.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:53.741Z,1491595913.741 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:54.112Z,1491595914.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:54.113Z,1491595914.113 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:54.544Z,1491595914.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:54.545Z,1491595914.545 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:54.937Z,1491595914.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:54.938Z,1491595914.938 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:55.316Z,1491595915.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:55.317Z,1491595915.317 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:55.444Z,1491595915.444 [CommandLine](IMPORTANT): got command quit
2017-04-07T20:11:55.740Z,1491595915.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:55.741Z,1491595915.741 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:55.791Z,1491595915.791 [MassServo](FAULT): Overload Error
2017-04-07T20:11:55.791Z,1491595915.792 [MassServo] Hardware Fault, FailCount= 4
2017-04-07T20:11:55.791Z,1491595915.792 [MassServo](ERROR): Hardware Fault
2017-04-07T20:11:55.797Z,1491595915.797 [CBIT](ERROR): Hardware Fault in component: MassServo
2017-04-07T20:11:56.140Z,1491595916.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:56.141Z,1491595916.141 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:56.155Z,1491595916.155 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-04-07T20:11:56.155Z,1491595916.155 [MassServo](INFO): Powering down
2017-04-07T20:11:56.450Z,1491595916.450 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:56.451Z,1491595916.451 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:56.524Z,1491595916.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:56.525Z,1491595916.525 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:56.555Z,1491595916.555 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2017-04-07T20:11:56.555Z,1491595916.555 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:56.556Z,1491595916.556 [CommandLine](INFO): Join timeout helper Thread ID is 433
2017-04-07T20:11:56.571Z,1491595916.571 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-04-07T20:11:56.571Z,1491595916.571 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:56.572Z,1491595916.572 [NavChartDb](INFO): Join timeout helper Thread ID is 434
2017-04-07T20:11:56.591Z,1491595916.591 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:56.592Z,1491595916.592 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:56.607Z,1491595916.607 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-04-07T20:11:56.607Z,1491595916.607 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:56.608Z,1491595916.608 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 435
2017-04-07T20:11:56.960Z,1491595916.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:56.961Z,1491595916.961 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:56.969Z,1491595916.969 [MassServo](DEBUG): Initializing EZServoServo.
2017-04-07T20:11:57.088Z,1491595917.088 [MassServo](DEBUG): Initializing MassServo.
2017-04-07T20:11:57.093Z,1491595917.093 [CBIT](INFO): Clearing failed state for component MassServo
2017-04-07T20:11:57.093Z,1491595917.093 [MassServo] No Fault, FailCount= 4
2017-04-07T20:11:57.324Z,1491595917.324 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:57.325Z,1491595917.325 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:57.387Z,1491595917.387 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:57.388Z,1491595917.388 [Rowe_600LCM](INFO): Powering down
2017-04-07T20:11:57.388Z,1491595917.388 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.403Z,1491595917.403 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-04-07T20:11:57.403Z,1491595917.403 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.404Z,1491595917.404 [Radio_Surface](INFO): Join timeout helper Thread ID is 437
2017-04-07T20:11:57.651Z,1491595917.651 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:57.652Z,1491595917.652 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.663Z,1491595917.663 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-04-07T20:11:57.663Z,1491595917.663 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.664Z,1491595917.664 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 438
2017-04-07T20:11:57.728Z,1491595917.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:57.729Z,1491595917.728 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-07T20:11:57.967Z,1491595917.967 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:57.967Z,1491595917.967 [WetLabsBB2FL](INFO): Powering down
2017-04-07T20:11:57.968Z,1491595917.968 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.970Z,1491595917.970 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-04-07T20:11:57.970Z,1491595917.970 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.971Z,1491595917.971 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 439
2017-04-07T20:11:57.971Z,1491595917.971 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:57.971Z,1491595917.971 [CTD_NeilBrown](INFO): Powering down
2017-04-07T20:11:57.972Z,1491595917.972 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.974Z,1491595917.974 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-04-07T20:11:57.974Z,1491595917.974 [logger ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:57.974Z,1491595917.974 [logger](INFO): Join timeout helper Thread ID is 440
2017-04-07T20:11:57.991Z,1491595917.991 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:57.992Z,1491595917.992 [logger ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.012Z,1491595918.012 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-04-07T20:11:58.012Z,1491595918.012 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.012Z,1491595918.012 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-04-07T20:11:58.012Z,1491595918.012 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.013Z,1491595918.013 [controlThread](INFO): Join timeout helper Thread ID is 441
2017-04-07T20:11:58.075Z,1491595918.075 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-07T20:11:58.075Z,1491595918.076 [controlThread](DEBUG): Uninitializing ControlThread
2017-04-07T20:11:58.195Z,1491595918.195 [Aanderaa_O2](INFO): Powering down
2017-04-07T20:11:58.198Z,1491595918.198 [NAL9602](INFO): Powering down
2017-04-07T20:11:58.199Z,1491595918.199 [DAT](INFO): Powering down
2017-04-07T20:11:58.336Z,1491595918.336 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-04-07T20:11:58.338Z,1491595918.338 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-04-07T20:11:58.339Z,1491595918.339 [MissionManager](INFO): Uninitializing Mission Default
2017-04-07T20:11:58.341Z,1491595918.341 [MissionManager](INFO): Uninitializing Mission Startup
2017-04-07T20:11:58.342Z,1491595918.342 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-04-07T20:11:58.342Z,1491595918.342 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-04-07T20:11:58.343Z,1491595918.342 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-04-07T20:11:58.343Z,1491595918.343 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-04-07T20:11:58.343Z,1491595918.343 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-04-07T20:11:58.343Z,1491595918.343 [BuoyancyServo](INFO): Powering down
2017-04-07T20:11:58.355Z,1491595918.355 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-04-07T20:11:58.355Z,1491595918.355 [ElevatorServo](INFO): Powering down
2017-04-07T20:11:58.375Z,1491595918.375 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-04-07T20:11:58.375Z,1491595918.375 [MassServo](INFO): Powering down
2017-04-07T20:11:58.376Z,1491595918.376 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-04-07T20:11:58.376Z,1491595918.376 [RudderServo](INFO): Powering down
2017-04-07T20:11:58.377Z,1491595918.376 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-04-07T20:11:58.377Z,1491595918.377 [ThrusterServo](INFO): Powering down
2017-04-07T20:11:58.377Z,1491595918.377 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-04-07T20:11:58.378Z,1491595918.378 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-04-07T20:11:58.378Z,1491595918.378 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-04-07T20:11:58.379Z,1491595918.379 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.404Z,1491595918.404 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.409Z,1491595918.409 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.494Z,1491595918.494 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.531Z,1491595918.531 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.823Z,1491595918.823 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-04-07T20:11:58.864Z,1491595918.864 [logger ThreadHandler](INFO): Thread cancelled.