2017-11-04T08:42:07.762Z,1509784927.762 [Supervisor](DEBUG): Initializing supervisor. 2017-11-04T08:42:07.765Z,1509784927.765 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2017-11-04T08:42:07.765Z,1509784927.765 [SyncHandler](INFO): Protected caller Thread ID is 798 2017-11-04T08:42:07.766Z,1509784927.766 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-11-04T08:42:07.767Z,1509784927.767 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2017-11-04T08:42:07.767Z,1509784927.767 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 799 2017-11-04T08:42:07.770Z,1509784927.770 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-11-04T08:42:07.781Z,1509784927.781 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-11-04T08:42:07.782Z,1509784927.782 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2017-11-04T08:42:07.782Z,1509784927.782 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 800 2017-11-04T08:42:07.783Z,1509784927.783 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-11-04T08:42:07.784Z,1509784927.784 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2017-11-04T08:42:07.784Z,1509784927.784 [logger ThreadHandler](INFO): Protected caller Thread ID is 801 2017-11-04T08:42:07.786Z,1509784927.786 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-11-04T08:42:07.786Z,1509784927.786 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-11-04T08:42:07.790Z,1509784927.790 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-11-04T08:42:07.888Z,1509784927.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-11-04T08:42:07.890Z,1509784927.890 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-11-04T08:42:07.989Z,1509784927.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-11-04T08:42:07.990Z,1509784927.990 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-11-04T08:42:08.175Z,1509784928.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-11-04T08:42:08.175Z,1509784928.175 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-11-04T08:42:08.430Z,1509784928.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-11-04T08:42:08.432Z,1509784928.432 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-11-04T08:42:08.746Z,1509784928.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-11-04T08:42:08.747Z,1509784928.747 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-11-04T08:42:09.179Z,1509784929.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-11-04T08:42:09.179Z,1509784929.179 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-11-04T08:42:09.505Z,1509784929.505 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-11-04T08:42:09.506Z,1509784929.506 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-11-04T08:42:09.911Z,1509784929.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-11-04T08:42:09.913Z,1509784929.913 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-11-04T08:42:09.994Z,1509784929.994 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-11-04T08:42:10.276Z,1509784930.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-11-04T08:42:10.277Z,1509784930.277 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-11-04T08:42:10.405Z,1509784930.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-11-04T08:42:10.405Z,1509784930.405 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-11-04T08:42:10.603Z,1509784930.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-11-04T08:42:10.605Z,1509784930.605 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-11-04T08:42:10.797Z,1509784930.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-11-04T08:42:10.798Z,1509784930.798 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-11-04T08:42:10.895Z,1509784930.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-11-04T08:42:10.895Z,1509784930.895 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-11-04T08:42:11.032Z,1509784931.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-11-04T08:42:11.034Z,1509784931.034 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2017-11-04T08:42:11.036Z,1509784931.036 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2017-11-04T08:42:11.299Z,1509784931.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-11-04T08:42:11.299Z,1509784931.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2017-11-04T08:42:12.138Z,1509784932.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2017-11-04T08:42:12.279Z,1509784932.279 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2017-11-04T08:42:12.371Z,1509784932.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2017-11-04T08:42:12.457Z,1509784932.457 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2017-11-04T08:42:12.591Z,1509784932.591 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2017-11-04T08:42:12.755Z,1509784932.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2017-11-04T08:42:12.853Z,1509784932.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2017-11-04T08:42:12.945Z,1509784932.945 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2017-11-04T08:42:13.042Z,1509784933.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2017-11-04T08:42:13.125Z,1509784933.125 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-11-04T08:42:13.129Z,1509784933.129 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-11-04T08:42:13.253Z,1509784933.253 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-11-04T08:42:13.254Z,1509784933.254 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-11-04T08:42:13.278Z,1509784933.278 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-11-04T08:42:13.279Z,1509784933.279 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-11-04T08:42:13.315Z,1509784933.315 [DepthRateCalculator] Loaded 2017-11-04T08:42:13.315Z,1509784933.315 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-11-04T08:42:13.321Z,1509784933.321 [PitchRateCalculator] Loaded 2017-11-04T08:42:13.322Z,1509784933.322 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-11-04T08:42:13.337Z,1509784933.337 [SpeedCalculator] Loaded 2017-11-04T08:42:13.337Z,1509784933.337 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-11-04T08:42:13.358Z,1509784933.358 [TempGradientCalculator] Loaded 2017-11-04T08:42:13.358Z,1509784933.358 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-11-04T08:42:13.373Z,1509784933.373 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-11-04T08:42:13.374Z,1509784933.374 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-11-04T08:42:13.379Z,1509784933.379 [YawRateCalculator] Loaded 2017-11-04T08:42:13.380Z,1509784933.380 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-11-04T08:42:13.380Z,1509784933.380 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-11-04T08:42:13.381Z,1509784933.381 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-11-04T08:42:13.418Z,1509784933.418 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-11-04T08:42:13.418Z,1509784933.418 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-11-04T08:42:13.866Z,1509784933.866 [AcousticModem_Benthos_ATM900] Loaded 2017-11-04T08:42:13.866Z,1509784933.866 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2017-11-04T08:42:14.005Z,1509784934.005 [DataOverHttps] Loaded 2017-11-04T08:42:14.005Z,1509784934.005 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-11-04T08:42:14.018Z,1509784934.018 [Depth_Keller] Loaded 2017-11-04T08:42:14.019Z,1509784934.019 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-11-04T08:42:14.023Z,1509784934.023 [DropWeight] Loaded 2017-11-04T08:42:14.024Z,1509784934.024 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-11-04T08:42:14.119Z,1509784934.119 [NAL9602] Loaded 2017-11-04T08:42:14.119Z,1509784934.119 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-11-04T08:42:14.135Z,1509784934.135 [Onboard] Loaded 2017-11-04T08:42:14.135Z,1509784934.135 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-11-04T08:42:14.145Z,1509784934.145 [Radio_Surface] Loaded 2017-11-04T08:42:14.146Z,1509784934.146 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-11-04T08:42:14.147Z,1509784934.147 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0 2017-11-04T08:42:14.147Z,1509784934.147 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 880 2017-11-04T08:42:14.298Z,1509784934.298 [PNI_TCM] Loaded 2017-11-04T08:42:14.299Z,1509784934.299 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-11-04T08:42:14.358Z,1509784934.358 [Rowe_600LCM] Loaded 2017-11-04T08:42:14.359Z,1509784934.359 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-11-04T08:42:14.360Z,1509784934.360 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0 2017-11-04T08:42:14.360Z,1509784934.360 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 881 2017-11-04T08:42:16.061Z,1509784936.061 [BPC1] Loaded 2017-11-04T08:42:16.061Z,1509784936.061 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-11-04T08:42:16.062Z,1509784936.062 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-11-04T08:42:16.063Z,1509784936.063 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-11-04T08:42:16.217Z,1509784936.217 [SBIT](DEBUG): Construct Startup Built In Test. 2017-11-04T08:42:16.243Z,1509784936.243 [SBIT] Loaded 2017-11-04T08:42:16.244Z,1509784936.244 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-11-04T08:42:16.244Z,1509784936.244 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-11-04T08:42:16.256Z,1509784936.256 [IBIT] Loaded 2017-11-04T08:42:16.256Z,1509784936.256 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-11-04T08:42:16.259Z,1509784936.259 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-11-04T08:42:16.405Z,1509784936.405 [CBIT] Loaded 2017-11-04T08:42:16.406Z,1509784936.406 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-11-04T08:42:16.406Z,1509784936.406 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-11-04T08:42:16.407Z,1509784936.407 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-11-04T08:42:16.745Z,1509784936.745 [Aanderaa_O2] Loaded 2017-11-04T08:42:16.745Z,1509784936.745 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-11-04T08:42:16.821Z,1509784936.821 [CTD_NeilBrown] Loaded 2017-11-04T08:42:16.821Z,1509784936.821 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-11-04T08:42:16.822Z,1509784936.822 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408944E0 2017-11-04T08:42:16.822Z,1509784936.822 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 882 2017-11-04T08:42:16.855Z,1509784936.855 [CTD_Seabird] Loaded 2017-11-04T08:42:16.855Z,1509784936.855 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-11-04T08:42:16.856Z,1509784936.856 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408C44E0 2017-11-04T08:42:16.856Z,1509784936.856 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 883 2017-11-04T08:42:16.873Z,1509784936.873 [ESPComponent] Loaded 2017-11-04T08:42:16.873Z,1509784936.873 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2017-11-04T08:42:16.887Z,1509784936.887 [PAR_Licor] Loaded 2017-11-04T08:42:16.887Z,1509784936.887 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-11-04T08:42:16.936Z,1509784936.936 [WetLabsBB2FL] Loaded 2017-11-04T08:42:16.936Z,1509784936.936 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-11-04T08:42:16.937Z,1509784936.937 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408F44E0 2017-11-04T08:42:16.937Z,1509784936.937 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 884 2017-11-04T08:42:16.938Z,1509784936.938 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-11-04T08:42:16.938Z,1509784936.938 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-11-04T08:42:17.229Z,1509784937.229 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-11-04T08:42:17.230Z,1509784937.230 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-11-04T08:42:17.316Z,1509784937.316 [VerticalControl](DEBUG): Construct VerticalControl. 2017-11-04T08:42:17.413Z,1509784937.413 [VerticalControl] Loaded 2017-11-04T08:42:17.413Z,1509784937.413 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-11-04T08:42:17.414Z,1509784937.414 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-11-04T08:42:17.474Z,1509784937.474 [HorizontalControl] Loaded 2017-11-04T08:42:17.474Z,1509784937.474 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-11-04T08:42:17.475Z,1509784937.475 [SpeedControl](DEBUG): Construct SpeedControl. 2017-11-04T08:42:17.480Z,1509784937.480 [SpeedControl] Loaded 2017-11-04T08:42:17.480Z,1509784937.480 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-11-04T08:42:17.481Z,1509784937.481 [LoopControl](DEBUG): Construct LoopControl. 2017-11-04T08:42:17.482Z,1509784937.482 [LoopControl] Loaded 2017-11-04T08:42:17.482Z,1509784937.482 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-11-04T08:42:17.482Z,1509784937.482 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-11-04T08:42:17.483Z,1509784937.483 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-11-04T08:42:17.600Z,1509784937.600 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-11-04T08:42:17.600Z,1509784937.600 [StratificationFrontDetector](DEBUG): (re)initializing 2017-11-04T08:42:17.600Z,1509784937.600 [StratificationFrontDetector] Loaded 2017-11-04T08:42:17.601Z,1509784937.601 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-11-04T08:42:17.601Z,1509784937.601 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-11-04T08:42:17.602Z,1509784937.602 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-11-04T08:42:17.718Z,1509784937.718 [BuoyancyServo] Loaded 2017-11-04T08:42:17.718Z,1509784937.718 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-11-04T08:42:17.730Z,1509784937.730 [ElevatorServo] Loaded 2017-11-04T08:42:17.730Z,1509784937.730 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-11-04T08:42:17.741Z,1509784937.741 [MassServo] Loaded 2017-11-04T08:42:17.741Z,1509784937.741 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-11-04T08:42:17.752Z,1509784937.752 [RudderServo] Loaded 2017-11-04T08:42:17.752Z,1509784937.752 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-11-04T08:42:17.763Z,1509784937.763 [ThrusterServo] Loaded 2017-11-04T08:42:17.763Z,1509784937.763 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-11-04T08:42:17.764Z,1509784937.764 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-11-04T08:42:17.764Z,1509784937.764 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-11-04T08:42:17.875Z,1509784937.875 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-11-04T08:42:17.876Z,1509784937.876 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-11-04T08:42:17.929Z,1509784937.929 [DeadReckonUsingSpeedCalculator] Loaded 2017-11-04T08:42:17.929Z,1509784937.929 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-11-04T08:42:17.945Z,1509784937.945 [NavChart] Loaded 2017-11-04T08:42:17.945Z,1509784937.945 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-11-04T08:42:17.949Z,1509784937.949 [UniversalFixResidualReporter] Loaded 2017-11-04T08:42:17.950Z,1509784937.950 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-11-04T08:42:17.950Z,1509784937.950 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-11-04T08:42:17.954Z,1509784937.954 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-11-04T08:42:17.955Z,1509784937.955 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-11-04T08:42:17.961Z,1509784937.961 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-11-04T08:42:17.962Z,1509784937.962 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0 2017-11-04T08:42:17.963Z,1509784937.963 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 886 2017-11-04T08:42:17.967Z,1509784937.967 [Supervisor](INFO): Main Thread ID is 794 2017-11-04T08:42:17.968Z,1509784937.968 [Supervisor](DEBUG): Running supervisor. 2017-11-04T08:42:17.968Z,1509784937.968 [CommandLine ThreadHandler](INFO): Handler Thread ID is 887 2017-11-04T08:42:17.971Z,1509784937.971 [controlThread ThreadHandler](INFO): Handler Thread ID is 888 2017-11-04T08:42:17.971Z,1509784937.971 [controlThread](DEBUG): Initializing ControlThread 2017-11-04T08:42:17.972Z,1509784937.972 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-11-04T08:42:17.972Z,1509784937.972 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-11-04T08:42:17.973Z,1509784937.973 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-11-04T08:42:17.973Z,1509784937.973 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-11-04T08:42:17.974Z,1509784937.974 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-11-04T08:42:17.974Z,1509784937.974 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-11-04T08:42:17.979Z,1509784937.979 [SBIT](INFO): Initialize SBIT Component. 2017-11-04T08:42:17.979Z,1509784937.979 [SBIT](IMPORTANT): git: 2017-10-26-27-g28caa4f 2017-11-04T08:42:17.980Z,1509784937.980 [SBIT](INFO): git hash: 28caa4f52d4014465ae2cbbbbc56c9b8302a605b 2017-11-04T08:42:17.980Z,1509784937.980 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2017-11-04T08:42:17.981Z,1509784937.981 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #17 PREEMPT Fri Oct 6 17:22:04 PDT 2017 2017-11-04T08:42:17.982Z,1509784937.982 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2017-11-04T08:42:17.983Z,1509784937.983 [IBIT](INFO): Initialize IBIT Component. 2017-11-04T08:42:17.983Z,1509784937.983 [CBIT](DEBUG): Initialize CBIT Component. 2017-11-04T08:42:17.984Z,1509784937.984 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-11-04T08:42:17.984Z,1509784937.984 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-11-04T08:42:17.985Z,1509784937.985 [logger ThreadHandler](INFO): Handler Thread ID is 889 2017-11-04T08:42:18.005Z,1509784938.005 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 890 2017-11-04T08:42:18.011Z,1509784938.011 [Radio_Surface](INFO): Powering up 2017-11-04T08:42:18.017Z,1509784938.017 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 891 2017-11-04T08:42:18.017Z,1509784938.017 [Rowe_600LCM](INFO): Initializing 2017-11-04T08:42:18.018Z,1509784938.018 [Rowe_600LCM](INFO): Checking LCM 2017-11-04T08:42:18.072Z,1509784938.072 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-11-04T08:42:18.074Z,1509784938.074 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-11-04T08:42:18.075Z,1509784938.075 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-11-04T08:42:18.075Z,1509784938.075 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-11-04T08:42:18.077Z,1509784938.077 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-11-04T08:42:18.118Z,1509784938.118 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-11-04T08:42:18.118Z,1509784938.118 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-11-04T08:42:18.118Z,1509784938.118 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-11-04T08:42:18.119Z,1509784938.119 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-11-04T08:42:18.119Z,1509784938.119 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-11-04T08:42:18.120Z,1509784938.120 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-11-04T08:42:18.120Z,1509784938.120 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-11-04T08:42:18.121Z,1509784938.121 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-11-04T08:42:18.122Z,1509784938.122 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 892 2017-11-04T08:42:18.123Z,1509784938.123 [CTD_NeilBrown](INFO): Powering down 2017-11-04T08:42:18.134Z,1509784938.134 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 893 2017-11-04T08:42:18.134Z,1509784938.134 [CTD_Seabird](INFO): Initializing 2017-11-04T08:42:18.289Z,1509784938.289 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 895 2017-11-04T08:42:18.290Z,1509784938.290 [WetLabsBB2FL](INFO): Powering down 2017-11-04T08:42:18.317Z,1509784938.317 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 896 2017-11-04T08:42:18.326Z,1509784938.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-11-04T08:42:18.326Z,1509784938.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-11-04T08:42:18.326Z,1509784938.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-11-04T08:42:18.326Z,1509784938.326 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-11-04T08:42:18.326Z,1509784938.326 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-11-04T08:42:18.327Z,1509784938.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-11-04T08:42:18.327Z,1509784938.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-11-04T08:42:18.327Z,1509784938.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-11-04T08:42:18.327Z,1509784938.327 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-11-04T08:42:18.327Z,1509784938.327 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-11-04T08:42:18.328Z,1509784938.328 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-11-04T08:42:18.328Z,1509784938.328 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-11-04T08:42:18.328Z,1509784938.328 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-11-04T08:42:18.328Z,1509784938.328 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-11-04T08:42:18.328Z,1509784938.328 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-11-04T08:42:18.329Z,1509784938.329 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-11-04T08:42:18.408Z,1509784938.408 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-11-04T08:42:18.408Z,1509784938.408 [CTD_Seabird](INFO): Powering down 2017-11-04T08:42:18.441Z,1509784938.441 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-11-04T08:42:18.522Z,1509784938.522 [MissionManager](DEBUG): 2017-11-04T08:42:18.527Z,1509784938.527 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-11-04T08:42:18.556Z,1509784938.556 [Rowe_600LCM](FAULT): LCM not connected 2017-11-04T08:42:18.556Z,1509784938.556 [Rowe_600LCM] Hardware Fault, FailCount= 1 2017-11-04T08:42:18.556Z,1509784938.556 [Rowe_600LCM](ERROR): Hardware Fault 2017-11-04T08:42:18.629Z,1509784938.629 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-11-04T08:42:18.630Z,1509784938.630 [Default:A.Wait](DEBUG): Construct Wait. 2017-11-04T08:42:18.632Z,1509784938.632 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-11-04T08:42:18.665Z,1509784938.665 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-11-04T08:42:18.667Z,1509784938.667 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-11-04T08:42:18.685Z,1509784938.685 [Default:E.Execute](DEBUG): Construct Execute. 2017-11-04T08:42:18.698Z,1509784938.698 [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 2017-11-04T08:42:18.706Z,1509784938.706 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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, 2017-11-04T08:42:18.732Z,1509784938.732 [AcousticModem_Benthos_ATM900](INFO): Powering up 2017-11-04T08:42:18.733Z,1509784938.733 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2017-11-04T08:42:18.762Z,1509784938.762 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-11-04T08:42:18.996Z,1509784938.996 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-11-04T08:42:19.165Z,1509784939.165 [ESPComponent](INFO): powering down ESP 2017-11-04T08:42:19.297Z,1509784939.297 [Rowe_600LCM](INFO): Powering down 2017-11-04T08:42:19.680Z,1509784939.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:19.766Z,1509784939.766 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:19.869Z,1509784939.869 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-11-04T08:42:19.876Z,1509784939.876 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-11-04T08:42:19.891Z,1509784939.891 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-11-04T08:42:19.896Z,1509784939.896 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-11-04T08:42:19.908Z,1509784939.908 [MassServo](DEBUG): Initializing EZServoServo. 2017-11-04T08:42:19.916Z,1509784939.916 [MassServo](DEBUG): Initializing MassServo. 2017-11-04T08:42:19.934Z,1509784939.934 [RudderServo](DEBUG): Initializing EZServoServo. 2017-11-04T08:42:19.940Z,1509784939.940 [RudderServo](DEBUG): Initializing RudderServo. 2017-11-04T08:42:19.955Z,1509784939.955 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-11-04T08:42:19.960Z,1509784939.960 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-11-04T08:42:20.005Z,1509784940.005 [CommandLine](FAULT): Scheduling is paused 2017-11-04T08:42:20.005Z,1509784940.005 [CBIT](INFO): Critical error at 20171104T084217 2017-11-04T08:42:20.006Z,1509784940.006 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-11-04T08:42:20.009Z,1509784940.009 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2017-11-04T08:42:20.216Z,1509784940.216 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:20.217Z,1509784940.217 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:20.516Z,1509784940.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:20.517Z,1509784940.517 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:20.864Z,1509784940.864 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-11-04T08:42:20.864Z,1509784940.864 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-11-04T08:42:20.864Z,1509784940.864 [BuoyancyServo] Communications Fault, FailCount= 1 2017-11-04T08:42:20.864Z,1509784940.864 [BuoyancyServo](ERROR): Communications Fault 2017-11-04T08:42:21.071Z,1509784941.071 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-11-04T08:42:21.071Z,1509784941.071 [Rowe_600LCM] No Fault, FailCount= 1 2017-11-04T08:42:21.073Z,1509784941.073 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-11-04T08:42:21.125Z,1509784941.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:21.126Z,1509784941.126 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:21.146Z,1509784941.146 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-11-04T08:42:21.146Z,1509784941.146 [BuoyancyServo](INFO): Powering down 2017-11-04T08:42:21.356Z,1509784941.356 [Rowe_600LCM](INFO): Initializing 2017-11-04T08:42:21.357Z,1509784941.357 [Rowe_600LCM](INFO): Checking LCM 2017-11-04T08:42:21.357Z,1509784941.357 [Rowe_600LCM](FAULT): LCM not connected 2017-11-04T08:42:21.357Z,1509784941.357 [Rowe_600LCM] Hardware Fault, FailCount= 2 2017-11-04T08:42:21.357Z,1509784941.357 [Rowe_600LCM](ERROR): Hardware Fault 2017-11-04T08:42:21.536Z,1509784941.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:21.549Z,1509784941.549 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:21.575Z,1509784941.575 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2017-11-04T08:42:21.760Z,1509784941.760 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-11-04T08:42:21.938Z,1509784941.938 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:21.939Z,1509784941.939 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:22.019Z,1509784942.019 [Rowe_600LCM](INFO): Powering down 2017-11-04T08:42:22.320Z,1509784942.320 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:22.328Z,1509784942.328 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:22.712Z,1509784942.712 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:22.713Z,1509784942.713 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:23.131Z,1509784943.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:23.141Z,1509784943.141 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:23.260Z,1509784943.260 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-11-04T08:42:23.260Z,1509784943.260 [Rowe_600LCM] No Fault, FailCount= 2 2017-11-04T08:42:23.261Z,1509784943.261 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-11-04T08:42:23.261Z,1509784943.261 [BuoyancyServo] No Fault, FailCount= 1 2017-11-04T08:42:23.547Z,1509784943.547 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:23.549Z,1509784943.549 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:23.570Z,1509784943.570 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-11-04T08:42:23.636Z,1509784943.636 [Rowe_600LCM](INFO): Initializing 2017-11-04T08:42:23.637Z,1509784943.637 [Rowe_600LCM](INFO): Checking LCM 2017-11-04T08:42:23.637Z,1509784943.637 [Rowe_600LCM](FAULT): LCM not connected 2017-11-04T08:42:23.637Z,1509784943.637 [Rowe_600LCM] Hardware Fault, FailCount= 3 2017-11-04T08:42:23.637Z,1509784943.637 [Rowe_600LCM](ERROR): Hardware Fault 2017-11-04T08:42:23.689Z,1509784943.689 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-11-04T08:42:23.892Z,1509784943.892 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2017-11-04T08:42:23.957Z,1509784943.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:23.958Z,1509784943.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:24.040Z,1509784944.040 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-11-04T08:42:24.350Z,1509784944.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:24.351Z,1509784944.351 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:24.356Z,1509784944.356 [Rowe_600LCM](INFO): Powering down 2017-11-04T08:42:24.749Z,1509784944.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:24.750Z,1509784944.750 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:25.165Z,1509784945.165 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:25.166Z,1509784945.166 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:25.573Z,1509784945.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:25.574Z,1509784945.574 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:25.632Z,1509784945.632 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-11-04T08:42:25.632Z,1509784945.632 [Rowe_600LCM] No Fault, FailCount= 3 2017-11-04T08:42:25.968Z,1509784945.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:25.969Z,1509784945.969 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:25.972Z,1509784945.972 [Rowe_600LCM](INFO): Initializing 2017-11-04T08:42:25.973Z,1509784945.973 [Rowe_600LCM](INFO): Checking LCM 2017-11-04T08:42:25.973Z,1509784945.973 [Rowe_600LCM](FAULT): LCM not connected 2017-11-04T08:42:25.973Z,1509784945.973 [Rowe_600LCM] Hardware Fault, FailCount= 4 2017-11-04T08:42:25.973Z,1509784945.973 [Rowe_600LCM](ERROR): Hardware Fault 2017-11-04T08:42:26.044Z,1509784946.044 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2017-11-04T08:42:26.300Z,1509784946.300 [AcousticModem_Benthos_ATM900](DEBUG): 2017-11-04T08:42:26.376Z,1509784946.376 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-11-04T08:42:26.410Z,1509784946.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:26.411Z,1509784946.411 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:26.598Z,1509784946.598 [Rowe_600LCM](INFO): Powering down 2017-11-04T08:42:26.723Z,1509784946.723 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2017-11-04T08:42:26.723Z,1509784946.723 [PNI_TCM] Data Fault, FailCount= 1 2017-11-04T08:42:26.723Z,1509784946.723 [PNI_TCM](ERROR): Data Fault 2017-11-04T08:42:26.753Z,1509784946.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:26.754Z,1509784946.754 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:26.869Z,1509784946.869 [CBIT](ERROR): Data Fault in component: PNI_TCM 2017-11-04T08:42:27.122Z,1509784947.122 [PNI_TCM](INFO): Powering down 2017-11-04T08:42:27.296Z,1509784947.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:27.297Z,1509784947.297 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:27.502Z,1509784947.502 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2017-11-04T08:42:27.503Z,1509784947.503 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2017-11-04T08:42:27.503Z,1509784947.503 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2017-11-04T08:42:27.551Z,1509784947.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:27.552Z,1509784947.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:27.900Z,1509784947.900 [AcousticModem_Benthos_ATM900](DEBUG): Jan 1 2000 14:01:24 2017-11-04T08:42:27.942Z,1509784947.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:27.943Z,1509784947.943 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:28.026Z,1509784948.026 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-11-04T08:42:28.026Z,1509784948.026 [Rowe_600LCM] No Fault, FailCount= 4 2017-11-04T08:42:28.212Z,1509784948.212 [Rowe_600LCM](INFO): Initializing 2017-11-04T08:42:28.213Z,1509784948.213 [Rowe_600LCM](INFO): Checking LCM 2017-11-04T08:42:28.213Z,1509784948.213 [Rowe_600LCM](FAULT): LCM not connected 2017-11-04T08:42:28.213Z,1509784948.213 [Rowe_600LCM] Hardware Fault, FailCount= 5 2017-11-04T08:42:28.213Z,1509784948.213 [Rowe_600LCM](ERROR): Hardware Fault 2017-11-04T08:42:28.300Z,1509784948.300 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2017-11-04T08:42:28.352Z,1509784948.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:28.360Z,1509784948.360 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:28.532Z,1509784948.532 [CBIT](INFO): Clearing failed state for component PNI_TCM 2017-11-04T08:42:28.532Z,1509784948.532 [PNI_TCM] No Fault, FailCount= 1 2017-11-04T08:42:28.533Z,1509784948.533 [CBIT](ERROR): Hardware Fault in component: Rowe_600LCM 2017-11-04T08:42:28.533Z,1509784948.533 [CBIT](FAULT): Hardware Fault in component: Rowe_600LCM 2017-11-04T08:42:28.620Z,1509784948.620 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-11-04T08:42:28.759Z,1509784948.759 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:28.773Z,1509784948.773 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:28.917Z,1509784948.917 [Rowe_600LCM](INFO): Powering down 2017-11-04T08:42:29.099Z,1509784949.099 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2017-11-04T08:42:29.153Z,1509784949.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:29.154Z,1509784949.154 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:29.499Z,1509784949.499 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2017-11-04T08:42:29.500Z,1509784949.500 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2017-11-04T08:42:29.500Z,1509784949.500 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2017-11-04T08:42:29.537Z,1509784949.537 [Aanderaa_O2](FAULT): Timed out starting 2017-11-04T08:42:29.537Z,1509784949.537 [Aanderaa_O2] Communications Fault, FailCount= 1 2017-11-04T08:42:29.537Z,1509784949.537 [Aanderaa_O2](ERROR): Communications Fault 2017-11-04T08:42:29.576Z,1509784949.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:29.577Z,1509784949.577 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:29.629Z,1509784949.629 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-11-04T08:42:29.900Z,1509784949.900 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2017-11-04T08:42:30.076Z,1509784950.076 [Aanderaa_O2](INFO): Powering down 2017-11-04T08:42:30.133Z,1509784950.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:30.134Z,1509784950.134 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:30.299Z,1509784950.299 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2017-11-04T08:42:30.300Z,1509784950.300 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2017-11-04T08:42:30.349Z,1509784950.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:30.350Z,1509784950.350 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:30.700Z,1509784950.700 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2017-11-04T08:42:30.761Z,1509784950.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:30.762Z,1509784950.762 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:30.809Z,1509784950.809 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-11-04T08:42:30.809Z,1509784950.809 [Aanderaa_O2] No Fault, FailCount= 1 2017-11-04T08:42:31.196Z,1509784951.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:31.197Z,1509784951.197 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:31.601Z,1509784951.601 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:31.603Z,1509784951.603 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:31.937Z,1509784951.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:31.938Z,1509784951.938 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:32.365Z,1509784952.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:32.366Z,1509784952.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:32.794Z,1509784952.794 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:32.795Z,1509784952.795 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:33.164Z,1509784953.164 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:33.165Z,1509784953.165 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:33.572Z,1509784953.572 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:33.573Z,1509784953.573 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:33.964Z,1509784953.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:33.965Z,1509784953.965 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:34.358Z,1509784954.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:34.359Z,1509784954.359 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:34.724Z,1509784954.724 [BPC1](ERROR): Failed to parse bank B battery data 2017-11-04T08:42:34.724Z,1509784954.724 [BPC1] Data Fault, FailCount= 1 2017-11-04T08:42:34.724Z,1509784954.724 [BPC1](ERROR): Data Fault 2017-11-04T08:42:34.753Z,1509784954.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:34.754Z,1509784954.754 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:34.809Z,1509784954.809 [CBIT](ERROR): Data Fault in component: BPC1 2017-11-04T08:42:35.188Z,1509784955.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:35.197Z,1509784955.197 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:35.545Z,1509784955.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:35.546Z,1509784955.546 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:35.934Z,1509784955.934 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2017-11-04T08:42:35.934Z,1509784955.934 [PNI_TCM] Data Fault, FailCount= 2 2017-11-04T08:42:35.934Z,1509784955.934 [PNI_TCM](ERROR): Data Fault 2017-11-04T08:42:35.965Z,1509784955.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:35.966Z,1509784955.966 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:36.021Z,1509784956.021 [CBIT](ERROR): Data Fault in component: PNI_TCM 2017-11-04T08:42:36.021Z,1509784956.021 [CBIT](INFO): Clearing failed state for component BPC1 2017-11-04T08:42:36.021Z,1509784956.021 [BPC1] No Fault, FailCount= 1 2017-11-04T08:42:36.322Z,1509784956.322 [PNI_TCM](INFO): Powering down 2017-11-04T08:42:36.469Z,1509784956.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:36.470Z,1509784956.470 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:36.745Z,1509784956.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:36.746Z,1509784956.746 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:37.141Z,1509784957.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:37.142Z,1509784957.142 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:37.552Z,1509784957.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:37.568Z,1509784957.568 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:37.648Z,1509784957.648 [CBIT](INFO): Clearing failed state for component PNI_TCM 2017-11-04T08:42:37.649Z,1509784957.649 [PNI_TCM] No Fault, FailCount= 2 2017-11-04T08:42:37.957Z,1509784957.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:37.958Z,1509784957.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:38.352Z,1509784958.352 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:38.353Z,1509784958.353 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:38.764Z,1509784958.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:38.765Z,1509784958.765 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:39.141Z,1509784959.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:39.142Z,1509784959.142 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:39.550Z,1509784959.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:39.550Z,1509784959.550 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:39.972Z,1509784959.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:39.973Z,1509784959.973 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:40.338Z,1509784960.338 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:40.339Z,1509784960.339 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:40.769Z,1509784960.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:40.770Z,1509784960.770 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:41.133Z,1509784961.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:41.134Z,1509784961.134 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:41.527Z,1509784961.527 [Aanderaa_O2](FAULT): Timed out starting 2017-11-04T08:42:41.527Z,1509784961.527 [Aanderaa_O2] Communications Fault, FailCount= 2 2017-11-04T08:42:41.527Z,1509784961.527 [Aanderaa_O2](ERROR): Communications Fault 2017-11-04T08:42:41.542Z,1509784961.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:41.543Z,1509784961.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-11-04T08:42:41.629Z,1509784961.629 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-11-04T08:42:42.048Z,1509784962.048 [Aanderaa_O2](INFO): Powering down 2017-11-04T08:42:42.077Z,1509784962.077 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't