2013-09-13T03:14:28.664Z,1379042068.664 [Supervisor](DEBUG): Initializing supervisor.
2013-09-13T03:14:28.667Z,1379042068.667 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-09-13T03:14:28.668Z,1379042068.668 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-09-13T03:14:28.668Z,1379042068.668 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-09-13T03:14:28.672Z,1379042068.672 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-09-13T03:14:28.683Z,1379042068.683 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-09-13T03:14:28.685Z,1379042068.685 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-09-13T03:14:28.685Z,1379042068.685 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-09-13T03:14:28.686Z,1379042068.686 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-09-13T03:14:28.687Z,1379042068.687 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-09-13T03:14:28.688Z,1379042068.688 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-09-13T03:14:28.972Z,1379042068.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-09-13T03:14:28.973Z,1379042068.973 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-09-13T03:14:29.167Z,1379042069.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-09-13T03:14:29.168Z,1379042069.168 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-09-13T03:14:29.252Z,1379042069.252 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-09-13T03:14:29.252Z,1379042069.252 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-09-13T03:14:29.364Z,1379042069.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-09-13T03:14:29.365Z,1379042069.365 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-09-13T03:14:29.503Z,1379042069.503 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-09-13T03:14:29.504Z,1379042069.504 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-09-13T03:14:29.745Z,1379042069.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-09-13T03:14:29.746Z,1379042069.746 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-09-13T03:14:29.911Z,1379042069.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-09-13T03:14:29.912Z,1379042069.912 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-09-13T03:14:30.176Z,1379042070.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-09-13T03:14:30.177Z,1379042070.177 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-09-13T03:14:30.278Z,1379042070.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-09-13T03:14:30.279Z,1379042070.279 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-09-13T03:14:30.697Z,1379042070.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-09-13T03:14:30.698Z,1379042070.698 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-09-13T03:14:30.808Z,1379042070.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-09-13T03:14:30.809Z,1379042070.809 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-09-13T03:14:30.894Z,1379042070.894 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-09-13T03:14:30.895Z,1379042070.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-09-13T03:14:30.994Z,1379042070.994 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-09-13T03:14:31.121Z,1379042071.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-09-13T03:14:31.214Z,1379042071.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-09-13T03:14:31.324Z,1379042071.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-09-13T03:14:31.527Z,1379042071.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-09-13T03:14:31.643Z,1379042071.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-09-13T03:14:31.738Z,1379042071.738 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-09-13T03:14:31.824Z,1379042071.824 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/
2013-09-13T03:14:31.824Z,1379042071.824 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-09-13T03:14:31.825Z,1379042071.825 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-09-13T03:14:31.993Z,1379042071.993 [InternalSim] Loaded
2013-09-13T03:14:31.993Z,1379042071.993 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-09-13T03:14:31.994Z,1379042071.994 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-09-13T03:14:31.995Z,1379042071.995 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-09-13T03:14:32.083Z,1379042072.083 [SBIT](DEBUG): Construct Startup Built In Test.
2013-09-13T03:14:32.112Z,1379042072.112 [SBIT] Loaded
2013-09-13T03:14:32.113Z,1379042072.113 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-09-13T03:14:32.113Z,1379042072.113 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-09-13T03:14:32.143Z,1379042072.143 [IBIT] Loaded
2013-09-13T03:14:32.144Z,1379042072.144 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-09-13T03:14:32.146Z,1379042072.146 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-09-13T03:14:32.264Z,1379042072.264 [CBIT] Loaded
2013-09-13T03:14:32.264Z,1379042072.264 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-09-13T03:14:32.264Z,1379042072.264 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-09-13T03:14:32.265Z,1379042072.265 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-09-13T03:14:32.433Z,1379042072.433 [BuoyancyServo] Loaded
2013-09-13T03:14:32.434Z,1379042072.434 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-09-13T03:14:32.446Z,1379042072.446 [ElevatorServo] Loaded
2013-09-13T03:14:32.446Z,1379042072.446 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-09-13T03:14:32.458Z,1379042072.458 [MassServo] Loaded
2013-09-13T03:14:32.458Z,1379042072.458 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-09-13T03:14:32.469Z,1379042072.469 [RudderServo] Loaded
2013-09-13T03:14:32.470Z,1379042072.470 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-09-13T03:14:32.481Z,1379042072.481 [ThrusterServo] Loaded
2013-09-13T03:14:32.481Z,1379042072.481 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-09-13T03:14:32.482Z,1379042072.482 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-09-13T03:14:32.482Z,1379042072.482 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-09-13T03:14:32.518Z,1379042072.518 [DepthRateCalculator] Loaded
2013-09-13T03:14:32.518Z,1379042072.518 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-09-13T03:14:35.617Z,1379042075.617 [HFRadarModelCalc] Loaded
2013-09-13T03:14:35.617Z,1379042075.617 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-09-13T03:14:35.634Z,1379042075.634 [NavChart] Loaded
2013-09-13T03:14:35.634Z,1379042075.634 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-09-13T03:14:35.640Z,1379042075.640 [PitchRateCalculator] Loaded
2013-09-13T03:14:35.640Z,1379042075.640 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-09-13T03:14:35.652Z,1379042075.652 [SpeedCalculator] Loaded
2013-09-13T03:14:35.652Z,1379042075.652 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-09-13T03:14:35.669Z,1379042075.669 [TempGradientCalculator] Loaded
2013-09-13T03:14:35.669Z,1379042075.669 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-09-13T03:14:35.674Z,1379042075.674 [YawRateCalculator] Loaded
2013-09-13T03:14:35.675Z,1379042075.675 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-09-13T03:14:35.722Z,1379042075.722 [Navigation] Loaded
2013-09-13T03:14:35.723Z,1379042075.723 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-09-13T03:14:35.723Z,1379042075.723 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-09-13T03:14:35.724Z,1379042075.724 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-09-13T03:14:35.968Z,1379042075.968 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-09-13T03:14:35.969Z,1379042075.969 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-09-13T03:14:35.992Z,1379042075.992 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-09-13T03:14:35.993Z,1379042075.993 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-09-13T03:14:36.041Z,1379042076.041 [VerticalControl](DEBUG): Construct VerticalControl.
2013-09-13T03:14:36.137Z,1379042076.137 [VerticalControl] Loaded
2013-09-13T03:14:36.137Z,1379042076.137 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-09-13T03:14:36.138Z,1379042076.138 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-09-13T03:14:36.197Z,1379042076.197 [HorizontalControl] Loaded
2013-09-13T03:14:36.197Z,1379042076.197 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-09-13T03:14:36.198Z,1379042076.198 [SpeedControl](DEBUG): Construct SpeedControl.
2013-09-13T03:14:36.200Z,1379042076.200 [SpeedControl] Loaded
2013-09-13T03:14:36.200Z,1379042076.200 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-09-13T03:14:36.201Z,1379042076.201 [LoopControl](DEBUG): Construct LoopControl.
2013-09-13T03:14:36.201Z,1379042076.201 [LoopControl] Loaded
2013-09-13T03:14:36.202Z,1379042076.202 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-09-13T03:14:36.202Z,1379042076.202 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-09-13T03:14:36.203Z,1379042076.203 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-09-13T03:14:36.208Z,1379042076.208 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-09-13T03:14:36.214Z,1379042076.214 [AsyncPiEstimator] Loaded
2013-09-13T03:14:36.214Z,1379042076.214 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-09-13T03:14:36.215Z,1379042076.215 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0
2013-09-13T03:14:36.216Z,1379042076.216 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-09-13T03:14:36.216Z,1379042076.216 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-09-13T03:14:36.416Z,1379042076.416 [AHRS_sp3003D] Loaded
2013-09-13T03:14:36.416Z,1379042076.416 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-09-13T03:14:36.749Z,1379042076.749 [Batt_Ocean_Server] Loaded
2013-09-13T03:14:36.750Z,1379042076.750 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread.
2013-09-13T03:14:36.764Z,1379042076.764 [Depth_Keller] Loaded
2013-09-13T03:14:36.764Z,1379042076.764 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-09-13T03:14:36.769Z,1379042076.769 [DropWeight] Loaded
2013-09-13T03:14:36.770Z,1379042076.770 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-09-13T03:14:36.957Z,1379042076.957 [DVL_micro] Loaded
2013-09-13T03:14:36.957Z,1379042076.957 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-09-13T03:14:36.958Z,1379042076.958 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0
2013-09-13T03:14:37.048Z,1379042077.048 [NAL9602] Loaded
2013-09-13T03:14:37.048Z,1379042077.048 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-09-13T03:14:37.092Z,1379042077.092 [Onboard] Loaded
2013-09-13T03:14:37.092Z,1379042077.092 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-09-13T03:14:37.099Z,1379042077.099 [Radio_Freewave] Loaded
2013-09-13T03:14:37.100Z,1379042077.100 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-09-13T03:14:37.244Z,1379042077.244 [DAT] Loaded
2013-09-13T03:14:37.244Z,1379042077.244 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-09-13T03:14:37.245Z,1379042077.245 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-09-13T03:14:37.245Z,1379042077.245 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-09-13T03:14:37.311Z,1379042077.311 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-09-13T03:14:37.313Z,1379042077.313 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-09-13T03:14:37.314Z,1379042077.314 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-09-13T03:14:37.321Z,1379042077.321 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-09-13T03:14:37.322Z,1379042077.322 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407324E0
2013-09-13T03:14:37.326Z,1379042077.326 [Supervisor](DEBUG): Running supervisor.
2013-09-13T03:14:37.328Z,1379042077.328 [CommandLine](INFO): Thread ID is 2474
2013-09-13T03:14:37.330Z,1379042077.330 [controlThread](INFO): Thread ID is 2473
2013-09-13T03:14:37.330Z,1379042077.330 [controlThread](DEBUG): Initializing ControlThread
2013-09-13T03:14:37.331Z,1379042077.331 [CycleStarter](INFO): Thread ID is 2472
2013-09-13T03:14:37.331Z,1379042077.331 [InternalSim](DEBUG):  InternalSim initializing...
2013-09-13T03:14:37.366Z,1379042077.366 [logger](INFO): Thread ID is 2475
2013-09-13T03:14:37.387Z,1379042077.387 [SBIT](INFO): Initialize SBIT Component.
2013-09-13T03:14:37.388Z,1379042077.388 [SBIT](IMPORTANT): Tethys CM Info: 
$Rev:10573
2013-09-13T03:14:37.388Z,1379042077.388 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-09-13T03:14:37.388Z,1379042077.388 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-09-13T03:14:37.389Z,1379042077.389 [IBIT](INFO): Initialize IBIT Component.
2013-09-13T03:14:37.390Z,1379042077.390 [CBIT](DEBUG): Initialize CBIT Component.
2013-09-13T03:14:37.390Z,1379042077.390 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-09-13T03:14:37.400Z,1379042077.400 [AsyncPiEstimator](INFO): Thread ID is 2538
2013-09-13T03:14:37.400Z,1379042077.400 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-09-13T03:14:37.417Z,1379042077.417 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-09-13T03:14:37.417Z,1379042077.417 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-09-13T03:14:37.418Z,1379042077.418 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-09-13T03:14:37.418Z,1379042077.418 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-09-13T03:14:37.418Z,1379042077.418 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-09-13T03:14:37.419Z,1379042077.419 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-09-13T03:14:37.419Z,1379042077.419 [Navigation](DEBUG): Initializing Navigation.
2013-09-13T03:14:37.420Z,1379042077.420 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-09-13T03:14:37.421Z,1379042077.421 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-09-13T03:14:37.422Z,1379042077.422 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-09-13T03:14:37.422Z,1379042077.422 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-09-13T03:14:37.428Z,1379042077.428 [DVL_micro](INFO): Thread ID is 2541
2013-09-13T03:14:37.437Z,1379042077.437 [DVL_micro](INFO): Initializing
2013-09-13T03:14:37.437Z,1379042077.437 [DVL_micro](INFO): start:Powering up
2013-09-13T03:14:37.438Z,1379042077.438 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:14:37.439Z,1379042077.439 [DVL_micro](INFO): Cycling power to configure device.
2013-09-13T03:14:37.460Z,1379042077.460 [NavChartDb](INFO): Thread ID is 2542
2013-09-13T03:14:37.463Z,1379042077.463 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-09-13T03:14:37.464Z,1379042077.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-09-13T03:14:37.464Z,1379042077.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-09-13T03:14:37.464Z,1379042077.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-09-13T03:14:37.464Z,1379042077.464 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-09-13T03:14:37.465Z,1379042077.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-09-13T03:14:37.465Z,1379042077.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-09-13T03:14:37.465Z,1379042077.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-09-13T03:14:37.465Z,1379042077.465 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-09-13T03:14:39.495Z,1379042079.495 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:14:39.495Z,1379042079.495 [DVL_micro](INFO): Querying output modes
2013-09-13T03:14:39.496Z,1379042079.496 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-13T03:14:41.502Z,1379042081.502 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:14:41.502Z,1379042081.502 [DVL_micro](INFO): Output Modes: No Response
2013-09-13T03:14:41.502Z,1379042081.502 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-13T03:14:43.513Z,1379042083.513 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:14:43.518Z,1379042083.518 [DVL_micro](INFO): pause:Powering down
2013-09-13T03:14:43.978Z,1379042083.978 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout
2013-09-13T03:14:43.978Z,1379042083.978 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing
2013-09-13T03:14:43.978Z,1379042083.978 [Batt_Ocean_Server] Communications Fault, FailCount= 1
2013-09-13T03:14:43.978Z,1379042083.978 [Batt_Ocean_Server](ERROR): Communications Fault
2013-09-13T03:14:43.983Z,1379042083.983 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2013-09-13T03:14:43.983Z,1379042083.983 [DropWeight] Hardware Fault, FailCount= 1
2013-09-13T03:14:43.983Z,1379042083.983 [DropWeight](ERROR): Hardware Fault
2013-09-13T03:14:43.985Z,1379042083.985 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-09-13T03:14:44.013Z,1379042084.013 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-13T03:14:44.039Z,1379042084.039 [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://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

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

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

    </Aggregate>

</Mission>


2013-09-13T03:14:44.040Z,1379042084.040 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-09-13T03:14:44.131Z,1379042084.131 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-09-13T03:14:44.133Z,1379042084.133 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-09-13T03:14:44.136Z,1379042084.136 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-13T03:14:44.156Z,1379042084.156 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-09-13T03:14:44.159Z,1379042084.159 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-13T03:14:44.198Z,1379042084.198 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-09-13T03:14:44.223Z,1379042084.223 [Default:D.SetSpeed](DEBUG): Construct.
2013-09-13T03:14:44.235Z,1379042084.235 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-13T03:14:44.240Z,1379042084.240 [Default:F.Wait](DEBUG): Construct Wait.
2013-09-13T03:14:44.244Z,1379042084.244 [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://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS" >

        <When>
            <Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS">
            <Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or><Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

        <Assign><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

<!-- Wait forever at the surface-->

<!-- Run at low speed -->

    <Guidance:SetSpeed RunIn="Parallel">
        <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:GoToSurface RunIn="Parallel"/>

    <Guidance:Wait RunIn="Sequence"></Guidance:Wait>

</Mission>


2013-09-13T03:14:44.258Z,1379042084.258 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Batt_Ocean_Server,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,DAT,Depth_Keller,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-09-13T03:14:44.321Z,1379042084.321 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-13T03:14:44.457Z,1379042084.457 [NAL9602](INFO): Powering up NAL9602
2013-09-13T03:14:44.565Z,1379042084.565 [Radio_Freewave](INFO): Powering up
2013-09-13T03:14:44.598Z,1379042084.598 [DAT](INFO): Powering up
2013-09-13T03:14:44.599Z,1379042084.599 [DAT](DEBUG): Initializing DAT.
2013-09-13T03:14:44.818Z,1379042084.818 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:14:44.823Z,1379042084.823 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-13T03:14:44.829Z,1379042084.829 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:14:44.835Z,1379042084.835 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-09-13T03:14:44.841Z,1379042084.841 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:14:44.847Z,1379042084.847 [MassServo](DEBUG): Initializing MassServo.
2013-09-13T03:14:44.853Z,1379042084.853 [RudderServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:14:44.859Z,1379042084.859 [RudderServo](DEBUG): Initializing RudderServo.
2013-09-13T03:14:44.865Z,1379042084.865 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:14:44.899Z,1379042084.899 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-09-13T03:14:44.926Z,1379042084.926 [CommandLine](FAULT): Scheduling is paused
2013-09-13T03:14:44.927Z,1379042084.927 [CBIT](FAULT): Main Battery Failure. Count: 1
2013-09-13T03:14:44.928Z,1379042084.928 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server
2013-09-13T03:14:44.928Z,1379042084.928 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server
2013-09-13T03:14:44.928Z,1379042084.928 [Batt_Ocean_Server] No Fault, FailCount= 1
2013-09-13T03:14:44.928Z,1379042084.928 [CBIT](ERROR): Hardware Fault in component: DropWeight
2013-09-13T03:14:44.929Z,1379042084.929 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2013-09-13T03:14:45.344Z,1379042085.344 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-13T03:14:45.344Z,1379042085.344 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-13T03:14:45.344Z,1379042085.344 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-09-13T03:14:45.344Z,1379042085.344 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-13T03:14:45.528Z,1379042085.528 [DVL_micro](INFO): NQ1 requested
2013-09-13T03:14:45.529Z,1379042085.529 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:14:45.529Z,1379042085.529 [DVL_micro](INFO): resume:Powering up
2013-09-13T03:14:45.529Z,1379042085.529 [DVL_micro](INFO): Cycling power to configure device.
2013-09-13T03:14:47.535Z,1379042087.535 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:14:47.536Z,1379042087.536 [DVL_micro](INFO): Querying output modes
2013-09-13T03:14:47.536Z,1379042087.536 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-13T03:14:49.546Z,1379042089.546 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:14:49.546Z,1379042089.546 [DVL_micro](INFO): Output Modes: No Response
2013-09-13T03:14:49.547Z,1379042089.547 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-13T03:14:51.549Z,1379042091.549 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:14:51.551Z,1379042091.551 [DVL_micro](INFO): NQ1 requested
2013-09-13T03:14:51.552Z,1379042091.552 [DVL_micro](INFO): Cycling power to configure device.
2013-09-13T03:14:51.910Z,1379042091.910 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout
2013-09-13T03:14:51.910Z,1379042091.910 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing
2013-09-13T03:14:51.910Z,1379042091.910 [Batt_Ocean_Server] Communications Fault, FailCount= 2
2013-09-13T03:14:51.910Z,1379042091.910 [Batt_Ocean_Server](ERROR): Communications Fault
2013-09-13T03:14:52.531Z,1379042092.531 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-09-13T03:14:52.531Z,1379042092.531 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-09-13T03:14:52.531Z,1379042092.531 [BuoyancyServo] Communications Fault, FailCount= 1
2013-09-13T03:14:52.531Z,1379042092.531 [BuoyancyServo](ERROR): Communications Fault
2013-09-13T03:14:53.040Z,1379042093.040 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-09-13T03:14:53.040Z,1379042093.040 [ElevatorServo](FAULT): Elevator failed to initialize
2013-09-13T03:14:53.040Z,1379042093.040 [ElevatorServo] Communications Fault, FailCount= 1
2013-09-13T03:14:53.040Z,1379042093.040 [ElevatorServo](ERROR): Communications Fault
2013-09-13T03:14:53.553Z,1379042093.553 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-09-13T03:14:53.553Z,1379042093.553 [RudderServo](FAULT): Rudder failed to initialize
2013-09-13T03:14:53.553Z,1379042093.553 [RudderServo] Communications Fault, FailCount= 1
2013-09-13T03:14:53.553Z,1379042093.553 [RudderServo](ERROR): Communications Fault
2013-09-13T03:14:53.557Z,1379042093.557 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:14:53.557Z,1379042093.557 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-13T03:14:54.073Z,1379042094.073 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-09-13T03:14:54.590Z,1379042094.590 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2013-09-13T03:14:54.590Z,1379042094.590 [ThrusterServo](FAULT): Thruster failed to initialize
2013-09-13T03:14:54.590Z,1379042094.590 [ThrusterServo] Communications Fault, FailCount= 1
2013-09-13T03:14:54.590Z,1379042094.590 [ThrusterServo](ERROR): Communications Fault
2013-09-13T03:14:54.592Z,1379042094.592 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-09-13T03:14:54.593Z,1379042094.593 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-09-13T03:14:54.593Z,1379042094.593 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-09-13T03:14:54.593Z,1379042094.593 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-09-13T03:14:54.593Z,1379042094.593 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-13T03:14:54.594Z,1379042094.594 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server
2013-09-13T03:14:54.774Z,1379042094.774 [AHRS_sp3003D](INFO): Powering down
2013-09-13T03:14:54.929Z,1379042094.929 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-09-13T03:14:54.935Z,1379042094.935 [BuoyancyServo](INFO): Powering down
2013-09-13T03:14:55.031Z,1379042095.031 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-09-13T03:14:55.031Z,1379042095.031 [ElevatorServo](INFO): Powering down
2013-09-13T03:14:55.559Z,1379042095.559 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:14:55.560Z,1379042095.560 [DVL_micro](INFO): Enabling NQ1 output
2013-09-13T03:14:55.560Z,1379042095.560 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-13T03:14:55.596Z,1379042095.596 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-09-13T03:14:55.596Z,1379042095.596 [MassServo] Communications Fault, FailCount= 1
2013-09-13T03:14:55.596Z,1379042095.596 [MassServo](ERROR): Communications Fault
2013-09-13T03:14:55.596Z,1379042095.596 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-09-13T03:14:55.597Z,1379042095.597 [RudderServo](INFO): Powering down
2013-09-13T03:14:55.620Z,1379042095.620 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-09-13T03:14:55.620Z,1379042095.620 [ThrusterServo](INFO): Powering down
2013-09-13T03:14:55.656Z,1379042095.656 [SBIT](IMPORTANT): Beginning Startup BIT
2013-09-13T03:14:55.659Z,1379042095.659 [CBIT](ERROR): Communications Fault in component: MassServo
2013-09-13T03:14:55.659Z,1379042095.659 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server
2013-09-13T03:14:55.659Z,1379042095.659 [Batt_Ocean_Server] No Fault, FailCount= 2
2013-09-13T03:14:55.660Z,1379042095.660 [CBIT](IMPORTANT): Beginning GF scan
2013-09-13T03:14:57.563Z,1379042097.563 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:14:57.563Z,1379042097.563 [DVL_micro](INFO): Querying output modes
2013-09-13T03:14:57.563Z,1379042097.563 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-13T03:14:59.566Z,1379042099.566 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:14:59.566Z,1379042099.566 [DVL_micro](INFO): Output Modes: No Response
2013-09-13T03:14:59.566Z,1379042099.566 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-13T03:15:01.577Z,1379042101.577 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:02.326Z,1379042102.326 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout
2013-09-13T03:15:02.326Z,1379042102.326 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing
2013-09-13T03:15:02.326Z,1379042102.326 [Batt_Ocean_Server] Communications Fault, FailCount= 3
2013-09-13T03:15:02.326Z,1379042102.326 [Batt_Ocean_Server](ERROR): Communications Fault
2013-09-13T03:15:02.367Z,1379042102.367 [NAL9602](INFO): NAL9602 initialized
2013-09-13T03:15:02.443Z,1379042102.443 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-13T03:15:02.443Z,1379042102.443 [MassServo](INFO): Powering down
2013-09-13T03:15:02.506Z,1379042102.506 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server
2013-09-13T03:15:02.514Z,1379042102.514 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server
2013-09-13T03:15:02.807Z,1379042102.807 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:02.924Z,1379042102.924 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-09-13T03:15:02.926Z,1379042102.926 [RudderServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:03.044Z,1379042103.044 [RudderServo](DEBUG): Initializing RudderServo.
2013-09-13T03:15:03.166Z,1379042103.166 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-09-13T03:15:03.166Z,1379042103.166 [BuoyancyServo] No Fault, FailCount= 1
2013-09-13T03:15:03.166Z,1379042103.166 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-09-13T03:15:03.166Z,1379042103.166 [ElevatorServo] No Fault, FailCount= 1
2013-09-13T03:15:03.166Z,1379042103.166 [CBIT](INFO): Clearing failed state for component RudderServo
2013-09-13T03:15:03.167Z,1379042103.167 [RudderServo] No Fault, FailCount= 1
2013-09-13T03:15:03.167Z,1379042103.167 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-09-13T03:15:03.167Z,1379042103.167 [ThrusterServo] No Fault, FailCount= 1
2013-09-13T03:15:03.188Z,1379042103.188 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-13T03:15:03.188Z,1379042103.188 [AHRS_sp3003D] No Fault, FailCount= 1
2013-09-13T03:15:03.212Z,1379042103.212 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-13T03:15:03.344Z,1379042103.344 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:03.465Z,1379042103.465 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-13T03:15:03.585Z,1379042103.585 [DVL_micro](INFO): pause:Powering down
2013-09-13T03:15:03.977Z,1379042103.977 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-09-13T03:15:03.977Z,1379042103.977 [ElevatorServo](FAULT): Elevator failed to initialize
2013-09-13T03:15:03.977Z,1379042103.977 [ElevatorServo] Communications Fault, FailCount= 2
2013-09-13T03:15:03.977Z,1379042103.977 [ElevatorServo](ERROR): Communications Fault
2013-09-13T03:15:03.978Z,1379042103.978 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:04.098Z,1379042104.098 [MassServo](DEBUG): Initializing MassServo.
2013-09-13T03:15:04.609Z,1379042104.609 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-09-13T03:15:04.610Z,1379042104.610 [RudderServo](FAULT): Rudder failed to initialize
2013-09-13T03:15:04.610Z,1379042104.610 [RudderServo] Communications Fault, FailCount= 2
2013-09-13T03:15:04.610Z,1379042104.610 [RudderServo](ERROR): Communications Fault
2013-09-13T03:15:04.611Z,1379042104.611 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:04.618Z,1379042104.618 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-09-13T03:15:04.621Z,1379042104.621 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-09-13T03:15:04.621Z,1379042104.621 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-13T03:15:04.621Z,1379042104.621 [MassServo] No Fault, FailCount= 1
2013-09-13T03:15:04.621Z,1379042104.621 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-09-13T03:15:05.030Z,1379042105.030 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-13T03:15:05.030Z,1379042105.030 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-13T03:15:05.030Z,1379042105.030 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2013-09-13T03:15:05.030Z,1379042105.030 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-13T03:15:05.040Z,1379042105.040 [NAL9602](IMPORTANT): GPS fix at: -32445540.00
2013-09-13T03:15:05.191Z,1379042105.191 [DVL_micro](INFO): NQ1 requested
2013-09-13T03:15:05.191Z,1379042105.191 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:15:05.192Z,1379042105.192 [DVL_micro](INFO): resume:Powering up
2013-09-13T03:15:05.192Z,1379042105.192 [DVL_micro](INFO): Cycling power to configure device.
2013-09-13T03:15:05.359Z,1379042105.359 [DAT](INFO): Init failed - response: 
2013-09-13T03:15:05.359Z,1379042105.359 [DAT](FAULT): DAT failed to initialize
2013-09-13T03:15:05.359Z,1379042105.359 [DAT] Communications Fault, FailCount= 1
2013-09-13T03:15:05.359Z,1379042105.359 [DAT](ERROR): Communications Fault
2013-09-13T03:15:05.908Z,1379042105.908 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-09-13T03:15:05.908Z,1379042105.908 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-09-13T03:15:05.908Z,1379042105.908 [BuoyancyServo] Communications Fault, FailCount= 2
2013-09-13T03:15:05.908Z,1379042105.908 [BuoyancyServo](ERROR): Communications Fault
2013-09-13T03:15:05.908Z,1379042105.908 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-09-13T03:15:05.909Z,1379042105.909 [ElevatorServo](INFO): Powering down
2013-09-13T03:15:05.910Z,1379042105.910 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-09-13T03:15:05.910Z,1379042105.910 [RudderServo](INFO): Powering down
2013-09-13T03:15:06.432Z,1379042106.432 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-09-13T03:15:06.949Z,1379042106.949 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2013-09-13T03:15:06.949Z,1379042106.949 [ThrusterServo](FAULT): Thruster failed to initialize
2013-09-13T03:15:06.949Z,1379042106.949 [ThrusterServo] Communications Fault, FailCount= 2
2013-09-13T03:15:06.949Z,1379042106.949 [ThrusterServo](ERROR): Communications Fault
2013-09-13T03:15:06.952Z,1379042106.952 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-09-13T03:15:06.952Z,1379042106.952 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-09-13T03:15:06.953Z,1379042106.953 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-13T03:15:06.953Z,1379042106.953 [CBIT](ERROR): Communications Fault in component: DAT
2013-09-13T03:15:07.063Z,1379042107.063 [AHRS_sp3003D](INFO): Powering down
2013-09-13T03:15:07.193Z,1379042107.193 [DAT](INFO): Powering down
2013-09-13T03:15:07.198Z,1379042107.198 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:15:07.198Z,1379042107.198 [DVL_micro](INFO): Querying output modes
2013-09-13T03:15:07.198Z,1379042107.198 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-13T03:15:07.434Z,1379042107.434 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-09-13T03:15:07.435Z,1379042107.435 [BuoyancyServo](INFO): Powering down
2013-09-13T03:15:07.955Z,1379042107.955 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-09-13T03:15:07.955Z,1379042107.955 [MassServo] Communications Fault, FailCount= 2
2013-09-13T03:15:07.955Z,1379042107.955 [MassServo](ERROR): Communications Fault
2013-09-13T03:15:07.956Z,1379042107.956 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-09-13T03:15:07.956Z,1379042107.956 [ThrusterServo](INFO): Powering down
2013-09-13T03:15:07.959Z,1379042107.959 [CBIT](ERROR): Communications Fault in component: MassServo
2013-09-13T03:15:08.097Z,1379042108.097 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:08.216Z,1379042108.216 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-09-13T03:15:08.217Z,1379042108.217 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-13T03:15:08.217Z,1379042108.217 [MassServo](INFO): Powering down
2013-09-13T03:15:08.218Z,1379042108.218 [RudderServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:08.336Z,1379042108.336 [RudderServo](DEBUG): Initializing RudderServo.
2013-09-13T03:15:08.340Z,1379042108.340 [CBIT](INFO): Clearing failed state for component ElevatorServo
2013-09-13T03:15:08.340Z,1379042108.340 [ElevatorServo] No Fault, FailCount= 2
2013-09-13T03:15:08.340Z,1379042108.340 [CBIT](INFO): Clearing failed state for component RudderServo
2013-09-13T03:15:08.340Z,1379042108.340 [RudderServo] No Fault, FailCount= 2
2013-09-13T03:15:08.583Z,1379042108.583 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-09-13T03:15:08.583Z,1379042108.583 [ThrusterServo] No Fault, FailCount= 2
2013-09-13T03:15:08.583Z,1379042108.583 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-13T03:15:08.583Z,1379042108.583 [AHRS_sp3003D] No Fault, FailCount= 2
2013-09-13T03:15:08.584Z,1379042108.584 [CBIT](INFO): Clearing failed state for component DAT
2013-09-13T03:15:08.584Z,1379042108.584 [DAT] No Fault, FailCount= 1
2013-09-13T03:15:08.813Z,1379042108.813 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-13T03:15:09.205Z,1379042109.205 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:09.205Z,1379042109.205 [DVL_micro](INFO): Output Modes: No Response
2013-09-13T03:15:09.205Z,1379042109.205 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-13T03:15:09.373Z,1379042109.373 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout
2013-09-13T03:15:09.373Z,1379042109.373 [ElevatorServo](FAULT): Elevator failed to initialize
2013-09-13T03:15:09.373Z,1379042109.373 [ElevatorServo] Communications Fault, FailCount= 3
2013-09-13T03:15:09.373Z,1379042109.373 [ElevatorServo](ERROR): Communications Fault
2013-09-13T03:15:09.374Z,1379042109.374 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:09.494Z,1379042109.494 [MassServo](DEBUG): Initializing MassServo.
2013-09-13T03:15:10.006Z,1379042110.006 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2013-09-13T03:15:10.006Z,1379042110.006 [RudderServo](FAULT): Rudder failed to initialize
2013-09-13T03:15:10.006Z,1379042110.006 [RudderServo] Communications Fault, FailCount= 3
2013-09-13T03:15:10.006Z,1379042110.006 [RudderServo](ERROR): Communications Fault
2013-09-13T03:15:10.007Z,1379042110.007 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:10.008Z,1379042110.008 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-09-13T03:15:10.012Z,1379042110.012 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2013-09-13T03:15:10.012Z,1379042110.012 [CBIT](CRITICAL): Communications Fault in component: ElevatorServo
2013-09-13T03:15:10.012Z,1379042110.012 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-13T03:15:10.012Z,1379042110.012 [MassServo] No Fault, FailCount= 2
2013-09-13T03:15:10.013Z,1379042110.013 [CBIT](ERROR): Communications Fault in component: RudderServo
2013-09-13T03:15:10.013Z,1379042110.013 [CBIT](CRITICAL): Communications Fault in component: RudderServo
2013-09-13T03:15:10.375Z,1379042110.375 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-13T03:15:10.375Z,1379042110.375 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-13T03:15:10.375Z,1379042110.375 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2013-09-13T03:15:10.375Z,1379042110.375 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-13T03:15:10.383Z,1379042110.383 [DAT](INFO): Powering up
2013-09-13T03:15:10.384Z,1379042110.384 [DAT](DEBUG): Initializing DAT.
2013-09-13T03:15:10.417Z,1379042110.417 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-09-13T03:15:10.418Z,1379042110.418 [ElevatorServo](INFO): Powering down
2013-09-13T03:15:10.425Z,1379042110.425 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-09-13T03:15:10.426Z,1379042110.426 [RudderServo](INFO): Powering down
2013-09-13T03:15:10.448Z,1379042110.448 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-13T03:15:10.462Z,1379042110.462 [AHRS_sp3003D](INFO): Powering down
2013-09-13T03:15:11.112Z,1379042111.112 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-09-13T03:15:11.208Z,1379042111.208 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:11.210Z,1379042111.210 [DVL_micro](INFO): NQ1 requested
2013-09-13T03:15:11.210Z,1379042111.210 [DVL_micro](INFO): Cycling power to configure device.
2013-09-13T03:15:11.636Z,1379042111.636 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2013-09-13T03:15:11.636Z,1379042111.636 [ThrusterServo](FAULT): Thruster failed to initialize
2013-09-13T03:15:11.637Z,1379042111.637 [ThrusterServo] Communications Fault, FailCount= 3
2013-09-13T03:15:11.637Z,1379042111.637 [ThrusterServo](ERROR): Communications Fault
2013-09-13T03:15:11.639Z,1379042111.639 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-09-13T03:15:11.639Z,1379042111.639 [BuoyancyServo] No Fault, FailCount= 2
2013-09-13T03:15:11.640Z,1379042111.640 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-09-13T03:15:11.724Z,1379042111.724 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:11.842Z,1379042111.842 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-13T03:15:11.843Z,1379042111.843 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:11.962Z,1379042111.962 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-09-13T03:15:12.466Z,1379042112.466 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-09-13T03:15:12.466Z,1379042112.466 [MassServo] Communications Fault, FailCount= 3
2013-09-13T03:15:12.466Z,1379042112.466 [MassServo](ERROR): Communications Fault
2013-09-13T03:15:12.467Z,1379042112.467 [RudderServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:12.587Z,1379042112.587 [RudderServo](DEBUG): Initializing RudderServo.
2013-09-13T03:15:12.588Z,1379042112.588 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-09-13T03:15:12.588Z,1379042112.588 [ThrusterServo](INFO): Powering down
2013-09-13T03:15:12.592Z,1379042112.592 [CBIT](ERROR): Communications Fault in component: MassServo
2013-09-13T03:15:13.159Z,1379042113.159 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-09-13T03:15:13.159Z,1379042113.159 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-09-13T03:15:13.159Z,1379042113.159 [BuoyancyServo] Communications Fault, FailCount= 3
2013-09-13T03:15:13.159Z,1379042113.159 [BuoyancyServo](ERROR): Communications Fault
2013-09-13T03:15:13.160Z,1379042113.160 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-13T03:15:13.160Z,1379042113.160 [MassServo](INFO): Powering down
2013-09-13T03:15:13.163Z,1379042113.163 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-09-13T03:15:13.164Z,1379042113.164 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-13T03:15:13.164Z,1379042113.164 [AHRS_sp3003D] No Fault, FailCount= 3
2013-09-13T03:15:13.210Z,1379042113.210 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-13T03:15:13.215Z,1379042113.215 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:15:13.216Z,1379042113.216 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-13T03:15:13.239Z,1379042113.239 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-09-13T03:15:13.240Z,1379042113.240 [BuoyancyServo](INFO): Powering down
2013-09-13T03:15:13.374Z,1379042113.374 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-09-13T03:15:13.374Z,1379042113.374 [ThrusterServo] No Fault, FailCount= 3
2013-09-13T03:15:13.621Z,1379042113.621 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:13.739Z,1379042113.739 [MassServo](DEBUG): Initializing MassServo.
2013-09-13T03:15:13.741Z,1379042113.741 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:13.742Z,1379042113.742 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-09-13T03:15:13.745Z,1379042113.745 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-13T03:15:13.745Z,1379042113.745 [MassServo] No Fault, FailCount= 3
2013-09-13T03:15:14.341Z,1379042114.341 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-13T03:15:14.341Z,1379042114.341 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-13T03:15:14.341Z,1379042114.341 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2013-09-13T03:15:14.341Z,1379042114.341 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-13T03:15:14.934Z,1379042114.934 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-09-13T03:15:15.218Z,1379042115.218 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:15.218Z,1379042115.218 [DVL_micro](INFO): Enabling NQ1 output
2013-09-13T03:15:15.218Z,1379042115.218 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-13T03:15:15.458Z,1379042115.458 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2013-09-13T03:15:15.458Z,1379042115.458 [ThrusterServo](FAULT): Thruster failed to initialize
2013-09-13T03:15:15.459Z,1379042115.459 [ThrusterServo] Communications Fault, FailCount= 4
2013-09-13T03:15:15.459Z,1379042115.459 [ThrusterServo](ERROR): Communications Fault
2013-09-13T03:15:15.462Z,1379042115.462 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-09-13T03:15:15.462Z,1379042115.462 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-13T03:15:15.515Z,1379042115.515 [AHRS_sp3003D](INFO): Powering down
2013-09-13T03:15:15.612Z,1379042115.612 [NAL9602](INFO): Powering down
2013-09-13T03:15:16.135Z,1379042116.135 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-09-13T03:15:16.135Z,1379042116.135 [MassServo] Communications Fault, FailCount= 4
2013-09-13T03:15:16.136Z,1379042116.136 [MassServo](ERROR): Communications Fault
2013-09-13T03:15:16.136Z,1379042116.136 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-09-13T03:15:16.136Z,1379042116.136 [ThrusterServo](INFO): Powering down
2013-09-13T03:15:16.140Z,1379042116.140 [CBIT](ERROR): Communications Fault in component: MassServo
2013-09-13T03:15:16.212Z,1379042116.212 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-13T03:15:16.212Z,1379042116.212 [MassServo](INFO): Powering down
2013-09-13T03:15:16.216Z,1379042116.216 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-09-13T03:15:16.216Z,1379042116.216 [BuoyancyServo] No Fault, FailCount= 3
2013-09-13T03:15:16.593Z,1379042116.593 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:16.717Z,1379042116.717 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-13T03:15:16.839Z,1379042116.839 [CBIT](INFO): Clearing failed state for component ThrusterServo
2013-09-13T03:15:16.840Z,1379042116.840 [ThrusterServo] No Fault, FailCount= 4
2013-09-13T03:15:16.994Z,1379042116.994 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:17.118Z,1379042117.118 [MassServo](DEBUG): Initializing MassServo.
2013-09-13T03:15:17.119Z,1379042117.119 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:17.120Z,1379042117.120 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-09-13T03:15:17.123Z,1379042117.123 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-13T03:15:17.123Z,1379042117.123 [MassServo] No Fault, FailCount= 4
2013-09-13T03:15:17.124Z,1379042117.124 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-13T03:15:17.124Z,1379042117.124 [AHRS_sp3003D] No Fault, FailCount= 4
2013-09-13T03:15:17.225Z,1379042117.225 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:17.225Z,1379042117.225 [DVL_micro](INFO): Querying output modes
2013-09-13T03:15:17.225Z,1379042117.225 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-13T03:15:17.358Z,1379042117.358 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-13T03:15:17.922Z,1379042117.922 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-09-13T03:15:17.922Z,1379042117.922 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-09-13T03:15:17.922Z,1379042117.922 [BuoyancyServo] Communications Fault, FailCount= 4
2013-09-13T03:15:17.923Z,1379042117.923 [BuoyancyServo](ERROR): Communications Fault
2013-09-13T03:15:18.451Z,1379042118.451 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout
2013-09-13T03:15:18.984Z,1379042118.984 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2013-09-13T03:15:18.984Z,1379042118.984 [ThrusterServo](FAULT): Thruster failed to initialize
2013-09-13T03:15:18.984Z,1379042118.984 [ThrusterServo] Communications Fault, FailCount= 5
2013-09-13T03:15:18.984Z,1379042118.984 [ThrusterServo](ERROR): Communications Fault
2013-09-13T03:15:18.987Z,1379042118.987 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-09-13T03:15:18.988Z,1379042118.988 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2013-09-13T03:15:18.988Z,1379042118.988 [CBIT](CRITICAL): Communications Fault in component: ThrusterServo
2013-09-13T03:15:19.232Z,1379042119.232 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:19.232Z,1379042119.232 [DVL_micro](INFO): Output Modes: No Response
2013-09-13T03:15:19.232Z,1379042119.232 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-13T03:15:19.352Z,1379042119.352 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-13T03:15:19.353Z,1379042119.353 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-13T03:15:19.353Z,1379042119.353 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2013-09-13T03:15:19.353Z,1379042119.353 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-13T03:15:19.382Z,1379042119.382 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-09-13T03:15:19.382Z,1379042119.382 [BuoyancyServo](INFO): Powering down
2013-09-13T03:15:19.909Z,1379042119.909 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2013-09-13T03:15:19.909Z,1379042119.909 [MassServo] Communications Fault, FailCount= 5
2013-09-13T03:15:19.909Z,1379042119.909 [MassServo](ERROR): Communications Fault
2013-09-13T03:15:19.910Z,1379042119.910 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-09-13T03:15:19.910Z,1379042119.910 [ThrusterServo](INFO): Powering down
2013-09-13T03:15:19.914Z,1379042119.914 [CBIT](ERROR): Communications Fault in component: MassServo
2013-09-13T03:15:19.914Z,1379042119.914 [CBIT](CRITICAL): Communications Fault in component: MassServo
2013-09-13T03:15:19.915Z,1379042119.915 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-13T03:15:19.915Z,1379042119.915 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2013-09-13T03:15:19.936Z,1379042119.936 [AHRS_sp3003D](INFO): Powering down
2013-09-13T03:15:20.038Z,1379042120.038 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-13T03:15:20.038Z,1379042120.038 [MassServo](INFO): Powering down
2013-09-13T03:15:20.757Z,1379042120.757 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:20.872Z,1379042120.872 [MassServo](DEBUG): Initializing MassServo.
2013-09-13T03:15:21.239Z,1379042121.239 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:21.562Z,1379042121.562 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-09-13T03:15:21.563Z,1379042121.563 [BuoyancyServo] No Fault, FailCount= 4
2013-09-13T03:15:21.960Z,1379042121.960 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-13T03:15:22.081Z,1379042122.081 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-13T03:15:23.243Z,1379042123.243 [DVL_micro](INFO): NQ1 requested
2013-09-13T03:15:23.243Z,1379042123.243 [DVL_micro](INFO): Cycling power to configure device.
2013-09-13T03:15:23.302Z,1379042123.302 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-09-13T03:15:23.303Z,1379042123.303 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-09-13T03:15:23.303Z,1379042123.303 [BuoyancyServo] Communications Fault, FailCount= 5
2013-09-13T03:15:23.303Z,1379042123.303 [BuoyancyServo](ERROR): Communications Fault
2013-09-13T03:15:23.306Z,1379042123.306 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-09-13T03:15:23.306Z,1379042123.306 [CBIT](CRITICAL): Communications Fault in component: BuoyancyServo
2013-09-13T03:15:23.340Z,1379042123.340 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-09-13T03:15:23.341Z,1379042123.341 [BuoyancyServo](INFO): Powering down
2013-09-13T03:15:24.556Z,1379042124.556 [CBIT](IMPORTANT): No ground fault detected
2013-09-13T03:15:25.246Z,1379042125.246 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:15:25.246Z,1379042125.246 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-13T03:15:27.253Z,1379042127.253 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:27.253Z,1379042127.253 [DVL_micro](INFO): Enabling NQ1 output
2013-09-13T03:15:27.253Z,1379042127.253 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-13T03:15:29.264Z,1379042129.264 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:29.264Z,1379042129.264 [DVL_micro](INFO): Querying output modes
2013-09-13T03:15:29.264Z,1379042129.264 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-13T03:15:30.033Z,1379042130.033 [DAT](INFO): Init failed - response: 
2013-09-13T03:15:30.033Z,1379042130.033 [DAT](FAULT): DAT failed to initialize
2013-09-13T03:15:30.033Z,1379042130.033 [DAT] Communications Fault, FailCount= 2
2013-09-13T03:15:30.033Z,1379042130.033 [DAT](ERROR): Communications Fault
2013-09-13T03:15:30.056Z,1379042130.056 [CBIT](ERROR): Communications Fault in component: DAT
2013-09-13T03:15:30.129Z,1379042130.129 [DAT](INFO): Powering down
2013-09-13T03:15:30.949Z,1379042130.949 [CBIT](INFO): Clearing failed state for component DAT
2013-09-13T03:15:30.949Z,1379042130.949 [DAT] No Fault, FailCount= 2
2013-09-13T03:15:31.267Z,1379042131.267 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:31.267Z,1379042131.267 [DVL_micro](INFO): Output Modes: No Response
2013-09-13T03:15:31.267Z,1379042131.267 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-13T03:15:33.278Z,1379042133.278 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:33.330Z,1379042133.330 [DAT](INFO): Powering up
2013-09-13T03:15:33.330Z,1379042133.330 [DAT](DEBUG): Initializing DAT.
2013-09-13T03:15:35.290Z,1379042135.290 [DVL_micro](INFO): NQ1 requested
2013-09-13T03:15:35.290Z,1379042135.290 [DVL_micro](INFO): Cycling power to configure device.
2013-09-13T03:15:37.293Z,1379042137.293 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-13T03:15:37.293Z,1379042137.293 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-13T03:15:39.299Z,1379042139.299 [DVL_micro](DEBUG): cmdResponse: 
2013-09-13T03:15:39.299Z,1379042139.299 [DVL_micro](INFO): Enabling NQ1 output
2013-09-13T03:15:39.300Z,1379042139.300 [