2018-03-12T17:27:49.873Z,1520875669.873 [Supervisor](DEBUG): Initializing supervisor. 2018-03-12T17:27:49.876Z,1520875669.876 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-03-12T17:27:49.877Z,1520875669.877 [SyncHandler](INFO): Protected caller Thread ID is 803 2018-03-12T17:27:49.877Z,1520875669.877 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-03-12T17:27:49.878Z,1520875669.878 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-03-12T17:27:49.878Z,1520875669.878 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804 2018-03-12T17:27:49.881Z,1520875669.881 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-03-12T17:27:49.892Z,1520875669.892 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-03-12T17:27:49.893Z,1520875669.893 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-03-12T17:27:49.893Z,1520875669.893 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805 2018-03-12T17:27:49.894Z,1520875669.894 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-03-12T17:27:49.895Z,1520875669.895 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-03-12T17:27:49.895Z,1520875669.895 [logger ThreadHandler](INFO): Protected caller Thread ID is 806 2018-03-12T17:27:49.897Z,1520875669.897 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-03-12T17:27:49.897Z,1520875669.897 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-03-12T17:27:49.900Z,1520875669.900 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-03-12T17:27:49.996Z,1520875669.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-03-12T17:27:49.998Z,1520875669.998 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-03-12T17:27:50.127Z,1520875670.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-03-12T17:27:50.128Z,1520875670.128 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-03-12T17:27:50.314Z,1520875670.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-03-12T17:27:50.316Z,1520875670.316 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-03-12T17:27:50.609Z,1520875670.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-03-12T17:27:50.611Z,1520875670.611 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-03-12T17:27:50.916Z,1520875670.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-03-12T17:27:50.917Z,1520875670.917 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-03-12T17:27:51.358Z,1520875671.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-03-12T17:27:51.359Z,1520875671.359 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-03-12T17:27:51.684Z,1520875671.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-03-12T17:27:51.686Z,1520875671.686 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-03-12T17:27:52.102Z,1520875672.102 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-03-12T17:27:52.103Z,1520875672.103 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-03-12T17:27:52.184Z,1520875672.184 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-03-12T17:27:52.465Z,1520875672.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-03-12T17:27:52.466Z,1520875672.466 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-03-12T17:27:52.605Z,1520875672.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-03-12T17:27:52.606Z,1520875672.606 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-03-12T17:27:52.801Z,1520875672.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-03-12T17:27:52.802Z,1520875672.802 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-03-12T17:27:53.003Z,1520875673.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-03-12T17:27:53.005Z,1520875673.005 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-03-12T17:27:53.164Z,1520875673.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-03-12T17:27:53.165Z,1520875673.165 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-03-12T17:27:53.927Z,1520875673.927 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-03-12T17:27:53.929Z,1520875673.929 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-03-12T17:27:53.933Z,1520875673.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-03-12T17:27:54.160Z,1520875674.160 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-03-12T17:27:54.161Z,1520875674.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-03-12T17:27:54.239Z,1520875674.239 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-03-12T17:27:54.381Z,1520875674.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-03-12T17:27:54.474Z,1520875674.474 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-03-12T17:27:54.560Z,1520875674.560 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-03-12T17:27:54.696Z,1520875674.696 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-03-12T17:27:54.861Z,1520875674.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-03-12T17:27:54.959Z,1520875674.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-03-12T17:27:55.052Z,1520875675.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-03-12T17:27:55.151Z,1520875675.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-03-12T17:27:55.234Z,1520875675.234 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-03-12T17:27:55.239Z,1520875675.239 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-03-12T17:27:55.365Z,1520875675.365 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-03-12T17:27:55.367Z,1520875675.367 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-03-12T17:27:55.391Z,1520875675.391 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-03-12T17:27:55.393Z,1520875675.393 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-03-12T17:27:55.449Z,1520875675.449 [DepthRateCalculator] Loaded 2018-03-12T17:27:55.449Z,1520875675.449 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-03-12T17:27:55.455Z,1520875675.455 [PitchRateCalculator] Loaded 2018-03-12T17:27:55.455Z,1520875675.455 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-03-12T17:27:55.472Z,1520875675.472 [SpeedCalculator] Loaded 2018-03-12T17:27:55.472Z,1520875675.472 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-03-12T17:27:55.493Z,1520875675.493 [TempGradientCalculator] Loaded 2018-03-12T17:27:55.493Z,1520875675.493 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-03-12T17:27:55.509Z,1520875675.509 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-03-12T17:27:55.509Z,1520875675.509 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-03-12T17:27:55.515Z,1520875675.515 [YawRateCalculator] Loaded 2018-03-12T17:27:55.515Z,1520875675.515 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-03-12T17:27:55.516Z,1520875675.516 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-03-12T17:27:55.516Z,1520875675.516 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-03-12T17:27:55.553Z,1520875675.553 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-03-12T17:27:55.554Z,1520875675.554 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-03-12T17:27:56.003Z,1520875676.003 [AcousticModem_Benthos_ATM900] Loaded 2018-03-12T17:27:56.003Z,1520875676.003 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-03-12T17:27:56.141Z,1520875676.141 [DataOverHttps] Loaded 2018-03-12T17:27:56.141Z,1520875676.141 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-03-12T17:27:56.155Z,1520875676.155 [Depth_Keller] Loaded 2018-03-12T17:27:56.155Z,1520875676.155 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-03-12T17:27:56.160Z,1520875676.160 [DropWeight] Loaded 2018-03-12T17:27:56.161Z,1520875676.161 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-03-12T17:27:56.259Z,1520875676.259 [NAL9602] Loaded 2018-03-12T17:27:56.259Z,1520875676.259 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-03-12T17:27:56.275Z,1520875676.275 [Onboard] Loaded 2018-03-12T17:27:56.275Z,1520875676.275 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-03-12T17:27:56.285Z,1520875676.285 [Radio_Surface] Loaded 2018-03-12T17:27:56.286Z,1520875676.286 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-03-12T17:27:56.287Z,1520875676.287 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0 2018-03-12T17:27:56.287Z,1520875676.287 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 885 2018-03-12T17:27:56.440Z,1520875676.440 [PNI_TCM] Loaded 2018-03-12T17:27:56.440Z,1520875676.440 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-03-12T17:27:56.501Z,1520875676.501 [Rowe_600LCM] Loaded 2018-03-12T17:27:56.501Z,1520875676.501 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-03-12T17:27:56.502Z,1520875676.502 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D54E0 2018-03-12T17:27:56.503Z,1520875676.503 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 886 2018-03-12T17:27:58.301Z,1520875678.301 [BPC1] Loaded 2018-03-12T17:27:58.301Z,1520875678.301 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-03-12T17:27:58.301Z,1520875678.301 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-03-12T17:27:58.303Z,1520875678.303 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-03-12T17:27:58.554Z,1520875678.554 [SBIT](DEBUG): Construct Startup Built In Test. 2018-03-12T17:27:58.581Z,1520875678.581 [SBIT] Loaded 2018-03-12T17:27:58.581Z,1520875678.581 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-03-12T17:27:58.582Z,1520875678.582 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-03-12T17:27:58.593Z,1520875678.593 [IBIT] Loaded 2018-03-12T17:27:58.593Z,1520875678.593 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-03-12T17:27:58.597Z,1520875678.597 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-03-12T17:27:58.749Z,1520875678.749 [CBIT] Loaded 2018-03-12T17:27:58.750Z,1520875678.750 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-03-12T17:27:58.750Z,1520875678.750 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-03-12T17:27:58.751Z,1520875678.751 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-03-12T17:27:58.938Z,1520875678.938 [Aanderaa_O2] Loaded 2018-03-12T17:27:58.939Z,1520875678.939 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-03-12T17:27:58.986Z,1520875678.986 [CTD_Seabird] Loaded 2018-03-12T17:27:58.987Z,1520875678.987 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-03-12T17:27:58.988Z,1520875678.988 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408954E0 2018-03-12T17:27:58.988Z,1520875678.988 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888 2018-03-12T17:27:59.004Z,1520875679.004 [ESPComponent] Loaded 2018-03-12T17:27:59.005Z,1520875679.005 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-03-12T17:27:59.019Z,1520875679.019 [PAR_Licor] Loaded 2018-03-12T17:27:59.019Z,1520875679.019 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-03-12T17:27:59.069Z,1520875679.069 [WetLabsBB2FL] Loaded 2018-03-12T17:27:59.069Z,1520875679.069 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-03-12T17:27:59.070Z,1520875679.070 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C54E0 2018-03-12T17:27:59.070Z,1520875679.070 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889 2018-03-12T17:27:59.071Z,1520875679.071 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-03-12T17:27:59.072Z,1520875679.072 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-03-12T17:27:59.349Z,1520875679.349 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-03-12T17:27:59.350Z,1520875679.350 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-03-12T17:27:59.437Z,1520875679.437 [VerticalControl](DEBUG): Construct VerticalControl. 2018-03-12T17:27:59.535Z,1520875679.535 [VerticalControl] Loaded 2018-03-12T17:27:59.535Z,1520875679.535 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-03-12T17:27:59.536Z,1520875679.536 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-03-12T17:27:59.598Z,1520875679.598 [HorizontalControl] Loaded 2018-03-12T17:27:59.598Z,1520875679.598 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-03-12T17:27:59.599Z,1520875679.599 [SpeedControl](DEBUG): Construct SpeedControl. 2018-03-12T17:27:59.604Z,1520875679.604 [SpeedControl] Loaded 2018-03-12T17:27:59.605Z,1520875679.605 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-03-12T17:27:59.605Z,1520875679.605 [LoopControl](DEBUG): Construct LoopControl. 2018-03-12T17:27:59.606Z,1520875679.606 [LoopControl] Loaded 2018-03-12T17:27:59.606Z,1520875679.606 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-03-12T17:27:59.606Z,1520875679.606 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-03-12T17:27:59.607Z,1520875679.607 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-03-12T17:27:59.726Z,1520875679.726 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-03-12T17:27:59.727Z,1520875679.727 [StratificationFrontDetector](DEBUG): (re)initializing 2018-03-12T17:27:59.727Z,1520875679.727 [StratificationFrontDetector] Loaded 2018-03-12T17:27:59.727Z,1520875679.727 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-03-12T17:27:59.728Z,1520875679.728 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-03-12T17:27:59.728Z,1520875679.728 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-03-12T17:27:59.848Z,1520875679.848 [BuoyancyServo] Loaded 2018-03-12T17:27:59.848Z,1520875679.848 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-03-12T17:27:59.860Z,1520875679.860 [ElevatorServo] Loaded 2018-03-12T17:27:59.860Z,1520875679.860 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-03-12T17:27:59.871Z,1520875679.871 [MassServo] Loaded 2018-03-12T17:27:59.871Z,1520875679.871 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-03-12T17:27:59.882Z,1520875679.882 [RudderServo] Loaded 2018-03-12T17:27:59.882Z,1520875679.882 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-03-12T17:27:59.893Z,1520875679.893 [ThrusterServo] Loaded 2018-03-12T17:27:59.893Z,1520875679.893 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-03-12T17:27:59.894Z,1520875679.894 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-03-12T17:27:59.894Z,1520875679.894 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-03-12T17:28:00.009Z,1520875680.009 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-03-12T17:28:00.009Z,1520875680.009 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-03-12T17:28:00.064Z,1520875680.064 [DeadReckonUsingSpeedCalculator] Loaded 2018-03-12T17:28:00.064Z,1520875680.064 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-03-12T17:28:00.081Z,1520875680.081 [NavChart] Loaded 2018-03-12T17:28:00.081Z,1520875680.081 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-03-12T17:28:00.085Z,1520875680.085 [UniversalFixResidualReporter] Loaded 2018-03-12T17:28:00.086Z,1520875680.086 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-03-12T17:28:00.086Z,1520875680.086 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-03-12T17:28:00.089Z,1520875680.089 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-03-12T17:28:00.090Z,1520875680.090 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-03-12T17:28:00.097Z,1520875680.097 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-03-12T17:28:00.098Z,1520875680.098 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0 2018-03-12T17:28:00.098Z,1520875680.098 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 890 2018-03-12T17:28:00.102Z,1520875680.102 [Supervisor](INFO): Main Thread ID is 795 2018-03-12T17:28:00.102Z,1520875680.102 [Supervisor](DEBUG): Running supervisor. 2018-03-12T17:28:00.103Z,1520875680.103 [CommandLine ThreadHandler](INFO): Handler Thread ID is 891 2018-03-12T17:28:00.106Z,1520875680.106 [controlThread ThreadHandler](INFO): Handler Thread ID is 892 2018-03-12T17:28:00.106Z,1520875680.106 [controlThread](DEBUG): Initializing ControlThread 2018-03-12T17:28:00.107Z,1520875680.107 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-12T17:28:00.107Z,1520875680.107 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-03-12T17:28:00.107Z,1520875680.107 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-03-12T17:28:00.108Z,1520875680.108 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-03-12T17:28:00.108Z,1520875680.108 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-03-12T17:28:00.109Z,1520875680.109 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-03-12T17:28:00.115Z,1520875680.115 [SBIT](INFO): Initialize SBIT Component. 2018-03-12T17:28:00.116Z,1520875680.116 [SBIT](IMPORTANT): git: 2018-02-09A-11-g1191477 2018-03-12T17:28:00.116Z,1520875680.116 [SBIT](INFO): git hash: 1191477e507aa94f59b4a9056a613189489a6cd1 2018-03-12T17:28:00.116Z,1520875680.116 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-03-12T17:28:00.117Z,1520875680.117 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017 2018-03-12T17:28:00.118Z,1520875680.118 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2018-03-12T17:28:00.119Z,1520875680.119 [IBIT](INFO): Initialize IBIT Component. 2018-03-12T17:28:00.119Z,1520875680.119 [CBIT](DEBUG): Initialize CBIT Component. 2018-03-12T17:28:00.120Z,1520875680.120 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-03-12T17:28:00.120Z,1520875680.120 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-03-12T17:28:00.121Z,1520875680.121 [logger ThreadHandler](INFO): Handler Thread ID is 893 2018-03-12T17:28:00.141Z,1520875680.141 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894 2018-03-12T17:28:00.147Z,1520875680.147 [Radio_Surface](INFO): Powering up 2018-03-12T17:28:00.153Z,1520875680.153 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 895 2018-03-12T17:28:00.154Z,1520875680.154 [Rowe_600LCM](INFO): Initializing 2018-03-12T17:28:00.154Z,1520875680.154 [Rowe_600LCM](INFO): Checking LCM 2018-03-12T17:28:00.239Z,1520875680.239 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 896 2018-03-12T17:28:00.241Z,1520875680.241 [CTD_Seabird](INFO): Initializing 2018-03-12T17:28:00.242Z,1520875680.242 [CTD_Seabird](INFO): Checking LCM 2018-03-12T17:28:00.242Z,1520875680.242 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 897 2018-03-12T17:28:00.243Z,1520875680.243 [WetLabsBB2FL](INFO): Powering down 2018-03-12T17:28:00.273Z,1520875680.273 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 898 2018-03-12T17:28:00.281Z,1520875680.281 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-03-12T17:28:00.281Z,1520875680.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-03-12T17:28:00.281Z,1520875680.281 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-03-12T17:28:00.281Z,1520875680.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-03-12T17:28:00.281Z,1520875680.281 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-03-12T17:28:00.282Z,1520875680.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-03-12T17:28:00.282Z,1520875680.282 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-03-12T17:28:00.282Z,1520875680.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-03-12T17:28:00.282Z,1520875680.282 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-03-12T17:28:00.282Z,1520875680.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-03-12T17:28:00.283Z,1520875680.283 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-03-12T17:28:00.283Z,1520875680.283 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-03-12T17:28:00.283Z,1520875680.283 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-03-12T17:28:00.283Z,1520875680.283 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-03-12T17:28:00.283Z,1520875680.283 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-03-12T17:28:00.283Z,1520875680.283 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-03-12T17:28:00.285Z,1520875680.285 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-03-12T17:28:00.307Z,1520875680.307 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-03-12T17:28:00.310Z,1520875680.310 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-03-12T17:28:00.310Z,1520875680.310 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-03-12T17:28:00.418Z,1520875680.418 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-03-12T17:28:00.419Z,1520875680.419 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-03-12T17:28:00.419Z,1520875680.419 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-03-12T17:28:00.420Z,1520875680.420 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-12T17:28:00.420Z,1520875680.420 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-12T17:28:00.421Z,1520875680.421 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-12T17:28:00.421Z,1520875680.421 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-03-12T17:28:00.421Z,1520875680.421 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-03-12T17:28:00.422Z,1520875680.422 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-03-12T17:28:00.445Z,1520875680.445 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-12T17:28:00.463Z,1520875680.463 [MissionManager](DEBUG): 2018-03-12T17:28:00.464Z,1520875680.464 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-03-12T17:28:00.630Z,1520875680.630 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-03-12T17:28:00.631Z,1520875680.631 [Default:A.Wait](DEBUG): Construct Wait. 2018-03-12T17:28:00.641Z,1520875680.641 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-12T17:28:00.653Z,1520875680.653 [CTD_Seabird](INFO): LCM OK 2018-03-12T17:28:00.653Z,1520875680.653 [CTD_Seabird](INFO): Powering up 2018-03-12T17:28:00.653Z,1520875680.653 [Rowe_600LCM](INFO): LCM OK 2018-03-12T17:28:00.653Z,1520875680.653 [Rowe_600LCM](INFO): Powering up 2018-03-12T17:28:00.663Z,1520875680.663 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-03-12T17:28:00.670Z,1520875680.670 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-03-12T17:28:00.676Z,1520875680.676 [Default:E.Execute](DEBUG): Construct Execute. 2018-03-12T17:28:00.703Z,1520875680.703 [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 2018-03-12T17:28:00.709Z,1520875680.709 [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, 2018-03-12T17:28:00.717Z,1520875680.717 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-03-12T17:28:00.717Z,1520875680.717 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-03-12T17:28:00.743Z,1520875680.743 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-03-12T17:28:01.103Z,1520875681.103 [ESPComponent](INFO): powering down ESP 2018-03-12T17:28:01.518Z,1520875681.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:01.613Z,1520875681.613 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:01.718Z,1520875681.718 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-12T17:28:01.725Z,1520875681.725 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-12T17:28:01.739Z,1520875681.739 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-03-12T17:28:01.745Z,1520875681.745 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-03-12T17:28:01.763Z,1520875681.763 [MassServo](DEBUG): Initializing EZServoServo. 2018-03-12T17:28:01.769Z,1520875681.769 [MassServo](DEBUG): Initializing MassServo. 2018-03-12T17:28:01.790Z,1520875681.790 [RudderServo](DEBUG): Initializing EZServoServo. 2018-03-12T17:28:01.797Z,1520875681.797 [RudderServo](DEBUG): Initializing RudderServo. 2018-03-12T17:28:01.810Z,1520875681.810 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-03-12T17:28:01.817Z,1520875681.817 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-03-12T17:28:01.855Z,1520875681.855 [CommandLine](FAULT): Scheduling is paused 2018-03-12T17:28:01.855Z,1520875681.855 [CBIT](INFO): Critical error at 20180312T172800 2018-03-12T17:28:01.855Z,1520875681.855 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-03-12T17:28:01.982Z,1520875681.982 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-03-12T17:28:01.982Z,1520875681.982 [DropWeight] Hardware Fault, FailCount= 1 2018-03-12T17:28:01.982Z,1520875681.982 [DropWeight](ERROR): Hardware Fault 2018-03-12T17:28:02.038Z,1520875682.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:02.039Z,1520875682.039 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:02.064Z,1520875682.064 [CBIT](INFO): Critical error at 20180312T172801 2018-03-12T17:28:02.066Z,1520875682.066 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-03-12T17:28:02.067Z,1520875682.067 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-03-12T17:28:02.356Z,1520875682.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:02.365Z,1520875682.365 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:02.693Z,1520875682.693 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-03-12T17:28:02.693Z,1520875682.693 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-03-12T17:28:02.693Z,1520875682.693 [BuoyancyServo] Communications Fault, FailCount= 1 2018-03-12T17:28:02.693Z,1520875682.693 [BuoyancyServo](ERROR): Communications Fault 2018-03-12T17:28:02.898Z,1520875682.898 [CBIT](INFO): Critical error at 20180312T172802 2018-03-12T17:28:02.901Z,1520875682.901 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-03-12T17:28:02.957Z,1520875682.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:02.958Z,1520875682.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:02.970Z,1520875682.970 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-03-12T17:28:02.972Z,1520875682.972 [BuoyancyServo](INFO): Powering down 2018-03-12T17:28:03.356Z,1520875683.356 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:03.357Z,1520875683.357 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:03.756Z,1520875683.756 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:03.773Z,1520875683.773 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:04.160Z,1520875684.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:04.169Z,1520875684.169 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:04.479Z,1520875684.479 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-03-12T17:28:04.558Z,1520875684.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:04.559Z,1520875684.559 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:04.584Z,1520875684.584 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2018-03-12T17:28:04.707Z,1520875684.707 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-03-12T17:28:04.708Z,1520875684.708 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-03-12T17:28:04.709Z,1520875684.709 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-03-12T17:28:04.949Z,1520875684.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:04.950Z,1520875684.950 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:05.026Z,1520875685.026 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-03-12T17:28:05.026Z,1520875685.026 [BuoyancyServo] No Fault, FailCount= 1 2018-03-12T17:28:05.359Z,1520875685.359 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:05.360Z,1520875685.360 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:05.411Z,1520875685.411 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-12T17:28:05.412Z,1520875685.412 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-12T17:28:05.763Z,1520875685.763 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:05.764Z,1520875685.764 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:05.869Z,1520875685.869 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-12T17:28:06.021Z,1520875686.021 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-03-12T17:28:06.023Z,1520875686.023 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-03-12T17:28:06.160Z,1520875686.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:06.177Z,1520875686.177 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:06.422Z,1520875686.422 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-12T17:28:06.422Z,1520875686.422 [CTD_Seabird](INFO): Powering down 2018-03-12T17:28:06.568Z,1520875686.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:06.569Z,1520875686.569 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:06.981Z,1520875686.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:06.982Z,1520875686.982 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:07.360Z,1520875687.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:07.366Z,1520875687.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:07.760Z,1520875687.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:07.777Z,1520875687.777 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:08.171Z,1520875688.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:08.172Z,1520875688.172 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:08.508Z,1520875688.508 [AcousticModem_Benthos_ATM900](DEBUG): 2018-03-12T17:28:08.529Z,1520875688.529 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-12T17:28:08.529Z,1520875688.529 [PNI_TCM] Data Fault, FailCount= 1 2018-03-12T17:28:08.530Z,1520875688.530 [PNI_TCM](ERROR): Data Fault 2018-03-12T17:28:08.565Z,1520875688.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:08.566Z,1520875688.566 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:08.682Z,1520875688.682 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-12T17:28:08.930Z,1520875688.930 [PNI_TCM](INFO): Powering down 2018-03-12T17:28:08.987Z,1520875688.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:08.989Z,1520875688.989 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:09.360Z,1520875689.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:09.369Z,1520875689.369 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:09.709Z,1520875689.709 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-03-12T17:28:09.710Z,1520875689.710 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-03-12T17:28:09.711Z,1520875689.711 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-03-12T17:28:09.711Z,1520875689.711 [AcousticModem_Benthos_ATM900](DEBUG): Mar 17 2000 19:24:52 2018-03-12T17:28:09.761Z,1520875689.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:09.762Z,1520875689.762 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:10.109Z,1520875690.109 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-03-12T17:28:10.145Z,1520875690.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:10.146Z,1520875690.146 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:10.198Z,1520875690.198 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-12T17:28:10.198Z,1520875690.198 [PNI_TCM] No Fault, FailCount= 1 2018-03-12T17:28:10.557Z,1520875690.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:10.558Z,1520875690.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:10.908Z,1520875690.908 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-03-12T17:28:10.957Z,1520875690.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:10.958Z,1520875690.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:11.308Z,1520875691.308 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-03-12T17:28:11.309Z,1520875691.309 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2018-03-12T17:28:11.309Z,1520875691.309 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2018-03-12T17:28:11.341Z,1520875691.341 [Aanderaa_O2](FAULT): Timed out starting 2018-03-12T17:28:11.341Z,1520875691.341 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-03-12T17:28:11.342Z,1520875691.342 [Aanderaa_O2](ERROR): Communications Fault 2018-03-12T17:28:11.378Z,1520875691.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:11.379Z,1520875691.379 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:11.425Z,1520875691.425 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-03-12T17:28:11.708Z,1520875691.708 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2018-03-12T17:28:11.740Z,1520875691.740 [Aanderaa_O2](INFO): Powering down 2018-03-12T17:28:11.897Z,1520875691.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:11.898Z,1520875691.898 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:12.108Z,1520875692.108 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2018-03-12T17:28:12.109Z,1520875692.109 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2018-03-12T17:28:12.192Z,1520875692.192 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:12.201Z,1520875692.201 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:12.509Z,1520875692.509 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2018-03-12T17:28:12.569Z,1520875692.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:12.570Z,1520875692.570 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:12.626Z,1520875692.626 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-03-12T17:28:12.626Z,1520875692.626 [Aanderaa_O2] No Fault, FailCount= 1 2018-03-12T17:28:12.945Z,1520875692.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:12.946Z,1520875692.946 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:13.375Z,1520875693.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:13.376Z,1520875693.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:13.753Z,1520875693.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:13.754Z,1520875693.754 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:14.174Z,1520875694.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:14.176Z,1520875694.176 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:14.588Z,1520875694.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:14.597Z,1520875694.597 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:14.961Z,1520875694.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:14.962Z,1520875694.962 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:15.365Z,1520875695.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:15.373Z,1520875695.373 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:15.762Z,1520875695.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:15.763Z,1520875695.763 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:16.166Z,1520875696.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:16.167Z,1520875696.167 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:16.569Z,1520875696.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:16.570Z,1520875696.570 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:16.981Z,1520875696.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:16.982Z,1520875696.982 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:17.381Z,1520875697.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:17.382Z,1520875697.382 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:17.731Z,1520875697.731 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-12T17:28:17.731Z,1520875697.731 [PNI_TCM] Data Fault, FailCount= 2 2018-03-12T17:28:17.731Z,1520875697.731 [PNI_TCM](ERROR): Data Fault 2018-03-12T17:28:17.804Z,1520875697.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:17.805Z,1520875697.805 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:17.850Z,1520875697.850 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-12T17:28:18.131Z,1520875698.131 [PNI_TCM](INFO): Powering down 2018-03-12T17:28:18.177Z,1520875698.177 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:18.178Z,1520875698.178 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:18.593Z,1520875698.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:18.594Z,1520875698.594 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:18.985Z,1520875698.985 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:18.992Z,1520875698.992 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:19.353Z,1520875699.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:19.354Z,1520875699.354 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:19.453Z,1520875699.453 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-12T17:28:19.453Z,1520875699.453 [PNI_TCM] No Fault, FailCount= 2 2018-03-12T17:28:19.761Z,1520875699.761 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:19.762Z,1520875699.762 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:20.141Z,1520875700.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:20.142Z,1520875700.142 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:20.565Z,1520875700.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:20.566Z,1520875700.566 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:20.972Z,1520875700.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:20.981Z,1520875700.981 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:21.357Z,1520875701.357 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:21.358Z,1520875701.358 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:21.792Z,1520875701.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:21.801Z,1520875701.801 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:22.149Z,1520875702.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:22.150Z,1520875702.150 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:22.560Z,1520875702.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:22.577Z,1520875702.577 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:22.932Z,1520875702.932 [Aanderaa_O2](FAULT): Timed out starting 2018-03-12T17:28:22.932Z,1520875702.932 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-03-12T17:28:22.932Z,1520875702.932 [Aanderaa_O2](ERROR): Communications Fault 2018-03-12T17:28:22.965Z,1520875702.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:22.966Z,1520875702.966 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:23.030Z,1520875703.030 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-03-12T17:28:23.343Z,1520875703.343 [Aanderaa_O2](INFO): Powering down 2018-03-12T17:28:23.350Z,1520875703.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:23.351Z,1520875703.351 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:23.780Z,1520875703.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:23.781Z,1520875703.781 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:24.181Z,1520875704.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:24.182Z,1520875704.182 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:24.246Z,1520875704.246 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-03-12T17:28:24.246Z,1520875704.246 [Aanderaa_O2] No Fault, FailCount= 2 2018-03-12T17:28:24.553Z,1520875704.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:24.554Z,1520875704.554 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:24.962Z,1520875704.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:24.962Z,1520875704.962 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:25.361Z,1520875705.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:25.362Z,1520875705.362 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:25.754Z,1520875705.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:25.755Z,1520875705.755 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:26.150Z,1520875706.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:26.151Z,1520875706.151 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:26.514Z,1520875706.514 [NAL9602](INFO): Powering up NAL9602 2018-03-12T17:28:26.564Z,1520875706.564 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:26.574Z,1520875706.574 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:26.930Z,1520875706.930 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-12T17:28:26.930Z,1520875706.930 [PNI_TCM] Data Fault, FailCount= 3 2018-03-12T17:28:26.931Z,1520875706.931 [PNI_TCM](ERROR): Data Fault 2018-03-12T17:28:26.981Z,1520875706.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:26.982Z,1520875706.982 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-12T17:28:27.042Z,1520875707.042 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-12T17:28:27.343Z,1520875707.343 [PNI_TCM](INFO): Powering down 2018-03-12T17:28:27.389Z,1520875707.389 [DeadReckonUsingMultipleVelocity