2015-12-18T00:47:17.394Z,1450399637.394 [Supervisor](DEBUG): Initializing supervisor.
2015-12-18T00:47:17.399Z,1450399637.399 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2015-12-18T00:47:17.400Z,1450399637.400 [SyncHandler](INFO): Protected caller Thread ID is 765
2015-12-18T00:47:17.400Z,1450399637.400 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2015-12-18T00:47:17.403Z,1450399637.403 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2015-12-18T00:47:17.403Z,1450399637.404 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 766
2015-12-18T00:47:17.406Z,1450399637.406 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2015-12-18T00:47:17.418Z,1450399637.418 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2015-12-18T00:47:17.423Z,1450399637.423 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2015-12-18T00:47:17.424Z,1450399637.424 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 767
2015-12-18T00:47:17.424Z,1450399637.424 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2015-12-18T00:47:17.427Z,1450399637.427 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2015-12-18T00:47:17.428Z,1450399637.428 [logger ThreadHandler](INFO): Protected caller Thread ID is 768
2015-12-18T00:47:17.430Z,1450399637.430 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2015-12-18T00:47:17.430Z,1450399637.430 [Supervisor](INFO): Looking for Config files in directory: Config/
2015-12-18T00:47:17.434Z,1450399637.434 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2015-12-18T00:47:17.821Z,1450399637.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2015-12-18T00:47:17.821Z,1450399637.821 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2015-12-18T00:47:18.008Z,1450399638.008 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2015-12-18T00:47:18.009Z,1450399638.009 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2015-12-18T00:47:18.148Z,1450399638.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2015-12-18T00:47:18.148Z,1450399638.148 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2015-12-18T00:47:18.296Z,1450399638.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2015-12-18T00:47:18.297Z,1450399638.297 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2015-12-18T00:47:18.392Z,1450399638.392 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2015-12-18T00:47:18.612Z,1450399638.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2015-12-18T00:47:18.613Z,1450399638.613 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2015-12-18T00:47:18.710Z,1450399638.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2015-12-18T00:47:18.711Z,1450399638.711 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2015-12-18T00:47:19.024Z,1450399639.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2015-12-18T00:47:19.025Z,1450399639.025 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2015-12-18T00:47:19.400Z,1450399639.400 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2015-12-18T00:47:19.401Z,1450399639.401 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2015-12-18T00:47:19.756Z,1450399639.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2015-12-18T00:47:19.756Z,1450399639.756 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2015-12-18T00:47:20.317Z,1450399640.317 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2015-12-18T00:47:20.318Z,1450399640.318 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2015-12-18T00:47:20.540Z,1450399640.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2015-12-18T00:47:20.541Z,1450399640.541 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2015-12-18T00:47:20.656Z,1450399640.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2015-12-18T00:47:20.656Z,1450399640.657 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2015-12-18T00:47:21.157Z,1450399641.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2015-12-18T00:47:21.157Z,1450399641.157 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2015-12-18T00:47:21.281Z,1450399641.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2015-12-18T00:47:21.283Z,1450399641.283 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2015-12-18T00:47:21.286Z,1450399641.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2015-12-18T00:47:21.554Z,1450399641.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2015-12-18T00:47:21.555Z,1450399641.555 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2015-12-18T00:47:21.688Z,1450399641.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2015-12-18T00:47:21.799Z,1450399641.799 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2015-12-18T00:47:21.909Z,1450399641.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2015-12-18T00:47:22.009Z,1450399642.009 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2015-12-18T00:47:22.169Z,1450399642.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2015-12-18T00:47:22.324Z,1450399642.325 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2015-12-18T00:47:22.437Z,1450399642.437 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2015-12-18T00:47:22.531Z,1450399642.531 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2015-12-18T00:47:22.632Z,1450399642.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2015-12-18T00:47:22.828Z,1450399642.828 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2015-12-18T00:47:22.831Z,1450399642.831 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2015-12-18T00:47:22.982Z,1450399642.982 [SBIT](DEBUG): Construct Startup Built In Test.
2015-12-18T00:47:23.017Z,1450399643.017 [SBIT] Loaded
2015-12-18T00:47:23.018Z,1450399643.018 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2015-12-18T00:47:23.018Z,1450399643.018 [IBIT](DEBUG): Construct Initiated Built In Test.
2015-12-18T00:47:23.047Z,1450399643.046 [IBIT] Loaded
2015-12-18T00:47:23.047Z,1450399643.047 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2015-12-18T00:47:23.049Z,1450399643.049 [CBIT](DEBUG): Construct Continuous Built In Test.
2015-12-18T00:47:23.204Z,1450399643.204 [CBIT] Loaded
2015-12-18T00:47:23.204Z,1450399643.204 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2015-12-18T00:47:23.205Z,1450399643.205 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2015-12-18T00:47:23.205Z,1450399643.205 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2015-12-18T00:47:23.274Z,1450399643.274 [VerticalControl](DEBUG): Construct VerticalControl.
2015-12-18T00:47:23.382Z,1450399643.382 [VerticalControl] Loaded
2015-12-18T00:47:23.382Z,1450399643.382 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2015-12-18T00:47:23.383Z,1450399643.383 [HorizontalControl](DEBUG): Construct HorizontalControl.
2015-12-18T00:47:23.454Z,1450399643.454 [HorizontalControl] Loaded
2015-12-18T00:47:23.454Z,1450399643.454 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2015-12-18T00:47:23.455Z,1450399643.455 [SpeedControl](DEBUG): Construct SpeedControl.
2015-12-18T00:47:23.461Z,1450399643.461 [SpeedControl] Loaded
2015-12-18T00:47:23.461Z,1450399643.461 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2015-12-18T00:47:23.461Z,1450399643.461 [LoopControl](DEBUG): Construct LoopControl.
2015-12-18T00:47:23.462Z,1450399643.462 [LoopControl] Loaded
2015-12-18T00:47:23.462Z,1450399643.462 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2015-12-18T00:47:23.463Z,1450399643.463 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2015-12-18T00:47:23.464Z,1450399643.464 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2015-12-18T00:47:23.501Z,1450399643.501 [DepthRateCalculator] Loaded
2015-12-18T00:47:23.502Z,1450399643.501 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2015-12-18T00:47:23.511Z,1450399643.511 [PitchRateCalculator] Loaded
2015-12-18T00:47:23.511Z,1450399643.511 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2015-12-18T00:47:23.522Z,1450399643.522 [SpeedCalculator] Loaded
2015-12-18T00:47:23.522Z,1450399643.522 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2015-12-18T00:47:23.538Z,1450399643.538 [TempGradientCalculator] Loaded
2015-12-18T00:47:23.539Z,1450399643.539 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2015-12-18T00:47:23.559Z,1450399643.559 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2015-12-18T00:47:23.559Z,1450399643.559 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2015-12-18T00:47:23.564Z,1450399643.564 [YawRateCalculator] Loaded
2015-12-18T00:47:23.565Z,1450399643.565 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2015-12-18T00:47:23.565Z,1450399643.565 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2015-12-18T00:47:23.566Z,1450399643.566 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2015-12-18T00:47:23.681Z,1450399643.681 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2015-12-18T00:47:23.681Z,1450399643.681 [StratificationFrontDetector](DEBUG): (re)initializing
2015-12-18T00:47:23.682Z,1450399643.681 [StratificationFrontDetector] Loaded
2015-12-18T00:47:23.682Z,1450399643.682 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2015-12-18T00:47:23.682Z,1450399643.682 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2015-12-18T00:47:23.687Z,1450399643.687 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2015-12-18T00:47:23.965Z,1450399643.965 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2015-12-18T00:47:23.965Z,1450399643.965 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2015-12-18T00:47:24.092Z,1450399644.092 [DeadReckonUsingMultipleVelocitySources] Loaded
2015-12-18T00:47:24.093Z,1450399644.093 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2015-12-18T00:47:24.151Z,1450399644.151 [DeadReckonUsingSpeedCalculator] Loaded
2015-12-18T00:47:24.152Z,1450399644.152 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2015-12-18T00:47:24.172Z,1450399644.172 [NavChart] Loaded
2015-12-18T00:47:24.172Z,1450399644.172 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2015-12-18T00:47:24.177Z,1450399644.177 [UniversalFixResidualReporter] Loaded
2015-12-18T00:47:24.177Z,1450399644.177 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2015-12-18T00:47:24.178Z,1450399644.178 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2015-12-18T00:47:24.180Z,1450399644.180 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2015-12-18T00:47:24.188Z,1450399644.188 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2015-12-18T00:47:24.189Z,1450399644.189 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2015-12-18T00:47:24.406Z,1450399644.406 [Aanderaa_O2] Loaded
2015-12-18T00:47:24.406Z,1450399644.406 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2015-12-18T00:47:24.489Z,1450399644.489 [CTD_NeilBrown] Loaded
2015-12-18T00:47:24.489Z,1450399644.489 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2015-12-18T00:47:24.491Z,1450399644.491 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059E4E0
2015-12-18T00:47:24.491Z,1450399644.491 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 847
2015-12-18T00:47:24.509Z,1450399644.509 [ESPComponent] Loaded
2015-12-18T00:47:24.510Z,1450399644.510 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2015-12-18T00:47:24.529Z,1450399644.529 [PAR_Licor] Loaded
2015-12-18T00:47:24.529Z,1450399644.529 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2015-12-18T00:47:24.582Z,1450399644.582 [WetLabsBB2FL] Loaded
2015-12-18T00:47:24.582Z,1450399644.582 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2015-12-18T00:47:24.587Z,1450399644.587 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CE4E0
2015-12-18T00:47:24.587Z,1450399644.588 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 848
2015-12-18T00:47:24.588Z,1450399644.588 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2015-12-18T00:47:24.589Z,1450399644.589 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2015-12-18T00:47:25.109Z,1450399645.109 [AcousticModem_Benthos_ATM900] Loaded
2015-12-18T00:47:25.109Z,1450399645.109 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2015-12-18T00:47:25.293Z,1450399645.293 [DataOverHttps] Loaded
2015-12-18T00:47:25.294Z,1450399645.294 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2015-12-18T00:47:25.311Z,1450399645.311 [Depth_Keller] Loaded
2015-12-18T00:47:25.311Z,1450399645.311 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2015-12-18T00:47:25.316Z,1450399645.316 [DropWeight] Loaded
2015-12-18T00:47:25.316Z,1450399645.316 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2015-12-18T00:47:25.415Z,1450399645.415 [NAL9602] Loaded
2015-12-18T00:47:25.416Z,1450399645.416 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2015-12-18T00:47:25.461Z,1450399645.461 [Onboard] Loaded
2015-12-18T00:47:25.461Z,1450399645.461 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2015-12-18T00:47:25.469Z,1450399645.469 [Radio_Surface] Loaded
2015-12-18T00:47:25.469Z,1450399645.469 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2015-12-18T00:47:25.471Z,1450399645.471 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408124E0
2015-12-18T00:47:25.472Z,1450399645.471 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 849
2015-12-18T00:47:25.685Z,1450399645.685 [PNI_TCM] Loaded
2015-12-18T00:47:25.685Z,1450399645.685 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2015-12-18T00:47:25.882Z,1450399645.882 [Rowe_600] Loaded
2015-12-18T00:47:25.883Z,1450399645.883 [ComponentRegistry](DEBUG): Component "Rowe_600" handled in its own thread.
2015-12-18T00:47:25.891Z,1450399645.891 [Rowe_600 ThreadHandler](DEBUG): Created PCaller Thread at 408424E0
2015-12-18T00:47:25.892Z,1450399645.892 [Rowe_600 ThreadHandler](INFO): Protected caller Thread ID is 850
2015-12-18T00:47:27.915Z,1450399647.915 [BPC1] Loaded
2015-12-18T00:47:27.916Z,1450399647.916 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2015-12-18T00:47:27.916Z,1450399647.916 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2015-12-18T00:47:27.917Z,1450399647.917 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2015-12-18T00:47:28.051Z,1450399648.051 [BuoyancyServo] Loaded
2015-12-18T00:47:28.051Z,1450399648.051 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2015-12-18T00:47:28.064Z,1450399648.064 [ElevatorServo] Loaded
2015-12-18T00:47:28.064Z,1450399648.064 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2015-12-18T00:47:28.076Z,1450399648.076 [MassServo] Loaded
2015-12-18T00:47:28.076Z,1450399648.076 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2015-12-18T00:47:28.093Z,1450399648.093 [RudderServo] Loaded
2015-12-18T00:47:28.093Z,1450399648.093 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2015-12-18T00:47:28.105Z,1450399648.105 [ThrusterServo] Loaded
2015-12-18T00:47:28.105Z,1450399648.105 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2015-12-18T00:47:28.105Z,1450399648.105 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2015-12-18T00:47:28.106Z,1450399648.106 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2015-12-18T00:47:28.269Z,1450399648.269 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2015-12-18T00:47:28.270Z,1450399648.270 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2015-12-18T00:47:28.308Z,1450399648.308 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2015-12-18T00:47:28.316Z,1450399648.316 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2015-12-18T00:47:28.317Z,1450399648.317 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2015-12-18T00:47:28.324Z,1450399648.324 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2015-12-18T00:47:28.327Z,1450399648.327 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 408F04E0
2015-12-18T00:47:28.328Z,1450399648.328 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 851
2015-12-18T00:47:28.332Z,1450399648.332 [Supervisor](INFO): Main Thread ID is 764
2015-12-18T00:47:28.332Z,1450399648.332 [Supervisor](DEBUG): Running supervisor.
2015-12-18T00:47:28.333Z,1450399648.333 [CommandLine ThreadHandler](INFO): Handler Thread ID is 852
2015-12-18T00:47:28.340Z,1450399648.340 [controlThread ThreadHandler](INFO): Handler Thread ID is 853
2015-12-18T00:47:28.340Z,1450399648.340 [controlThread](DEBUG): Initializing ControlThread
2015-12-18T00:47:28.341Z,1450399648.341 [SBIT](INFO): Initialize SBIT Component.
2015-12-18T00:47:28.341Z,1450399648.341 [SBIT](IMPORTANT): git: 2015-12-17
2015-12-18T00:47:28.342Z,1450399648.342 [SBIT](INFO): git hash: 52f20fd1fdee2f789766e92ee5f80cc9036abc83
2015-12-18T00:47:28.342Z,1450399648.342 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2015-12-18T00:47:28.342Z,1450399648.342 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2015-12-18T00:47:28.343Z,1450399648.343 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2015-12-18T00:47:28.344Z,1450399648.344 [IBIT](INFO): Initialize IBIT Component.
2015-12-18T00:47:28.345Z,1450399648.345 [CBIT](DEBUG): Initialize CBIT Component.
2015-12-18T00:47:28.345Z,1450399648.345 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2015-12-18T00:47:28.345Z,1450399648.345 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2015-12-18T00:47:28.348Z,1450399648.348 [logger ThreadHandler](INFO): Handler Thread ID is 854
2015-12-18T00:47:28.371Z,1450399648.371 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2015-12-18T00:47:28.373Z,1450399648.373 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2015-12-18T00:47:28.374Z,1450399648.374 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2015-12-18T00:47:28.374Z,1450399648.374 [LoopControl](DEBUG): Initialize LoopControlComponent.
2015-12-18T00:47:28.375Z,1450399648.375 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-12-18T00:47:28.375Z,1450399648.375 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2015-12-18T00:47:28.375Z,1450399648.375 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2015-12-18T00:47:28.376Z,1450399648.376 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2015-12-18T00:47:28.376Z,1450399648.376 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2015-12-18T00:47:28.377Z,1450399648.377 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2015-12-18T00:47:28.377Z,1450399648.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2015-12-18T00:47:28.378Z,1450399648.378 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2015-12-18T00:47:28.379Z,1450399648.378 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2015-12-18T00:47:28.379Z,1450399648.379 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2015-12-18T00:47:28.380Z,1450399648.379 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2015-12-18T00:47:28.380Z,1450399648.380 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2015-12-18T00:47:28.380Z,1450399648.380 [NavChart](DEBUG): Initialize NavChart Navigation.
2015-12-18T00:47:28.380Z,1450399648.381 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2015-12-18T00:47:28.392Z,1450399648.392 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2015-12-18T00:47:28.404Z,1450399648.404 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 855
2015-12-18T00:47:28.408Z,1450399648.408 [CTD_NeilBrown](INFO): Powering down
2015-12-18T00:47:28.428Z,1450399648.428 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 856
2015-12-18T00:47:28.429Z,1450399648.429 [WetLabsBB2FL](INFO): Powering down
2015-12-18T00:47:28.452Z,1450399648.452 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 857
2015-12-18T00:47:28.458Z,1450399648.458 [Radio_Surface](INFO): Powering up
2015-12-18T00:47:28.464Z,1450399648.464 [Rowe_600 ThreadHandler](INFO): Handler Thread ID is 858
2015-12-18T00:47:28.480Z,1450399648.480 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-12-18T00:47:28.495Z,1450399648.495 [MissionManager](DEBUG):
2015-12-18T00:47:28.496Z,1450399648.496 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2015-12-18T00:47:28.498Z,1450399648.497 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 859
2015-12-18T00:47:28.508Z,1450399648.508 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources/ElectronicNavigationCharts
2015-12-18T00:47:28.512Z,1450399648.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2015-12-18T00:47:28.512Z,1450399648.512 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2015-12-18T00:47:28.513Z,1450399648.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2015-12-18T00:47:28.513Z,1450399648.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2015-12-18T00:47:28.513Z,1450399648.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2015-12-18T00:47:28.513Z,1450399648.513 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2015-12-18T00:47:28.514Z,1450399648.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2015-12-18T00:47:28.514Z,1450399648.514 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2015-12-18T00:47:28.583Z,1450399648.583 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2015-12-18T00:47:28.584Z,1450399648.584 [Default:A.GoToSurface](DEBUG): Construct GoToSurface.
2015-12-18T00:47:28.594Z,1450399648.594 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2015-12-18T00:47:28.625Z,1450399648.625 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2015-12-18T00:47:28.647Z,1450399648.647 [Default:D.Execute](DEBUG): Construct Execute.
2015-12-18T00:47:28.650Z,1450399648.650 [MissionManager](DEBUG):
0
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2015-12-18T00:47:28.656Z,1450399648.656 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2015-12-18T00:47:28.743Z,1450399648.743 [Rowe_600](INFO): Powering down
2015-12-18T00:47:28.770Z,1450399648.770 [AcousticModem_Benthos_ATM900](INFO): Powering up
2015-12-18T00:47:28.770Z,1450399648.770 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2015-12-18T00:47:28.988Z,1450399648.988 [Rowe_600](INFO): Initializing
2015-12-18T00:47:29.091Z,1450399649.091 [Rowe_600](INFO): Powering up
2015-12-18T00:47:29.319Z,1450399649.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:29.507Z,1450399649.507 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:29.621Z,1450399649.621 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-12-18T00:47:29.627Z,1450399649.628 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-12-18T00:47:29.658Z,1450399649.658 [ElevatorServo](DEBUG): Initializing EZServoServo.
2015-12-18T00:47:29.664Z,1450399649.664 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2015-12-18T00:47:29.670Z,1450399649.670 [MassServo](DEBUG): Initializing EZServoServo.
2015-12-18T00:47:29.675Z,1450399649.675 [MassServo](DEBUG): Initializing MassServo.
2015-12-18T00:47:29.693Z,1450399649.693 [RudderServo](DEBUG): Initializing EZServoServo.
2015-12-18T00:47:29.699Z,1450399649.699 [RudderServo](DEBUG): Initializing RudderServo.
2015-12-18T00:47:29.721Z,1450399649.721 [ThrusterServo](DEBUG): Initializing EZServoServo.
2015-12-18T00:47:29.728Z,1450399649.727 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2015-12-18T00:47:29.934Z,1450399649.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:29.935Z,1450399649.935 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:30.269Z,1450399650.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:30.270Z,1450399650.270 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:30.623Z,1450399650.623 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2015-12-18T00:47:30.623Z,1450399650.623 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2015-12-18T00:47:30.623Z,1450399650.623 [BuoyancyServo] Communications Fault, FailCount= 1
2015-12-18T00:47:30.623Z,1450399650.624 [BuoyancyServo](ERROR): Communications Fault
2015-12-18T00:47:30.829Z,1450399650.829 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2015-12-18T00:47:30.853Z,1450399650.853 [NAL9602](INFO): Powering up NAL9602
2015-12-18T00:47:30.931Z,1450399650.931 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:30.932Z,1450399650.932 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:30.955Z,1450399650.955 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-12-18T00:47:30.956Z,1450399650.956 [BuoyancyServo](INFO): Powering down
2015-12-18T00:47:31.296Z,1450399651.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:31.297Z,1450399651.297 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:31.664Z,1450399651.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:31.665Z,1450399651.665 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:32.155Z,1450399652.155 [Aanderaa_O2](INFO): Powering down
2015-12-18T00:47:32.262Z,1450399652.262 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:32.263Z,1450399652.263 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:32.496Z,1450399652.496 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:32.497Z,1450399652.497 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2015-12-18T00:47:33.474Z,1450399653.474 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2015-12-18T00:47:33.474Z,1450399653.474 [BuoyancyServo] No Fault, FailCount= 1
2015-12-18T00:47:33.808Z,1450399653.808 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2015-12-18T00:47:33.928Z,1450399653.928 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2015-12-18T00:47:36.196Z,1450399656.196 [AcousticModem_Benthos_ATM900](DEBUG):
2015-12-18T00:47:37.407Z,1450399657.407 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2015-12-18T00:47:37.407Z,1450399657.407 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2015-12-18T00:47:37.408Z,1450399657.408 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2015-12-18T00:47:37.956Z,1450399657.956 [AcousticModem_Benthos_ATM900](DEBUG): Dec 18 2015 00:51:36
2015-12-18T00:47:38.787Z,1450399658.787 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2015-12-18T00:47:39.158Z,1450399659.158 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-12-18T00:47:39.555Z,1450399659.555 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2015-12-18T00:47:39.557Z,1450399659.557 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2015-12-18T00:47:39.558Z,1450399659.557 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2015-12-18T00:47:39.959Z,1450399659.959 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2015-12-18T00:47:40.352Z,1450399660.352 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2015-12-18T00:47:40.353Z,1450399660.353 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2015-12-18T00:47:40.761Z,1450399660.761 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2015-12-18T00:47:41.957Z,1450399661.957 [NAL9602](INFO): NAL9602 initialized
2015-12-18T00:48:00.825Z,1450399680.825 [Rowe_600](CRITICAL): SIGSEGV: address not mapped to object
2015-12-18T00:48:00.826Z,1450399680.826 [Rowe_600](CRITICAL): Bad address: 0x0
2015-12-18T00:48:00.827Z,1450399680.827 [Rowe_600](CRITICAL): Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1737f4]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40212510]
2015-12-18T00:48:00.856Z,1450399680.856 [Rowe_600] Software Fault, FailCount= 1
2015-12-18T00:48:00.856Z,1450399680.856 [Rowe_600](ERROR): Software Fault
2015-12-18T00:48:01.101Z,1450399681.101 [CommandLine](FAULT): Scheduling is paused
2015-12-18T00:48:01.104Z,1450399681.104 [CBIT](ERROR): Software Fault in component: Rowe_600
2015-12-18T00:48:01.280Z,1450399681.280 [Rowe_600 ThreadHandler](DEBUG): Created PCaller Thread at 408424E0
2015-12-18T00:48:01.281Z,1450399681.281 [Rowe_600 ThreadHandler](INFO): Protected caller Thread ID is 871
2015-12-18T00:48:02.127Z,1450399682.127 [Rowe_600](INFO): Powering down
2015-12-18T00:48:04.136Z,1450399684.136 [CBIT](INFO): Clearing failed state for component Rowe_600
2015-12-18T00:48:04.136Z,1450399684.136 [Rowe_600] No Fault, FailCount= 1
2015-12-18T00:48:04.255Z,1450399684.256 [Rowe_600](INFO): Initializing
2015-12-18T00:48:04.279Z,1450399684.279 [Rowe_600](INFO): Powering up
2015-12-18T00:48:18.513Z,1450399698.513 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.3 s old, using for 20.0 s.
2015-12-18T00:48:19.006Z,1450399699.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2015-12-18T00:48:19.320Z,1450399699.320 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.1 s old, using for 20.0 s.
2015-12-18T00:48:19.730Z,1450399699.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2015-12-18T00:48:20.177Z,1450399700.177 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2015-12-18T00:48:20.538Z,1450399700.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2015-12-18T00:48:21.053Z,1450399701.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2015-12-18T00:48:21.361Z,1450399701.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2015-12-18T00:48:21.791Z,1450399701.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2015-12-18T00:48:22.149Z,1450399702.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2015-12-18T00:48:22.593Z,1450399702.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2015-12-18T00:48:23.033Z,1450399703.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2015-12-18T00:48:23.358Z,1450399703.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2015-12-18T00:48:23.702Z,1450399703.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2015-12-18T00:48:24.114Z,1450399704.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2015-12-18T00:48:24.624Z,1450399704.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2015-12-18T00:48:24.992Z,1450399704.992 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2015-12-18T00:48:25.341Z,1450399705.341 [BPC1](ERROR): Failed to parse bank B battery data
2015-12-18T00:48:25.341Z,1450399705.341 [BPC1] Data Fault, FailCount= 1
2015-12-18T00:48:25.341Z,1450399705.341 [BPC1](ERROR): Data Fault
2015-12-18T00:48:25.357Z,1450399705.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2015-12-18T00:48:25.462Z,1450399705.462 [CBIT](ERROR): Data Fault in component: BPC1
2015-12-18T00:48:25.881Z,1450399705.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2015-12-18T00:48:26.224Z,1450399706.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2015-12-18T00:48:26.546Z,1450399706.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2015-12-18T00:48:26.583Z,1450399706.583 [CBIT](INFO): Clearing failed state for component BPC1
2015-12-18T00:48:26.583Z,1450399706.583 [BPC1] No Fault, FailCount= 1
2015-12-18T00:48:27.111Z,1450399707.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.9 s old, using for 20.0 s.
2015-12-18T00:48:27.363Z,1450399707.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2015-12-18T00:48:27.734Z,1450399707.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2015-12-18T00:48:28.122Z,1450399708.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2015-12-18T00:48:28.529Z,1450399708.529 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2015-12-18T00:48:29.001Z,1450399709.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2015-12-18T00:48:29.301Z,1450399709.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.1 s old, using for 20.0 s.
2015-12-18T00:48:29.775Z,1450399709.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2015-12-18T00:48:30.097Z,1450399710.097 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.9 s old, using for 20.0 s.
2015-12-18T00:48:30.517Z,1450399710.517 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.3 s old, using for 20.0 s.
2015-12-18T00:48:31.020Z,1450399711.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2015-12-18T00:48:31.365Z,1450399711.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2015-12-18T00:48:31.839Z,1450399711.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.7 s old, using for 20.0 s.
2015-12-18T00:48:31.861Z,1450399711.861 [SBIT](IMPORTANT): Beginning Startup BIT
2015-12-18T00:48:31.880Z,1450399711.880 [CBIT](IMPORTANT): Beginning GF scan
2015-12-18T00:48:32.150Z,1450399712.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2015-12-18T00:48:32.554Z,1450399712.554 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2015-12-18T00:48:32.925Z,1450399712.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.7 s old, using for 20.0 s.
2015-12-18T00:48:33.343Z,1450399713.343 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2015-12-18T00:48:33.893Z,1450399713.893 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2015-12-18T00:48:34.620Z,1450399714.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2015-12-18T00:48:34.866Z,1450399714.866 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2015-12-18T00:48:35.316Z,1450399715.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2015-12-18T00:48:35.714Z,1450399715.714 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2015-12-18T00:48:36.116Z,1450399716.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2015-12-18T00:48:36.655Z,1450399716.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2015-12-18T00:48:36.923Z,1450399716.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.7 s old, using for 20.0 s.
2015-12-18T00:48:37.382Z,1450399717.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2015-12-18T00:48:37.715Z,1450399717.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2015-12-18T00:48:38.110Z,1450399718.110 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2015-12-18T00:48:45.213Z,1450399725.214 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.703104
2015-12-18T00:48:58.586Z,1450399738.586 [CBIT](IMPORTANT): No ground fault detected
2015-12-18T00:49:16.456Z,1450399756.456 [RudderServo](FAULT): Invalid EZ Servo response:""
2015-12-18T00:49:16.456Z,1450399756.457 [RudderServo] Communications Fault, FailCount= 1
2015-12-18T00:49:16.457Z,1450399756.457 [RudderServo](ERROR): Communications Fault
2015-12-18T00:49:16.457Z,1450399756.457 [RudderServo](ERROR): Rudder reporting null position
2015-12-18T00:49:16.467Z,1450399756.467 [CBIT](ERROR): Communications Fault in component: RudderServo
2015-12-18T00:49:16.889Z,1450399756.889 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-12-18T00:49:16.889Z,1450399756.889 [RudderServo](INFO): Powering down
2015-12-18T00:49:17.571Z,1450399757.571 [RudderServo](DEBUG): Initializing EZServoServo.
2015-12-18T00:49:17.694Z,1450399757.694 [RudderServo](DEBUG): Initializing RudderServo.
2015-12-18T00:49:17.706Z,1450399757.706 [CBIT](INFO): Clearing failed state for component RudderServo
2015-12-18T00:49:17.706Z,1450399757.706 [RudderServo] No Fault, FailCount= 1
2015-12-18T00:49:25.566Z,1450399765.566 [SBIT](CRITICAL): SBIT FAILED
2015-12-18T00:49:25.963Z,1450399765.963 [MissionManager](IMPORTANT): Started mission Startup
2015-12-18T00:49:25.968Z,1450399765.968 [Startup] Running Loop=1
2015-12-18T00:49:25.968Z,1450399765.968 [Startup](DEBUG): Aggregate::initialize Startup
2015-12-18T00:49:25.968Z,1450399765.968 [Startup:A.GoToSurface] Running Loop=1
2015-12-18T00:49:25.968Z,1450399765.968 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2015-12-18T00:49:25.969Z,1450399765.969 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2015-12-18T00:49:25.970Z,1450399765.970 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2015-12-18T00:49:25.971Z,1450399765.971 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2015-12-18T00:49:25.976Z,1450399765.976 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2015-12-18T00:49:25.977Z,1450399765.977 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2015-12-18T00:49:26.002Z,1450399766.003 [Startup:StartupSatComms] Running Loop=1
2015-12-18T00:49:26.003Z,1450399766.003 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2015-12-18T00:49:26.007Z,1450399766.007 [Startup:StartupSatComms:A] Running Loop=1
2015-12-18T00:49:26.329Z,1450399766.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2015-12-18T00:49:26.334Z,1450399766.334 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2015-12-18T00:49:26.757Z,1450399766.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2015-12-18T00:49:27.138Z,1450399767.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2015-12-18T00:49:27.543Z,1450399767.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2015-12-18T00:49:27.905Z,1450399767.905 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2015-12-18T00:49:28.310Z,1450399768.310 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2015-12-18T00:49:28.760Z,1450399768.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2015-12-18T00:49:29.114Z,1450399769.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2015-12-18T00:49:29.572Z,1450399769.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2015-12-18T00:49:29.912Z,1450399769.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2015-12-18T00:49:30.346Z,1450399770.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2015-12-18T00:49:30.718Z,1450399770.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2015-12-18T00:49:31.134Z,1450399771.134 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2015-12-18T00:49:31.565Z,1450399771.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2015-12-18T00:49:31.912Z,1450399771.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2015-12-18T00:49:32.312Z,1450399772.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2015-12-18T00:49:32.715Z,1450399772.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2015-12-18T00:49:33.149Z,1450399773.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2015-12-18T00:49:33.578Z,1450399773.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2015-12-18T00:49:33.925Z,1450399773.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2015-12-18T00:49:34.348Z,1450399774.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2015-12-18T00:49:34.779Z,1450399774.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2015-12-18T00:49:35.135Z,1450399775.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2015-12-18T00:49:35.558Z,1450399775.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2015-12-18T00:49:35.980Z,1450399775.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2015-12-18T00:49:36.389Z,1450399776.389 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2015-12-18T00:49:36.759Z,1450399776.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2015-12-18T00:49:37.156Z,1450399777.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2015-12-18T00:49:37.524Z,1450399777.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2015-12-18T00:49:37.916Z,1450399777.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2015-12-18T00:49:38.429Z,1450399778.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.5 s old, using for 20.0 s.
2015-12-18T00:49:38.706Z,1450399778.706 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2015-12-18T00:49:39.195Z,1450399779.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2015-12-18T00:49:39.512Z,1450399779.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2015-12-18T00:49:39.965Z,1450399779.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2015-12-18T00:49:40.388Z,1450399780.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2015-12-18T00:49:40.764Z,1450399780.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2015-12-18T00:49:41.182Z,1450399781.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2015-12-18T00:49:41.518Z,1450399781.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2015-12-18T00:49:41.916Z,1450399781.916 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2015-12-18T00:49:42.321Z,1450399782.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2015-12-18T00:49:42.757Z,1450399782.757 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2015-12-18T00:49:43.156Z,1450399783.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2015-12-18T00:49:43.581Z,1450399783.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2015-12-18T00:49:44.001Z,1450399784.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2015-12-18T00:49:44.355Z,1450399784.355 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2015-12-18T00:49:44.713Z,1450399784.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2015-12-18T00:49:45.146Z,1450399785.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2015-12-18T00:49:45.565Z,1450399785.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2015-12-18T00:49:56.601Z,1450399796.601 [CommandLine](IMPORTANT): got command restart application
2015-12-18T00:49:57.613Z,1450399797.613 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:57.614Z,1450399797.614 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:57.675Z,1450399797.675 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2015-12-18T00:49:57.675Z,1450399797.675 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:57.676Z,1450399797.676 [CommandLine](INFO): Join timeout helper Thread ID is 888
2015-12-18T00:49:57.676Z,1450399797.676 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2015-12-18T00:49:57.676Z,1450399797.676 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:57.677Z,1450399797.677 [NavChartDb](INFO): Join timeout helper Thread ID is 889
2015-12-18T00:49:58.003Z,1450399798.003 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:58.004Z,1450399798.004 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.023Z,1450399798.023 [ComponentRegistry](INFO): Shutting down Rowe_600 ThreadHandler
2015-12-18T00:49:58.024Z,1450399798.024 [Rowe_600 ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.024Z,1450399798.024 [Rowe_600](INFO): Join timeout helper Thread ID is 890
2015-12-18T00:49:58.399Z,1450399798.399 [Rowe_600 ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:58.400Z,1450399798.400 [Rowe_600](INFO): Powering down
2015-12-18T00:49:58.488Z,1450399798.488 [Rowe_600 ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.495Z,1450399798.495 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2015-12-18T00:49:58.495Z,1450399798.495 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.496Z,1450399798.496 [Radio_Surface](INFO): Join timeout helper Thread ID is 891
2015-12-18T00:49:58.512Z,1450399798.511 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:58.512Z,1450399798.512 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.515Z,1450399798.515 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2015-12-18T00:49:58.516Z,1450399798.516 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.516Z,1450399798.516 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 892
2015-12-18T00:49:58.883Z,1450399798.883 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:58.884Z,1450399798.884 [WetLabsBB2FL](INFO): Powering down
2015-12-18T00:49:58.884Z,1450399798.884 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.910Z,1450399798.910 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2015-12-18T00:49:58.910Z,1450399798.910 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.910Z,1450399798.910 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 893
2015-12-18T00:49:58.919Z,1450399798.919 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:58.919Z,1450399798.920 [CTD_NeilBrown](INFO): Powering down
2015-12-18T00:49:58.920Z,1450399798.920 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.928Z,1450399798.928 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2015-12-18T00:49:58.928Z,1450399798.928 [logger ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.928Z,1450399798.928 [logger](INFO): Join timeout helper Thread ID is 894
2015-12-18T00:49:58.951Z,1450399798.951 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:58.952Z,1450399798.952 [logger ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.968Z,1450399798.968 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2015-12-18T00:49:58.968Z,1450399798.968 [CommandLine ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.968Z,1450399798.968 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2015-12-18T00:49:58.968Z,1450399798.968 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:58.969Z,1450399798.969 [controlThread](INFO): Join timeout helper Thread ID is 895
2015-12-18T00:49:59.064Z,1450399799.063 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2015-12-18T00:49:59.064Z,1450399799.064 [controlThread](DEBUG): Uninitializing ControlThread
2015-12-18T00:49:59.065Z,1450399799.065 [Aanderaa_O2](INFO): Powering down
2015-12-18T00:49:59.067Z,1450399799.067 [AcousticModem_Benthos_ATM900](INFO): Powering down
2015-12-18T00:49:59.188Z,1450399799.188 [NAL9602](INFO): Powering down
2015-12-18T00:49:59.190Z,1450399799.190 [PNI_TCM](INFO): Powering down
2015-12-18T00:49:59.276Z,1450399799.276 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2015-12-18T00:49:59.278Z,1450399799.278 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2015-12-18T00:49:59.280Z,1450399799.280 [Startup] Stopped
2015-12-18T00:49:59.280Z,1450399799.280 [Startup](DEBUG): Aggregate::uninitialize Startup
2015-12-18T00:49:59.280Z,1450399799.280 [Startup:A.GoToSurface] Stopped
2015-12-18T00:49:59.280Z,1450399799.280 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2015-12-18T00:49:59.281Z,1450399799.281 [Startup:StartupSatComms] Stopped
2015-12-18T00:49:59.281Z,1450399799.281 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2015-12-18T00:49:59.281Z,1450399799.281 [Startup:StartupSatComms:A] Stopped
2015-12-18T00:49:59.282Z,1450399799.282 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2015-12-18T00:49:59.282Z,1450399799.282 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2015-12-18T00:49:59.283Z,1450399799.283 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2015-12-18T00:49:59.283Z,1450399799.283 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2015-12-18T00:49:59.283Z,1450399799.283 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2015-12-18T00:49:59.283Z,1450399799.283 [BuoyancyServo](INFO): Powering down
2015-12-18T00:49:59.295Z,1450399799.295 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2015-12-18T00:49:59.295Z,1450399799.295 [ElevatorServo](INFO): Powering down
2015-12-18T00:49:59.296Z,1450399799.296 [MassServo](DEBUG): Uninitialize Mass Servo.
2015-12-18T00:49:59.296Z,1450399799.296 [MassServo](INFO): Powering down
2015-12-18T00:49:59.297Z,1450399799.297 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2015-12-18T00:49:59.297Z,1450399799.297 [RudderServo](INFO): Powering down
2015-12-18T00:49:59.298Z,1450399799.298 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2015-12-18T00:49:59.298Z,1450399799.298 [ThrusterServo](INFO): Powering down
2015-12-18T00:49:59.299Z,1450399799.299 [SBIT](DEBUG): Uninitialize SBIT Component.
2015-12-18T00:49:59.299Z,1450399799.299 [IBIT](DEBUG): Uninitialize IBIT Component.
2015-12-18T00:49:59.300Z,1450399799.300 [CBIT](DEBUG): Uninitialize CBIT Component.
2015-12-18T00:49:59.332Z,1450399799.332 [controlThread ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:59.391Z,1450399799.391 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:59.395Z,1450399799.395 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:59.459Z,1450399799.459 [Rowe_600 ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:59.466Z,1450399799.466 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:59.507Z,1450399799.507 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2015-12-18T00:49:59.562Z,1450399799.562 [logger ThreadHandler](INFO): Thread cancelled.