2017-05-11T17:48:16.237Z,1494524896.237 [Supervisor](DEBUG): Initializing supervisor. 2017-05-11T17:48:16.241Z,1494524896.241 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-05-11T17:48:16.241Z,1494524896.241 [SyncHandler](INFO): Protected caller Thread ID is 769 2017-05-11T17:48:16.242Z,1494524896.242 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-05-11T17:48:16.246Z,1494524896.246 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-05-11T17:48:16.246Z,1494524896.246 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770 2017-05-11T17:48:16.249Z,1494524896.249 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-05-11T17:48:16.262Z,1494524896.262 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-05-11T17:48:16.266Z,1494524896.266 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-05-11T17:48:16.266Z,1494524896.266 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771 2017-05-11T17:48:16.267Z,1494524896.267 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-05-11T17:48:16.270Z,1494524896.270 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-05-11T17:48:16.270Z,1494524896.270 [logger ThreadHandler](INFO): Protected caller Thread ID is 772 2017-05-11T17:48:16.272Z,1494524896.272 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-05-11T17:48:16.273Z,1494524896.273 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-05-11T17:48:16.277Z,1494524896.277 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-05-11T17:48:16.371Z,1494524896.371 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-05-11T17:48:16.745Z,1494524896.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-05-11T17:48:16.746Z,1494524896.746 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-05-11T17:48:16.898Z,1494524896.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-05-11T17:48:16.899Z,1494524896.899 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-05-11T17:48:17.126Z,1494524897.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-05-11T17:48:17.127Z,1494524897.127 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-05-11T17:48:17.238Z,1494524897.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-05-11T17:48:17.239Z,1494524897.239 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-05-11T17:48:17.462Z,1494524897.462 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-05-11T17:48:17.464Z,1494524897.464 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-05-11T17:48:17.995Z,1494524897.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-05-11T17:48:17.996Z,1494524897.996 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-05-11T17:48:18.329Z,1494524898.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-05-11T17:48:18.330Z,1494524898.330 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-05-11T17:48:22.692Z,1494524902.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-05-11T17:48:22.693Z,1494524902.693 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-05-11T17:48:22.850Z,1494524902.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-05-11T17:48:22.851Z,1494524902.851 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-05-11T17:48:23.078Z,1494524903.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-05-11T17:48:23.079Z,1494524903.079 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-05-11T17:48:23.580Z,1494524903.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-05-11T17:48:23.581Z,1494524903.581 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-05-11T17:48:28.350Z,1494524908.350 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-05-11T17:48:28.350Z,1494524908.351 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-05-11T17:48:28.710Z,1494524908.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-05-11T17:48:28.711Z,1494524908.711 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-05-11T17:48:28.898Z,1494524908.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-05-11T17:48:28.900Z,1494524908.900 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2017-05-11T17:48:28.905Z,1494524908.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2017-05-11T17:48:29.023Z,1494524909.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2017-05-11T17:48:29.126Z,1494524909.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2017-05-11T17:48:29.227Z,1494524909.227 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2017-05-11T17:48:29.339Z,1494524909.339 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2017-05-11T17:48:29.440Z,1494524909.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2017-05-11T17:48:29.585Z,1494524909.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2017-05-11T17:48:29.691Z,1494524909.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2017-05-11T17:48:29.899Z,1494524909.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2017-05-11T17:48:30.167Z,1494524910.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-05-11T17:48:30.167Z,1494524910.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2017-05-11T17:48:30.347Z,1494524910.347 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2017-05-11T17:48:30.460Z,1494524910.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2017-05-11T17:48:30.549Z,1494524910.549 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-05-11T17:48:30.553Z,1494524910.553 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-05-11T17:48:30.631Z,1494524910.631 [VerticalControl](DEBUG): Construct VerticalControl. 2017-05-11T17:48:30.765Z,1494524910.765 [VerticalControl] Loaded 2017-05-11T17:48:30.765Z,1494524910.765 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-05-11T17:48:30.766Z,1494524910.766 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-05-11T17:48:30.847Z,1494524910.847 [HorizontalControl] Loaded 2017-05-11T17:48:30.847Z,1494524910.847 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-05-11T17:48:30.848Z,1494524910.848 [SpeedControl](DEBUG): Construct SpeedControl. 2017-05-11T17:48:30.854Z,1494524910.854 [SpeedControl] Loaded 2017-05-11T17:48:30.854Z,1494524910.854 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-05-11T17:48:30.855Z,1494524910.855 [LoopControl](DEBUG): Construct LoopControl. 2017-05-11T17:48:30.855Z,1494524910.855 [LoopControl] Loaded 2017-05-11T17:48:30.856Z,1494524910.856 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-05-11T17:48:30.856Z,1494524910.856 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-05-11T17:48:30.857Z,1494524910.857 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-05-11T17:48:35.426Z,1494524915.427 [SBIT](DEBUG): Construct Startup Built In Test. 2017-05-11T17:48:35.439Z,1494524915.439 [SBIT] Loaded 2017-05-11T17:48:35.439Z,1494524915.439 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-05-11T17:48:35.440Z,1494524915.440 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-05-11T17:48:35.469Z,1494524915.469 [IBIT] Loaded 2017-05-11T17:48:35.470Z,1494524915.470 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-05-11T17:48:35.473Z,1494524915.473 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-05-11T17:48:35.625Z,1494524915.625 [CBIT] Loaded 2017-05-11T17:48:35.625Z,1494524915.625 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-05-11T17:48:35.626Z,1494524915.626 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-05-11T17:48:35.924Z,1494524915.924 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-05-11T17:48:35.979Z,1494524915.979 [DepthRateCalculator] Loaded 2017-05-11T17:48:35.979Z,1494524915.979 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-05-11T17:48:35.985Z,1494524915.985 [PitchRateCalculator] Loaded 2017-05-11T17:48:35.985Z,1494524915.985 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-05-11T17:48:36.017Z,1494524916.017 [SpeedCalculator] Loaded 2017-05-11T17:48:36.017Z,1494524916.017 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-05-11T17:48:36.040Z,1494524916.040 [TempGradientCalculator] Loaded 2017-05-11T17:48:36.040Z,1494524916.040 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-05-11T17:48:36.056Z,1494524916.056 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-05-11T17:48:36.057Z,1494524916.057 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-05-11T17:48:36.062Z,1494524916.062 [YawRateCalculator] Loaded 2017-05-11T17:48:36.062Z,1494524916.062 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-05-11T17:48:36.063Z,1494524916.063 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-05-11T17:48:36.064Z,1494524916.064 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-05-11T17:48:36.187Z,1494524916.187 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-05-11T17:48:36.188Z,1494524916.188 [StratificationFrontDetector](DEBUG): (re)initializing 2017-05-11T17:48:36.188Z,1494524916.188 [StratificationFrontDetector] Loaded 2017-05-11T17:48:36.188Z,1494524916.188 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-05-11T17:48:36.212Z,1494524916.212 [DepAvgTempFrontDetector] Loaded 2017-05-11T17:48:36.213Z,1494524916.213 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread. 2017-05-11T17:48:36.213Z,1494524916.213 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-05-11T17:48:36.214Z,1494524916.214 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-05-11T17:48:36.503Z,1494524916.503 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-05-11T17:48:36.503Z,1494524916.503 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-05-11T17:48:36.631Z,1494524916.631 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-05-11T17:48:36.632Z,1494524916.632 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-05-11T17:48:36.688Z,1494524916.688 [DeadReckonUsingSpeedCalculator] Loaded 2017-05-11T17:48:36.688Z,1494524916.688 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-05-11T17:48:36.706Z,1494524916.706 [NavChart] Loaded 2017-05-11T17:48:36.706Z,1494524916.706 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-05-11T17:48:36.711Z,1494524916.711 [UniversalFixResidualReporter] Loaded 2017-05-11T17:48:36.711Z,1494524916.711 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-05-11T17:48:36.711Z,1494524916.711 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-05-11T17:48:36.712Z,1494524916.712 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-05-11T17:48:36.721Z,1494524916.721 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-05-11T17:48:36.722Z,1494524916.722 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-05-11T17:48:36.917Z,1494524916.917 [Aanderaa_O2] Loaded 2017-05-11T17:48:36.918Z,1494524916.918 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-05-11T17:48:36.999Z,1494524916.999 [CTD_NeilBrown] Loaded 2017-05-11T17:48:36.999Z,1494524917.000 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-05-11T17:48:37.001Z,1494524917.001 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407734E0 2017-05-11T17:48:37.001Z,1494524917.001 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 855 2017-05-11T17:48:37.332Z,1494524917.332 [CTD_Seabird] Loaded 2017-05-11T17:48:37.333Z,1494524917.332 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-05-11T17:48:37.334Z,1494524917.334 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407A34E0 2017-05-11T17:48:37.334Z,1494524917.334 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 856 2017-05-11T17:48:37.350Z,1494524917.350 [PAR_Licor] Loaded 2017-05-11T17:48:37.350Z,1494524917.350 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-05-11T17:48:37.401Z,1494524917.401 [WetLabsBB2FL] Loaded 2017-05-11T17:48:37.401Z,1494524917.401 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-05-11T17:48:37.403Z,1494524917.403 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407D34E0 2017-05-11T17:48:37.403Z,1494524917.403 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 857 2017-05-11T17:48:37.403Z,1494524917.404 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-05-11T17:48:37.404Z,1494524917.404 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-05-11T17:48:38.029Z,1494524918.029 [DataOverHttps] Loaded 2017-05-11T17:48:38.029Z,1494524918.029 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-05-11T17:48:38.043Z,1494524918.043 [Depth_Keller] Loaded 2017-05-11T17:48:38.043Z,1494524918.043 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-05-11T17:48:38.049Z,1494524918.049 [DropWeight] Loaded 2017-05-11T17:48:38.049Z,1494524918.049 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-05-11T17:48:38.143Z,1494524918.143 [NAL9602] Loaded 2017-05-11T17:48:38.143Z,1494524918.143 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-05-11T17:48:38.153Z,1494524918.153 [Onboard] Loaded 2017-05-11T17:48:38.153Z,1494524918.153 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-05-11T17:48:38.160Z,1494524918.160 [Radio_Surface] Loaded 2017-05-11T17:48:38.161Z,1494524918.161 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-05-11T17:48:38.162Z,1494524918.162 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0 2017-05-11T17:48:38.162Z,1494524918.162 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 858 2017-05-11T17:48:38.357Z,1494524918.357 [PNI_TCM] Loaded 2017-05-11T17:48:38.357Z,1494524918.357 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-05-11T17:48:38.422Z,1494524918.422 [Rowe_600LCM] Loaded 2017-05-11T17:48:38.422Z,1494524918.422 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-05-11T17:48:38.423Z,1494524918.423 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0 2017-05-11T17:48:38.424Z,1494524918.424 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 859 2017-05-11T17:48:40.288Z,1494524920.288 [BPC1] Loaded 2017-05-11T17:48:40.289Z,1494524920.289 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-05-11T17:48:40.289Z,1494524920.289 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-05-11T17:48:40.290Z,1494524920.290 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-05-11T17:48:40.460Z,1494524920.460 [BuoyancyServo] Loaded 2017-05-11T17:48:40.460Z,1494524920.460 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-05-11T17:48:40.473Z,1494524920.473 [ElevatorServo] Loaded 2017-05-11T17:48:40.473Z,1494524920.473 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-05-11T17:48:40.485Z,1494524920.485 [MassServo] Loaded 2017-05-11T17:48:40.485Z,1494524920.485 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-05-11T17:48:40.498Z,1494524920.498 [RudderServo] Loaded 2017-05-11T17:48:40.498Z,1494524920.498 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-05-11T17:48:40.510Z,1494524920.510 [ThrusterServo] Loaded 2017-05-11T17:48:40.510Z,1494524920.510 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-05-11T17:48:40.510Z,1494524920.510 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-05-11T17:48:40.511Z,1494524920.511 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-05-11T17:48:40.646Z,1494524920.646 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-05-11T17:48:40.647Z,1494524920.647 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-05-11T17:48:40.688Z,1494524920.688 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-05-11T17:48:40.692Z,1494524920.692 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-05-11T17:48:40.693Z,1494524920.693 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-05-11T17:48:40.700Z,1494524920.700 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-05-11T17:48:40.701Z,1494524920.701 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0 2017-05-11T17:48:40.702Z,1494524920.702 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 860 2017-05-11T17:48:40.706Z,1494524920.707 [Supervisor](INFO): Main Thread ID is 764 2017-05-11T17:48:40.707Z,1494524920.707 [Supervisor](DEBUG): Running supervisor. 2017-05-11T17:48:40.707Z,1494524920.707 [CommandLine ThreadHandler](INFO): Handler Thread ID is 861 2017-05-11T17:48:40.710Z,1494524920.710 [controlThread ThreadHandler](INFO): Handler Thread ID is 862 2017-05-11T17:48:40.711Z,1494524920.711 [controlThread](DEBUG): Initializing ControlThread 2017-05-11T17:48:40.711Z,1494524920.711 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-05-11T17:48:40.713Z,1494524920.713 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-05-11T17:48:40.714Z,1494524920.714 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-05-11T17:48:40.715Z,1494524920.715 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-05-11T17:48:40.715Z,1494524920.715 [SBIT](INFO): Initialize SBIT Component. 2017-05-11T17:48:40.716Z,1494524920.716 [SBIT](IMPORTANT): git: 2017-04-19-5-g443ab08 2017-05-11T17:48:40.716Z,1494524920.716 [SBIT](INFO): git hash: 443ab0852958b11c27b2467e632b948d2f0b0b23 2017-05-11T17:48:40.717Z,1494524920.717 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-05-11T17:48:40.718Z,1494524920.718 [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-05-11T17:48:40.719Z,1494524920.719 [SBIT](INFO): Beginning SBIT in 32.000000 seconds. 2017-05-11T17:48:40.719Z,1494524920.719 [IBIT](INFO): Initialize IBIT Component. 2017-05-11T17:48:40.720Z,1494524920.720 [CBIT](DEBUG): Initialize CBIT Component. 2017-05-11T17:48:40.720Z,1494524920.720 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-05-11T17:48:40.721Z,1494524920.721 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-05-11T17:48:40.722Z,1494524920.722 [logger ThreadHandler](INFO): Handler Thread ID is 863 2017-05-11T17:48:40.750Z,1494524920.750 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 864 2017-05-11T17:48:40.751Z,1494524920.751 [CTD_NeilBrown](INFO): Powering down 2017-05-11T17:48:40.785Z,1494524920.785 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 865 2017-05-11T17:48:40.786Z,1494524920.786 [CTD_Seabird](INFO): Initializing 2017-05-11T17:48:40.822Z,1494524920.822 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-05-11T17:48:40.823Z,1494524920.823 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-05-11T17:48:40.823Z,1494524920.823 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-05-11T17:48:40.823Z,1494524920.823 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-05-11T17:48:40.824Z,1494524920.824 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-05-11T17:48:40.825Z,1494524920.825 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-05-11T17:48:40.826Z,1494524920.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-05-11T17:48:40.827Z,1494524920.827 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-05-11T17:48:40.827Z,1494524920.827 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-05-11T17:48:40.828Z,1494524920.828 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-05-11T17:48:40.828Z,1494524920.828 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-05-11T17:48:40.829Z,1494524920.829 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-05-11T17:48:40.829Z,1494524920.829 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-05-11T17:48:40.830Z,1494524920.830 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-05-11T17:48:40.842Z,1494524920.842 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-05-11T17:48:40.855Z,1494524920.855 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 867 2017-05-11T17:48:40.857Z,1494524920.857 [WetLabsBB2FL](INFO): Powering down 2017-05-11T17:48:40.896Z,1494524920.896 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-05-11T17:48:40.898Z,1494524920.898 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 868 2017-05-11T17:48:40.905Z,1494524920.905 [Radio_Surface](INFO): Powering up 2017-05-11T17:48:40.928Z,1494524920.928 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 869 2017-05-11T17:48:40.928Z,1494524920.928 [Rowe_600LCM](INFO): Initializing 2017-05-11T17:48:40.929Z,1494524920.929 [Rowe_600LCM](INFO): Checking LCM 2017-05-11T17:48:40.929Z,1494524920.929 [Rowe_600LCM](INFO): LCM OK 2017-05-11T17:48:40.930Z,1494524920.930 [Rowe_600LCM](INFO): Powering up 2017-05-11T17:48:40.943Z,1494524920.943 [MissionManager](DEBUG): 2017-05-11T17:48:40.944Z,1494524920.944 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-05-11T17:48:40.966Z,1494524920.966 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 870 2017-05-11T17:48:40.976Z,1494524920.976 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-05-11T17:48:40.976Z,1494524920.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-05-11T17:48:40.977Z,1494524920.977 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-05-11T17:48:40.977Z,1494524920.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-05-11T17:48:40.977Z,1494524920.977 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-05-11T17:48:40.977Z,1494524920.977 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-05-11T17:48:40.978Z,1494524920.978 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-05-11T17:48:40.978Z,1494524920.978 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-05-11T17:48:40.978Z,1494524920.978 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-05-11T17:48:40.978Z,1494524920.978 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-05-11T17:48:40.978Z,1494524920.978 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-05-11T17:48:40.979Z,1494524920.979 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-05-11T17:48:40.979Z,1494524920.979 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-05-11T17:48:40.979Z,1494524920.979 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-05-11T17:48:40.979Z,1494524920.979 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-05-11T17:48:40.979Z,1494524920.980 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-05-11T17:48:41.073Z,1494524921.073 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-05-11T17:48:41.074Z,1494524921.074 [Default:A.Wait](DEBUG): Construct Wait. 2017-05-11T17:48:41.075Z,1494524921.076 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-05-11T17:48:41.108Z,1494524921.108 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-05-11T17:48:41.108Z,1494524921.108 [CTD_Seabird](INFO): Powering down 2017-05-11T17:48:41.147Z,1494524921.147 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-05-11T17:48:41.150Z,1494524921.150 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-05-11T17:48:41.176Z,1494524921.176 [Default:E.Execute](DEBUG): Construct Execute. 2017-05-11T17:48:41.180Z,1494524921.1