2017-09-11T22:30:31.120Z,1505169031.120 [Supervisor](DEBUG): Initializing supervisor.
2017-09-11T22:30:31.124Z,1505169031.124 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-09-11T22:30:31.125Z,1505169031.125 [SyncHandler](INFO): Protected caller Thread ID is 794
2017-09-11T22:30:31.126Z,1505169031.126 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-09-11T22:30:31.127Z,1505169031.127 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-09-11T22:30:31.127Z,1505169031.127 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 795
2017-09-11T22:30:31.132Z,1505169031.132 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-09-11T22:30:31.148Z,1505169031.148 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-09-11T22:30:31.149Z,1505169031.149 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-09-11T22:30:31.150Z,1505169031.150 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 796
2017-09-11T22:30:31.151Z,1505169031.151 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-09-11T22:30:31.152Z,1505169031.152 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-09-11T22:30:31.152Z,1505169031.152 [logger ThreadHandler](INFO): Protected caller Thread ID is 797
2017-09-11T22:30:31.155Z,1505169031.155 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-09-11T22:30:31.155Z,1505169031.155 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-09-11T22:30:31.157Z,1505169031.157 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-09-11T22:30:31.364Z,1505169031.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-09-11T22:30:31.365Z,1505169031.365 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-09-11T22:30:31.508Z,1505169031.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-09-11T22:30:31.509Z,1505169031.509 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-09-11T22:30:31.904Z,1505169031.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-09-11T22:30:31.905Z,1505169031.905 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-09-11T22:30:32.186Z,1505169032.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-09-11T22:30:32.187Z,1505169032.187 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-09-11T22:30:32.304Z,1505169032.304 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-09-11T22:30:32.657Z,1505169032.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-09-11T22:30:32.658Z,1505169032.658 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-09-11T22:30:32.897Z,1505169032.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-09-11T22:30:32.897Z,1505169032.897 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-09-11T22:30:33.476Z,1505169033.476 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-09-11T22:30:33.476Z,1505169033.476 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-09-11T22:30:33.990Z,1505169033.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-09-11T22:30:33.991Z,1505169033.991 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-09-11T22:30:34.352Z,1505169034.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-09-11T22:30:34.353Z,1505169034.353 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-09-11T22:30:34.980Z,1505169034.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-09-11T22:30:34.981Z,1505169034.981 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-09-11T22:30:35.200Z,1505169035.200 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-09-11T22:30:35.201Z,1505169035.201 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-09-11T22:30:35.308Z,1505169035.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-09-11T22:30:35.309Z,1505169035.309 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-09-11T22:30:35.874Z,1505169035.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-09-11T22:30:35.875Z,1505169035.875 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-09-11T22:30:35.993Z,1505169035.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-09-11T22:30:35.995Z,1505169035.995 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-09-11T22:30:35.996Z,1505169035.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-09-11T22:30:36.303Z,1505169036.303 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-09-11T22:30:36.303Z,1505169036.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-09-11T22:30:36.411Z,1505169036.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-09-11T22:30:36.508Z,1505169036.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-09-11T22:30:36.619Z,1505169036.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-09-11T22:30:36.710Z,1505169036.710 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-09-11T22:30:36.851Z,1505169036.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-09-11T22:30:37.025Z,1505169037.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-09-11T22:30:37.149Z,1505169037.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-09-11T22:30:37.247Z,1505169037.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-09-11T22:30:37.336Z,1505169037.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-09-11T22:30:37.539Z,1505169037.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-09-11T22:30:37.621Z,1505169037.621 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2017-09-11T22:30:37.621Z,1505169037.621 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-09-11T22:30:37.623Z,1505169037.623 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-09-11T22:30:37.777Z,1505169037.777 [SBIT](DEBUG): Construct Startup Built In Test.
2017-09-11T22:30:37.815Z,1505169037.815 [SBIT] Loaded
2017-09-11T22:30:37.815Z,1505169037.815 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-09-11T22:30:37.816Z,1505169037.816 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-09-11T22:30:37.852Z,1505169037.852 [IBIT] Loaded
2017-09-11T22:30:37.852Z,1505169037.852 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-09-11T22:30:37.857Z,1505169037.857 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-09-11T22:30:38.055Z,1505169038.055 [CBIT] Loaded
2017-09-11T22:30:38.055Z,1505169038.055 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-09-11T22:30:38.056Z,1505169038.056 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-09-11T22:30:38.056Z,1505169038.056 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-09-11T22:30:38.130Z,1505169038.130 [VerticalControl](DEBUG): Construct VerticalControl.
2017-09-11T22:30:38.253Z,1505169038.253 [VerticalControl] Loaded
2017-09-11T22:30:38.253Z,1505169038.253 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-09-11T22:30:38.254Z,1505169038.254 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-09-11T22:30:38.333Z,1505169038.333 [HorizontalControl] Loaded
2017-09-11T22:30:38.334Z,1505169038.334 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-09-11T22:30:38.334Z,1505169038.334 [SpeedControl](DEBUG): Construct SpeedControl.
2017-09-11T22:30:38.342Z,1505169038.342 [SpeedControl] Loaded
2017-09-11T22:30:38.342Z,1505169038.342 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-09-11T22:30:38.343Z,1505169038.343 [LoopControl](DEBUG): Construct LoopControl.
2017-09-11T22:30:38.344Z,1505169038.344 [LoopControl] Loaded
2017-09-11T22:30:38.344Z,1505169038.344 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-09-11T22:30:38.344Z,1505169038.344 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-09-11T22:30:38.345Z,1505169038.345 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-09-11T22:30:38.372Z,1505169038.372 [DepthRateCalculator] Loaded
2017-09-11T22:30:38.372Z,1505169038.372 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-09-11T22:30:38.378Z,1505169038.378 [PitchRateCalculator] Loaded
2017-09-11T22:30:38.379Z,1505169038.379 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-09-11T22:30:38.398Z,1505169038.398 [SpeedCalculator] Loaded
2017-09-11T22:30:38.398Z,1505169038.398 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-09-11T22:30:38.425Z,1505169038.425 [TempGradientCalculator] Loaded
2017-09-11T22:30:38.425Z,1505169038.425 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-09-11T22:30:38.444Z,1505169038.444 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-09-11T22:30:38.445Z,1505169038.445 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-09-11T22:30:38.451Z,1505169038.451 [YawRateCalculator] Loaded
2017-09-11T22:30:38.451Z,1505169038.451 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-09-11T22:30:38.452Z,1505169038.452 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-09-11T22:30:38.452Z,1505169038.452 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-09-11T22:30:38.539Z,1505169038.539 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-09-11T22:30:38.539Z,1505169038.539 [StratificationFrontDetector](DEBUG): (re)initializing
2017-09-11T22:30:38.540Z,1505169038.540 [StratificationFrontDetector] Loaded
2017-09-11T22:30:38.540Z,1505169038.540 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-09-11T22:30:38.541Z,1505169038.541 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-09-11T22:30:38.542Z,1505169038.542 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-09-11T22:30:38.781Z,1505169038.781 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-09-11T22:30:38.781Z,1505169038.781 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-09-11T22:30:38.896Z,1505169038.896 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-09-11T22:30:38.896Z,1505169038.896 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-09-11T22:30:39.000Z,1505169039.000 [DeadReckonUsingSpeedCalculator] Loaded
2017-09-11T22:30:39.013Z,1505169039.013 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-09-11T22:30:39.059Z,1505169039.059 [NavChart] Loaded
2017-09-11T22:30:39.059Z,1505169039.059 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-09-11T22:30:39.073Z,1505169039.073 [UniversalFixResidualReporter] Loaded
2017-09-11T22:30:39.073Z,1505169039.073 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-09-11T22:30:39.073Z,1505169039.073 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-09-11T22:30:39.074Z,1505169039.074 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-09-11T22:30:39.121Z,1505169039.121 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-09-11T22:30:39.122Z,1505169039.122 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-09-11T22:30:39.326Z,1505169039.326 [Aanderaa_O2] Loaded
2017-09-11T22:30:39.326Z,1505169039.326 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-09-11T22:30:39.434Z,1505169039.434 [CTD_NeilBrown] Loaded
2017-09-11T22:30:39.434Z,1505169039.434 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-09-11T22:30:39.436Z,1505169039.436 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408154E0
2017-09-11T22:30:39.436Z,1505169039.436 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 880
2017-09-11T22:30:39.455Z,1505169039.455 [PAR_Licor] Loaded
2017-09-11T22:30:39.455Z,1505169039.455 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-09-11T22:30:39.515Z,1505169039.515 [WetLabsBB2FL] Loaded
2017-09-11T22:30:39.516Z,1505169039.516 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-09-11T22:30:39.517Z,1505169039.517 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408454E0
2017-09-11T22:30:39.517Z,1505169039.517 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 881
2017-09-11T22:30:39.518Z,1505169039.518 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-09-11T22:30:39.518Z,1505169039.518 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-09-11T22:30:39.804Z,1505169039.804 [DataOverHttps] Loaded
2017-09-11T22:30:39.804Z,1505169039.804 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-09-11T22:30:39.821Z,1505169039.821 [Depth_Keller] Loaded
2017-09-11T22:30:39.822Z,1505169039.822 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-09-11T22:30:39.828Z,1505169039.828 [DropWeight] Loaded
2017-09-11T22:30:39.829Z,1505169039.829 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-09-11T22:30:39.943Z,1505169039.943 [NAL9602] Loaded
2017-09-11T22:30:39.943Z,1505169039.943 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-09-11T22:30:39.955Z,1505169039.955 [Onboard] Loaded
2017-09-11T22:30:39.955Z,1505169039.955 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-09-11T22:30:39.964Z,1505169039.964 [Radio_Surface] Loaded
2017-09-11T22:30:39.965Z,1505169039.965 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-09-11T22:30:39.966Z,1505169039.966 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40ACC4E0
2017-09-11T22:30:39.966Z,1505169039.966 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 882
2017-09-11T22:30:40.143Z,1505169040.143 [DAT] Loaded
2017-09-11T22:30:40.144Z,1505169040.144 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2017-09-11T22:30:40.203Z,1505169040.203 [PNI_TCM] Loaded
2017-09-11T22:30:40.203Z,1505169040.203 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-09-11T22:30:42.801Z,1505169042.801 [BPC1] Loaded
2017-09-11T22:30:42.801Z,1505169042.801 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-09-11T22:30:42.801Z,1505169042.801 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-09-11T22:30:42.802Z,1505169042.802 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-09-11T22:30:42.906Z,1505169042.906 [BuoyancyServo] Loaded
2017-09-11T22:30:42.907Z,1505169042.907 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-09-11T22:30:42.922Z,1505169042.922 [ElevatorServo] Loaded
2017-09-11T22:30:42.923Z,1505169042.923 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-09-11T22:30:42.937Z,1505169042.937 [MassServo] Loaded
2017-09-11T22:30:42.938Z,1505169042.938 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-09-11T22:30:42.953Z,1505169042.953 [RudderServo] Loaded
2017-09-11T22:30:42.954Z,1505169042.954 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-09-11T22:30:42.968Z,1505169042.968 [ThrusterServo] Loaded
2017-09-11T22:30:42.969Z,1505169042.969 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-09-11T22:30:42.969Z,1505169042.969 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-09-11T22:30:42.970Z,1505169042.970 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-09-11T22:30:43.070Z,1505169043.070 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-09-11T22:30:43.071Z,1505169043.071 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-09-11T22:30:43.096Z,1505169043.096 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-09-11T22:30:43.101Z,1505169043.101 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-09-11T22:30:43.103Z,1505169043.103 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-09-11T22:30:43.113Z,1505169043.113 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-09-11T22:30:43.115Z,1505169043.115 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA04E0
2017-09-11T22:30:43.115Z,1505169043.115 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 883
2017-09-11T22:30:43.122Z,1505169043.122 [Supervisor](INFO): Main Thread ID is 793
2017-09-11T22:30:43.122Z,1505169043.122 [Supervisor](DEBUG): Running supervisor.
2017-09-11T22:30:43.123Z,1505169043.123 [CommandLine ThreadHandler](INFO): Handler Thread ID is 884
2017-09-11T22:30:43.127Z,1505169043.127 [controlThread ThreadHandler](INFO): Handler Thread ID is 885
2017-09-11T22:30:43.127Z,1505169043.127 [controlThread](DEBUG): Initializing ControlThread
2017-09-11T22:30:43.128Z,1505169043.128 [SBIT](INFO): Initialize SBIT Component.
2017-09-11T22:30:43.129Z,1505169043.129 [SBIT](IMPORTANT): git: 2017-08-02-81-g2dedf71
2017-09-11T22:30:43.129Z,1505169043.129 [SBIT](INFO): git hash: 2dedf719d304b5c3f7e3acd061a0c8109ea4feed
2017-09-11T22:30:43.131Z,1505169043.131 [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-11T22:30:43.132Z,1505169043.132 [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-11T22:30:43.133Z,1505169043.133 [SBIT](INFO): Beginning SBIT in 23.000000 seconds.
2017-09-11T22:30:43.133Z,1505169043.133 [IBIT](INFO): Initialize IBIT Component.
2017-09-11T22:30:43.134Z,1505169043.134 [CBIT](DEBUG): Initialize CBIT Component.
2017-09-11T22:30:43.135Z,1505169043.135 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-09-11T22:30:43.135Z,1505169043.135 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-09-11T22:30:43.136Z,1505169043.136 [logger ThreadHandler](INFO): Handler Thread ID is 886
2017-09-11T22:30:43.164Z,1505169043.164 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 887
2017-09-11T22:30:43.165Z,1505169043.165 [CTD_NeilBrown](INFO): Powering down
2017-09-11T22:30:43.200Z,1505169043.200 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 888
2017-09-11T22:30:43.202Z,1505169043.202 [WetLabsBB2FL](INFO): Powering down
2017-09-11T22:30:43.216Z,1505169043.216 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-09-11T22:30:43.218Z,1505169043.218 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-09-11T22:30:43.219Z,1505169043.219 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-09-11T22:30:43.220Z,1505169043.220 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-09-11T22:30:43.220Z,1505169043.220 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-11T22:30:43.221Z,1505169043.221 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-09-11T22:30:43.221Z,1505169043.221 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-09-11T22:30:43.221Z,1505169043.221 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-09-11T22:30:43.222Z,1505169043.222 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-09-11T22:30:43.223Z,1505169043.223 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-09-11T22:30:43.224Z,1505169043.224 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T22:30:43.225Z,1505169043.225 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:30:43.225Z,1505169043.225 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:30:43.226Z,1505169043.226 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T22:30:43.226Z,1505169043.226 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:30:43.226Z,1505169043.226 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:30:43.227Z,1505169043.227 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-09-11T22:30:43.227Z,1505169043.227 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-09-11T22:30:43.272Z,1505169043.272 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 889
2017-09-11T22:30:43.274Z,1505169043.274 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 890
2017-09-11T22:30:43.279Z,1505169043.279 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-09-11T22:30:43.279Z,1505169043.279 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-09-11T22:30:43.280Z,1505169043.280 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-09-11T22:30:43.280Z,1505169043.280 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-09-11T22:30:43.280Z,1505169043.280 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-09-11T22:30:43.281Z,1505169043.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-09-11T22:30:43.281Z,1505169043.281 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-09-11T22:30:43.281Z,1505169043.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-09-11T22:30:43.281Z,1505169043.281 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-09-11T22:30:43.281Z,1505169043.281 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-09-11T22:30:43.282Z,1505169043.282 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-09-11T22:30:43.282Z,1505169043.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-09-11T22:30:43.282Z,1505169043.282 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-09-11T22:30:43.282Z,1505169043.282 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-09-11T22:30:43.282Z,1505169043.282 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-09-11T22:30:43.283Z,1505169043.283 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-09-11T22:30:43.302Z,1505169043.302 [Radio_Surface](INFO): Powering up
2017-09-11T22:30:43.304Z,1505169043.304 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-09-11T22:30:43.336Z,1505169043.336 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-11T22:30:43.389Z,1505169043.389 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2017-09-11T22:30:43.390Z,1505169043.390 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-09-11T22:30:43.462Z,1505169043.462 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-09-11T22:30:43.463Z,1505169043.463 [Default:A.Wait](DEBUG): Construct Wait.
2017-09-11T22:30:43.465Z,1505169043.465 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-11T22:30:43.499Z,1505169043.499 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-09-11T22:30:43.526Z,1505169043.526 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-09-11T22:30:43.550Z,1505169043.550 [Default:E.Execute](DEBUG): Construct Execute.
2017-09-11T22:30:43.554Z,1505169043.554 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Guidance="Tethys/Guidance"
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2017-09-11T22:30:43.565Z,1505169043.565 [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-11T22:30:43.767Z,1505169043.767 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-09-11T22:30:43.831Z,1505169043.831 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2017-09-11T22:30:43.925Z,1505169043.925 [DAT](INFO): Powering up
2017-09-11T22:30:43.925Z,1505169043.925 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:30:44.122Z,1505169044.122 [DepthRateCalculator](ERROR): Depth measurement is not active
2017-09-11T22:30:44.246Z,1505169044.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:44.294Z,1505169044.294 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:44.479Z,1505169044.479 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-11T22:30:44.486Z,1505169044.486 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-11T22:30:44.514Z,1505169044.514 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-09-11T22:30:44.522Z,1505169044.522 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-09-11T22:30:44.538Z,1505169044.538 [MassServo](DEBUG): Initializing EZServoServo.
2017-09-11T22:30:44.546Z,1505169044.546 [MassServo](DEBUG): Initializing MassServo.
2017-09-11T22:30:44.587Z,1505169044.587 [RudderServo](DEBUG): Initializing EZServoServo.
2017-09-11T22:30:44.594Z,1505169044.594 [RudderServo](DEBUG): Initializing RudderServo.
2017-09-11T22:30:44.658Z,1505169044.658 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-09-11T22:30:44.662Z,1505169044.662 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-09-11T22:30:45.157Z,1505169045.157 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2017-09-11T22:30:45.157Z,1505169045.157 [DropWeight] Hardware Fault, FailCount= 1
2017-09-11T22:30:45.157Z,1505169045.157 [DropWeight](ERROR): Hardware Fault
2017-09-11T22:30:45.159Z,1505169045.159 [NAL9602](INFO): Powering up NAL9602
2017-09-11T22:30:45.241Z,1505169045.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:45.243Z,1505169045.243 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:45.630Z,1505169045.630 [CommandLine](FAULT): Scheduling is paused
2017-09-11T22:30:45.630Z,1505169045.630 [CBIT](INFO): Critical error at 20170911T223045
2017-09-11T22:30:45.630Z,1505169045.630 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-09-11T22:30:45.633Z,1505169045.633 [CBIT](ERROR): Hardware Fault in component: DropWeight
2017-09-11T22:30:45.634Z,1505169045.634 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2017-09-11T22:30:54.883Z,1505169054.883 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:54.885Z,1505169054.885 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:54.948Z,1505169054.948 [CBIT](INFO): Critical error at 20170911T223045
2017-09-11T22:30:55.044Z,1505169055.044 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:55.045Z,1505169055.045 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:55.503Z,1505169055.503 [Aanderaa_O2](INFO): Powering down
2017-09-11T22:30:55.720Z,1505169055.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:55.721Z,1505169055.721 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:55.805Z,1505169055.805 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008112
2017-09-11T22:30:55.876Z,1505169055.876 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:55.878Z,1505169055.878 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:56.234Z,1505169056.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:56.235Z,1505169056.235 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:57.022Z,1505169057.022 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:30:57.024Z,1505169057.024 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T22:31:06.037Z,1505169066.037 [NAL9602](ERROR): NAL9602 initialization error.
2017-09-11T22:31:06.037Z,1505169066.037 [NAL9602] Communications Fault, FailCount= 1
2017-09-11T22:31:06.038Z,1505169066.038 [NAL9602](ERROR): Communications Fault
2017-09-11T22:31:06.182Z,1505169066.182 [CBIT](ERROR): Communications Fault in component: NAL9602
2017-09-11T22:31:06.438Z,1505169066.438 [NAL9602](INFO): Powering down
2017-09-11T22:31:06.555Z,1505169066.555 [SBIT](IMPORTANT): Beginning Startup BIT
2017-09-11T22:31:06.560Z,1505169066.560 [CBIT](IMPORTANT): Beginning ground fault scan
2017-09-11T22:31:07.404Z,1505169067.404 [CBIT](INFO): Clearing failed state for component NAL9602
2017-09-11T22:31:07.404Z,1505169067.404 [NAL9602] No Fault, FailCount= 1
2017-09-11T22:31:11.638Z,1505169071.638 [NAL9602](INFO): Powering up NAL9602
2017-09-11T22:31:17.387Z,1505169077.387 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.027623
CHAN A2 (12V): -0.001944
CHAN A3 (5V): 0.000360
CHAN B0 (3.3V): 4.766929
CHAN B1 (3.15aV): 0.002429
CHAN B2 (3.15bV): 4.767929
CHAN B3 (GND): 0.027562
OPEN: -0.000377
Full Scale Calc: 4.765 mA, -1.589 mA
2017-09-11T22:31:19.749Z,1505169079.749 [SBIT](FAULT): Mass: EXPECTED:0.004450 ACTUAL:0.001991
2017-09-11T22:31:22.844Z,1505169082.844 [NAL9602](INFO): NAL9602 initialized
2017-09-11T22:31:24.883Z,1505169084.883 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T22:31:24.883Z,1505169084.883 [BPC1] Data Fault, FailCount= 1
2017-09-11T22:31:24.883Z,1505169084.883 [BPC1](ERROR): Data Fault
2017-09-11T22:31:25.044Z,1505169085.044 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T22:31:26.234Z,1505169086.234 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:31:26.234Z,1505169086.234 [BPC1] No Fault, FailCount= 1
2017-09-11T22:31:33.266Z,1505169093.266 [DAT](DEBUG): 
2017-09-11T22:31:33.267Z,1505169093.267 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:31:33.268Z,1505169093.268 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:31:33.269Z,1505169093.269 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:31:33.270Z,1505169093.270 [DAT](DEBUG): Jan  3 2000 01:13:00 
2017-09-11T22:31:33.655Z,1505169093.655 [DAT](DEBUG): Warning battery low
2017-09-11T22:31:34.468Z,1505169094.468 [DAT](INFO): entering command mode
2017-09-11T22:31:34.856Z,1505169094.856 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:35.253Z,1505169095.253 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:35.653Z,1505169095.653 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:36.068Z,1505169096.068 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:36.491Z,1505169096.491 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:36.869Z,1505169096.869 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:37.271Z,1505169097.271 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:37.652Z,1505169097.652 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:38.091Z,1505169098.091 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:38.461Z,1505169098.461 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:38.865Z,1505169098.865 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:39.255Z,1505169099.255 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:39.656Z,1505169099.656 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:40.063Z,1505169100.063 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:40.453Z,1505169100.453 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:40.854Z,1505169100.854 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:41.291Z,1505169101.291 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:41.669Z,1505169101.669 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:42.069Z,1505169102.069 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:42.478Z,1505169102.478 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:42.856Z,1505169102.856 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:43.264Z,1505169103.264 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:43.666Z,1505169103.666 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:31:44.055Z,1505169104.055 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:31:44.055Z,1505169104.055 [DAT] Communications Fault, FailCount= 1
2017-09-11T22:31:44.055Z,1505169104.055 [DAT](ERROR): Communications Fault
2017-09-11T22:31:44.177Z,1505169104.177 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:31:44.472Z,1505169104.472 [DAT](INFO): Powering down
2017-09-11T22:31:50.965Z,1505169110.965 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:31:50.966Z,1505169110.966 [DAT] No Fault, FailCount= 1
2017-09-11T22:31:51.244Z,1505169111.244 [DAT](INFO): Powering up
2017-09-11T22:31:51.244Z,1505169111.244 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:32:04.329Z,1505169124.329 [SBIT](CRITICAL): SBIT FAILED
2017-09-11T22:32:04.331Z,1505169124.331 [CBIT](INFO): Critical error at 20170911T223204
2017-09-11T22:32:04.710Z,1505169124.710 [MissionManager](IMPORTANT): Started mission Startup
2017-09-11T22:32:04.711Z,1505169124.711 [Startup] Running Loop=1
2017-09-11T22:32:04.711Z,1505169124.711 [Startup](DEBUG): Aggregate::initialize Startup
2017-09-11T22:32:04.711Z,1505169124.711 [Startup:A.GoToSurface] Running Loop=1
2017-09-11T22:32:04.711Z,1505169124.711 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-11T22:32:04.712Z,1505169124.712 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-11T22:32:04.725Z,1505169124.725 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-11T22:32:04.725Z,1505169124.725 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-11T22:32:04.726Z,1505169124.726 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-11T22:32:04.726Z,1505169124.726 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-11T22:32:04.762Z,1505169124.762 [Startup:StartupSatComms] Running Loop=1
2017-09-11T22:32:04.762Z,1505169124.762 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-09-11T22:32:04.762Z,1505169124.762 [Startup:StartupSatComms:A] Running Loop=1
2017-09-11T22:32:05.088Z,1505169125.088 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-09-11T22:32:40.216Z,1505169160.216 [DAT](DEBUG): 
2017-09-11T22:32:40.217Z,1505169160.217 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:32:40.218Z,1505169160.218 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:32:40.219Z,1505169160.219 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:32:40.220Z,1505169160.220 [DAT](DEBUG): Jan  3 2000 01:14:07 
2017-09-11T22:32:40.600Z,1505169160.600 [DAT](DEBUG): Warning battery low
2017-09-11T22:32:41.403Z,1505169161.403 [DAT](INFO): entering command mode
2017-09-11T22:32:41.799Z,1505169161.799 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:42.232Z,1505169162.232 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:42.622Z,1505169162.622 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:43.014Z,1505169163.014 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:43.418Z,1505169163.418 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:43.804Z,1505169163.804 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:44.215Z,1505169164.215 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:44.616Z,1505169164.616 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:47.949Z,1505169167.949 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:48.110Z,1505169168.110 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:48.458Z,1505169168.458 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:48.843Z,1505169168.843 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:49.231Z,1505169169.231 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:49.638Z,1505169169.638 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:50.029Z,1505169170.029 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:50.434Z,1505169170.434 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:50.840Z,1505169170.840 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:51.240Z,1505169171.240 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:32:51.629Z,1505169171.629 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:32:51.629Z,1505169171.629 [DAT] Communications Fault, FailCount= 2
2017-09-11T22:32:51.629Z,1505169171.629 [DAT](ERROR): Communications Fault
2017-09-11T22:32:51.774Z,1505169171.774 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:32:52.034Z,1505169172.034 [DAT](INFO): Powering down
2017-09-11T22:32:52.934Z,1505169172.934 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:32:52.934Z,1505169172.934 [DAT] No Fault, FailCount= 2
2017-09-11T22:32:55.237Z,1505169175.237 [DAT](INFO): Powering up
2017-09-11T22:32:55.237Z,1505169175.237 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:33:04.863Z,1505169184.863 [Startup:StartupSatComms:A](INFO): Timed out from 2017-09-11T22:32:04.8Z
2017-09-11T22:33:04.864Z,1505169184.864 [Startup:StartupSatComms:A] Stopped
2017-09-11T22:33:04.864Z,1505169184.864 [Startup:StartupSatComms:B] Running Loop=1
2017-09-11T22:33:05.273Z,1505169185.273 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-09-11T22:33:09.468Z,1505169189.468 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170911T221404/Courier0004.lzma
2017-09-11T22:33:11.226Z,1505169191.226 [DataOverHttps](INFO): Moved sent file to Logs/20170911T221404/Courier0004.lzma.bak
2017-09-11T22:33:11.226Z,1505169191.226 [DataOverHttps](INFO): SBD MOMSN=5085889
2017-09-11T22:33:20.365Z,1505169200.365 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20170911T223031/Courier0000.lzma
2017-09-11T22:33:21.136Z,1505169201.136 [DataOverHttps](INFO): Moved sent file to Logs/20170911T223031/Courier0000.lzma.bak
2017-09-11T22:33:21.136Z,1505169201.136 [DataOverHttps](INFO): SBD MOMSN=5085891
2017-09-11T22:33:30.545Z,1505169210.545 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170911T221404/Express0005.lzma
2017-09-11T22:33:31.292Z,1505169211.292 [DataOverHttps](INFO): Moved sent file to Logs/20170911T221404/Express0005.lzma.bak
2017-09-11T22:33:31.292Z,1505169211.292 [DataOverHttps](INFO): SBD MOMSN=5085897
2017-09-11T22:33:40.935Z,1505169220.935 [DataOverHttps](INFO): Sending 711 bytes from file Logs/20170911T223031/Express0001.lzma
2017-09-11T22:33:41.693Z,1505169221.693 [DataOverHttps](INFO): Moved sent file to Logs/20170911T223031/Express0001.lzma.bak
2017-09-11T22:33:41.693Z,1505169221.693 [DataOverHttps](INFO): SBD MOMSN=5085899
2017-09-11T22:33:42.485Z,1505169222.485 [Startup:StartupSatComms:B] Stopped
2017-09-11T22:33:42.485Z,1505169222.485 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-09-11T22:33:42.485Z,1505169222.485 [Startup:StartupSatComms] Stopped
2017-09-11T22:33:42.485Z,1505169222.485 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-09-11T22:33:42.486Z,1505169222.486 [Startup](INFO): Completed Startup
2017-09-11T22:33:42.486Z,1505169222.486 [MissionManager](INFO): Startup is completed.
2017-09-11T22:33:42.487Z,1505169222.487 [MissionManager](INFO): Uninitializing Mission Startup
2017-09-11T22:33:42.507Z,1505169222.507 [Startup] Stopped
2017-09-11T22:33:42.507Z,1505169222.507 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-09-11T22:33:42.507Z,1505169222.507 [Startup:A.GoToSurface] Stopped
2017-09-11T22:33:42.507Z,1505169222.507 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-11T22:33:42.635Z,1505169222.635 [MissionManager](IMPORTANT): Started mission Default
2017-09-11T22:33:42.639Z,1505169222.639 [Default] Running Loop=1
2017-09-11T22:33:42.639Z,1505169222.639 [Default](DEBUG): Aggregate::initialize Default
2017-09-11T22:33:42.639Z,1505169222.639 [Default:B.GoToSurface] Running Loop=1
2017-09-11T22:33:42.640Z,1505169222.640 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-11T22:33:42.640Z,1505169222.640 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-11T22:33:42.640Z,1505169222.640 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-11T22:33:42.642Z,1505169222.642 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-11T22:33:42.642Z,1505169222.642 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-11T22:33:42.643Z,1505169222.643 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-11T22:33:42.643Z,1505169222.643 [Default:A.Wait] Running Loop=1
2017-09-11T22:33:42.647Z,1505169222.647 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-09-11T22:33:45.913Z,1505169225.913 [DAT](DEBUG): 
2017-09-11T22:33:45.915Z,1505169225.915 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:33:45.916Z,1505169225.916 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:33:45.932Z,1505169225.932 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:33:45.933Z,1505169225.933 [DAT](DEBUG): Jan  3 2000 01:15:12 
2017-09-11T22:33:45.934Z,1505169225.934 [DAT](DEBUG): Warning battery low
2017-09-11T22:33:46.004Z,1505169226.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:33:46.004Z,1505169226.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-09-11T22:33:46.004Z,1505169226.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-09-11T22:33:46.006Z,1505169226.006 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:33:46.006Z,1505169226.006 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-09-11T22:33:46.007Z,1505169226.007 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-09-11T22:33:46.045Z,1505169226.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-09-11T22:33:46.045Z,1505169226.045 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-09-11T22:33:46.203Z,1505169226.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-09-11T22:33:46.203Z,1505169226.203 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-09-11T22:33:46.204Z,1505169226.204 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-09-11T22:33:46.204Z,1505169226.204 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-09-11T22:33:46.519Z,1505169226.519 [DAT](INFO): entering command mode
2017-09-11T22:33:46.588Z,1505169226.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T22:33:46.589Z,1505169226.589 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:33:46.589Z,1505169226.589 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:33:46.590Z,1505169226.590 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T22:33:46.590Z,1505169226.590 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:33:46.591Z,1505169226.591 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:33:46.921Z,1505169226.921 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:46.941Z,1505169226.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2017-09-11T22:33:47.289Z,1505169227.289 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:47.325Z,1505169227.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.3 s old, using for 20.0 s.
2017-09-11T22:33:47.689Z,1505169227.689 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:47.725Z,1505169227.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2017-09-11T22:33:48.095Z,1505169228.095 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:48.134Z,1505169228.134 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2017-09-11T22:33:48.498Z,1505169228.498 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:48.518Z,1505169228.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2017-09-11T22:33:48.903Z,1505169228.903 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:48.923Z,1505169228.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.9 s old, using for 20.0 s.
2017-09-11T22:33:49.296Z,1505169229.296 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:49.326Z,1505169229.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2017-09-11T22:33:49.685Z,1505169229.685 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:49.721Z,1505169229.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.7 s old, using for 20.0 s.
2017-09-11T22:33:50.086Z,1505169230.086 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:50.122Z,1505169230.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2017-09-11T22:33:50.488Z,1505169230.488 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:50.535Z,1505169230.535 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2017-09-11T22:33:50.905Z,1505169230.905 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:50.941Z,1505169230.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2017-09-11T22:33:51.315Z,1505169231.315 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:51.335Z,1505169231.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.3 s old, using for 20.0 s.
2017-09-11T22:33:51.702Z,1505169231.702 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:51.745Z,1505169231.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.7 s old, using for 20.0 s.
2017-09-11T22:33:52.100Z,1505169232.100 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:52.138Z,1505169232.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.1 s old, using for 20.0 s.
2017-09-11T22:33:52.497Z,1505169232.497 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:52.533Z,1505169232.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-09-11T22:33:52.895Z,1505169232.895 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:52.946Z,1505169232.946 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-09-11T22:33:53.282Z,1505169233.282 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:53.322Z,1505169233.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2017-09-11T22:33:53.698Z,1505169233.698 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:53.719Z,1505169233.719 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.7 s old, using for 20.0 s.
2017-09-11T22:33:54.103Z,1505169234.103 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:54.125Z,1505169234.125 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.1 s old, using for 20.0 s.
2017-09-11T22:33:54.488Z,1505169234.488 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:54.525Z,1505169234.525 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2017-09-11T22:33:54.905Z,1505169234.905 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:33:54.971Z,1505169234.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.0 s old, using for 20.0 s.
2017-09-11T22:33:55.282Z,1505169235.282 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:33:55.282Z,1505169235.282 [DAT] Communications Fault, FailCount= 3
2017-09-11T22:33:55.282Z,1505169235.282 [DAT](ERROR): Communications Fault
2017-09-11T22:33:55.333Z,1505169235.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s.
2017-09-11T22:33:55.377Z,1505169235.377 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:33:55.682Z,1505169235.682 [DAT](INFO): Powering down
2017-09-11T22:33:55.817Z,1505169235.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s.
2017-09-11T22:33:55.822Z,1505169235.822 [Default:A.Wait](INFO): Done Waiting.
2017-09-11T22:33:55.823Z,1505169235.823 [Default:A.Wait] Stopped
2017-09-11T22:33:55.823Z,1505169235.823 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-09-11T22:33:56.152Z,1505169236.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s.
2017-09-11T22:33:56.172Z,1505169236.172 [Default:CheckIn] Running Loop=1
2017-09-11T22:33:56.177Z,1505169236.177 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-09-11T22:33:56.177Z,1505169236.177 [Default:CheckIn:Read_GPS] Running Loop=1
2017-09-11T22:33:56.542Z,1505169236.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.5 s old, using for 20.0 s.
2017-09-11T22:33:56.546Z,1505169236.546 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-09-11T22:33:56.582Z,1505169236.582 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:33:56.582Z,1505169236.582 [DAT] No Fault, FailCount= 3
2017-09-11T22:33:56.955Z,1505169236.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2017-09-11T22:33:57.317Z,1505169237.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.3 s old, using for 20.0 s.
2017-09-11T22:33:57.726Z,1505169237.726 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2017-09-11T22:33:58.118Z,1505169238.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.1 s old, using for 20.0 s.
2017-09-11T22:33:58.571Z,1505169238.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s.
2017-09-11T22:33:58.883Z,1505169238.883 [DAT](INFO): Powering up
2017-09-11T22:33:58.883Z,1505169238.883 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:33:58.984Z,1505169238.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2017-09-11T22:33:59.308Z,1505169239.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.3 s old, using for 20.0 s.
2017-09-11T22:33:59.758Z,1505169239.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2017-09-11T22:34:00.111Z,1505169240.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2017-09-11T22:34:00.551Z,1505169240.551 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.5 s old, using for 20.0 s.
2017-09-11T22:34:00.992Z,1505169240.992 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.0 s old, using for 20.0 s.
2017-09-11T22:34:01.324Z,1505169241.324 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.3 s old, using for 20.0 s.
2017-09-11T22:34:01.727Z,1505169241.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2017-09-11T22:34:02.129Z,1505169242.129 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.1 s old, using for 20.0 s.
2017-09-11T22:34:02.526Z,1505169242.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2017-09-11T22:34:02.921Z,1505169242.921 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2017-09-11T22:34:03.328Z,1505169243.328 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2017-09-11T22:34:03.718Z,1505169243.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.7 s old, using for 20.0 s.
2017-09-11T22:34:04.106Z,1505169244.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.1 s old, using for 20.0 s.
2017-09-11T22:34:04.511Z,1505169244.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2017-09-11T22:34:04.912Z,1505169244.912 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2017-09-11T22:34:05.311Z,1505169245.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.3 s old, using for 20.0 s.
2017-09-11T22:34:05.710Z,1505169245.710 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2017-09-11T22:34:48.091Z,1505169288.091 [DAT](DEBUG): 
2017-09-11T22:34:48.093Z,1505169288.093 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:34:48.093Z,1505169288.093 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:34:48.095Z,1505169288.095 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:34:48.096Z,1505169288.096 [DAT](DEBUG): Jan  3 2000 01:16:15 
2017-09-11T22:34:48.513Z,1505169288.513 [DAT](DEBUG): Warning battery low
2017-09-11T22:34:49.302Z,1505169289.302 [DAT](INFO): entering command mode
2017-09-11T22:34:49.696Z,1505169289.696 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:50.096Z,1505169290.096 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:50.490Z,1505169290.490 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:50.897Z,1505169290.897 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:51.286Z,1505169291.286 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:51.699Z,1505169291.699 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:52.103Z,1505169292.103 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:52.503Z,1505169292.503 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:52.893Z,1505169292.893 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:53.315Z,1505169293.315 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:53.706Z,1505169293.706 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:54.097Z,1505169294.097 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:54.506Z,1505169294.506 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:54.895Z,1505169294.895 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:55.289Z,1505169295.289 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:55.697Z,1505169295.697 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:56.098Z,1505169296.098 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:56.499Z,1505169296.499 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:56.893Z,1505169296.893 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:57.289Z,1505169297.289 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:57.685Z,1505169297.685 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:58.086Z,1505169298.086 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:58.518Z,1505169298.518 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:34:58.917Z,1505169298.917 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:34:58.917Z,1505169298.917 [DAT] Communications Fault, FailCount= 4
2017-09-11T22:34:58.917Z,1505169298.917 [DAT](ERROR): Communications Fault
2017-09-11T22:34:59.014Z,1505169299.014 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:34:59.310Z,1505169299.310 [DAT](INFO): Powering down
2017-09-11T22:35:00.153Z,1505169300.153 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:35:00.153Z,1505169300.153 [DAT] No Fault, FailCount= 4
2017-09-11T22:35:02.484Z,1505169302.484 [DAT](INFO): Powering up
2017-09-11T22:35:02.484Z,1505169302.484 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:35:10.126Z,1505169310.126 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T22:35:10.126Z,1505169310.126 [BPC1] Data Fault, FailCount= 1
2017-09-11T22:35:10.126Z,1505169310.126 [BPC1](ERROR): Data Fault
2017-09-11T22:35:10.154Z,1505169310.154 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T22:35:11.349Z,1505169311.349 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:35:11.350Z,1505169311.350 [BPC1] No Fault, FailCount= 1
2017-09-11T22:35:12.864Z,1505169312.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-09-11T22:35:51.704Z,1505169351.704 [DAT](DEBUG): 
2017-09-11T22:35:51.706Z,1505169351.706 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:35:51.706Z,1505169351.706 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:35:51.708Z,1505169351.708 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:35:51.709Z,1505169351.709 [DAT](DEBUG): Jan  3 2000 01:17:19 
2017-09-11T22:35:52.093Z,1505169352.093 [DAT](DEBUG): Warning battery low
2017-09-11T22:35:52.891Z,1505169352.891 [DAT](INFO): entering command mode
2017-09-11T22:35:53.289Z,1505169353.289 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:53.691Z,1505169353.691 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:54.096Z,1505169354.096 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:54.491Z,1505169354.491 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:54.895Z,1505169354.895 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:55.295Z,1505169355.295 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:55.709Z,1505169355.709 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:56.106Z,1505169356.106 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:56.512Z,1505169356.512 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:56.906Z,1505169356.906 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:57.287Z,1505169357.287 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:57.695Z,1505169357.695 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:58.093Z,1505169358.093 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:58.496Z,1505169358.496 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:58.902Z,1505169358.902 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:59.282Z,1505169359.282 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:35:59.682Z,1505169359.682 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:00.088Z,1505169360.088 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:00.485Z,1505169360.485 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:00.910Z,1505169360.910 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:01.307Z,1505169361.307 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:01.700Z,1505169361.700 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:02.104Z,1505169362.104 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:02.493Z,1505169362.493 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:36:02.493Z,1505169362.493 [DAT] Communications Fault, FailCount= 5
2017-09-11T22:36:02.493Z,1505169362.493 [DAT](ERROR): Communications Fault
2017-09-11T22:36:02.561Z,1505169362.561 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:36:02.896Z,1505169362.896 [DAT](INFO): Powering down
2017-09-11T22:36:03.765Z,1505169363.765 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:36:03.765Z,1505169363.765 [DAT] No Fault, FailCount= 5
2017-09-11T22:36:06.119Z,1505169366.119 [DAT](INFO): Powering up
2017-09-11T22:36:06.119Z,1505169366.119 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:36:06.159Z,1505169366.159 [BPC1](ERROR): Failed to parse bank A battery data
2017-09-11T22:36:06.159Z,1505169366.159 [BPC1] Data Fault, FailCount= 1
2017-09-11T22:36:06.159Z,1505169366.159 [BPC1](ERROR): Data Fault
2017-09-11T22:36:06.187Z,1505169366.187 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T22:36:07.377Z,1505169367.377 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:36:07.377Z,1505169367.377 [BPC1] No Fault, FailCount= 1
2017-09-11T22:36:12.878Z,1505169372.878 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-09-11T22:36:26.071Z,1505169386.071 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-09-11T22:36:46.922Z,1505169406.922 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:36:46.924Z,1505169406.924 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2017-09-11T22:36:46.924Z,1505169406.924 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-09-11T22:36:46.926Z,1505169406.926 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:36:46.926Z,1505169406.926 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2017-09-11T22:36:46.926Z,1505169406.926 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-09-11T22:36:46.959Z,1505169406.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-09-11T22:36:46.959Z,1505169406.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-09-11T22:36:47.269Z,1505169407.269 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.405045
2017-09-11T22:36:47.363Z,1505169407.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-09-11T22:36:47.363Z,1505169407.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2017-09-11T22:36:47.368Z,1505169407.368 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-09-11T22:36:47.368Z,1505169407.368 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2017-09-11T22:36:47.739Z,1505169407.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T22:36:47.740Z,1505169407.740 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:36:47.740Z,1505169407.740 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:36:47.741Z,1505169407.741 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T22:36:47.741Z,1505169407.741 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:36:47.742Z,1505169407.742 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:36:48.135Z,1505169408.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-09-11T22:36:48.531Z,1505169408.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-09-11T22:36:48.993Z,1505169408.993 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2017-09-11T22:36:49.311Z,1505169409.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-09-11T22:36:49.718Z,1505169409.718 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-09-11T22:36:50.159Z,1505169410.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-09-11T22:36:50.534Z,1505169410.534 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-09-11T22:36:50.961Z,1505169410.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-09-11T22:36:51.376Z,1505169411.376 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2017-09-11T22:36:51.728Z,1505169411.728 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-09-11T22:36:52.116Z,1505169412.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-09-11T22:36:52.508Z,1505169412.508 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-09-11T22:36:52.939Z,1505169412.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-09-11T22:36:53.306Z,1505169413.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-09-11T22:36:53.738Z,1505169413.738 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-09-11T22:36:54.133Z,1505169414.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-09-11T22:36:54.518Z,1505169414.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-09-11T22:36:54.918Z,1505169414.918 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-09-11T22:36:55.312Z,1505169415.312 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-09-11T22:36:55.722Z,1505169415.722 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-09-11T22:36:56.103Z,1505169416.103 [DAT](DEBUG): 
2017-09-11T22:36:56.104Z,1505169416.104 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:36:56.105Z,1505169416.105 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:36:56.106Z,1505169416.106 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:36:56.107Z,1505169416.107 [DAT](DEBUG): Jan  3 2000 01:18:23 
2017-09-11T22:36:56.126Z,1505169416.126 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-09-11T22:36:56.506Z,1505169416.506 [DAT](DEBUG): Warning battery low
2017-09-11T22:36:56.536Z,1505169416.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-09-11T22:36:56.939Z,1505169416.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-09-11T22:36:57.300Z,1505169417.300 [DAT](INFO): entering command mode
2017-09-11T22:36:57.336Z,1505169417.336 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-09-11T22:36:57.694Z,1505169417.694 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:57.738Z,1505169417.738 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-09-11T22:36:58.095Z,1505169418.095 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:58.135Z,1505169418.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-09-11T22:36:58.496Z,1505169418.496 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:58.536Z,1505169418.536 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-09-11T22:36:58.897Z,1505169418.897 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:58.941Z,1505169418.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-09-11T22:36:59.293Z,1505169419.293 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:59.325Z,1505169419.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-09-11T22:36:59.701Z,1505169419.701 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:36:59.721Z,1505169419.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-09-11T22:37:00.104Z,1505169420.104 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:00.124Z,1505169420.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-09-11T22:37:00.506Z,1505169420.506 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:00.527Z,1505169420.527 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-09-11T22:37:00.903Z,1505169420.903 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:00.923Z,1505169420.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-09-11T22:37:01.284Z,1505169421.284 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:01.335Z,1505169421.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-09-11T22:37:01.692Z,1505169421.692 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:01.725Z,1505169421.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-09-11T22:37:02.093Z,1505169422.093 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:02.128Z,1505169422.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-09-11T22:37:02.519Z,1505169422.519 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:02.613Z,1505169422.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2017-09-11T22:37:02.901Z,1505169422.901 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:02.932Z,1505169422.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-09-11T22:37:03.319Z,1505169423.319 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:03.339Z,1505169423.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-09-11T22:37:03.698Z,1505169423.698 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:03.780Z,1505169423.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.9 s old, using for 20.0 s.
2017-09-11T22:37:04.093Z,1505169424.093 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:04.113Z,1505169424.113 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-09-11T22:37:04.498Z,1505169424.498 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:04.519Z,1505169424.519 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-09-11T22:37:04.894Z,1505169424.894 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:04.957Z,1505169424.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-09-11T22:37:05.301Z,1505169425.301 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:05.322Z,1505169425.322 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-09-11T22:37:05.711Z,1505169425.711 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:05.772Z,1505169425.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.9 s old, using for 20.0 s.
2017-09-11T22:37:06.110Z,1505169426.110 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:37:06.163Z,1505169426.163 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-09-11T22:37:06.509Z,1505169426.509 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:37:06.509Z,1505169426.509 [DAT] Communications Fault, FailCount= 6
2017-09-11T22:37:06.509Z,1505169426.509 [DAT](ERROR): Communications Fault
2017-09-11T22:37:06.529Z,1505169426.529 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-09-11T22:37:06.578Z,1505169426.578 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:37:06.907Z,1505169426.907 [DAT](INFO): Powering down
2017-09-11T22:37:07.754Z,1505169427.754 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:37:07.754Z,1505169427.754 [DAT] No Fault, FailCount= 6
2017-09-11T22:37:10.089Z,1505169430.089 [DAT](INFO): Powering up
2017-09-11T22:37:10.090Z,1505169430.089 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:37:47.961Z,1505169467.961 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003506
2017-09-11T22:37:59.198Z,1505169479.198 [DAT](DEBUG): 
2017-09-11T22:37:59.199Z,1505169479.199 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:37:59.200Z,1505169479.200 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:37:59.202Z,1505169479.202 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:37:59.202Z,1505169479.202 [DAT](DEBUG): Jan  3 2000 01:19:26 
2017-09-11T22:37:59.588Z,1505169479.588 [DAT](DEBUG): Warning battery low
2017-09-11T22:38:00.397Z,1505169480.397 [DAT](INFO): entering command mode
2017-09-11T22:38:00.797Z,1505169480.797 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:01.201Z,1505169481.201 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:01.576Z,1505169481.576 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:01.979Z,1505169481.979 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:02.386Z,1505169482.386 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:02.807Z,1505169482.807 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:03.207Z,1505169483.207 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:03.579Z,1505169483.579 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:03.982Z,1505169483.982 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:04.405Z,1505169484.405 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:04.808Z,1505169484.808 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:05.199Z,1505169485.199 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:05.590Z,1505169485.590 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:05.989Z,1505169485.989 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:06.397Z,1505169486.397 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:06.798Z,1505169486.798 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:07.194Z,1505169487.194 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:07.586Z,1505169487.586 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:07.989Z,1505169487.989 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:08.392Z,1505169488.392 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:08.801Z,1505169488.801 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:09.204Z,1505169489.204 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:09.577Z,1505169489.577 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:09.997Z,1505169489.997 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:38:10.407Z,1505169490.407 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:38:10.407Z,1505169490.407 [DAT] Communications Fault, FailCount= 7
2017-09-11T22:38:10.407Z,1505169490.407 [DAT](ERROR): Communications Fault
2017-09-11T22:38:10.478Z,1505169490.478 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:38:10.812Z,1505169490.812 [DAT](INFO): Powering down
2017-09-11T22:38:11.654Z,1505169491.654 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:38:11.654Z,1505169491.654 [DAT] No Fault, FailCount= 7
2017-09-11T22:38:13.987Z,1505169493.987 [DAT](INFO): Powering up
2017-09-11T22:38:13.987Z,1505169493.987 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:38:56.217Z,1505169536.217 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-09-11T22:33:56.2Z
2017-09-11T22:38:56.217Z,1505169536.217 [Default:CheckIn:Read_GPS] Stopped
2017-09-11T22:38:56.217Z,1505169536.217 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-09-11T22:38:56.604Z,1505169536.604 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-09-11T22:39:00.743Z,1505169540.743 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20170911T223031/Courier0004.lzma
2017-09-11T22:39:01.486Z,1505169541.486 [DataOverHttps](INFO): Moved sent file to Logs/20170911T223031/Courier0004.lzma.bak
2017-09-11T22:39:01.486Z,1505169541.486 [DataOverHttps](INFO): SBD MOMSN=5085912
2017-09-11T22:39:05.788Z,1505169545.788 [DAT](DEBUG): 
2017-09-11T22:39:05.790Z,1505169545.790 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:39:05.791Z,1505169545.791 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:39:05.792Z,1505169545.792 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:39:05.793Z,1505169545.793 [DAT](DEBUG): Jan  3 2000 01:20:30 
2017-09-11T22:39:05.815Z,1505169545.815 [DAT](DEBUG): Warning battery low
2017-09-11T22:39:06.383Z,1505169546.383 [DAT](INFO): entering command mode
2017-09-11T22:39:06.788Z,1505169546.788 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:07.184Z,1505169547.184 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:07.584Z,1505169547.584 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:10.847Z,1505169550.847 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:10.980Z,1505169550.980 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20170911T223031/Express0005.lzma
2017-09-11T22:39:11.024Z,1505169551.024 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:11.369Z,1505169551.369 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:11.730Z,1505169551.730 [DataOverHttps](INFO): Moved sent file to Logs/20170911T223031/Express0005.lzma.bak
2017-09-11T22:39:11.730Z,1505169551.730 [DataOverHttps](INFO): SBD MOMSN=5085914
2017-09-11T22:39:11.773Z,1505169551.773 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:12.410Z,1505169552.410 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:12.433Z,1505169552.433 [Default:CheckIn:Read_Iridium] Stopped
2017-09-11T22:39:12.433Z,1505169552.433 [Default:CheckIn:C.Wait] Running Loop=1
2017-09-11T22:39:12.433Z,1505169552.433 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-09-11T22:39:12.560Z,1505169552.560 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:39:15.843Z,1505169555.843 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:39:15.843Z,1505169555.843 [DAT] Communications Fault, FailCount= 8
2017-09-11T22:39:15.843Z,1505169555.843 [DAT](ERROR): Communications Fault
2017-09-11T22:39:15.939Z,1505169555.939 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:39:15.939Z,1505169555.939 [CBIT](CRITICAL): Communications Fault in component: DAT
2017-09-11T22:39:16.028Z,1505169556.028 [DAT](INFO): Powering down
2017-09-11T22:39:16.200Z,1505169556.200 [CBIT](INFO): Critical error at 20170911T223915
2017-09-11T22:39:48.002Z,1505169588.002 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:39:48.002Z,1505169588.002 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2017-09-11T22:39:48.002Z,1505169588.002 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-09-11T22:39:48.004Z,1505169588.004 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:39:48.005Z,1505169588.005 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2017-09-11T22:39:48.017Z,1505169588.017 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-09-11T22:39:48.032Z,1505169588.032 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-09-11T22:39:48.033Z,1505169588.033 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-09-11T22:39:48.448Z,1505169588.448 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-09-11T22:39:48.448Z,1505169588.448 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2017-09-11T22:39:48.449Z,1505169588.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-09-11T22:39:48.449Z,1505169588.449 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2017-09-11T22:39:48.837Z,1505169588.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T22:39:48.837Z,1505169588.837 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:39:48.838Z,1505169588.838 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:39:48.838Z,1505169588.838 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T22:39:48.839Z,1505169588.839 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:39:48.839Z,1505169588.839 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:39:49.242Z,1505169589.242 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-09-11T22:39:49.613Z,1505169589.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-09-11T22:39:50.015Z,1505169590.015 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-09-11T22:39:50.472Z,1505169590.472 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2017-09-11T22:39:50.828Z,1505169590.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-09-11T22:39:51.266Z,1505169591.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.3 s old, using for 20.0 s.
2017-09-11T22:39:51.629Z,1505169591.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-09-11T22:39:52.043Z,1505169592.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-09-11T22:39:52.455Z,1505169592.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s.
2017-09-11T22:39:52.851Z,1505169592.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-09-11T22:39:53.236Z,1505169593.236 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-09-11T22:39:53.625Z,1505169593.625 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-09-11T22:39:54.004Z,1505169594.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-09-11T22:39:54.414Z,1505169594.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-09-11T22:39:54.823Z,1505169594.823 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-09-11T22:39:55.222Z,1505169595.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-09-11T22:39:55.614Z,1505169595.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-09-11T22:39:56.015Z,1505169596.015 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-09-11T22:39:56.420Z,1505169596.420 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-09-11T22:39:56.825Z,1505169596.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-09-11T22:39:57.223Z,1505169597.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-09-11T22:39:57.620Z,1505169597.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-09-11T22:39:58.021Z,1505169598.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-09-11T22:39:58.423Z,1505169598.423 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-09-11T22:39:58.827Z,1505169598.827 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-09-11T22:39:59.233Z,1505169599.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-09-11T22:39:59.611Z,1505169599.611 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-09-11T22:39:59.999Z,1505169599.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-09-11T22:40:00.413Z,1505169600.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-09-11T22:40:00.821Z,1505169600.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-09-11T22:40:01.219Z,1505169601.219 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-09-11T22:40:01.608Z,1505169601.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-09-11T22:40:02.002Z,1505169602.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-09-11T22:40:02.425Z,1505169602.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-09-11T22:40:02.821Z,1505169602.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-09-11T22:40:03.225Z,1505169603.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-09-11T22:40:03.665Z,1505169603.665 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2017-09-11T22:40:04.006Z,1505169604.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-09-11T22:40:04.463Z,1505169604.463 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.5 s old, using for 20.0 s.
2017-09-11T22:40:04.815Z,1505169604.815 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-09-11T22:40:05.252Z,1505169605.252 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.3 s old, using for 20.0 s.
2017-09-11T22:40:05.612Z,1505169605.612 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-09-11T22:40:06.042Z,1505169606.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-09-11T22:40:06.457Z,1505169606.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.5 s old, using for 20.0 s.
2017-09-11T22:40:06.820Z,1505169606.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-09-11T22:40:07.247Z,1505169607.247 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-09-11T22:40:07.614Z,1505169607.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-09-11T22:40:32.596Z,1505169632.596 [PNI_TCM](ERROR): CRC does not match. Expected:0x60575 got:0x50048
2017-09-11T22:40:43.448Z,1505169643.448 [CBIT](DEBUG): EFC running - data check-sum false
2017-09-11T22:41:24.464Z,1505169684.464 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-09-11T22:41:24.464Z,1505169684.464 [NAL9602] Data Fault, FailCount= 2
2017-09-11T22:41:24.464Z,1505169684.464 [NAL9602](ERROR): Data Fault
2017-09-11T22:41:24.554Z,1505169684.554 [CBIT](ERROR): Data Fault in component: NAL9602
2017-09-11T22:41:24.862Z,1505169684.862 [NAL9602](INFO): Powering down
2017-09-11T22:41:25.755Z,1505169685.755 [CBIT](INFO): Clearing failed state for component NAL9602
2017-09-11T22:41:25.755Z,1505169685.755 [NAL9602] No Fault, FailCount= 2
2017-09-11T22:41:30.064Z,1505169690.064 [NAL9602](INFO): Powering up NAL9602
2017-09-11T22:41:40.869Z,1505169700.869 [NAL9602](INFO): NAL9602 initialized
2017-09-11T22:42:43.660Z,1505169763.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-09-11T22:42:48.923Z,1505169768.923 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:42:48.924Z,1505169768.924 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2017-09-11T22:42:48.924Z,1505169768.924 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-09-11T22:42:48.926Z,1505169768.926 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:42:48.926Z,1505169768.926 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2017-09-11T22:42:48.926Z,1505169768.926 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-09-11T22:42:48.959Z,1505169768.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-09-11T22:42:48.959Z,1505169768.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-09-11T22:42:49.334Z,1505169769.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-09-11T22:42:49.334Z,1505169769.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2017-09-11T22:42:49.335Z,1505169769.335 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-09-11T22:42:49.335Z,1505169769.335 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2017-09-11T22:42:49.730Z,1505169769.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T22:42:49.731Z,1505169769.731 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:42:49.731Z,1505169769.731 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:42:49.732Z,1505169769.732 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T22:42:49.732Z,1505169769.732 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:42:49.732Z,1505169769.732 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:42:50.136Z,1505169770.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-09-11T22:42:50.535Z,1505169770.535 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-09-11T22:42:50.933Z,1505169770.933 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-09-11T22:42:51.308Z,1505169771.308 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-09-11T22:42:51.713Z,1505169771.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-09-11T22:42:52.112Z,1505169772.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-09-11T22:42:52.514Z,1505169772.514 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-09-11T22:42:52.923Z,1505169772.923 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-09-11T22:42:53.326Z,1505169773.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-09-11T22:42:53.725Z,1505169773.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-09-11T22:42:54.124Z,1505169774.124 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-09-11T22:42:54.523Z,1505169774.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-09-11T22:42:54.921Z,1505169774.921 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-09-11T22:42:55.324Z,1505169775.324 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-09-11T22:42:55.736Z,1505169775.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-09-11T22:42:56.142Z,1505169776.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-09-11T22:42:56.528Z,1505169776.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-09-11T22:42:56.936Z,1505169776.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-09-11T22:42:57.388Z,1505169777.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s.
2017-09-11T22:42:57.716Z,1505169777.716 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-09-11T22:42:58.152Z,1505169778.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-09-11T22:42:58.512Z,1505169778.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-09-11T22:42:58.957Z,1505169778.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-09-11T22:42:59.321Z,1505169779.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-09-11T22:42:59.760Z,1505169779.760 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-09-11T22:43:00.122Z,1505169780.122 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-09-11T22:43:00.519Z,1505169780.519 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-09-11T22:43:00.929Z,1505169780.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-09-11T22:43:01.332Z,1505169781.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-09-11T22:43:01.726Z,1505169781.726 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-09-11T22:43:02.135Z,1505169782.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-09-11T22:43:02.528Z,1505169782.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-09-11T22:43:02.927Z,1505169782.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-09-11T22:43:03.299Z,1505169783.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-09-11T22:43:03.731Z,1505169783.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-09-11T22:43:04.107Z,1505169784.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-09-11T22:43:04.520Z,1505169784.520 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-09-11T22:43:04.917Z,1505169784.917 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-09-11T22:43:05.319Z,1505169785.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-09-11T22:43:05.727Z,1505169785.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-09-11T22:43:06.117Z,1505169786.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-09-11T22:43:06.523Z,1505169786.523 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-09-11T22:43:06.921Z,1505169786.921 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-09-11T22:43:07.317Z,1505169787.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-09-11T22:43:07.721Z,1505169787.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-09-11T22:43:08.133Z,1505169788.133 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-09-11T22:43:08.537Z,1505169788.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-09-11T22:43:43.659Z,1505169823.659 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2017-09-11T22:44:08.524Z,1505169848.524 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T22:44:08.524Z,1505169848.524 [BPC1] Data Fault, FailCount= 1
2017-09-11T22:44:08.524Z,1505169848.524 [BPC1](ERROR): Data Fault
2017-09-11T22:44:08.590Z,1505169848.590 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T22:44:09.760Z,1505169849.760 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:44:09.760Z,1505169849.760 [BPC1] No Fault, FailCount= 1
2017-09-11T22:44:12.917Z,1505169852.917 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-09-11T22:44:12.918Z,1505169852.918 [Default:CheckIn:C.Wait] Stopped
2017-09-11T22:44:12.918Z,1505169852.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-09-11T22:44:12.918Z,1505169852.918 [Default:CheckIn:D] Running Loop=1
2017-09-11T22:44:13.338Z,1505169853.338 [Default:CheckIn:D] Stopped
2017-09-11T22:44:13.338Z,1505169853.338 [Default:CheckIn:E] Running Loop=1
2017-09-11T22:44:13.723Z,1505169853.723 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.511594 min 
2017-09-11T22:44:13.726Z,1505169853.726 [Default:CheckIn:E] Stopped
2017-09-11T22:44:13.726Z,1505169853.726 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-09-11T22:44:13.726Z,1505169853.726 [Default:CheckIn] Stopped
2017-09-11T22:44:13.726Z,1505169853.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-09-11T22:44:13.726Z,1505169853.726 [Default:CheckIn](INFO): Running loop #2
2017-09-11T22:44:13.726Z,1505169853.726 [Default:CheckIn] Running Loop=2
2017-09-11T22:44:13.727Z,1505169853.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-09-11T22:44:13.727Z,1505169853.727 [Default:CheckIn:Read_GPS] Running Loop=1
2017-09-11T22:44:17.449Z,1505169857.449 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:44:17.450Z,1505169857.450 [DAT] No Fault, FailCount= 8
2017-09-11T22:44:17.535Z,1505169857.535 [DAT](INFO): Powering up
2017-09-11T22:44:17.535Z,1505169857.535 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:45:07.129Z,1505169907.129 [DAT](DEBUG): 
2017-09-11T22:45:07.131Z,1505169907.131 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:45:07.132Z,1505169907.132 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:45:07.134Z,1505169907.134 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:45:07.532Z,1505169907.532 [DAT](DEBUG): Jan  3 2000 01:26:34 
2017-09-11T22:45:07.937Z,1505169907.937 [DAT](DEBUG): Warning battery low
2017-09-11T22:45:08.711Z,1505169908.711 [DAT](INFO): entering command mode
2017-09-11T22:45:09.112Z,1505169909.112 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:09.513Z,1505169909.513 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:09.911Z,1505169909.911 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:10.315Z,1505169910.315 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:10.728Z,1505169910.728 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:11.113Z,1505169911.113 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:11.511Z,1505169911.511 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:11.913Z,1505169911.913 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:12.319Z,1505169912.319 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:12.709Z,1505169912.709 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:13.129Z,1505169913.129 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:13.527Z,1505169913.527 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:13.928Z,1505169913.928 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:14.308Z,1505169914.308 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:45:17.612Z,1505169917.612 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:45:17.612Z,1505169917.612 [DAT] Communications Fault, FailCount= 1
2017-09-11T22:45:17.612Z,1505169917.612 [DAT](ERROR): Communications Fault
2017-09-11T22:45:17.708Z,1505169917.708 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:45:17.786Z,1505169917.786 [DAT](INFO): Powering down
2017-09-11T22:45:18.621Z,1505169918.621 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:45:18.622Z,1505169918.622 [DAT] No Fault, FailCount= 1
2017-09-11T22:45:20.943Z,1505169920.943 [DAT](INFO): Powering up
2017-09-11T22:45:20.943Z,1505169920.943 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:45:45.419Z,1505169945.419 [CBIT](INFO): Clearing failed state for component DropWeight
2017-09-11T22:45:45.419Z,1505169945.419 [DropWeight] No Fault, FailCount= 1
2017-09-11T22:45:49.786Z,1505169949.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:45:49.787Z,1505169949.787 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2017-09-11T22:45:49.787Z,1505169949.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-09-11T22:45:49.789Z,1505169949.789 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T22:45:49.789Z,1505169949.789 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2017-09-11T22:45:49.789Z,1505169949.789 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-09-11T22:45:49.816Z,1505169949.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-09-11T22:45:49.816Z,1505169949.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-09-11T22:45:50.204Z,1505169950.204 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-09-11T22:45:50.204Z,1505169950.204 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2017-09-11T22:45:50.205Z,1505169950.205 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-09-11T22:45:50.205Z,1505169950.205 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2017-09-11T22:45:50.598Z,1505169950.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T22:45:50.598Z,1505169950.598 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:45:50.599Z,1505169950.599 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:45:50.599Z,1505169950.599 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T22:45:50.600Z,1505169950.600 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T22:45:50.600Z,1505169950.600 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T22:45:50.999Z,1505169950.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-09-11T22:45:51.410Z,1505169951.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-09-11T22:45:51.816Z,1505169951.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-09-11T22:45:52.198Z,1505169952.198 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-09-11T22:45:52.583Z,1505169952.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-09-11T22:45:52.980Z,1505169952.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-09-11T22:45:53.395Z,1505169953.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-09-11T22:45:53.780Z,1505169953.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-09-11T22:45:54.196Z,1505169954.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-09-11T22:45:54.608Z,1505169954.608 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-09-11T22:45:55.001Z,1505169955.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-09-11T22:45:55.392Z,1505169955.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-09-11T22:45:55.772Z,1505169955.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-09-11T22:45:56.200Z,1505169956.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-09-11T22:45:56.584Z,1505169956.584 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-09-11T22:45:56.975Z,1505169956.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-09-11T22:45:57.395Z,1505169957.395 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-09-11T22:45:57.789Z,1505169957.789 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-09-11T22:45:58.205Z,1505169958.205 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-09-11T22:45:58.577Z,1505169958.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-09-11T22:45:58.985Z,1505169958.985 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-09-11T22:45:59.401Z,1505169959.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-09-11T22:45:59.779Z,1505169959.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-09-11T22:46:00.201Z,1505169960.201 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-09-11T22:46:00.585Z,1505169960.585 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-09-11T22:46:00.984Z,1505169960.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-09-11T22:46:01.377Z,1505169961.377 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-09-11T22:46:01.789Z,1505169961.789 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-09-11T22:46:02.188Z,1505169962.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-09-11T22:46:02.587Z,1505169962.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-09-11T22:46:02.979Z,1505169962.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-09-11T22:46:03.393Z,1505169963.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-09-11T22:46:03.855Z,1505169963.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s.
2017-09-11T22:46:04.206Z,1505169964.206 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-09-11T22:46:04.579Z,1505169964.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-09-11T22:46:05.009Z,1505169965.009 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-09-11T22:46:05.412Z,1505169965.412 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-09-11T22:46:05.804Z,1505169965.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-09-11T22:46:06.232Z,1505169966.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-09-11T22:46:06.587Z,1505169966.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-09-11T22:46:07.006Z,1505169967.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-09-11T22:46:07.391Z,1505169967.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-09-11T22:46:07.783Z,1505169967.783 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-09-11T22:46:08.180Z,1505169968.180 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-09-11T22:46:08.596Z,1505169968.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-09-11T22:46:09.001Z,1505169969.001 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-09-11T22:46:09.389Z,1505169969.389 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-09-11T22:46:09.769Z,1505169969.769 [DAT](DEBUG): 
2017-09-11T22:46:09.771Z,1505169969.771 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:46:09.772Z,1505169969.772 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:46:09.781Z,1505169969.781 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:46:09.782Z,1505169969.782 [DAT](DEBUG): Jan  3 2000 01:27:37 
2017-09-11T22:46:10.166Z,1505169970.166 [DAT](DEBUG): Warning battery low
2017-09-11T22:46:10.961Z,1505169970.961 [DAT](INFO): entering command mode
2017-09-11T22:46:11.369Z,1505169971.369 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:11.749Z,1505169971.749 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:12.157Z,1505169972.157 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:12.579Z,1505169972.579 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:12.970Z,1505169972.970 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:13.357Z,1505169973.357 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:13.758Z,1505169973.758 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:14.158Z,1505169974.158 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:14.567Z,1505169974.567 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:17.912Z,1505169977.912 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:18.103Z,1505169978.103 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:18.482Z,1505169978.482 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:18.896Z,1505169978.896 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:19.259Z,1505169979.259 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:19.664Z,1505169979.664 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:20.064Z,1505169980.064 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:20.470Z,1505169980.470 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:20.873Z,1505169980.873 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:46:21.277Z,1505169981.277 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:46:21.277Z,1505169981.277 [DAT] Communications Fault, FailCount= 2
2017-09-11T22:46:21.277Z,1505169981.277 [DAT](ERROR): Communications Fault
2017-09-11T22:46:21.348Z,1505169981.348 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:46:21.676Z,1505169981.676 [DAT](INFO): Powering down
2017-09-11T22:46:22.544Z,1505169982.544 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:46:22.544Z,1505169982.544 [DAT] No Fault, FailCount= 2
2017-09-11T22:46:24.881Z,1505169984.881 [DAT](INFO): Powering up
2017-09-11T22:46:24.881Z,1505169984.881 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:46:32.077Z,1505169992.077 [BPC1](ERROR): Failed to parse bank A battery data
2017-09-11T22:46:32.077Z,1505169992.077 [BPC1] Data Fault, FailCount= 1
2017-09-11T22:46:32.077Z,1505169992.077 [BPC1](ERROR): Data Fault
2017-09-11T22:46:32.163Z,1505169992.163 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T22:46:33.349Z,1505169993.349 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:46:33.349Z,1505169993.349 [BPC1] No Fault, FailCount= 1
2017-09-11T22:47:13.658Z,1505170033.658 [DAT](DEBUG): 
2017-09-11T22:47:13.659Z,1505170033.659 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:47:13.660Z,1505170033.660 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:47:13.661Z,1505170033.661 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:47:13.662Z,1505170033.662 [DAT](DEBUG): Jan  3 2000 01:28:41 
2017-09-11T22:47:14.090Z,1505170034.090 [DAT](DEBUG): Warning battery low
2017-09-11T22:47:14.874Z,1505170034.874 [DAT](INFO): entering command mode
2017-09-11T22:47:18.172Z,1505170038.172 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:47:18.375Z,1505170038.375 [NAL9602](INFO): GPS fix at 20170911T224520: (36.802613, -121.787938)
2017-09-11T22:47:18.392Z,1505170038.392 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:47:18.454Z,1505170038.454 [Default:CheckIn:Read_GPS] Stopped
2017-09-11T22:47:18.454Z,1505170038.454 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-09-11T22:47:18.745Z,1505170038.745 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:47:19.262Z,1505170039.262 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:47:19.542Z,1505170039.542 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:47:19.950Z,1505170039.950 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:47:25.516Z,1505170045.516 [NAL9602](INFO): Powering down
2017-09-11T22:47:25.582Z,1505170045.582 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:47:25.582Z,1505170045.582 [DAT] Communications Fault, FailCount= 3
2017-09-11T22:47:25.582Z,1505170045.582 [DAT](ERROR): Communications Fault
2017-09-11T22:47:25.770Z,1505170045.770 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:47:25.792Z,1505170045.792 [DataOverHttps](INFO): Sending 275 bytes from file Logs/20170911T223031/Courier0008.lzma
2017-09-11T22:47:25.849Z,1505170045.849 [DAT](INFO): Powering down
2017-09-11T22:47:26.598Z,1505170046.598 [DataOverHttps](INFO): Moved sent file to Logs/20170911T223031/Courier0008.lzma.bak
2017-09-11T22:47:26.598Z,1505170046.598 [DataOverHttps](INFO): SBD MOMSN=5085923
2017-09-11T22:47:26.774Z,1505170046.774 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:47:26.774Z,1505170046.774 [DAT] No Fault, FailCount= 3
2017-09-11T22:47:31.230Z,1505170051.230 [DAT](INFO): Powering up
2017-09-11T22:47:31.230Z,1505170051.230 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:47:37.477Z,1505170057.477 [DataOverHttps](INFO): Sending 394 bytes from file Logs/20170911T223031/Express0009.lzma
2017-09-11T22:47:38.255Z,1505170058.255 [DataOverHttps](INFO): Moved sent file to Logs/20170911T223031/Express0009.lzma.bak
2017-09-11T22:47:38.255Z,1505170058.255 [DataOverHttps](INFO): SBD MOMSN=5085927
2017-09-11T22:47:38.993Z,1505170058.993 [Default:CheckIn:Read_Iridium] Stopped
2017-09-11T22:47:38.993Z,1505170058.993 [Default:CheckIn:C.Wait] Running Loop=1
2017-09-11T22:47:38.993Z,1505170058.993 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-09-11T22:47:59.933Z,1505170079.933 [BPC1](ERROR): Failed to receive battery data
2017-09-11T22:47:59.933Z,1505170079.933 [BPC1] Communications Fault, FailCount= 1
2017-09-11T22:47:59.933Z,1505170079.933 [BPC1](ERROR): Communications Fault
2017-09-11T22:47:59.999Z,1505170079.999 [CBIT](ERROR): Communications Fault in component: BPC1
2017-09-11T22:48:01.173Z,1505170081.173 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:48:01.173Z,1505170081.173 [BPC1] No Fault, FailCount= 1
2017-09-11T22:48:16.330Z,1505170096.330 [BPC1](ERROR): Failed to parse message.
2017-09-11T22:48:16.331Z,1505170096.331 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T22:48:16.331Z,1505170096.331 [BPC1] Data Fault, FailCount= 2
2017-09-11T22:48:16.331Z,1505170096.331 [BPC1](ERROR): Data Fault
2017-09-11T22:48:16.403Z,1505170096.403 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T22:48:17.580Z,1505170097.580 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:48:17.580Z,1505170097.580 [BPC1] No Fault, FailCount= 2
2017-09-11T22:48:19.928Z,1505170099.928 [DAT](DEBUG): 
2017-09-11T22:48:19.930Z,1505170099.930 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:48:19.931Z,1505170099.931 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:48:19.932Z,1505170099.932 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:48:20.315Z,1505170100.315 [DAT](DEBUG): Jan  3 2000 01:29:47 
2017-09-11T22:48:20.711Z,1505170100.711 [DAT](DEBUG): Warning battery low
2017-09-11T22:48:21.516Z,1505170101.516 [DAT](INFO): entering command mode
2017-09-11T22:48:21.923Z,1505170101.923 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:22.323Z,1505170102.323 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:22.724Z,1505170102.724 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:23.109Z,1505170103.109 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:23.510Z,1505170103.510 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:23.911Z,1505170103.911 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:24.318Z,1505170104.318 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:24.719Z,1505170104.719 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:25.117Z,1505170105.117 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:25.520Z,1505170105.520 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:25.914Z,1505170105.914 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:26.310Z,1505170106.310 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:26.711Z,1505170106.711 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:27.112Z,1505170107.112 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:27.512Z,1505170107.512 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:27.913Z,1505170107.913 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:28.316Z,1505170108.316 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:28.722Z,1505170108.722 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:29.122Z,1505170109.122 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:29.522Z,1505170109.522 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:29.912Z,1505170109.912 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:30.313Z,1505170110.313 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:30.709Z,1505170110.709 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:31.110Z,1505170111.110 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:48:31.517Z,1505170111.517 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:48:31.518Z,1505170111.518 [DAT] Communications Fault, FailCount= 4
2017-09-11T22:48:31.518Z,1505170111.518 [DAT](ERROR): Communications Fault
2017-09-11T22:48:31.612Z,1505170111.612 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:48:31.922Z,1505170111.922 [DAT](INFO): Powering down
2017-09-11T22:48:32.778Z,1505170112.778 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:48:32.778Z,1505170112.778 [DAT] No Fault, FailCount= 4
2017-09-11T22:48:35.124Z,1505170115.124 [DAT](INFO): Powering up
2017-09-11T22:48:35.124Z,1505170115.124 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:49:12.669Z,1505170152.669 [BPC1](ERROR): Failed to receive battery data
2017-09-11T22:49:12.669Z,1505170152.669 [BPC1] Communications Fault, FailCount= 1
2017-09-11T22:49:12.670Z,1505170152.670 [BPC1](ERROR): Communications Fault
2017-09-11T22:49:12.722Z,1505170152.722 [CBIT](ERROR): Communications Fault in component: BPC1
2017-09-11T22:49:13.926Z,1505170153.926 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:49:13.927Z,1505170153.927 [BPC1] No Fault, FailCount= 1
2017-09-11T22:49:23.850Z,1505170163.850 [DAT](DEBUG): 
2017-09-11T22:49:23.851Z,1505170163.851 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:49:23.852Z,1505170163.852 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:49:23.854Z,1505170163.854 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:49:23.855Z,1505170163.855 [DAT](DEBUG): Jan  3 2000 01:30:51 
2017-09-11T22:49:24.649Z,1505170164.649 [DAT](DEBUG): Warning battery low
2017-09-11T22:49:25.456Z,1505170165.456 [DAT](INFO): entering command mode
2017-09-11T22:49:25.859Z,1505170165.859 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:26.253Z,1505170166.253 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:26.661Z,1505170166.661 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:27.055Z,1505170167.055 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:27.449Z,1505170167.449 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:27.856Z,1505170167.856 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:28.248Z,1505170168.248 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:28.649Z,1505170168.649 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:29.053Z,1505170169.053 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:29.458Z,1505170169.458 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:29.858Z,1505170169.858 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:30.260Z,1505170170.260 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:30.658Z,1505170170.658 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:31.061Z,1505170171.061 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:31.468Z,1505170171.468 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:31.844Z,1505170171.844 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:32.249Z,1505170172.249 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:32.655Z,1505170172.655 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:33.052Z,1505170173.052 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:33.455Z,1505170173.455 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:33.862Z,1505170173.862 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:34.259Z,1505170174.259 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:34.657Z,1505170174.657 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:35.067Z,1505170175.067 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:49:35.448Z,1505170175.448 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:49:35.448Z,1505170175.448 [DAT] Communications Fault, FailCount= 5
2017-09-11T22:49:35.448Z,1505170175.448 [DAT](ERROR): Communications Fault
2017-09-11T22:49:35.541Z,1505170175.541 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:49:35.849Z,1505170175.849 [DAT](INFO): Powering down
2017-09-11T22:49:36.760Z,1505170176.760 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:49:36.760Z,1505170176.760 [DAT] No Fault, FailCount= 5
2017-09-11T22:49:39.064Z,1505170179.064 [DAT](INFO): Powering up
2017-09-11T22:49:39.065Z,1505170179.065 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:50:27.850Z,1505170227.850 [DAT](DEBUG): 
2017-09-11T22:50:27.852Z,1505170227.852 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:50:27.852Z,1505170227.852 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:50:27.854Z,1505170227.854 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:50:27.855Z,1505170227.855 [DAT](DEBUG): Jan  3 2000 01:31:55 
2017-09-11T22:50:28.653Z,1505170228.653 [DAT](DEBUG): Warning battery low
2017-09-11T22:50:29.438Z,1505170229.438 [DAT](INFO): entering command mode
2017-09-11T22:50:29.839Z,1505170229.839 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:29.851Z,1505170229.851 [BPC1](ERROR): Failed to receive battery data
2017-09-11T22:50:29.851Z,1505170229.851 [BPC1] Communications Fault, FailCount= 1
2017-09-11T22:50:29.851Z,1505170229.851 [BPC1](ERROR): Communications Fault
2017-09-11T22:50:29.932Z,1505170229.932 [CBIT](ERROR): Communications Fault in component: BPC1
2017-09-11T22:50:30.248Z,1505170230.248 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:30.650Z,1505170230.650 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:31.051Z,1505170231.051 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:31.114Z,1505170231.114 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T22:50:31.114Z,1505170231.114 [BPC1] No Fault, FailCount= 1
2017-09-11T22:50:31.447Z,1505170231.447 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:31.851Z,1505170231.851 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:32.239Z,1505170232.239 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:32.638Z,1505170232.638 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:33.039Z,1505170233.039 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:33.442Z,1505170233.442 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:33.841Z,1505170233.841 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:34.241Z,1505170234.241 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:34.646Z,1505170234.646 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:35.048Z,1505170235.048 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:35.449Z,1505170235.449 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:35.851Z,1505170235.851 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:36.251Z,1505170236.251 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:36.639Z,1505170236.639 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:37.039Z,1505170237.039 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:37.438Z,1505170237.438 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:37.840Z,1505170237.840 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:38.241Z,1505170238.241 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:38.647Z,1505170238.647 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:39.048Z,1505170239.048 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:50:39.447Z,1505170239.447 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: CONNECT 02400 bits/sec, available:  1 of 4

user:1>
2017-09-11T22:50:39.447Z,1505170239.447 [DAT] Communications Fault, FailCount= 6
2017-09-11T22:50:39.447Z,1505170239.447 [DAT](ERROR): Communications Fault
2017-09-11T22:50:39.520Z,1505170239.520 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T22:50:39.848Z,1505170239.848 [DAT](INFO): Powering down
2017-09-11T22:50:43.886Z,1505170243.886 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T22:50:43.887Z,1505170243.887 [DAT] No Fault, FailCount= 6
2017-09-11T22:50:43.887Z,1505170243.887 [CBIT](DEBUG): EFC running - data check-sum false
2017-09-11T22:50:43.945Z,1505170243.945 [DAT](INFO): Powering up
2017-09-11T22:50:43.945Z,1505170243.945 [DAT](DEBUG): Initializing DAT.
2017-09-11T22:51:32.735Z,1505170292.735 [DAT](DEBUG): 
2017-09-11T22:51:32.736Z,1505170292.736 [DAT](DEBUG): Teledyne Benthos ATM-900 Series
2017-09-11T22:51:32.737Z,1505170292.737 [DAT](DEBUG): MF Frequency Band
2017-09-11T22:51:32.738Z,1505170292.738 [DAT](DEBUG): Directional Acoustic Transponder version 8.3.1
2017-09-11T22:51:32.741Z,1505170292.741 [DAT](DEBUG): Jan  3 2000 01:33:00 
2017-09-11T22:51:33.125Z,1505170293.125 [DAT](DEBUG): Warning battery low
2017-09-11T22:51:33.938Z,1505170293.938 [DAT](INFO): entering command mode
2017-09-11T22:51:34.320Z,1505170294.320 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:34.734Z,1505170294.734 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:35.136Z,1505170295.136 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:35.529Z,1505170295.529 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:35.935Z,1505170295.935 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:36.324Z,1505170296.324 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:36.721Z,1505170296.721 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:37.125Z,1505170297.125 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:37.538Z,1505170297.538 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T22:51:3