2017-12-08T17:35:03.042Z,1512754503.042 [Supervisor](DEBUG): Initializing supervisor. 2017-12-08T17:35:03.044Z,1512754503.044 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2017-12-08T17:35:03.045Z,1512754503.045 [SyncHandler](INFO): Protected caller Thread ID is 801 2017-12-08T17:35:03.045Z,1512754503.045 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-12-08T17:35:03.046Z,1512754503.046 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2017-12-08T17:35:03.047Z,1512754503.047 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 802 2017-12-08T17:35:03.049Z,1512754503.049 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-12-08T17:35:03.060Z,1512754503.060 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-12-08T17:35:03.061Z,1512754503.061 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2017-12-08T17:35:03.062Z,1512754503.062 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 803 2017-12-08T17:35:03.062Z,1512754503.062 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-12-08T17:35:03.063Z,1512754503.063 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2017-12-08T17:35:03.064Z,1512754503.064 [logger ThreadHandler](INFO): Protected caller Thread ID is 804 2017-12-08T17:35:03.066Z,1512754503.066 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-12-08T17:35:03.066Z,1512754503.066 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-12-08T17:35:03.070Z,1512754503.070 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-12-08T17:35:03.166Z,1512754503.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-12-08T17:35:03.168Z,1512754503.168 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-12-08T17:35:03.267Z,1512754503.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-12-08T17:35:03.269Z,1512754503.269 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-12-08T17:35:03.453Z,1512754503.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-12-08T17:35:03.453Z,1512754503.453 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-12-08T17:35:03.744Z,1512754503.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-12-08T17:35:03.746Z,1512754503.746 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-12-08T17:35:04.056Z,1512754504.056 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-12-08T17:35:04.058Z,1512754504.058 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-12-08T17:35:04.492Z,1512754504.492 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-12-08T17:35:04.493Z,1512754504.493 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-12-08T17:35:04.819Z,1512754504.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-12-08T17:35:04.820Z,1512754504.820 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-12-08T17:35:05.255Z,1512754505.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-12-08T17:35:05.256Z,1512754505.256 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-12-08T17:35:05.337Z,1512754505.337 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-12-08T17:35:05.620Z,1512754505.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-12-08T17:35:05.622Z,1512754505.622 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-12-08T17:35:05.761Z,1512754505.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-12-08T17:35:05.761Z,1512754505.761 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-12-08T17:35:05.956Z,1512754505.956 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-12-08T17:35:05.958Z,1512754505.958 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-12-08T17:35:06.152Z,1512754506.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-12-08T17:35:06.152Z,1512754506.152 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-12-08T17:35:06.249Z,1512754506.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-12-08T17:35:06.249Z,1512754506.249 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-12-08T17:35:06.387Z,1512754506.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-12-08T17:35:06.390Z,1512754506.390 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2017-12-08T17:35:06.392Z,1512754506.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2017-12-08T17:35:06.649Z,1512754506.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-12-08T17:35:06.650Z,1512754506.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2017-12-08T17:35:07.666Z,1512754507.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2017-12-08T17:35:07.805Z,1512754507.805 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2017-12-08T17:35:07.898Z,1512754507.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2017-12-08T17:35:08.026Z,1512754508.026 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2017-12-08T17:35:08.159Z,1512754508.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2017-12-08T17:35:08.320Z,1512754508.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2017-12-08T17:35:08.417Z,1512754508.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2017-12-08T17:35:08.509Z,1512754508.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2017-12-08T17:35:08.605Z,1512754508.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2017-12-08T17:35:08.687Z,1512754508.687 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2017-12-08T17:35:08.691Z,1512754508.691 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-12-08T17:35:08.811Z,1512754508.811 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-12-08T17:35:08.812Z,1512754508.812 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-12-08T17:35:08.835Z,1512754508.835 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-12-08T17:35:08.836Z,1512754508.836 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-12-08T17:35:08.889Z,1512754508.889 [DepthRateCalculator] Loaded 2017-12-08T17:35:08.889Z,1512754508.889 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-12-08T17:35:08.895Z,1512754508.895 [PitchRateCalculator] Loaded 2017-12-08T17:35:08.895Z,1512754508.895 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-12-08T17:35:08.910Z,1512754508.910 [SpeedCalculator] Loaded 2017-12-08T17:35:08.911Z,1512754508.911 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-12-08T17:35:08.932Z,1512754508.932 [TempGradientCalculator] Loaded 2017-12-08T17:35:08.932Z,1512754508.932 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-12-08T17:35:08.947Z,1512754508.947 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-12-08T17:35:08.947Z,1512754508.947 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-12-08T17:35:08.953Z,1512754508.953 [YawRateCalculator] Loaded 2017-12-08T17:35:08.953Z,1512754508.953 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-12-08T17:35:08.954Z,1512754508.954 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-12-08T17:35:08.954Z,1512754508.954 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-12-08T17:35:08.990Z,1512754508.990 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-12-08T17:35:08.990Z,1512754508.990 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-12-08T17:35:09.430Z,1512754509.430 [AcousticModem_Benthos_ATM900] Loaded 2017-12-08T17:35:09.430Z,1512754509.430 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2017-12-08T17:35:09.568Z,1512754509.568 [DataOverHttps] Loaded 2017-12-08T17:35:09.569Z,1512754509.569 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-12-08T17:35:09.582Z,1512754509.582 [Depth_Keller] Loaded 2017-12-08T17:35:09.582Z,1512754509.582 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-12-08T17:35:09.587Z,1512754509.587 [DropWeight] Loaded 2017-12-08T17:35:09.587Z,1512754509.587 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-12-08T17:35:09.680Z,1512754509.680 [NAL9602] Loaded 2017-12-08T17:35:09.680Z,1512754509.680 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-12-08T17:35:09.696Z,1512754509.696 [Onboard] Loaded 2017-12-08T17:35:09.696Z,1512754509.696 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-12-08T17:35:09.706Z,1512754509.706 [Radio_Surface] Loaded 2017-12-08T17:35:09.706Z,1512754509.706 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-12-08T17:35:09.707Z,1512754509.707 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A24E0 2017-12-08T17:35:09.707Z,1512754509.707 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 884 2017-12-08T17:35:09.859Z,1512754509.859 [PNI_TCM] Loaded 2017-12-08T17:35:09.859Z,1512754509.859 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-12-08T17:35:09.918Z,1512754509.918 [Rowe_600LCM] Loaded 2017-12-08T17:35:09.918Z,1512754509.918 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2017-12-08T17:35:09.919Z,1512754509.919 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D24E0 2017-12-08T17:35:09.919Z,1512754509.919 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 885 2017-12-08T17:35:11.605Z,1512754511.605 [BPC1] Loaded 2017-12-08T17:35:11.605Z,1512754511.605 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-12-08T17:35:11.606Z,1512754511.606 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-12-08T17:35:11.608Z,1512754511.608 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-12-08T17:35:11.809Z,1512754511.809 [SBIT](DEBUG): Construct Startup Built In Test. 2017-12-08T17:35:11.916Z,1512754511.916 [SBIT] Loaded 2017-12-08T17:35:11.927Z,1512754511.927 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-12-08T17:35:11.928Z,1512754511.928 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-12-08T17:35:11.959Z,1512754511.959 [IBIT] Loaded 2017-12-08T17:35:11.960Z,1512754511.960 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-12-08T17:35:11.963Z,1512754511.963 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-12-08T17:35:12.201Z,1512754512.201 [CBIT] Loaded 2017-12-08T17:35:12.201Z,1512754512.201 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-12-08T17:35:12.202Z,1512754512.202 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-12-08T17:35:12.202Z,1512754512.202 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-12-08T17:35:12.381Z,1512754512.381 [Aanderaa_O2] Loaded 2017-12-08T17:35:12.382Z,1512754512.382 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-12-08T17:35:12.456Z,1512754512.456 [CTD_NeilBrown] Loaded 2017-12-08T17:35:12.456Z,1512754512.456 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-12-08T17:35:12.457Z,1512754512.457 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408994E0 2017-12-08T17:35:12.458Z,1512754512.458 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 886 2017-12-08T17:35:12.489Z,1512754512.489 [CTD_Seabird] Loaded 2017-12-08T17:35:12.490Z,1512754512.490 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2017-12-08T17:35:12.491Z,1512754512.491 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408C94E0 2017-12-08T17:35:12.491Z,1512754512.491 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887 2017-12-08T17:35:12.508Z,1512754512.508 [ESPComponent] Loaded 2017-12-08T17:35:12.508Z,1512754512.508 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2017-12-08T17:35:12.522Z,1512754512.522 [PAR_Licor] Loaded 2017-12-08T17:35:12.522Z,1512754512.522 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-12-08T17:35:12.570Z,1512754512.570 [WetLabsBB2FL] Loaded 2017-12-08T17:35:12.570Z,1512754512.570 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-12-08T17:35:12.571Z,1512754512.571 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408F94E0 2017-12-08T17:35:12.571Z,1512754512.571 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888 2017-12-08T17:35:12.613Z,1512754512.613 [WetLabsSeaOWL_UV_A] Loaded 2017-12-08T17:35:12.614Z,1512754512.614 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread. 2017-12-08T17:35:12.615Z,1512754512.615 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409294E0 2017-12-08T17:35:12.615Z,1512754512.615 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 889 2017-12-08T17:35:12.615Z,1512754512.615 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-12-08T17:35:12.616Z,1512754512.616 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-12-08T17:35:12.879Z,1512754512.879 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-12-08T17:35:12.880Z,1512754512.880 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-12-08T17:35:12.963Z,1512754512.963 [VerticalControl](DEBUG): Construct VerticalControl. 2017-12-08T17:35:13.058Z,1512754513.058 [VerticalControl] Loaded 2017-12-08T17:35:13.058Z,1512754513.058 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-12-08T17:35:13.059Z,1512754513.059 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-12-08T17:35:13.119Z,1512754513.119 [HorizontalControl] Loaded 2017-12-08T17:35:13.119Z,1512754513.119 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-12-08T17:35:13.120Z,1512754513.120 [SpeedControl](DEBUG): Construct SpeedControl. 2017-12-08T17:35:13.125Z,1512754513.125 [SpeedControl] Loaded 2017-12-08T17:35:13.126Z,1512754513.126 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-12-08T17:35:13.126Z,1512754513.126 [LoopControl](DEBUG): Construct LoopControl. 2017-12-08T17:35:13.127Z,1512754513.127 [LoopControl] Loaded 2017-12-08T17:35:13.127Z,1512754513.127 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-12-08T17:35:13.127Z,1512754513.127 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-12-08T17:35:13.128Z,1512754513.128 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-12-08T17:35:13.245Z,1512754513.245 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-12-08T17:35:13.246Z,1512754513.246 [StratificationFrontDetector](DEBUG): (re)initializing 2017-12-08T17:35:13.246Z,1512754513.246 [StratificationFrontDetector] Loaded 2017-12-08T17:35:13.246Z,1512754513.246 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-12-08T17:35:13.247Z,1512754513.247 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-12-08T17:35:13.247Z,1512754513.247 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-12-08T17:35:13.362Z,1512754513.362 [BuoyancyServo] Loaded 2017-12-08T17:35:13.362Z,1512754513.362 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-12-08T17:35:13.373Z,1512754513.373 [ElevatorServo] Loaded 2017-12-08T17:35:13.373Z,1512754513.373 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-12-08T17:35:13.384Z,1512754513.384 [MassServo] Loaded 2017-12-08T17:35:13.384Z,1512754513.384 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-12-08T17:35:13.395Z,1512754513.395 [RudderServo] Loaded 2017-12-08T17:35:13.395Z,1512754513.395 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-12-08T17:35:13.406Z,1512754513.406 [ThrusterServo] Loaded 2017-12-08T17:35:13.406Z,1512754513.406 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-12-08T17:35:13.406Z,1512754513.406 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-12-08T17:35:13.407Z,1512754513.407 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-12-08T17:35:13.518Z,1512754513.518 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-12-08T17:35:13.518Z,1512754513.518 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-12-08T17:35:13.572Z,1512754513.572 [DeadReckonUsingSpeedCalculator] Loaded 2017-12-08T17:35:13.572Z,1512754513.572 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-12-08T17:35:13.588Z,1512754513.588 [NavChart] Loaded 2017-12-08T17:35:13.588Z,1512754513.588 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-12-08T17:35:13.592Z,1512754513.592 [UniversalFixResidualReporter] Loaded 2017-12-08T17:35:13.592Z,1512754513.592 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-12-08T17:35:13.592Z,1512754513.592 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-12-08T17:35:13.596Z,1512754513.596 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-12-08T17:35:13.597Z,1512754513.597 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-12-08T17:35:13.603Z,1512754513.603 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-12-08T17:35:13.605Z,1512754513.605 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A7B4E0 2017-12-08T17:35:13.605Z,1512754513.605 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 890 2017-12-08T17:35:13.610Z,1512754513.610 [Supervisor](INFO): Main Thread ID is 797 2017-12-08T17:35:13.610Z,1512754513.610 [Supervisor](DEBUG): Running supervisor. 2017-12-08T17:35:13.610Z,1512754513.610 [CommandLine ThreadHandler](INFO): Handler Thread ID is 891 2017-12-08T17:35:13.613Z,1512754513.613 [controlThread ThreadHandler](INFO): Handler Thread ID is 892 2017-12-08T17:35:13.613Z,1512754513.613 [controlThread](DEBUG): Initializing ControlThread 2017-12-08T17:35:13.614Z,1512754513.614 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-12-08T17:35:13.614Z,1512754513.614 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-12-08T17:35:13.615Z,1512754513.615 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-12-08T17:35:13.615Z,1512754513.615 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-12-08T17:35:13.616Z,1512754513.616 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-12-08T17:35:13.616Z,1512754513.616 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-12-08T17:35:13.623Z,1512754513.623 [SBIT](INFO): Initialize SBIT Component. 2017-12-08T17:35:13.623Z,1512754513.623 [SBIT](IMPORTANT): git: 2017-11-20A-2-g40c84ee 2017-12-08T17:35:13.624Z,1512754513.624 [SBIT](INFO): git hash: 40c84eed00c272bed270104db0bfbfef219794f5 2017-12-08T17:35:13.624Z,1512754513.624 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2017-12-08T17:35:13.625Z,1512754513.625 [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 2017-12-08T17:35:13.626Z,1512754513.626 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2017-12-08T17:35:13.627Z,1512754513.627 [IBIT](INFO): Initialize IBIT Component. 2017-12-08T17:35:13.628Z,1512754513.628 [CBIT](DEBUG): Initialize CBIT Component. 2017-12-08T17:35:13.628Z,1512754513.628 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2017-12-08T17:35:13.628Z,1512754513.628 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2017-12-08T17:35:13.629Z,1512754513.629 [logger ThreadHandler](INFO): Handler Thread ID is 893 2017-12-08T17:35:13.649Z,1512754513.649 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894 2017-12-08T17:35:13.655Z,1512754513.655 [Radio_Surface](INFO): Powering up 2017-12-08T17:35:13.661Z,1512754513.661 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 895 2017-12-08T17:35:13.662Z,1512754513.662 [Rowe_600LCM](INFO): Initializing 2017-12-08T17:35:13.662Z,1512754513.662 [Rowe_600LCM](INFO): Checking LCM 2017-12-08T17:35:13.713Z,1512754513.713 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-12-08T17:35:13.715Z,1512754513.715 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-12-08T17:35:13.715Z,1512754513.715 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-12-08T17:35:13.716Z,1512754513.716 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-12-08T17:35:13.718Z,1512754513.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-08T17:35:13.719Z,1512754513.719 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T17:35:13.719Z,1512754513.719 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T17:35:13.719Z,1512754513.719 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-08T17:35:13.720Z,1512754513.720 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-08T17:35:13.720Z,1512754513.720 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-08T17:35:13.721Z,1512754513.721 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-12-08T17:35:13.721Z,1512754513.721 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-12-08T17:35:13.785Z,1512754513.785 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-12-08T17:35:13.786Z,1512754513.786 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 896 2017-12-08T17:35:13.787Z,1512754513.787 [CTD_NeilBrown](INFO): Powering down 2017-12-08T17:35:13.896Z,1512754513.896 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 897 2017-12-08T17:35:13.897Z,1512754513.897 [CTD_Seabird](INFO): Initializing 2017-12-08T17:35:13.920Z,1512754513.920 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-08T17:35:13.926Z,1512754513.926 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 899 2017-12-08T17:35:13.928Z,1512754513.928 [WetLabsBB2FL](INFO): Powering down 2017-12-08T17:35:14.032Z,1512754514.032 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 900 2017-12-08T17:35:14.036Z,1512754514.036 [MissionManager](DEBUG): 2017-12-08T17:35:14.037Z,1512754514.037 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-12-08T17:35:14.038Z,1512754514.038 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 901 2017-12-08T17:35:14.051Z,1512754514.051 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-12-08T17:35:14.051Z,1512754514.051 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-12-08T17:35:14.051Z,1512754514.051 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-12-08T17:35:14.051Z,1512754514.051 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-12-08T17:35:14.052Z,1512754514.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-12-08T17:35:14.052Z,1512754514.052 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-12-08T17:35:14.052Z,1512754514.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-12-08T17:35:14.052Z,1512754514.052 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-12-08T17:35:14.052Z,1512754514.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-12-08T17:35:14.052Z,1512754514.052 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-12-08T17:35:14.053Z,1512754514.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-12-08T17:35:14.053Z,1512754514.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-12-08T17:35:14.053Z,1512754514.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-12-08T17:35:14.053Z,1512754514.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-12-08T17:35:14.053Z,1512754514.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-12-08T17:35:14.054Z,1512754514.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-12-08T17:35:14.139Z,1512754514.139 [Rowe_600LCM](INFO): LCM OK 2017-12-08T17:35:14.139Z,1512754514.139 [Rowe_600LCM](INFO): Powering up 2017-12-08T17:35:14.162Z,1512754514.162 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-12-08T17:35:14.164Z,1512754514.164 [Default:A.Wait](DEBUG): Construct Wait. 2017-12-08T17:35:14.197Z,1512754514.197 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-12-08T17:35:14.235Z,1512754514.235 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-12-08T17:35:14.239Z,1512754514.239 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2017-12-08T17:35:14.239Z,1512754514.239 [CTD_Seabird](INFO): Powering down 2017-12-08T17:35:14.281Z,1512754514.281 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-12-08T17:35:14.287Z,1512754514.287 [Default:E.Execute](DEBUG): Construct Execute. 2017-12-08T17:35:14.302Z,1512754514.302 [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-12-08T17:35:14.314Z,1512754514.314 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-12-08T17:35:14.341Z,1512754514.341 [AcousticModem_Benthos_ATM900](INFO): Powering up 2017-12-08T17:35:14.341Z,1512754514.341 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2017-12-08T17:35:14.367Z,1512754514.367 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-12-08T17:35:14.724Z,1512754514.724 [ESPComponent](INFO): powering down ESP 2017-12-08T17:35:15.134Z,1512754515.134 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:15.246Z,1512754515.246 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:15.347Z,1512754515.347 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-12-08T17:35:15.354Z,1512754515.354 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-12-08T17:35:15.376Z,1512754515.376 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-12-08T17:35:15.381Z,1512754515.381 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-12-08T17:35:15.395Z,1512754515.395 [MassServo](DEBUG): Initializing EZServoServo. 2017-12-08T17:35:15.401Z,1512754515.401 [MassServo](DEBUG): Initializing MassServo. 2017-12-08T17:35:15.415Z,1512754515.415 [RudderServo](DEBUG): Initializing EZServoServo. 2017-12-08T17:35:15.421Z,1512754515.421 [RudderServo](DEBUG): Initializing RudderServo. 2017-12-08T17:35:15.439Z,1512754515.439 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-12-08T17:35:15.445Z,1512754515.445 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-12-08T17:35:15.482Z,1512754515.482 [CommandLine](FAULT): Scheduling is paused 2017-12-08T17:35:15.482Z,1512754515.482 [CBIT](INFO): Critical error at 20171208T173513 2017-12-08T17:35:15.483Z,1512754515.483 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2017-12-08T17:35:15.704Z,1512754515.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:15.705Z,1512754515.705 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:15.972Z,1512754515.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:15.982Z,1512754515.982 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:16.313Z,1512754516.313 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2017-12-08T17:35:16.313Z,1512754516.313 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2017-12-08T17:35:16.313Z,1512754516.313 [BuoyancyServo] Communications Fault, FailCount= 1 2017-12-08T17:35:16.313Z,1512754516.313 [BuoyancyServo](ERROR): Communications Fault 2017-12-08T17:35:16.520Z,1512754516.520 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2017-12-08T17:35:16.575Z,1512754516.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:16.576Z,1512754516.576 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:16.598Z,1512754516.598 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2017-12-08T17:35:16.598Z,1512754516.598 [BuoyancyServo](INFO): Powering down 2017-12-08T17:35:16.984Z,1512754516.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:16.993Z,1512754516.993 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:17.362Z,1512754517.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:17.363Z,1512754517.363 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:17.829Z,1512754517.829 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:17.830Z,1512754517.830 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:18.059Z,1512754518.059 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-08T17:35:18.167Z,1512754518.167 [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-12-08T17:35:18.189Z,1512754518.189 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:18.191Z,1512754518.191 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:18.301Z,1512754518.301 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-08T17:35:18.302Z,1512754518.302 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-08T17:35:18.303Z,1512754518.303 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-08T17:35:18.582Z,1512754518.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:18.583Z,1512754518.583 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:18.638Z,1512754518.638 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2017-12-08T17:35:18.638Z,1512754518.638 [BuoyancyServo] No Fault, FailCount= 1 2017-12-08T17:35:18.973Z,1512754518.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:18.981Z,1512754518.981 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:18.994Z,1512754518.994 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-12-08T17:35:18.995Z,1512754518.995 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-12-08T17:35:19.382Z,1512754519.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:19.383Z,1512754519.383 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:19.810Z,1512754519.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:19.811Z,1512754519.811 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:20.201Z,1512754520.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:20.218Z,1512754520.218 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:20.575Z,1512754520.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:20.576Z,1512754520.576 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:21.021Z,1512754521.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:21.022Z,1512754521.022 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:21.362Z,1512754521.362 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:21.363Z,1512754521.363 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:21.779Z,1512754521.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:21.780Z,1512754521.780 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:22.128Z,1512754522.128 [AcousticModem_Benthos_ATM900](DEBUG): 2017-12-08T17:35:22.151Z,1512754522.151 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2017-12-08T17:35:22.151Z,1512754522.151 [PNI_TCM] Data Fault, FailCount= 1 2017-12-08T17:35:22.151Z,1512754522.151 [PNI_TCM](ERROR): Data Fault 2017-12-08T17:35:22.181Z,1512754522.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:22.182Z,1512754522.182 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:22.290Z,1512754522.290 [CBIT](ERROR): Data Fault in component: PNI_TCM 2017-12-08T17:35:22.550Z,1512754522.550 [PNI_TCM](INFO): Powering down 2017-12-08T17:35:22.608Z,1512754522.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:22.610Z,1512754522.610 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:22.961Z,1512754522.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:22.962Z,1512754522.962 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:23.329Z,1512754523.329 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2017-12-08T17:35:23.330Z,1512754523.330 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2017-12-08T17:35:23.331Z,1512754523.331 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2017-12-08T17:35:23.331Z,1512754523.331 [AcousticModem_Benthos_ATM900](DEBUG): Feb 1 2000 23:56:14 2017-12-08T17:35:23.401Z,1512754523.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:23.402Z,1512754523.402 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:23.729Z,1512754523.729 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2017-12-08T17:35:23.785Z,1512754523.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:23.786Z,1512754523.786 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:23.842Z,1512754523.842 [CBIT](CRITICAL): Environmental Failure. Press:13.693519 PSI. Humidity:16%. Temp:25 C. ABORTING MISSION 2017-12-08T17:35:23.842Z,1512754523.842 [CBIT](INFO): Clearing failed state for component PNI_TCM 2017-12-08T17:35:23.842Z,1512754523.842 [PNI_TCM] No Fault, FailCount= 1 2017-12-08T17:35:24.194Z,1512754524.194 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:24.195Z,1512754524.195 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:24.234Z,1512754524.234 [CBIT](INFO): Critical error at 20171208T173523 2017-12-08T17:35:24.528Z,1512754524.528 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2017-12-08T17:35:24.577Z,1512754524.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:24.578Z,1512754524.578 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:24.928Z,1512754524.928 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2017-12-08T17:35:24.929Z,1512754524.929 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2017-12-08T17:35:24.929Z,1512754524.929 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2017-12-08T17:35:24.962Z,1512754524.962 [Aanderaa_O2](FAULT): Timed out starting 2017-12-08T17:35:24.962Z,1512754524.962 [Aanderaa_O2] Communications Fault, FailCount= 1 2017-12-08T17:35:24.962Z,1512754524.962 [Aanderaa_O2](ERROR): Communications Fault 2017-12-08T17:35:24.968Z,1512754524.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:24.970Z,1512754524.970 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:25.010Z,1512754525.010 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2017-12-08T17:35:25.328Z,1512754525.328 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2017-12-08T17:35:25.359Z,1512754525.359 [Aanderaa_O2](INFO): Powering down 2017-12-08T17:35:25.465Z,1512754525.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:25.466Z,1512754525.466 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:25.728Z,1512754525.728 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2017-12-08T17:35:25.730Z,1512754525.730 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2017-12-08T17:35:25.797Z,1512754525.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:25.798Z,1512754525.798 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:26.129Z,1512754526.129 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2017-12-08T17:35:26.205Z,1512754526.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:26.206Z,1512754526.206 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:26.261Z,1512754526.261 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2017-12-08T17:35:26.261Z,1512754526.261 [Aanderaa_O2] No Fault, FailCount= 1 2017-12-08T17:35:26.566Z,1512754526.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:26.567Z,1512754526.567 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:26.990Z,1512754526.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:26.991Z,1512754526.991 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:27.401Z,1512754527.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:27.402Z,1512754527.402 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:27.789Z,1512754527.789 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:27.790Z,1512754527.790 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:28.226Z,1512754528.226 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:28.227Z,1512754528.227 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:28.573Z,1512754528.573 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:28.574Z,1512754528.574 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:28.974Z,1512754528.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:28.975Z,1512754528.975 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:29.381Z,1512754529.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:29.382Z,1512754529.382 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:29.802Z,1512754529.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:29.803Z,1512754529.803 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:30.201Z,1512754530.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:30.202Z,1512754530.202 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:30.593Z,1512754530.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:30.594Z,1512754530.594 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:30.985Z,1512754530.985 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:30.986Z,1512754530.986 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:31.363Z,1512754531.363 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2017-12-08T17:35:31.364Z,1512754531.364 [PNI_TCM] Data Fault, FailCount= 2 2017-12-08T17:35:31.364Z,1512754531.364 [PNI_TCM](ERROR): Data Fault 2017-12-08T17:35:31.408Z,1512754531.408 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:31.417Z,1512754531.417 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:31.470Z,1512754531.470 [CBIT](ERROR): Data Fault in component: PNI_TCM 2017-12-08T17:35:31.752Z,1512754531.752 [PNI_TCM](INFO): Powering down 2017-12-08T17:35:31.809Z,1512754531.809 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:31.810Z,1512754531.810 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:32.182Z,1512754532.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:32.183Z,1512754532.183 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:32.577Z,1512754532.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:32.578Z,1512754532.578 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:32.980Z,1512754532.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:32.997Z,1512754532.997 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:33.102Z,1512754533.102 [CBIT](INFO): Clearing failed state for component PNI_TCM 2017-12-08T17:35:33.102Z,1512754533.102 [PNI_TCM] No Fault, FailCount= 2 2017-12-08T17:35:33.381Z,1512754533.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:33.382Z,1512754533.382 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:33.770Z,1512754533.770 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:33.771Z,1512754533.771 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:34.208Z,1512754534.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:34.209Z,1512754534.209 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:34.596Z,1512754534.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:34.606Z,1512754534.606 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:34.952Z,1512754534.952 [BPC1](ERROR): Failed to receive battery data 2017-12-08T17:35:34.952Z,1512754534.952 [BPC1] Communications Fault, FailCount= 1 2017-12-08T17:35:34.952Z,1512754534.952 [BPC1](ERROR): Communications Fault 2017-12-08T17:35:34.962Z,1512754534.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:34.963Z,1512754534.963 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:35.026Z,1512754535.026 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-08T17:35:35.379Z,1512754535.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:35.380Z,1512754535.380 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-12-08T17:35:35.798Z,1512754535.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-12-0