2017-09-25T13:34:49.577Z,1506346489.577 [Supervisor](DEBUG): Initializing supervisor. 2017-09-25T13:34:49.580Z,1506346489.580 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-09-25T13:34:49.581Z,1506346489.581 [SyncHandler](INFO): Protected caller Thread ID is 769 2017-09-25T13:34:49.581Z,1506346489.581 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-09-25T13:34:49.582Z,1506346489.582 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-09-25T13:34:49.583Z,1506346489.583 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770 2017-09-25T13:34:49.585Z,1506346489.585 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-09-25T13:34:49.598Z,1506346489.598 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-09-25T13:34:49.599Z,1506346489.599 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-09-25T13:34:49.599Z,1506346489.599 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771 2017-09-25T13:34:49.600Z,1506346489.600 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-09-25T13:34:49.601Z,1506346489.601 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-09-25T13:34:49.601Z,1506346489.601 [logger ThreadHandler](INFO): Protected caller Thread ID is 772 2017-09-25T13:34:49.603Z,1506346489.603 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-09-25T13:34:49.604Z,1506346489.604 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-09-25T13:34:49.607Z,1506346489.607 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-09-25T13:34:49.803Z,1506346489.803 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-09-25T13:34:49.804Z,1506346489.804 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-09-25T13:34:49.941Z,1506346489.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-09-25T13:34:49.942Z,1506346489.942 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-09-25T13:34:50.302Z,1506346490.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-09-25T13:34:50.304Z,1506346490.304 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-09-25T13:34:50.448Z,1506346490.448 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-09-25T13:34:50.449Z,1506346490.449 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-09-25T13:34:50.536Z,1506346490.536 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-09-25T13:34:50.745Z,1506346490.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-09-25T13:34:50.745Z,1506346490.745 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-09-25T13:34:50.833Z,1506346490.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-09-25T13:34:50.834Z,1506346490.834 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-09-25T13:34:51.133Z,1506346491.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-09-25T13:34:51.134Z,1506346491.134 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-09-25T13:34:51.505Z,1506346491.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-09-25T13:34:51.506Z,1506346491.506 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-09-25T13:34:51.823Z,1506346491.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-09-25T13:34:51.824Z,1506346491.824 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-09-25T13:34:52.463Z,1506346492.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-09-25T13:34:55.831Z,1506346495.831 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-09-25T13:34:56.079Z,1506346496.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-09-25T13:34:56.080Z,1506346496.080 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-09-25T13:34:56.187Z,1506346496.187 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-09-25T13:34:56.188Z,1506346496.188 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-09-25T13:34:56.663Z,1506346496.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-09-25T13:34:56.664Z,1506346496.664 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-09-25T13:34:56.779Z,1506346496.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-09-25T13:34:56.781Z,1506346496.781 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2017-09-25T13:34:56.784Z,1506346496.784 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2017-09-25T13:34:57.016Z,1506346497.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-09-25T13:34:57.017Z,1506346497.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2017-09-25T13:34:57.122Z,1506346497.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2017-09-25T13:34:57.234Z,1506346497.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2017-09-25T13:34:57.337Z,1506346497.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2017-09-25T13:34:57.429Z,1506346497.429 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2017-09-25T13:34:57.587Z,1506346497.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2017-09-25T13:35:01.160Z,1506346501.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2017-09-25T13:35:01.277Z,1506346501.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2017-09-25T13:35:01.381Z,1506346501.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2017-09-25T13:35:01.471Z,1506346501.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2017-09-25T13:35:01.647Z,1506346501.647 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-09-25T13:35:01.651Z,1506346501.651 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-09-25T13:35:01.802Z,1506346501.802 [SBIT](DEBUG): Construct Startup Built In Test. 2017-09-25T13:35:01.832Z,1506346501.832 [SBIT] Loaded 2017-09-25T13:35:01.832Z,1506346501.832 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-09-25T13:35:01.833Z,1506346501.833 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-09-25T13:35:01.861Z,1506346501.861 [IBIT] Loaded 2017-09-25T13:35:01.861Z,1506346501.861 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-09-25T13:35:01.865Z,1506346501.865 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-09-25T13:35:02.012Z,1506346502.012 [CBIT] Loaded 2017-09-25T13:35:02.013Z,1506346502.013 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-09-25T13:35:02.013Z,1506346502.013 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-09-25T13:35:02.014Z,1506346502.014 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-09-25T13:35:02.103Z,1506346502.103 [VerticalControl](DEBUG): Construct VerticalControl. 2017-09-25T13:35:02.202Z,1506346502.202 [VerticalControl] Loaded 2017-09-25T13:35:02.202Z,1506346502.202 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-09-25T13:35:02.203Z,1506346502.203 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-09-25T13:35:02.265Z,1506346502.265 [HorizontalControl] Loaded 2017-09-25T13:35:02.265Z,1506346502.265 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-09-25T13:35:02.266Z,1506346502.266 [SpeedControl](DEBUG): Construct SpeedControl. 2017-09-25T13:35:02.271Z,1506346502.271 [SpeedControl] Loaded 2017-09-25T13:35:02.272Z,1506346502.272 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-09-25T13:35:02.272Z,1506346502.272 [BackSeatDriver](DEBUG): Construct BackSeatDriver. 2017-09-25T13:35:02.275Z,1506346502.275 [BackSeatDriver] Loaded 2017-09-25T13:35:02.275Z,1506346502.275 [ComponentRegistry](DEBUG): SyncComponent "BackSeatDriver" handled in the control thread. 2017-09-25T13:35:02.276Z,1506346502.276 [LoopControl](DEBUG): Construct LoopControl. 2017-09-25T13:35:02.276Z,1506346502.276 [LoopControl] Loaded 2017-09-25T13:35:02.277Z,1506346502.277 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-09-25T13:35:02.277Z,1506346502.277 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-09-25T13:35:02.278Z,1506346502.278 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-09-25T13:35:02.317Z,1506346502.317 [DepthRateCalculator] Loaded 2017-09-25T13:35:02.317Z,1506346502.317 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-09-25T13:35:02.322Z,1506346502.322 [PitchRateCalculator] Loaded 2017-09-25T13:35:02.323Z,1506346502.323 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-09-25T13:35:02.338Z,1506346502.338 [SpeedCalculator] Loaded 2017-09-25T13:35:02.338Z,1506346502.338 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-09-25T13:35:02.360Z,1506346502.360 [TempGradientCalculator] Loaded 2017-09-25T13:35:02.360Z,1506346502.360 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-09-25T13:35:02.375Z,1506346502.375 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-09-25T13:35:02.376Z,1506346502.376 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-09-25T13:35:02.381Z,1506346502.381 [YawRateCalculator] Loaded 2017-09-25T13:35:02.381Z,1506346502.381 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-09-25T13:35:02.381Z,1506346502.381 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-09-25T13:35:02.382Z,1506346502.382 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-09-25T13:35:02.500Z,1506346502.500 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-09-25T13:35:02.500Z,1506346502.500 [StratificationFrontDetector](DEBUG): (re)initializing 2017-09-25T13:35:02.500Z,1506346502.500 [StratificationFrontDetector] Loaded 2017-09-25T13:35:02.501Z,1506346502.501 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-09-25T13:35:02.501Z,1506346502.501 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-09-25T13:35:02.502Z,1506346502.502 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-09-25T13:35:02.768Z,1506346502.768 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-09-25T13:35:02.769Z,1506346502.769 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-09-25T13:35:03.843Z,1506346503.843 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-09-25T13:35:03.843Z,1506346503.843 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-09-25T13:35:03.896Z,1506346503.896 [DeadReckonUsingSpeedCalculator] Loaded 2017-09-25T13:35:03.897Z,1506346503.897 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-09-25T13:35:03.950Z,1506346503.950 [DeadReckonWithRespectToSeafloor] Loaded 2017-09-25T13:35:03.950Z,1506346503.950 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-09-25T13:35:03.967Z,1506346503.967 [NavChart] Loaded 2017-09-25T13:35:03.967Z,1506346503.967 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-09-25T13:35:03.971Z,1506346503.971 [UniversalFixResidualReporter] Loaded 2017-09-25T13:35:03.972Z,1506346503.972 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-09-25T13:35:03.972Z,1506346503.972 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-09-25T13:35:03.973Z,1506346503.973 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-09-25T13:35:03.982Z,1506346503.982 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-09-25T13:35:03.983Z,1506346503.983 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-09-25T13:35:04.168Z,1506346504.168 [Aanderaa_O2] Loaded 2017-09-25T13:35:04.168Z,1506346504.168 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-09-25T13:35:04.203Z,1506346504.203 [CTD_Seabird] Loaded 2017-09-25T13:35:04.203Z,1506346504.203 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-09-25T13:35:04.205Z,1506346504.205 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4077A4E0 2017-09-25T13:35:04.205Z,1506346504.205 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 852 2017-09-25T13:35:04.220Z,1506346504.220 [PAR_Licor] Loaded 2017-09-25T13:35:04.220Z,1506346504.220 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-09-25T13:35:04.268Z,1506346504.268 [WetLabsBB2FL] Loaded 2017-09-25T13:35:04.269Z,1506346504.269 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-09-25T13:35:04.270Z,1506346504.270 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AA4E0 2017-09-25T13:35:04.270Z,1506346504.270 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853 2017-09-25T13:35:04.271Z,1506346504.271 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-09-25T13:35:04.271Z,1506346504.271 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-09-25T13:35:04.881Z,1506346504.881 [DataOverHttps] Loaded 2017-09-25T13:35:04.882Z,1506346504.882 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-09-25T13:35:04.896Z,1506346504.896 [Depth_Keller] Loaded 2017-09-25T13:35:04.896Z,1506346504.896 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-09-25T13:35:04.901Z,1506346504.901 [DropWeight] Loaded 2017-09-25T13:35:04.901Z,1506346504.901 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-09-25T13:35:04.989Z,1506346504.989 [NAL9602] Loaded 2017-09-25T13:35:04.990Z,1506346504.990 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-09-25T13:35:04.999Z,1506346504.999 [Onboard] Loaded 2017-09-25T13:35:04.999Z,1506346504.999 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-09-25T13:35:05.006Z,1506346505.006 [Radio_Surface] Loaded 2017-09-25T13:35:05.006Z,1506346505.006 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-09-25T13:35:05.008Z,1506346505.008 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409F54E0 2017-09-25T13:35:05.008Z,1506346505.008 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854 2017-09-25T13:35:05.199Z,1506346505.199 [PNI_TCM] Loaded 2017-09-25T13:35:05.200Z,1506346505.200 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-09-25T13:35:05.261Z,1506346505.261 [Rowe_600LCM] Loaded 2017-09-25T13:35:05.261Z,1506346505.261 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-09-25T13:35:05.262Z,1506346505.262 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0 2017-09-25T13:35:05.263Z,1506346505.263 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 855 2017-09-25T13:35:07.030Z,1506346507.030 [BPC1] Loaded 2017-09-25T13:35:07.030Z,1506346507.030 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-09-25T13:35:07.030Z,1506346507.030 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-09-25T13:35:07.031Z,1506346507.031 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-09-25T13:35:07.149Z,1506346507.149 [BuoyancyServo] Loaded 2017-09-25T13:35:07.149Z,1506346507.149 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-09-25T13:35:07.162Z,1506346507.162 [ElevatorServo] Loaded 2017-09-25T13:35:07.162Z,1506346507.162 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-09-25T13:35:07.174Z,1506346507.174 [MassServo] Loaded 2017-09-25T13:35:07.174Z,1506346507.174 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-09-25T13:35:07.186Z,1506346507.186 [RudderServo] Loaded 2017-09-25T13:35:07.186Z,1506346507.186 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-09-25T13:35:07.198Z,1506346507.198 [ThrusterServo] Loaded 2017-09-25T13:35:07.198Z,1506346507.198 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-09-25T13:35:07.198Z,1506346507.198 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-09-25T13:35:07.199Z,1506346507.199 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-09-25T13:35:07.326Z,1506346507.326 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-09-25T13:35:07.327Z,1506346507.327 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-09-25T13:35:07.366Z,1506346507.366 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-09-25T13:35:07.370Z,1506346507.370 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-09-25T13:35:07.371Z,1506346507.371 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-09-25T13:35:07.378Z,1506346507.378 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-09-25T13:35:07.379Z,1506346507.379 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD64E0 2017-09-25T13:35:07.379Z,1506346507.379 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 856 2017-09-25T13:35:07.384Z,1506346507.384 [Supervisor](INFO): Main Thread ID is 764 2017-09-25T13:35:07.384Z,1506346507.384 [Supervisor](DEBUG): Running supervisor. 2017-09-25T13:35:07.385Z,1506346507.385 [CommandLine ThreadHandler](INFO): Handler Thread ID is 857 2017-09-25T13:35:07.388Z,1506346507.388 [controlThread ThreadHandler](INFO): Handler Thread ID is 858 2017-09-25T13:35:07.388Z,1506346507.388 [controlThread](DEBUG): Initializing ControlThread 2017-09-25T13:35:07.389Z,1506346507.389 [SBIT](INFO): Initialize SBIT Component. 2017-09-25T13:35:07.390Z,1506346507.390 [SBIT](IMPORTANT): git: 2017-04-25-53-gcaea116 2017-09-25T13:35:07.390Z,1506346507.390 [SBIT](INFO): git hash: caea116986ae94020af0fb1f22ca68d0c0be9268 2017-09-25T13:35:07.391Z,1506346507.391 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-09-25T13:35:07.392Z,1506346507.392 [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-09-25T13:35:07.393Z,1506346507.393 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2017-09-25T13:35:07.394Z,1506346507.394 [IBIT](INFO): Initialize IBIT Component. 2017-09-25T13:35:07.394Z,1506346507.394 [CBIT](DEBUG): Initialize CBIT Component. 2017-09-25T13:35:07.395Z,1506346507.395 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-09-25T13:35:07.395Z,1506346507.395 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-09-25T13:35:07.396Z,1506346507.396 [logger ThreadHandler](INFO): Handler Thread ID is 859 2017-09-25T13:35:07.422Z,1506346507.422 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 860 2017-09-25T13:35:07.423Z,1506346507.423 [CTD_Seabird](INFO): Initializing 2017-09-25T13:35:07.467Z,1506346507.467 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 862 2017-09-25T13:35:07.468Z,1506346507.468 [WetLabsBB2FL](INFO): Powering down 2017-09-25T13:35:07.478Z,1506346507.478 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-09-25T13:35:07.481Z,1506346507.481 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-09-25T13:35:07.481Z,1506346507.481 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-09-25T13:35:07.482Z,1506346507.482 [BackSeatDriver](DEBUG): Initialize BackSeatDriver Component. 2017-09-25T13:35:07.592Z,1506346507.592 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 863 2017-09-25T13:35:07.599Z,1506346507.599 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 864 2017-09-25T13:35:07.599Z,1506346507.599 [Rowe_600LCM](INFO): Initializing 2017-09-25T13:35:07.600Z,1506346507.600 [Rowe_600LCM](INFO): Checking LCM 2017-09-25T13:35:07.601Z,1506346507.601 [Radio_Surface](INFO): Powering up 2017-09-25T13:35:07.634Z,1506346507.634 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 865 2017-09-25T13:35:07.753Z,1506346507.753 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-09-25T13:35:07.753Z,1506346507.753 [CTD_Seabird](INFO): Powering down 2017-09-25T13:35:08.264Z,1506346508.264 [Rowe_600LCM](INFO): LCM OK 2017-09-25T13:35:08.264Z,1506346508.264 [Rowe_600LCM](INFO): Powering up 2017-09-25T13:35:08.562Z,1506346508.562 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-09-25T13:35:08.562Z,1506346508.562 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-09-25T13:35:08.562Z,1506346508.562 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-09-25T13:35:08.562Z,1506346508.562 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-09-25T13:35:08.562Z,1506346508.562 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-09-25T13:35:08.563Z,1506346508.563 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-09-25T13:35:08.563Z,1506346508.563 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-09-25T13:35:08.563Z,1506346508.563 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-09-25T13:35:08.563Z,1506346508.563 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-09-25T13:35:08.563Z,1506346508.563 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-09-25T13:35:08.564Z,1506346508.564 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-09-25T13:35:08.564Z,1506346508.564 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-09-25T13:35:08.564Z,1506346508.564 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-09-25T13:35:08.564Z,1506346508.564 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-09-25T13:35:08.564Z,1506346508.564 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-09-25T13:35:08.565Z,1506346508.565 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-09-25T13:35:08.633Z,1506346508.633 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-09-25T13:35:08.633Z,1506346508.633 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-09-25T13:35:08.634Z,1506346508.634 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-09-25T13:35:08.634Z,1506346508.634 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-09-25T13:35:08.635Z,1506346508.635 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-09-25T13:35:08.635Z,1506346508.635 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-09-25T13:35:08.644Z,1506346508.644 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-09-25T13:35:08.644Z,1506346508.644 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-09-25T13:35:08.645Z,1506346508.645 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-09-25T13:35:08.646Z,1506346508.646 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-09-25T13:35:08.646Z,1506346508.646 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-09-25T13:35:08.647Z,1506346508.647 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-09-25T13:35:08.647Z,1506346508.647 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-09-25T13:35:08.647Z,1506346508.647 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-09-25T13:35:08.648Z,1506346508.648 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-09-25T13:35:08.648Z,1506346508.648 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-09-25T13:35:08.649Z,1506346508.649 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-09-25T13:35:08.649Z,1506346508.649 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-09-25T13:35:08.674Z,1506346508.674 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-09-25T13:35:08.715Z,1506346508.715 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-25T13:35:08.749Z,1506346508.749 [MissionManager](DEBUG): 2017-09-25T13:35:08.750Z,1506346508.750 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-09-25T13:35:08.811Z,1506346508.811 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-09-25T13:35:08.828Z,1506346508.828 [Default:A.Wait](DEBUG): Construct Wait. 2017-09-25T13:35:08.830Z,1506346508.830 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-25T13:35:08.856Z,1506346508.856 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-09-25T13:35:08.859Z,1506346508.859 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-09-25T13:35:08.876Z,1506346508.876 [Default:E.E