2018-01-17T17:18:04.384Z,1516209484.384 [Supervisor](DEBUG): Initializing supervisor. 2018-01-17T17:18:04.387Z,1516209484.387 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-01-17T17:18:04.387Z,1516209484.387 [SyncHandler](INFO): Protected caller Thread ID is 803 2018-01-17T17:18:04.388Z,1516209484.388 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-01-17T17:18:04.389Z,1516209484.389 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-01-17T17:18:04.389Z,1516209484.389 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804 2018-01-17T17:18:04.391Z,1516209484.391 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-01-17T17:18:04.403Z,1516209484.403 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-01-17T17:18:04.403Z,1516209484.403 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-01-17T17:18:04.404Z,1516209484.404 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805 2018-01-17T17:18:04.405Z,1516209484.405 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-01-17T17:18:04.406Z,1516209484.406 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-01-17T17:18:04.406Z,1516209484.406 [logger ThreadHandler](INFO): Protected caller Thread ID is 806 2018-01-17T17:18:04.408Z,1516209484.408 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-01-17T17:18:04.408Z,1516209484.408 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-01-17T17:18:04.411Z,1516209484.411 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-01-17T17:18:04.508Z,1516209484.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-01-17T17:18:04.510Z,1516209484.510 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-01-17T17:18:04.609Z,1516209484.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-01-17T17:18:04.611Z,1516209484.611 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-01-17T17:18:04.797Z,1516209484.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-01-17T17:18:04.797Z,1516209484.797 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-01-17T17:18:05.093Z,1516209485.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-01-17T17:18:05.095Z,1516209485.095 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-01-17T17:18:05.408Z,1516209485.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-01-17T17:18:05.409Z,1516209485.409 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-01-17T17:18:05.849Z,1516209485.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-01-17T17:18:05.849Z,1516209485.849 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-01-17T17:18:06.179Z,1516209486.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-01-17T17:18:06.181Z,1516209486.181 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-01-17T17:18:06.603Z,1516209486.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-01-17T17:18:06.605Z,1516209486.605 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-01-17T17:18:06.686Z,1516209486.686 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-01-17T17:18:06.973Z,1516209486.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-01-17T17:18:06.974Z,1516209486.974 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-01-17T17:18:07.116Z,1516209487.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-01-17T17:18:07.116Z,1516209487.116 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-01-17T17:18:07.313Z,1516209487.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-01-17T17:18:07.315Z,1516209487.315 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-01-17T17:18:07.510Z,1516209487.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-01-17T17:18:07.511Z,1516209487.511 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-01-17T17:18:07.611Z,1516209487.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-01-17T17:18:07.611Z,1516209487.611 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-01-17T17:18:08.218Z,1516209488.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-01-17T17:18:08.221Z,1516209488.221 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-01-17T17:18:08.379Z,1516209488.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-01-17T17:18:08.681Z,1516209488.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-01-17T17:18:08.682Z,1516209488.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-01-17T17:18:08.760Z,1516209488.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-01-17T17:18:08.899Z,1516209488.899 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-01-17T17:18:08.991Z,1516209488.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-01-17T17:18:09.076Z,1516209489.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-01-17T17:18:09.211Z,1516209489.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-01-17T17:18:09.373Z,1516209489.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-01-17T17:18:09.469Z,1516209489.469 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-01-17T17:18:09.562Z,1516209489.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-01-17T17:18:09.658Z,1516209489.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-01-17T17:18:09.741Z,1516209489.741 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-01-17T17:18:09.746Z,1516209489.746 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-01-17T17:18:09.901Z,1516209489.901 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-01-17T17:18:09.902Z,1516209489.902 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-01-17T17:18:09.926Z,1516209489.926 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-01-17T17:18:09.928Z,1516209489.928 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-01-17T17:18:09.984Z,1516209489.984 [DepthRateCalculator] Loaded 2018-01-17T17:18:09.984Z,1516209489.984 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-01-17T17:18:09.990Z,1516209489.990 [PitchRateCalculator] Loaded 2018-01-17T17:18:09.990Z,1516209489.990 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-01-17T17:18:10.007Z,1516209490.007 [SpeedCalculator] Loaded 2018-01-17T17:18:10.007Z,1516209490.007 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-01-17T17:18:10.028Z,1516209490.028 [TempGradientCalculator] Loaded 2018-01-17T17:18:10.028Z,1516209490.028 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-01-17T17:18:10.044Z,1516209490.044 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-01-17T17:18:10.044Z,1516209490.044 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-01-17T17:18:10.050Z,1516209490.050 [YawRateCalculator] Loaded 2018-01-17T17:18:10.050Z,1516209490.050 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-01-17T17:18:10.050Z,1516209490.050 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-01-17T17:18:10.051Z,1516209490.051 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-01-17T17:18:10.088Z,1516209490.088 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-01-17T17:18:10.089Z,1516209490.089 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-01-17T17:18:10.539Z,1516209490.539 [AcousticModem_Benthos_ATM900] Loaded 2018-01-17T17:18:10.539Z,1516209490.539 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-01-17T17:18:10.684Z,1516209490.684 [DataOverHttps] Loaded 2018-01-17T17:18:10.684Z,1516209490.684 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-01-17T17:18:10.698Z,1516209490.698 [Depth_Keller] Loaded 2018-01-17T17:18:10.698Z,1516209490.698 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-01-17T17:18:10.703Z,1516209490.703 [DropWeight] Loaded 2018-01-17T17:18:10.703Z,1516209490.703 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-01-17T17:18:10.801Z,1516209490.801 [NAL9602] Loaded 2018-01-17T17:18:10.801Z,1516209490.801 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-01-17T17:18:10.817Z,1516209490.817 [Onboard] Loaded 2018-01-17T17:18:10.817Z,1516209490.817 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-01-17T17:18:10.827Z,1516209490.827 [Radio_Surface] Loaded 2018-01-17T17:18:10.828Z,1516209490.828 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-01-17T17:18:10.829Z,1516209490.829 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A44E0 2018-01-17T17:18:10.829Z,1516209490.829 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 885 2018-01-17T17:18:10.983Z,1516209490.983 [PNI_TCM] Loaded 2018-01-17T17:18:10.983Z,1516209490.983 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-01-17T17:18:11.046Z,1516209491.046 [Rowe_600LCM] Loaded 2018-01-17T17:18:11.046Z,1516209491.046 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-01-17T17:18:11.047Z,1516209491.047 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D44E0 2018-01-17T17:18:11.048Z,1516209491.048 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 886 2018-01-17T17:18:12.796Z,1516209492.796 [BPC1] Loaded 2018-01-17T17:18:12.796Z,1516209492.796 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-01-17T17:18:12.796Z,1516209492.796 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-01-17T17:18:12.798Z,1516209492.798 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-01-17T17:18:13.668Z,1516209493.668 [SBIT](DEBUG): Construct Startup Built In Test. 2018-01-17T17:18:13.696Z,1516209493.696 [SBIT] Loaded 2018-01-17T17:18:13.696Z,1516209493.696 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-01-17T17:18:13.697Z,1516209493.697 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-01-17T17:18:13.708Z,1516209493.708 [IBIT] Loaded 2018-01-17T17:18:13.708Z,1516209493.708 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-01-17T17:18:13.712Z,1516209493.712 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-01-17T17:18:13.864Z,1516209493.864 [CBIT] Loaded 2018-01-17T17:18:13.864Z,1516209493.864 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-01-17T17:18:13.865Z,1516209493.865 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-01-17T17:18:13.865Z,1516209493.865 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-01-17T17:18:14.052Z,1516209494.052 [Aanderaa_O2] Loaded 2018-01-17T17:18:14.052Z,1516209494.052 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-01-17T17:18:14.087Z,1516209494.087 [CTD_Seabird] Loaded 2018-01-17T17:18:14.088Z,1516209494.088 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-01-17T17:18:14.089Z,1516209494.089 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4089A4E0 2018-01-17T17:18:14.089Z,1516209494.089 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887 2018-01-17T17:18:14.105Z,1516209494.105 [ESPComponent] Loaded 2018-01-17T17:18:14.106Z,1516209494.106 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-01-17T17:18:14.120Z,1516209494.120 [PAR_Licor] Loaded 2018-01-17T17:18:14.120Z,1516209494.120 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-01-17T17:18:14.169Z,1516209494.169 [WetLabsBB2FL] Loaded 2018-01-17T17:18:14.169Z,1516209494.169 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-01-17T17:18:14.170Z,1516209494.170 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408CA4E0 2018-01-17T17:18:14.170Z,1516209494.170 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888 2018-01-17T17:18:14.171Z,1516209494.171 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-01-17T17:18:14.171Z,1516209494.171 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-01-17T17:18:14.450Z,1516209494.450 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-01-17T17:18:14.450Z,1516209494.450 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-01-17T17:18:14.538Z,1516209494.538 [VerticalControl](DEBUG): Construct VerticalControl. 2018-01-17T17:18:14.635Z,1516209494.635 [VerticalControl] Loaded 2018-01-17T17:18:14.635Z,1516209494.635 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-01-17T17:18:14.636Z,1516209494.636 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-01-17T17:18:14.697Z,1516209494.697 [HorizontalControl] Loaded 2018-01-17T17:18:14.698Z,1516209494.698 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-01-17T17:18:14.698Z,1516209494.698 [SpeedControl](DEBUG): Construct SpeedControl. 2018-01-17T17:18:14.703Z,1516209494.703 [SpeedControl] Loaded 2018-01-17T17:18:14.704Z,1516209494.704 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-01-17T17:18:14.704Z,1516209494.704 [LoopControl](DEBUG): Construct LoopControl. 2018-01-17T17:18:14.705Z,1516209494.705 [LoopControl] Loaded 2018-01-17T17:18:14.705Z,1516209494.705 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-01-17T17:18:14.706Z,1516209494.706 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-01-17T17:18:14.706Z,1516209494.706 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-01-17T17:18:14.825Z,1516209494.825 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-01-17T17:18:14.825Z,1516209494.825 [StratificationFrontDetector](DEBUG): (re)initializing 2018-01-17T17:18:14.826Z,1516209494.826 [StratificationFrontDetector] Loaded 2018-01-17T17:18:14.826Z,1516209494.826 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-01-17T17:18:14.826Z,1516209494.826 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-01-17T17:18:14.827Z,1516209494.827 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-01-17T17:18:14.946Z,1516209494.946 [BuoyancyServo] Loaded 2018-01-17T17:18:14.947Z,1516209494.947 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-01-17T17:18:14.958Z,1516209494.958 [ElevatorServo] Loaded 2018-01-17T17:18:14.958Z,1516209494.958 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-01-17T17:18:14.970Z,1516209494.970 [MassServo] Loaded 2018-01-17T17:18:14.970Z,1516209494.970 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-01-17T17:18:14.981Z,1516209494.981 [RudderServo] Loaded 2018-01-17T17:18:14.981Z,1516209494.981 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-01-17T17:18:14.992Z,1516209494.992 [ThrusterServo] Loaded 2018-01-17T17:18:14.992Z,1516209494.992 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-01-17T17:18:14.993Z,1516209494.993 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-01-17T17:18:14.993Z,1516209494.993 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-01-17T17:18:15.107Z,1516209495.107 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-01-17T17:18:15.107Z,1516209495.107 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-01-17T17:18:15.162Z,1516209495.162 [DeadReckonUsingSpeedCalculator] Loaded 2018-01-17T17:18:15.162Z,1516209495.162 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-01-17T17:18:15.178Z,1516209495.178 [NavChart] Loaded 2018-01-17T17:18:15.179Z,1516209495.179 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-01-17T17:18:15.183Z,1516209495.183 [UniversalFixResidualReporter] Loaded 2018-01-17T17:18:15.183Z,1516209495.183 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-01-17T17:18:15.184Z,1516209495.184 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-01-17T17:18:15.187Z,1516209495.187 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-01-17T17:18:15.188Z,1516209495.188 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-01-17T17:18:15.195Z,1516209495.195 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-01-17T17:18:15.196Z,1516209495.196 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1C4E0 2018-01-17T17:18:15.196Z,1516209495.196 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 889 2018-01-17T17:18:15.201Z,1516209495.201 [Supervisor](INFO): Main Thread ID is 795 2018-01-17T17:18:15.201Z,1516209495.201 [Supervisor](DEBUG): Running supervisor. 2018-01-17T17:18:15.202Z,1516209495.202 [CommandLine ThreadHandler](INFO): Handler Thread ID is 890 2018-01-17T17:18:15.204Z,1516209495.204 [controlThread ThreadHandler](INFO): Handler Thread ID is 891 2018-01-17T17:18:15.205Z,1516209495.205 [controlThread](DEBUG): Initializing ControlThread 2018-01-17T17:18:15.206Z,1516209495.206 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-01-17T17:18:15.206Z,1516209495.206 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-01-17T17:18:15.206Z,1516209495.206 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-01-17T17:18:15.206Z,1516209495.206 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-01-17T17:18:15.207Z,1516209495.207 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-01-17T17:18:15.208Z,1516209495.208 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-01-17T17:18:15.215Z,1516209495.215 [SBIT](INFO): Initialize SBIT Component. 2018-01-17T17:18:15.216Z,1516209495.216 [SBIT](IMPORTANT): git: 2017-12-12 2018-01-17T17:18:15.216Z,1516209495.216 [SBIT](INFO): git hash: 18638d31f033e3fa0cb90b26256f5481d37f00cc 2018-01-17T17:18:15.216Z,1516209495.216 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-01-17T17:18:15.217Z,1516209495.217 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017 2018-01-17T17:18:15.218Z,1516209495.218 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2018-01-17T17:18:15.219Z,1516209495.219 [IBIT](INFO): Initialize IBIT Component. 2018-01-17T17:18:15.220Z,1516209495.220 [CBIT](DEBUG): Initialize CBIT Component. 2018-01-17T17:18:15.220Z,1516209495.220 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-01-17T17:18:15.220Z,1516209495.220 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-01-17T17:18:15.221Z,1516209495.221 [logger ThreadHandler](INFO): Handler Thread ID is 892 2018-01-17T17:18:15.241Z,1516209495.241 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 893 2018-01-17T17:18:15.247Z,1516209495.247 [Radio_Surface](INFO): Powering up 2018-01-17T17:18:15.253Z,1516209495.253 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 894 2018-01-17T17:18:15.254Z,1516209495.254 [Rowe_600LCM](INFO): Initializing 2018-01-17T17:18:15.255Z,1516209495.255 [Rowe_600LCM](INFO): Checking LCM 2018-01-17T17:18:15.339Z,1516209495.339 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 895 2018-01-17T17:18:15.341Z,1516209495.341 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-01-17T17:18:15.343Z,1516209495.343 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-01-17T17:18:15.343Z,1516209495.343 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-01-17T17:18:15.344Z,1516209495.344 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-01-17T17:18:15.349Z,1516209495.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-01-17T17:18:15.350Z,1516209495.350 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-01-17T17:18:15.350Z,1516209495.350 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-01-17T17:18:15.351Z,1516209495.351 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-01-17T17:18:15.351Z,1516209495.351 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-01-17T17:18:15.351Z,1516209495.351 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-01-17T17:18:15.352Z,1516209495.352 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-01-17T17:18:15.352Z,1516209495.352 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-01-17T17:18:15.353Z,1516209495.353 [CTD_Seabird](INFO): Initializing 2018-01-17T17:18:15.355Z,1516209495.355 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 896 2018-01-17T17:18:15.356Z,1516209495.356 [WetLabsBB2FL](INFO): Powering down 2018-01-17T17:18:15.397Z,1516209495.397 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-01-17T17:18:15.548Z,1516209495.548 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-01-17T17:18:15.557Z,1516209495.557 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 898 2018-01-17T17:18:15.567Z,1516209495.567 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-01-17T17:18:15.567Z,1516209495.567 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-01-17T17:18:15.567Z,1516209495.567 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-01-17T17:18:15.568Z,1516209495.568 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-01-17T17:18:15.568Z,1516209495.568 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-01-17T17:18:15.568Z,1516209495.568 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-01-17T17:18:15.568Z,1516209495.568 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-01-17T17:18:15.568Z,1516209495.568 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-01-17T17:18:15.569Z,1516209495.569 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-01-17T17:18:15.569Z,1516209495.569 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-01-17T17:18:15.569Z,1516209495.569 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-01-17T17:18:15.569Z,1516209495.569 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-01-17T17:18:15.569Z,1516209495.569 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-01-17T17:18:15.570Z,1516209495.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-01-17T17:18:15.570Z,1516209495.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-01-17T17:18:15.570Z,1516209495.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-01-17T17:18:15.590Z,1516209495.590 [MissionManager](DEBUG): 2018-01-17T17:18:15.591Z,1516209495.591 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-01-17T17:18:15.614Z,1516209495.614 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-01-17T17:18:15.615Z,1516209495.615 [CTD_Seabird](INFO): Powering down 2018-01-17T17:18:15.753Z,1516209495.753 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-01-17T17:18:15.754Z,1516209495.754 [Default:A.Wait](DEBUG): Construct Wait. 2018-01-17T17:18:15.756Z,1516209495.756 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-01-17T17:18:15.786Z,1516209495.786 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-01-17T17:18:15.788Z,1516209495.788 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-01-17T17:18:15.818Z,1516209495.818 [Default:E.Execute](DEBUG): Construct Execute. 2018-01-17T17:18:15.848Z,1516209495.848 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-01-17T17:18:15.853Z,1516209495.853 [Rowe_600LCM](INFO): LCM OK 2018-01-17T17:18:15.853Z,1516209495.853 [Rowe_600LCM](INFO): Powering up 2018-01-17T17:18:15.859Z,1516209495.859 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-01-17T17:18:15.873Z,1516209495.873 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-01-17T17:18:15.873Z,1516209495.873 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-01-17T17:18:15.911Z,1516209495.911 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-01-17T17:18:16.287Z,1516209496.287 [ESPComponent](INFO): powering down ESP 2018-01-17T17:18:16.797Z,1516209496.797 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:16.889Z,1516209496.889 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:16.993Z,1516209496.993 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-01-17T17:18:17.001Z,1516209497.001 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-01-17T17:18:17.025Z,1516209497.025 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-01-17T17:18:17.033Z,1516209497.033 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-01-17T17:18:17.046Z,1516209497.046 [MassServo](DEBUG): Initializing EZServoServo. 2018-01-17T17:18:17.053Z,1516209497.053 [MassServo](DEBUG): Initializing MassServo. 2018-01-17T17:18:17.091Z,1516209497.091 [RudderServo](DEBUG): Initializing EZServoServo. 2018-01-17T17:18:17.101Z,1516209497.101 [RudderServo](DEBUG): Initializing RudderServo. 2018-01-17T17:18:17.107Z,1516209497.107 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-01-17T17:18:17.117Z,1516209497.117 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-01-17T17:18:17.178Z,1516209497.178 [CommandLine](FAULT): Scheduling is paused 2018-01-17T17:18:17.178Z,1516209497.178 [CBIT](INFO): Critical error at 20180117T171815 2018-01-17T17:18:17.179Z,1516209497.179 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-01-17T17:18:17.289Z,1516209497.289 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-01-17T17:18:17.289Z,1516209497.289 [DropWeight] Hardware Fault, FailCount= 1 2018-01-17T17:18:17.289Z,1516209497.289 [DropWeight](ERROR): Hardware Fault 2018-01-17T17:18:17.384Z,1516209497.384 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:17.393Z,1516209497.393 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:17.422Z,1516209497.422 [CBIT](INFO): Critical error at 20180117T171817 2018-01-17T17:18:17.424Z,1516209497.424 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-01-17T17:18:17.425Z,1516209497.425 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-01-17T17:18:17.665Z,1516209497.665 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:17.666Z,1516209497.666 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:18.005Z,1516209498.005 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-01-17T17:18:18.005Z,1516209498.005 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-01-17T17:18:18.005Z,1516209498.005 [BuoyancyServo] Communications Fault, FailCount= 1 2018-01-17T17:18:18.005Z,1516209498.005 [BuoyancyServo](ERROR): Communications Fault 2018-01-17T17:18:18.210Z,1516209498.210 [CBIT](INFO): Critical error at 20180117T171817 2018-01-17T17:18:18.213Z,1516209498.213 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-01-17T17:18:18.269Z,1516209498.269 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:18.270Z,1516209498.270 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:18.283Z,1516209498.283 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-01-17T17:18:18.284Z,1516209498.284 [BuoyancyServo](INFO): Powering down 2018-01-17T17:18:18.696Z,1516209498.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:18.709Z,1516209498.709 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:19.076Z,1516209499.076 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:19.077Z,1516209499.077 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:19.488Z,1516209499.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:19.489Z,1516209499.489 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:19.781Z,1516209499.781 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-01-17T17:18:19.870Z,1516209499.870 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:19.872Z,1516209499.872 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:19.884Z,1516209499.884 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2018-01-17T17:18:20.027Z,1516209500.027 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-01-17T17:18:20.028Z,1516209500.028 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-01-17T17:18:20.029Z,1516209500.029 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-01-17T17:18:20.290Z,1516209500.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:20.291Z,1516209500.291 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:20.342Z,1516209500.342 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-01-17T17:18:20.342Z,1516209500.342 [BuoyancyServo] No Fault, FailCount= 1 2018-01-17T17:18:20.672Z,1516209500.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:20.681Z,1516209500.681 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:20.691Z,1516209500.691 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-01-17T17:18:20.691Z,1516209500.691 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-01-17T17:18:21.072Z,1516209501.072 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:21.081Z,1516209501.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:21.481Z,1516209501.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:21.482Z,1516209501.482 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:21.873Z,1516209501.873 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:21.881Z,1516209501.881 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:22.274Z,1516209502.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:22.275Z,1516209502.275 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:22.702Z,1516209502.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:22.703Z,1516209502.703 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:23.073Z,1516209503.073 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:23.081Z,1516209503.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:23.420Z,1516209503.420 [AcousticModem_Benthos_ATM900](DEBUG): 2018-01-17T17:18:23.453Z,1516209503.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:23.454Z,1516209503.454 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:23.842Z,1516209503.842 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-01-17T17:18:23.842Z,1516209503.842 [PNI_TCM] Data Fault, FailCount= 1 2018-01-17T17:18:23.842Z,1516209503.842 [PNI_TCM](ERROR): Data Fault 2018-01-17T17:18:23.881Z,1516209503.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:23.897Z,1516209503.897 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:24.006Z,1516209504.006 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-01-17T17:18:24.241Z,1516209504.241 [PNI_TCM](INFO): Powering down 2018-01-17T17:18:24.314Z,1516209504.314 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:24.315Z,1516209504.315 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:24.623Z,1516209504.623 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-01-17T17:18:24.704Z,1516209504.704 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:24.713Z,1516209504.713 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:25.021Z,1516209505.021 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-01-17T17:18:25.021Z,1516209505.021 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-01-17T17:18:25.022Z,1516209505.022 [AcousticModem_Benthos_ATM900](DEBUG): Feb 16 2000 05:26:36 2018-01-17T17:18:25.073Z,1516209505.073 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:25.081Z,1516209505.081 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:25.421Z,1516209505.421 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-01-17T17:18:25.473Z,1516209505.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:25.474Z,1516209505.474 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:25.533Z,1516209505.533 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-01-17T17:18:25.533Z,1516209505.533 [PNI_TCM] No Fault, FailCount= 1 2018-01-17T17:18:25.877Z,1516209505.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:25.878Z,1516209505.878 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:26.220Z,1516209506.220 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-01-17T17:18:26.273Z,1516209506.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:26.281Z,1516209506.281 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:26.620Z,1516209506.620 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-01-17T17:18:26.621Z,1516209506.621 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2018-01-17T17:18:26.621Z,1516209506.621 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2018-01-17T17:18:26.674Z,1516209506.674 [Aanderaa_O2](FAULT): Timed out starting 2018-01-17T17:18:26.674Z,1516209506.674 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-01-17T17:18:26.674Z,1516209506.674 [Aanderaa_O2](ERROR): Communications Fault 2018-01-17T17:18:26.725Z,1516209506.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:26.733Z,1516209506.733 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:26.778Z,1516209506.778 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-01-17T17:18:27.020Z,1516209507.020 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2018-01-17T17:18:27.079Z,1516209507.079 [Aanderaa_O2](INFO): Powering down 2018-01-17T17:18:27.158Z,1516209507.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:27.159Z,1516209507.159 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:27.420Z,1516209507.420 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2018-01-17T17:18:27.421Z,1516209507.421 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2018-01-17T17:18:27.458Z,1516209507.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:27.459Z,1516209507.459 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:27.821Z,1516209507.821 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2018-01-17T17:18:27.882Z,1516209507.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:27.883Z,1516209507.883 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:27.938Z,1516209507.938 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-01-17T17:18:27.938Z,1516209507.938 [Aanderaa_O2] No Fault, FailCount= 1 2018-01-17T17:18:28.286Z,1516209508.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:28.287Z,1516209508.287 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:28.695Z,1516209508.695 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:28.696Z,1516209508.696 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:29.093Z,1516209509.093 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:29.094Z,1516209509.094 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:29.480Z,1516209509.480 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:29.481Z,1516209509.481 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:29.881Z,1516209509.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:29.882Z,1516209509.882 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:30.285Z,1516209510.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:30.286Z,1516209510.286 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:30.685Z,1516209510.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:30.686Z,1516209510.686 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:31.086Z,1516209511.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:31.087Z,1516209511.087 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:31.493Z,1516209511.493 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:31.494Z,1516209511.494 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:31.877Z,1516209511.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:31.878Z,1516209511.878 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:32.265Z,1516209512.265 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:32.266Z,1516209512.266 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:32.714Z,1516209512.714 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:32.715Z,1516209512.715 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:33.042Z,1516209513.042 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-01-17T17:18:33.042Z,1516209513.042 [PNI_TCM] Data Fault, FailCount= 2 2018-01-17T17:18:33.043Z,1516209513.043 [PNI_TCM](ERROR): Data Fault 2018-01-17T17:18:33.053Z,1516209513.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:33.054Z,1516209513.054 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:33.114Z,1516209513.114 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-01-17T17:18:33.444Z,1516209513.444 [PNI_TCM](INFO): Powering down 2018-01-17T17:18:33.489Z,1516209513.489 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:33.490Z,1516209513.490 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:33.897Z,1516209513.897 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:33.898Z,1516209513.898 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:34.297Z,1516209514.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:34.298Z,1516209514.298 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:34.672Z,1516209514.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:34.681Z,1516209514.681 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:34.738Z,1516209514.738 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-01-17T17:18:34.738Z,1516209514.738 [PNI_TCM] No Fault, FailCount= 2 2018-01-17T17:18:35.073Z,1516209515.073 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:35.074Z,1516209515.074 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:35.476Z,1516209515.476 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:35.477Z,1516209515.477 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:35.896Z,1516209515.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:35.897Z,1516209515.897 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:36.274Z,1516209516.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:36.274Z,1516209516.274 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:36.673Z,1516209516.673 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:36.674Z,1516209516.674 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:37.092Z,1516209517.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:37.097Z,1516209517.097 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:37.474Z,1516209517.474 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:37.474Z,1516209517.474 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:37.872Z,1516209517.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:37.878Z,1516209517.878 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:38.273Z,1516209518.273 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:38.274Z,1516209518.274 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:38.643Z,1516209518.643 [Aanderaa_O2](FAULT): Timed out starting 2018-01-17T17:18:38.643Z,1516209518.643 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-01-17T17:18:38.643Z,1516209518.643 [Aanderaa_O2](ERROR): Communications Fault 2018-01-17T17:18:38.672Z,1516209518.672 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:38.682Z,1516209518.682 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:38.738Z,1516209518.738 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-01-17T17:18:39.042Z,1516209519.042 [Aanderaa_O2](INFO): Powering down 2018-01-17T17:18:39.062Z,1516209519.062 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:39.063Z,1516209519.063 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:39.493Z,1516209519.493 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:39.494Z,1516209519.494 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:39.865Z,1516209519.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:39.866Z,1516209519.866 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:39.931Z,1516209519.931 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-01-17T17:18:39.931Z,1516209519.931 [Aanderaa_O2] No Fault, FailCount= 2 2018-01-17T17:18:40.288Z,1516209520.288 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:40.293Z,1516209520.293 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:40.669Z,1516209520.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:40.670Z,1516209520.670 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:41.026Z,1516209521.026 [NAL9602](INFO): Powering up NAL9602 2018-01-17T17:18:41.081Z,1516209521.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:41.082Z,1516209521.082 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:41.457Z,1516209521.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:41.458Z,1516209521.458 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:41.861Z,1516209521.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:41.862Z,1516209521.862 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:42.248Z,1516209522.248 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-01-17T17:18:42.248Z,1516209522.248 [PNI_TCM] Data Fault, FailCount= 3 2018-01-17T17:18:42.248Z,1516209522.248 [PNI_TCM](ERROR): Data Fault 2018-01-17T17:18:42.265Z,1516209522.265 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:42.266Z,1516209522.266 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:42.347Z,1516209522.347 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-01-17T17:18:42.642Z,1516209522.642 [PNI_TCM](INFO): Powering down 2018-01-17T17:18:42.661Z,1516209522.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:42.662Z,1516209522.662 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:43.087Z,1516209523.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:43.088Z,1516209523.088 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:43.457Z,1516209523.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-01-17T17:18:43.458Z,1516209523.458 [DeadReckonUsingSpeedCalculator](DEBUG): S