2016-07-30T20:26:10.289Z,1469910370.289 [Supervisor](DEBUG): Initializing supervisor.
2016-07-30T20:26:10.292Z,1469910370.292 [SyncHandler](DEBUG): Created PCaller Thread at 4034C4E0
2016-07-30T20:26:10.293Z,1469910370.293 [SyncHandler](INFO): Protected caller Thread ID is 768
2016-07-30T20:26:10.293Z,1469910370.293 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2016-07-30T20:26:10.296Z,1469910370.296 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4037C4E0
2016-07-30T20:26:10.296Z,1469910370.296 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 769
2016-07-30T20:26:10.299Z,1469910370.299 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2016-07-30T20:26:10.311Z,1469910370.311 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2016-07-30T20:26:10.316Z,1469910370.316 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 403AC4E0
2016-07-30T20:26:10.316Z,1469910370.316 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 770
2016-07-30T20:26:10.317Z,1469910370.317 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2016-07-30T20:26:10.320Z,1469910370.320 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403DC4E0
2016-07-30T20:26:10.320Z,1469910370.320 [logger ThreadHandler](INFO): Protected caller Thread ID is 771
2016-07-30T20:26:10.322Z,1469910370.322 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2016-07-30T20:26:10.323Z,1469910370.323 [Supervisor](INFO): Looking for Config files in directory: Config/
2016-07-30T20:26:10.327Z,1469910370.327 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2016-07-30T20:26:10.710Z,1469910370.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2016-07-30T20:26:10.712Z,1469910370.712 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2016-07-30T20:26:10.894Z,1469910370.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2016-07-30T20:26:10.894Z,1469910370.894 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2016-07-30T20:26:11.034Z,1469910371.034 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2016-07-30T20:26:11.035Z,1469910371.035 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2016-07-30T20:26:11.181Z,1469910371.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2016-07-30T20:26:11.183Z,1469910371.183 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2016-07-30T20:26:11.277Z,1469910371.277 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2016-07-30T20:26:11.491Z,1469910371.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2016-07-30T20:26:11.491Z,1469910371.491 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2016-07-30T20:26:11.589Z,1469910371.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2016-07-30T20:26:11.591Z,1469910371.591 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2016-07-30T20:26:11.899Z,1469910371.899 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2016-07-30T20:26:11.900Z,1469910371.900 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2016-07-30T20:26:12.284Z,1469910372.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2016-07-30T20:26:12.285Z,1469910372.285 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2016-07-30T20:26:12.619Z,1469910372.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2016-07-30T20:26:12.620Z,1469910372.620 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2016-07-30T20:26:13.178Z,1469910373.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2016-07-30T20:26:13.179Z,1469910373.179 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2016-07-30T20:26:13.406Z,1469910373.406 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2016-07-30T20:26:13.406Z,1469910373.406 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2016-07-30T20:26:13.518Z,1469910373.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2016-07-30T20:26:13.518Z,1469910373.518 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2016-07-30T20:26:14.002Z,1469910374.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2016-07-30T20:26:14.003Z,1469910374.003 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2016-07-30T20:26:14.173Z,1469910374.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2016-07-30T20:26:14.175Z,1469910374.175 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2016-07-30T20:26:14.179Z,1469910374.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2016-07-30T20:26:14.443Z,1469910374.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2016-07-30T20:26:14.444Z,1469910374.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2016-07-30T20:26:14.576Z,1469910374.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2016-07-30T20:26:14.684Z,1469910374.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2016-07-30T20:26:14.792Z,1469910374.792 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2016-07-30T20:26:14.892Z,1469910374.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2016-07-30T20:26:15.049Z,1469910375.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2016-07-30T20:26:15.225Z,1469910375.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2016-07-30T20:26:15.337Z,1469910375.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2016-07-30T20:26:15.432Z,1469910375.432 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2016-07-30T20:26:15.533Z,1469910375.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2016-07-30T20:26:15.734Z,1469910375.734 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2016-07-30T20:26:15.736Z,1469910375.736 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2016-07-30T20:26:15.887Z,1469910375.887 [SBIT](DEBUG): Construct Startup Built In Test.
2016-07-30T20:26:15.921Z,1469910375.921 [SBIT] Loaded
2016-07-30T20:26:15.921Z,1469910375.921 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2016-07-30T20:26:15.922Z,1469910375.922 [IBIT](DEBUG): Construct Initiated Built In Test.
2016-07-30T20:26:15.955Z,1469910375.955 [IBIT] Loaded
2016-07-30T20:26:15.955Z,1469910375.955 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2016-07-30T20:26:15.957Z,1469910375.957 [CBIT](DEBUG): Construct Continuous Built In Test.
2016-07-30T20:26:16.111Z,1469910376.111 [CBIT] Loaded
2016-07-30T20:26:16.111Z,1469910376.111 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2016-07-30T20:26:16.112Z,1469910376.112 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2016-07-30T20:26:16.113Z,1469910376.113 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2016-07-30T20:26:16.182Z,1469910376.182 [VerticalControl](DEBUG): Construct VerticalControl.
2016-07-30T20:26:16.294Z,1469910376.294 [VerticalControl] Loaded
2016-07-30T20:26:16.294Z,1469910376.294 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2016-07-30T20:26:16.295Z,1469910376.295 [HorizontalControl](DEBUG): Construct HorizontalControl.
2016-07-30T20:26:16.360Z,1469910376.360 [HorizontalControl] Loaded
2016-07-30T20:26:16.361Z,1469910376.361 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2016-07-30T20:26:16.365Z,1469910376.365 [SpeedControl](DEBUG): Construct SpeedControl.
2016-07-30T20:26:16.371Z,1469910376.371 [SpeedControl] Loaded
2016-07-30T20:26:16.371Z,1469910376.371 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2016-07-30T20:26:16.372Z,1469910376.372 [LoopControl](DEBUG): Construct LoopControl.
2016-07-30T20:26:16.373Z,1469910376.373 [LoopControl] Loaded
2016-07-30T20:26:16.373Z,1469910376.373 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2016-07-30T20:26:16.373Z,1469910376.373 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2016-07-30T20:26:16.374Z,1469910376.374 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2016-07-30T20:26:16.411Z,1469910376.411 [DepthRateCalculator] Loaded
2016-07-30T20:26:16.411Z,1469910376.411 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2016-07-30T20:26:16.420Z,1469910376.420 [PitchRateCalculator] Loaded
2016-07-30T20:26:16.421Z,1469910376.421 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2016-07-30T20:26:16.432Z,1469910376.432 [SpeedCalculator] Loaded
2016-07-30T20:26:16.432Z,1469910376.432 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2016-07-30T20:26:16.452Z,1469910376.452 [TempGradientCalculator] Loaded
2016-07-30T20:26:16.453Z,1469910376.453 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2016-07-30T20:26:16.469Z,1469910376.469 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2016-07-30T20:26:16.469Z,1469910376.469 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2016-07-30T20:26:16.474Z,1469910376.474 [YawRateCalculator] Loaded
2016-07-30T20:26:16.475Z,1469910376.475 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2016-07-30T20:26:16.475Z,1469910376.475 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2016-07-30T20:26:16.476Z,1469910376.476 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2016-07-30T20:26:16.590Z,1469910376.590 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2016-07-30T20:26:16.590Z,1469910376.590 [StratificationFrontDetector](DEBUG): (re)initializing
2016-07-30T20:26:16.591Z,1469910376.591 [StratificationFrontDetector] Loaded
2016-07-30T20:26:16.591Z,1469910376.591 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2016-07-30T20:26:16.591Z,1469910376.591 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2016-07-30T20:26:16.592Z,1469910376.592 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2016-07-30T20:26:16.871Z,1469910376.871 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2016-07-30T20:26:16.872Z,1469910376.872 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2016-07-30T20:26:16.996Z,1469910376.996 [DeadReckonUsingMultipleVelocitySources] Loaded
2016-07-30T20:26:16.996Z,1469910376.996 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2016-07-30T20:26:17.058Z,1469910377.058 [DeadReckonUsingSpeedCalculator] Loaded
2016-07-30T20:26:17.059Z,1469910377.059 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2016-07-30T20:26:17.075Z,1469910377.075 [NavChart] Loaded
2016-07-30T20:26:17.075Z,1469910377.075 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2016-07-30T20:26:17.080Z,1469910377.080 [UniversalFixResidualReporter] Loaded
2016-07-30T20:26:17.080Z,1469910377.080 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2016-07-30T20:26:17.080Z,1469910377.080 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2016-07-30T20:26:17.081Z,1469910377.081 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2016-07-30T20:26:17.089Z,1469910377.089 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2016-07-30T20:26:17.090Z,1469910377.090 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2016-07-30T20:26:17.318Z,1469910377.318 [Aanderaa_O2] Loaded
2016-07-30T20:26:17.318Z,1469910377.318 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2016-07-30T20:26:17.402Z,1469910377.402 [CTD_NeilBrown] Loaded
2016-07-30T20:26:17.402Z,1469910377.402 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2016-07-30T20:26:17.407Z,1469910377.407 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4059E4E0
2016-07-30T20:26:17.407Z,1469910377.407 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 850
2016-07-30T20:26:17.422Z,1469910377.422 [PAR_Licor] Loaded
2016-07-30T20:26:17.423Z,1469910377.423 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2016-07-30T20:26:17.480Z,1469910377.480 [WetLabsBB2FL] Loaded
2016-07-30T20:26:17.480Z,1469910377.480 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2016-07-30T20:26:17.483Z,1469910377.483 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 405CE4E0
2016-07-30T20:26:17.484Z,1469910377.484 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 851
2016-07-30T20:26:17.484Z,1469910377.484 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2016-07-30T20:26:17.485Z,1469910377.485 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2016-07-30T20:26:19.346Z,1469910379.346 [AcousticModem_Benthos_ATM900] Loaded
2016-07-30T20:26:19.346Z,1469910379.346 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2016-07-30T20:26:19.537Z,1469910379.537 [DataOverHttps] Loaded
2016-07-30T20:26:19.537Z,1469910379.537 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2016-07-30T20:26:19.550Z,1469910379.550 [Depth_Keller] Loaded
2016-07-30T20:26:19.551Z,1469910379.551 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2016-07-30T20:26:19.555Z,1469910379.555 [DropWeight] Loaded
2016-07-30T20:26:19.556Z,1469910379.556 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2016-07-30T20:26:19.655Z,1469910379.655 [NAL9602] Loaded
2016-07-30T20:26:19.656Z,1469910379.656 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2016-07-30T20:26:19.701Z,1469910379.701 [Onboard] Loaded
2016-07-30T20:26:19.702Z,1469910379.702 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2016-07-30T20:26:19.713Z,1469910379.713 [Radio_Surface] Loaded
2016-07-30T20:26:19.713Z,1469910379.713 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2016-07-30T20:26:19.715Z,1469910379.715 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E74E0
2016-07-30T20:26:19.715Z,1469910379.715 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 852
2016-07-30T20:26:19.928Z,1469910379.928 [PNI_TCM] Loaded
2016-07-30T20:26:19.928Z,1469910379.928 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2016-07-30T20:26:20.311Z,1469910380.311 [Rowe_600LCM] Loaded
2016-07-30T20:26:20.311Z,1469910380.311 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2016-07-30T20:26:20.315Z,1469910380.315 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A174E0
2016-07-30T20:26:20.316Z,1469910380.316 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 853
2016-07-30T20:26:22.312Z,1469910382.312 [BPC1] Loaded
2016-07-30T20:26:22.312Z,1469910382.312 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2016-07-30T20:26:22.312Z,1469910382.312 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2016-07-30T20:26:22.313Z,1469910382.313 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2016-07-30T20:26:22.441Z,1469910382.441 [BuoyancyServo] Loaded
2016-07-30T20:26:22.441Z,1469910382.441 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2016-07-30T20:26:22.453Z,1469910382.453 [ElevatorServo] Loaded
2016-07-30T20:26:22.454Z,1469910382.454 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2016-07-30T20:26:22.469Z,1469910382.469 [MassServo] Loaded
2016-07-30T20:26:22.470Z,1469910382.470 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2016-07-30T20:26:22.481Z,1469910382.481 [RudderServo] Loaded
2016-07-30T20:26:22.482Z,1469910382.482 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2016-07-30T20:26:22.493Z,1469910382.493 [ThrusterServo] Loaded
2016-07-30T20:26:22.493Z,1469910382.493 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2016-07-30T20:26:22.493Z,1469910382.493 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2016-07-30T20:26:22.494Z,1469910382.494 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2016-07-30T20:26:22.634Z,1469910382.634 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2016-07-30T20:26:22.635Z,1469910382.635 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2016-07-30T20:26:22.677Z,1469910382.677 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2016-07-30T20:26:22.681Z,1469910382.681 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2016-07-30T20:26:22.682Z,1469910382.682 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2016-07-30T20:26:22.689Z,1469910382.689 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2016-07-30T20:26:22.691Z,1469910382.691 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0
2016-07-30T20:26:22.692Z,1469910382.692 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 854
2016-07-30T20:26:22.696Z,1469910382.696 [Supervisor](INFO): Main Thread ID is 767
2016-07-30T20:26:22.696Z,1469910382.696 [Supervisor](DEBUG): Running supervisor.
2016-07-30T20:26:22.697Z,1469910382.697 [CommandLine ThreadHandler](INFO): Handler Thread ID is 855
2016-07-30T20:26:22.704Z,1469910382.704 [controlThread ThreadHandler](INFO): Handler Thread ID is 856
2016-07-30T20:26:22.704Z,1469910382.704 [controlThread](DEBUG): Initializing ControlThread
2016-07-30T20:26:22.705Z,1469910382.705 [SBIT](INFO): Initialize SBIT Component.
2016-07-30T20:26:22.705Z,1469910382.705 [SBIT](IMPORTANT): git: 2015-11-13-11-g036008f
2016-07-30T20:26:22.706Z,1469910382.706 [SBIT](INFO): git hash: 036008fb27dd1443c9c6898c567944386f491f02
2016-07-30T20:26:22.706Z,1469910382.706 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2016-07-30T20:26:22.706Z,1469910382.706 [SBIT](IMPORTANT): Kernel Version:#639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
2016-07-30T20:26:22.707Z,1469910382.707 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2016-07-30T20:26:22.708Z,1469910382.708 [IBIT](INFO): Initialize IBIT Component.
2016-07-30T20:26:22.709Z,1469910382.709 [CBIT](DEBUG): Initialize CBIT Component.
2016-07-30T20:26:22.709Z,1469910382.709 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2016-07-30T20:26:22.709Z,1469910382.709 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2016-07-30T20:26:22.712Z,1469910382.712 [logger ThreadHandler](INFO): Handler Thread ID is 857
2016-07-30T20:26:22.735Z,1469910382.735 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2016-07-30T20:26:22.737Z,1469910382.737 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2016-07-30T20:26:22.738Z,1469910382.738 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2016-07-30T20:26:22.738Z,1469910382.738 [LoopControl](DEBUG): Initialize LoopControlComponent.
2016-07-30T20:26:22.739Z,1469910382.739 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2016-07-30T20:26:22.739Z,1469910382.739 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2016-07-30T20:26:22.739Z,1469910382.739 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2016-07-30T20:26:22.740Z,1469910382.740 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2016-07-30T20:26:22.740Z,1469910382.740 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2016-07-30T20:26:22.741Z,1469910382.741 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2016-07-30T20:26:22.742Z,1469910382.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2016-07-30T20:26:22.742Z,1469910382.742 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2016-07-30T20:26:22.743Z,1469910382.743 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2016-07-30T20:26:22.743Z,1469910382.743 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2016-07-30T20:26:22.744Z,1469910382.744 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2016-07-30T20:26:22.744Z,1469910382.744 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2016-07-30T20:26:22.744Z,1469910382.744 [NavChart](DEBUG): Initialize NavChart Navigation.
2016-07-30T20:26:22.745Z,1469910382.745 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2016-07-30T20:26:22.756Z,1469910382.756 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2016-07-30T20:26:22.772Z,1469910382.772 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 858
2016-07-30T20:26:22.773Z,1469910382.773 [CTD_NeilBrown](INFO): Powering down
2016-07-30T20:26:22.797Z,1469910382.797 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 859
2016-07-30T20:26:22.799Z,1469910382.799 [WetLabsBB2FL](INFO): Powering down
2016-07-30T20:26:22.809Z,1469910382.809 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2016-07-30T20:26:22.840Z,1469910382.840 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 860
2016-07-30T20:26:22.846Z,1469910382.846 [Radio_Surface](INFO): Powering up
2016-07-30T20:26:22.855Z,1469910382.855 [MissionManager](DEBUG):
2016-07-30T20:26:22.860Z,1469910382.860 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 861
2016-07-30T20:26:22.861Z,1469910382.861 [Rowe_600LCM](INFO): Initializing
2016-07-30T20:26:22.861Z,1469910382.861 [Rowe_600LCM](INFO): Checking LCM
2016-07-30T20:26:22.861Z,1469910382.861 [Rowe_600LCM](INFO): LCM OK
2016-07-30T20:26:22.861Z,1469910382.861 [Rowe_600LCM](INFO): Powering up
2016-07-30T20:26:22.862Z,1469910382.862 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2016-07-30T20:26:22.876Z,1469910382.876 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 862
2016-07-30T20:26:22.882Z,1469910382.882 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources/ElectronicNavigationCharts
2016-07-30T20:26:22.889Z,1469910382.889 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2016-07-30T20:26:22.890Z,1469910382.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2016-07-30T20:26:22.890Z,1469910382.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2016-07-30T20:26:22.890Z,1469910382.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2016-07-30T20:26:22.890Z,1469910382.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2016-07-30T20:26:22.890Z,1469910382.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2016-07-30T20:26:22.891Z,1469910382.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2016-07-30T20:26:22.891Z,1469910382.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2016-07-30T20:26:22.949Z,1469910382.949 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2016-07-30T20:26:22.950Z,1469910382.950 [Default:A.Wait](DEBUG): Construct Wait.
2016-07-30T20:26:22.964Z,1469910382.964 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2016-07-30T20:26:22.990Z,1469910382.990 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2016-07-30T20:26:22.993Z,1469910382.993 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2016-07-30T20:26:23.015Z,1469910383.015 [Default:E.Execute](DEBUG): Construct Execute.
2016-07-30T20:26:23.018Z,1469910383.018 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before starting to actually run Default.
13
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2016-07-30T20:26:23.032Z,1469910383.032 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2016-07-30T20:26:23.091Z,1469910383.091 [AcousticModem_Benthos_ATM900](INFO): Powering up
2016-07-30T20:26:23.091Z,1469910383.091 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2016-07-30T20:26:23.666Z,1469910383.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:23.893Z,1469910383.893 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:24.019Z,1469910384.019 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-07-30T20:26:24.022Z,1469910384.022 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-07-30T20:26:24.044Z,1469910384.044 [ElevatorServo](DEBUG): Initializing EZServoServo.
2016-07-30T20:26:24.050Z,1469910384.050 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2016-07-30T20:26:24.056Z,1469910384.056 [MassServo](DEBUG): Initializing EZServoServo.
2016-07-30T20:26:24.062Z,1469910384.062 [MassServo](DEBUG): Initializing MassServo.
2016-07-30T20:26:24.067Z,1469910384.067 [RudderServo](DEBUG): Initializing EZServoServo.
2016-07-30T20:26:24.078Z,1469910384.078 [RudderServo](DEBUG): Initializing RudderServo.
2016-07-30T20:26:24.099Z,1469910384.099 [ThrusterServo](DEBUG): Initializing EZServoServo.
2016-07-30T20:26:24.106Z,1469910384.106 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2016-07-30T20:26:24.158Z,1469910384.158 [CommandLine](FAULT): Scheduling is paused
2016-07-30T20:26:24.396Z,1469910384.396 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:24.398Z,1469910384.398 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:24.664Z,1469910384.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:24.665Z,1469910384.665 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:25.011Z,1469910385.011 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2016-07-30T20:26:25.011Z,1469910385.011 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2016-07-30T20:26:25.011Z,1469910385.011 [BuoyancyServo] Communications Fault, FailCount= 1
2016-07-30T20:26:25.011Z,1469910385.011 [BuoyancyServo](ERROR): Communications Fault
2016-07-30T20:26:25.217Z,1469910385.217 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2016-07-30T20:26:25.229Z,1469910385.229 [NAL9602](INFO): Powering up NAL9602
2016-07-30T20:26:25.285Z,1469910385.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:25.286Z,1469910385.286 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:25.292Z,1469910385.292 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-07-30T20:26:25.293Z,1469910385.293 [BuoyancyServo](INFO): Powering down
2016-07-30T20:26:25.670Z,1469910385.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:25.671Z,1469910385.671 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:26.061Z,1469910386.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:26.062Z,1469910386.062 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:26.541Z,1469910386.541 [Aanderaa_O2](INFO): Powering down
2016-07-30T20:26:26.601Z,1469910386.601 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:26.606Z,1469910386.606 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:26.899Z,1469910386.899 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:26.900Z,1469910386.900 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:27.129Z,1469910387.129 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2016-07-30T20:26:27.210Z,1469910387.210 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:./bin/roweadcp -dev /dev/ttyA1 -b 38400 >& /dev/null &
2016-07-30T20:26:27.686Z,1469910387.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:27.707Z,1469910387.707 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:27.767Z,1469910387.767 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2016-07-30T20:26:27.767Z,1469910387.767 [BuoyancyServo] No Fault, FailCount= 1
2016-07-30T20:26:28.054Z,1469910388.054 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:28.055Z,1469910388.055 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:28.070Z,1469910388.070 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-07-30T20:26:28.070Z,1469910388.070 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-07-30T20:26:28.299Z,1469910388.299 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2016-07-30T20:26:28.300Z,1469910388.300 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2016-07-30T20:26:28.353Z,1469910388.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:28.355Z,1469910388.355 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:28.686Z,1469910388.686 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:28.687Z,1469910388.687 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:29.021Z,1469910389.021 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2016-07-30T20:26:29.021Z,1469910389.021 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2016-07-30T20:26:29.021Z,1469910389.021 [BuoyancyServo] Communications Fault, FailCount= 2
2016-07-30T20:26:29.021Z,1469910389.021 [BuoyancyServo](ERROR): Communications Fault
2016-07-30T20:26:29.087Z,1469910389.087 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2016-07-30T20:26:29.212Z,1469910389.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:29.229Z,1469910389.229 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:29.236Z,1469910389.236 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2016-07-30T20:26:29.241Z,1469910389.241 [BuoyancyServo](INFO): Powering down
2016-07-30T20:26:29.551Z,1469910389.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:29.552Z,1469910389.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:29.963Z,1469910389.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:29.964Z,1469910389.964 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:30.329Z,1469910390.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:30.330Z,1469910390.330 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:30.697Z,1469910390.697 [AcousticModem_Benthos_ATM900](DEBUG):
2016-07-30T20:26:30.744Z,1469910390.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:30.745Z,1469910390.745 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:31.148Z,1469910391.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:31.150Z,1469910391.150 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:31.589Z,1469910391.589 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:31.602Z,1469910391.602 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:31.668Z,1469910391.668 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2016-07-30T20:26:31.668Z,1469910391.668 [BuoyancyServo] No Fault, FailCount= 2
2016-07-30T20:26:31.899Z,1469910391.899 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2016-07-30T20:26:31.900Z,1469910391.900 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2016-07-30T20:26:31.901Z,1469910391.901 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2016-07-30T20:26:31.951Z,1469910391.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:31.952Z,1469910391.952 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2016-07-30T20:26:31.967Z,1469910391.967 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2016-07-30T20:26:31.967Z,1469910391.967 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2016-07-30T20:26:32.300Z,1469910392.300 [AcousticModem_Benthos_ATM900](DEBUG): Jul 30 2016 20:20:45