2017-09-12T18:00:13.762Z,1505239213.762 [Supervisor](DEBUG): Initializing supervisor. 2017-09-12T18:00:13.765Z,1505239213.765 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0 2017-09-12T18:00:13.766Z,1505239213.766 [SyncHandler](INFO): Protected caller Thread ID is 4485 2017-09-12T18:00:13.766Z,1505239213.766 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2017-09-12T18:00:13.767Z,1505239213.767 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0 2017-09-12T18:00:13.768Z,1505239213.768 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4486 2017-09-12T18:00:13.771Z,1505239213.771 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2017-09-12T18:00:13.786Z,1505239213.786 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2017-09-12T18:00:13.787Z,1505239213.787 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0 2017-09-12T18:00:13.787Z,1505239213.787 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4487 2017-09-12T18:00:13.788Z,1505239213.788 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2017-09-12T18:00:13.789Z,1505239213.789 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0 2017-09-12T18:00:13.790Z,1505239213.790 [logger ThreadHandler](INFO): Protected caller Thread ID is 4488 2017-09-12T18:00:13.792Z,1505239213.792 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2017-09-12T18:00:13.793Z,1505239213.793 [Supervisor](INFO): Looking for Config files in directory: Config/ 2017-09-12T18:00:13.794Z,1505239213.794 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2017-09-12T18:00:14.001Z,1505239214.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2017-09-12T18:00:14.001Z,1505239214.001 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2017-09-12T18:00:14.143Z,1505239214.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2017-09-12T18:00:14.144Z,1505239214.144 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2017-09-12T18:00:14.538Z,1505239214.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2017-09-12T18:00:14.539Z,1505239214.539 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2017-09-12T18:00:14.693Z,1505239214.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2017-09-12T18:00:14.693Z,1505239214.693 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2017-09-12T18:00:14.778Z,1505239214.778 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2017-09-12T18:00:15.095Z,1505239215.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2017-09-12T18:00:15.096Z,1505239215.096 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2017-09-12T18:00:15.205Z,1505239215.205 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2017-09-12T18:00:15.206Z,1505239215.206 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2017-09-12T18:00:15.543Z,1505239215.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2017-09-12T18:00:15.543Z,1505239215.543 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2017-09-12T18:00:15.973Z,1505239215.973 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2017-09-12T18:00:15.974Z,1505239215.974 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2017-09-12T18:00:16.340Z,1505239216.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2017-09-12T18:00:16.341Z,1505239216.341 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2017-09-12T18:00:16.963Z,1505239216.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2017-09-12T18:00:16.964Z,1505239216.964 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2017-09-12T18:00:17.180Z,1505239217.180 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2017-09-12T18:00:17.181Z,1505239217.181 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2017-09-12T18:00:17.288Z,1505239217.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2017-09-12T18:00:17.289Z,1505239217.289 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2017-09-12T18:00:17.848Z,1505239217.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2017-09-12T18:00:17.849Z,1505239217.849 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2017-09-12T18:00:17.966Z,1505239217.966 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2017-09-12T18:00:17.968Z,1505239217.968 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2017-09-12T18:00:17.969Z,1505239217.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2017-09-12T18:00:18.270Z,1505239218.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2017-09-12T18:00:18.271Z,1505239218.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2017-09-12T18:00:18.380Z,1505239218.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2017-09-12T18:00:18.486Z,1505239218.486 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2017-09-12T18:00:18.597Z,1505239218.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2017-09-12T18:00:18.688Z,1505239218.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2017-09-12T18:00:18.828Z,1505239218.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2017-09-12T18:00:19.000Z,1505239219.000 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2017-09-12T18:00:19.103Z,1505239219.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2017-09-12T18:00:19.200Z,1505239219.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2017-09-12T18:00:19.289Z,1505239219.289 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2017-09-12T18:00:19.491Z,1505239219.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2017-09-12T18:00:19.572Z,1505239219.572 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2017-09-12T18:00:19.572Z,1505239219.572 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2017-09-12T18:00:19.580Z,1505239219.580 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2017-09-12T18:00:19.705Z,1505239219.705 [SBIT](DEBUG): Construct Startup Built In Test. 2017-09-12T18:00:19.769Z,1505239219.769 [SBIT] Loaded 2017-09-12T18:00:19.781Z,1505239219.781 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2017-09-12T18:00:19.782Z,1505239219.782 [IBIT](DEBUG): Construct Initiated Built In Test. 2017-09-12T18:00:19.878Z,1505239219.878 [IBIT] Loaded 2017-09-12T18:00:19.878Z,1505239219.878 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2017-09-12T18:00:19.894Z,1505239219.894 [CBIT](DEBUG): Construct Continuous Built In Test. 2017-09-12T18:00:20.155Z,1505239220.155 [CBIT] Loaded 2017-09-12T18:00:20.156Z,1505239220.156 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2017-09-12T18:00:20.156Z,1505239220.156 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2017-09-12T18:00:20.157Z,1505239220.157 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2017-09-12T18:00:20.224Z,1505239220.224 [VerticalControl](DEBUG): Construct VerticalControl. 2017-09-12T18:00:20.344Z,1505239220.344 [VerticalControl] Loaded 2017-09-12T18:00:20.344Z,1505239220.344 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2017-09-12T18:00:20.345Z,1505239220.345 [HorizontalControl](DEBUG): Construct HorizontalControl. 2017-09-12T18:00:20.423Z,1505239220.423 [HorizontalControl] Loaded 2017-09-12T18:00:20.423Z,1505239220.423 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2017-09-12T18:00:20.424Z,1505239220.424 [SpeedControl](DEBUG): Construct SpeedControl. 2017-09-12T18:00:20.431Z,1505239220.431 [SpeedControl] Loaded 2017-09-12T18:00:20.431Z,1505239220.431 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2017-09-12T18:00:20.432Z,1505239220.432 [LoopControl](DEBUG): Construct LoopControl. 2017-09-12T18:00:20.433Z,1505239220.433 [LoopControl] Loaded 2017-09-12T18:00:20.433Z,1505239220.433 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2017-09-12T18:00:20.433Z,1505239220.433 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2017-09-12T18:00:20.434Z,1505239220.434 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2017-09-12T18:00:20.460Z,1505239220.460 [DepthRateCalculator] Loaded 2017-09-12T18:00:20.460Z,1505239220.460 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2017-09-12T18:00:20.467Z,1505239220.467 [PitchRateCalculator] Loaded 2017-09-12T18:00:20.467Z,1505239220.467 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2017-09-12T18:00:20.486Z,1505239220.486 [SpeedCalculator] Loaded 2017-09-12T18:00:20.486Z,1505239220.486 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2017-09-12T18:00:20.512Z,1505239220.512 [TempGradientCalculator] Loaded 2017-09-12T18:00:20.512Z,1505239220.512 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2017-09-12T18:00:20.531Z,1505239220.531 [VerticalTemperatureHomogeneityIndexCalculator] Loaded 2017-09-12T18:00:20.531Z,1505239220.531 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread. 2017-09-12T18:00:20.537Z,1505239220.537 [YawRateCalculator] Loaded 2017-09-12T18:00:20.538Z,1505239220.538 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2017-09-12T18:00:20.538Z,1505239220.538 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2017-09-12T18:00:20.539Z,1505239220.539 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2017-09-12T18:00:20.625Z,1505239220.625 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC 2017-09-12T18:00:20.625Z,1505239220.625 [StratificationFrontDetector](DEBUG): (re)initializing 2017-09-12T18:00:20.625Z,1505239220.625 [StratificationFrontDetector] Loaded 2017-09-12T18:00:20.625Z,1505239220.625 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread. 2017-09-12T18:00:20.626Z,1505239220.626 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2017-09-12T18:00:20.627Z,1505239220.627 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2017-09-12T18:00:20.865Z,1505239220.865 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2017-09-12T18:00:20.866Z,1505239220.866 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2017-09-12T18:00:20.979Z,1505239220.979 [DeadReckonUsingMultipleVelocitySources] Loaded 2017-09-12T18:00:20.979Z,1505239220.979 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2017-09-12T18:00:21.047Z,1505239221.047 [DeadReckonUsingSpeedCalculator] Loaded 2017-09-12T18:00:21.048Z,1505239221.048 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2017-09-12T18:00:21.068Z,1505239221.068 [NavChart] Loaded 2017-09-12T18:00:21.069Z,1505239221.069 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2017-09-12T18:00:21.074Z,1505239221.074 [UniversalFixResidualReporter] Loaded 2017-09-12T18:00:21.075Z,1505239221.075 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2017-09-12T18:00:21.075Z,1505239221.075 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2017-09-12T18:00:21.076Z,1505239221.076 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2017-09-12T18:00:21.089Z,1505239221.089 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2017-09-12T18:00:21.090Z,1505239221.090 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2017-09-12T18:00:21.240Z,1505239221.240 [Aanderaa_O2] Loaded 2017-09-12T18:00:21.240Z,1505239221.240 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2017-09-12T18:00:21.346Z,1505239221.346 [CTD_NeilBrown] Loaded 2017-09-12T18:00:21.347Z,1505239221.347 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread. 2017-09-12T18:00:21.348Z,1505239221.348 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408134E0 2017-09-12T18:00:21.348Z,1505239221.348 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 4571 2017-09-12T18:00:21.367Z,1505239221.367 [PAR_Licor] Loaded 2017-09-12T18:00:21.367Z,1505239221.367 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2017-09-12T18:00:21.426Z,1505239221.426 [WetLabsBB2FL] Loaded 2017-09-12T18:00:21.426Z,1505239221.426 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2017-09-12T18:00:21.427Z,1505239221.427 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408434E0 2017-09-12T18:00:21.428Z,1505239221.428 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4572 2017-09-12T18:00:21.428Z,1505239221.428 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2017-09-12T18:00:21.429Z,1505239221.429 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2017-09-12T18:00:21.707Z,1505239221.707 [DataOverHttps] Loaded 2017-09-12T18:00:21.708Z,1505239221.708 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread. 2017-09-12T18:00:21.725Z,1505239221.725 [Depth_Keller] Loaded 2017-09-12T18:00:21.725Z,1505239221.725 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2017-09-12T18:00:21.731Z,1505239221.731 [DropWeight] Loaded 2017-09-12T18:00:21.732Z,1505239221.732 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2017-09-12T18:00:21.844Z,1505239221.844 [NAL9602] Loaded 2017-09-12T18:00:21.844Z,1505239221.844 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2017-09-12T18:00:21.855Z,1505239221.855 [Onboard] Loaded 2017-09-12T18:00:21.856Z,1505239221.856 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2017-09-12T18:00:21.865Z,1505239221.865 [Radio_Surface] Loaded 2017-09-12T18:00:21.865Z,1505239221.865 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2017-09-12T18:00:21.866Z,1505239221.866 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AC74E0 2017-09-12T18:00:21.867Z,1505239221.867 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4573 2017-09-12T18:00:22.171Z,1505239222.171 [DAT] Loaded 2017-09-12T18:00:22.172Z,1505239222.172 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2017-09-12T18:00:22.230Z,1505239222.230 [PNI_TCM] Loaded 2017-09-12T18:00:22.231Z,1505239222.231 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 2017-09-12T18:00:24.968Z,1505239224.968 [BPC1] Loaded 2017-09-12T18:00:24.968Z,1505239224.968 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2017-09-12T18:00:24.968Z,1505239224.968 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2017-09-12T18:00:24.969Z,1505239224.969 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2017-09-12T18:00:25.071Z,1505239225.071 [BuoyancyServo] Loaded 2017-09-12T18:00:25.072Z,1505239225.072 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2017-09-12T18:00:25.087Z,1505239225.087 [ElevatorServo] Loaded 2017-09-12T18:00:25.087Z,1505239225.087 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2017-09-12T18:00:25.102Z,1505239225.102 [MassServo] Loaded 2017-09-12T18:00:25.102Z,1505239225.102 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2017-09-12T18:00:25.117Z,1505239225.117 [RudderServo] Loaded 2017-09-12T18:00:25.117Z,1505239225.117 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2017-09-12T18:00:25.132Z,1505239225.132 [ThrusterServo] Loaded 2017-09-12T18:00:25.132Z,1505239225.132 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2017-09-12T18:00:25.132Z,1505239225.132 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2017-09-12T18:00:25.133Z,1505239225.133 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2017-09-12T18:00:25.229Z,1505239225.229 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2017-09-12T18:00:25.230Z,1505239225.230 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2017-09-12T18:00:25.255Z,1505239225.255 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2017-09-12T18:00:25.260Z,1505239225.260 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2017-09-12T18:00:25.261Z,1505239225.261 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2017-09-12T18:00:25.272Z,1505239225.272 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2017-09-12T18:00:25.273Z,1505239225.273 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B9B4E0 2017-09-12T18:00:25.273Z,1505239225.273 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4574 2017-09-12T18:00:25.280Z,1505239225.280 [Supervisor](INFO): Main Thread ID is 4484 2017-09-12T18:00:25.280Z,1505239225.280 [Supervisor](DEBUG): Running supervisor. 2017-09-12T18:00:25.281Z,1505239225.281 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4575 2017-09-12T18:00:25.284Z,1505239225.284 [controlThread ThreadHandler](INFO): Handler Thread ID is 4576 2017-09-12T18:00:25.285Z,1505239225.285 [controlThread](DEBUG): Initializing ControlThread 2017-09-12T18:00:25.286Z,1505239225.286 [SBIT](INFO): Initialize SBIT Component. 2017-09-12T18:00:25.286Z,1505239225.286 [SBIT](IMPORTANT): git: 2017-08-02-103-g54acef0 2017-09-12T18:00:25.286Z,1505239225.286 [SBIT](INFO): git hash: 54acef0e8867e4ebce852e89742b80c54cea63d9 2017-09-12T18:00:25.289Z,1505239225.289 [SBIT](INFO): Kernel Reporting Different Release From Configuration. Kernel Expected: 2.6.27.8 Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty 2017-09-12T18:00:25.289Z,1505239225.289 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016 2017-09-12T18:00:25.290Z,1505239225.290 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2017-09-12T18:00:25.291Z,1505239225.291 [IBIT](INFO): Initialize IBIT Component. 2017-09-12T18:00:25.292Z,1505239225.292 [CBIT](DEBUG): Initialize CBIT Component. 2017-09-12T18:00:25.292Z,1505239225.292 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2017-09-12T18:00:25.293Z,1505239225.293 [logger ThreadHandler](INFO): Handler Thread ID is 4577 2017-09-12T18:00:25.319Z,1505239225.319 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 4578 2017-09-12T18:00:25.320Z,1505239225.320 [CTD_NeilBrown](INFO): Powering down 2017-09-12T18:00:25.343Z,1505239225.343 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4579 2017-09-12T18:00:25.345Z,1505239225.345 [WetLabsBB2FL](INFO): Powering down 2017-09-12T18:00:25.375Z,1505239225.375 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2017-09-12T18:00:25.377Z,1505239225.377 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2017-09-12T18:00:25.377Z,1505239225.377 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2017-09-12T18:00:25.378Z,1505239225.378 [LoopControl](DEBUG): Initialize LoopControlComponent. 2017-09-12T18:00:25.378Z,1505239225.378 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2017-09-12T18:00:25.379Z,1505239225.379 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2017-09-12T18:00:25.379Z,1505239225.379 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2017-09-12T18:00:25.380Z,1505239225.380 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2017-09-12T18:00:25.380Z,1505239225.380 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing 2017-09-12T18:00:25.381Z,1505239225.381 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2017-09-12T18:00:25.382Z,1505239225.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-09-12T18:00:25.383Z,1505239225.383 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-09-12T18:00:25.384Z,1505239225.384 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-09-12T18:00:25.384Z,1505239225.384 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-09-12T18:00:25.385Z,1505239225.385 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-09-12T18:00:25.385Z,1505239225.385 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-09-12T18:00:25.385Z,1505239225.385 [NavChart](DEBUG): Initialize NavChart Navigation. 2017-09-12T18:00:25.386Z,1505239225.386 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2017-09-12T18:00:25.407Z,1505239225.407 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4580 2017-09-12T18:00:25.409Z,1505239225.409 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2017-09-12T18:00:25.422Z,1505239225.422 [Radio_Surface](INFO): Powering up 2017-09-12T18:00:25.439Z,1505239225.439 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4581 2017-09-12T18:00:25.443Z,1505239225.443 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2017-09-12T18:00:25.443Z,1505239225.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2017-09-12T18:00:25.443Z,1505239225.443 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2017-09-12T18:00:25.443Z,1505239225.443 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2017-09-12T18:00:25.444Z,1505239225.444 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2017-09-12T18:00:25.444Z,1505239225.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2017-09-12T18:00:25.444Z,1505239225.444 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2017-09-12T18:00:25.444Z,1505239225.444 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2017-09-12T18:00:25.445Z,1505239225.445 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2017-09-12T18:00:25.445Z,1505239225.445 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2017-09-12T18:00:25.445Z,1505239225.445 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2017-09-12T18:00:25.445Z,1505239225.445 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2017-09-12T18:00:25.445Z,1505239225.445 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2017-09-12T18:00:25.446Z,1505239225.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2017-09-12T18:00:25.446Z,1505239225.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2017-09-12T18:00:25.446Z,1505239225.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2017-09-12T18:00:25.480Z,1505239225.480 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-12T18:00:25.517Z,1505239225.517 [MissionManager](DEBUG): 2017-09-12T18:00:25.518Z,1505239225.518 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2017-09-12T18:00:25.589Z,1505239225.589 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2017-09-12T18:00:25.590Z,1505239225.590 [Default:A.Wait](DEBUG): Construct Wait. 2017-09-12T18:00:25.608Z,1505239225.608 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2017-09-12T18:00:25.641Z,1505239225.641 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2017-09-12T18:00:25.660Z,1505239225.660 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2017-09-12T18:00:25.684Z,1505239225.684 [Default:E.Execute](DEBUG): Construct Execute. 2017-09-12T18:00:25.696Z,1505239225.696 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn 300 Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2017-09-12T18:00:25.702Z,1505239225.702 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-09-12T18:00:25.932Z,1505239225.932 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2017-09-12T18:00:25.968Z,1505239225.968 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar 2017-09-12T18:00:26.094Z,1505239226.094 [DAT](INFO): Powering up 2017-09-12T18:00:26.094Z,1505239226.094 [DAT](DEBUG): Initializing DAT. 2017-09-12T18:00:26.296Z,1505239226.296 [DepthRateCalculator](ERROR): Depth measurement is not active 2017-09-12T18:00:26.411Z,1505239226.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:26.448Z,1505239226.448 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:26.605Z,1505239226.605 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2017-09-12T18:00:26.613Z,1505239226.613 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2017-09-12T18:00:26.645Z,1505239226.645 [ElevatorServo](DEBUG): Initializing EZServoServo. 2017-09-12T18:00:26.657Z,1505239226.657 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2017-09-12T18:00:26.664Z,1505239226.664 [MassServo](DEBUG): Initializing EZServoServo. 2017-09-12T18:00:26.665Z,1505239226.665 [MassServo](DEBUG): Initializing MassServo. 2017-09-12T18:00:26.688Z,1505239226.688 [RudderServo](DEBUG): Initializing EZServoServo. 2017-09-12T18:00:26.693Z,1505239226.693 [RudderServo](DEBUG): Initializing RudderServo. 2017-09-12T18:00:26.719Z,1505239226.719 [ThrusterServo](DEBUG): Initializing EZServoServo. 2017-09-12T18:00:26.725Z,1505239226.725 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2017-09-12T18:00:27.173Z,1505239227.173 [NAL9602](INFO): Powering up NAL9602 2017-09-12T18:00:27.257Z,1505239227.257 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:27.265Z,1505239227.265 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:36.811Z,1505239236.811 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:36.812Z,1505239236.812 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:36.987Z,1505239236.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:37.005Z,1505239237.005 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:37.361Z,1505239237.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:37.371Z,1505239237.371 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:37.798Z,1505239237.798 [Aanderaa_O2](INFO): Powering down 2017-09-12T18:00:37.888Z,1505239237.888 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003807 2017-09-12T18:00:38.011Z,1505239238.011 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:38.013Z,1505239238.013 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:38.239Z,1505239238.239 [NAL9602](INFO): NAL9602 initialized 2017-09-12T18:00:38.311Z,1505239238.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:00:38.313Z,1505239238.313 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet. 2017-09-12T18:01:16.411Z,1505239276.411 [DAT](DEBUG): Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:54:22 Warning battery low CONNECT 2017-09-12T18:01:16.411Z,1505239276.411 [DAT](INFO): Got CONNECT 2017-09-12T18:01:16.411Z,1505239276.411 [DAT](INFO): entering command mode 2017-09-12T18:01:16.794Z,1505239276.794 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:17.207Z,1505239277.207 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:17.596Z,1505239277.596 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:17.996Z,1505239277.996 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:18.420Z,1505239278.420 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:18.798Z,1505239278.798 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:19.198Z,1505239279.198 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:19.615Z,1505239279.615 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:20.008Z,1505239280.008 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:20.396Z,1505239280.396 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:20.810Z,1505239280.810 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:21.197Z,1505239281.197 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:21.594Z,1505239281.594 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:22.011Z,1505239282.011 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:22.422Z,1505239282.422 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:22.820Z,1505239282.820 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:23.196Z,1505239283.196 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:23.597Z,1505239283.597 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:24.007Z,1505239284.007 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:24.412Z,1505239284.412 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:24.809Z,1505239284.809 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:25.211Z,1505239285.211 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:25.619Z,1505239285.619 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:26.003Z,1505239286.003 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:26.402Z,1505239286.402 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:32.658Z,1505239292.658 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:32.894Z,1505239292.894 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:33.267Z,1505239293.267 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:33.676Z,1505239293.676 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:34.069Z,1505239294.069 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:34.470Z,1505239294.470 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:34.865Z,1505239294.865 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:35.260Z,1505239295.260 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:35.656Z,1505239295.656 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:36.080Z,1505239296.080 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:01:36.458Z,1505239296.458 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:54:22 Warning battery low CONNECT, available: 02400 bits/sec 1 of 4 user:1>œQ@ 2017-09-12T18:01:36.458Z,1505239296.458 [DAT] Communications Fault, FailCount= 1 2017-09-12T18:01:36.458Z,1505239296.458 [DAT](ERROR): Communications Fault 2017-09-12T18:01:36.585Z,1505239296.585 [CBIT](ERROR): Communications Fault in component: DAT 2017-09-12T18:01:36.889Z,1505239296.889 [DAT](INFO): Powering down 2017-09-12T18:01:37.198Z,1505239297.198 [SBIT](IMPORTANT): Beginning Startup BIT 2017-09-12T18:01:37.203Z,1505239297.203 [CBIT](IMPORTANT): Beginning ground fault scan 2017-09-12T18:01:37.802Z,1505239297.802 [CBIT](INFO): Clearing failed state for component DAT 2017-09-12T18:01:37.802Z,1505239297.802 [DAT] No Fault, FailCount= 1 2017-09-12T18:01:40.227Z,1505239300.227 [DAT](INFO): Powering up 2017-09-12T18:01:40.228Z,1505239300.228 [DAT](DEBUG): Initializing DAT. 2017-09-12T18:01:47.587Z,1505239307.587 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 4.657929 CHAN A1 (24V): 0.027691 CHAN A2 (12V): 4.697929 CHAN A3 (5V): -0.011630 CHAN B0 (3.3V): 4.766929 CHAN B1 (3.15aV): 0.002568 CHAN B2 (3.15bV): 4.767929 CHAN B3 (GND): 0.027562 OPEN: -0.000379 Full Scale Calc: 4.765 mA, -1.589 mA 2017-09-12T18:02:29.980Z,1505239349.980 [DAT](DEBUG): Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:55:36 Warning battery low CONNECT 2017-09-12T18:02:29.981Z,1505239349.981 [DAT](INFO): Got CONNECT 2017-09-12T18:02:29.981Z,1505239349.981 [DAT](INFO): entering command mode 2017-09-12T18:02:30.173Z,1505239350.173 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:30.568Z,1505239350.568 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:30.651Z,1505239350.651 [SBIT](IMPORTANT): SBIT PASSED 2017-09-12T18:02:30.968Z,1505239350.968 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:31.008Z,1505239351.008 [MissionManager](IMPORTANT): Started mission Startup 2017-09-12T18:02:31.009Z,1505239351.009 [Startup] Running Loop=1 2017-09-12T18:02:31.009Z,1505239351.009 [Startup](DEBUG): Aggregate::initialize Startup 2017-09-12T18:02:31.009Z,1505239351.009 [Startup:A.GoToSurface] Running Loop=1 2017-09-12T18:02:31.009Z,1505239351.009 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-09-12T18:02:31.010Z,1505239351.010 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-09-12T18:02:31.010Z,1505239351.010 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-09-12T18:02:31.011Z,1505239351.011 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-09-12T18:02:31.011Z,1505239351.011 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-09-12T18:02:31.012Z,1505239351.012 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-09-12T18:02:31.019Z,1505239351.019 [Startup:StartupSatComms] Running Loop=1 2017-09-12T18:02:31.019Z,1505239351.019 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2017-09-12T18:02:31.019Z,1505239351.019 [Startup:StartupSatComms:A] Running Loop=1 2017-09-12T18:02:31.364Z,1505239351.364 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:31.422Z,1505239351.422 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2017-09-12T18:02:31.769Z,1505239351.769 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:32.167Z,1505239352.167 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:32.566Z,1505239352.566 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:32.967Z,1505239352.967 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:33.364Z,1505239353.364 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:33.777Z,1505239353.777 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:34.172Z,1505239354.172 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:34.567Z,1505239354.567 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:34.963Z,1505239354.963 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:35.360Z,1505239355.360 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:35.761Z,1505239355.761 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:36.159Z,1505239356.159 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:36.569Z,1505239356.569 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:36.962Z,1505239356.962 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:37.371Z,1505239357.371 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:37.781Z,1505239357.781 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:38.173Z,1505239358.173 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:38.560Z,1505239358.560 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:38.960Z,1505239358.960 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:39.370Z,1505239359.370 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:39.766Z,1505239359.766 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:40.165Z,1505239360.165 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:40.570Z,1505239360.570 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:40.971Z,1505239360.971 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:41.372Z,1505239361.372 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:41.775Z,1505239361.775 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:42.182Z,1505239362.182 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:42.575Z,1505239362.575 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:42.974Z,1505239362.974 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:43.379Z,1505239363.379 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:43.760Z,1505239363.760 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:44.160Z,1505239364.160 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:44.569Z,1505239364.569 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:44.966Z,1505239364.966 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:45.365Z,1505239365.365 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:45.770Z,1505239365.770 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:46.172Z,1505239366.172 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:46.568Z,1505239366.568 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:46.973Z,1505239366.973 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:47.358Z,1505239367.358 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:47.762Z,1505239367.762 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:48.167Z,1505239368.167 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:48.563Z,1505239368.563 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:48.958Z,1505239368.958 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:49.363Z,1505239369.363 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:49.765Z,1505239369.765 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:50.167Z,1505239370.167 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:02:50.570Z,1505239370.570 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:55:36 Warning battery low CONNECT, available: 02400 bits/sec 1 of 4 user:1>œQ@ 2017-09-12T18:02:50.570Z,1505239370.570 [DAT] Communications Fault, FailCount= 2 2017-09-12T18:02:50.570Z,1505239370.570 [DAT](ERROR): Communications Fault 2017-09-12T18:02:50.647Z,1505239370.647 [CBIT](ERROR): Communications Fault in component: DAT 2017-09-12T18:02:50.959Z,1505239370.959 [DAT](INFO): Powering down 2017-09-12T18:02:51.814Z,1505239371.814 [CBIT](INFO): Clearing failed state for component DAT 2017-09-12T18:02:51.814Z,1505239371.814 [DAT] No Fault, FailCount= 2 2017-09-12T18:02:54.164Z,1505239374.164 [DAT](INFO): Powering up 2017-09-12T18:02:54.164Z,1505239374.164 [DAT](DEBUG): Initializing DAT. 2017-09-12T18:03:02.944Z,1505239382.944 [NAL9602](INFO): SBD MO Status=0, MOMSN=476, MT Status=0, MTMSN=0 2017-09-12T18:03:02.944Z,1505239382.944 [NAL9602](INFO): No messages in MT queue 2017-09-12T18:03:25.392Z,1505239405.392 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-09-12T18:03:25.392Z,1505239405.392 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2017-09-12T18:03:25.392Z,1505239405.392 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-09-12T18:03:25.396Z,1505239405.396 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-09-12T18:03:25.396Z,1505239405.396 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2017-09-12T18:03:25.396Z,1505239405.396 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-09-12T18:03:25.423Z,1505239405.423 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-09-12T18:03:25.430Z,1505239405.430 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-09-12T18:03:25.819Z,1505239405.819 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-09-12T18:03:25.820Z,1505239405.820 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2017-09-12T18:03:25.820Z,1505239405.820 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-09-12T18:03:25.820Z,1505239405.820 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2017-09-12T18:03:26.209Z,1505239406.209 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-09-12T18:03:26.209Z,1505239406.209 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-09-12T18:03:26.210Z,1505239406.210 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-09-12T18:03:26.210Z,1505239406.210 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-09-12T18:03:26.211Z,1505239406.211 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-09-12T18:03:26.211Z,1505239406.211 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-09-12T18:03:26.604Z,1505239406.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-09-12T18:03:27.002Z,1505239407.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-09-12T18:03:30.350Z,1505239410.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.0 s old, using for 20.0 s. 2017-09-12T18:03:30.500Z,1505239410.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s. 2017-09-12T18:03:30.891Z,1505239410.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s. 2017-09-12T18:03:31.253Z,1505239411.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s. 2017-09-12T18:03:31.265Z,1505239411.265 [Startup:StartupSatComms:A](INFO): Timed out from 2017-09-12T18:02:31.0Z 2017-09-12T18:03:31.265Z,1505239411.265 [Startup:StartupSatComms:A] Stopped 2017-09-12T18:03:31.265Z,1505239411.265 [Startup:StartupSatComms:B] Running Loop=1 2017-09-12T18:03:31.677Z,1505239411.677 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s. 2017-09-12T18:03:31.681Z,1505239411.681 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2017-09-12T18:03:32.328Z,1505239412.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s. 2017-09-12T18:03:32.460Z,1505239412.460 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s. 2017-09-12T18:03:35.754Z,1505239415.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2017-09-12T18:03:35.860Z,1505239415.860 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170912T173030/Courier0008.lzma 2017-09-12T18:03:35.989Z,1505239415.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s. 2017-09-12T18:03:36.308Z,1505239416.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s. 2017-09-12T18:03:36.607Z,1505239416.607 [DataOverHttps](INFO): Moved sent file to Logs/20170912T173030/Courier0008.lzma.bak 2017-09-12T18:03:36.607Z,1505239416.607 [DataOverHttps](INFO): SBD MOMSN=5086160 2017-09-12T18:03:36.678Z,1505239416.678 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s. 2017-09-12T18:03:37.279Z,1505239417.279 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.9 s old, using for 20.0 s. 2017-09-12T18:03:37.473Z,1505239417.473 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s. 2017-09-12T18:03:40.784Z,1505239420.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.4 s old, using for 20.0 s. 2017-09-12T18:03:40.896Z,1505239420.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s. 2017-09-12T18:03:41.292Z,1505239421.292 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s. 2017-09-12T18:03:41.689Z,1505239421.689 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s. 2017-09-12T18:03:42.074Z,1505239422.074 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s. 2017-09-12T18:03:42.488Z,1505239422.488 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s. 2017-09-12T18:03:45.777Z,1505239425.777 [DAT](DEBUG): Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:56:49 Warning battery low CONNECT 2017-09-12T18:03:45.777Z,1505239425.777 [DAT](INFO): Got CONNECT 2017-09-12T18:03:45.777Z,1505239425.777 [DAT](INFO): entering command mode 2017-09-12T18:03:45.972Z,1505239425.972 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170912T173945/Courier0000.lzma 2017-09-12T18:03:46.021Z,1505239426.021 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:46.397Z,1505239426.397 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:46.751Z,1505239426.751 [DataOverHttps](INFO): Moved sent file to Logs/20170912T173945/Courier0000.lzma.bak 2017-09-12T18:03:46.751Z,1505239426.751 [DataOverHttps](INFO): SBD MOMSN=5086162 2017-09-12T18:03:46.804Z,1505239426.804 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:47.515Z,1505239427.515 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:47.743Z,1505239427.743 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:50.985Z,1505239430.985 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:51.163Z,1505239431.163 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:51.529Z,1505239431.529 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:51.941Z,1505239431.941 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:52.327Z,1505239432.327 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:52.726Z,1505239432.726 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:55.990Z,1505239435.990 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:56.082Z,1505239436.082 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170912T173030/Express0009.lzma 2017-09-12T18:03:56.137Z,1505239436.137 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:56.518Z,1505239436.518 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:56.871Z,1505239436.871 [DataOverHttps](INFO): Moved sent file to Logs/20170912T173030/Express0009.lzma.bak 2017-09-12T18:03:56.871Z,1505239436.871 [DataOverHttps](INFO): SBD MOMSN=5086165 2017-09-12T18:03:56.938Z,1505239436.938 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:57.670Z,1505239437.670 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:03:57.806Z,1505239437.806 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:04:01.051Z,1505239441.051 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:04:01.190Z,1505239441.190 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:04:01.581Z,1505239441.581 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:04:01.986Z,1505239441.986 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:04:02.388Z,1505239442.388 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:04:02.771Z,1505239442.771 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:04:06.498Z,1505239446.498 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:56:49 Warning battery low CONNECT, available: 02400 bits/sec 1 of 4 user:1>œQ@ 2017-09-12T18:04:06.499Z,1505239446.499 [DAT] Communications Fault, FailCount= 3 2017-09-12T18:04:06.499Z,1505239446.499 [DAT](ERROR): Communications Fault 2017-09-12T18:04:06.662Z,1505239446.662 [CBIT](ERROR): Communications Fault in component: DAT 2017-09-12T18:04:06.723Z,1505239446.723 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170912T173945/Express0001.lzma 2017-09-12T18:04:06.746Z,1505239446.746 [DAT](INFO): Powering down 2017-09-12T18:04:07.488Z,1505239447.488 [DataOverHttps](INFO): Moved sent file to Logs/20170912T173945/Express0001.lzma.bak 2017-09-12T18:04:07.488Z,1505239447.488 [DataOverHttps](INFO): SBD MOMSN=5086167 2017-09-12T18:04:07.598Z,1505239447.598 [CBIT](INFO): Clearing failed state for component DAT 2017-09-12T18:04:07.599Z,1505239447.599 [DAT] No Fault, FailCount= 3 2017-09-12T18:04:11.713Z,1505239451.713 [DAT](INFO): Powering up 2017-09-12T18:04:11.713Z,1505239451.713 [DAT](DEBUG): Initializing DAT. 2017-09-12T18:04:16.821Z,1505239456.821 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170912T174700/Express0001.lzma 2017-09-12T18:04:17.596Z,1505239457.596 [DataOverHttps](INFO): Moved sent file to Logs/20170912T174700/Express0001.lzma.bak 2017-09-12T18:04:17.596Z,1505239457.596 [DataOverHttps](INFO): SBD MOMSN=5086169 2017-09-12T18:04:26.866Z,1505239466.866 [DataOverHttps](INFO): Sending 732 bytes from file Logs/20170912T180013/Express0001.lzma 2017-09-12T18:04:27.651Z,1505239467.651 [DataOverHttps](INFO): Moved sent file to Logs/20170912T180013/Express0001.lzma.bak 2017-09-12T18:04:27.651Z,1505239467.651 [DataOverHttps](INFO): SBD MOMSN=5086172 2017-09-12T18:04:28.355Z,1505239468.355 [Startup:StartupSatComms:B] Stopped 2017-09-12T18:04:28.356Z,1505239468.356 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2017-09-12T18:04:28.356Z,1505239468.356 [Startup:StartupSatComms] Stopped 2017-09-12T18:04:28.356Z,1505239468.356 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2017-09-12T18:04:28.357Z,1505239468.357 [Startup](INFO): Completed Startup 2017-09-12T18:04:28.357Z,1505239468.357 [MissionManager](INFO): Startup is completed. 2017-09-12T18:04:28.357Z,1505239468.357 [MissionManager](INFO): Uninitializing Mission Startup 2017-09-12T18:04:28.357Z,1505239468.357 [Startup] Stopped 2017-09-12T18:04:28.358Z,1505239468.358 [Startup](DEBUG): Aggregate::uninitialize Startup 2017-09-12T18:04:28.358Z,1505239468.358 [Startup:A.GoToSurface] Stopped 2017-09-12T18:04:28.358Z,1505239468.358 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-09-12T18:04:28.548Z,1505239468.548 [MissionManager](IMPORTANT): Started mission Default 2017-09-12T18:04:28.548Z,1505239468.548 [Default] Running Loop=1 2017-09-12T18:04:28.548Z,1505239468.548 [Default](DEBUG): Aggregate::initialize Default 2017-09-12T18:04:28.548Z,1505239468.548 [Default:B.GoToSurface] Running Loop=1 2017-09-12T18:04:28.548Z,1505239468.548 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-09-12T18:04:28.549Z,1505239468.549 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-09-12T18:04:28.549Z,1505239468.549 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-09-12T18:04:28.549Z,1505239468.549 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-09-12T18:04:28.550Z,1505239468.550 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-09-12T18:04:28.550Z,1505239468.550 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-09-12T18:04:28.550Z,1505239468.550 [Default:A.Wait] Running Loop=1 2017-09-12T18:04:28.550Z,1505239468.550 [Default:A.Wait](DEBUG): Initialize Wait Component. 2017-09-12T18:04:32.662Z,1505239472.662 [NAL9602](INFO): GPS fix at 20170912T180219: (36.802879, -121.788052) 2017-09-12T18:04:39.842Z,1505239479.842 [NAL9602](INFO): Powering down 2017-09-12T18:04:41.907Z,1505239481.907 [Default:A.Wait](INFO): Done Waiting. 2017-09-12T18:04:41.908Z,1505239481.908 [Default:A.Wait] Stopped 2017-09-12T18:04:41.908Z,1505239481.908 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2017-09-12T18:04:42.319Z,1505239482.319 [Default:CheckIn] Running Loop=1 2017-09-12T18:04:42.319Z,1505239482.319 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-09-12T18:04:42.319Z,1505239482.319 [Default:CheckIn:Read_GPS] Running Loop=1 2017-09-12T18:04:42.721Z,1505239482.721 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2017-09-12T18:04:43.043Z,1505239483.043 [NAL9602](INFO): Powering up 2017-09-12T18:04:54.249Z,1505239494.249 [NAL9602](INFO): NAL9602 initialized 2017-09-12T18:05:01.084Z,1505239501.084 [DAT](DEBUG): Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:58:07 Warning battery low CONNECT 2017-09-12T18:05:01.084Z,1505239501.084 [DAT](INFO): Got CONNECT 2017-09-12T18:05:01.084Z,1505239501.084 [DAT](INFO): entering command mode 2017-09-12T18:05:01.465Z,1505239501.465 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:01.869Z,1505239501.869 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:02.300Z,1505239502.300 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:02.698Z,1505239502.698 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:03.070Z,1505239503.070 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:03.510Z,1505239503.510 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:03.912Z,1505239503.912 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:04.279Z,1505239504.279 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:04.682Z,1505239504.682 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:05.079Z,1505239505.079 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:05.479Z,1505239505.479 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:05.896Z,1505239505.896 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:06.264Z,1505239506.264 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:06.669Z,1505239506.669 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:07.078Z,1505239507.078 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:07.466Z,1505239507.466 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:07.884Z,1505239507.884 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:08.304Z,1505239508.304 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:08.693Z,1505239508.693 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:09.112Z,1505239509.112 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:09.489Z,1505239509.489 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:09.891Z,1505239509.891 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:10.307Z,1505239510.307 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:10.720Z,1505239510.720 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:11.064Z,1505239511.064 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:11.473Z,1505239511.473 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:11.903Z,1505239511.903 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:12.275Z,1505239512.275 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:12.680Z,1505239512.680 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:13.072Z,1505239513.072 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:13.475Z,1505239513.475 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:13.878Z,1505239513.878 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:14.273Z,1505239514.273 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:14.669Z,1505239514.669 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:15.078Z,1505239515.078 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:15.483Z,1505239515.483 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:15.931Z,1505239515.931 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:16.257Z,1505239516.257 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:16.672Z,1505239516.672 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:17.073Z,1505239517.073 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:17.499Z,1505239517.499 [NAL9602](INFO): SBD MO Status=0, MOMSN=477, MT Status=0, MTMSN=0 2017-09-12T18:05:17.500Z,1505239517.500 [NAL9602](INFO): No messages in MT queue 2017-09-12T18:05:17.521Z,1505239517.521 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:17.879Z,1505239517.879 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:18.282Z,1505239518.282 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:18.678Z,1505239518.678 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:19.087Z,1505239519.087 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:19.472Z,1505239519.472 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:19.872Z,1505239519.872 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:20.268Z,1505239520.268 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:20.663Z,1505239520.663 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:21.101Z,1505239521.101 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:21.487Z,1505239521.487 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:05:21.875Z,1505239521.875 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:58:07 Warning battery low CONNECT, available: 02400 bits/sec 1 of 4 user:1>œQ@ 2017-09-12T18:05:21.875Z,1505239521.875 [DAT] Communications Fault, FailCount= 4 2017-09-12T18:05:21.875Z,1505239521.875 [DAT](ERROR): Communications Fault 2017-09-12T18:05:21.987Z,1505239521.987 [CBIT](ERROR): Communications Fault in component: DAT 2017-09-12T18:05:22.276Z,1505239522.276 [DAT](INFO): Powering down 2017-09-12T18:05:23.150Z,1505239523.150 [CBIT](INFO): Clearing failed state for component DAT 2017-09-12T18:05:23.150Z,1505239523.150 [DAT] No Fault, FailCount= 4 2017-09-12T18:05:25.476Z,1505239525.476 [DAT](INFO): Powering up 2017-09-12T18:05:25.476Z,1505239525.476 [DAT](DEBUG): Initializing DAT. 2017-09-12T18:06:12.137Z,1505239572.137 [BPC1](ERROR): Failed to parse bank A battery data 2017-09-12T18:06:12.137Z,1505239572.137 [BPC1] Data Fault, FailCount= 1 2017-09-12T18:06:12.137Z,1505239572.137 [BPC1](ERROR): Data Fault 2017-09-12T18:06:12.202Z,1505239572.202 [CBIT](ERROR): Data Fault in component: BPC1 2017-09-12T18:06:13.406Z,1505239573.406 [CBIT](INFO): Clearing failed state for component BPC1 2017-09-12T18:06:13.406Z,1505239573.406 [BPC1] No Fault, FailCount= 1 2017-09-12T18:06:14.949Z,1505239574.949 [DAT](DEBUG): Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:59:21 Warning battery low CONNECT 2017-09-12T18:06:14.950Z,1505239574.950 [DAT](INFO): Got CONNECT 2017-09-12T18:06:14.950Z,1505239574.950 [DAT](INFO): entering command mode 2017-09-12T18:06:15.337Z,1505239575.337 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:15.738Z,1505239575.738 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:16.144Z,1505239576.144 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:16.540Z,1505239576.540 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:16.937Z,1505239576.937 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:17.354Z,1505239577.354 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:17.748Z,1505239577.748 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:18.128Z,1505239578.128 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:18.543Z,1505239578.543 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:18.941Z,1505239578.941 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:19.330Z,1505239579.330 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:19.746Z,1505239579.746 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:20.147Z,1505239580.147 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:20.545Z,1505239580.545 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:20.953Z,1505239580.953 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:21.337Z,1505239581.337 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:21.748Z,1505239581.748 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:22.125Z,1505239582.125 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:22.533Z,1505239582.533 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:22.944Z,1505239582.944 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:23.357Z,1505239583.357 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:23.741Z,1505239583.741 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:24.132Z,1505239584.132 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:24.527Z,1505239584.527 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:24.947Z,1505239584.947 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:25.327Z,1505239585.327 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:25.731Z,1505239585.731 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:26.161Z,1505239586.161 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:26.562Z,1505239586.562 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:26.935Z,1505239586.935 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:27.344Z,1505239587.344 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:27.730Z,1505239587.730 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:28.128Z,1505239588.128 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:28.528Z,1505239588.528 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:28.946Z,1505239588.946 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:29.351Z,1505239589.351 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:32.638Z,1505239592.638 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:32.775Z,1505239592.775 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:33.163Z,1505239593.163 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:33.564Z,1505239593.564 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:33.968Z,1505239593.968 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:34.367Z,1505239594.367 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:34.780Z,1505239594.780 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:35.186Z,1505239595.186 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:06:35.588Z,1505239595.588 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 03:59:21 Warning battery low CONNECT, available: 02400 bits/sec 1 of 4 user:1>œQ@ 2017-09-12T18:06:35.588Z,1505239595.588 [DAT] Communications Fault, FailCount= 5 2017-09-12T18:06:35.588Z,1505239595.588 [DAT](ERROR): Communications Fault 2017-09-12T18:06:35.686Z,1505239595.686 [CBIT](ERROR): Communications Fault in component: DAT 2017-09-12T18:06:35.960Z,1505239595.960 [DAT](INFO): Powering down 2017-09-12T18:06:36.366Z,1505239596.366 [DAT](FAULT): LCB fault: Current Limiter Activated. 2017-09-12T18:06:36.366Z,1505239596.366 [DAT] Hardware Fault, FailCount= 5 2017-09-12T18:06:36.367Z,1505239596.367 [DAT](ERROR): Hardware Fault 2017-09-12T18:06:36.831Z,1505239596.831 [CBIT](INFO): Clearing failed state for component DAT 2017-09-12T18:06:36.831Z,1505239596.831 [DAT] No Fault, FailCount= 5 2017-09-12T18:06:39.165Z,1505239599.165 [DAT](INFO): Powering up 2017-09-12T18:06:39.165Z,1505239599.165 [DAT](DEBUG): Initializing DAT. 2017-09-12T18:07:49.225Z,1505239669.225 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2017-09-12T18:07:49.226Z,1505239669.226 [DAT] Communications Fault, FailCount= 6 2017-09-12T18:07:49.226Z,1505239669.226 [DAT](ERROR): Communications Fault 2017-09-12T18:07:49.309Z,1505239669.309 [CBIT](ERROR): Communications Fault in component: DAT 2017-09-12T18:07:49.628Z,1505239669.628 [DAT](INFO): Powering down 2017-09-12T18:07:50.500Z,1505239670.500 [CBIT](INFO): Clearing failed state for component DAT 2017-09-12T18:07:50.501Z,1505239670.501 [DAT] No Fault, FailCount= 6 2017-09-12T18:07:52.446Z,1505239672.446 [BPC1](ERROR): Failed to parse bank B battery data 2017-09-12T18:07:52.446Z,1505239672.446 [BPC1] Data Fault, FailCount= 1 2017-09-12T18:07:52.446Z,1505239672.446 [BPC1](ERROR): Data Fault 2017-09-12T18:07:52.528Z,1505239672.528 [CBIT](ERROR): Data Fault in component: BPC1 2017-09-12T18:07:52.819Z,1505239672.819 [DAT](INFO): Powering up 2017-09-12T18:07:52.819Z,1505239672.819 [DAT](DEBUG): Initializing DAT. 2017-09-12T18:07:53.691Z,1505239673.691 [CBIT](INFO): Clearing failed state for component BPC1 2017-09-12T18:07:53.692Z,1505239673.692 [BPC1] No Fault, FailCount= 1 2017-09-12T18:08:42.541Z,1505239722.541 [DAT](DEBUG): Teledyne Benthos ATM-900 Series MF Frequency Band Directional Acoustic Transponder version 8.3.1 Jan 3 2000 04:01:48 Warning battery low CONNECT 2017-09-12T18:08:42.542Z,1505239722.542 [DAT](INFO): Got CONNECT 2017-09-12T18:08:42.542Z,1505239722.542 [DAT](INFO): entering command mode 2017-09-12T18:08:42.930Z,1505239722.930 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:43.329Z,1505239723.329 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:43.735Z,1505239723.735 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:44.141Z,1505239724.141 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:44.591Z,1505239724.591 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:44.945Z,1505239724.945 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:45.361Z,1505239725.361 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:45.766Z,1505239725.766 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:46.163Z,1505239726.163 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:46.528Z,1505239726.528 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:46.972Z,1505239726.972 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:47.380Z,1505239727.380 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:47.775Z,1505239727.775 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:48.140Z,1505239728.140 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:48.552Z,1505239728.552 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:48.928Z,1505239728.928 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:49.353Z,1505239729.353 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:49.754Z,1505239729.754 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:50.140Z,1505239730.140 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:50.529Z,1505239730.529 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:50.926Z,1505239730.926 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:51.341Z,1505239731.341 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:51.729Z,1505239731.729 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:52.142Z,1505239732.142 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:52.597Z,1505239732.597 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:52.950Z,1505239732.950 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:53.341Z,1505239733.341 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:53.728Z,1505239733.728 [DAT](DEBUG): checking for command mode acknowledgment 2017-09-12T18:08:54.182