2017-04-18T23:10:39.291Z,1492557039.291 [Supervisor](DEBUG): Initializing supervisor. 2017-04-18T23:10:39.294Z,1492557039.294 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-04-18T23:10:39.295Z,1492557039.295 [SyncHandler](INFO): Protected caller Thread ID is 769 2017-04-18T23:10:39.295Z,1492557039.295 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-04-18T23:10:39.296Z,1492557039.296 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-04-18T23:10:39.296Z,1492557039.296 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770 2017-04-18T23:10:39.299Z,1492557039.299 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-04-18T23:10:39.312Z,1492557039.312 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-04-18T23:10:39.313Z,1492557039.313 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-04-18T23:10:39.313Z,1492557039.313 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771 2017-04-18T23:10:39.314Z,1492557039.314 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-04-18T23:10:39.315Z,1492557039.315 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-04-18T23:10:39.315Z,1492557039.315 [logger ThreadHandler](INFO): Protected caller Thread ID is 772 2017-04-18T23:10:39.317Z,1492557039.317 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-04-18T23:10:39.318Z,1492557039.318 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-04-18T23:10:39.322Z,1492557039.322 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-04-18T23:10:39.408Z,1492557039.408 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-04-18T23:10:39.733Z,1492557039.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-04-18T23:10:39.735Z,1492557039.735 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-04-18T23:10:39.869Z,1492557039.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-04-18T23:10:39.870Z,1492557039.870 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-04-18T23:10:40.069Z,1492557040.069 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-04-18T23:10:40.070Z,1492557040.070 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-04-18T23:10:40.170Z,1492557040.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-04-18T23:10:40.172Z,1492557040.172 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-04-18T23:10:40.368Z,1492557040.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-04-18T23:10:40.370Z,1492557040.370 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-04-18T23:10:40.837Z,1492557040.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-04-18T23:10:40.838Z,1492557040.838 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-04-18T23:10:41.125Z,1492557041.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-04-18T23:10:41.126Z,1492557041.126 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-04-18T23:10:41.213Z,1492557041.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-04-18T23:10:41.213Z,1492557041.213 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-04-18T23:10:41.355Z,1492557041.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-04-18T23:10:41.356Z,1492557041.356 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-04-18T23:10:41.556Z,1492557041.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-04-18T23:10:41.557Z,1492557041.557 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-04-18T23:10:42.019Z,1492557042.019 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-04-18T23:10:42.020Z,1492557042.020 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-04-18T23:10:42.511Z,1492557042.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-04-18T23:10:42.511Z,1492557042.511 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-04-18T23:10:48.003Z,1492557048.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-04-18T23:10:48.004Z,1492557048.004 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-04-18T23:10:48.116Z,1492557048.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-04-18T23:10:48.118Z,1492557048.118 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/ 2017-04-18T23:10:48.122Z,1492557048.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg 2017-04-18T23:10:48.210Z,1492557048.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg 2017-04-18T23:10:48.377Z,1492557048.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg 2017-04-18T23:10:48.614Z,1492557048.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-04-18T23:10:48.614Z,1492557048.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg 2017-04-18T23:10:48.714Z,1492557048.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg 2017-04-18T23:10:48.826Z,1492557048.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg 2017-04-18T23:10:48.933Z,1492557048.933 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg 2017-04-18T23:10:49.079Z,1492557049.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg 2017-04-18T23:10:49.244Z,1492557049.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg 2017-04-18T23:10:49.354Z,1492557049.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg 2017-04-18T23:10:49.444Z,1492557049.444 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg 2017-04-18T23:10:49.544Z,1492557049.544 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-04-18T23:10:49.549Z,1492557049.549 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-04-18T23:10:49.591Z,1492557049.591 [DepthRateCalculator] Loaded 2017-04-18T23:10:49.591Z,1492557049.592 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-04-18T23:10:49.598Z,1492557049.598 [PitchRateCalculator] Loaded 2017-04-18T23:10:49.598Z,1492557049.598 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-04-18T23:10:49.610Z,1492557049.610 [SpeedCalculator] Loaded 2017-04-18T23:10:49.610Z,1492557049.610 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-04-18T23:10:49.632Z,1492557049.632 [TempGradientCalculator] Loaded 2017-04-18T23:10:49.633Z,1492557049.633 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-04-18T23:10:49.649Z,1492557049.649 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-04-18T23:10:49.649Z,1492557049.649 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-04-18T23:10:49.655Z,1492557049.655 [YawRateCalculator] Loaded 2017-04-18T23:10:49.655Z,1492557049.655 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-04-18T23:10:49.656Z,1492557049.656 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-04-18T23:10:49.656Z,1492557049.657 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-04-18T23:10:49.780Z,1492557049.780 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-04-18T23:10:49.780Z,1492557049.780 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-04-18T23:10:49.834Z,1492557049.834 [DeadReckonUsingSpeedCalculator] Loaded 2017-04-18T23:10:49.835Z,1492557049.835 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-04-18T23:10:49.890Z,1492557049.890 [DeadReckonWithRespectToSeafloor] Loaded 2017-04-18T23:10:49.890Z,1492557049.890 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2017-04-18T23:10:49.907Z,1492557049.907 [NavChart] Loaded 2017-04-18T23:10:49.907Z,1492557049.907 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-04-18T23:10:49.911Z,1492557049.911 [UniversalFixResidualReporter] Loaded 2017-04-18T23:10:49.912Z,1492557049.912 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-04-18T23:10:49.912Z,1492557049.912 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-04-18T23:10:49.913Z,1492557049.913 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-04-18T23:10:50.107Z,1492557050.107 [Aanderaa_O2] Loaded 2017-04-18T23:10:50.108Z,1492557050.108 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-04-18T23:10:50.123Z,1492557050.123 [PAR_Licor] Loaded 2017-04-18T23:10:50.123Z,1492557050.123 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-04-18T23:10:50.174Z,1492557050.174 [WetLabsBB2FL] Loaded 2017-04-18T23:10:50.174Z,1492557050.174 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-04-18T23:10:50.175Z,1492557050.175 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4066F4E0 2017-04-18T23:10:50.176Z,1492557050.176 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 852 2017-04-18T23:10:50.176Z,1492557050.176 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-04-18T23:10:50.177Z,1492557050.177 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-04-18T23:10:50.217Z,1492557050.217 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-04-18T23:10:50.218Z,1492557050.218 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-04-18T23:10:50.227Z,1492557050.227 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-04-18T23:10:50.228Z,1492557050.228 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-04-18T23:10:50.513Z,1492557050.513 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-04-18T23:10:50.513Z,1492557050.513 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-04-18T23:10:50.671Z,1492557050.671 [SBIT](DEBUG): Construct Startup Built In Test. 2017-04-18T23:10:50.703Z,1492557050.703 [SBIT] Loaded 2017-04-18T23:10:50.703Z,1492557050.703 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-04-18T23:10:50.704Z,1492557050.704 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-04-18T23:10:50.732Z,1492557050.732 [IBIT] Loaded 2017-04-18T23:10:50.732Z,1492557050.732 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-04-18T23:10:50.736Z,1492557050.736 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-04-18T23:10:50.891Z,1492557050.891 [CBIT] Loaded 2017-04-18T23:10:50.891Z,1492557050.891 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-04-18T23:10:50.892Z,1492557050.892 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-04-18T23:10:50.892Z,1492557050.892 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-04-18T23:10:51.510Z,1492557051.510 [DataOverHttps] Loaded 2017-04-18T23:10:51.510Z,1492557051.510 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-04-18T23:10:51.524Z,1492557051.524 [Depth_Keller] Loaded 2017-04-18T23:10:51.524Z,1492557051.524 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-04-18T23:10:51.529Z,1492557051.529 [DropWeight] Loaded 2017-04-18T23:10:51.530Z,1492557051.530 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-04-18T23:10:51.624Z,1492557051.624 [NAL9602] Loaded 2017-04-18T23:10:51.624Z,1492557051.624 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-04-18T23:10:51.634Z,1492557051.634 [Onboard] Loaded 2017-04-18T23:10:51.634Z,1492557051.634 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-04-18T23:10:51.641Z,1492557051.641 [Radio_Surface] Loaded 2017-04-18T23:10:51.641Z,1492557051.641 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-04-18T23:10:51.642Z,1492557051.642 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0 2017-04-18T23:10:51.643Z,1492557051.643 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 853 2017-04-18T23:10:51.779Z,1492557051.779 [DAT] Loaded 2017-04-18T23:10:51.779Z,1492557051.779 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2017-04-18T23:10:51.786Z,1492557051.786 [SCPI] Loaded 2017-04-18T23:10:51.786Z,1492557051.786 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread. 2017-04-18T23:10:51.979Z,1492557051.979 [PNI_TCM] Loaded 2017-04-18T23:10:51.979Z,1492557051.979 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-04-18T23:10:52.337Z,1492557052.337 [Rowe_600LCM] Loaded 2017-04-18T23:10:52.337Z,1492557052.337 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-04-18T23:10:52.338Z,1492557052.339 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409904E0 2017-04-18T23:10:52.339Z,1492557052.339 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 854 2017-04-18T23:10:54.189Z,1492557054.189 [BPC1] Loaded 2017-04-18T23:10:54.190Z,1492557054.190 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-04-18T23:10:54.190Z,1492557054.190 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-04-18T23:10:54.191Z,1492557054.191 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-04-18T23:10:54.321Z,1492557054.321 [BuoyancyServo] Loaded 2017-04-18T23:10:54.321Z,1492557054.321 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-04-18T23:10:54.337Z,1492557054.337 [ElevatorServo] Loaded 2017-04-18T23:10:54.337Z,1492557054.337 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-04-18T23:10:54.352Z,1492557054.352 [MassServo] Loaded 2017-04-18T23:10:54.353Z,1492557054.353 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-04-18T23:10:54.368Z,1492557054.368 [RudderServo] Loaded 2017-04-18T23:10:54.369Z,1492557054.369 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-04-18T23:10:54.384Z,1492557054.384 [ThrusterServo] Loaded 2017-04-18T23:10:54.384Z,1492557054.384 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-04-18T23:10:54.385Z,1492557054.385 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-04-18T23:10:54.385Z,1492557054.385 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-04-18T23:10:55.244Z,1492557055.244 [VerticalControl](DEBUG): Construct VerticalControl. 2017-04-18T23:10:55.336Z,1492557055.336 [VerticalControl] Loaded 2017-04-18T23:10:55.336Z,1492557055.336 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-04-18T23:10:55.337Z,1492557055.337 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-04-18T23:10:55.397Z,1492557055.397 [HorizontalControl] Loaded 2017-04-18T23:10:55.397Z,1492557055.397 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-04-18T23:10:55.398Z,1492557055.398 [SpeedControl](DEBUG): Construct SpeedControl. 2017-04-18T23:10:55.400Z,1492557055.399 [SpeedControl] Loaded 2017-04-18T23:10:55.400Z,1492557055.400 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-04-18T23:10:55.400Z,1492557055.401 [LoopControl](DEBUG): Construct LoopControl. 2017-04-18T23:10:55.401Z,1492557055.401 [LoopControl] Loaded 2017-04-18T23:10:55.401Z,1492557055.401 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-04-18T23:10:55.402Z,1492557055.402 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-04-18T23:10:55.403Z,1492557055.402 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-04-18T23:10:55.525Z,1492557055.525 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-04-18T23:10:55.526Z,1492557055.526 [StratificationFrontDetector](DEBUG): (re)initializing 2017-04-18T23:10:55.526Z,1492557055.526 [StratificationFrontDetector] Loaded 2017-04-18T23:10:55.526Z,1492557055.526 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-04-18T23:10:55.551Z,1492557055.551 [DepAvgTempFrontDetector] Loaded 2017-04-18T23:10:55.551Z,1492557055.551 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread. 2017-04-18T23:10:55.552Z,1492557055.552 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-04-18T23:10:55.553Z,1492557055.553 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-04-18T23:10:55.685Z,1492557055.685 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-04-18T23:10:55.689Z,1492557055.689 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-04-18T23:10:55.690Z,1492557055.690 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-04-18T23:10:55.697Z,1492557055.697 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-04-18T23:10:55.698Z,1492557055.698 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0 2017-04-18T23:10:55.698Z,1492557055.698 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 855 2017-04-18T23:10:55.703Z,1492557055.703 [Supervisor](INFO): Main Thread ID is 764 2017-04-18T23:10:55.703Z,1492557055.703 [Supervisor](DEBUG): Running supervisor. 2017-04-18T23:10:55.704Z,1492557055.704 [CommandLine ThreadHandler](INFO): Handler Thread ID is 856 2017-04-18T23:10:55.707Z,1492557055.707 [controlThread ThreadHandler](INFO): Handler Thread ID is 857 2017-04-18T23:10:55.707Z,1492557055.707 [controlThread](DEBUG): Initializing ControlThread 2017-04-18T23:10:55.708Z,1492557055.708 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-04-18T23:10:55.708Z,1492557055.708 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-04-18T23:10:55.709Z,1492557055.709 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-04-18T23:10:55.709Z,1492557055.709 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-04-18T23:10:55.710Z,1492557055.710 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-04-18T23:10:55.711Z,1492557055.711 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-04-18T23:10:55.711Z,1492557055.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-04-18T23:10:55.712Z,1492557055.712 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-04-18T23:10:55.712Z,1492557055.712 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-04-18T23:10:55.713Z,1492557055.713 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-04-18T23:10:55.713Z,1492557055.713 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-04-18T23:10:55.714Z,1492557055.714 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-04-18T23:10:55.714Z,1492557055.714 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component. 2017-04-18T23:10:55.715Z,1492557055.714 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s. 2017-04-18T23:10:55.715Z,1492557055.715 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s. 2017-04-18T23:10:55.715Z,1492557055.715 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-04-18T23:10:55.716Z,1492557055.716 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-04-18T23:10:55.720Z,1492557055.720 [SBIT](INFO): Initialize SBIT Component. 2017-04-18T23:10:55.721Z,1492557055.721 [SBIT](IMPORTANT): git: 2017-04-12-3-g3a402a6 2017-04-18T23:10:55.721Z,1492557055.721 [SBIT](INFO): git hash: 3a402a68fbc2d1f037574985f21b5bcd039a347c 2017-04-18T23:10:55.723Z,1492557055.723 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-04-18T23:10:55.723Z,1492557055.723 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016 2017-04-18T23:10:55.724Z,1492557055.724 [SBIT](INFO): Beginning SBIT in 79.000000 seconds. 2017-04-18T23:10:55.725Z,1492557055.725 [IBIT](INFO): Initialize IBIT Component. 2017-04-18T23:10:55.726Z,1492557055.726 [CBIT](DEBUG): Initialize CBIT Component. 2017-04-18T23:10:55.726Z,1492557055.726 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-04-18T23:10:55.726Z,1492557055.726 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-04-18T23:10:55.727Z,1492557055.727 [logger ThreadHandler](INFO): Handler Thread ID is 858 2017-04-18T23:10:55.745Z,1492557055.745 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 859 2017-04-18T23:10:55.746Z,1492557055.746 [WetLabsBB2FL](INFO): Powering down 2017-04-18T23:10:55.769Z,1492557055.769 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 860 2017-04-18T23:10:55.780Z,1492557055.780 [Radio_Surface](INFO): Powering up 2017-04-18T23:10:55.789Z,1492557055.789 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 861 2017-04-18T23:10:55.789Z,1492557055.789 [Rowe_600LCM](INFO): Initializing 2017-04-18T23:10:55.790Z,1492557055.790 [Rowe_600LCM](INFO): Checking LCM 2017-04-18T23:10:55.790Z,1492557055.790 [Rowe_600LCM](INFO): LCM OK 2017-04-18T23:10:55.790Z,1492557055.790 [Rowe_600LCM](INFO): Powering up 2017-04-18T23:10:55.797Z,1492557055.797 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 862 2017-04-18T23:10:55.807Z,1492557055.807 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-04-18T23:10:55.807Z,1492557055.807 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-04-18T23:10:55.807Z,1492557055.807 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-04-18T23:10:55.807Z,1492557055.807 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-04-18T23:10:55.808Z,1492557055.807 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-04-18T23:10:55.808Z,1492557055.808 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-04-18T23:10:55.817Z,1492557055.817 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-04-18T23:10:55.819Z,1492557055.819 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-04-18T23:10:55.820Z,1492557055.820 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-04-18T23:10:55.820Z,1492557055.820 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-04-18T23:10:55.820Z,1492557055.820 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-04-18T23:10:55.821Z,1492557055.821 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-04-18T23:10:55.821Z,1492557055.821 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-04-18T23:10:55.821Z,1492557055.821 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-04-18T23:10:55.821Z,1492557055.821 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-04-18T23:10:55.821Z,1492557055.821 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-04-18T23:10:55.822Z,1492557055.822 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-04-18T23:10:55.822Z,1492557055.822 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-04-18T23:10:55.822Z,1492557055.822 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-04-18T23:10:55.823Z,1492557055.823 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-04-18T23:10:55.825Z,1492557055.825 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-04-18T23:10:55.850Z,1492557055.850 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-18T23:10:55.900Z,1492557055.900 [MissionManager](DEBUG): 2017-04-18T23:10:55.901Z,1492557055.901 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-04-18T23:10:55.958Z,1492557055.958 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-04-18T23:10:55.960Z,1492557055.960 [Default:A.Wait](DEBUG): Construct Wait. 2017-04-18T23:10:55.977Z,1492557055.977 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-04-18T23:10:56.004Z,1492557056.003 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-04-18T23:10:56.022Z,1492557056.022 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-04-18T23:10:56.044Z,1492557056.044 [Default:E.Execute](DEBUG): Construct Execute. 2017-04-18T23:10:56.048Z,1492557056.048 [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-04-18T23:10:56.054Z,1492557056.054 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,SCPI,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-04-18T23:10:56.211Z,1492557056.211 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-04-18T23:10:56.399Z,1492557056.399 [DAT](INFO): Powering up 2017-04-18T23:10:56.399Z,1492557056.399 [DAT](DEBUG): Initializing DAT. 2017-04-18T23:10:56.697Z,1492557056.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:56.697Z,1492557056.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:56.840Z,1492557056.840 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:56.852Z,1492557056.852 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:56.864Z,1492557056.864 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:56.973Z,1492557056.973 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-04-18T23:10:56.980Z,1492557056.980 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-04-18T23:10:56.995Z,1492557056.995 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-04-18T23:10:57.005Z,1492557057.005 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-04-18T23:10:57.027Z,1492557057.027 [MassServo](DEBUG): Initializing EZServoServo. 2017-04-18T23:10:57.032Z,1492557057.033 [MassServo](DEBUG): Initializing MassServo. 2017-04-18T23:10:57.054Z,1492557057.054 [RudderServo](DEBUG): Initializing EZServoServo. 2017-04-18T23:10:57.060Z,1492557057.060 [RudderServo](DEBUG): Initializing RudderServo. 2017-04-18T23:10:57.066Z,1492557057.066 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-04-18T23:10:57.072Z,1492557057.072 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-04-18T23:10:57.121Z,1492557057.121 [CommandLine](FAULT): Scheduling is paused 2017-04-18T23:10:57.121Z,1492557057.121 [CBIT](INFO): Critical error at 20170418T231055 2017-04-18T23:10:57.122Z,1492557057.122 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-04-18T23:10:57.330Z,1492557057.330 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2017-04-18T23:10:57.330Z,1492557057.330 [DropWeight] Hardware Fault, FailCount= 1 2017-04-18T23:10:57.330Z,1492557057.330 [DropWeight](ERROR): Hardware Fault 2017-04-18T23:10:57.331Z,1492557057.331 [NAL9602](INFO): Powering up NAL9602 2017-04-18T23:10:57.410Z,1492557057.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:57.410Z,1492557057.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:57.412Z,1492557057.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:57.421Z,1492557057.421 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:57.422Z,1492557057.422 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:57.457Z,1492557057.457 [CBIT](INFO): Critical error at 20170418T231057 2017-04-18T23:10:57.459Z,1492557057.459 [CBIT](ERROR): Hardware Fault in component: DropWeight 2017-04-18T23:10:57.476Z,1492557057.476 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2017-04-18T23:10:57.570Z,1492557057.570 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 454 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 11 2017-04-18T23:10:57.570Z,1492557057.570 [Aanderaa_O2] Data Fault, FailCount= 1 2017-04-18T23:10:57.570Z,1492557057.570 [Aanderaa_O2](ERROR): Data Fault 2017-04-18T23:10:57.620Z,1492557057.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:57.620Z,1492557057.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:57.621Z,1492557057.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:57.628Z,1492557057.628 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:57.630Z,1492557057.630 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.062Z,1492557058.062 [CBIT](INFO): Critical error at 20170418T231057 2017-04-18T23:10:58.064Z,1492557058.064 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2017-04-18T23:10:58.188Z,1492557058.188 [Aanderaa_O2](INFO): Powering down 2017-04-18T23:10:58.293Z,1492557058.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.293Z,1492557058.293 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:58.294Z,1492557058.294 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:58.295Z,1492557058.295 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.296Z,1492557058.296 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.532Z,1492557058.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.532Z,1492557058.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:58.534Z,1492557058.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:58.534Z,1492557058.534 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.535Z,1492557058.535 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.924Z,1492557058.924 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.925Z,1492557058.925 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:58.926Z,1492557058.926 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:58.926Z,1492557058.926 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.927Z,1492557058.927 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:58.954Z,1492557058.954 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-04-18T23:10:58.955Z,1492557058.954 [Aanderaa_O2] No Fault, FailCount= 1 2017-04-18T23:10:59.316Z,1492557059.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:59.316Z,1492557059.316 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:59.317Z,1492557059.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:59.318Z,1492557059.318 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:59.319Z,1492557059.319 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:59.723Z,1492557059.723 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:59.724Z,1492557059.724 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:10:59.725Z,1492557059.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:10:59.725Z,1492557059.725 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:10:59.726Z,1492557059.726 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet. 2017-04-18T23:11:00.064Z,1492557060.064 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-04-18T23:11:00.191Z,1492557060.191 [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 & 2017-04-18T23:11:00.325Z,1492557060.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:11:00.326Z,1492557060.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:00.642Z,1492557060.642 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:11:00.643Z,1492557060.644 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:00.896Z,1492557060.895 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-04-18T23:11:00.897Z,1492557060.897 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-04-18T23:11:00.897Z,1492557060.898 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-04-18T23:11:01.051Z,1492557061.051 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid. 2017-04-18T23:11:01.052Z,1492557061.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:01.423Z,1492557061.423 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:01.934Z,1492557061.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:02.330Z,1492557062.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:02.622Z,1492557062.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:03.020Z,1492557063.020 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:03.430Z,1492557063.430 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:03.819Z,1492557063.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:04.228Z,1492557064.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:04.611Z,1492557064.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:04.975Z,1492557064.975 [PNI_TCM](ERROR): CRC does not match. Expected:0x62390 got:0x6977 2017-04-18T23:11:05.007Z,1492557065.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:05.410Z,1492557065.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:06.010Z,1492557066.010 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:06.266Z,1492557066.266 [DAT](DEBUG): 2017-04-18T23:11:06.413Z,1492557066.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:06.719Z,1492557066.719 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:07.080Z,1492557067.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:07.413Z,1492557067.413 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2017-04-18T23:11:07.414Z,1492557067.414 [DAT](DEBUG): MF Frequency Band 2017-04-18T23:11:07.415Z,1492557067.415 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9 2017-04-18T23:11:07.416Z,1492557067.416 [DAT](DEBUG): Apr 18 2017 23:10:28 2017-04-18T23:11:07.469Z,1492557067.469 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:07.976Z,1492557067.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:08.230Z,1492557068.230 [NAL9602](INFO): NAL9602 initialized 2017-04-18T23:11:08.267Z,1492557068.267 [DAT](DEBUG): WARNING: battery low 2017-04-18T23:11:08.270Z,1492557068.270 [DAT](DEBUG): Features enabled [Bearing] 2017-04-18T23:11:08.512Z,1492557068.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:08.765Z,1492557068.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:09.105Z,1492557069.105 [DAT](INFO): entering command mode 2017-04-18T23:11:09.141Z,1492557069.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:09.468Z,1492557069.468 [Aanderaa_O2](FAULT): Timed out starting 2017-04-18T23:11:09.468Z,1492557069.468 [Aanderaa_O2] Communications Fault, FailCount= 2 2017-04-18T23:11:09.468Z,1492557069.468 [Aanderaa_O2](ERROR): Communications Fault 2017-04-18T23:11:09.509Z,1492557069.509 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-18T23:11:09.590Z,1492557069.589 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:09.705Z,1492557069.705 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-04-18T23:11:09.875Z,1492557069.875 [Aanderaa_O2](INFO): Powering down 2017-04-18T23:11:10.026Z,1492557070.026 [DAT](DEBUG): checking for command mode acknowledgment 2017-04-18T23:11:10.027Z,1492557070.027 [DAT](DEBUG): read user prompt 1: user:1> 2017-04-18T23:11:10.028Z,1492557070.028 [DAT](INFO): command mode acknowledged 2017-04-18T23:11:10.153Z,1492557070.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:10.383Z,1492557070.383 [DAT](INFO): setting local address to 0 2017-04-18T23:11:10.549Z,1492557070.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:10.731Z,1492557070.731 [DAT](DEBUG): checking for local address setting acknowledgment 2017-04-18T23:11:10.732Z,1492557070.731 [DAT](INFO): set local address to 0 2017-04-18T23:11:10.803Z,1492557070.803 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:10.861Z,1492557070.861 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-04-18T23:11:10.861Z,1492557070.861 [Aanderaa_O2] No Fault, FailCount= 2 2017-04-18T23:11:11.125Z,1492557071.125 [DAT](DEBUG): read user prompt 2: user:2> 2017-04-18T23:11:11.159Z,1492557071.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:11.647Z,1492557071.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:12.244Z,1492557072.244 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:12.426Z,1492557072.427 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 454 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 11 2017-04-18T23:11:12.427Z,1492557072.427 [Aanderaa_O2] Data Fault, FailCount= 3 2017-04-18T23:11:12.427Z,1492557072.427 [Aanderaa_O2](ERROR): Data Fault 2017-04-18T23:11:12.606Z,1492557072.606 [BPC1](ERROR): Failed to receive battery data 2017-04-18T23:11:12.607Z,1492557072.606 [BPC1] Communications Fault, FailCount= 1 2017-04-18T23:11:12.607Z,1492557072.607 [BPC1](ERROR): Communications Fault 2017-04-18T23:11:12.669Z,1492557072.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:12.725Z,1492557072.725 [CBIT](ERROR): Data Fault in component: Aanderaa_O2 2017-04-18T23:11:12.725Z,1492557072.725 [CBIT](FAULT): Data Fault in component: Aanderaa_O2 2017-04-18T23:11:12.726Z,1492557072.726 [CBIT](ERROR): Communications Fault in component: BPC1 2017-04-18T23:11:12.804Z,1492557072.804 [Aanderaa_O2](INFO): Powering down 2017-04-18T23:11:12.883Z,1492557072.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:13.318Z,1492557073.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:13.828Z,1492557073.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:13.953Z,1492557073.953 [CBIT](INFO): Clearing failed state for component BPC1 2017-04-18T23:11:13.954Z,1492557073.954 [BPC1] No Fault, FailCount= 1 2017-04-18T23:11:14.426Z,1492557074.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:14.707Z,1492557074.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:15.021Z,1492557075.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:15.422Z,1492557075.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:16.006Z,1492557076.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:16.404Z,1492557076.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:16.894Z,1492557076.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:17.142Z,1492557077.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:17.447Z,1492557077.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:17.844Z,1492557077.844 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:18.444Z,1492557078.444 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:18.922Z,1492557078.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:19.227Z,1492557079.227 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:19.601Z,1492557079.602 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:19.999Z,1492557080.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:20.523Z,1492557080.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:20.000Z,1492557081.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:21.242Z,1492557081.242 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:21.860Z,1492557081.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:22.312Z,1492557082.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:22.680Z,1492557082.680 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:22.956Z,1492557082.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:23.344Z,1492557083.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:23.890Z,1492557083.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:24.281Z,1492557084.281 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:24.654Z,1492557084.654 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:24.953Z,1492557084.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:25.371Z,1492557085.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:25.818Z,1492557085.818 [PNI_TCM](ERROR): CRC does not match. Expected:0x55698 got:0x36476 2017-04-18T23:11:25.918Z,1492557085.918 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:26.386Z,1492557086.386 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:26.699Z,1492557086.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:27.064Z,1492557087.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-04-18T23:11:27.447Z,1492557087.447 [DeadReckonUsingMultipleVelocityS