2018-03-05T02:45:21.452Z,1520217921.452 [Supervisor](DEBUG): Initializing supervisor. 2018-03-05T02:45:21.454Z,1520217921.454 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-03-05T02:45:21.455Z,1520217921.455 [SyncHandler](INFO): Protected caller Thread ID is 803 2018-03-05T02:45:21.455Z,1520217921.455 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-03-05T02:45:21.456Z,1520217921.456 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-03-05T02:45:21.456Z,1520217921.456 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804 2018-03-05T02:45:21.459Z,1520217921.459 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-03-05T02:45:21.470Z,1520217921.470 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-03-05T02:45:21.471Z,1520217921.471 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-03-05T02:45:21.472Z,1520217921.472 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805 2018-03-05T02:45:21.472Z,1520217921.472 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-03-05T02:45:21.473Z,1520217921.473 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-03-05T02:45:21.474Z,1520217921.474 [logger ThreadHandler](INFO): Protected caller Thread ID is 806 2018-03-05T02:45:21.476Z,1520217921.476 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-03-05T02:45:21.476Z,1520217921.476 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-03-05T02:45:21.479Z,1520217921.479 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-03-05T02:45:21.575Z,1520217921.575 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-03-05T02:45:21.577Z,1520217921.577 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-03-05T02:45:21.677Z,1520217921.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-03-05T02:45:21.678Z,1520217921.678 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-03-05T02:45:21.875Z,1520217921.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-03-05T02:45:21.877Z,1520217921.877 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-03-05T02:45:22.179Z,1520217922.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-03-05T02:45:22.181Z,1520217922.181 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-03-05T02:45:22.500Z,1520217922.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-03-05T02:45:22.500Z,1520217922.500 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-03-05T02:45:22.960Z,1520217922.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-03-05T02:45:22.960Z,1520217922.960 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-03-05T02:45:23.294Z,1520217923.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-03-05T02:45:23.296Z,1520217923.296 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-03-05T02:45:23.724Z,1520217923.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-03-05T02:45:23.725Z,1520217923.725 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-03-05T02:45:23.806Z,1520217923.806 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-03-05T02:45:24.097Z,1520217924.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-03-05T02:45:24.098Z,1520217924.098 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-03-05T02:45:24.240Z,1520217924.240 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-03-05T02:45:24.240Z,1520217924.240 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-03-05T02:45:24.440Z,1520217924.440 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-03-05T02:45:24.441Z,1520217924.441 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-03-05T02:45:24.653Z,1520217924.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-03-05T02:45:25.040Z,1520217925.040 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-03-05T02:45:25.228Z,1520217925.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-03-05T02:45:25.228Z,1520217925.228 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-03-05T02:45:25.368Z,1520217925.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-03-05T02:45:25.370Z,1520217925.370 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-03-05T02:45:25.373Z,1520217925.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-03-05T02:45:25.600Z,1520217925.600 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-03-05T02:45:25.601Z,1520217925.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-03-05T02:45:25.679Z,1520217925.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-03-05T02:45:25.820Z,1520217925.820 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-03-05T02:45:25.913Z,1520217925.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-03-05T02:45:25.998Z,1520217925.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-03-05T02:45:26.134Z,1520217926.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-03-05T02:45:26.298Z,1520217926.298 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-03-05T02:45:26.396Z,1520217926.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-03-05T02:45:26.488Z,1520217926.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-03-05T02:45:26.585Z,1520217926.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-03-05T02:45:26.668Z,1520217926.668 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-03-05T02:45:26.674Z,1520217926.674 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-03-05T02:45:26.795Z,1520217926.795 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-03-05T02:45:26.797Z,1520217926.797 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-03-05T02:45:26.820Z,1520217926.820 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-03-05T02:45:26.822Z,1520217926.822 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-03-05T02:45:26.876Z,1520217926.876 [DepthRateCalculator] Loaded 2018-03-05T02:45:26.876Z,1520217926.876 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-03-05T02:45:26.882Z,1520217926.882 [PitchRateCalculator] Loaded 2018-03-05T02:45:26.883Z,1520217926.883 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-03-05T02:45:26.898Z,1520217926.898 [SpeedCalculator] Loaded 2018-03-05T02:45:26.898Z,1520217926.898 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-03-05T02:45:26.919Z,1520217926.919 [TempGradientCalculator] Loaded 2018-03-05T02:45:26.919Z,1520217926.919 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-03-05T02:45:26.934Z,1520217926.934 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-03-05T02:45:26.934Z,1520217926.934 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-03-05T02:45:26.940Z,1520217926.940 [YawRateCalculator] Loaded 2018-03-05T02:45:26.940Z,1520217926.940 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-03-05T02:45:26.940Z,1520217926.940 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-03-05T02:45:26.941Z,1520217926.941 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-03-05T02:45:26.977Z,1520217926.977 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-03-05T02:45:26.978Z,1520217926.978 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-03-05T02:45:27.423Z,1520217927.423 [AcousticModem_Benthos_ATM900] Loaded 2018-03-05T02:45:27.423Z,1520217927.423 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-03-05T02:45:27.562Z,1520217927.562 [DataOverHttps] Loaded 2018-03-05T02:45:27.562Z,1520217927.562 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-03-05T02:45:27.576Z,1520217927.576 [Depth_Keller] Loaded 2018-03-05T02:45:27.576Z,1520217927.576 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-03-05T02:45:27.580Z,1520217927.580 [DropWeight] Loaded 2018-03-05T02:45:27.581Z,1520217927.581 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-03-05T02:45:27.675Z,1520217927.675 [NAL9602] Loaded 2018-03-05T02:45:27.675Z,1520217927.675 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-03-05T02:45:27.691Z,1520217927.691 [Onboard] Loaded 2018-03-05T02:45:27.691Z,1520217927.691 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-03-05T02:45:27.701Z,1520217927.701 [Radio_Surface] Loaded 2018-03-05T02:45:27.701Z,1520217927.701 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-03-05T02:45:27.702Z,1520217927.702 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0 2018-03-05T02:45:27.702Z,1520217927.702 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 885 2018-03-05T02:45:27.853Z,1520217927.853 [PNI_TCM] Loaded 2018-03-05T02:45:27.853Z,1520217927.853 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-03-05T02:45:27.911Z,1520217927.911 [Rowe_600LCM] Loaded 2018-03-05T02:45:27.911Z,1520217927.911 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-03-05T02:45:27.912Z,1520217927.912 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D54E0 2018-03-05T02:45:27.913Z,1520217927.913 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 886 2018-03-05T02:45:29.594Z,1520217929.594 [BPC1] Loaded 2018-03-05T02:45:29.594Z,1520217929.594 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-03-05T02:45:29.595Z,1520217929.595 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-03-05T02:45:29.597Z,1520217929.597 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-03-05T02:45:29.743Z,1520217929.743 [SBIT](DEBUG): Construct Startup Built In Test. 2018-03-05T02:45:29.770Z,1520217929.770 [SBIT] Loaded 2018-03-05T02:45:29.770Z,1520217929.770 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-03-05T02:45:29.771Z,1520217929.771 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-03-05T02:45:29.782Z,1520217929.782 [IBIT] Loaded 2018-03-05T02:45:29.782Z,1520217929.782 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-03-05T02:45:29.785Z,1520217929.785 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-03-05T02:45:29.932Z,1520217929.932 [CBIT] Loaded 2018-03-05T02:45:29.933Z,1520217929.933 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-03-05T02:45:29.933Z,1520217929.933 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-03-05T02:45:29.934Z,1520217929.934 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-03-05T02:45:30.594Z,1520217930.594 [Aanderaa_O2] Loaded 2018-03-05T02:45:30.594Z,1520217930.594 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-03-05T02:45:30.640Z,1520217930.640 [CTD_Seabird] Loaded 2018-03-05T02:45:30.640Z,1520217930.640 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-03-05T02:45:30.641Z,1520217930.641 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408954E0 2018-03-05T02:45:30.642Z,1520217930.642 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 887 2018-03-05T02:45:30.658Z,1520217930.658 [ESPComponent] Loaded 2018-03-05T02:45:30.658Z,1520217930.658 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-03-05T02:45:30.672Z,1520217930.672 [PAR_Licor] Loaded 2018-03-05T02:45:30.672Z,1520217930.672 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-03-05T02:45:30.719Z,1520217930.719 [WetLabsBB2FL] Loaded 2018-03-05T02:45:30.720Z,1520217930.720 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-03-05T02:45:30.721Z,1520217930.721 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C54E0 2018-03-05T02:45:30.721Z,1520217930.721 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 888 2018-03-05T02:45:30.722Z,1520217930.722 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-03-05T02:45:30.722Z,1520217930.722 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-03-05T02:45:30.988Z,1520217930.988 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-03-05T02:45:30.988Z,1520217930.988 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-03-05T02:45:31.072Z,1520217931.072 [VerticalControl](DEBUG): Construct VerticalControl. 2018-03-05T02:45:31.167Z,1520217931.167 [VerticalControl] Loaded 2018-03-05T02:45:31.167Z,1520217931.167 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-03-05T02:45:31.168Z,1520217931.168 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-03-05T02:45:31.228Z,1520217931.228 [HorizontalControl] Loaded 2018-03-05T02:45:31.228Z,1520217931.228 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-03-05T02:45:31.229Z,1520217931.229 [SpeedControl](DEBUG): Construct SpeedControl. 2018-03-05T02:45:31.234Z,1520217931.234 [SpeedControl] Loaded 2018-03-05T02:45:31.234Z,1520217931.234 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-03-05T02:45:31.235Z,1520217931.235 [LoopControl](DEBUG): Construct LoopControl. 2018-03-05T02:45:31.235Z,1520217931.235 [LoopControl] Loaded 2018-03-05T02:45:31.235Z,1520217931.235 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-03-05T02:45:31.236Z,1520217931.236 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-03-05T02:45:31.237Z,1520217931.237 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-03-05T02:45:31.353Z,1520217931.353 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-03-05T02:45:31.353Z,1520217931.353 [StratificationFrontDetector](DEBUG): (re)initializing 2018-03-05T02:45:31.354Z,1520217931.354 [StratificationFrontDetector] Loaded 2018-03-05T02:45:31.354Z,1520217931.354 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-03-05T02:45:31.355Z,1520217931.355 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-03-05T02:45:31.355Z,1520217931.355 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-03-05T02:45:31.470Z,1520217931.470 [BuoyancyServo] Loaded 2018-03-05T02:45:31.470Z,1520217931.470 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-03-05T02:45:31.481Z,1520217931.481 [ElevatorServo] Loaded 2018-03-05T02:45:31.481Z,1520217931.481 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-03-05T02:45:31.492Z,1520217931.492 [MassServo] Loaded 2018-03-05T02:45:31.492Z,1520217931.492 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-03-05T02:45:31.503Z,1520217931.503 [RudderServo] Loaded 2018-03-05T02:45:31.503Z,1520217931.503 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-03-05T02:45:31.514Z,1520217931.514 [ThrusterServo] Loaded 2018-03-05T02:45:31.514Z,1520217931.514 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-03-05T02:45:31.515Z,1520217931.515 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-03-05T02:45:31.515Z,1520217931.515 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-03-05T02:45:31.626Z,1520217931.626 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-03-05T02:45:31.627Z,1520217931.627 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-03-05T02:45:31.680Z,1520217931.680 [DeadReckonUsingSpeedCalculator] Loaded 2018-03-05T02:45:31.680Z,1520217931.680 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-03-05T02:45:31.696Z,1520217931.696 [NavChart] Loaded 2018-03-05T02:45:31.697Z,1520217931.697 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-03-05T02:45:31.700Z,1520217931.700 [UniversalFixResidualReporter] Loaded 2018-03-05T02:45:31.701Z,1520217931.701 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-03-05T02:45:31.701Z,1520217931.701 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-03-05T02:45:31.705Z,1520217931.705 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-03-05T02:45:31.705Z,1520217931.705 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-03-05T02:45:31.712Z,1520217931.712 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-03-05T02:45:31.713Z,1520217931.713 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0 2018-03-05T02:45:31.713Z,1520217931.713 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 889 2018-03-05T02:45:31.718Z,1520217931.718 [Supervisor](INFO): Main Thread ID is 795 2018-03-05T02:45:31.718Z,1520217931.718 [Supervisor](DEBUG): Running supervisor. 2018-03-05T02:45:31.719Z,1520217931.719 [CommandLine ThreadHandler](INFO): Handler Thread ID is 890 2018-03-05T02:45:31.721Z,1520217931.721 [controlThread ThreadHandler](INFO): Handler Thread ID is 891 2018-03-05T02:45:31.722Z,1520217931.722 [controlThread](DEBUG): Initializing ControlThread 2018-03-05T02:45:31.722Z,1520217931.722 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-05T02:45:31.723Z,1520217931.723 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-03-05T02:45:31.723Z,1520217931.723 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-03-05T02:45:31.723Z,1520217931.723 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-03-05T02:45:31.724Z,1520217931.724 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-03-05T02:45:31.725Z,1520217931.725 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-03-05T02:45:31.729Z,1520217931.729 [SBIT](INFO): Initialize SBIT Component. 2018-03-05T02:45:31.729Z,1520217931.729 [SBIT](IMPORTANT): git: 2018-02-09A-11-g1191477 2018-03-05T02:45:31.729Z,1520217931.729 [SBIT](INFO): git hash: 1191477e507aa94f59b4a9056a613189489a6cd1 2018-03-05T02:45:31.730Z,1520217931.730 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-03-05T02:45:31.731Z,1520217931.731 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Nov 29 17:23:09 PST 2017 2018-03-05T02:45:31.732Z,1520217931.732 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2018-03-05T02:45:31.732Z,1520217931.732 [IBIT](INFO): Initialize IBIT Component. 2018-03-05T02:45:31.733Z,1520217931.733 [CBIT](DEBUG): Initialize CBIT Component. 2018-03-05T02:45:31.733Z,1520217931.733 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-03-05T02:45:31.733Z,1520217931.733 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-03-05T02:45:31.734Z,1520217931.734 [logger ThreadHandler](INFO): Handler Thread ID is 892 2018-03-05T02:45:31.755Z,1520217931.755 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 893 2018-03-05T02:45:31.761Z,1520217931.761 [Radio_Surface](INFO): Powering up 2018-03-05T02:45:31.767Z,1520217931.767 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 894 2018-03-05T02:45:31.767Z,1520217931.767 [Rowe_600LCM](INFO): Initializing 2018-03-05T02:45:31.768Z,1520217931.768 [Rowe_600LCM](INFO): Checking LCM 2018-03-05T02:45:31.853Z,1520217931.853 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 895 2018-03-05T02:45:31.855Z,1520217931.855 [CTD_Seabird](INFO): Initializing 2018-03-05T02:45:31.855Z,1520217931.855 [CTD_Seabird](INFO): Checking LCM 2018-03-05T02:45:31.856Z,1520217931.856 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 896 2018-03-05T02:45:31.857Z,1520217931.857 [WetLabsBB2FL](INFO): Powering down 2018-03-05T02:45:31.887Z,1520217931.887 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 897 2018-03-05T02:45:31.894Z,1520217931.894 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-03-05T02:45:31.894Z,1520217931.894 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-03-05T02:45:31.895Z,1520217931.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-03-05T02:45:31.895Z,1520217931.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-03-05T02:45:31.895Z,1520217931.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-03-05T02:45:31.895Z,1520217931.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-03-05T02:45:31.895Z,1520217931.895 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-03-05T02:45:31.895Z,1520217931.895 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-03-05T02:45:31.896Z,1520217931.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-03-05T02:45:31.896Z,1520217931.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-03-05T02:45:31.896Z,1520217931.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-03-05T02:45:31.896Z,1520217931.896 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-03-05T02:45:31.896Z,1520217931.896 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-03-05T02:45:31.897Z,1520217931.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-03-05T02:45:31.897Z,1520217931.897 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-03-05T02:45:31.897Z,1520217931.897 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-03-05T02:45:31.900Z,1520217931.900 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-03-05T02:45:31.920Z,1520217931.920 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-03-05T02:45:31.923Z,1520217931.923 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-03-05T02:45:31.924Z,1520217931.924 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-03-05T02:45:32.032Z,1520217932.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-03-05T02:45:32.032Z,1520217932.032 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-03-05T02:45:32.033Z,1520217932.033 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-03-05T02:45:32.033Z,1520217932.033 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-05T02:45:32.034Z,1520217932.034 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-05T02:45:32.034Z,1520217932.034 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-05T02:45:32.034Z,1520217932.034 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-03-05T02:45:32.035Z,1520217932.035 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-03-05T02:45:32.036Z,1520217932.036 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-03-05T02:45:32.059Z,1520217932.059 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-05T02:45:32.077Z,1520217932.077 [MissionManager](DEBUG): 2018-03-05T02:45:32.078Z,1520217932.078 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-03-05T02:45:32.255Z,1520217932.255 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-03-05T02:45:32.256Z,1520217932.256 [Default:A.Wait](DEBUG): Construct Wait. 2018-03-05T02:45:32.262Z,1520217932.262 [CTD_Seabird](INFO): LCM OK 2018-03-05T02:45:32.262Z,1520217932.262 [CTD_Seabird](INFO): Powering up 2018-03-05T02:45:32.270Z,1520217932.270 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-05T02:45:32.274Z,1520217932.274 [Rowe_600LCM](INFO): LCM OK 2018-03-05T02:45:32.274Z,1520217932.274 [Rowe_600LCM](INFO): Powering up 2018-03-05T02:45:32.304Z,1520217932.304 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-03-05T02:45:32.335Z,1520217932.335 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-03-05T02:45:32.341Z,1520217932.341 [Default:E.Execute](DEBUG): Construct Execute. 2018-03-05T02:45:32.359Z,1520217932.359 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2018-03-05T02:45:32.371Z,1520217932.371 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2018-03-05T02:45:32.388Z,1520217932.388 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-03-05T02:45:32.388Z,1520217932.388 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-03-05T02:45:32.413Z,1520217932.413 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-03-05T02:45:32.780Z,1520217932.780 [ESPComponent](INFO): powering down ESP 2018-03-05T02:45:33.307Z,1520217933.307 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:33.398Z,1520217933.398 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:33.499Z,1520217933.499 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-05T02:45:33.506Z,1520217933.506 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-05T02:45:33.521Z,1520217933.521 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-03-05T02:45:33.527Z,1520217933.527 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-03-05T02:45:33.540Z,1520217933.540 [MassServo](DEBUG): Initializing EZServoServo. 2018-03-05T02:45:33.546Z,1520217933.546 [MassServo](DEBUG): Initializing MassServo. 2018-03-05T02:45:33.568Z,1520217933.568 [RudderServo](DEBUG): Initializing EZServoServo. 2018-03-05T02:45:33.574Z,1520217933.574 [RudderServo](DEBUG): Initializing RudderServo. 2018-03-05T02:45:33.588Z,1520217933.588 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-03-05T02:45:33.595Z,1520217933.595 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-03-05T02:45:33.637Z,1520217933.637 [CommandLine](FAULT): Scheduling is paused 2018-03-05T02:45:33.637Z,1520217933.637 [CBIT](INFO): Critical error at 20180305T024531 2018-03-05T02:45:33.638Z,1520217933.638 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-03-05T02:45:33.742Z,1520217933.742 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-03-05T02:45:33.742Z,1520217933.742 [DropWeight] Hardware Fault, FailCount= 1 2018-03-05T02:45:33.742Z,1520217933.742 [DropWeight](ERROR): Hardware Fault 2018-03-05T02:45:33.858Z,1520217933.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:33.859Z,1520217933.859 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:33.884Z,1520217933.884 [CBIT](INFO): Critical error at 20180305T024533 2018-03-05T02:45:33.886Z,1520217933.886 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-03-05T02:45:33.886Z,1520217933.886 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-03-05T02:45:34.135Z,1520217934.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:34.136Z,1520217934.136 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:34.466Z,1520217934.466 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-03-05T02:45:34.466Z,1520217934.466 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-03-05T02:45:34.467Z,1520217934.467 [BuoyancyServo] Communications Fault, FailCount= 1 2018-03-05T02:45:34.467Z,1520217934.467 [BuoyancyServo](ERROR): Communications Fault 2018-03-05T02:45:34.672Z,1520217934.672 [CBIT](INFO): Critical error at 20180305T024533 2018-03-05T02:45:34.674Z,1520217934.674 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-03-05T02:45:34.729Z,1520217934.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:34.730Z,1520217934.730 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:34.751Z,1520217934.751 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-03-05T02:45:34.752Z,1520217934.752 [BuoyancyServo](INFO): Powering down 2018-03-05T02:45:35.166Z,1520217935.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:35.174Z,1520217935.174 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:35.522Z,1520217935.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:35.523Z,1520217935.523 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:35.947Z,1520217935.947 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:35.949Z,1520217935.949 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:36.184Z,1520217936.184 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-03-05T02:45:36.277Z,1520217936.277 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2018-03-05T02:45:36.343Z,1520217936.343 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:36.344Z,1520217936.344 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:36.517Z,1520217936.517 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-03-05T02:45:36.518Z,1520217936.518 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-03-05T02:45:36.519Z,1520217936.519 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-03-05T02:45:36.734Z,1520217936.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:36.735Z,1520217936.735 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:36.791Z,1520217936.791 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-03-05T02:45:36.792Z,1520217936.792 [BuoyancyServo] No Fault, FailCount= 1 2018-03-05T02:45:37.202Z,1520217937.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:37.203Z,1520217937.203 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:37.210Z,1520217937.210 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-05T02:45:37.211Z,1520217937.211 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-05T02:45:37.413Z,1520217937.413 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-05T02:45:37.549Z,1520217937.549 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null & 2018-03-05T02:45:37.551Z,1520217937.551 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-03-05T02:45:37.580Z,1520217937.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:37.581Z,1520217937.581 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:37.933Z,1520217937.933 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-05T02:45:37.933Z,1520217937.933 [CTD_Seabird](INFO): Powering down 2018-03-05T02:45:38.027Z,1520217938.027 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:38.028Z,1520217938.028 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:38.349Z,1520217938.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:38.350Z,1520217938.350 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:38.735Z,1520217938.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:38.736Z,1520217938.736 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:39.131Z,1520217939.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:39.132Z,1520217939.132 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:39.534Z,1520217939.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:39.535Z,1520217939.535 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:39.882Z,1520217939.882 [AcousticModem_Benthos_ATM900](DEBUG): 2018-03-05T02:45:39.923Z,1520217939.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:39.924Z,1520217939.924 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:40.316Z,1520217940.316 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-05T02:45:40.316Z,1520217940.316 [PNI_TCM] Data Fault, FailCount= 1 2018-03-05T02:45:40.316Z,1520217940.316 [PNI_TCM](ERROR): Data Fault 2018-03-05T02:45:40.331Z,1520217940.331 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:40.332Z,1520217940.332 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:40.447Z,1520217940.447 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-05T02:45:40.703Z,1520217940.703 [PNI_TCM](INFO): Powering down 2018-03-05T02:45:40.753Z,1520217940.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:40.762Z,1520217940.762 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:41.138Z,1520217941.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:41.147Z,1520217941.147 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:41.483Z,1520217941.483 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-03-05T02:45:41.483Z,1520217941.483 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-03-05T02:45:41.484Z,1520217941.484 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-03-05T02:45:41.485Z,1520217941.485 [AcousticModem_Benthos_ATM900](DEBUG): Mar 10 2000 04:43:11 2018-03-05T02:45:41.534Z,1520217941.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:41.542Z,1520217941.542 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:41.882Z,1520217941.882 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-03-05T02:45:41.927Z,1520217941.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:41.928Z,1520217941.928 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:41.983Z,1520217941.983 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-05T02:45:41.983Z,1520217941.983 [PNI_TCM] No Fault, FailCount= 1 2018-03-05T02:45:42.339Z,1520217942.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:42.340Z,1520217942.340 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:42.682Z,1520217942.682 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-03-05T02:45:42.730Z,1520217942.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:42.731Z,1520217942.731 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:43.081Z,1520217943.081 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-03-05T02:45:43.082Z,1520217943.082 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2018-03-05T02:45:43.082Z,1520217943.082 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2018-03-05T02:45:43.119Z,1520217943.119 [Aanderaa_O2](FAULT): Timed out starting 2018-03-05T02:45:43.119Z,1520217943.119 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-03-05T02:45:43.119Z,1520217943.119 [Aanderaa_O2](ERROR): Communications Fault 2018-03-05T02:45:43.163Z,1520217943.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:43.164Z,1520217943.164 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:43.227Z,1520217943.227 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-03-05T02:45:43.482Z,1520217943.482 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2018-03-05T02:45:43.535Z,1520217943.535 [Aanderaa_O2](INFO): Powering down 2018-03-05T02:45:43.633Z,1520217943.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:43.634Z,1520217943.634 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:43.882Z,1520217943.882 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2018-03-05T02:45:43.883Z,1520217943.883 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2018-03-05T02:45:43.970Z,1520217943.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:43.978Z,1520217943.978 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:44.282Z,1520217944.282 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2018-03-05T02:45:44.339Z,1520217944.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:44.340Z,1520217944.340 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:44.387Z,1520217944.387 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-03-05T02:45:44.387Z,1520217944.387 [Aanderaa_O2] No Fault, FailCount= 1 2018-03-05T02:45:44.738Z,1520217944.738 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:44.739Z,1520217944.739 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:45.202Z,1520217945.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:45.210Z,1520217945.210 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:45.523Z,1520217945.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:45.524Z,1520217945.524 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:45.927Z,1520217945.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:45.928Z,1520217945.928 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:46.344Z,1520217946.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:46.345Z,1520217946.345 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:46.728Z,1520217946.728 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:46.729Z,1520217946.729 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:47.151Z,1520217947.151 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:47.152Z,1520217947.152 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:47.542Z,1520217947.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:47.543Z,1520217947.543 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:47.915Z,1520217947.915 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:47.916Z,1520217947.916 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:48.339Z,1520217948.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:48.340Z,1520217948.340 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:48.742Z,1520217948.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:48.743Z,1520217948.743 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:49.143Z,1520217949.143 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:49.144Z,1520217949.144 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:49.504Z,1520217949.504 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-05T02:45:49.504Z,1520217949.504 [PNI_TCM] Data Fault, FailCount= 2 2018-03-05T02:45:49.504Z,1520217949.504 [PNI_TCM](ERROR): Data Fault 2018-03-05T02:45:49.551Z,1520217949.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:49.552Z,1520217949.552 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:49.591Z,1520217949.591 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-05T02:45:49.905Z,1520217949.905 [PNI_TCM](INFO): Powering down 2018-03-05T02:45:49.958Z,1520217949.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:49.970Z,1520217949.970 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:50.382Z,1520217950.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:50.383Z,1520217950.383 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:50.730Z,1520217950.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:50.731Z,1520217950.731 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:51.134Z,1520217951.134 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:51.139Z,1520217951.139 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:51.227Z,1520217951.227 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-05T02:45:51.227Z,1520217951.227 [PNI_TCM] No Fault, FailCount= 2 2018-03-05T02:45:51.523Z,1520217951.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:51.523Z,1520217951.523 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:51.934Z,1520217951.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:51.935Z,1520217951.935 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:52.346Z,1520217952.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:52.362Z,1520217952.362 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:52.727Z,1520217952.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:52.728Z,1520217952.728 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:53.135Z,1520217953.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:53.136Z,1520217953.136 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:53.558Z,1520217953.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:53.559Z,1520217953.559 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:53.942Z,1520217953.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:53.943Z,1520217953.943 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:54.334Z,1520217954.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:54.342Z,1520217954.342 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:54.730Z,1520217954.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:54.739Z,1520217954.739 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:55.104Z,1520217955.104 [Aanderaa_O2](FAULT): Timed out starting 2018-03-05T02:45:55.105Z,1520217955.105 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-03-05T02:45:55.105Z,1520217955.105 [Aanderaa_O2](ERROR): Communications Fault 2018-03-05T02:45:55.139Z,1520217955.139 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:55.139Z,1520217955.139 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:55.203Z,1520217955.203 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-03-05T02:45:55.505Z,1520217955.505 [Aanderaa_O2](INFO): Powering down 2018-03-05T02:45:55.538Z,1520217955.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:55.539Z,1520217955.539 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:55.927Z,1520217955.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:55.928Z,1520217955.928 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:56.330Z,1520217956.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:56.339Z,1520217956.339 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:56.396Z,1520217956.396 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-03-05T02:45:56.396Z,1520217956.396 [Aanderaa_O2] No Fault, FailCount= 2 2018-03-05T02:45:56.734Z,1520217956.734 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:56.742Z,1520217956.742 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:57.122Z,1520217957.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:57.123Z,1520217957.123 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:57.531Z,1520217957.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:57.531Z,1520217957.531 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:57.888Z,1520217957.888 [NAL9602](INFO): Powering up NAL9602 2018-03-05T02:45:57.935Z,1520217957.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:57.936Z,1520217957.936 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:58.334Z,1520217958.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:58.342Z,1520217958.342 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:58.704Z,1520217958.704 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-05T02:45:58.704Z,1520217958.704 [PNI_TCM] Data Fault, FailCount= 3 2018-03-05T02:45:58.704Z,1520217958.704 [PNI_TCM](ERROR): Data Fault 2018-03-05T02:45:58.718Z,1520217958.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:58.719Z,1520217958.719 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:58.803Z,1520217958.803 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-05T02:45:59.107Z,1520217959.107 [PNI_TCM](INFO): Powering down 2018-03-05T02:45:59.123Z,1520217959.123 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:59.124Z,1520217959.124 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:59.526Z,1520217959.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:59.527Z,1520217959.527 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:59.948Z,1520217959.948 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:45:59.949Z,1520217959.949 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:00.347Z,1520217960.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:00.348Z,1520217960.348 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:00.399Z,1520217960.399 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-05T02:46:00.399Z,1520217960.399 [PNI_TCM] No Fault, FailCount= 3 2018-03-05T02:46:00.748Z,1520217960.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:00.749Z,1520217960.749 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:01.126Z,1520217961.126 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:01.127Z,1520217961.127 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:01.558Z,1520217961.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:01.562Z,1520217961.562 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:01.960Z,1520217961.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:01.961Z,1520217961.961 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:02.367Z,1520217962.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:02.368Z,1520217962.368 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:02.764Z,1520217962.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:02.765Z,1520217962.765 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:03.169Z,1520217963.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:03.170Z,1520217963.170 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:03.530Z,1520217963.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:03.531Z,1520217963.531 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:03.961Z,1520217963.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:03.962Z,1520217963.962 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:04.330Z,1520217964.330 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:04.331Z,1520217964.331 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:04.727Z,1520217964.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-05T02:46:04.731Z,1520217964.731 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have