2018-03-06T16:57:58.298Z,1520355478.298 [Supervisor](DEBUG): Initializing supervisor. 2018-03-06T16:57:58.300Z,1520355478.300 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2018-03-06T16:57:58.301Z,1520355478.301 [SyncHandler](INFO): Protected caller Thread ID is 803 2018-03-06T16:57:58.301Z,1520355478.301 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2018-03-06T16:57:58.302Z,1520355478.302 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2018-03-06T16:57:58.302Z,1520355478.302 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 804 2018-03-06T16:57:58.305Z,1520355478.305 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2018-03-06T16:57:58.316Z,1520355478.316 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2018-03-06T16:57:58.317Z,1520355478.317 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2018-03-06T16:57:58.317Z,1520355478.317 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 805 2018-03-06T16:57:58.318Z,1520355478.318 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2018-03-06T16:57:58.319Z,1520355478.319 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2018-03-06T16:57:58.319Z,1520355478.319 [logger ThreadHandler](INFO): Protected caller Thread ID is 806 2018-03-06T16:57:58.321Z,1520355478.321 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2018-03-06T16:57:58.321Z,1520355478.321 [Supervisor](INFO): Looking for Config files in directory: Config/ 2018-03-06T16:57:58.324Z,1520355478.324 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2018-03-06T16:57:58.420Z,1520355478.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2018-03-06T16:57:58.422Z,1520355478.422 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2018-03-06T16:57:58.520Z,1520355478.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2018-03-06T16:57:58.521Z,1520355478.521 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2018-03-06T16:57:58.706Z,1520355478.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2018-03-06T16:57:58.708Z,1520355478.708 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2018-03-06T16:57:58.995Z,1520355478.995 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2018-03-06T16:57:58.997Z,1520355478.997 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2018-03-06T16:57:59.298Z,1520355479.298 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2018-03-06T16:57:59.299Z,1520355479.299 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2018-03-06T16:57:59.732Z,1520355479.732 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2018-03-06T16:57:59.733Z,1520355479.733 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2018-03-06T16:58:00.066Z,1520355480.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2018-03-06T16:58:00.068Z,1520355480.068 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2018-03-06T16:58:00.477Z,1520355480.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2018-03-06T16:58:00.479Z,1520355480.479 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2018-03-06T16:58:00.559Z,1520355480.559 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2018-03-06T16:58:00.835Z,1520355480.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2018-03-06T16:58:00.836Z,1520355480.836 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2018-03-06T16:58:00.973Z,1520355480.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2018-03-06T16:58:00.974Z,1520355480.974 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2018-03-06T16:58:01.167Z,1520355481.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2018-03-06T16:58:01.168Z,1520355481.168 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2018-03-06T16:58:01.366Z,1520355481.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2018-03-06T16:58:01.368Z,1520355481.368 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2018-03-06T16:58:01.465Z,1520355481.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2018-03-06T16:58:01.465Z,1520355481.465 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2018-03-06T16:58:01.603Z,1520355481.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2018-03-06T16:58:01.605Z,1520355481.605 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2018-03-06T16:58:01.693Z,1520355481.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2018-03-06T16:58:02.368Z,1520355482.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2018-03-06T16:58:02.369Z,1520355482.369 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2018-03-06T16:58:02.450Z,1520355482.450 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2018-03-06T16:58:02.586Z,1520355482.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2018-03-06T16:58:02.677Z,1520355482.677 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2018-03-06T16:58:02.760Z,1520355482.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2018-03-06T16:58:02.891Z,1520355482.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2018-03-06T16:58:03.090Z,1520355483.090 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2018-03-06T16:58:03.186Z,1520355483.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2018-03-06T16:58:03.276Z,1520355483.276 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2018-03-06T16:58:03.371Z,1520355483.371 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2018-03-06T16:58:03.453Z,1520355483.453 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2018-03-06T16:58:03.459Z,1520355483.459 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2018-03-06T16:58:03.580Z,1520355483.580 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2018-03-06T16:58:03.582Z,1520355483.582 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2018-03-06T16:58:03.605Z,1520355483.605 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2018-03-06T16:58:03.607Z,1520355483.607 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2018-03-06T16:58:03.661Z,1520355483.661 [DepthRateCalculator] Loaded 2018-03-06T16:58:03.661Z,1520355483.661 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2018-03-06T16:58:03.667Z,1520355483.667 [PitchRateCalculator] Loaded 2018-03-06T16:58:03.667Z,1520355483.667 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2018-03-06T16:58:03.682Z,1520355483.682 [SpeedCalculator] Loaded 2018-03-06T16:58:03.683Z,1520355483.683 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2018-03-06T16:58:03.703Z,1520355483.703 [TempGradientCalculator] Loaded 2018-03-06T16:58:03.703Z,1520355483.703 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2018-03-06T16:58:03.717Z,1520355483.717 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2018-03-06T16:58:03.718Z,1520355483.718 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2018-03-06T16:58:03.723Z,1520355483.723 [YawRateCalculator] Loaded 2018-03-06T16:58:03.723Z,1520355483.723 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2018-03-06T16:58:03.724Z,1520355483.724 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2018-03-06T16:58:03.724Z,1520355483.724 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2018-03-06T16:58:03.760Z,1520355483.760 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2018-03-06T16:58:03.761Z,1520355483.761 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2018-03-06T16:58:04.201Z,1520355484.201 [AcousticModem_Benthos_ATM900] Loaded 2018-03-06T16:58:04.201Z,1520355484.201 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread. 2018-03-06T16:58:04.338Z,1520355484.338 [DataOverHttps] Loaded 2018-03-06T16:58:04.339Z,1520355484.339 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2018-03-06T16:58:04.352Z,1520355484.352 [Depth_Keller] Loaded 2018-03-06T16:58:04.352Z,1520355484.352 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2018-03-06T16:58:04.357Z,1520355484.357 [DropWeight] Loaded 2018-03-06T16:58:04.357Z,1520355484.357 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2018-03-06T16:58:04.449Z,1520355484.449 [NAL9602] Loaded 2018-03-06T16:58:04.450Z,1520355484.450 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2018-03-06T16:58:04.465Z,1520355484.465 [Onboard] Loaded 2018-03-06T16:58:04.465Z,1520355484.465 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2018-03-06T16:58:04.475Z,1520355484.475 [Radio_Surface] Loaded 2018-03-06T16:58:04.475Z,1520355484.475 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2018-03-06T16:58:04.476Z,1520355484.476 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407A54E0 2018-03-06T16:58:04.476Z,1520355484.476 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 886 2018-03-06T16:58:04.626Z,1520355484.626 [PNI_TCM] Loaded 2018-03-06T16:58:04.626Z,1520355484.626 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2018-03-06T16:58:04.683Z,1520355484.683 [Rowe_600LCM] Loaded 2018-03-06T16:58:04.684Z,1520355484.684 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread. 2018-03-06T16:58:04.685Z,1520355484.685 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407D54E0 2018-03-06T16:58:04.685Z,1520355484.685 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 887 2018-03-06T16:58:06.354Z,1520355486.354 [BPC1] Loaded 2018-03-06T16:58:06.354Z,1520355486.354 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2018-03-06T16:58:06.354Z,1520355486.354 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2018-03-06T16:58:06.356Z,1520355486.356 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2018-03-06T16:58:06.504Z,1520355486.504 [SBIT](DEBUG): Construct Startup Built In Test. 2018-03-06T16:58:06.530Z,1520355486.530 [SBIT] Loaded 2018-03-06T16:58:06.530Z,1520355486.530 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2018-03-06T16:58:06.530Z,1520355486.530 [IBIT](DEBUG): Construct Initiated Built In Test. 2018-03-06T16:58:06.542Z,1520355486.542 [IBIT] Loaded 2018-03-06T16:58:06.542Z,1520355486.542 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2018-03-06T16:58:06.545Z,1520355486.545 [CBIT](DEBUG): Construct Continuous Built In Test. 2018-03-06T16:58:06.690Z,1520355486.690 [CBIT] Loaded 2018-03-06T16:58:06.691Z,1520355486.691 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2018-03-06T16:58:06.691Z,1520355486.691 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2018-03-06T16:58:06.692Z,1520355486.692 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2018-03-06T16:58:07.006Z,1520355487.006 [Aanderaa_O2] Loaded 2018-03-06T16:58:07.006Z,1520355487.006 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2018-03-06T16:58:07.051Z,1520355487.051 [CTD_Seabird] Loaded 2018-03-06T16:58:07.052Z,1520355487.052 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2018-03-06T16:58:07.053Z,1520355487.053 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408954E0 2018-03-06T16:58:07.053Z,1520355487.053 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888 2018-03-06T16:58:07.069Z,1520355487.069 [ESPComponent] Loaded 2018-03-06T16:58:07.069Z,1520355487.069 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2018-03-06T16:58:07.082Z,1520355487.082 [PAR_Licor] Loaded 2018-03-06T16:58:07.083Z,1520355487.083 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2018-03-06T16:58:07.130Z,1520355487.130 [WetLabsBB2FL] Loaded 2018-03-06T16:58:07.130Z,1520355487.130 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2018-03-06T16:58:07.131Z,1520355487.131 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408C54E0 2018-03-06T16:58:07.132Z,1520355487.132 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889 2018-03-06T16:58:07.132Z,1520355487.132 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2018-03-06T16:58:07.133Z,1520355487.133 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2018-03-06T16:58:07.399Z,1520355487.399 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2018-03-06T16:58:07.399Z,1520355487.399 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2018-03-06T16:58:07.483Z,1520355487.483 [VerticalControl](DEBUG): Construct VerticalControl. 2018-03-06T16:58:07.576Z,1520355487.576 [VerticalControl] Loaded 2018-03-06T16:58:07.576Z,1520355487.576 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2018-03-06T16:58:07.577Z,1520355487.577 [HorizontalControl](DEBUG): Construct HorizontalControl. 2018-03-06T16:58:07.636Z,1520355487.636 [HorizontalControl] Loaded 2018-03-06T16:58:07.636Z,1520355487.636 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2018-03-06T16:58:07.637Z,1520355487.637 [SpeedControl](DEBUG): Construct SpeedControl. 2018-03-06T16:58:07.642Z,1520355487.642 [SpeedControl] Loaded 2018-03-06T16:58:07.642Z,1520355487.642 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2018-03-06T16:58:07.643Z,1520355487.643 [LoopControl](DEBUG): Construct LoopControl. 2018-03-06T16:58:07.643Z,1520355487.643 [LoopControl] Loaded 2018-03-06T16:58:07.643Z,1520355487.643 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2018-03-06T16:58:07.644Z,1520355487.644 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2018-03-06T16:58:07.644Z,1520355487.644 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2018-03-06T16:58:07.760Z,1520355487.760 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2018-03-06T16:58:07.760Z,1520355487.760 [StratificationFrontDetector](DEBUG): (re)initializing 2018-03-06T16:58:07.761Z,1520355487.761 [StratificationFrontDetector] Loaded 2018-03-06T16:58:07.761Z,1520355487.761 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2018-03-06T16:58:07.762Z,1520355487.762 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2018-03-06T16:58:07.762Z,1520355487.762 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2018-03-06T16:58:07.877Z,1520355487.877 [BuoyancyServo] Loaded 2018-03-06T16:58:07.878Z,1520355487.878 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2018-03-06T16:58:07.888Z,1520355487.888 [ElevatorServo] Loaded 2018-03-06T16:58:07.889Z,1520355487.889 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2018-03-06T16:58:07.899Z,1520355487.899 [MassServo] Loaded 2018-03-06T16:58:07.900Z,1520355487.900 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2018-03-06T16:58:07.910Z,1520355487.910 [RudderServo] Loaded 2018-03-06T16:58:07.911Z,1520355487.911 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2018-03-06T16:58:07.921Z,1520355487.921 [ThrusterServo] Loaded 2018-03-06T16:58:07.921Z,1520355487.921 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2018-03-06T16:58:07.922Z,1520355487.922 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2018-03-06T16:58:07.922Z,1520355487.922 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2018-03-06T16:58:08.032Z,1520355488.032 [DeadReckonUsingMultipleVelocitySources] Loaded 2018-03-06T16:58:08.032Z,1520355488.032 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2018-03-06T16:58:08.085Z,1520355488.085 [DeadReckonUsingSpeedCalculator] Loaded 2018-03-06T16:58:08.085Z,1520355488.085 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2018-03-06T16:58:08.101Z,1520355488.101 [NavChart] Loaded 2018-03-06T16:58:08.101Z,1520355488.101 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2018-03-06T16:58:08.105Z,1520355488.105 [UniversalFixResidualReporter] Loaded 2018-03-06T16:58:08.105Z,1520355488.105 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2018-03-06T16:58:08.106Z,1520355488.106 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2018-03-06T16:58:08.109Z,1520355488.109 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2018-03-06T16:58:08.110Z,1520355488.110 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2018-03-06T16:58:08.116Z,1520355488.116 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2018-03-06T16:58:08.117Z,1520355488.117 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0 2018-03-06T16:58:08.118Z,1520355488.118 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 890 2018-03-06T16:58:08.122Z,1520355488.122 [Supervisor](INFO): Main Thread ID is 795 2018-03-06T16:58:08.122Z,1520355488.122 [Supervisor](DEBUG): Running supervisor. 2018-03-06T16:58:08.123Z,1520355488.123 [CommandLine ThreadHandler](INFO): Handler Thread ID is 891 2018-03-06T16:58:08.125Z,1520355488.125 [controlThread ThreadHandler](INFO): Handler Thread ID is 892 2018-03-06T16:58:08.126Z,1520355488.126 [controlThread](DEBUG): Initializing ControlThread 2018-03-06T16:58:08.126Z,1520355488.126 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-03-06T16:58:08.127Z,1520355488.127 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2018-03-06T16:58:08.127Z,1520355488.127 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2018-03-06T16:58:08.127Z,1520355488.127 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2018-03-06T16:58:08.128Z,1520355488.128 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2018-03-06T16:58:08.129Z,1520355488.129 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2018-03-06T16:58:08.135Z,1520355488.135 [SBIT](INFO): Initialize SBIT Component. 2018-03-06T16:58:08.136Z,1520355488.136 [SBIT](IMPORTANT): git: 2018-02-09A-11-g1191477 2018-03-06T16:58:08.136Z,1520355488.136 [SBIT](INFO): git hash: 1191477e507aa94f59b4a9056a613189489a6cd1 2018-03-06T16:58:08.136Z,1520355488.136 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2018-03-06T16:58:08.138Z,1520355488.138 [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-06T16:58:08.139Z,1520355488.139 [SBIT](INFO): Beginning SBIT in 63.000000 seconds. 2018-03-06T16:58:08.139Z,1520355488.139 [IBIT](INFO): Initialize IBIT Component. 2018-03-06T16:58:08.140Z,1520355488.140 [CBIT](DEBUG): Initialize CBIT Component. 2018-03-06T16:58:08.140Z,1520355488.140 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2018-03-06T16:58:08.140Z,1520355488.140 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2018-03-06T16:58:08.141Z,1520355488.141 [logger ThreadHandler](INFO): Handler Thread ID is 893 2018-03-06T16:58:08.161Z,1520355488.161 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 894 2018-03-06T16:58:08.167Z,1520355488.167 [Radio_Surface](INFO): Powering up 2018-03-06T16:58:08.173Z,1520355488.173 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 895 2018-03-06T16:58:08.174Z,1520355488.174 [Rowe_600LCM](INFO): Initializing 2018-03-06T16:58:08.174Z,1520355488.174 [Rowe_600LCM](INFO): Checking LCM 2018-03-06T16:58:08.265Z,1520355488.265 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 896 2018-03-06T16:58:08.266Z,1520355488.266 [CTD_Seabird](INFO): Initializing 2018-03-06T16:58:08.266Z,1520355488.266 [CTD_Seabird](INFO): Checking LCM 2018-03-06T16:58:08.277Z,1520355488.277 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2018-03-06T16:58:08.294Z,1520355488.294 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2018-03-06T16:58:08.297Z,1520355488.297 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2018-03-06T16:58:08.300Z,1520355488.300 [LoopControl](DEBUG): Initialize LoopControlComponent. 2018-03-06T16:58:08.302Z,1520355488.302 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2018-03-06T16:58:08.303Z,1520355488.303 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2018-03-06T16:58:08.303Z,1520355488.303 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2018-03-06T16:58:08.304Z,1520355488.304 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-03-06T16:58:08.304Z,1520355488.304 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-03-06T16:58:08.304Z,1520355488.304 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-03-06T16:58:08.305Z,1520355488.305 [NavChart](DEBUG): Initialize NavChart Navigation. 2018-03-06T16:58:08.305Z,1520355488.305 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2018-03-06T16:58:08.401Z,1520355488.401 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2018-03-06T16:58:08.411Z,1520355488.411 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 897 2018-03-06T16:58:08.413Z,1520355488.413 [WetLabsBB2FL](INFO): Powering down 2018-03-06T16:58:08.432Z,1520355488.432 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 898 2018-03-06T16:58:08.492Z,1520355488.492 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-06T16:58:08.496Z,1520355488.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2018-03-06T16:58:08.496Z,1520355488.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2018-03-06T16:58:08.496Z,1520355488.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2018-03-06T16:58:08.496Z,1520355488.496 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2018-03-06T16:58:08.496Z,1520355488.496 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2018-03-06T16:58:08.497Z,1520355488.497 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2018-03-06T16:58:08.497Z,1520355488.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2018-03-06T16:58:08.497Z,1520355488.497 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2018-03-06T16:58:08.497Z,1520355488.497 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2018-03-06T16:58:08.497Z,1520355488.497 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2018-03-06T16:58:08.498Z,1520355488.498 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2018-03-06T16:58:08.498Z,1520355488.498 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2018-03-06T16:58:08.498Z,1520355488.498 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2018-03-06T16:58:08.498Z,1520355488.498 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2018-03-06T16:58:08.498Z,1520355488.498 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2018-03-06T16:58:08.498Z,1520355488.498 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2018-03-06T16:58:08.573Z,1520355488.573 [MissionManager](DEBUG): 2018-03-06T16:58:08.584Z,1520355488.584 [CTD_Seabird](INFO): LCM OK 2018-03-06T16:58:08.584Z,1520355488.584 [CTD_Seabird](INFO): Powering up 2018-03-06T16:58:08.584Z,1520355488.584 [Rowe_600LCM](INFO): LCM OK 2018-03-06T16:58:08.584Z,1520355488.584 [Rowe_600LCM](INFO): Powering up 2018-03-06T16:58:08.593Z,1520355488.593 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2018-03-06T16:58:08.683Z,1520355488.683 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2018-03-06T16:58:08.684Z,1520355488.684 [Default:A.Wait](DEBUG): Construct Wait. 2018-03-06T16:58:08.714Z,1520355488.714 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2018-03-06T16:58:08.749Z,1520355488.749 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2018-03-06T16:58:08.752Z,1520355488.752 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2018-03-06T16:58:08.773Z,1520355488.773 [Default:E.Execute](DEBUG): Construct Execute. 2018-03-06T16:58:08.797Z,1520355488.797 [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-06T16:58:08.802Z,1520355488.802 [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-06T16:58:08.813Z,1520355488.813 [AcousticModem_Benthos_ATM900](INFO): Powering up 2018-03-06T16:58:08.813Z,1520355488.813 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900. 2018-03-06T16:58:08.839Z,1520355488.839 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2018-03-06T16:58:09.179Z,1520355489.179 [ESPComponent](INFO): powering down ESP 2018-03-06T16:58:09.669Z,1520355489.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:09.781Z,1520355489.781 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:09.877Z,1520355489.877 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-06T16:58:09.885Z,1520355489.885 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-06T16:58:09.907Z,1520355489.907 [ElevatorServo](DEBUG): Initializing EZServoServo. 2018-03-06T16:58:09.913Z,1520355489.913 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2018-03-06T16:58:09.923Z,1520355489.923 [MassServo](DEBUG): Initializing EZServoServo. 2018-03-06T16:58:09.929Z,1520355489.929 [MassServo](DEBUG): Initializing MassServo. 2018-03-06T16:58:09.961Z,1520355489.961 [RudderServo](DEBUG): Initializing EZServoServo. 2018-03-06T16:58:09.963Z,1520355489.963 [RudderServo](DEBUG): Initializing RudderServo. 2018-03-06T16:58:09.997Z,1520355489.997 [ThrusterServo](DEBUG): Initializing EZServoServo. 2018-03-06T16:58:10.013Z,1520355490.013 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2018-03-06T16:58:10.058Z,1520355490.058 [CommandLine](FAULT): Scheduling is paused 2018-03-06T16:58:10.058Z,1520355490.058 [CBIT](INFO): Critical error at 20180306T165808 2018-03-06T16:58:10.059Z,1520355490.059 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2018-03-06T16:58:10.172Z,1520355490.172 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2018-03-06T16:58:10.172Z,1520355490.172 [DropWeight] Hardware Fault, FailCount= 1 2018-03-06T16:58:10.172Z,1520355490.172 [DropWeight](ERROR): Hardware Fault 2018-03-06T16:58:10.288Z,1520355490.288 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:10.297Z,1520355490.297 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:10.322Z,1520355490.322 [CBIT](INFO): Critical error at 20180306T165810 2018-03-06T16:58:10.324Z,1520355490.324 [CBIT](ERROR): Hardware Fault in component: DropWeight 2018-03-06T16:58:10.324Z,1520355490.324 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2018-03-06T16:58:10.544Z,1520355490.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:10.553Z,1520355490.553 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:10.873Z,1520355490.873 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2018-03-06T16:58:10.873Z,1520355490.873 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2018-03-06T16:58:10.873Z,1520355490.873 [BuoyancyServo] Communications Fault, FailCount= 1 2018-03-06T16:58:10.873Z,1520355490.873 [BuoyancyServo](ERROR): Communications Fault 2018-03-06T16:58:11.078Z,1520355491.078 [CBIT](INFO): Critical error at 20180306T165810 2018-03-06T16:58:11.081Z,1520355491.081 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2018-03-06T16:58:11.176Z,1520355491.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:11.177Z,1520355491.177 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:11.182Z,1520355491.182 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-03-06T16:58:11.183Z,1520355491.183 [BuoyancyServo](INFO): Powering down 2018-03-06T16:58:11.544Z,1520355491.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:11.561Z,1520355491.561 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:11.936Z,1520355491.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:11.953Z,1520355491.953 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:12.349Z,1520355492.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:12.351Z,1520355492.351 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:12.578Z,1520355492.578 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-03-06T16:58:12.667Z,1520355492.667 [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-06T16:58:12.802Z,1520355492.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:12.803Z,1520355492.803 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:12.845Z,1520355492.845 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-03-06T16:58:12.845Z,1520355492.845 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-03-06T16:58:12.846Z,1520355492.846 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-03-06T16:58:13.144Z,1520355493.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:13.146Z,1520355493.146 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:13.218Z,1520355493.218 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2018-03-06T16:58:13.218Z,1520355493.218 [BuoyancyServo] No Fault, FailCount= 1 2018-03-06T16:58:13.553Z,1520355493.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:13.561Z,1520355493.561 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:13.583Z,1520355493.583 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2018-03-06T16:58:13.583Z,1520355493.583 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2018-03-06T16:58:13.797Z,1520355493.797 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-06T16:58:13.931Z,1520355493.931 [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-06T16:58:13.933Z,1520355493.933 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd 2018-03-06T16:58:13.969Z,1520355493.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:13.971Z,1520355493.971 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:14.317Z,1520355494.317 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-03-06T16:58:14.317Z,1520355494.317 [CTD_Seabird](INFO): Powering down 2018-03-06T16:58:14.373Z,1520355494.373 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:14.389Z,1520355494.389 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:14.742Z,1520355494.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:14.743Z,1520355494.743 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:15.142Z,1520355495.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:15.143Z,1520355495.143 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:15.557Z,1520355495.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:15.565Z,1520355495.565 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:15.940Z,1520355495.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:15.949Z,1520355495.949 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:16.290Z,1520355496.290 [AcousticModem_Benthos_ATM900](DEBUG): 2018-03-06T16:58:16.329Z,1520355496.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:16.330Z,1520355496.330 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:16.711Z,1520355496.711 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-06T16:58:16.711Z,1520355496.711 [PNI_TCM] Data Fault, FailCount= 1 2018-03-06T16:58:16.712Z,1520355496.712 [PNI_TCM](ERROR): Data Fault 2018-03-06T16:58:16.725Z,1520355496.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:16.726Z,1520355496.726 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:16.826Z,1520355496.826 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-06T16:58:17.111Z,1520355497.111 [PNI_TCM](INFO): Powering down 2018-03-06T16:58:17.232Z,1520355497.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:17.241Z,1520355497.241 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:17.548Z,1520355497.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:17.558Z,1520355497.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:17.889Z,1520355497.889 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire 2018-03-06T16:58:17.890Z,1520355497.890 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band 2018-03-06T16:58:17.891Z,1520355497.891 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3 2018-03-06T16:58:17.891Z,1520355497.891 [AcousticModem_Benthos_ATM900](DEBUG): Mar 11 2000 18:55:36 2018-03-06T16:58:17.941Z,1520355497.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:17.949Z,1520355497.949 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:18.289Z,1520355498.289 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low 2018-03-06T16:58:18.321Z,1520355498.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:18.322Z,1520355498.322 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:18.370Z,1520355498.370 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-06T16:58:18.370Z,1520355498.370 [PNI_TCM] No Fault, FailCount= 1 2018-03-06T16:58:18.745Z,1520355498.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:18.746Z,1520355498.746 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:19.088Z,1520355499.088 [AcousticModem_Benthos_ATM900](INFO): entering command mode 2018-03-06T16:58:19.137Z,1520355499.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:19.138Z,1520355499.138 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:19.488Z,1520355499.488 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment 2018-03-06T16:58:19.489Z,1520355499.489 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1> 2018-03-06T16:58:19.489Z,1520355499.489 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged 2018-03-06T16:58:19.534Z,1520355499.534 [Aanderaa_O2](FAULT): Timed out starting 2018-03-06T16:58:19.534Z,1520355499.534 [Aanderaa_O2] Communications Fault, FailCount= 1 2018-03-06T16:58:19.534Z,1520355499.534 [Aanderaa_O2](ERROR): Communications Fault 2018-03-06T16:58:19.577Z,1520355499.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:19.578Z,1520355499.578 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:19.638Z,1520355499.638 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-03-06T16:58:19.888Z,1520355499.888 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3 2018-03-06T16:58:19.919Z,1520355499.919 [Aanderaa_O2](INFO): Powering down 2018-03-06T16:58:20.010Z,1520355500.010 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:20.011Z,1520355500.011 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:20.288Z,1520355500.288 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment 2018-03-06T16:58:20.289Z,1520355500.289 [AcousticModem_Benthos_ATM900](INFO): set local address to 3 2018-03-06T16:58:20.365Z,1520355500.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:20.366Z,1520355500.366 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:20.689Z,1520355500.689 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2> 2018-03-06T16:58:20.749Z,1520355500.749 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:20.750Z,1520355500.750 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:20.806Z,1520355500.806 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-03-06T16:58:20.806Z,1520355500.806 [Aanderaa_O2] No Fault, FailCount= 1 2018-03-06T16:58:21.145Z,1520355501.145 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:21.146Z,1520355501.146 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:21.581Z,1520355501.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:21.582Z,1520355501.582 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:21.941Z,1520355501.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:21.942Z,1520355501.942 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:22.333Z,1520355502.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:22.334Z,1520355502.334 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:22.765Z,1520355502.765 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:22.766Z,1520355502.766 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:23.161Z,1520355503.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:23.162Z,1520355503.162 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:23.557Z,1520355503.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:23.558Z,1520355503.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:23.949Z,1520355503.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:23.950Z,1520355503.950 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:24.353Z,1520355504.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:24.354Z,1520355504.354 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:24.737Z,1520355504.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:24.738Z,1520355504.738 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:25.160Z,1520355505.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:25.161Z,1520355505.161 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:25.553Z,1520355505.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:25.554Z,1520355505.554 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:25.909Z,1520355505.909 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-06T16:58:25.909Z,1520355505.909 [PNI_TCM] Data Fault, FailCount= 2 2018-03-06T16:58:25.909Z,1520355505.909 [PNI_TCM](ERROR): Data Fault 2018-03-06T16:58:25.936Z,1520355505.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:25.945Z,1520355505.945 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:25.990Z,1520355505.990 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-06T16:58:26.311Z,1520355506.311 [PNI_TCM](INFO): Powering down 2018-03-06T16:58:26.363Z,1520355506.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:26.364Z,1520355506.364 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:26.778Z,1520355506.778 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:26.779Z,1520355506.779 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:27.169Z,1520355507.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:27.177Z,1520355507.177 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:27.544Z,1520355507.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:27.553Z,1520355507.553 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:27.630Z,1520355507.630 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-06T16:58:27.630Z,1520355507.630 [PNI_TCM] No Fault, FailCount= 2 2018-03-06T16:58:27.957Z,1520355507.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:27.958Z,1520355507.958 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:28.344Z,1520355508.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:28.353Z,1520355508.353 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:28.764Z,1520355508.764 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:28.777Z,1520355508.777 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:29.131Z,1520355509.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:29.132Z,1520355509.132 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:29.548Z,1520355509.548 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:29.558Z,1520355509.558 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:29.960Z,1520355509.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:29.965Z,1520355509.965 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:30.340Z,1520355510.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:30.349Z,1520355510.349 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:30.745Z,1520355510.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:30.746Z,1520355510.746 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:31.136Z,1520355511.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:31.145Z,1520355511.145 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:31.511Z,1520355511.511 [Aanderaa_O2](FAULT): Timed out starting 2018-03-06T16:58:31.511Z,1520355511.511 [Aanderaa_O2] Communications Fault, FailCount= 2 2018-03-06T16:58:31.512Z,1520355511.512 [Aanderaa_O2](ERROR): Communications Fault 2018-03-06T16:58:31.552Z,1520355511.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:31.561Z,1520355511.561 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:31.619Z,1520355511.619 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2018-03-06T16:58:31.919Z,1520355511.919 [Aanderaa_O2](INFO): Powering down 2018-03-06T16:58:31.945Z,1520355511.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:31.946Z,1520355511.946 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:32.348Z,1520355512.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:32.349Z,1520355512.349 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:32.740Z,1520355512.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:32.750Z,1520355512.750 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:32.806Z,1520355512.806 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2018-03-06T16:58:32.806Z,1520355512.806 [Aanderaa_O2] No Fault, FailCount= 2 2018-03-06T16:58:33.140Z,1520355513.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:33.154Z,1520355513.154 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:33.539Z,1520355513.539 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:33.540Z,1520355513.540 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:33.945Z,1520355513.945 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:33.946Z,1520355513.946 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:34.337Z,1520355514.337 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:34.338Z,1520355514.338 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:34.694Z,1520355514.694 [NAL9602](INFO): Powering up NAL9602 2018-03-06T16:58:34.747Z,1520355514.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:34.748Z,1520355514.748 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:35.110Z,1520355515.110 [PNI_TCM](FAULT): Failed to set parameters during initialization. 2018-03-06T16:58:35.110Z,1520355515.110 [PNI_TCM] Data Fault, FailCount= 3 2018-03-06T16:58:35.110Z,1520355515.110 [PNI_TCM](ERROR): Data Fault 2018-03-06T16:58:35.156Z,1520355515.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:35.162Z,1520355515.162 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:35.223Z,1520355515.223 [CBIT](ERROR): Data Fault in component: PNI_TCM 2018-03-06T16:58:35.510Z,1520355515.510 [PNI_TCM](INFO): Powering down 2018-03-06T16:58:35.544Z,1520355515.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:35.545Z,1520355515.545 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:35.948Z,1520355515.948 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:35.949Z,1520355515.949 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:36.337Z,1520355516.337 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:36.338Z,1520355516.338 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:36.745Z,1520355516.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:36.746Z,1520355516.746 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:36.794Z,1520355516.794 [CBIT](INFO): Clearing failed state for component PNI_TCM 2018-03-06T16:58:36.794Z,1520355516.794 [PNI_TCM] No Fault, FailCount= 3 2018-03-06T16:58:37.137Z,1520355517.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:37.138Z,1520355517.138 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:37.537Z,1520355517.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:37.538Z,1520355517.538 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:37.950Z,1520355517.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:37.951Z,1520355517.951 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:38.341Z,1520355518.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:38.342Z,1520355518.342 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:38.793Z,1520355518.793 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:38.794Z,1520355518.794 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:39.169Z,1520355519.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:39.170Z,1520355519.170 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:39.568Z,1520355519.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:39.569Z,1520355519.569 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:39.929Z,1520355519.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:39.930Z,1520355519.930 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:40.359Z,1520355520.359 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:40.360Z,1520355520.360 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:40.758Z,1520355520.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:40.759Z,1520355520.759 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:41.137Z,1520355521.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2018-03-06T16:58:41.138Z,1520355521.138 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have