2017-10-24T18:21:04.934Z,1508869264.934 [Supervisor](DEBUG): Initializing supervisor.
2017-10-24T18:21:04.937Z,1508869264.937 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-10-24T18:21:04.937Z,1508869264.937 [SyncHandler](INFO): Protected caller Thread ID is 770
2017-10-24T18:21:04.938Z,1508869264.938 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-10-24T18:21:04.939Z,1508869264.939 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-10-24T18:21:04.939Z,1508869264.939 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 771
2017-10-24T18:21:04.942Z,1508869264.942 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-10-24T18:21:04.954Z,1508869264.954 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-10-24T18:21:04.955Z,1508869264.955 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-10-24T18:21:04.955Z,1508869264.955 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 772
2017-10-24T18:21:04.956Z,1508869264.956 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-10-24T18:21:04.957Z,1508869264.957 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-10-24T18:21:04.958Z,1508869264.958 [logger ThreadHandler](INFO): Protected caller Thread ID is 773
2017-10-24T18:21:04.960Z,1508869264.960 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-10-24T18:21:04.960Z,1508869264.960 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-10-24T18:21:04.963Z,1508869264.963 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-10-24T18:21:05.144Z,1508869265.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-10-24T18:21:05.145Z,1508869265.145 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-10-24T18:21:05.276Z,1508869265.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-10-24T18:21:05.277Z,1508869265.277 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-10-24T18:21:05.598Z,1508869265.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-10-24T18:21:05.598Z,1508869265.598 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-10-24T18:21:05.737Z,1508869265.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-10-24T18:21:05.738Z,1508869265.738 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-10-24T18:21:05.824Z,1508869265.824 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-10-24T18:21:06.021Z,1508869266.021 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-10-24T18:21:06.021Z,1508869266.021 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-10-24T18:21:06.123Z,1508869266.123 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-10-24T18:21:06.125Z,1508869266.125 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-10-24T18:21:06.409Z,1508869266.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-10-24T18:21:06.410Z,1508869266.410 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-10-24T18:21:06.771Z,1508869266.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-10-24T18:21:06.772Z,1508869266.772 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-10-24T18:21:07.072Z,1508869267.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-10-24T18:21:07.072Z,1508869267.072 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-10-24T18:21:07.585Z,1508869267.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-10-24T18:21:10.680Z,1508869270.680 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-10-24T18:21:10.869Z,1508869270.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-10-24T18:21:10.870Z,1508869270.870 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-10-24T18:21:15.304Z,1508869275.304 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-10-24T18:21:15.305Z,1508869275.305 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-10-24T18:21:15.791Z,1508869275.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-10-24T18:21:15.792Z,1508869275.792 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-10-24T18:21:15.902Z,1508869275.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-10-24T18:21:15.904Z,1508869275.904 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-10-24T18:21:15.906Z,1508869275.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-10-24T18:21:16.149Z,1508869276.149 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-10-24T18:21:16.150Z,1508869276.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-10-24T18:21:16.253Z,1508869276.253 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-10-24T18:21:16.346Z,1508869276.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-10-24T18:21:16.451Z,1508869276.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-10-24T18:21:16.539Z,1508869276.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-10-24T18:21:16.667Z,1508869276.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-10-24T18:21:16.819Z,1508869276.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-10-24T18:21:16.917Z,1508869276.917 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-10-24T18:21:17.012Z,1508869277.012 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-10-24T18:21:17.101Z,1508869277.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-10-24T18:21:17.275Z,1508869277.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-10-24T18:21:17.357Z,1508869277.357 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2017-10-24T18:21:17.357Z,1508869277.357 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-10-24T18:21:17.371Z,1508869277.371 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-10-24T18:21:17.534Z,1508869277.534 [SBIT](DEBUG): Construct Startup Built In Test.
2017-10-24T18:21:17.565Z,1508869277.565 [SBIT] Loaded
2017-10-24T18:21:17.565Z,1508869277.565 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-10-24T18:21:17.566Z,1508869277.566 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-10-24T18:21:17.595Z,1508869277.595 [IBIT] Loaded
2017-10-24T18:21:17.595Z,1508869277.595 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-10-24T18:21:17.599Z,1508869277.599 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-10-24T18:21:17.756Z,1508869277.756 [CBIT] Loaded
2017-10-24T18:21:17.756Z,1508869277.756 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-10-24T18:21:17.756Z,1508869277.756 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-10-24T18:21:17.757Z,1508869277.757 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-10-24T18:21:17.850Z,1508869277.850 [VerticalControl](DEBUG): Construct VerticalControl.
2017-10-24T18:21:17.953Z,1508869277.953 [VerticalControl] Loaded
2017-10-24T18:21:17.953Z,1508869277.953 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-10-24T18:21:17.954Z,1508869277.954 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-10-24T18:21:18.018Z,1508869278.018 [HorizontalControl] Loaded
2017-10-24T18:21:18.018Z,1508869278.018 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-10-24T18:21:18.019Z,1508869278.019 [SpeedControl](DEBUG): Construct SpeedControl.
2017-10-24T18:21:18.025Z,1508869278.025 [SpeedControl] Loaded
2017-10-24T18:21:18.025Z,1508869278.025 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-10-24T18:21:18.026Z,1508869278.026 [LoopControl](DEBUG): Construct LoopControl.
2017-10-24T18:21:18.026Z,1508869278.026 [LoopControl] Loaded
2017-10-24T18:21:18.027Z,1508869278.027 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-10-24T18:21:18.027Z,1508869278.027 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-10-24T18:21:18.028Z,1508869278.028 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-10-24T18:21:18.070Z,1508869278.070 [DepthRateCalculator] Loaded
2017-10-24T18:21:18.070Z,1508869278.070 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-10-24T18:21:18.075Z,1508869278.075 [PitchRateCalculator] Loaded
2017-10-24T18:21:18.076Z,1508869278.076 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-10-24T18:21:18.092Z,1508869278.092 [SpeedCalculator] Loaded
2017-10-24T18:21:18.092Z,1508869278.092 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-10-24T18:21:18.114Z,1508869278.114 [TempGradientCalculator] Loaded
2017-10-24T18:21:18.115Z,1508869278.115 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-10-24T18:21:18.131Z,1508869278.131 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-10-24T18:21:18.131Z,1508869278.131 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-10-24T18:21:18.136Z,1508869278.136 [YawRateCalculator] Loaded
2017-10-24T18:21:18.137Z,1508869278.137 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-10-24T18:21:18.137Z,1508869278.137 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-10-24T18:21:18.138Z,1508869278.138 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-10-24T18:21:18.260Z,1508869278.260 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-10-24T18:21:18.260Z,1508869278.260 [StratificationFrontDetector](DEBUG): (re)initializing
2017-10-24T18:21:18.260Z,1508869278.260 [StratificationFrontDetector] Loaded
2017-10-24T18:21:18.260Z,1508869278.260 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-10-24T18:21:18.261Z,1508869278.261 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-10-24T18:21:18.262Z,1508869278.262 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-10-24T18:21:18.543Z,1508869278.543 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-10-24T18:21:18.544Z,1508869278.544 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-10-24T18:21:18.657Z,1508869278.657 [DeadReckonUsingSpeedCalculator] Loaded
2017-10-24T18:21:18.658Z,1508869278.658 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-10-24T18:21:18.714Z,1508869278.714 [DeadReckonWithRespectToSeafloor] Loaded
2017-10-24T18:21:18.714Z,1508869278.714 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2017-10-24T18:21:18.732Z,1508869278.732 [NavChart] Loaded
2017-10-24T18:21:18.732Z,1508869278.732 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-10-24T18:21:18.736Z,1508869278.736 [UniversalFixResidualReporter] Loaded
2017-10-24T18:21:18.737Z,1508869278.737 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-10-24T18:21:18.737Z,1508869278.737 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-10-24T18:21:18.738Z,1508869278.738 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-10-24T18:21:18.767Z,1508869278.767 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-10-24T18:21:18.768Z,1508869278.768 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-10-24T18:21:18.960Z,1508869278.960 [Aanderaa_O2] Loaded
2017-10-24T18:21:18.961Z,1508869278.961 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-10-24T18:21:18.973Z,1508869278.973 [CANONSampler] Loaded
2017-10-24T18:21:18.974Z,1508869278.974 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2017-10-24T18:21:19.056Z,1508869279.056 [CTD_NeilBrown] Loaded
2017-10-24T18:21:19.057Z,1508869279.057 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-10-24T18:21:19.058Z,1508869279.058 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407864E0
2017-10-24T18:21:19.058Z,1508869279.058 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 857
2017-10-24T18:21:19.093Z,1508869279.093 [CTD_Seabird] Loaded
2017-10-24T18:21:19.094Z,1508869279.094 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-10-24T18:21:19.095Z,1508869279.095 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407B64E0
2017-10-24T18:21:19.095Z,1508869279.095 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 858
2017-10-24T18:21:19.111Z,1508869279.111 [PAR_Licor] Loaded
2017-10-24T18:21:19.111Z,1508869279.111 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-10-24T18:21:19.162Z,1508869279.162 [WetLabsBB2FL] Loaded
2017-10-24T18:21:19.163Z,1508869279.163 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-10-24T18:21:19.164Z,1508869279.164 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407E64E0
2017-10-24T18:21:19.164Z,1508869279.164 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 859
2017-10-24T18:21:19.164Z,1508869279.164 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-10-24T18:21:19.165Z,1508869279.165 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-10-24T18:21:19.782Z,1508869279.782 [DataOverHttps] Loaded
2017-10-24T18:21:19.782Z,1508869279.782 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-10-24T18:21:19.796Z,1508869279.796 [Depth_Keller] Loaded
2017-10-24T18:21:19.796Z,1508869279.796 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-10-24T18:21:19.891Z,1508869279.891 [NAL9602] Loaded
2017-10-24T18:21:19.891Z,1508869279.891 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-10-24T18:21:19.900Z,1508869279.900 [Onboard] Loaded
2017-10-24T18:21:19.901Z,1508869279.901 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-10-24T18:21:19.908Z,1508869279.908 [Radio_Surface] Loaded
2017-10-24T18:21:19.908Z,1508869279.908 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-10-24T18:21:19.910Z,1508869279.910 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A314E0
2017-10-24T18:21:19.910Z,1508869279.910 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 860
2017-10-24T18:21:20.105Z,1508869280.105 [PNI_TCM] Loaded
2017-10-24T18:21:20.105Z,1508869280.105 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-10-24T18:21:22.016Z,1508869282.016 [BPC1] Loaded
2017-10-24T18:21:22.016Z,1508869282.016 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-10-24T18:21:22.017Z,1508869282.017 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-10-24T18:21:22.017Z,1508869282.017 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-10-24T18:21:23.987Z,1508869283.987 [BuoyancyServo] Loaded
2017-10-24T18:21:23.987Z,1508869283.987 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-10-24T18:21:23.999Z,1508869283.999 [ElevatorServo] Loaded
2017-10-24T18:21:23.000Z,1508869284.000 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-10-24T18:21:24.012Z,1508869284.012 [MassServo] Loaded
2017-10-24T18:21:24.012Z,1508869284.012 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-10-24T18:21:24.024Z,1508869284.024 [RudderServo] Loaded
2017-10-24T18:21:24.025Z,1508869284.025 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-10-24T18:21:24.037Z,1508869284.037 [ThrusterServo] Loaded
2017-10-24T18:21:24.037Z,1508869284.037 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-10-24T18:21:24.037Z,1508869284.037 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-10-24T18:21:24.038Z,1508869284.038 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-10-24T18:21:24.173Z,1508869284.173 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-10-24T18:21:24.174Z,1508869284.174 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-10-24T18:21:24.215Z,1508869284.215 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-10-24T18:21:24.219Z,1508869284.219 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-10-24T18:21:24.220Z,1508869284.220 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-10-24T18:21:24.227Z,1508869284.227 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-10-24T18:21:24.228Z,1508869284.228 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AE24E0
2017-10-24T18:21:24.228Z,1508869284.228 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 861
2017-10-24T18:21:24.233Z,1508869284.233 [Supervisor](INFO): Main Thread ID is 765
2017-10-24T18:21:24.233Z,1508869284.233 [Supervisor](DEBUG): Running supervisor.
2017-10-24T18:21:24.234Z,1508869284.234 [CommandLine ThreadHandler](INFO): Handler Thread ID is 862
2017-10-24T18:21:24.237Z,1508869284.237 [controlThread ThreadHandler](INFO): Handler Thread ID is 863
2017-10-24T18:21:24.237Z,1508869284.237 [controlThread](DEBUG): Initializing ControlThread
2017-10-24T18:21:24.238Z,1508869284.238 [SBIT](INFO): Initialize SBIT Component.
2017-10-24T18:21:24.239Z,1508869284.239 [SBIT](IMPORTANT): git: 2017-10-16
2017-10-24T18:21:24.239Z,1508869284.239 [SBIT](INFO): git hash: 5e637d88ea6653172d5095372d78c21c0064ca6c
2017-10-24T18:21:24.240Z,1508869284.240 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-10-24T18:21:24.241Z,1508869284.241 [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-10-24T18:21:24.242Z,1508869284.242 [SBIT](INFO): Beginning SBIT in 160.000000 seconds.
2017-10-24T18:21:24.243Z,1508869284.243 [IBIT](INFO): Initialize IBIT Component.
2017-10-24T18:21:24.243Z,1508869284.243 [CBIT](DEBUG): Initialize CBIT Component.
2017-10-24T18:21:24.244Z,1508869284.244 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-10-24T18:21:24.244Z,1508869284.244 [logger ThreadHandler](INFO): Handler Thread ID is 864
2017-10-24T18:21:24.261Z,1508869284.261 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 865
2017-10-24T18:21:24.262Z,1508869284.262 [CTD_NeilBrown](INFO): Powering down
2017-10-24T18:21:24.292Z,1508869284.292 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 866
2017-10-24T18:21:24.298Z,1508869284.298 [CTD_Seabird](INFO): Initializing
2017-10-24T18:21:24.334Z,1508869284.334 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-10-24T18:21:24.337Z,1508869284.337 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-10-24T18:21:24.338Z,1508869284.338 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-10-24T18:21:24.338Z,1508869284.338 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-10-24T18:21:24.338Z,1508869284.338 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-10-24T18:21:24.339Z,1508869284.339 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-10-24T18:21:24.339Z,1508869284.339 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-10-24T18:21:24.339Z,1508869284.339 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-10-24T18:21:24.340Z,1508869284.340 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-10-24T18:21:24.341Z,1508869284.341 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-10-24T18:21:24.342Z,1508869284.342 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-10-24T18:21:24.343Z,1508869284.343 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-10-24T18:21:24.343Z,1508869284.343 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-10-24T18:21:24.343Z,1508869284.343 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-10-24T18:21:24.344Z,1508869284.344 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-10-24T18:21:24.344Z,1508869284.344 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-10-24T18:21:24.345Z,1508869284.345 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-10-24T18:21:24.345Z,1508869284.345 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-10-24T18:21:24.354Z,1508869284.354 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 868
2017-10-24T18:21:24.355Z,1508869284.355 [WetLabsBB2FL](INFO): Powering down
2017-10-24T18:21:24.384Z,1508869284.384 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-10-24T18:21:24.395Z,1508869284.395 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 869
2017-10-24T18:21:24.403Z,1508869284.403 [Radio_Surface](INFO): Powering up
2017-10-24T18:21:24.405Z,1508869284.405 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 870
2017-10-24T18:21:24.420Z,1508869284.420 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-10-24T18:21:24.420Z,1508869284.420 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-10-24T18:21:24.420Z,1508869284.420 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-10-24T18:21:24.421Z,1508869284.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-10-24T18:21:24.421Z,1508869284.421 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-10-24T18:21:24.421Z,1508869284.421 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-10-24T18:21:24.422Z,1508869284.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-10-24T18:21:24.422Z,1508869284.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-10-24T18:21:24.422Z,1508869284.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-10-24T18:21:24.422Z,1508869284.422 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-10-24T18:21:24.422Z,1508869284.422 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-10-24T18:21:24.423Z,1508869284.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-10-24T18:21:24.423Z,1508869284.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-10-24T18:21:24.423Z,1508869284.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-10-24T18:21:24.423Z,1508869284.423 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-10-24T18:21:24.423Z,1508869284.423 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-10-24T18:21:24.463Z,1508869284.463 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-24T18:21:24.498Z,1508869284.498 [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-10-24T18:21:24.499Z,1508869284.499 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-10-24T18:21:24.616Z,1508869284.616 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-10-24T18:21:24.617Z,1508869284.617 [Default:A.Wait](DEBUG): Construct Wait.
2017-10-24T18:21:24.619Z,1508869284.619 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-10-24T18:21:24.656Z,1508869284.656 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-10-24T18:21:24.656Z,1508869284.656 [CTD_Seabird](INFO): Powering down
2017-10-24T18:21:24.701Z,1508869284.701 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-10-24T18:21:24.704Z,1508869284.704 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-10-24T18:21:24.718Z,1508869284.718 [Default:E.Execute](DEBUG): Construct Execute.
2017-10-24T18:21:24.732Z,1508869284.732 [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-10-24T18:21:24.738Z,1508869284.738 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,CANONSampler,PAR_Licor,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-10-24T18:21:24.802Z,1508869284.802 [CANONSampler](INFO): Powering down
2017-10-24T18:21:25.013Z,1508869285.013 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-10-24T18:21:25.071Z,1508869285.071 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-10-24T18:21:25.279Z,1508869285.279 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-10-24T18:21:25.371Z,1508869285.371 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:25.608Z,1508869285.608 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:25.705Z,1508869285.705 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-10-24T18:21:25.712Z,1508869285.712 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-10-24T18:21:25.726Z,1508869285.726 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-10-24T18:21:25.732Z,1508869285.732 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-10-24T18:21:25.738Z,1508869285.738 [MassServo](DEBUG): Initializing EZServoServo.
2017-10-24T18:21:25.744Z,1508869285.744 [MassServo](DEBUG): Initializing MassServo.
2017-10-24T18:21:25.750Z,1508869285.750 [RudderServo](DEBUG): Initializing EZServoServo.
2017-10-24T18:21:25.756Z,1508869285.756 [RudderServo](DEBUG): Initializing RudderServo.
2017-10-24T18:21:25.761Z,1508869285.761 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-10-24T18:21:25.768Z,1508869285.768 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-10-24T18:21:26.128Z,1508869286.128 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:26.129Z,1508869286.129 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:26.329Z,1508869286.329 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:26.330Z,1508869286.330 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:26.661Z,1508869286.661 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-10-24T18:21:26.661Z,1508869286.661 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-10-24T18:21:26.661Z,1508869286.661 [BuoyancyServo] Communications Fault, FailCount= 1
2017-10-24T18:21:26.661Z,1508869286.661 [BuoyancyServo](ERROR): Communications Fault
2017-10-24T18:21:26.873Z,1508869286.873 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-10-24T18:21:26.920Z,1508869286.920 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:26.921Z,1508869286.921 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:26.943Z,1508869286.943 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-10-24T18:21:26.944Z,1508869286.944 [BuoyancyServo](INFO): Powering down
2017-10-24T18:21:27.337Z,1508869287.337 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:27.342Z,1508869287.342 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:27.718Z,1508869287.718 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:27.723Z,1508869287.723 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:28.199Z,1508869288.199 [Aanderaa_O2](INFO): Powering down
2017-10-24T18:21:28.336Z,1508869288.336 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:28.336Z,1508869288.336 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:28.535Z,1508869288.535 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:28.536Z,1508869288.536 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-10-24T18:21:29.018Z,1508869289.018 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-10-24T18:21:29.018Z,1508869289.018 [BuoyancyServo] No Fault, FailCount= 1
2017-10-24T18:21:29.367Z,1508869289.367 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-10-24T18:21:29.486Z,1508869289.486 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-10-24T18:21:45.817Z,1508869305.817 [CommandLine](IMPORTANT): got command quit
2017-10-24T18:21:46.821Z,1508869306.821 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2017-10-24T18:21:46.824Z,1508869306.824 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:46.824Z,1508869306.824 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:46.869Z,1508869306.869 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-10-24T18:21:46.869Z,1508869306.869 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:46.870Z,1508869306.870 [CommandLine](INFO): Join timeout helper Thread ID is 880
2017-10-24T18:21:46.898Z,1508869306.898 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-10-24T18:21:46.899Z,1508869306.899 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:46.921Z,1508869306.921 [NavChartDb](INFO): Join timeout helper Thread ID is 881
2017-10-24T18:21:47.747Z,1508869307.747 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:47.747Z,1508869307.747 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:47.754Z,1508869307.754 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-10-24T18:21:47.754Z,1508869307.754 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:47.755Z,1508869307.755 [Radio_Surface](INFO): Join timeout helper Thread ID is 882
2017-10-24T18:21:47.851Z,1508869307.851 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:47.851Z,1508869307.851 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:47.866Z,1508869307.866 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-10-24T18:21:47.866Z,1508869307.866 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:47.867Z,1508869307.867 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 883
2017-10-24T18:21:48.255Z,1508869308.255 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:48.256Z,1508869308.256 [WetLabsBB2FL](INFO): Powering down
2017-10-24T18:21:48.256Z,1508869308.256 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.275Z,1508869308.275 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2017-10-24T18:21:48.275Z,1508869308.275 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.276Z,1508869308.276 [CTD_Seabird](INFO): Join timeout helper Thread ID is 884
2017-10-24T18:21:48.375Z,1508869308.375 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:48.594Z,1508869308.594 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-10-24T18:21:48.595Z,1508869308.595 [CTD_Seabird](INFO): Powering down
2017-10-24T18:21:48.596Z,1508869308.596 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.609Z,1508869308.609 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-10-24T18:21:48.609Z,1508869308.609 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.610Z,1508869308.610 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 886
2017-10-24T18:21:48.828Z,1508869308.828 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:48.829Z,1508869308.829 [CTD_NeilBrown](INFO): Powering down
2017-10-24T18:21:48.829Z,1508869308.829 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.845Z,1508869308.845 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-10-24T18:21:48.845Z,1508869308.845 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.845Z,1508869308.845 [logger](INFO): Join timeout helper Thread ID is 887
2017-10-24T18:21:48.896Z,1508869308.896 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:48.897Z,1508869308.897 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.909Z,1508869308.909 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-10-24T18:21:48.909Z,1508869308.909 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.909Z,1508869308.909 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-10-24T18:21:48.909Z,1508869308.909 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:48.910Z,1508869308.910 [controlThread](INFO): Join timeout helper Thread ID is 888
2017-10-24T18:21:48.975Z,1508869308.975 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-24T18:21:48.975Z,1508869308.975 [controlThread](DEBUG): Uninitializing ControlThread
2017-10-24T18:21:48.975Z,1508869308.975 [Aanderaa_O2](INFO): Powering down
2017-10-24T18:21:48.978Z,1508869308.978 [NAL9602](INFO): Powering down
2017-10-24T18:21:48.000Z,1508869309.000 [PNI_TCM](INFO): Powering down
2017-10-24T18:21:49.089Z,1508869309.089 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-10-24T18:21:49.091Z,1508869309.091 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-10-24T18:21:49.092Z,1508869309.092 [MissionManager](INFO): Uninitializing Mission Default
2017-10-24T18:21:49.094Z,1508869309.094 [MissionManager](INFO): Uninitializing Mission Startup
2017-10-24T18:21:49.095Z,1508869309.095 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-10-24T18:21:49.096Z,1508869309.096 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-10-24T18:21:49.096Z,1508869309.096 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-10-24T18:21:49.096Z,1508869309.096 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-10-24T18:21:49.097Z,1508869309.097 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-10-24T18:21:49.097Z,1508869309.097 [BuoyancyServo](INFO): Powering down
2017-10-24T18:21:49.108Z,1508869309.108 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-10-24T18:21:49.108Z,1508869309.108 [ElevatorServo](INFO): Powering down
2017-10-24T18:21:49.128Z,1508869309.128 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-10-24T18:21:49.128Z,1508869309.128 [MassServo](INFO): Powering down
2017-10-24T18:21:49.139Z,1508869309.139 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-10-24T18:21:49.139Z,1508869309.139 [RudderServo](INFO): Powering down
2017-10-24T18:21:49.140Z,1508869309.140 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-10-24T18:21:49.140Z,1508869309.140 [ThrusterServo](INFO): Powering down
2017-10-24T18:21:49.141Z,1508869309.141 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-10-24T18:21:49.141Z,1508869309.141 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-10-24T18:21:49.142Z,1508869309.142 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-10-24T18:21:49.143Z,1508869309.143 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:49.210Z,1508869309.210 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:49.214Z,1508869309.214 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:49.216Z,1508869309.216 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:49.285Z,1508869309.285 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:49.562Z,1508869309.562 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-24T18:21:49.616Z,1508869309.616 [logger ThreadHandler](INFO): Thread cancelled.