2017-04-19T03:44:34.678Z,1492573474.678 [Supervisor](DEBUG): Initializing supervisor.
2017-04-19T03:44:34.681Z,1492573474.681 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-04-19T03:44:34.682Z,1492573474.682 [SyncHandler](INFO): Protected caller Thread ID is 771
2017-04-19T03:44:34.682Z,1492573474.682 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-04-19T03:44:34.683Z,1492573474.683 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-04-19T03:44:34.684Z,1492573474.684 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 772
2017-04-19T03:44:34.687Z,1492573474.687 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-04-19T03:44:34.700Z,1492573474.700 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-04-19T03:44:34.701Z,1492573474.701 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-04-19T03:44:34.701Z,1492573474.701 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 773
2017-04-19T03:44:34.702Z,1492573474.702 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-04-19T03:44:34.703Z,1492573474.703 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-04-19T03:44:34.703Z,1492573474.703 [logger ThreadHandler](INFO): Protected caller Thread ID is 774
2017-04-19T03:44:34.705Z,1492573474.705 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-04-19T03:44:34.706Z,1492573474.706 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-04-19T03:44:34.710Z,1492573474.710 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-04-19T03:44:34.798Z,1492573474.798 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-04-19T03:44:35.128Z,1492573475.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-04-19T03:44:35.130Z,1492573475.130 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-04-19T03:44:35.265Z,1492573475.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-04-19T03:44:35.265Z,1492573475.265 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-04-19T03:44:35.467Z,1492573475.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-04-19T03:44:35.468Z,1492573475.468 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-04-19T03:44:35.569Z,1492573475.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-04-19T03:44:35.571Z,1492573475.571 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-04-19T03:44:35.768Z,1492573475.768 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-04-19T03:44:35.770Z,1492573475.770 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-04-19T03:44:36.243Z,1492573476.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-04-19T03:44:36.244Z,1492573476.244 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-04-19T03:44:36.535Z,1492573476.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-04-19T03:44:36.535Z,1492573476.535 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-04-19T03:44:36.622Z,1492573476.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-04-19T03:44:36.623Z,1492573476.623 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-04-19T03:44:36.766Z,1492573476.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-04-19T03:44:36.767Z,1492573476.767 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-04-19T03:44:36.968Z,1492573476.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-04-19T03:44:36.969Z,1492573476.969 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-04-19T03:44:37.415Z,1492573477.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-04-19T03:44:37.415Z,1492573477.415 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-04-19T03:44:42.262Z,1492573482.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-04-19T03:44:42.263Z,1492573482.263 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-04-19T03:44:42.584Z,1492573482.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-04-19T03:44:42.584Z,1492573482.584 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-04-19T03:44:43.513Z,1492573483.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-04-19T03:44:43.515Z,1492573483.515 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/
2017-04-19T03:44:43.519Z,1492573483.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg
2017-04-19T03:44:43.607Z,1492573483.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg
2017-04-19T03:44:43.776Z,1492573483.776 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg
2017-04-19T03:44:44.016Z,1492573484.016 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-04-19T03:44:44.017Z,1492573484.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg
2017-04-19T03:44:44.117Z,1492573484.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg
2017-04-19T03:44:44.230Z,1492573484.230 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg
2017-04-19T03:44:44.337Z,1492573484.337 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg
2017-04-19T03:44:44.484Z,1492573484.484 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg
2017-04-19T03:44:44.651Z,1492573484.651 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg
2017-04-19T03:44:44.762Z,1492573484.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg
2017-04-19T03:44:44.853Z,1492573484.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg
2017-04-19T03:44:44.954Z,1492573484.954 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-04-19T03:44:44.958Z,1492573484.958 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-04-19T03:44:45.001Z,1492573485.001 [DepthRateCalculator] Loaded
2017-04-19T03:44:45.002Z,1492573485.002 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-04-19T03:44:45.008Z,1492573485.008 [PitchRateCalculator] Loaded
2017-04-19T03:44:45.009Z,1492573485.009 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-04-19T03:44:45.021Z,1492573485.021 [SpeedCalculator] Loaded
2017-04-19T03:44:45.021Z,1492573485.021 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-04-19T03:44:45.044Z,1492573485.044 [TempGradientCalculator] Loaded
2017-04-19T03:44:45.044Z,1492573485.044 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-04-19T03:44:45.060Z,1492573485.060 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-04-19T03:44:45.061Z,1492573485.061 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-04-19T03:44:45.067Z,1492573485.067 [YawRateCalculator] Loaded
2017-04-19T03:44:45.067Z,1492573485.067 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-04-19T03:44:45.067Z,1492573485.067 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-04-19T03:44:45.068Z,1492573485.068 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-04-19T03:44:45.193Z,1492573485.193 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-04-19T03:44:45.194Z,1492573485.194 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-04-19T03:44:45.249Z,1492573485.249 [DeadReckonUsingSpeedCalculator] Loaded
2017-04-19T03:44:45.250Z,1492573485.250 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-04-19T03:44:45.306Z,1492573485.306 [DeadReckonWithRespectToSeafloor] Loaded
2017-04-19T03:44:45.306Z,1492573485.306 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2017-04-19T03:44:45.323Z,1492573485.323 [NavChart] Loaded
2017-04-19T03:44:45.323Z,1492573485.323 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-04-19T03:44:45.328Z,1492573485.328 [UniversalFixResidualReporter] Loaded
2017-04-19T03:44:45.328Z,1492573485.328 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-04-19T03:44:45.329Z,1492573485.329 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-04-19T03:44:45.329Z,1492573485.329 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-04-19T03:44:45.523Z,1492573485.523 [Aanderaa_O2] Loaded
2017-04-19T03:44:45.523Z,1492573485.523 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-04-19T03:44:45.538Z,1492573485.538 [PAR_Licor] Loaded
2017-04-19T03:44:45.539Z,1492573485.539 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-04-19T03:44:45.591Z,1492573485.591 [WetLabsBB2FL] Loaded
2017-04-19T03:44:45.591Z,1492573485.591 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-04-19T03:44:45.592Z,1492573485.592 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4066F4E0
2017-04-19T03:44:45.593Z,1492573485.593 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 853
2017-04-19T03:44:45.593Z,1492573485.593 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-04-19T03:44:45.594Z,1492573485.594 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-04-19T03:44:45.635Z,1492573485.635 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-04-19T03:44:45.635Z,1492573485.635 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-04-19T03:44:45.645Z,1492573485.645 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-04-19T03:44:45.645Z,1492573485.645 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-04-19T03:44:45.932Z,1492573485.931 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-04-19T03:44:45.932Z,1492573485.932 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-04-19T03:44:46.094Z,1492573486.093 [SBIT](DEBUG): Construct Startup Built In Test.
2017-04-19T03:44:46.126Z,1492573486.126 [SBIT] Loaded
2017-04-19T03:44:46.126Z,1492573486.126 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-04-19T03:44:46.127Z,1492573486.127 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-04-19T03:44:46.156Z,1492573486.156 [IBIT] Loaded
2017-04-19T03:44:46.156Z,1492573486.156 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-04-19T03:44:46.160Z,1492573486.160 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-04-19T03:44:46.316Z,1492573486.316 [CBIT] Loaded
2017-04-19T03:44:46.316Z,1492573486.316 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-04-19T03:44:46.317Z,1492573486.317 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-04-19T03:44:46.317Z,1492573486.317 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-04-19T03:44:46.941Z,1492573486.941 [DataOverHttps] Loaded
2017-04-19T03:44:46.941Z,1492573486.941 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-04-19T03:44:46.956Z,1492573486.955 [Depth_Keller] Loaded
2017-04-19T03:44:46.956Z,1492573486.956 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-04-19T03:44:47.052Z,1492573487.052 [NAL9602] Loaded
2017-04-19T03:44:47.052Z,1492573487.052 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-04-19T03:44:47.062Z,1492573487.062 [Onboard] Loaded
2017-04-19T03:44:47.062Z,1492573487.062 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-04-19T03:44:47.069Z,1492573487.069 [Radio_Surface] Loaded
2017-04-19T03:44:47.070Z,1492573487.070 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-04-19T03:44:47.071Z,1492573487.071 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2017-04-19T03:44:47.071Z,1492573487.071 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 854
2017-04-19T03:44:47.210Z,1492573487.210 [DAT] Loaded
2017-04-19T03:44:47.210Z,1492573487.210 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2017-04-19T03:44:47.217Z,1492573487.217 [SCPI] Loaded
2017-04-19T03:44:47.217Z,1492573487.217 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2017-04-19T03:44:47.411Z,1492573487.411 [PNI_TCM] Loaded
2017-04-19T03:44:47.411Z,1492573487.411 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-04-19T03:44:47.770Z,1492573487.770 [Rowe_600LCM] Loaded
2017-04-19T03:44:47.771Z,1492573487.771 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-04-19T03:44:47.772Z,1492573487.772 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409904E0
2017-04-19T03:44:47.772Z,1492573487.772 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 855
2017-04-19T03:44:49.819Z,1492573489.819 [BPC1] Loaded
2017-04-19T03:44:49.819Z,1492573489.819 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-04-19T03:44:49.820Z,1492573489.819 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-04-19T03:44:49.820Z,1492573489.820 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-04-19T03:44:49.952Z,1492573489.952 [BuoyancyServo] Loaded
2017-04-19T03:44:49.952Z,1492573489.952 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-04-19T03:44:49.969Z,1492573489.969 [ElevatorServo] Loaded
2017-04-19T03:44:49.969Z,1492573489.969 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-04-19T03:44:49.985Z,1492573489.985 [MassServo] Loaded
2017-04-19T03:44:49.985Z,1492573489.985 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-04-19T03:44:50.001Z,1492573490.001 [RudderServo] Loaded
2017-04-19T03:44:50.002Z,1492573490.002 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-04-19T03:44:50.017Z,1492573490.017 [ThrusterServo] Loaded
2017-04-19T03:44:50.017Z,1492573490.017 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-04-19T03:44:50.018Z,1492573490.018 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-04-19T03:44:50.019Z,1492573490.019 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-04-19T03:44:50.090Z,1492573490.090 [VerticalControl](DEBUG): Construct VerticalControl.
2017-04-19T03:44:50.184Z,1492573490.184 [VerticalControl] Loaded
2017-04-19T03:44:50.184Z,1492573490.184 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-04-19T03:44:50.185Z,1492573490.185 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-04-19T03:44:50.247Z,1492573490.247 [HorizontalControl] Loaded
2017-04-19T03:44:50.247Z,1492573490.247 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-04-19T03:44:50.248Z,1492573490.248 [SpeedControl](DEBUG): Construct SpeedControl.
2017-04-19T03:44:50.249Z,1492573490.249 [SpeedControl] Loaded
2017-04-19T03:44:50.250Z,1492573490.250 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-04-19T03:44:50.250Z,1492573490.250 [LoopControl](DEBUG): Construct LoopControl.
2017-04-19T03:44:50.251Z,1492573490.251 [LoopControl] Loaded
2017-04-19T03:44:50.251Z,1492573490.251 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-04-19T03:44:50.252Z,1492573490.252 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-04-19T03:44:50.253Z,1492573490.253 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-04-19T03:44:50.377Z,1492573490.376 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-04-19T03:44:50.377Z,1492573490.377 [StratificationFrontDetector](DEBUG): (re)initializing
2017-04-19T03:44:50.377Z,1492573490.377 [StratificationFrontDetector] Loaded
2017-04-19T03:44:50.377Z,1492573490.377 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-04-19T03:44:50.403Z,1492573490.403 [DepAvgTempFrontDetector] Loaded
2017-04-19T03:44:50.403Z,1492573490.403 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread.
2017-04-19T03:44:50.403Z,1492573490.404 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-04-19T03:44:50.404Z,1492573490.404 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-04-19T03:44:50.538Z,1492573490.538 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-04-19T03:44:50.542Z,1492573490.542 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-04-19T03:44:50.543Z,1492573490.543 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-04-19T03:44:50.550Z,1492573490.550 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-04-19T03:44:50.551Z,1492573490.551 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0
2017-04-19T03:44:50.552Z,1492573490.552 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 856
2017-04-19T03:44:50.557Z,1492573490.556 [Supervisor](INFO): Main Thread ID is 766
2017-04-19T03:44:50.557Z,1492573490.557 [Supervisor](DEBUG): Running supervisor.
2017-04-19T03:44:50.557Z,1492573490.557 [CommandLine ThreadHandler](INFO): Handler Thread ID is 857
2017-04-19T03:44:50.560Z,1492573490.560 [controlThread ThreadHandler](INFO): Handler Thread ID is 858
2017-04-19T03:44:50.561Z,1492573490.561 [controlThread](DEBUG): Initializing ControlThread
2017-04-19T03:44:50.562Z,1492573490.562 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-04-19T03:44:50.562Z,1492573490.562 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-04-19T03:44:50.562Z,1492573490.562 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-04-19T03:44:50.563Z,1492573490.563 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-04-19T03:44:50.563Z,1492573490.563 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-04-19T03:44:50.564Z,1492573490.564 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-04-19T03:44:50.565Z,1492573490.565 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-04-19T03:44:50.565Z,1492573490.565 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-04-19T03:44:50.566Z,1492573490.566 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-04-19T03:44:50.566Z,1492573490.566 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-04-19T03:44:50.567Z,1492573490.567 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-04-19T03:44:50.567Z,1492573490.567 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-04-19T03:44:50.567Z,1492573490.568 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-04-19T03:44:50.568Z,1492573490.568 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-04-19T03:44:50.568Z,1492573490.568 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-04-19T03:44:50.569Z,1492573490.569 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-04-19T03:44:50.569Z,1492573490.569 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-04-19T03:44:50.571Z,1492573490.571 [SBIT](INFO): Initialize SBIT Component.
2017-04-19T03:44:50.572Z,1492573490.572 [SBIT](IMPORTANT): git: 2017-04-12-3-g3a402a6
2017-04-19T03:44:50.572Z,1492573490.572 [SBIT](INFO): git hash: 3a402a68fbc2d1f037574985f21b5bcd039a347c
2017-04-19T03:44:50.574Z,1492573490.574 [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-19T03:44:50.574Z,1492573490.574 [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-19T03:44:50.575Z,1492573490.575 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2017-04-19T03:44:50.576Z,1492573490.576 [IBIT](INFO): Initialize IBIT Component.
2017-04-19T03:44:50.576Z,1492573490.576 [CBIT](DEBUG): Initialize CBIT Component.
2017-04-19T03:44:50.577Z,1492573490.577 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-04-19T03:44:50.577Z,1492573490.577 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2017-04-19T03:44:50.578Z,1492573490.578 [logger ThreadHandler](INFO): Handler Thread ID is 859
2017-04-19T03:44:50.596Z,1492573490.596 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 860
2017-04-19T03:44:50.597Z,1492573490.597 [WetLabsBB2FL](INFO): Powering down
2017-04-19T03:44:50.620Z,1492573490.620 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 861
2017-04-19T03:44:50.631Z,1492573490.631 [Radio_Surface](INFO): Powering up
2017-04-19T03:44:50.640Z,1492573490.640 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 862
2017-04-19T03:44:50.640Z,1492573490.640 [Rowe_600LCM](INFO): Initializing
2017-04-19T03:44:50.641Z,1492573490.641 [Rowe_600LCM](INFO): Checking LCM
2017-04-19T03:44:50.641Z,1492573490.641 [Rowe_600LCM](INFO): LCM OK
2017-04-19T03:44:50.641Z,1492573490.641 [Rowe_600LCM](INFO): Powering up
2017-04-19T03:44:50.648Z,1492573490.648 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 863
2017-04-19T03:44:50.658Z,1492573490.658 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-04-19T03:44:50.658Z,1492573490.658 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-04-19T03:44:50.658Z,1492573490.658 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-04-19T03:44:50.658Z,1492573490.658 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-04-19T03:44:50.658Z,1492573490.658 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-04-19T03:44:50.659Z,1492573490.659 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-04-19T03:44:50.659Z,1492573490.659 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-04-19T03:44:50.666Z,1492573490.667 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-04-19T03:44:50.669Z,1492573490.669 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-04-19T03:44:50.669Z,1492573490.669 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-04-19T03:44:50.670Z,1492573490.670 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-04-19T03:44:50.671Z,1492573490.671 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-04-19T03:44:50.671Z,1492573490.671 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-04-19T03:44:50.672Z,1492573490.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-04-19T03:44:50.672Z,1492573490.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-04-19T03:44:50.672Z,1492573490.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-04-19T03:44:50.672Z,1492573490.672 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-04-19T03:44:50.672Z,1492573490.672 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-04-19T03:44:50.673Z,1492573490.673 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-04-19T03:44:50.673Z,1492573490.673 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-04-19T03:44:50.674Z,1492573490.674 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-04-19T03:44:50.728Z,1492573490.728 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-04-19T03:44:50.754Z,1492573490.754 [MissionManager](DEBUG):
2017-04-19T03:44:50.754Z,1492573490.754 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-04-19T03:44:50.825Z,1492573490.825 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-04-19T03:44:50.827Z,1492573490.827 [Default:A.Wait](DEBUG): Construct Wait.
2017-04-19T03:44:50.828Z,1492573490.828 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-04-19T03:44:50.871Z,1492573490.871 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-04-19T03:44:50.874Z,1492573490.874 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-04-19T03:44:50.896Z,1492573490.896 [Default:E.Execute](DEBUG): Construct Execute.
2017-04-19T03:44:50.900Z,1492573490.900 [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-19T03:44:50.912Z,1492573490.912 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,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-19T03:44:50.990Z,1492573490.990 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-04-19T03:44:51.014Z,1492573491.014 [Depth_Keller](ERROR): Pressure reading out of range: 1658.386841 decibar
2017-04-19T03:44:51.083Z,1492573491.083 [DAT](INFO): Powering up
2017-04-19T03:44:51.084Z,1492573491.084 [DAT](DEBUG): Initializing DAT.
2017-04-19T03:44:51.280Z,1492573491.280 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-04-19T03:44:51.384Z,1492573491.384 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:51.384Z,1492573491.384 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:51.588Z,1492573491.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:51.610Z,1492573491.610 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:51.635Z,1492573491.635 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:51.732Z,1492573491.731 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-04-19T03:44:51.739Z,1492573491.740 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-04-19T03:44:51.754Z,1492573491.754 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-04-19T03:44:51.760Z,1492573491.760 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-04-19T03:44:51.766Z,1492573491.766 [MassServo](DEBUG): Initializing EZServoServo.
2017-04-19T03:44:51.771Z,1492573491.772 [MassServo](DEBUG): Initializing MassServo.
2017-04-19T03:44:51.777Z,1492573491.777 [RudderServo](DEBUG): Initializing EZServoServo.
2017-04-19T03:44:51.783Z,1492573491.783 [RudderServo](DEBUG): Initializing RudderServo.
2017-04-19T03:44:51.789Z,1492573491.789 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-04-19T03:44:51.795Z,1492573491.795 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-04-19T03:44:51.828Z,1492573491.828 [CommandLine](FAULT): Scheduling is paused
2017-04-19T03:44:51.828Z,1492573491.829 [CBIT](INFO): Critical error at 20170419T034450
2017-04-19T03:44:51.829Z,1492573491.829 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-04-19T03:44:52.111Z,1492573492.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:52.111Z,1492573492.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:52.112Z,1492573492.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:52.113Z,1492573492.113 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:52.114Z,1492573492.114 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:52.277Z,1492573492.277 [Aanderaa_O2](DEBUG): Error parsing data: StartupInfo 4831F 454 Mode AADI Smart Sensor Terminal Protocol RS232 Protocol Version 3 Config Version 11
2017-04-19T03:44:52.277Z,1492573492.277 [Aanderaa_O2] Data Fault, FailCount= 1
2017-04-19T03:44:52.278Z,1492573492.277 [Aanderaa_O2](ERROR): Data Fault
2017-04-19T03:44:52.287Z,1492573492.286 [NAL9602](INFO): Powering up NAL9602
2017-04-19T03:44:52.362Z,1492573492.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:52.362Z,1492573492.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:52.364Z,1492573492.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:52.376Z,1492573492.376 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:52.377Z,1492573492.377 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:52.727Z,1492573492.727 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-04-19T03:44:52.727Z,1492573492.727 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-04-19T03:44:52.728Z,1492573492.727 [BuoyancyServo] Communications Fault, FailCount= 1
2017-04-19T03:44:52.728Z,1492573492.727 [BuoyancyServo](ERROR): Communications Fault
2017-04-19T03:44:52.931Z,1492573492.931 [CBIT](ERROR): Data Fault in component: Aanderaa_O2
2017-04-19T03:44:52.931Z,1492573492.931 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-04-19T03:44:53.056Z,1492573493.056 [Aanderaa_O2](INFO): Powering down
2017-04-19T03:44:53.179Z,1492573493.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.179Z,1492573493.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:53.181Z,1492573493.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:53.181Z,1492573493.181 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.182Z,1492573493.182 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.188Z,1492573493.188 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-04-19T03:44:53.189Z,1492573493.188 [BuoyancyServo](INFO): Powering down
2017-04-19T03:44:53.411Z,1492573493.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.411Z,1492573493.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:53.412Z,1492573493.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:53.413Z,1492573493.413 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.414Z,1492573493.414 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.775Z,1492573493.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.775Z,1492573493.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:53.776Z,1492573493.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:53.777Z,1492573493.777 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.778Z,1492573493.778 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:53.805Z,1492573493.805 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2017-04-19T03:44:53.805Z,1492573493.806 [Aanderaa_O2] No Fault, FailCount= 1
2017-04-19T03:44:54.175Z,1492573494.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:54.175Z,1492573494.175 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:54.176Z,1492573494.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:54.177Z,1492573494.177 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:54.178Z,1492573494.178 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:54.603Z,1492573494.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:54.603Z,1492573494.603 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:54.604Z,1492573494.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:54.605Z,1492573494.605 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:54.606Z,1492573494.606 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T03:44:54.919Z,1492573494.920 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-04-19T03:44:55.078Z,1492573495.078 [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-19T03:44:55.130Z,1492573495.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:55.144Z,1492573495.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T03:44:55.426Z,1492573495.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T03:44:55.437Z,1492573495.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, d