2013-09-14T18:27:53.204Z,1379183273.204 [Supervisor](DEBUG): Initializing supervisor. 2013-09-14T18:27:53.207Z,1379183273.207 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2013-09-14T18:27:53.207Z,1379183273.207 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2013-09-14T18:27:53.208Z,1379183273.208 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2013-09-14T18:27:53.212Z,1379183273.212 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2013-09-14T18:27:53.223Z,1379183273.223 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2013-09-14T18:27:53.224Z,1379183273.224 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2013-09-14T18:27:53.225Z,1379183273.225 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2013-09-14T18:27:53.226Z,1379183273.226 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2013-09-14T18:27:53.227Z,1379183273.227 [Supervisor](INFO): Looking for Config files in directory: Config/ 2013-09-14T18:27:53.228Z,1379183273.228 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2013-09-14T18:27:53.540Z,1379183273.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2013-09-14T18:27:53.540Z,1379183273.540 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2013-09-14T18:27:53.753Z,1379183273.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2013-09-14T18:27:53.753Z,1379183273.753 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2013-09-14T18:27:53.834Z,1379183273.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2013-09-14T18:27:53.835Z,1379183273.835 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2013-09-14T18:27:53.944Z,1379183273.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2013-09-14T18:27:53.944Z,1379183273.944 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2013-09-14T18:27:54.077Z,1379183274.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2013-09-14T18:27:54.077Z,1379183274.077 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2013-09-14T18:27:54.307Z,1379183274.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2013-09-14T18:27:54.308Z,1379183274.308 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2013-09-14T18:27:54.466Z,1379183274.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2013-09-14T18:27:54.466Z,1379183274.466 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2013-09-14T18:27:54.719Z,1379183274.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2013-09-14T18:27:54.720Z,1379183274.720 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2013-09-14T18:27:54.816Z,1379183274.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2013-09-14T18:27:54.817Z,1379183274.817 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2013-09-14T18:27:55.229Z,1379183275.229 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2013-09-14T18:27:55.229Z,1379183275.229 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2013-09-14T18:27:55.334Z,1379183275.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2013-09-14T18:27:55.335Z,1379183275.335 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2013-09-14T18:27:55.416Z,1379183275.416 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2013-09-14T18:27:55.417Z,1379183275.417 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2013-09-14T18:27:55.510Z,1379183275.510 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2013-09-14T18:27:55.630Z,1379183275.630 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2013-09-14T18:27:55.718Z,1379183275.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2013-09-14T18:27:55.817Z,1379183275.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2013-09-14T18:27:55.926Z,1379183275.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2013-09-14T18:27:56.069Z,1379183276.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2013-09-14T18:27:56.160Z,1379183276.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2013-09-14T18:27:56.241Z,1379183276.241 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2013-09-14T18:27:56.241Z,1379183276.241 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2013-09-14T18:27:56.242Z,1379183276.242 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2013-09-14T18:27:56.401Z,1379183276.401 [InternalSim] Loaded 2013-09-14T18:27:56.401Z,1379183276.401 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2013-09-14T18:27:56.402Z,1379183276.402 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2013-09-14T18:27:56.402Z,1379183276.402 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2013-09-14T18:27:56.485Z,1379183276.485 [SBIT](DEBUG): Construct Startup Built In Test. 2013-09-14T18:27:56.513Z,1379183276.513 [SBIT] Loaded 2013-09-14T18:27:56.513Z,1379183276.513 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2013-09-14T18:27:56.514Z,1379183276.514 [IBIT](DEBUG): Construct Initiated Built In Test. 2013-09-14T18:27:56.542Z,1379183276.542 [IBIT] Loaded 2013-09-14T18:27:56.542Z,1379183276.542 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2013-09-14T18:27:56.545Z,1379183276.545 [CBIT](DEBUG): Construct CBIT Built In Test. 2013-09-14T18:27:56.656Z,1379183276.656 [CBIT] Loaded 2013-09-14T18:27:56.656Z,1379183276.656 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2013-09-14T18:27:56.656Z,1379183276.656 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2013-09-14T18:27:56.657Z,1379183276.657 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2013-09-14T18:27:56.822Z,1379183276.822 [BuoyancyServo] Loaded 2013-09-14T18:27:56.822Z,1379183276.822 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2013-09-14T18:27:56.833Z,1379183276.833 [ElevatorServo] Loaded 2013-09-14T18:27:56.834Z,1379183276.834 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2013-09-14T18:27:56.845Z,1379183276.845 [MassServo] Loaded 2013-09-14T18:27:56.845Z,1379183276.845 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2013-09-14T18:27:56.856Z,1379183276.856 [RudderServo] Loaded 2013-09-14T18:27:56.856Z,1379183276.856 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2013-09-14T18:27:56.867Z,1379183276.867 [ThrusterServo] Loaded 2013-09-14T18:27:56.867Z,1379183276.867 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2013-09-14T18:27:56.868Z,1379183276.868 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2013-09-14T18:27:56.868Z,1379183276.868 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2013-09-14T18:27:56.903Z,1379183276.903 [DepthRateCalculator] Loaded 2013-09-14T18:27:56.903Z,1379183276.903 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2013-09-14T18:27:59.997Z,1379183279.997 [HFRadarModelCalc] Loaded 2013-09-14T18:27:59.997Z,1379183279.997 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2013-09-14T18:28:00.013Z,1379183280.013 [NavChart] Loaded 2013-09-14T18:28:00.013Z,1379183280.013 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2013-09-14T18:28:00.018Z,1379183280.018 [PitchRateCalculator] Loaded 2013-09-14T18:28:00.019Z,1379183280.019 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2013-09-14T18:28:00.029Z,1379183280.029 [SpeedCalculator] Loaded 2013-09-14T18:28:00.029Z,1379183280.029 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2013-09-14T18:28:00.045Z,1379183280.045 [TempGradientCalculator] Loaded 2013-09-14T18:28:00.045Z,1379183280.045 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2013-09-14T18:28:00.050Z,1379183280.050 [YawRateCalculator] Loaded 2013-09-14T18:28:00.051Z,1379183280.051 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2013-09-14T18:28:00.096Z,1379183280.096 [Navigation] Loaded 2013-09-14T18:28:00.097Z,1379183280.097 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2013-09-14T18:28:00.097Z,1379183280.097 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2013-09-14T18:28:00.098Z,1379183280.098 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2013-09-14T18:28:00.328Z,1379183280.328 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2013-09-14T18:28:00.329Z,1379183280.329 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2013-09-14T18:28:00.351Z,1379183280.351 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2013-09-14T18:28:00.351Z,1379183280.351 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2013-09-14T18:28:00.397Z,1379183280.397 [VerticalControl](DEBUG): Construct VerticalControl. 2013-09-14T18:28:00.488Z,1379183280.488 [VerticalControl] Loaded 2013-09-14T18:28:00.488Z,1379183280.488 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2013-09-14T18:28:00.489Z,1379183280.489 [HorizontalControl](DEBUG): Construct HorizontalControl. 2013-09-14T18:28:00.546Z,1379183280.546 [HorizontalControl] Loaded 2013-09-14T18:28:00.546Z,1379183280.546 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2013-09-14T18:28:00.547Z,1379183280.547 [SpeedControl](DEBUG): Construct SpeedControl. 2013-09-14T18:28:00.548Z,1379183280.548 [SpeedControl] Loaded 2013-09-14T18:28:00.549Z,1379183280.549 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2013-09-14T18:28:00.549Z,1379183280.549 [LoopControl](DEBUG): Construct LoopControl. 2013-09-14T18:28:00.550Z,1379183280.550 [LoopControl] Loaded 2013-09-14T18:28:00.550Z,1379183280.550 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2013-09-14T18:28:00.551Z,1379183280.551 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2013-09-14T18:28:00.551Z,1379183280.551 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2013-09-14T18:28:00.557Z,1379183280.557 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2013-09-14T18:28:00.562Z,1379183280.562 [AsyncPiEstimator] Loaded 2013-09-14T18:28:00.562Z,1379183280.562 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2013-09-14T18:28:00.563Z,1379183280.563 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0 2013-09-14T18:28:00.564Z,1379183280.564 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2013-09-14T18:28:00.564Z,1379183280.564 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2013-09-14T18:28:00.753Z,1379183280.753 [AHRS_sp3003D] Loaded 2013-09-14T18:28:00.753Z,1379183280.753 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2013-09-14T18:28:00.996Z,1379183280.996 [Batt_Ocean_Server] Loaded 2013-09-14T18:28:00.996Z,1379183280.996 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2013-09-14T18:28:01.010Z,1379183281.010 [Depth_Keller] Loaded 2013-09-14T18:28:01.010Z,1379183281.010 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2013-09-14T18:28:01.015Z,1379183281.015 [DropWeight] Loaded 2013-09-14T18:28:01.015Z,1379183281.015 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2013-09-14T18:28:01.194Z,1379183281.194 [DVL_micro] Loaded 2013-09-14T18:28:01.194Z,1379183281.194 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2013-09-14T18:28:01.198Z,1379183281.198 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0 2013-09-14T18:28:01.282Z,1379183281.282 [NAL9602] Loaded 2013-09-14T18:28:01.283Z,1379183281.283 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2013-09-14T18:28:01.340Z,1379183281.340 [Onboard] Loaded 2013-09-14T18:28:01.340Z,1379183281.340 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2013-09-14T18:28:01.347Z,1379183281.347 [Radio_Freewave] Loaded 2013-09-14T18:28:01.347Z,1379183281.347 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2013-09-14T18:28:01.484Z,1379183281.484 [DAT] Loaded 2013-09-14T18:28:01.484Z,1379183281.484 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2013-09-14T18:28:01.485Z,1379183281.485 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2013-09-14T18:28:01.486Z,1379183281.486 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2013-09-14T18:28:01.547Z,1379183281.547 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2013-09-14T18:28:01.550Z,1379183281.550 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2013-09-14T18:28:01.550Z,1379183281.550 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2013-09-14T18:28:01.557Z,1379183281.557 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2013-09-14T18:28:01.559Z,1379183281.559 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407324E0 2013-09-14T18:28:01.563Z,1379183281.563 [Supervisor](DEBUG): Running supervisor. 2013-09-14T18:28:01.565Z,1379183281.565 [CommandLine](INFO): Thread ID is 8986 2013-09-14T18:28:01.568Z,1379183281.568 [controlThread](INFO): Thread ID is 8985 2013-09-14T18:28:01.568Z,1379183281.568 [controlThread](DEBUG): Initializing ControlThread 2013-09-14T18:28:01.569Z,1379183281.569 [CycleStarter](INFO): Thread ID is 8984 2013-09-14T18:28:01.569Z,1379183281.569 [InternalSim](DEBUG): InternalSim initializing... 2013-09-14T18:28:01.604Z,1379183281.604 [logger](INFO): Thread ID is 8987 2013-09-14T18:28:01.620Z,1379183281.620 [SBIT](INFO): Initialize SBIT Component. 2013-09-14T18:28:01.621Z,1379183281.621 [SBIT](IMPORTANT): Tethys CM Info: $Rev:10573 2013-09-14T18:28:01.621Z,1379183281.621 [SBIT](IMPORTANT): Kernel Release:2.6.27.8 2013-09-14T18:28:01.621Z,1379183281.621 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013 2013-09-14T18:28:01.622Z,1379183281.622 [IBIT](INFO): Initialize IBIT Component. 2013-09-14T18:28:01.623Z,1379183281.623 [CBIT](DEBUG): Initialize CBIT Component. 2013-09-14T18:28:01.623Z,1379183281.623 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2013-09-14T18:28:01.633Z,1379183281.633 [AsyncPiEstimator](INFO): Thread ID is 9052 2013-09-14T18:28:01.633Z,1379183281.633 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2013-09-14T18:28:01.653Z,1379183281.653 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-09-14T18:28:01.654Z,1379183281.654 [NavChart](DEBUG): Initialize NavChart Derivation. 2013-09-14T18:28:01.654Z,1379183281.654 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2013-09-14T18:28:01.655Z,1379183281.655 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2013-09-14T18:28:01.655Z,1379183281.655 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2013-09-14T18:28:01.655Z,1379183281.655 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2013-09-14T18:28:01.656Z,1379183281.656 [Navigation](DEBUG): Initializing Navigation. 2013-09-14T18:28:01.657Z,1379183281.657 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2013-09-14T18:28:01.659Z,1379183281.659 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2013-09-14T18:28:01.660Z,1379183281.660 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2013-09-14T18:28:01.661Z,1379183281.661 [LoopControl](DEBUG): Initialize LoopControlComponent. 2013-09-14T18:28:01.663Z,1379183281.663 [DVL_micro](INFO): Thread ID is 9053 2013-09-14T18:28:01.672Z,1379183281.672 [DVL_micro](INFO): Initializing 2013-09-14T18:28:01.672Z,1379183281.672 [DVL_micro](INFO): start:Powering up 2013-09-14T18:28:01.673Z,1379183281.673 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-09-14T18:28:01.674Z,1379183281.674 [DVL_micro](INFO): Cycling power to configure device. 2013-09-14T18:28:01.692Z,1379183281.692 [NavChartDb](INFO): Thread ID is 9054 2013-09-14T18:28:01.694Z,1379183281.694 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2013-09-14T18:28:01.695Z,1379183281.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2013-09-14T18:28:01.695Z,1379183281.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2013-09-14T18:28:01.695Z,1379183281.695 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2013-09-14T18:28:01.696Z,1379183281.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2013-09-14T18:28:01.696Z,1379183281.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2013-09-14T18:28:01.696Z,1379183281.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2013-09-14T18:28:01.696Z,1379183281.696 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2013-09-14T18:28:01.697Z,1379183281.697 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2013-09-14T18:28:03.744Z,1379183283.744 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-09-14T18:28:03.744Z,1379183283.744 [DVL_micro](INFO): Querying output modes 2013-09-14T18:28:03.745Z,1379183283.745 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-09-14T18:28:03.756Z,1379183283.756 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2013-09-14T18:28:03.756Z,1379183283.756 [DVL_micro](INFO): NQ1 output enabled 2013-09-14T18:28:03.756Z,1379183283.756 [DVL_micro](INFO): RSSI output enabled 2013-09-14T18:28:03.756Z,1379183283.756 [DVL_micro](INFO): ADCP output enabled 2013-09-14T18:28:03.756Z,1379183283.756 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-09-14T18:28:03.768Z,1379183283.768 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-09-14T18:28:03.773Z,1379183283.773 [DVL_micro](INFO): pause:Powering down 2013-09-14T18:28:08.231Z,1379183288.231 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:28:08.231Z,1379183288.231 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:28:08.231Z,1379183288.231 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T18:28:08.231Z,1379183288.231 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:28:08.238Z,1379183288.238 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2013-09-14T18:28:08.271Z,1379183288.271 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-14T18:28:08.297Z,1379183288.297 [MissionManager](DEBUG): 2013-09-14T18:28:08.298Z,1379183288.298 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2013-09-14T18:28:08.391Z,1379183288.391 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2013-09-14T18:28:08.393Z,1379183288.393 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2013-09-14T18:28:08.396Z,1379183288.396 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-14T18:28:08.452Z,1379183288.452 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2013-09-14T18:28:08.455Z,1379183288.455 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-14T18:28:08.478Z,1379183288.478 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2013-09-14T18:28:08.487Z,1379183288.487 [Default:D.SetSpeed](DEBUG): Construct. 2013-09-14T18:28:08.490Z,1379183288.490 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-14T18:28:08.536Z,1379183288.536 [Default:F.Wait](DEBUG): Construct Wait. 2013-09-14T18:28:08.540Z,1379183288.540 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2013-09-14T18:28:08.555Z,1379183288.555 [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-14T18:28:08.599Z,1379183288.599 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2013-09-14T18:28:08.780Z,1379183288.780 [NAL9602](INFO): Powering up NAL9602 2013-09-14T18:28:08.852Z,1379183288.852 [Radio_Freewave](INFO): Powering up 2013-09-14T18:28:08.857Z,1379183288.857 [DAT](INFO): Powering up 2013-09-14T18:28:08.857Z,1379183288.857 [DAT](DEBUG): Initializing DAT. 2013-09-14T18:28:09.159Z,1379183289.159 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-09-14T18:28:09.164Z,1379183289.164 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-09-14T18:28:09.170Z,1379183289.170 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-09-14T18:28:09.176Z,1379183289.176 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-09-14T18:28:09.182Z,1379183289.182 [MassServo](DEBUG): Initializing EZServoServo. 2013-09-14T18:28:09.188Z,1379183289.188 [MassServo](DEBUG): Initializing MassServo. 2013-09-14T18:28:09.194Z,1379183289.194 [RudderServo](DEBUG): Initializing EZServoServo. 2013-09-14T18:28:09.200Z,1379183289.200 [RudderServo](DEBUG): Initializing RudderServo. 2013-09-14T18:28:09.206Z,1379183289.206 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-09-14T18:28:09.244Z,1379183289.244 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-09-14T18:28:09.267Z,1379183289.267 [CBIT](FAULT): Main Battery Failure. Count: 1 2013-09-14T18:28:09.268Z,1379183289.268 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:28:09.268Z,1379183289.268 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T18:28:09.268Z,1379183289.268 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T18:28:16.022Z,1379183296.022 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:28:16.023Z,1379183296.023 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:28:16.023Z,1379183296.023 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T18:28:16.023Z,1379183296.023 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:28:16.353Z,1379183296.353 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:28:16.736Z,1379183296.736 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T18:28:16.736Z,1379183296.736 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T18:28:23.502Z,1379183303.502 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:28:23.502Z,1379183303.502 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:28:23.502Z,1379183303.502 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T18:28:23.502Z,1379183303.502 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:28:23.586Z,1379183303.586 [SBIT](IMPORTANT): Beginning Startup BIT 2013-09-14T18:28:23.589Z,1379183303.589 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:28:23.589Z,1379183303.589 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:28:23.590Z,1379183303.590 [CBIT](IMPORTANT): Beginning GF scan 2013-09-14T18:28:24.051Z,1379183304.051 [NAL9602](INFO): NAL9602 initialized 2013-09-14T18:28:24.229Z,1379183304.229 [CommandLine](FAULT): Scheduling is paused 2013-09-14T18:28:28.407Z,1379183308.407 [DAT](INFO): Init failed - response: 2013-09-14T18:28:28.407Z,1379183308.407 [DAT](FAULT): DAT failed to initialize 2013-09-14T18:28:28.407Z,1379183308.407 [DAT] Communications Fault, FailCount= 1 2013-09-14T18:28:28.407Z,1379183308.407 [DAT](ERROR): Communications Fault 2013-09-14T18:28:28.665Z,1379183308.665 [CBIT](ERROR): Communications Fault in component: DAT 2013-09-14T18:28:28.894Z,1379183308.894 [DAT](INFO): Powering down 2013-09-14T18:28:29.928Z,1379183309.928 [CBIT](INFO): Clearing failed state for component DAT 2013-09-14T18:28:29.928Z,1379183309.928 [DAT] No Fault, FailCount= 1 2013-09-14T18:28:32.253Z,1379183312.253 [DAT](INFO): Powering up 2013-09-14T18:28:32.253Z,1379183312.253 [DAT](DEBUG): Initializing DAT. 2013-09-14T18:28:50.076Z,1379183330.076 [CBIT](IMPORTANT): No ground fault detected 2013-09-14T18:28:52.438Z,1379183332.438 [DAT](INFO): Powering down 2013-09-14T18:29:17.345Z,1379183357.345 [SBIT](IMPORTANT): SBIT PASSED 2013-09-14T18:29:17.745Z,1379183357.745 [MissionManager](IMPORTANT): Started mission Startup 2013-09-14T18:29:17.745Z,1379183357.745 [Startup] Running Loop=1 2013-09-14T18:29:17.746Z,1379183357.746 [Startup](INFO): Aggregate::initialize Startup 2013-09-14T18:29:17.746Z,1379183357.746 [Startup:A.GoToSurface] Running Loop=1 2013-09-14T18:29:17.746Z,1379183357.746 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-14T18:29:17.752Z,1379183357.752 [Startup:StartupSatComms] Running Loop=1 2013-09-14T18:29:17.752Z,1379183357.752 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2013-09-14T18:29:17.752Z,1379183357.752 [Startup:StartupSatComms:A] Running Loop=1 2013-09-14T18:29:18.146Z,1379183358.146 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-09-14T18:30:18.147Z,1379183418.147 [Startup:StartupSatComms:A](INFO): Timed out from 2013-09-14T18:29:17.8Z 2013-09-14T18:30:18.147Z,1379183418.147 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1 2013-09-14T18:30:18.147Z,1379183418.147 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout 2013-09-14T18:30:18.147Z,1379183418.147 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout 2013-09-14T18:30:18.147Z,1379183418.147 [Startup:StartupSatComms:A] Stopped 2013-09-14T18:30:18.147Z,1379183418.147 [Startup:StartupSatComms:B] Running Loop=1 2013-09-14T18:30:18.559Z,1379183418.559 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-09-14T18:31:18.442Z,1379183478.442 [Startup:StartupSatComms:B](INFO): Timed out from 2013-09-14T18:30:18.1Z 2013-09-14T18:31:18.443Z,1379183478.443 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1 2013-09-14T18:31:18.443Z,1379183478.443 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout 2013-09-14T18:31:18.443Z,1379183478.443 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout 2013-09-14T18:31:18.443Z,1379183478.443 [Startup:StartupSatComms:B] Stopped 2013-09-14T18:31:18.443Z,1379183478.443 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2013-09-14T18:31:18.443Z,1379183478.443 [Startup:StartupSatComms] Stopped 2013-09-14T18:31:18.443Z,1379183478.443 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2013-09-14T18:31:18.444Z,1379183478.444 [Startup](INFO): Completed Startup 2013-09-14T18:31:18.444Z,1379183478.444 [Startup] Stopped 2013-09-14T18:31:18.444Z,1379183478.444 [Startup](INFO): Aggregate::uninitialize Startup 2013-09-14T18:31:18.444Z,1379183478.444 [Startup:A.GoToSurface] Stopped 2013-09-14T18:31:18.444Z,1379183478.444 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-14T18:31:19.336Z,1379183479.336 [MissionManager](IMPORTANT): Started mission Default 2013-09-14T18:31:19.336Z,1379183479.336 [Default] Running Loop=1 2013-09-14T18:31:19.336Z,1379183479.336 [Default](INFO): Aggregate::initialize Default 2013-09-14T18:31:19.336Z,1379183479.336 [Default:D.SetSpeed] Running Loop=1 2013-09-14T18:31:19.336Z,1379183479.336 [Default:D.SetSpeed](DEBUG): Initialize. 2013-09-14T18:31:19.336Z,1379183479.336 [Default:E.GoToSurface] Running Loop=1 2013-09-14T18:31:19.336Z,1379183479.336 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-14T18:31:19.336Z,1379183479.336 [Default:Iridium] Running Loop=1 2013-09-14T18:31:19.337Z,1379183479.337 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2013-09-14T18:31:19.337Z,1379183479.337 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-09-14T18:31:19.337Z,1379183479.337 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2013-09-14T18:31:19.337Z,1379183479.337 [Default:Iridium:B.GoToSurface] Running Loop=1 2013-09-14T18:31:19.337Z,1379183479.337 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-14T18:31:19.337Z,1379183479.337 [Default:E.GoToSurface] Running Loop=1 2013-09-14T18:31:19.343Z,1379183479.343 [Default:D.SetSpeed] Running Loop=1 2013-09-14T18:31:19.348Z,1379183479.348 [Default:CallIridium] Running Loop=1 2013-09-14T18:31:19.348Z,1379183479.348 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2013-09-14T18:31:19.348Z,1379183479.348 [Default:CallIridium:A] Running Loop=1 2013-09-14T18:31:19.350Z,1379183479.350 [Default:CallIridium:A] Stopped 2013-09-14T18:31:19.350Z,1379183479.350 [Default:CallIridium:B] Running Loop=1 2013-09-14T18:31:19.350Z,1379183479.350 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2013-09-14T18:31:19.356Z,1379183479.356 [Default:Iridium:B.GoToSurface] Stopped 2013-09-14T18:31:19.356Z,1379183479.356 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-14T18:31:19.356Z,1379183479.356 [Default:Iridium:Read_Iridium] Running Loop=1 2013-09-14T18:31:19.356Z,1379183479.356 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-09-14T18:31:19.361Z,1379183479.361 [Default:GPS] Running Loop=1 2013-09-14T18:31:19.361Z,1379183479.361 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2013-09-14T18:31:19.361Z,1379183479.361 [Default:GPS:A.SetSpeed] Running Loop=1 2013-09-14T18:31:19.361Z,1379183479.361 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2013-09-14T18:31:19.361Z,1379183479.361 [Default:GPS:B.GoToSurface] Running Loop=1 2013-09-14T18:31:19.361Z,1379183479.361 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-14T18:31:19.368Z,1379183479.368 [Default:GPS:B.GoToSurface] Stopped 2013-09-14T18:31:19.368Z,1379183479.368 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-14T18:31:19.368Z,1379183479.368 [Default:GPS:Read_GPS] Running Loop=1 2013-09-14T18:31:19.368Z,1379183479.368 [Default:GPS:A.SetSpeed] Running Loop=1 2013-09-14T18:31:19.639Z,1379183479.639 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-09-14T18:31:19.642Z,1379183479.642 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-09-14T18:38:25.329Z,1379183905.329 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T18:38:25.329Z,1379183905.329 [NAL9602] Data Fault, FailCount= 1 2013-09-14T18:38:25.329Z,1379183905.329 [NAL9602](ERROR): Data Fault 2013-09-14T18:38:25.352Z,1379183905.352 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T18:38:25.750Z,1379183905.750 [NAL9602](INFO): Powering down 2013-09-14T18:38:26.550Z,1379183906.550 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T18:38:26.550Z,1379183906.550 [NAL9602] No Fault, FailCount= 1 2013-09-14T18:38:30.942Z,1379183910.942 [NAL9602](INFO): Powering up NAL9602 2013-09-14T18:38:41.761Z,1379183921.761 [NAL9602](INFO): NAL9602 initialized 2013-09-14T18:41:16.038Z,1379184076.038 [CommandLine](IMPORTANT): got command failComponent 2013-09-14T18:41:16.038Z,1379184076.038 [CommandLine](IMPORTANT): Failed components: 2013-09-14T18:41:16.038Z,1379184076.038 [CommandLine](IMPORTANT): Batt_Ocean_Server: Communications Fault 2013-09-14T18:43:23.580Z,1379184203.580 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T18:43:23.580Z,1379184203.580 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T18:43:30.508Z,1379184210.508 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:43:30.508Z,1379184210.508 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:43:30.508Z,1379184210.508 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T18:43:30.508Z,1379184210.508 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:43:30.543Z,1379184210.543 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:43:30.881Z,1379184210.881 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T18:43:30.881Z,1379184210.881 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T18:43:37.703Z,1379184217.703 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:43:37.703Z,1379184217.703 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:43:37.704Z,1379184217.704 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T18:43:37.704Z,1379184217.704 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:43:38.241Z,1379184218.241 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:43:38.562Z,1379184218.562 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T18:43:38.562Z,1379184218.562 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T18:43:45.359Z,1379184225.359 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:43:45.359Z,1379184225.359 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:43:45.359Z,1379184225.359 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T18:43:45.360Z,1379184225.360 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:43:45.392Z,1379184225.392 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:43:45.392Z,1379184225.392 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:48:43.762Z,1379184523.762 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T18:48:43.762Z,1379184523.762 [NAL9602] Data Fault, FailCount= 2 2013-09-14T18:48:43.762Z,1379184523.762 [NAL9602](ERROR): Data Fault 2013-09-14T18:48:43.786Z,1379184523.786 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T18:48:43.787Z,1379184523.787 [CBIT](CRITICAL): Data Fault in component: NAL9602 2013-09-14T18:48:44.176Z,1379184524.176 [NAL9602](INFO): Powering down 2013-09-14T18:50:43.791Z,1379184643.791 [CBIT](INFO): Clearing failed count for component NAL9602 2013-09-14T18:50:43.791Z,1379184643.791 [NAL9602] No Fault, FailCount= 2 2013-09-14T18:50:44.170Z,1379184644.170 [NAL9602](INFO): Powering up NAL9602 2013-09-14T18:50:54.617Z,1379184654.617 [NAL9602](INFO): NAL9602 initialized 2013-09-14T18:58:45.529Z,1379185125.529 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T18:58:45.529Z,1379185125.529 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T18:58:52.434Z,1379185132.434 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:58:52.434Z,1379185132.434 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:58:52.434Z,1379185132.434 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T18:58:52.434Z,1379185132.434 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:58:52.467Z,1379185132.467 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:58:52.831Z,1379185132.831 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T18:58:52.831Z,1379185132.831 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T18:58:59.649Z,1379185139.649 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:58:59.649Z,1379185139.649 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:58:59.649Z,1379185139.649 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T18:58:59.649Z,1379185139.649 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:59:00.184Z,1379185140.184 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:59:00.519Z,1379185140.519 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T18:59:00.519Z,1379185140.519 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T18:59:07.341Z,1379185147.341 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T18:59:07.341Z,1379185147.341 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T18:59:07.341Z,1379185147.341 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T18:59:07.341Z,1379185147.341 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T18:59:07.373Z,1379185147.373 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T18:59:07.374Z,1379185147.374 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:00:55.658Z,1379185255.658 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T19:00:55.659Z,1379185255.659 [NAL9602] Data Fault, FailCount= 1 2013-09-14T19:00:55.659Z,1379185255.659 [NAL9602](ERROR): Data Fault 2013-09-14T19:00:55.682Z,1379185255.682 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T19:00:56.045Z,1379185256.045 [NAL9602](INFO): Powering down 2013-09-14T19:00:56.875Z,1379185256.875 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T19:00:56.875Z,1379185256.875 [NAL9602] No Fault, FailCount= 1 2013-09-14T19:01:01.251Z,1379185261.251 [NAL9602](INFO): Powering up NAL9602 2013-09-14T19:01:11.694Z,1379185271.694 [NAL9602](INFO): NAL9602 initialized 2013-09-14T19:04:48.314Z,1379185488.314 [NAL9602](ERROR): parseGSV uart error: serial timeout 2013-09-14T19:05:19.109Z,1379185519.109 [NAL9602](ERROR): Queried for signal strength and failed to receive response. serial timeout 2013-09-14T19:05:50.826Z,1379185550.826 [NAL9602](ERROR): getFix uart error: serial timeout 2013-09-14T19:06:21.448Z,1379185581.448 [NAL9602](ERROR): parseGSV uart error: serial timeout 2013-09-14T19:06:52.242Z,1379185612.242 [NAL9602](ERROR): Queried for signal strength and failed to receive response. serial timeout 2013-09-14T19:07:23.975Z,1379185643.975 [NAL9602](ERROR): getFix uart error: serial timeout 2013-09-14T19:07:54.608Z,1379185674.608 [NAL9602](ERROR): parseGSV uart error: serial timeout 2013-09-14T19:08:25.412Z,1379185705.412 [NAL9602](ERROR): Queried for signal strength and failed to receive response. serial timeout 2013-09-14T19:08:57.119Z,1379185737.119 [NAL9602](ERROR): getFix uart error: serial timeout 2013-09-14T19:09:27.741Z,1379185767.741 [NAL9602](ERROR): parseGSV uart error: serial timeout 2013-09-14T19:09:58.539Z,1379185798.539 [NAL9602](ERROR): Queried for signal strength and failed to receive response. serial timeout 2013-09-14T19:10:30.035Z,1379185830.035 [NAL9602](ERROR): getFix uart error: serial timeout 2013-09-14T19:11:00.657Z,1379185860.657 [NAL9602](ERROR): parseGSV uart error: serial timeout 2013-09-14T19:11:31.457Z,1379185891.457 [NAL9602](ERROR): Queried for signal strength and failed to receive response. serial timeout 2013-09-14T19:11:32.249Z,1379185892.249 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T19:11:32.249Z,1379185892.249 [NAL9602] Data Fault, FailCount= 2 2013-09-14T19:11:32.249Z,1379185892.249 [NAL9602](ERROR): Data Fault 2013-09-14T19:11:32.273Z,1379185892.273 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T19:11:32.273Z,1379185892.273 [CBIT](CRITICAL): Data Fault in component: NAL9602 2013-09-14T19:11:32.644Z,1379185892.644 [NAL9602](INFO): Powering down 2013-09-14T19:13:32.272Z,1379186012.272 [CBIT](INFO): Clearing failed count for component NAL9602 2013-09-14T19:13:32.272Z,1379186012.272 [NAL9602] No Fault, FailCount= 2 2013-09-14T19:13:32.652Z,1379186012.652 [NAL9602](INFO): Powering up NAL9602 2013-09-14T19:13:43.487Z,1379186023.487 [NAL9602](INFO): NAL9602 initialized 2013-09-14T19:14:07.476Z,1379186047.476 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T19:14:07.476Z,1379186047.476 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T19:14:14.419Z,1379186054.419 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:14:14.419Z,1379186054.419 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:14:14.419Z,1379186054.419 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T19:14:14.419Z,1379186054.419 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:14:14.446Z,1379186054.446 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:14:14.787Z,1379186054.787 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T19:14:14.787Z,1379186054.787 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T19:14:21.594Z,1379186061.594 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:14:21.594Z,1379186061.594 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:14:21.594Z,1379186061.594 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T19:14:21.594Z,1379186061.594 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:14:22.154Z,1379186062.154 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:14:22.481Z,1379186062.481 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T19:14:22.481Z,1379186062.481 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T19:14:29.310Z,1379186069.310 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:14:29.310Z,1379186069.310 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:14:29.310Z,1379186069.310 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T19:14:29.310Z,1379186069.310 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:14:29.343Z,1379186069.343 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:14:29.344Z,1379186069.344 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:17:57.281Z,1379186277.281 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 0.000000 centimeter 2013-09-14T19:17:57.283Z,1379186277.283 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread. 2013-09-14T19:17:57.462Z,1379186277.462 [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,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter, 2013-09-14T19:23:45.851Z,1379186625.851 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T19:23:45.851Z,1379186625.851 [NAL9602] Data Fault, FailCount= 1 2013-09-14T19:23:45.851Z,1379186625.851 [NAL9602](ERROR): Data Fault 2013-09-14T19:23:45.875Z,1379186625.875 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T19:23:46.242Z,1379186626.242 [NAL9602](INFO): Powering down 2013-09-14T19:23:47.070Z,1379186627.070 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T19:23:47.070Z,1379186627.070 [NAL9602] No Fault, FailCount= 1 2013-09-14T19:23:51.448Z,1379186631.448 [NAL9602](INFO): Powering up NAL9602 2013-09-14T19:24:01.878Z,1379186641.878 [NAL9602](INFO): NAL9602 initialized 2013-09-14T19:29:29.952Z,1379186969.952 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T19:29:29.952Z,1379186969.952 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T19:29:36.737Z,1379186976.737 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:29:36.737Z,1379186976.737 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:29:36.737Z,1379186976.737 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T19:29:36.737Z,1379186976.737 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:29:36.785Z,1379186976.785 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:29:37.131Z,1379186977.131 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T19:29:37.131Z,1379186977.131 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T19:29:43.960Z,1379186983.960 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:29:43.960Z,1379186983.960 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:29:43.960Z,1379186983.960 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T19:29:43.960Z,1379186983.960 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:29:44.032Z,1379186984.032 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:29:44.351Z,1379186984.351 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T19:29:44.351Z,1379186984.351 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T19:29:51.183Z,1379186991.183 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:29:51.183Z,1379186991.183 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:29:51.184Z,1379186991.184 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T19:29:51.184Z,1379186991.184 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:29:51.242Z,1379186991.242 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:29:51.242Z,1379186991.242 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:34:04.213Z,1379187244.213 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T19:34:04.214Z,1379187244.214 [NAL9602] Data Fault, FailCount= 2 2013-09-14T19:34:04.214Z,1379187244.214 [NAL9602](ERROR): Data Fault 2013-09-14T19:34:04.237Z,1379187244.237 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T19:34:04.238Z,1379187244.238 [CBIT](CRITICAL): Data Fault in component: NAL9602 2013-09-14T19:34:04.614Z,1379187244.614 [NAL9602](INFO): Powering down 2013-09-14T19:36:04.237Z,1379187364.237 [CBIT](INFO): Clearing failed count for component NAL9602 2013-09-14T19:36:04.237Z,1379187364.237 [NAL9602] No Fault, FailCount= 2 2013-09-14T19:36:04.615Z,1379187364.615 [NAL9602](INFO): Powering up NAL9602 2013-09-14T19:36:15.051Z,1379187375.051 [NAL9602](INFO): NAL9602 initialized 2013-09-14T19:44:51.384Z,1379187891.384 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T19:44:51.384Z,1379187891.384 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T19:44:58.162Z,1379187898.162 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:44:58.162Z,1379187898.162 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:44:58.162Z,1379187898.162 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T19:44:58.162Z,1379187898.162 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:44:58.242Z,1379187898.242 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:44:58.560Z,1379187898.560 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T19:44:58.560Z,1379187898.560 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T19:45:05.381Z,1379187905.381 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:45:05.381Z,1379187905.381 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:45:05.381Z,1379187905.381 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T19:45:05.381Z,1379187905.381 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:45:05.471Z,1379187905.471 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:45:05.827Z,1379187905.827 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T19:45:05.827Z,1379187905.827 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T19:45:12.632Z,1379187912.632 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T19:45:12.632Z,1379187912.632 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T19:45:12.632Z,1379187912.632 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T19:45:12.632Z,1379187912.632 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T19:45:12.672Z,1379187912.672 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:45:12.672Z,1379187912.672 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T19:46:16.822Z,1379187976.822 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T19:46:16.822Z,1379187976.822 [NAL9602] Data Fault, FailCount= 1 2013-09-14T19:46:16.822Z,1379187976.822 [NAL9602](ERROR): Data Fault 2013-09-14T19:46:16.853Z,1379187976.853 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T19:46:17.216Z,1379187977.216 [NAL9602](INFO): Powering down 2013-09-14T19:46:18.041Z,1379187978.041 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T19:46:18.041Z,1379187978.041 [NAL9602] No Fault, FailCount= 1 2013-09-14T19:46:22.422Z,1379187982.422 [NAL9602](INFO): Powering up NAL9602 2013-09-14T19:46:33.261Z,1379187993.261 [NAL9602](INFO): NAL9602 initialized 2013-09-14T19:56:36.201Z,1379188596.201 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T19:56:36.201Z,1379188596.201 [NAL9602] Data Fault, FailCount= 2 2013-09-14T19:56:36.201Z,1379188596.201 [NAL9602](ERROR): Data Fault 2013-09-14T19:56:36.225Z,1379188596.225 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T19:56:36.225Z,1379188596.225 [CBIT](CRITICAL): Data Fault in component: NAL9602 2013-09-14T19:56:36.605Z,1379188596.605 [NAL9602](INFO): Powering down 2013-09-14T19:58:36.237Z,1379188716.237 [CBIT](INFO): Clearing failed count for component NAL9602 2013-09-14T19:58:36.237Z,1379188716.237 [NAL9602] No Fault, FailCount= 2 2013-09-14T19:58:36.591Z,1379188716.591 [NAL9602](INFO): Powering up NAL9602 2013-09-14T19:58:47.040Z,1379188727.040 [NAL9602](INFO): NAL9602 initialized 2013-09-14T20:00:12.743Z,1379188812.743 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T20:00:12.743Z,1379188812.743 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T20:00:19.694Z,1379188819.694 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:00:19.694Z,1379188819.694 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:00:19.695Z,1379188819.695 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T20:00:19.695Z,1379188819.695 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:00:19.768Z,1379188819.768 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:00:20.620Z,1379188820.620 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T20:00:20.620Z,1379188820.620 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T20:00:27.382Z,1379188827.382 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:00:27.382Z,1379188827.382 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:00:27.382Z,1379188827.382 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T20:00:27.383Z,1379188827.383 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:00:27.442Z,1379188827.442 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:00:27.747Z,1379188827.747 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T20:00:27.748Z,1379188827.748 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T20:00:34.593Z,1379188834.593 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:00:34.594Z,1379188834.594 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:00:34.594Z,1379188834.594 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T20:00:34.594Z,1379188834.594 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:00:34.665Z,1379188834.665 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:00:34.665Z,1379188834.665 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:08:50.808Z,1379189330.808 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T20:08:50.809Z,1379189330.809 [NAL9602] Data Fault, FailCount= 1 2013-09-14T20:08:50.809Z,1379189330.809 [NAL9602](ERROR): Data Fault 2013-09-14T20:08:50.832Z,1379189330.832 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T20:08:51.209Z,1379189331.209 [NAL9602](INFO): Powering down 2013-09-14T20:08:52.028Z,1379189332.028 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T20:08:52.028Z,1379189332.028 [NAL9602] No Fault, FailCount= 1 2013-09-14T20:08:56.405Z,1379189336.405 [NAL9602](INFO): Powering up NAL9602 2013-09-14T20:09:06.840Z,1379189346.840 [NAL9602](INFO): NAL9602 initialized 2013-09-14T20:15:34.616Z,1379189734.616 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T20:15:34.616Z,1379189734.616 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T20:15:41.544Z,1379189741.544 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:15:41.544Z,1379189741.544 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:15:41.544Z,1379189741.544 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T20:15:41.544Z,1379189741.544 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:15:41.583Z,1379189741.583 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:15:42.438Z,1379189742.438 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T20:15:42.438Z,1379189742.438 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T20:15:49.224Z,1379189749.224 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:15:49.224Z,1379189749.224 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:15:49.224Z,1379189749.224 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T20:15:49.224Z,1379189749.224 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:15:49.271Z,1379189749.271 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:15:49.625Z,1379189749.625 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T20:15:49.625Z,1379189749.625 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T20:15:56.455Z,1379189756.455 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:15:56.455Z,1379189756.455 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:15:56.455Z,1379189756.455 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T20:15:56.455Z,1379189756.455 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:15:56.494Z,1379189756.494 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:15:56.494Z,1379189756.494 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:19:09.323Z,1379189949.323 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T20:19:09.324Z,1379189949.324 [NAL9602] Data Fault, FailCount= 2 2013-09-14T20:19:09.324Z,1379189949.324 [NAL9602](ERROR): Data Fault 2013-09-14T20:19:09.348Z,1379189949.348 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T20:19:09.348Z,1379189949.348 [CBIT](CRITICAL): Data Fault in component: NAL9602 2013-09-14T20:19:09.724Z,1379189949.724 [NAL9602](INFO): Powering down 2013-09-14T20:21:09.339Z,1379190069.339 [CBIT](INFO): Clearing failed count for component NAL9602 2013-09-14T20:21:09.340Z,1379190069.340 [NAL9602] No Fault, FailCount= 2 2013-09-14T20:21:09.723Z,1379190069.723 [NAL9602](INFO): Powering up NAL9602 2013-09-14T20:21:20.562Z,1379190080.562 [NAL9602](INFO): NAL9602 initialized 2013-09-14T20:30:56.731Z,1379190656.731 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T20:30:56.731Z,1379190656.731 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T20:31:03.657Z,1379190663.657 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:31:03.657Z,1379190663.657 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:31:03.657Z,1379190663.657 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T20:31:03.658Z,1379190663.658 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:31:03.732Z,1379190663.732 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:31:04.127Z,1379190664.127 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T20:31:04.127Z,1379190664.127 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T20:31:10.900Z,1379190670.900 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:31:10.901Z,1379190670.901 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:31:10.901Z,1379190670.901 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T20:31:10.901Z,1379190670.901 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:31:10.935Z,1379190670.935 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:31:11.277Z,1379190671.277 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T20:31:11.277Z,1379190671.277 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T20:31:18.096Z,1379190678.096 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T20:31:18.096Z,1379190678.096 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T20:31:18.096Z,1379190678.096 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T20:31:18.096Z,1379190678.096 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T20:31:18.671Z,1379190678.671 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:31:18.672Z,1379190678.672 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T20:31:19.686Z,1379190679.686 [Default:Iridium:Read_Iridium](INFO): Timed out from 2013-09-14T18:31:19.4Z 2013-09-14T20:31:19.686Z,1379190679.686 [Default:Iridium:Read_Iridium:A_Timeout] Running Loop=1 2013-09-14T20:31:19.686Z,1379190679.686 [Default:Iridium:Read_Iridium:A_Timeout](INFO): Aggregate::initialize Default:Iridium:Read_Iridium:A_Timeout 2013-09-14T20:31:19.686Z,1379190679.686 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute] Running Loop=1 2013-09-14T20:31:19.686Z,1379190679.686 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](INFO): Executing command Burn 300 2013-09-14T20:31:19.692Z,1379190679.692 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute] Stopped 2013-09-14T20:31:19.692Z,1379190679.692 [Default:Iridium:Read_Iridium:A_Timeout:B] Running Loop=1 2013-09-14T20:31:19.749Z,1379190679.749 [CommandLine](IMPORTANT): got command burn 300.000000 2013-09-14T20:31:20.086Z,1379190680.086 [Default:Iridium:Read_Iridium:A_Timeout:B](CRITICAL): Dropped drop weight due to communications timeout 2013-09-14T20:31:20.088Z,1379190680.088 [Default:Iridium:Read_Iridium:A_Timeout:B] Stopped 2013-09-14T20:31:20.088Z,1379190680.088 [Default:Iridium:Read_Iridium:A_Timeout](INFO): Completed Default:Iridium:Read_Iridium:A_Timeout 2013-09-14T20:31:20.088Z,1379190680.088 [Default:Iridium:Read_Iridium] Stopped 2013-09-14T20:31:20.089Z,1379190680.089 [Default:Iridium](INFO): Completed Default:Iridium 2013-09-14T20:31:20.089Z,1379190680.089 [Default:Iridium] Stopped 2013-09-14T20:31:20.089Z,1379190680.089 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2013-09-14T20:31:20.089Z,1379190680.089 [Default:Iridium:A.SetSpeed] Stopped 2013-09-14T20:31:20.089Z,1379190680.089 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2013-09-14T20:31:20.089Z,1379190680.089 [Default:F.Wait] Running Loop=1 2013-09-14T20:31:20.089Z,1379190680.089 [Default:F.Wait](DEBUG): Initialize Wait Component. 2013-09-14T20:31:20.499Z,1379190680.499 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B 2013-09-14T20:31:20.499Z,1379190680.499 [Default:CallIridium:B] Stopped 2013-09-14T20:31:20.500Z,1379190680.500 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2013-09-14T20:31:20.500Z,1379190680.500 [Default:CallIridium](INFO): Completed Default:CallIridium 2013-09-14T20:31:20.500Z,1379190680.500 [Default:CallIridium] Stopped 2013-09-14T20:31:20.500Z,1379190680.500 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2013-09-14T20:31:20.867Z,1379190680.867 [Default:CallIridium] Running Loop=1 2013-09-14T20:31:20.867Z,1379190680.867 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2013-09-14T20:31:20.867Z,1379190680.867 [Default:CallIridium:A] Running Loop=1 2013-09-14T20:31:20.867Z,1379190680.867 [Default:CallIridium:A] Stopped 2013-09-14T20:31:20.867Z,1379190680.867 [Default:CallIridium:B] Running Loop=1 2013-09-14T20:31:20.867Z,1379190680.867 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2013-09-14T20:31:21.267Z,1379190681.267 [Default:Iridium] Running Loop=1 2013-09-14T20:31:21.267Z,1379190681.267 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2013-09-14T20:31:21.267Z,1379190681.267 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-09-14T20:31:21.267Z,1379190681.267 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2013-09-14T20:31:21.267Z,1379190681.267 [Default:Iridium:B.GoToSurface] Running Loop=1 2013-09-14T20:31:21.267Z,1379190681.267 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-14T20:31:21.268Z,1379190681.268 [Default:Iridium:B.GoToSurface] Stopped 2013-09-14T20:31:21.268Z,1379190681.268 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-14T20:31:21.268Z,1379190681.268 [Default:Iridium:Read_Iridium] Running Loop=1 2013-09-14T20:31:22.455Z,1379190682.455 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T20:31:22.455Z,1379190682.455 [NAL9602] Data Fault, FailCount= 1 2013-09-14T20:31:22.455Z,1379190682.455 [NAL9602](ERROR): Data Fault 2013-09-14T20:31:22.479Z,1379190682.479 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T20:31:22.857Z,1379190682.857 [NAL9602](INFO): Powering down 2013-09-14T20:31:23.682Z,1379190683.682 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T20:31:23.682Z,1379190683.682 [NAL9602] No Fault, FailCount= 1 2013-09-14T20:31:28.061Z,1379190688.061 [NAL9602](INFO): Powering up NAL9602 2013-09-14T20:31:38.892Z,1379190698.892 [N