2017-09-11T23:32:20.000Z,1505172741.000 [Supervisor](DEBUG): Initializing supervisor.
2017-09-11T23:32:21.003Z,1505172741.003 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-09-11T23:32:21.004Z,1505172741.004 [SyncHandler](INFO): Protected caller Thread ID is 771
2017-09-11T23:32:21.004Z,1505172741.004 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-09-11T23:32:21.005Z,1505172741.005 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-09-11T23:32:21.006Z,1505172741.006 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 772
2017-09-11T23:32:21.009Z,1505172741.009 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-09-11T23:32:21.023Z,1505172741.023 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-09-11T23:32:21.025Z,1505172741.025 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-09-11T23:32:21.025Z,1505172741.025 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 773
2017-09-11T23:32:21.026Z,1505172741.026 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-09-11T23:32:21.027Z,1505172741.027 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-09-11T23:32:21.027Z,1505172741.027 [logger ThreadHandler](INFO): Protected caller Thread ID is 774
2017-09-11T23:32:21.030Z,1505172741.030 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-09-11T23:32:21.031Z,1505172741.031 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-09-11T23:32:21.034Z,1505172741.034 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-09-11T23:32:21.265Z,1505172741.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-09-11T23:32:21.266Z,1505172741.266 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-09-11T23:32:21.531Z,1505172741.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-09-11T23:32:21.531Z,1505172741.531 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-09-11T23:32:22.145Z,1505172742.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-09-11T23:32:22.146Z,1505172742.146 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-09-11T23:32:22.301Z,1505172742.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-09-11T23:32:22.302Z,1505172742.302 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-09-11T23:32:22.389Z,1505172742.389 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-09-11T23:32:22.617Z,1505172742.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-09-11T23:32:22.619Z,1505172742.619 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-09-11T23:32:22.730Z,1505172742.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-09-11T23:32:22.731Z,1505172742.731 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-09-11T23:32:23.073Z,1505172743.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-09-11T23:32:23.074Z,1505172743.074 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-09-11T23:32:23.517Z,1505172743.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-09-11T23:32:23.518Z,1505172743.518 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-09-11T23:32:23.901Z,1505172743.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-09-11T23:32:23.901Z,1505172743.901 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-09-11T23:32:27.252Z,1505172747.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-09-11T23:32:27.254Z,1505172747.254 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-09-11T23:32:27.474Z,1505172747.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-09-11T23:32:27.474Z,1505172747.474 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-09-11T23:32:27.584Z,1505172747.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-09-11T23:32:27.585Z,1505172747.585 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-09-11T23:32:28.151Z,1505172748.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-09-11T23:32:28.152Z,1505172748.152 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-09-11T23:32:28.271Z,1505172748.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-09-11T23:32:28.273Z,1505172748.273 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-09-11T23:32:28.275Z,1505172748.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-09-11T23:32:28.605Z,1505172748.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-09-11T23:32:28.605Z,1505172748.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-09-11T23:32:31.697Z,1505172751.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-09-11T23:32:31.795Z,1505172751.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-09-11T23:32:31.906Z,1505172751.906 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-09-11T23:32:31.999Z,1505172751.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-09-11T23:32:32.142Z,1505172752.142 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-09-11T23:32:32.316Z,1505172752.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-09-11T23:32:32.421Z,1505172752.421 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-09-11T23:32:32.521Z,1505172752.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-09-11T23:32:32.613Z,1505172752.613 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-09-11T23:32:32.818Z,1505172752.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-09-11T23:32:32.900Z,1505172752.900 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-09-11T23:32:32.911Z,1505172752.911 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-09-11T23:32:33.076Z,1505172753.076 [SBIT](DEBUG): Construct Startup Built In Test.
2017-09-11T23:32:33.112Z,1505172753.112 [SBIT] Loaded
2017-09-11T23:32:33.113Z,1505172753.113 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-09-11T23:32:33.114Z,1505172753.114 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-09-11T23:32:33.149Z,1505172753.149 [IBIT] Loaded
2017-09-11T23:32:33.149Z,1505172753.149 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-09-11T23:32:33.153Z,1505172753.153 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-09-11T23:32:33.349Z,1505172753.349 [CBIT] Loaded
2017-09-11T23:32:33.350Z,1505172753.350 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-09-11T23:32:33.350Z,1505172753.350 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-09-11T23:32:33.351Z,1505172753.351 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-09-11T23:32:33.449Z,1505172753.449 [VerticalControl](DEBUG): Construct VerticalControl.
2017-09-11T23:32:33.570Z,1505172753.570 [VerticalControl] Loaded
2017-09-11T23:32:33.570Z,1505172753.570 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-09-11T23:32:33.571Z,1505172753.571 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-09-11T23:32:33.649Z,1505172753.649 [HorizontalControl] Loaded
2017-09-11T23:32:33.649Z,1505172753.649 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-09-11T23:32:33.650Z,1505172753.650 [SpeedControl](DEBUG): Construct SpeedControl.
2017-09-11T23:32:33.657Z,1505172753.657 [SpeedControl] Loaded
2017-09-11T23:32:33.658Z,1505172753.658 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-09-11T23:32:33.658Z,1505172753.658 [LoopControl](DEBUG): Construct LoopControl.
2017-09-11T23:32:33.659Z,1505172753.659 [LoopControl] Loaded
2017-09-11T23:32:33.660Z,1505172753.660 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-09-11T23:32:33.660Z,1505172753.660 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-09-11T23:32:33.662Z,1505172753.662 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-09-11T23:32:33.791Z,1505172753.791 [DepthRateCalculator] Loaded
2017-09-11T23:32:33.791Z,1505172753.791 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-09-11T23:32:33.817Z,1505172753.817 [PitchRateCalculator] Loaded
2017-09-11T23:32:33.817Z,1505172753.817 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-09-11T23:32:33.837Z,1505172753.837 [SpeedCalculator] Loaded
2017-09-11T23:32:33.837Z,1505172753.837 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-09-11T23:32:33.863Z,1505172753.863 [TempGradientCalculator] Loaded
2017-09-11T23:32:33.864Z,1505172753.864 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-09-11T23:32:33.882Z,1505172753.882 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-09-11T23:32:33.883Z,1505172753.883 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-09-11T23:32:33.889Z,1505172753.889 [YawRateCalculator] Loaded
2017-09-11T23:32:33.889Z,1505172753.889 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-09-11T23:32:33.890Z,1505172753.890 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-09-11T23:32:33.890Z,1505172753.890 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-09-11T23:32:34.611Z,1505172754.611 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-09-11T23:32:34.611Z,1505172754.611 [StratificationFrontDetector](DEBUG): (re)initializing
2017-09-11T23:32:34.612Z,1505172754.612 [StratificationFrontDetector] Loaded
2017-09-11T23:32:34.612Z,1505172754.612 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-09-11T23:32:34.613Z,1505172754.613 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-09-11T23:32:34.613Z,1505172754.613 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-09-11T23:32:34.906Z,1505172754.906 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-09-11T23:32:34.907Z,1505172754.907 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-09-11T23:32:35.044Z,1505172755.044 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-09-11T23:32:35.044Z,1505172755.044 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-09-11T23:32:35.113Z,1505172755.113 [DeadReckonUsingSpeedCalculator] Loaded
2017-09-11T23:32:35.113Z,1505172755.113 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-09-11T23:32:35.134Z,1505172755.134 [NavChart] Loaded
2017-09-11T23:32:35.135Z,1505172755.135 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-09-11T23:32:35.140Z,1505172755.140 [UniversalFixResidualReporter] Loaded
2017-09-11T23:32:35.140Z,1505172755.140 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-09-11T23:32:35.141Z,1505172755.141 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-09-11T23:32:35.142Z,1505172755.142 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-09-11T23:32:35.172Z,1505172755.172 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-09-11T23:32:35.173Z,1505172755.173 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-09-11T23:32:35.390Z,1505172755.390 [Aanderaa_O2] Loaded
2017-09-11T23:32:35.391Z,1505172755.391 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-09-11T23:32:35.497Z,1505172755.497 [CTD_NeilBrown] Loaded
2017-09-11T23:32:35.497Z,1505172755.497 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-09-11T23:32:35.498Z,1505172755.498 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408154E0
2017-09-11T23:32:35.499Z,1505172755.499 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 857
2017-09-11T23:32:35.518Z,1505172755.518 [PAR_Licor] Loaded
2017-09-11T23:32:35.518Z,1505172755.518 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-09-11T23:32:35.576Z,1505172755.576 [WetLabsBB2FL] Loaded
2017-09-11T23:32:35.577Z,1505172755.577 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-09-11T23:32:35.578Z,1505172755.578 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408454E0
2017-09-11T23:32:35.579Z,1505172755.579 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 858
2017-09-11T23:32:35.579Z,1505172755.579 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-09-11T23:32:35.580Z,1505172755.580 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-09-11T23:32:36.225Z,1505172756.225 [DataOverHttps] Loaded
2017-09-11T23:32:36.226Z,1505172756.226 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-09-11T23:32:36.243Z,1505172756.243 [Depth_Keller] Loaded
2017-09-11T23:32:36.243Z,1505172756.243 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-09-11T23:32:36.250Z,1505172756.250 [DropWeight] Loaded
2017-09-11T23:32:36.250Z,1505172756.250 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-09-11T23:32:36.363Z,1505172756.363 [NAL9602] Loaded
2017-09-11T23:32:36.363Z,1505172756.363 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-09-11T23:32:36.375Z,1505172756.375 [Onboard] Loaded
2017-09-11T23:32:36.375Z,1505172756.375 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-09-11T23:32:36.384Z,1505172756.384 [Radio_Surface] Loaded
2017-09-11T23:32:36.384Z,1505172756.384 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-09-11T23:32:36.386Z,1505172756.386 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0
2017-09-11T23:32:36.386Z,1505172756.386 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 859
2017-09-11T23:32:36.560Z,1505172756.560 [DAT] Loaded
2017-09-11T23:32:36.561Z,1505172756.561 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2017-09-11T23:32:36.764Z,1505172756.764 [PNI_TCM] Loaded
2017-09-11T23:32:36.764Z,1505172756.764 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-09-11T23:32:39.204Z,1505172759.204 [BPC1] Loaded
2017-09-11T23:32:39.205Z,1505172759.205 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-09-11T23:32:39.205Z,1505172759.205 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-09-11T23:32:39.206Z,1505172759.206 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-09-11T23:32:39.339Z,1505172759.339 [BuoyancyServo] Loaded
2017-09-11T23:32:39.340Z,1505172759.340 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-09-11T23:32:39.355Z,1505172759.355 [ElevatorServo] Loaded
2017-09-11T23:32:39.356Z,1505172759.356 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-09-11T23:32:39.370Z,1505172759.370 [MassServo] Loaded
2017-09-11T23:32:39.370Z,1505172759.370 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-09-11T23:32:39.386Z,1505172759.386 [RudderServo] Loaded
2017-09-11T23:32:39.386Z,1505172759.386 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-09-11T23:32:39.400Z,1505172759.400 [ThrusterServo] Loaded
2017-09-11T23:32:39.401Z,1505172759.401 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-09-11T23:32:39.401Z,1505172759.401 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-09-11T23:32:39.402Z,1505172759.402 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-09-11T23:32:39.543Z,1505172759.543 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-09-11T23:32:39.544Z,1505172759.544 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-09-11T23:32:39.616Z,1505172759.616 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-09-11T23:32:39.641Z,1505172759.641 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-09-11T23:32:39.642Z,1505172759.642 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-09-11T23:32:39.681Z,1505172759.681 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-09-11T23:32:39.682Z,1505172759.682 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B9F4E0
2017-09-11T23:32:39.682Z,1505172759.682 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 860
2017-09-11T23:32:39.757Z,1505172759.757 [Supervisor](INFO): Main Thread ID is 766
2017-09-11T23:32:39.757Z,1505172759.757 [Supervisor](DEBUG): Running supervisor.
2017-09-11T23:32:39.758Z,1505172759.758 [CommandLine ThreadHandler](INFO): Handler Thread ID is 861
2017-09-11T23:32:39.782Z,1505172759.782 [controlThread ThreadHandler](INFO): Handler Thread ID is 862
2017-09-11T23:32:39.783Z,1505172759.783 [controlThread](DEBUG): Initializing ControlThread
2017-09-11T23:32:39.784Z,1505172759.784 [SBIT](INFO): Initialize SBIT Component.
2017-09-11T23:32:39.784Z,1505172759.784 [SBIT](IMPORTANT): git: 2017-08-02-81-g2dedf71
2017-09-11T23:32:39.785Z,1505172759.785 [SBIT](INFO): git hash: 2dedf719d304b5c3f7e3acd061a0c8109ea4feed
2017-09-11T23:32:39.787Z,1505172759.787 [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-11T23:32:39.787Z,1505172759.787 [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-11T23:32:39.788Z,1505172759.788 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2017-09-11T23:32:39.789Z,1505172759.789 [IBIT](INFO): Initialize IBIT Component.
2017-09-11T23:32:39.790Z,1505172759.790 [CBIT](DEBUG): Initialize CBIT Component.
2017-09-11T23:32:39.790Z,1505172759.790 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-09-11T23:32:39.791Z,1505172759.791 [logger ThreadHandler](INFO): Handler Thread ID is 863
2017-09-11T23:32:39.817Z,1505172759.817 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 864
2017-09-11T23:32:39.819Z,1505172759.819 [CTD_NeilBrown](INFO): Powering down
2017-09-11T23:32:39.848Z,1505172759.848 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 865
2017-09-11T23:32:39.850Z,1505172759.850 [WetLabsBB2FL](INFO): Powering down
2017-09-11T23:32:39.873Z,1505172759.873 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-09-11T23:32:39.875Z,1505172759.875 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-09-11T23:32:39.876Z,1505172759.876 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-09-11T23:32:39.877Z,1505172759.877 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-09-11T23:32:39.877Z,1505172759.877 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-11T23:32:39.878Z,1505172759.878 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-09-11T23:32:39.878Z,1505172759.878 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-09-11T23:32:39.878Z,1505172759.878 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-09-11T23:32:39.879Z,1505172759.879 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-09-11T23:32:39.880Z,1505172759.880 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-09-11T23:32:39.881Z,1505172759.881 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T23:32:39.882Z,1505172759.882 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T23:32:39.882Z,1505172759.882 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T23:32:39.883Z,1505172759.883 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T23:32:39.883Z,1505172759.883 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T23:32:39.883Z,1505172759.883 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T23:32:39.884Z,1505172759.884 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-09-11T23:32:39.884Z,1505172759.884 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-09-11T23:32:39.900Z,1505172759.900 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-09-11T23:32:39.933Z,1505172759.933 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 866
2017-09-11T23:32:39.941Z,1505172759.941 [Radio_Surface](INFO): Powering up
2017-09-11T23:32:39.949Z,1505172759.949 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-11T23:32:39.954Z,1505172759.954 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 867
2017-09-11T23:32:39.962Z,1505172759.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-09-11T23:32:39.962Z,1505172759.962 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-09-11T23:32:39.962Z,1505172759.962 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-09-11T23:32:39.963Z,1505172759.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-09-11T23:32:39.963Z,1505172759.963 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-09-11T23:32:39.963Z,1505172759.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-09-11T23:32:39.963Z,1505172759.963 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-09-11T23:32:39.963Z,1505172759.963 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-09-11T23:32:39.964Z,1505172759.964 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-09-11T23:32:39.964Z,1505172759.964 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-09-11T23:32:39.964Z,1505172759.964 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-09-11T23:32:39.964Z,1505172759.964 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-09-11T23:32:39.964Z,1505172759.964 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-09-11T23:32:39.965Z,1505172759.965 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-09-11T23:32:39.965Z,1505172759.965 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-09-11T23:32:39.965Z,1505172759.965 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-09-11T23:32:40.004Z,1505172760.004 [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-11T23:32:40.005Z,1505172760.005 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-09-11T23:32:40.080Z,1505172760.080 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-09-11T23:32:40.081Z,1505172760.081 [Default:A.Wait](DEBUG): Construct Wait.
2017-09-11T23:32:40.083Z,1505172760.083 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-09-11T23:32:40.132Z,1505172760.132 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-09-11T23:32:40.136Z,1505172760.136 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-09-11T23:32:40.163Z,1505172760.163 [Default:E.Execute](DEBUG): Construct Execute.
2017-09-11T23:32:40.183Z,1505172760.183 [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-11T23:32:40.189Z,1505172760.189 [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-11T23:32:40.294Z,1505172760.294 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-09-11T23:32:40.522Z,1505172760.522 [DAT](INFO): Powering up
2017-09-11T23:32:40.523Z,1505172760.523 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:32:40.839Z,1505172760.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:40.995Z,1505172760.995 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:41.116Z,1505172761.116 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-11T23:32:41.124Z,1505172761.124 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-11T23:32:41.156Z,1505172761.156 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-09-11T23:32:41.163Z,1505172761.163 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-09-11T23:32:41.178Z,1505172761.178 [MassServo](DEBUG): Initializing EZServoServo.
2017-09-11T23:32:41.188Z,1505172761.188 [MassServo](DEBUG): Initializing MassServo.
2017-09-11T23:32:41.195Z,1505172761.195 [RudderServo](DEBUG): Initializing EZServoServo.
2017-09-11T23:32:41.207Z,1505172761.207 [RudderServo](DEBUG): Initializing RudderServo.
2017-09-11T23:32:41.230Z,1505172761.230 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-09-11T23:32:41.236Z,1505172761.236 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-09-11T23:32:41.599Z,1505172761.599 [NAL9602](INFO): Powering up NAL9602
2017-09-11T23:32:41.675Z,1505172761.675 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:41.677Z,1505172761.677 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:42.016Z,1505172762.016 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-09-11T23:32:42.017Z,1505172762.017 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-09-11T23:32:42.017Z,1505172762.017 [BuoyancyServo] Communications Fault, FailCount= 1
2017-09-11T23:32:42.017Z,1505172762.017 [BuoyancyServo](ERROR): Communications Fault
2017-09-11T23:32:42.253Z,1505172762.253 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-09-11T23:32:42.346Z,1505172762.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:42.347Z,1505172762.347 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:42.375Z,1505172762.375 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-11T23:32:42.377Z,1505172762.377 [BuoyancyServo](INFO): Powering down
2017-09-11T23:32:42.715Z,1505172762.715 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:42.716Z,1505172762.716 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:43.114Z,1505172763.114 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:43.116Z,1505172763.116 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:43.583Z,1505172763.583 [Aanderaa_O2](INFO): Powering down
2017-09-11T23:32:43.752Z,1505172763.752 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:43.754Z,1505172763.754 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:43.973Z,1505172763.973 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:43.974Z,1505172763.974 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-09-11T23:32:44.582Z,1505172764.582 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-09-11T23:32:44.582Z,1505172764.582 [BuoyancyServo] No Fault, FailCount= 1
2017-09-11T23:32:44.812Z,1505172764.812 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-09-11T23:32:44.930Z,1505172764.930 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-09-11T23:32:52.764Z,1505172772.764 [NAL9602](INFO): NAL9602 initialized
2017-09-11T23:33:30.415Z,1505172810.415 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:14:48 
Warning battery low
CONNECT
2017-09-11T23:33:30.415Z,1505172810.415 [DAT](INFO): Got CONNECT
2017-09-11T23:33:30.421Z,1505172810.421 [DAT](INFO): entering command mode
2017-09-11T23:33:30.801Z,1505172810.801 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:31.180Z,1505172811.180 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:31.597Z,1505172811.597 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:31.993Z,1505172811.993 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:32.392Z,1505172812.392 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:32.795Z,1505172812.795 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:33.200Z,1505172813.200 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:33.589Z,1505172813.589 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:34.005Z,1505172814.005 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:34.399Z,1505172814.399 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:34.806Z,1505172814.806 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:35.193Z,1505172815.193 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:35.592Z,1505172815.592 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:35.997Z,1505172815.997 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:36.383Z,1505172816.383 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:36.790Z,1505172816.790 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:37.178Z,1505172817.178 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:37.588Z,1505172817.588 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:37.992Z,1505172817.992 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:38.392Z,1505172818.392 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:38.806Z,1505172818.806 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:39.206Z,1505172819.206 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:39.592Z,1505172819.592 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:39.983Z,1505172819.983 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:40.386Z,1505172820.386 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:40.780Z,1505172820.780 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:41.181Z,1505172821.181 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:41.583Z,1505172821.583 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:41.978Z,1505172821.978 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:42.379Z,1505172822.379 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:42.780Z,1505172822.780 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:43.180Z,1505172823.180 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:43.584Z,1505172823.584 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:43.978Z,1505172823.978 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:44.378Z,1505172824.378 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:44.779Z,1505172824.779 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:45.179Z,1505172825.179 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:45.586Z,1505172825.586 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:45.993Z,1505172825.993 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:46.398Z,1505172826.398 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:46.789Z,1505172826.789 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:47.185Z,1505172827.185 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:47.594Z,1505172827.594 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:47.994Z,1505172827.994 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:48.399Z,1505172828.399 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:48.796Z,1505172828.796 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:49.215Z,1505172829.215 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:49.612Z,1505172829.612 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:49.987Z,1505172829.987 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:50.401Z,1505172830.401 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:33:50.788Z,1505172830.788 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:14:48 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:33:50.789Z,1505172830.789 [DAT] Communications Fault, FailCount= 1
2017-09-11T23:33:50.789Z,1505172830.789 [DAT](ERROR): Communications Fault
2017-09-11T23:33:50.899Z,1505172830.899 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:33:51.185Z,1505172831.185 [DAT](INFO): Powering down
2017-09-11T23:33:51.490Z,1505172831.490 [SBIT](IMPORTANT): Beginning Startup BIT
2017-09-11T23:33:51.502Z,1505172831.502 [CBIT](IMPORTANT): Beginning ground fault scan
2017-09-11T23:33:52.185Z,1505172832.185 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:33:52.185Z,1505172832.185 [DAT] No Fault, FailCount= 1
2017-09-11T23:33:54.598Z,1505172834.598 [DAT](INFO): Powering up
2017-09-11T23:33:54.598Z,1505172834.598 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:34:01.863Z,1505172841.863 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.027287
CHAN A2 (12V): -0.002819
CHAN A3 (5V): -0.000495
CHAN B0 (3.3V): 0.000187
CHAN B1 (3.15aV): 0.000199
CHAN B2 (3.15bV): 0.000602
CHAN B3 (GND): -0.000253
OPEN: -0.000127
Full Scale Calc: 4.765 mA, -1.589 mA
2017-09-11T23:34:04.602Z,1505172844.602 [BPC1](ERROR): Failed to parse bank A battery data
2017-09-11T23:34:04.602Z,1505172844.602 [BPC1] Data Fault, FailCount= 1
2017-09-11T23:34:04.602Z,1505172844.602 [BPC1](ERROR): Data Fault
2017-09-11T23:34:04.704Z,1505172844.704 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T23:34:05.863Z,1505172845.863 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T23:34:05.863Z,1505172845.863 [BPC1] No Fault, FailCount= 1
2017-09-11T23:34:22.754Z,1505172862.754 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003906
2017-09-11T23:34:44.149Z,1505172884.149 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:16:02 
Warning battery low
CONNECT
2017-09-11T23:34:44.149Z,1505172884.149 [DAT](INFO): Got CONNECT
2017-09-11T23:34:44.150Z,1505172884.150 [DAT](INFO): entering command mode
2017-09-11T23:34:44.529Z,1505172884.529 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:44.930Z,1505172884.930 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:45.360Z,1505172885.360 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:45.389Z,1505172885.389 [SBIT](IMPORTANT): SBIT PASSED
2017-09-11T23:34:45.749Z,1505172885.749 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:45.790Z,1505172885.790 [MissionManager](IMPORTANT): Started mission Startup
2017-09-11T23:34:45.791Z,1505172885.791 [Startup] Running Loop=1
2017-09-11T23:34:45.791Z,1505172885.791 [Startup](DEBUG): Aggregate::initialize Startup
2017-09-11T23:34:45.791Z,1505172885.791 [Startup:A.GoToSurface] Running Loop=1
2017-09-11T23:34:45.791Z,1505172885.791 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-11T23:34:45.792Z,1505172885.792 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-11T23:34:45.792Z,1505172885.792 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-11T23:34:45.793Z,1505172885.793 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-11T23:34:45.793Z,1505172885.793 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-11T23:34:45.794Z,1505172885.794 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-11T23:34:45.812Z,1505172885.812 [Startup:StartupSatComms] Running Loop=1
2017-09-11T23:34:45.813Z,1505172885.813 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-09-11T23:34:45.813Z,1505172885.813 [Startup:StartupSatComms:A] Running Loop=1
2017-09-11T23:34:46.143Z,1505172886.143 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:46.183Z,1505172886.183 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-09-11T23:34:46.544Z,1505172886.544 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:46.940Z,1505172886.940 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:47.346Z,1505172887.346 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:47.748Z,1505172887.748 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:48.137Z,1505172888.137 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:48.548Z,1505172888.548 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:48.931Z,1505172888.931 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:49.363Z,1505172889.363 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:49.754Z,1505172889.754 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:50.138Z,1505172890.138 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:50.534Z,1505172890.534 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:50.943Z,1505172890.943 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:51.344Z,1505172891.344 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:51.737Z,1505172891.737 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:52.135Z,1505172892.135 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:52.545Z,1505172892.545 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:52.944Z,1505172892.944 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:53.347Z,1505172893.347 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:53.753Z,1505172893.753 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:54.158Z,1505172894.158 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:54.546Z,1505172894.546 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:54.950Z,1505172894.950 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:55.329Z,1505172895.329 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:55.738Z,1505172895.738 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:56.143Z,1505172896.143 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:56.531Z,1505172896.531 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:56.937Z,1505172896.937 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:57.341Z,1505172897.341 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:57.741Z,1505172897.741 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:58.148Z,1505172898.148 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:58.548Z,1505172898.548 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:58.944Z,1505172898.944 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:59.353Z,1505172899.353 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:34:59.748Z,1505172899.748 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:00.130Z,1505172900.130 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:00.534Z,1505172900.534 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:00.939Z,1505172900.939 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:01.337Z,1505172901.337 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:01.741Z,1505172901.741 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:02.142Z,1505172902.142 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:02.547Z,1505172902.547 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:02.949Z,1505172902.949 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:03.343Z,1505172903.343 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:03.737Z,1505172903.737 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:04.152Z,1505172904.152 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:04.541Z,1505172904.541 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:35:04.933Z,1505172904.933 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:16:02 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:35:04.934Z,1505172904.934 [DAT] Communications Fault, FailCount= 2
2017-09-11T23:35:04.934Z,1505172904.934 [DAT](ERROR): Communications Fault
2017-09-11T23:35:05.038Z,1505172905.038 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:35:05.332Z,1505172905.332 [DAT](INFO): Powering down
2017-09-11T23:35:06.247Z,1505172906.247 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:35:06.247Z,1505172906.247 [DAT] No Fault, FailCount= 2
2017-09-11T23:35:08.544Z,1505172908.544 [DAT](INFO): Powering up
2017-09-11T23:35:08.544Z,1505172908.544 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:35:40.043Z,1505172940.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T23:35:40.043Z,1505172940.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-09-11T23:35:40.043Z,1505172940.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-09-11T23:35:40.045Z,1505172940.045 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-09-11T23:35:40.045Z,1505172940.045 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-09-11T23:35:40.045Z,1505172940.045 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-09-11T23:35:40.086Z,1505172940.086 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-09-11T23:35:40.087Z,1505172940.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-09-11T23:35:40.465Z,1505172940.465 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-09-11T23:35:40.465Z,1505172940.465 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-09-11T23:35:40.466Z,1505172940.466 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-09-11T23:35:40.466Z,1505172940.466 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-09-11T23:35:40.817Z,1505172940.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-09-11T23:35:40.817Z,1505172940.817 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T23:35:40.818Z,1505172940.818 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T23:35:40.818Z,1505172940.818 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-09-11T23:35:40.819Z,1505172940.819 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-09-11T23:35:40.819Z,1505172940.819 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-09-11T23:35:41.225Z,1505172941.225 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-09-11T23:35:41.628Z,1505172941.628 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-09-11T23:35:42.032Z,1505172942.032 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-09-11T23:35:42.429Z,1505172942.429 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-09-11T23:35:42.826Z,1505172942.826 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-09-11T23:35:43.228Z,1505172943.228 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-09-11T23:35:43.623Z,1505172943.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-09-11T23:35:44.028Z,1505172944.028 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-09-11T23:35:44.409Z,1505172944.409 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-09-11T23:35:44.825Z,1505172944.825 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-09-11T23:35:45.217Z,1505172945.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-09-11T23:35:45.629Z,1505172945.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-09-11T23:35:46.054Z,1505172946.054 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-09-11T23:35:46.058Z,1505172946.058 [Startup:StartupSatComms:A](INFO): Timed out from 2017-09-11T23:34:45.8Z
2017-09-11T23:35:46.058Z,1505172946.058 [Startup:StartupSatComms:A] Stopped
2017-09-11T23:35:46.058Z,1505172946.058 [Startup:StartupSatComms:B] Running Loop=1
2017-09-11T23:35:46.425Z,1505172946.425 [PNI_TCM](ERROR): CRC does not match. Expected:0x35492 got:0x10267
2017-09-11T23:35:46.459Z,1505172946.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-09-11T23:35:46.464Z,1505172946.464 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-09-11T23:35:47.184Z,1505172947.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s.
2017-09-11T23:35:47.342Z,1505172947.342 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2017-09-11T23:35:50.737Z,1505172950.737 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.7 s old, using for 20.0 s.
2017-09-11T23:35:50.822Z,1505172950.822 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20170911T231512/Courier0008.lzma
2017-09-11T23:35:50.891Z,1505172950.891 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.9 s old, using for 20.0 s.
2017-09-11T23:35:51.264Z,1505172951.264 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-09-11T23:35:51.596Z,1505172951.596 [DataOverHttps](INFO): Moved sent file to Logs/20170911T231512/Courier0008.lzma.bak
2017-09-11T23:35:51.596Z,1505172951.596 [DataOverHttps](INFO): SBD MOMSN=5086073
2017-09-11T23:35:51.696Z,1505172951.696 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.7 s old, using for 20.0 s.
2017-09-11T23:35:52.274Z,1505172952.274 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s.
2017-09-11T23:35:52.481Z,1505172952.481 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-09-11T23:35:55.780Z,1505172955.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.7 s old, using for 20.0 s.
2017-09-11T23:35:55.959Z,1505172955.959 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2017-09-11T23:35:56.323Z,1505172956.323 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2017-09-11T23:35:56.731Z,1505172956.731 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2017-09-11T23:35:57.094Z,1505172957.094 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2017-09-11T23:35:57.494Z,1505172957.494 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2017-09-11T23:36:00.780Z,1505172960.780 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:17:16 
Warning battery low
CONNECT
2017-09-11T23:36:00.780Z,1505172960.780 [DAT](INFO): Got CONNECT
2017-09-11T23:36:00.781Z,1505172960.781 [DAT](INFO): entering command mode
2017-09-11T23:36:00.890Z,1505172960.890 [DataOverHttps](INFO): Sending 523 bytes from file Logs/20170911T231512/Express0009.lzma
2017-09-11T23:36:00.928Z,1505172960.928 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:01.311Z,1505172961.311 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:01.667Z,1505172961.667 [DataOverHttps](INFO): Moved sent file to Logs/20170911T231512/Express0009.lzma.bak
2017-09-11T23:36:01.667Z,1505172961.667 [DataOverHttps](INFO): SBD MOMSN=5086076
2017-09-11T23:36:01.720Z,1505172961.720 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:02.321Z,1505172962.321 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:02.513Z,1505172962.513 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:05.827Z,1505172965.827 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:05.972Z,1505172965.972 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:06.338Z,1505172966.338 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:06.743Z,1505172966.743 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:07.153Z,1505172967.153 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:07.558Z,1505172967.558 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:10.836Z,1505172970.836 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:10.981Z,1505172970.981 [DataOverHttps](INFO): Sending 756 bytes from file Logs/20170911T233220/Express0001.lzma
2017-09-11T23:36:11.014Z,1505172971.014 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:11.378Z,1505172971.378 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:11.759Z,1505172971.759 [DataOverHttps](INFO): Moved sent file to Logs/20170911T233220/Express0001.lzma.bak
2017-09-11T23:36:11.759Z,1505172971.759 [DataOverHttps](INFO): SBD MOMSN=5086087
2017-09-11T23:36:11.809Z,1505172971.809 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:12.430Z,1505172972.430 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:12.503Z,1505172972.503 [Startup:StartupSatComms:B] Stopped
2017-09-11T23:36:12.503Z,1505172972.503 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-09-11T23:36:12.503Z,1505172972.503 [Startup:StartupSatComms] Stopped
2017-09-11T23:36:12.504Z,1505172972.504 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-09-11T23:36:12.505Z,1505172972.505 [Startup](INFO): Completed Startup
2017-09-11T23:36:12.505Z,1505172972.505 [MissionManager](INFO): Startup is completed.
2017-09-11T23:36:12.505Z,1505172972.505 [MissionManager](INFO): Uninitializing Mission Startup
2017-09-11T23:36:12.505Z,1505172972.505 [Startup] Stopped
2017-09-11T23:36:12.505Z,1505172972.505 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-09-11T23:36:12.505Z,1505172972.505 [Startup:A.GoToSurface] Stopped
2017-09-11T23:36:12.506Z,1505172972.506 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-11T23:36:12.627Z,1505172972.627 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:12.688Z,1505172972.688 [MissionManager](IMPORTANT): Started mission Default
2017-09-11T23:36:12.688Z,1505172972.688 [Default] Running Loop=1
2017-09-11T23:36:12.688Z,1505172972.688 [Default](DEBUG): Aggregate::initialize Default
2017-09-11T23:36:12.688Z,1505172972.688 [Default:B.GoToSurface] Running Loop=1
2017-09-11T23:36:12.688Z,1505172972.688 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-09-11T23:36:12.689Z,1505172972.689 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-09-11T23:36:12.689Z,1505172972.689 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-09-11T23:36:12.689Z,1505172972.689 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-09-11T23:36:12.690Z,1505172972.690 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-09-11T23:36:12.690Z,1505172972.690 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-09-11T23:36:12.690Z,1505172972.690 [Default:A.Wait] Running Loop=1
2017-09-11T23:36:12.691Z,1505172972.691 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-09-11T23:36:15.871Z,1505172975.871 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:16.032Z,1505172976.032 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:16.416Z,1505172976.416 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:16.810Z,1505172976.810 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:17.183Z,1505172977.183 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:17.594Z,1505172977.594 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:17.985Z,1505172977.985 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:18.403Z,1505172978.403 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:36:18.802Z,1505172978.802 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:17:16 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:36:18.803Z,1505172978.803 [DAT] Communications Fault, FailCount= 3
2017-09-11T23:36:18.803Z,1505172978.803 [DAT](ERROR): Communications Fault
2017-09-11T23:36:18.881Z,1505172978.881 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:36:19.183Z,1505172979.183 [DAT](INFO): Powering down
2017-09-11T23:36:20.037Z,1505172980.037 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:36:20.037Z,1505172980.037 [DAT] No Fault, FailCount= 3
2017-09-11T23:36:22.409Z,1505172982.409 [DAT](INFO): Powering up
2017-09-11T23:36:22.409Z,1505172982.409 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:36:26.065Z,1505172986.065 [Default:A.Wait](INFO): Done Waiting.
2017-09-11T23:36:26.066Z,1505172986.066 [Default:A.Wait] Stopped
2017-09-11T23:36:26.066Z,1505172986.066 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-09-11T23:36:26.465Z,1505172986.465 [Default:CheckIn] Running Loop=1
2017-09-11T23:36:26.465Z,1505172986.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-09-11T23:36:26.465Z,1505172986.465 [Default:CheckIn:Read_GPS] Running Loop=1
2017-09-11T23:36:26.841Z,1505172986.841 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-09-11T23:36:27.227Z,1505172987.227 [BPC1](ERROR): Failed to parse bank A battery data
2017-09-11T23:36:27.227Z,1505172987.227 [BPC1] Data Fault, FailCount= 1
2017-09-11T23:36:27.227Z,1505172987.227 [BPC1](ERROR): Data Fault
2017-09-11T23:36:27.254Z,1505172987.254 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T23:36:28.452Z,1505172988.452 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T23:36:28.452Z,1505172988.452 [BPC1] No Fault, FailCount= 1
2017-09-11T23:36:36.014Z,1505172996.014 [PNI_TCM](ERROR): CRC does not match. Expected:0x27938 got:0x17179
2017-09-11T23:36:41.600Z,1505173001.600 [PNI_TCM](ERROR): CRC does not match. Expected:0x47828 got:0x49029
2017-09-11T23:36:56.371Z,1505173016.371 [NAL9602](INFO): SBD MO Status=2, MOMSN=475, MT Status=2, MTMSN=0
2017-09-11T23:36:56.371Z,1505173016.371 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2017-09-11T23:36:57.601Z,1505173017.601 [PNI_TCM](ERROR): CRC does not match. Expected:0x45532 got:0x33801
2017-09-11T23:37:10.417Z,1505173030.417 [PNI_TCM](ERROR): CRC does not match. Expected:0x54671 got:0x33415
2017-09-11T23:37:11.623Z,1505173031.623 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:18:30 
Warning battery low
CONNECT
2017-09-11T23:37:11.623Z,1505173031.623 [DAT](INFO): Got CONNECT
2017-09-11T23:37:11.624Z,1505173031.624 [DAT](INFO): entering command mode
2017-09-11T23:37:12.006Z,1505173032.006 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:12.402Z,1505173032.402 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:12.800Z,1505173032.800 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:13.204Z,1505173033.204 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:19.959Z,1505173039.959 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:20.236Z,1505173040.236 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:20.601Z,1505173040.601 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:21.036Z,1505173041.036 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:21.403Z,1505173041.403 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:21.809Z,1505173041.809 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:22.211Z,1505173042.211 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:22.611Z,1505173042.611 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:23.007Z,1505173043.007 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:23.399Z,1505173043.399 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:23.814Z,1505173043.814 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:24.193Z,1505173044.193 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:24.594Z,1505173044.594 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:25.014Z,1505173045.014 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:25.423Z,1505173045.423 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:25.810Z,1505173045.810 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:26.201Z,1505173046.201 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:26.601Z,1505173046.601 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:26.994Z,1505173046.994 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:27.406Z,1505173047.406 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:27.807Z,1505173047.807 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:28.197Z,1505173048.197 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:28.603Z,1505173048.603 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:29.007Z,1505173049.007 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:29.405Z,1505173049.405 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:29.820Z,1505173049.820 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:30.223Z,1505173050.223 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:30.595Z,1505173050.595 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:30.999Z,1505173050.999 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:31.400Z,1505173051.400 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:31.805Z,1505173051.805 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:32.214Z,1505173052.214 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:37:32.595Z,1505173052.595 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:18:30 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:37:32.595Z,1505173052.595 [DAT] Communications Fault, FailCount= 4
2017-09-11T23:37:32.595Z,1505173052.595 [DAT](ERROR): Communications Fault
2017-09-11T23:37:32.690Z,1505173052.690 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:37:33.003Z,1505173053.003 [DAT](INFO): Powering down
2017-09-11T23:37:33.849Z,1505173053.849 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:37:33.850Z,1505173053.850 [DAT] No Fault, FailCount= 4
2017-09-11T23:37:36.192Z,1505173056.192 [DAT](INFO): Powering up
2017-09-11T23:37:36.193Z,1505173056.193 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:37:56.188Z,1505173076.188 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-09-11T23:38:03.023Z,1505173083.023 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T23:38:03.023Z,1505173083.023 [BPC1] Data Fault, FailCount= 1
2017-09-11T23:38:03.024Z,1505173083.024 [BPC1](ERROR): Data Fault
2017-09-11T23:38:03.079Z,1505173083.079 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T23:38:04.256Z,1505173084.256 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T23:38:04.256Z,1505173084.256 [BPC1] No Fault, FailCount= 1
2017-09-11T23:38:06.185Z,1505173086.185 [NAL9602](INFO): GPS fix at 20170911T233559: (36.802749, -121.787999)
2017-09-11T23:38:06.274Z,1505173086.274 [Default:CheckIn:Read_GPS] Stopped
2017-09-11T23:38:06.274Z,1505173086.274 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-09-11T23:38:06.665Z,1505173086.665 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-09-11T23:38:12.416Z,1505173092.416 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20170911T233220/Courier0004.lzma
2017-09-11T23:38:13.203Z,1505173093.203 [DataOverHttps](INFO): Moved sent file to Logs/20170911T233220/Courier0004.lzma.bak
2017-09-11T23:38:13.203Z,1505173093.203 [DataOverHttps](INFO): SBD MOMSN=5086100
2017-09-11T23:38:18.385Z,1505173098.385 [NAL9602](INFO): Powering down
2017-09-11T23:38:24.019Z,1505173104.019 [DataOverHttps](INFO): Sending 505 bytes from file Logs/20170911T233220/Express0005.lzma
2017-09-11T23:38:24.781Z,1505173104.781 [DataOverHttps](INFO): Moved sent file to Logs/20170911T233220/Express0005.lzma.bak
2017-09-11T23:38:24.782Z,1505173104.782 [DataOverHttps](INFO): SBD MOMSN=5086103
2017-09-11T23:38:25.701Z,1505173105.701 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:19:43 
Warning battery low
CONNECT
2017-09-11T23:38:25.701Z,1505173105.701 [DAT](INFO): Got CONNECT
2017-09-11T23:38:25.702Z,1505173105.702 [DAT](INFO): entering command mode
2017-09-11T23:38:25.796Z,1505173105.796 [Default:CheckIn:Read_Iridium] Stopped
2017-09-11T23:38:25.797Z,1505173105.797 [Default:CheckIn:C.Wait] Running Loop=1
2017-09-11T23:38:25.797Z,1505173105.797 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-09-11T23:38:25.905Z,1505173105.905 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:29.675Z,1505173109.675 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:29.809Z,1505173109.809 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:30.190Z,1505173110.190 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:30.600Z,1505173110.600 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:30.996Z,1505173110.996 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:31.383Z,1505173111.383 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:31.780Z,1505173111.780 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:32.190Z,1505173112.190 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:32.597Z,1505173112.597 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:32.981Z,1505173112.981 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:33.394Z,1505173113.394 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:33.791Z,1505173113.791 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:34.190Z,1505173114.190 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:34.602Z,1505173114.602 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:34.980Z,1505173114.980 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:35.381Z,1505173115.381 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:35.782Z,1505173115.782 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:36.182Z,1505173116.182 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:36.590Z,1505173116.590 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:36.990Z,1505173116.990 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:37.388Z,1505173117.388 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:37.793Z,1505173117.793 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:38.193Z,1505173118.193 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:38.593Z,1505173118.593 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:38.983Z,1505173118.983 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:39.379Z,1505173119.379 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:39.780Z,1505173119.780 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:40.189Z,1505173120.189 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:40.591Z,1505173120.591 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:40.991Z,1505173120.991 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:41.395Z,1505173121.395 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:41.790Z,1505173121.790 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:42.191Z,1505173122.191 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:42.580Z,1505173122.580 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:42.981Z,1505173122.981 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:43.382Z,1505173123.382 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:43.790Z,1505173123.790 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:44.186Z,1505173124.186 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:44.590Z,1505173124.590 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:44.987Z,1505173124.987 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:45.393Z,1505173125.393 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:45.794Z,1505173125.794 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:46.203Z,1505173126.203 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:38:46.608Z,1505173126.608 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:19:43 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:38:46.608Z,1505173126.608 [DAT] Communications Fault, FailCount= 5
2017-09-11T23:38:46.608Z,1505173126.608 [DAT](ERROR): Communications Fault
2017-09-11T23:38:46.680Z,1505173126.680 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:38:46.979Z,1505173126.979 [DAT](INFO): Powering down
2017-09-11T23:38:47.860Z,1505173127.860 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:38:47.860Z,1505173127.860 [DAT] No Fault, FailCount= 5
2017-09-11T23:38:50.185Z,1505173130.185 [DAT](INFO): Powering up
2017-09-11T23:38:50.185Z,1505173130.185 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:39:39.360Z,1505173179.360 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:20:57 
Warning battery low
CONNECT
2017-09-11T23:39:39.360Z,1505173179.360 [DAT](INFO): Got CONNECT
2017-09-11T23:39:39.361Z,1505173179.361 [DAT](INFO): entering command mode
2017-09-11T23:39:39.746Z,1505173179.746 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:40.157Z,1505173180.157 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:40.551Z,1505173180.551 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:40.941Z,1505173180.941 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:41.362Z,1505173181.362 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:41.763Z,1505173181.763 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:42.191Z,1505173182.191 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:42.551Z,1505173182.551 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:42.956Z,1505173182.956 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:43.364Z,1505173183.364 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:43.798Z,1505173183.798 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:44.160Z,1505173184.160 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:44.541Z,1505173184.541 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:44.943Z,1505173184.943 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:45.347Z,1505173185.347 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:45.756Z,1505173185.756 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:46.153Z,1505173186.153 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:46.542Z,1505173186.542 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:46.941Z,1505173186.941 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:47.346Z,1505173187.346 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:47.744Z,1505173187.744 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:48.140Z,1505173188.140 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:48.548Z,1505173188.548 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:48.952Z,1505173188.952 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:49.354Z,1505173189.354 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:49.744Z,1505173189.744 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:50.138Z,1505173190.138 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:50.554Z,1505173190.554 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:50.953Z,1505173190.953 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:51.348Z,1505173191.348 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:51.750Z,1505173191.750 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:52.150Z,1505173192.150 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:52.537Z,1505173192.537 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:52.938Z,1505173192.938 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:53.339Z,1505173193.339 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:53.748Z,1505173193.748 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:54.153Z,1505173194.153 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:54.554Z,1505173194.554 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:54.956Z,1505173194.956 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:55.346Z,1505173195.346 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:55.772Z,1505173195.772 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:56.152Z,1505173196.152 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:56.556Z,1505173196.556 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:56.971Z,1505173196.971 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:57.342Z,1505173197.342 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:57.748Z,1505173197.748 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:58.161Z,1505173198.161 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:58.563Z,1505173198.563 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:58.971Z,1505173198.971 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:59.346Z,1505173199.346 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:39:59.763Z,1505173199.763 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:00.162Z,1505173200.162 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:00.578Z,1505173200.578 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:20:57 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:40:00.578Z,1505173200.578 [DAT] Communications Fault, FailCount= 6
2017-09-11T23:40:00.578Z,1505173200.578 [DAT](ERROR): Communications Fault
2017-09-11T23:40:00.703Z,1505173200.703 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:40:00.956Z,1505173200.956 [DAT](INFO): Powering down
2017-09-11T23:40:01.863Z,1505173201.863 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:40:01.863Z,1505173201.863 [DAT] No Fault, FailCount= 6
2017-09-11T23:40:04.158Z,1505173204.158 [DAT](INFO): Powering up
2017-09-11T23:40:04.158Z,1505173204.158 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:40:45.080Z,1505173245.080 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T23:40:45.080Z,1505173245.080 [BPC1] Data Fault, FailCount= 1
2017-09-11T23:40:45.080Z,1505173245.080 [BPC1](ERROR): Data Fault
2017-09-11T23:40:45.177Z,1505173245.177 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T23:40:46.361Z,1505173246.361 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T23:40:46.361Z,1505173246.361 [BPC1] No Fault, FailCount= 1
2017-09-11T23:40:53.479Z,1505173253.479 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:22:11 
Warning battery low
CONNECT
2017-09-11T23:40:53.479Z,1505173253.479 [DAT](INFO): Got CONNECT
2017-09-11T23:40:53.480Z,1505173253.480 [DAT](INFO): entering command mode
2017-09-11T23:40:53.884Z,1505173253.884 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:54.277Z,1505173254.277 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:54.669Z,1505173254.669 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:55.094Z,1505173255.094 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:55.498Z,1505173255.498 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:55.871Z,1505173255.871 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:56.284Z,1505173256.284 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:56.684Z,1505173256.684 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:57.076Z,1505173257.076 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:57.485Z,1505173257.485 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:57.879Z,1505173257.879 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:58.279Z,1505173258.279 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:58.692Z,1505173258.692 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:59.079Z,1505173259.079 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:59.477Z,1505173259.477 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:40:59.874Z,1505173259.874 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:00.281Z,1505173260.281 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:00.686Z,1505173260.686 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:01.083Z,1505173261.083 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:01.485Z,1505173261.485 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:01.869Z,1505173261.869 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:02.269Z,1505173262.269 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:02.670Z,1505173262.670 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:03.070Z,1505173263.070 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:03.478Z,1505173263.478 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:03.882Z,1505173263.882 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:04.281Z,1505173264.281 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:04.681Z,1505173264.681 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:05.070Z,1505173265.070 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:05.470Z,1505173265.470 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:05.878Z,1505173265.878 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:06.284Z,1505173266.284 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:06.684Z,1505173266.684 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:07.080Z,1505173267.080 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:07.491Z,1505173267.491 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:07.874Z,1505173267.874 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:08.271Z,1505173268.271 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:08.683Z,1505173268.683 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:09.076Z,1505173269.076 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:09.477Z,1505173269.477 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:09.881Z,1505173269.881 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:10.271Z,1505173270.271 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:10.679Z,1505173270.679 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:11.083Z,1505173271.083 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:11.484Z,1505173271.484 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:11.881Z,1505173271.881 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:12.281Z,1505173272.281 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:12.681Z,1505173272.681 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:13.074Z,1505173273.074 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:13.490Z,1505173273.490 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:13.879Z,1505173273.879 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:41:14.273Z,1505173274.273 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:22:11 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:41:14.273Z,1505173274.273 [DAT] Communications Fault, FailCount= 7
2017-09-11T23:41:14.273Z,1505173274.273 [DAT](ERROR): Communications Fault
2017-09-11T23:41:14.417Z,1505173274.417 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:41:14.676Z,1505173274.676 [DAT](INFO): Powering down
2017-09-11T23:41:15.563Z,1505173275.563 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:41:15.563Z,1505173275.563 [DAT] No Fault, FailCount= 7
2017-09-11T23:41:17.870Z,1505173277.870 [DAT](INFO): Powering up
2017-09-11T23:41:17.870Z,1505173277.870 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:42:07.404Z,1505173327.404 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:23:25 
Warning battery low
CONNECT
2017-09-11T23:42:07.404Z,1505173327.404 [DAT](INFO): Got CONNECT
2017-09-11T23:42:07.405Z,1505173327.405 [DAT](INFO): entering command mode
2017-09-11T23:42:07.799Z,1505173327.799 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:08.205Z,1505173328.205 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:08.603Z,1505173328.603 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:09.002Z,1505173329.002 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:09.407Z,1505173329.407 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:09.788Z,1505173329.788 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:10.191Z,1505173330.191 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:10.640Z,1505173330.640 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:10.996Z,1505173330.996 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:11.409Z,1505173331.409 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:11.798Z,1505173331.798 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:12.201Z,1505173332.201 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:12.590Z,1505173332.590 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:12.995Z,1505173332.995 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:13.392Z,1505173333.392 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:13.803Z,1505173333.803 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:14.197Z,1505173334.197 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:14.594Z,1505173334.594 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:14.988Z,1505173334.988 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:15.413Z,1505173335.413 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:15.822Z,1505173335.822 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:16.190Z,1505173336.190 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:16.598Z,1505173336.598 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:17.031Z,1505173337.031 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:17.399Z,1505173337.399 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:17.802Z,1505173337.802 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:18.195Z,1505173338.195 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:18.604Z,1505173338.604 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:18.992Z,1505173338.992 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:19.416Z,1505173339.416 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:19.801Z,1505173339.801 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:20.213Z,1505173340.213 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:20.589Z,1505173340.589 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:20.990Z,1505173340.990 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:21.390Z,1505173341.390 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:21.791Z,1505173341.791 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:22.202Z,1505173342.202 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:22.602Z,1505173342.602 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:23.031Z,1505173343.031 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:23.405Z,1505173343.405 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:23.792Z,1505173343.792 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:24.200Z,1505173344.200 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:24.593Z,1505173344.593 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:25.009Z,1505173345.009 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:25.409Z,1505173345.409 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:25.789Z,1505173345.789 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:26.190Z,1505173346.190 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:26.591Z,1505173346.591 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:27.001Z,1505173347.001 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:42:30.437Z,1505173350.437 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:23:25 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:42:30.437Z,1505173350.437 [DAT] Communications Fault, FailCount= 8
2017-09-11T23:42:30.437Z,1505173350.437 [DAT](ERROR): Communications Fault
2017-09-11T23:42:30.574Z,1505173350.574 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:42:30.575Z,1505173350.575 [CBIT](CRITICAL): Communications Fault in component: DAT
2017-09-11T23:42:30.633Z,1505173350.633 [DAT](INFO): Powering down
2017-09-11T23:42:30.832Z,1505173350.832 [CommandLine](FAULT): Scheduling is paused
2017-09-11T23:42:30.832Z,1505173350.832 [CBIT](INFO): Critical error at 20170911T234230
2017-09-11T23:42:30.832Z,1505173350.832 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-09-11T23:42:39.930Z,1505173359.930 [CBIT](DEBUG): EFC running - data check-sum false
2017-09-11T23:43:26.260Z,1505173406.260 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-09-11T23:43:26.261Z,1505173406.261 [Default:CheckIn:C.Wait] Stopped
2017-09-11T23:43:26.261Z,1505173406.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-09-11T23:43:26.261Z,1505173406.261 [Default:CheckIn:D] Running Loop=1
2017-09-11T23:43:26.660Z,1505173406.660 [Default:CheckIn:D] Stopped
2017-09-11T23:43:26.660Z,1505173406.660 [Default:CheckIn:E] Running Loop=1
2017-09-11T23:43:27.068Z,1505173407.068 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.232821 min 
2017-09-11T23:43:27.071Z,1505173407.071 [Default:CheckIn:E] Stopped
2017-09-11T23:43:27.072Z,1505173407.072 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-09-11T23:43:27.072Z,1505173407.072 [Default:CheckIn] Stopped
2017-09-11T23:43:27.072Z,1505173407.072 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-09-11T23:43:27.072Z,1505173407.072 [Default:CheckIn](INFO): Running loop #2
2017-09-11T23:43:27.076Z,1505173407.076 [Default:CheckIn] Running Loop=2
2017-09-11T23:43:27.077Z,1505173407.077 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-09-11T23:43:27.077Z,1505173407.077 [Default:CheckIn:Read_GPS] Running Loop=1
2017-09-11T23:43:30.737Z,1505173410.737 [NAL9602](INFO): Powering up
2017-09-11T23:43:41.926Z,1505173421.926 [NAL9602](INFO): NAL9602 initialized
2017-09-11T23:43:58.349Z,1505173438.349 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T23:43:58.349Z,1505173438.349 [BPC1] Data Fault, FailCount= 1
2017-09-11T23:43:58.349Z,1505173438.349 [BPC1](ERROR): Data Fault
2017-09-11T23:43:58.419Z,1505173438.419 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T23:43:59.596Z,1505173439.596 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T23:43:59.596Z,1505173439.596 [BPC1] No Fault, FailCount= 1
2017-09-11T23:44:12.343Z,1505173452.343 [BPC1](ERROR): Failed to parse bank A battery data
2017-09-11T23:44:12.343Z,1505173452.343 [BPC1] Data Fault, FailCount= 2
2017-09-11T23:44:12.343Z,1505173452.343 [BPC1](ERROR): Data Fault
2017-09-11T23:44:12.437Z,1505173452.437 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T23:44:13.615Z,1505173453.615 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T23:44:13.615Z,1505173453.615 [BPC1] No Fault, FailCount= 2
2017-09-11T23:44:13.916Z,1505173453.916 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2017-09-11T23:44:13.995Z,1505173453.995 [NAL9602](FAULT): received: 
+CSQ:0

OK5, 2, 0, 0, 0

OK
2017-09-11T23:44:13.995Z,1505173453.995 [NAL9602] Data Fault, FailCount= 1
2017-09-11T23:44:13.995Z,1505173453.995 [NAL9602](ERROR): Data Fault
2017-09-11T23:44:14.179Z,1505173454.179 [CBIT](ERROR): Data Fault in component: NAL9602
2017-09-11T23:44:14.325Z,1505173454.325 [NAL9602](INFO): Powering down
2017-09-11T23:44:15.209Z,1505173455.209 [CBIT](INFO): Clearing failed state for component NAL9602
2017-09-11T23:44:15.209Z,1505173455.209 [NAL9602] No Fault, FailCount= 1
2017-09-11T23:44:19.517Z,1505173459.517 [NAL9602](INFO): Powering up NAL9602
2017-09-11T23:44:25.948Z,1505173465.948 [BPC1](ERROR): Failed to parse bank B battery data
2017-09-11T23:44:25.948Z,1505173465.948 [BPC1] Data Fault, FailCount= 3
2017-09-11T23:44:25.948Z,1505173465.948 [BPC1](ERROR): Data Fault
2017-09-11T23:44:26.017Z,1505173466.017 [CBIT](ERROR): Data Fault in component: BPC1
2017-09-11T23:44:27.204Z,1505173467.204 [CBIT](INFO): Clearing failed state for component BPC1
2017-09-11T23:44:27.204Z,1505173467.204 [BPC1] No Fault, FailCount= 3
2017-09-11T23:44:31.003Z,1505173471.003 [NAL9602](INFO): NAL9602 initialized
2017-09-11T23:46:32.001Z,1505173592.001 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41
2017-09-11T23:47:31.087Z,1505173651.087 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:47:31.087Z,1505173651.087 [DAT] No Fault, FailCount= 8
2017-09-11T23:47:31.147Z,1505173651.147 [DAT](INFO): Powering up
2017-09-11T23:47:31.148Z,1505173651.148 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:48:12.306Z,1505173692.306 [NAL9602](INFO): SBD MO Status=0, MOMSN=475, MT Status=0, MTMSN=0
2017-09-11T23:48:12.307Z,1505173692.307 [NAL9602](INFO): No messages in MT queue
2017-09-11T23:48:21.129Z,1505173701.129 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:29:39 
Warning battery low
CONNECT
2017-09-11T23:48:21.129Z,1505173701.129 [DAT](INFO): Got CONNECT
2017-09-11T23:48:21.130Z,1505173701.130 [DAT](INFO): entering command mode
2017-09-11T23:48:21.547Z,1505173701.547 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:21.946Z,1505173701.946 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:22.343Z,1505173702.343 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:22.722Z,1505173702.722 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:23.123Z,1505173703.123 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:23.533Z,1505173703.533 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:23.939Z,1505173703.939 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:24.330Z,1505173704.330 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:24.740Z,1505173704.740 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:25.148Z,1505173705.148 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:25.552Z,1505173705.552 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:25.924Z,1505173705.924 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:26.325Z,1505173706.325 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:26.731Z,1505173706.731 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:27.134Z,1505173707.134 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:27.176Z,1505173707.176 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-09-11T23:43:27.1Z
2017-09-11T23:48:27.177Z,1505173707.177 [Default:CheckIn:Read_GPS] Stopped
2017-09-11T23:48:27.177Z,1505173707.177 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-09-11T23:48:27.541Z,1505173707.541 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:28.076Z,1505173708.076 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:31.384Z,1505173711.384 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:31.491Z,1505173711.491 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20170911T233220/Courier0008.lzma
2017-09-11T23:48:31.520Z,1505173711.520 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:31.888Z,1505173711.888 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:32.270Z,1505173712.270 [DataOverHttps](INFO): Moved sent file to Logs/20170911T233220/Courier0008.lzma.bak
2017-09-11T23:48:32.270Z,1505173712.270 [DataOverHttps](INFO): SBD MOMSN=5086109
2017-09-11T23:48:32.293Z,1505173712.293 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:32.930Z,1505173712.930 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:33.082Z,1505173713.082 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:36.429Z,1505173716.429 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:36.632Z,1505173716.632 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:36.991Z,1505173716.991 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:37.393Z,1505173717.393 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:37.790Z,1505173717.790 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:38.179Z,1505173718.179 [NAL9602](INFO): Powering down
2017-09-11T23:48:38.208Z,1505173718.208 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:48:41.758Z,1505173721.758 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:29:39 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:48:41.759Z,1505173721.759 [DAT] Communications Fault, FailCount= 1
2017-09-11T23:48:41.759Z,1505173721.759 [DAT](ERROR): Communications Fault
2017-09-11T23:48:41.863Z,1505173721.863 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:48:41.916Z,1505173721.916 [DataOverHttps](INFO): Sending 469 bytes from file Logs/20170911T233220/Express0009.lzma
2017-09-11T23:48:41.964Z,1505173721.964 [DAT](INFO): Powering down
2017-09-11T23:48:42.678Z,1505173722.678 [DataOverHttps](INFO): Moved sent file to Logs/20170911T233220/Express0009.lzma.bak
2017-09-11T23:48:42.678Z,1505173722.678 [DataOverHttps](INFO): SBD MOMSN=5086112
2017-09-11T23:48:42.788Z,1505173722.788 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:48:42.788Z,1505173722.788 [DAT] No Fault, FailCount= 1
2017-09-11T23:48:43.378Z,1505173723.378 [Default:CheckIn:Read_Iridium] Stopped
2017-09-11T23:48:43.378Z,1505173723.378 [Default:CheckIn:C.Wait] Running Loop=1
2017-09-11T23:48:43.378Z,1505173723.378 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-09-11T23:48:46.995Z,1505173726.995 [DAT](INFO): Powering up
2017-09-11T23:48:46.995Z,1505173726.995 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:49:36.338Z,1505173776.338 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:30:54 
Warning battery low
CONNECT
2017-09-11T23:49:36.338Z,1505173776.338 [DAT](INFO): Got CONNECT
2017-09-11T23:49:36.339Z,1505173776.339 [DAT](INFO): entering command mode
2017-09-11T23:49:36.731Z,1505173776.731 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:37.131Z,1505173777.131 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:37.544Z,1505173777.544 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:37.933Z,1505173777.933 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:38.341Z,1505173778.341 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:38.743Z,1505173778.743 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:39.142Z,1505173779.142 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:39.542Z,1505173779.542 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:39.935Z,1505173779.935 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:40.332Z,1505173780.332 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:40.729Z,1505173780.729 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:41.138Z,1505173781.138 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:41.542Z,1505173781.542 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:41.935Z,1505173781.935 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:42.344Z,1505173782.344 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:42.742Z,1505173782.742 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:43.137Z,1505173783.137 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:43.534Z,1505173783.534 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:43.935Z,1505173783.935 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:47.511Z,1505173787.511 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:47.659Z,1505173787.659 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:48.020Z,1505173788.020 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:48.440Z,1505173788.440 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:48.816Z,1505173788.816 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:49.214Z,1505173789.214 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:49.617Z,1505173789.617 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:49.627Z,1505173789.627 [PNI_TCM](ERROR): CRC does not match. Expected:0x61927 got:0x1797
2017-09-11T23:49:50.023Z,1505173790.023 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:50.425Z,1505173790.425 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:50.824Z,1505173790.824 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:51.226Z,1505173791.226 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:51.616Z,1505173791.616 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:52.039Z,1505173792.039 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:52.411Z,1505173792.411 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:52.818Z,1505173792.818 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:53.224Z,1505173793.224 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:53.615Z,1505173793.615 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:54.018Z,1505173794.018 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:54.424Z,1505173794.424 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:54.433Z,1505173794.433 [PNI_TCM](ERROR): CRC does not match. Expected:0x63703 got:0x8476
2017-09-11T23:49:54.825Z,1505173794.825 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:55.227Z,1505173795.227 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:55.621Z,1505173795.621 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:56.025Z,1505173796.025 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:56.429Z,1505173796.429 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:56.831Z,1505173796.831 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:49:57.211Z,1505173797.211 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:30:54 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:49:57.212Z,1505173797.212 [DAT] Communications Fault, FailCount= 2
2017-09-11T23:49:57.212Z,1505173797.212 [DAT](ERROR): Communications Fault
2017-09-11T23:49:57.318Z,1505173797.318 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:49:57.626Z,1505173797.626 [DAT](INFO): Powering down
2017-09-11T23:49:57.754Z,1505173797.754 [PNI_TCM](ERROR): CRC does not match. Expected:0x26231 got:0x49202
2017-09-11T23:49:58.488Z,1505173798.488 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:49:58.488Z,1505173798.488 [DAT] No Fault, FailCount= 2
2017-09-11T23:50:00.813Z,1505173800.813 [DAT](INFO): Powering up
2017-09-11T23:50:00.813Z,1505173800.813 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:50:02.433Z,1505173802.433 [PNI_TCM](ERROR): CRC does not match. Expected:0x6824 got:0x16384
2017-09-11T23:50:05.631Z,1505173805.631 [PNI_TCM](ERROR): CRC does not match. Expected:0x4725 got:0x32768
2017-09-11T23:50:16.845Z,1505173816.845 [PNI_TCM](ERROR): CRC does not match. Expected:0x52460 got:0x7359
2017-09-11T23:50:18.446Z,1505173818.446 [PNI_TCM](ERROR): CRC does not match. Expected:0x20348 got:0x19366
2017-09-11T23:50:28.028Z,1505173828.028 [PNI_TCM](ERROR): CRC does not match. Expected:0x59556 got:0x16836
2017-09-11T23:50:33.222Z,1505173833.222 [PNI_TCM](ERROR): CRC does not match. Expected:0x39075 got:0x4381
2017-09-11T23:50:34.435Z,1505173834.435 [PNI_TCM](ERROR): CRC does not match. Expected:0x31231 got:0x7016
2017-09-11T23:50:36.035Z,1505173836.035 [PNI_TCM](ERROR): CRC does not match. Expected:0x3892 got:0x39943
2017-09-11T23:50:39.252Z,1505173839.252 [PNI_TCM](ERROR): CRC does not match. Expected:0x61927 got:0x1797
2017-09-11T23:50:42.435Z,1505173842.435 [PNI_TCM](ERROR): CRC does not match. Expected:0x47456 got:0x42407
2017-09-11T23:50:44.028Z,1505173844.028 [PNI_TCM](ERROR): CRC does not match. Expected:0x59456 got:0x7359
2017-09-11T23:50:50.278Z,1505173850.278 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:32:08 
Warning battery low
CONNECT
2017-09-11T23:50:50.278Z,1505173850.278 [DAT](INFO): Got CONNECT
2017-09-11T23:50:50.279Z,1505173850.279 [DAT](INFO): entering command mode
2017-09-11T23:50:50.669Z,1505173850.669 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:51.066Z,1505173851.066 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:51.467Z,1505173851.467 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:51.867Z,1505173851.867 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:52.266Z,1505173852.266 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:52.667Z,1505173852.667 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:53.075Z,1505173853.075 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:53.476Z,1505173853.476 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:53.871Z,1505173853.871 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:54.272Z,1505173854.272 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:54.660Z,1505173854.660 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:55.062Z,1505173855.062 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:55.475Z,1505173855.475 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:55.871Z,1505173855.871 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:56.277Z,1505173856.277 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:56.676Z,1505173856.676 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:57.066Z,1505173857.066 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:57.474Z,1505173857.474 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:57.867Z,1505173857.867 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:58.275Z,1505173858.275 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:58.672Z,1505173858.672 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:59.076Z,1505173859.076 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:59.468Z,1505173859.468 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:50:59.882Z,1505173859.882 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:00.267Z,1505173860.267 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:00.663Z,1505173860.663 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:01.064Z,1505173861.064 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:01.465Z,1505173861.465 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:01.870Z,1505173861.870 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:02.273Z,1505173862.273 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:02.662Z,1505173862.662 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:03.063Z,1505173863.063 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:03.464Z,1505173863.464 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:03.864Z,1505173863.864 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:04.265Z,1505173864.265 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:04.662Z,1505173864.662 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:05.062Z,1505173865.062 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:05.471Z,1505173865.471 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:05.870Z,1505173865.870 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:06.276Z,1505173866.276 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:06.674Z,1505173866.674 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:07.076Z,1505173867.076 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:07.482Z,1505173867.482 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:07.880Z,1505173867.880 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:08.263Z,1505173868.263 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:08.664Z,1505173868.664 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:09.065Z,1505173869.065 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:09.472Z,1505173869.472 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:09.874Z,1505173869.874 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:10.267Z,1505173870.267 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:10.674Z,1505173870.674 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:51:11.076Z,1505173871.076 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:32:08 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:51:11.076Z,1505173871.076 [DAT] Communications Fault, FailCount= 3
2017-09-11T23:51:11.076Z,1505173871.076 [DAT](ERROR): Communications Fault
2017-09-11T23:51:11.159Z,1505173871.159 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:51:11.464Z,1505173871.464 [DAT](INFO): Powering down
2017-09-11T23:51:12.323Z,1505173872.323 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:51:12.324Z,1505173872.324 [DAT] No Fault, FailCount= 3
2017-09-11T23:51:14.674Z,1505173874.674 [DAT](INFO): Powering up
2017-09-11T23:51:14.674Z,1505173874.674 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:51:47.795Z,1505173907.795 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-09-11T23:51:47.980Z,1505173907.980 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-09-11T23:51:48.363Z,1505173908.363 [PNI_TCM](DEBUG): LCB error: Software Overcurrent.
2017-09-11T23:52:03.967Z,1505173923.967 [DAT](DEBUG): 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:33:22 
Warning battery low
CONNECT
2017-09-11T23:52:03.967Z,1505173923.967 [DAT](INFO): Got CONNECT
2017-09-11T23:52:03.978Z,1505173923.978 [DAT](INFO): entering command mode
2017-09-11T23:52:04.377Z,1505173924.377 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:04.778Z,1505173924.778 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:05.154Z,1505173925.154 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:05.566Z,1505173925.566 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:05.960Z,1505173925.960 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:06.370Z,1505173926.370 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:06.763Z,1505173926.763 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:07.161Z,1505173927.161 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:07.553Z,1505173927.553 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:07.954Z,1505173927.954 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:08.354Z,1505173928.354 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:08.762Z,1505173928.762 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:09.168Z,1505173929.168 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:09.561Z,1505173929.561 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:09.967Z,1505173929.967 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:10.363Z,1505173930.363 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:10.756Z,1505173930.756 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:11.161Z,1505173931.161 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:11.569Z,1505173931.569 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:11.958Z,1505173931.958 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:12.358Z,1505173932.358 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:12.766Z,1505173932.766 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:13.156Z,1505173933.156 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:13.555Z,1505173933.555 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:13.970Z,1505173933.970 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:14.367Z,1505173934.367 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:14.791Z,1505173934.791 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:15.169Z,1505173935.169 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:15.599Z,1505173935.599 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:15.992Z,1505173935.992 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:16.356Z,1505173936.356 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:16.757Z,1505173936.757 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:17.171Z,1505173937.171 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:17.554Z,1505173937.554 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:17.981Z,1505173937.981 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:18.366Z,1505173938.366 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:18.796Z,1505173938.796 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:19.168Z,1505173939.168 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:19.558Z,1505173939.558 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:19.965Z,1505173939.965 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:20.372Z,1505173940.372 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:20.765Z,1505173940.765 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:21.155Z,1505173941.155 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:21.563Z,1505173941.563 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:21.956Z,1505173941.956 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:22.364Z,1505173942.364 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:22.765Z,1505173942.765 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:23.164Z,1505173943.164 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:23.565Z,1505173943.565 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:23.971Z,1505173943.971 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:24.367Z,1505173944.367 [DAT](DEBUG): checking for command mode acknowledgment
2017-09-11T23:52:24.767Z,1505173944.767 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: 
Teledyne Benthos ATM-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.3.1
Jan  3 2000 02:33:22 
Warning battery low
CONNECT, available:  02400 bits/sec 1 of 4

user:1>œQ@
2017-09-11T23:52:24.767Z,1505173944.767 [DAT] Communications Fault, FailCount= 4
2017-09-11T23:52:24.767Z,1505173944.767 [DAT](ERROR): Communications Fault
2017-09-11T23:52:24.848Z,1505173944.848 [CBIT](ERROR): Communications Fault in component: DAT
2017-09-11T23:52:25.156Z,1505173945.156 [DAT](INFO): Powering down
2017-09-11T23:52:26.030Z,1505173946.030 [CBIT](INFO): Clearing failed state for component DAT
2017-09-11T23:52:26.030Z,1505173946.030 [DAT] No Fault, FailCount= 4
2017-09-11T23:52:28.358Z,1505173948.358 [DAT](INFO): Powering up
2017-09-11T23:52:28.358Z,1505173948.358 [DAT](DEBUG): Initializing DAT.
2017-09-11T23:52:40.068Z,1505173960.068 [CBIT](DEBUG): EFC running - data check-sum false
2017-09-11T23:52:50.540Z,1505173970.540 [CommandLine](IMPORTANT): got command quit
2017-09-11T23:52:51.550Z,1505173971.550 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-11T23:52:51.551Z,1505173971.551 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:51.629Z,1505173971.629 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-09-11T23:52:51.629Z,1505173971.629 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:51.631Z,1505173971.631 [CommandLine](INFO): Join timeout helper Thread ID is 925
2017-09-11T23:52:51.640Z,1505173971.640 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-09-11T23:52:51.640Z,1505173971.640 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:51.641Z,1505173971.641 [NavChartDb](INFO): Join timeout helper Thread ID is 926
2017-09-11T23:52:51.790Z,1505173971.790 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-11T23:52:51.790Z,1505173971.790 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:51.793Z,1505173971.793 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-09-11T23:52:51.794Z,1505173971.794 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:51.794Z,1505173971.794 [Radio_Surface](INFO): Join timeout helper Thread ID is 927
2017-09-11T23:52:52.123Z,1505173972.123 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-11T23:52:52.123Z,1505173972.123 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.130Z,1505173972.130 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-09-11T23:52:52.130Z,1505173972.130 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.130Z,1505173972.130 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 928
2017-09-11T23:52:52.138Z,1505173972.138 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-11T23:52:52.138Z,1505173972.138 [WetLabsBB2FL](INFO): Powering down
2017-09-11T23:52:52.139Z,1505173972.139 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.146Z,1505173972.146 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2017-09-11T23:52:52.146Z,1505173972.146 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.147Z,1505173972.147 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 929
2017-09-11T23:52:52.154Z,1505173972.154 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-11T23:52:52.154Z,1505173972.154 [CTD_NeilBrown](INFO): Powering down
2017-09-11T23:52:52.155Z,1505173972.155 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.163Z,1505173972.163 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-09-11T23:52:52.163Z,1505173972.163 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.164Z,1505173972.164 [logger](INFO): Join timeout helper Thread ID is 930
2017-09-11T23:52:52.185Z,1505173972.185 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-11T23:52:52.186Z,1505173972.186 [logger ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.195Z,1505173972.195 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-09-11T23:52:52.195Z,1505173972.195 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.195Z,1505173972.195 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-09-11T23:52:52.195Z,1505173972.195 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.196Z,1505173972.196 [controlThread](INFO): Join timeout helper Thread ID is 931
2017-09-11T23:52:52.458Z,1505173972.458 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-09-11T23:52:52.459Z,1505173972.459 [controlThread](DEBUG): Uninitializing ControlThread
2017-09-11T23:52:52.459Z,1505173972.459 [Aanderaa_O2](INFO): Powering down
2017-09-11T23:52:52.461Z,1505173972.461 [NAL9602](INFO): Powering down
2017-09-11T23:52:52.462Z,1505173972.462 [DAT](INFO): Powering down
2017-09-11T23:52:52.583Z,1505173972.583 [PNI_TCM](INFO): Powering down
2017-09-11T23:52:52.672Z,1505173972.672 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-09-11T23:52:52.673Z,1505173972.673 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-09-11T23:52:52.674Z,1505173972.674 [MissionManager](INFO): Uninitializing Mission Default
2017-09-11T23:52:52.674Z,1505173972.674 [Default] Stopped
2017-09-11T23:52:52.674Z,1505173972.674 [Default](DEBUG): Aggregate::uninitialize Default
2017-09-11T23:52:52.674Z,1505173972.674 [Default:B.GoToSurface] Stopped
2017-09-11T23:52:52.675Z,1505173972.675 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-09-11T23:52:52.675Z,1505173972.675 [Default:CheckIn] Stopped
2017-09-11T23:52:52.675Z,1505173972.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-09-11T23:52:52.675Z,1505173972.675 [Default:CheckIn:C.Wait] Stopped
2017-09-11T23:52:52.675Z,1505173972.675 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-09-11T23:52:52.679Z,1505173972.679 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-09-11T23:52:52.679Z,1505173972.679 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-09-11T23:52:52.679Z,1505173972.679 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-09-11T23:52:52.680Z,1505173972.680 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-09-11T23:52:52.680Z,1505173972.680 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-09-11T23:52:52.680Z,1505173972.680 [BuoyancyServo](INFO): Powering down
2017-09-11T23:52:52.695Z,1505173972.695 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-09-11T23:52:52.695Z,1505173972.695 [ElevatorServo](INFO): Powering down
2017-09-11T23:52:52.696Z,1505173972.696 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-09-11T23:52:52.696Z,1505173972.696 [MassServo](INFO): Powering down
2017-09-11T23:52:52.697Z,1505173972.697 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-09-11T23:52:52.697Z,1505173972.697 [RudderServo](INFO): Powering down
2017-09-11T23:52:52.698Z,1505173972.698 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-09-11T23:52:52.698Z,1505173972.698 [ThrusterServo](INFO): Powering down
2017-09-11T23:52:52.699Z,1505173972.699 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-09-11T23:52:52.700Z,1505173972.700 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-09-11T23:52:52.700Z,1505173972.700 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-09-11T23:52:52.701Z,1505173972.701 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.774Z,1505173972.774 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.779Z,1505173972.779 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.896Z,1505173972.896 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:52.945Z,1505173972.945 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-09-11T23:52:53.049Z,1505173973.049 [logger ThreadHandler](INFO): Thread cancelled.