2017-04-19T14:18:01.586Z,1492611481.586 [Supervisor](DEBUG): Initializing supervisor. 2017-04-19T14:18:01.589Z,1492611481.589 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-04-19T14:18:01.589Z,1492611481.589 [SyncHandler](INFO): Protected caller Thread ID is 770 2017-04-19T14:18:01.590Z,1492611481.590 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-04-19T14:18:01.591Z,1492611481.591 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-04-19T14:18:01.591Z,1492611481.591 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 771 2017-04-19T14:18:01.594Z,1492611481.594 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-04-19T14:18:01.606Z,1492611481.606 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-04-19T14:18:01.607Z,1492611481.607 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-04-19T14:18:01.607Z,1492611481.607 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 772 2017-04-19T14:18:01.608Z,1492611481.608 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-04-19T14:18:01.609Z,1492611481.609 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-04-19T14:18:01.610Z,1492611481.610 [logger ThreadHandler](INFO): Protected caller Thread ID is 773 2017-04-19T14:18:01.612Z,1492611481.612 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-04-19T14:18:01.612Z,1492611481.612 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-04-19T14:18:01.615Z,1492611481.615 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-04-19T14:18:01.802Z,1492611481.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-04-19T14:18:01.802Z,1492611481.802 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-04-19T14:18:01.937Z,1492611481.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-04-19T14:18:01.938Z,1492611481.938 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-04-19T14:18:02.267Z,1492611482.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-04-19T14:18:02.267Z,1492611482.267 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-04-19T14:18:02.407Z,1492611482.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-04-19T14:18:02.407Z,1492611482.408 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-04-19T14:18:02.494Z,1492611482.494 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-04-19T14:18:02.699Z,1492611482.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-04-19T14:18:02.700Z,1492611482.700 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-04-19T14:18:02.787Z,1492611482.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-04-19T14:18:02.788Z,1492611482.787 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-04-19T14:18:03.078Z,1492611483.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-04-19T14:18:03.079Z,1492611483.079 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-04-19T14:18:03.436Z,1492611483.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-04-19T14:18:03.437Z,1492611483.437 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-04-19T14:18:03.735Z,1492611483.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-04-19T14:18:03.736Z,1492611483.736 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-04-19T14:18:04.238Z,1492611484.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-04-19T14:18:04.238Z,1492611484.239 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-04-19T14:18:07.934Z,1492611487.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-04-19T14:18:07.935Z,1492611487.935 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-04-19T14:18:08.037Z,1492611488.037 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-04-19T14:18:08.038Z,1492611488.038 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-04-19T14:18:08.489Z,1492611488.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-04-19T14:18:08.489Z,1492611488.490 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-04-19T14:18:08.602Z,1492611488.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-04-19T14:18:08.604Z,1492611488.604 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2017-04-19T14:18:08.606Z,1492611488.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2017-04-19T14:18:08.834Z,1492611488.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-04-19T14:18:08.836Z,1492611488.836 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2017-04-19T14:18:08.941Z,1492611488.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2017-04-19T14:18:09.051Z,1492611489.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2017-04-19T14:18:09.153Z,1492611489.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2017-04-19T14:18:09.246Z,1492611489.246 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2017-04-19T14:18:12.864Z,1492611492.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2017-04-19T14:18:13.042Z,1492611493.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2017-04-19T14:18:13.157Z,1492611493.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2017-04-19T14:18:13.260Z,1492611493.260 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2017-04-19T14:18:13.349Z,1492611493.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2017-04-19T14:18:13.524Z,1492611493.524 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-04-19T14:18:13.528Z,1492611493.528 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-04-19T14:18:13.570Z,1492611493.570 [DepthRateCalculator] Loaded 2017-04-19T14:18:13.570Z,1492611493.570 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-04-19T14:18:13.577Z,1492611493.577 [PitchRateCalculator] Loaded 2017-04-19T14:18:13.577Z,1492611493.577 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-04-19T14:18:13.589Z,1492611493.589 [SpeedCalculator] Loaded 2017-04-19T14:18:13.589Z,1492611493.589 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-04-19T14:18:13.611Z,1492611493.611 [TempGradientCalculator] Loaded 2017-04-19T14:18:13.611Z,1492611493.611 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-04-19T14:18:13.627Z,1492611493.627 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-04-19T14:18:13.627Z,1492611493.628 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-04-19T14:18:13.633Z,1492611493.633 [YawRateCalculator] Loaded 2017-04-19T14:18:13.633Z,1492611493.634 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-04-19T14:18:13.634Z,1492611493.634 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-04-19T14:18:13.636Z,1492611493.636 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-04-19T14:18:13.762Z,1492611493.762 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-04-19T14:18:13.762Z,1492611493.762 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-04-19T14:18:13.816Z,1492611493.816 [DeadReckonUsingSpeedCalculator] Loaded 2017-04-19T14:18:13.817Z,1492611493.817 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-04-19T14:18:13.871Z,1492611493.871 [DeadReckonWithRespectToWater] Loaded 2017-04-19T14:18:13.872Z,1492611493.872 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToWater" handled in the control thread. 2017-04-19T14:18:13.926Z,1492611493.926 [DeadReckonWithRespectToSeafloor] Loaded 2017-04-19T14:18:13.926Z,1492611493.926 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-04-19T14:18:13.985Z,1492611493.985 [DeadReckonUsingDVLWaterTrack] Loaded 2017-04-19T14:18:13.985Z,1492611493.985 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingDVLWaterTrack" handled in the control thread. 2017-04-19T14:18:14.002Z,1492611494.002 [NavChart] Loaded 2017-04-19T14:18:14.002Z,1492611494.003 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-04-19T14:18:14.007Z,1492611494.007 [UniversalFixResidualReporter] Loaded 2017-04-19T14:18:14.007Z,1492611494.007 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-04-19T14:18:14.007Z,1492611494.007 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-04-19T14:18:14.009Z,1492611494.010 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-04-19T14:18:14.517Z,1492611494.517 [CTD_Seabird] Loaded 2017-04-19T14:18:14.517Z,1492611494.517 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-04-19T14:18:14.518Z,1492611494.519 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066F4E0 2017-04-19T14:18:14.519Z,1492611494.519 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 852 2017-04-19T14:18:14.520Z,1492611494.520 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-04-19T14:18:14.521Z,1492611494.521 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-04-19T14:18:14.603Z,1492611494.603 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-04-19T14:18:14.604Z,1492611494.604 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-04-19T14:18:14.626Z,1492611494.626 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-04-19T14:18:14.627Z,1492611494.627 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-04-19T14:18:14.913Z,1492611494.913 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-04-19T14:18:14.914Z,1492611494.914 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-04-19T14:18:15.072Z,1492611495.072 [SBIT](DEBUG): Construct Startup Built In Test. 2017-04-19T14:18:15.103Z,1492611495.103 [SBIT] Loaded 2017-04-19T14:18:15.103Z,1492611495.103 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-04-19T14:18:15.104Z,1492611495.104 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-04-19T14:18:15.131Z,1492611495.131 [IBIT] Loaded 2017-04-19T14:18:15.131Z,1492611495.131 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-04-19T14:18:15.136Z,1492611495.136 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-04-19T14:18:15.289Z,1492611495.289 [CBIT] Loaded 2017-04-19T14:18:15.290Z,1492611495.290 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-04-19T14:18:15.290Z,1492611495.290 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-04-19T14:18:15.292Z,1492611495.292 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-04-19T14:18:15.919Z,1492611495.919 [DataOverHttps] Loaded 2017-04-19T14:18:15.919Z,1492611495.919 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-04-19T14:18:15.932Z,1492611495.932 [Depth_Keller] Loaded 2017-04-19T14:18:15.933Z,1492611495.933 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-04-19T14:18:15.938Z,1492611495.938 [DropWeight] Loaded 2017-04-19T14:18:15.938Z,1492611495.938 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-04-19T14:18:16.031Z,1492611496.031 [NAL9602] Loaded 2017-04-19T14:18:16.031Z,1492611496.031 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-04-19T14:18:16.040Z,1492611496.040 [Onboard] Loaded 2017-04-19T14:18:16.041Z,1492611496.041 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-04-19T14:18:16.048Z,1492611496.048 [Radio_Surface] Loaded 2017-04-19T14:18:16.048Z,1492611496.048 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-04-19T14:18:16.049Z,1492611496.049 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0 2017-04-19T14:18:16.049Z,1492611496.049 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 853 2017-04-19T14:18:16.242Z,1492611496.242 [PNI_TCM] Loaded 2017-04-19T14:18:16.242Z,1492611496.242 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-04-19T14:18:16.305Z,1492611496.305 [Rowe_600LCM] Loaded 2017-04-19T14:18:16.305Z,1492611496.305 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-04-19T14:18:16.306Z,1492611496.306 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409904E0 2017-04-19T14:18:16.307Z,1492611496.307 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 854 2017-04-19T14:18:18.141Z,1492611498.141 [BPC1] Loaded 2017-04-19T14:18:18.142Z,1492611498.142 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-04-19T14:18:18.142Z,1492611498.142 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-04-19T14:18:18.143Z,1492611498.143 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-04-19T14:18:18.271Z,1492611498.271 [BuoyancyServo] Loaded 2017-04-19T14:18:18.272Z,1492611498.272 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-04-19T14:18:18.287Z,1492611498.287 [ElevatorServo] Loaded 2017-04-19T14:18:18.288Z,1492611498.288 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-04-19T14:18:18.303Z,1492611498.303 [MassServo] Loaded 2017-04-19T14:18:18.303Z,1492611498.303 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-04-19T14:18:18.319Z,1492611498.319 [RudderServo] Loaded 2017-04-19T14:18:18.319Z,1492611498.319 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-04-19T14:18:18.334Z,1492611498.334 [ThrusterServo] Loaded 2017-04-19T14:18:18.335Z,1492611498.335 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-04-19T14:18:18.335Z,1492611498.335 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-04-19T14:18:18.336Z,1492611498.336 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-04-19T14:18:18.406Z,1492611498.406 [VerticalControl](DEBUG): Construct VerticalControl. 2017-04-19T14:18:18.498Z,1492611498.498 [VerticalControl] Loaded 2017-04-19T14:18:18.498Z,1492611498.498 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-04-19T14:18:18.499Z,1492611498.499 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-04-19T14:18:18.559Z,1492611498.559 [HorizontalControl] Loaded 2017-04-19T14:18:18.559Z,1492611498.559 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-04-19T14:18:18.560Z,1492611498.560 [SpeedControl](DEBUG): Construct SpeedControl. 2017-04-19T14:18:18.562Z,1492611498.562 [SpeedControl] Loaded 2017-04-19T14:18:18.562Z,1492611498.562 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-04-19T14:18:18.563Z,1492611498.563 [LoopControl](DEBUG): Construct LoopControl. 2017-04-19T14:18:18.563Z,1492611498.563 [LoopControl] Loaded 2017-04-19T14:18:18.563Z,1492611498.563 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-04-19T14:18:18.564Z,1492611498.564 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-04-19T14:18:18.565Z,1492611498.565 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-04-19T14:18:18.688Z,1492611498.688 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-04-19T14:18:18.688Z,1492611498.688 [StratificationFrontDetector](DEBUG): (re)initializing 2017-04-19T14:18:18.688Z,1492611498.688 [StratificationFrontDetector] Loaded 2017-04-19T14:18:18.688Z,1492611498.688 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-04-19T14:18:18.713Z,1492611498.713 [DepAvgTempFrontDetector] Loaded 2017-04-19T14:18:18.714Z,1492611498.714 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread. 2017-04-19T14:18:18.714Z,1492611498.714 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-04-19T14:18:18.716Z,1492611498.716 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-04-19T14:18:18.851Z,1492611498.851 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-04-19T14:18:18.855Z,1492611498.855 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-04-19T14:18:18.856Z,1492611498.856 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-04-19T14:18:18.863Z,1492611498.863 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-04-19T14:18:18.864Z,1492611498.864 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0 2017-04-19T14:18:18.864Z,1492611498.864 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 855 2017-04-19T14:18:18.869Z,1492611498.869 [Supervisor](INFO): Main Thread ID is 765 2017-04-19T14:18:18.869Z,1492611498.869 [Supervisor](DEBUG): Running supervisor. 2017-04-19T14:18:18.870Z,1492611498.870 [CommandLine ThreadHandler](INFO): Handler Thread ID is 856 2017-04-19T14:18:18.872Z,1492611498.872 [controlThread ThreadHandler](INFO): Handler Thread ID is 857 2017-04-19T14:18:18.873Z,1492611498.873 [controlThread](DEBUG): Initializing ControlThread 2017-04-19T14:18:18.874Z,1492611498.874 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-04-19T14:18:18.874Z,1492611498.874 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-04-19T14:18:18.874Z,1492611498.875 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-04-19T14:18:18.875Z,1492611498.875 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-04-19T14:18:18.876Z,1492611498.876 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-04-19T14:18:18.877Z,1492611498.876 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-04-19T14:18:18.877Z,1492611498.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-04-19T14:18:18.878Z,1492611498.878 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T14:18:18.878Z,1492611498.878 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T14:18:18.878Z,1492611498.878 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-04-19T14:18:18.879Z,1492611498.879 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T14:18:18.879Z,1492611498.879 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T14:18:18.880Z,1492611498.880 [DeadReckonWithRespectToWater](DEBUG): Initializing DeadReckonWithRespectToWater component. 2017-04-19T14:18:18.880Z,1492611498.880 [DeadReckonWithRespectToWater](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T14:18:18.881Z,1492611498.881 [DeadReckonWithRespectToWater](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T14:18:18.881Z,1492611498.881 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-04-19T14:18:18.881Z,1492611498.881 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T14:18:18.882Z,1492611498.882 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T14:18:18.882Z,1492611498.882 [DeadReckonUsingDVLWaterTrack](DEBUG): Initializing DeadReckonUsingDVLWaterTrack component. 2017-04-19T14:18:18.882Z,1492611498.882 [DeadReckonUsingDVLWaterTrack](INFO): Will consider orientation measurement stale after 120s. 2017-04-19T14:18:18.883Z,1492611498.883 [DeadReckonUsingDVLWaterTrack](INFO): Will consider velocity measurement stale after 20s. 2017-04-19T14:18:18.883Z,1492611498.883 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-04-19T14:18:18.883Z,1492611498.884 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-04-19T14:18:18.884Z,1492611498.884 [SBIT](INFO): Initialize SBIT Component. 2017-04-19T14:18:18.884Z,1492611498.884 [SBIT](IMPORTANT): git: 2017-02-07-44-g97d18de 2017-04-19T14:18:18.885Z,1492611498.885 [SBIT](INFO): git hash: 97d18dee453c718dff1ed32f738d235d58ab32f6 2017-04-19T14:18:18.886Z,1492611498.886 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-04-19T14:18:18.887Z,1492611498.887 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016 2017-04-19T14:18:18.887Z,1492611498.888 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2017-04-19T14:18:18.888Z,1492611498.888 [IBIT](INFO): Initialize IBIT Component. 2017-04-19T14:18:18.889Z,1492611498.889 [CBIT](DEBUG): Initialize CBIT Component. 2017-04-19T14:18:18.889Z,1492611498.889 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-04-19T14:18:18.889Z,1492611498.889 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-04-19T14:18:18.890Z,1492611498.890 [logger ThreadHandler](INFO): Handler Thread ID is 858 2017-04-19T14:18:18.916Z,1492611498.916 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 859 2017-04-19T14:18:18.916Z,1492611498.916 [CTD_Seabird](INFO): Initializing 2017-04-19T14:18:18.960Z,1492611498.960 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 861 2017-04-19T14:18:18.966Z,1492611498.967 [Radio_Surface](INFO): Powering up 2017-04-19T14:18:18.980Z,1492611498.980 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-04-19T14:18:18.982Z,1492611498.982 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-04-19T14:18:18.983Z,1492611498.983 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-04-19T14:18:18.984Z,1492611498.984 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-04-19T14:18:18.986Z,1492611498.986 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-04-19T14:18:18.998Z,1492611498.997 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 862 2017-04-19T14:18:18.000Z,1492611499.000 [Rowe_600LCM](INFO): Initializing 2017-04-19T14:18:19.001Z,1492611499.001 [Rowe_600LCM](INFO): Checking LCM 2017-04-19T14:18:19.001Z,1492611499.001 [Rowe_600LCM](INFO): LCM OK 2017-04-19T14:18:19.001Z,1492611499.001 [Rowe_600LCM](INFO): Powering up 2017-04-19T14:18:19.020Z,1492611499.020 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-19T14:18:19.028Z,1492611499.028 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 863 2017-04-19T14:18:19.037Z,1492611499.037 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-04-19T14:18:19.037Z,1492611499.037 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-04-19T14:18:19.037Z,1492611499.037 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-04-19T14:18:19.038Z,1492611499.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-04-19T14:18:19.038Z,1492611499.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-04-19T14:18:19.038Z,1492611499.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-04-19T14:18:19.038Z,1492611499.038 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-04-19T14:18:19.038Z,1492611499.038 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-04-19T14:18:19.039Z,1492611499.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-04-19T14:18:19.039Z,1492611499.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-04-19T14:18:19.039Z,1492611499.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-04-19T14:18:19.039Z,1492611499.039 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-04-19T14:18:19.040Z,1492611499.039 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-04-19T14:18:19.040Z,1492611499.040 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-04-19T14:18:19.040Z,1492611499.040 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-04-19T14:18:19.040Z,1492611499.040 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-04-19T14:18:19.092Z,1492611499.092 [MissionManager](DEBUG): 2017-04-19T14:18:19.093Z,1492611499.093 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-04-19T14:18:19.179Z,1492611499.179 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-04-19T14:18:19.180Z,1492611499.180 [Default:A.Wait](DEBUG): Construct Wait. 2017-04-19T14:18:19.182Z,1492611499.182 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-19T14:18:19.204Z,1492611499.204 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-04-19T14:18:19.204Z,1492611499.204 [CTD_Seabird](INFO): Powering down 2017-04-19T14:18:19.252Z,1492611499.252 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-04-19T14:18:19.255Z,14926114