2013-09-13T23:51:45.271Z,1379116305.271 [Supervisor](DEBUG): Initializing supervisor. 2013-09-13T23:51:45.273Z,1379116305.273 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0 2013-09-13T23:51:45.274Z,1379116305.274 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2013-09-13T23:51:45.275Z,1379116305.275 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0 2013-09-13T23:51:45.279Z,1379116305.279 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2013-09-13T23:51:45.290Z,1379116305.290 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2013-09-13T23:51:45.291Z,1379116305.291 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0 2013-09-13T23:51:45.292Z,1379116305.292 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2013-09-13T23:51:45.293Z,1379116305.293 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0 2013-09-13T23:51:45.293Z,1379116305.293 [Supervisor](INFO): Looking for Config files in directory: Config/ 2013-09-13T23:51:45.294Z,1379116305.294 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2013-09-13T23:51:45.569Z,1379116305.569 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2013-09-13T23:51:45.570Z,1379116305.570 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2013-09-13T23:51:45.757Z,1379116305.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2013-09-13T23:51:45.757Z,1379116305.757 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2013-09-13T23:51:45.836Z,1379116305.836 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2013-09-13T23:51:45.837Z,1379116305.837 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2013-09-13T23:51:45.943Z,1379116305.943 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2013-09-13T23:51:45.944Z,1379116305.944 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2013-09-13T23:51:46.076Z,1379116306.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2013-09-13T23:51:46.076Z,1379116306.076 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2013-09-13T23:51:46.309Z,1379116306.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2013-09-13T23:51:46.310Z,1379116306.310 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2013-09-13T23:51:46.469Z,1379116306.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2013-09-13T23:51:46.470Z,1379116306.470 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2013-09-13T23:51:46.729Z,1379116306.729 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2013-09-13T23:51:46.730Z,1379116306.730 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2013-09-13T23:51:46.826Z,1379116306.826 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2013-09-13T23:51:46.827Z,1379116306.827 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2013-09-13T23:51:47.235Z,1379116307.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2013-09-13T23:51:47.236Z,1379116307.236 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2013-09-13T23:51:47.341Z,1379116307.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2013-09-13T23:51:47.341Z,1379116307.341 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2013-09-13T23:51:47.422Z,1379116307.422 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2013-09-13T23:51:47.423Z,1379116307.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2013-09-13T23:51:47.516Z,1379116307.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2013-09-13T23:51:47.636Z,1379116307.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2013-09-13T23:51:47.724Z,1379116307.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2013-09-13T23:51:47.823Z,1379116307.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2013-09-13T23:51:47.912Z,1379116307.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2013-09-13T23:51:48.021Z,1379116308.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2013-09-13T23:51:48.111Z,1379116308.111 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2013-09-13T23:51:48.192Z,1379116308.192 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2013-09-13T23:51:48.192Z,1379116308.192 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2013-09-13T23:51:48.193Z,1379116308.193 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2013-09-13T23:51:48.351Z,1379116308.351 [InternalSim] Loaded 2013-09-13T23:51:48.351Z,1379116308.351 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread. 2013-09-13T23:51:48.352Z,1379116308.352 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2013-09-13T23:51:48.353Z,1379116308.353 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2013-09-13T23:51:48.435Z,1379116308.435 [SBIT](DEBUG): Construct Startup Built In Test. 2013-09-13T23:51:48.463Z,1379116308.463 [SBIT] Loaded 2013-09-13T23:51:48.463Z,1379116308.463 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2013-09-13T23:51:48.464Z,1379116308.464 [IBIT](DEBUG): Construct Initiated Built In Test. 2013-09-13T23:51:48.492Z,1379116308.492 [IBIT] Loaded 2013-09-13T23:51:48.492Z,1379116308.492 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2013-09-13T23:51:48.495Z,1379116308.495 [CBIT](DEBUG): Construct CBIT Built In Test. 2013-09-13T23:51:48.606Z,1379116308.606 [CBIT] Loaded 2013-09-13T23:51:48.606Z,1379116308.606 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2013-09-13T23:51:48.607Z,1379116308.607 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2013-09-13T23:51:48.607Z,1379116308.607 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2013-09-13T23:51:48.773Z,1379116308.773 [BuoyancyServo] Loaded 2013-09-13T23:51:48.773Z,1379116308.773 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2013-09-13T23:51:48.785Z,1379116308.785 [ElevatorServo] Loaded 2013-09-13T23:51:48.785Z,1379116308.785 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2013-09-13T23:51:48.796Z,1379116308.796 [MassServo] Loaded 2013-09-13T23:51:48.797Z,1379116308.797 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2013-09-13T23:51:48.808Z,1379116308.808 [RudderServo] Loaded 2013-09-13T23:51:48.808Z,1379116308.808 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2013-09-13T23:51:48.819Z,1379116308.819 [ThrusterServo] Loaded 2013-09-13T23:51:48.819Z,1379116308.819 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2013-09-13T23:51:48.820Z,1379116308.820 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2013-09-13T23:51:48.820Z,1379116308.820 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2013-09-13T23:51:48.854Z,1379116308.854 [DepthRateCalculator] Loaded 2013-09-13T23:51:48.855Z,1379116308.855 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2013-09-13T23:51:51.992Z,1379116311.992 [HFRadarModelCalc] Loaded 2013-09-13T23:51:51.992Z,1379116311.992 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread. 2013-09-13T23:51:52.008Z,1379116312.008 [NavChart] Loaded 2013-09-13T23:51:52.008Z,1379116312.008 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2013-09-13T23:51:52.014Z,1379116312.014 [PitchRateCalculator] Loaded 2013-09-13T23:51:52.014Z,1379116312.014 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2013-09-13T23:51:52.024Z,1379116312.024 [SpeedCalculator] Loaded 2013-09-13T23:51:52.025Z,1379116312.025 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2013-09-13T23:51:52.040Z,1379116312.040 [TempGradientCalculator] Loaded 2013-09-13T23:51:52.041Z,1379116312.041 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2013-09-13T23:51:52.046Z,1379116312.046 [YawRateCalculator] Loaded 2013-09-13T23:51:52.046Z,1379116312.046 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2013-09-13T23:51:52.092Z,1379116312.092 [Navigation] Loaded 2013-09-13T23:51:52.092Z,1379116312.092 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread. 2013-09-13T23:51:52.093Z,1379116312.093 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2013-09-13T23:51:52.093Z,1379116312.093 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2013-09-13T23:51:52.320Z,1379116312.320 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2013-09-13T23:51:52.321Z,1379116312.321 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2013-09-13T23:51:52.343Z,1379116312.343 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2013-09-13T23:51:52.344Z,1379116312.344 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2013-09-13T23:51:52.390Z,1379116312.390 [VerticalControl](DEBUG): Construct VerticalControl. 2013-09-13T23:51:52.481Z,1379116312.481 [VerticalControl] Loaded 2013-09-13T23:51:52.481Z,1379116312.481 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2013-09-13T23:51:52.482Z,1379116312.482 [HorizontalControl](DEBUG): Construct HorizontalControl. 2013-09-13T23:51:52.538Z,1379116312.538 [HorizontalControl] Loaded 2013-09-13T23:51:52.538Z,1379116312.538 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2013-09-13T23:51:52.539Z,1379116312.539 [SpeedControl](DEBUG): Construct SpeedControl. 2013-09-13T23:51:52.541Z,1379116312.541 [SpeedControl] Loaded 2013-09-13T23:51:52.541Z,1379116312.541 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2013-09-13T23:51:52.542Z,1379116312.542 [LoopControl](DEBUG): Construct LoopControl. 2013-09-13T23:51:52.542Z,1379116312.542 [LoopControl] Loaded 2013-09-13T23:51:52.542Z,1379116312.542 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2013-09-13T23:51:52.543Z,1379116312.543 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2013-09-13T23:51:52.543Z,1379116312.543 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2013-09-13T23:51:52.549Z,1379116312.549 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator. 2013-09-13T23:51:52.554Z,1379116312.554 [AsyncPiEstimator] Loaded 2013-09-13T23:51:52.554Z,1379116312.554 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread. 2013-09-13T23:51:52.555Z,1379116312.555 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0 2013-09-13T23:51:52.556Z,1379116312.556 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2013-09-13T23:51:52.557Z,1379116312.557 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2013-09-13T23:51:52.744Z,1379116312.744 [AHRS_sp3003D] Loaded 2013-09-13T23:51:52.745Z,1379116312.745 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread. 2013-09-13T23:51:52.988Z,1379116312.988 [Batt_Ocean_Server] Loaded 2013-09-13T23:51:52.988Z,1379116312.988 [ComponentRegistry](DEBUG): SyncComponent "Batt_Ocean_Server" handled in the control thread. 2013-09-13T23:51:53.001Z,1379116313.001 [Depth_Keller] Loaded 2013-09-13T23:51:53.002Z,1379116313.002 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2013-09-13T23:51:53.007Z,1379116313.007 [DropWeight] Loaded 2013-09-13T23:51:53.007Z,1379116313.007 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2013-09-13T23:51:53.184Z,1379116313.184 [DVL_micro] Loaded 2013-09-13T23:51:53.185Z,1379116313.185 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread. 2013-09-13T23:51:53.186Z,1379116313.186 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406D54E0 2013-09-13T23:51:53.271Z,1379116313.271 [NAL9602] Loaded 2013-09-13T23:51:53.271Z,1379116313.271 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2013-09-13T23:51:53.313Z,1379116313.313 [Onboard] Loaded 2013-09-13T23:51:53.313Z,1379116313.313 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2013-09-13T23:51:53.320Z,1379116313.320 [Radio_Freewave] Loaded 2013-09-13T23:51:53.320Z,1379116313.320 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread. 2013-09-13T23:51:53.457Z,1379116313.457 [DAT] Loaded 2013-09-13T23:51:53.458Z,1379116313.458 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2013-09-13T23:51:53.458Z,1379116313.458 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2013-09-13T23:51:53.459Z,1379116313.459 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2013-09-13T23:51:53.521Z,1379116313.521 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2013-09-13T23:51:53.523Z,1379116313.523 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2013-09-13T23:51:53.524Z,1379116313.524 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2013-09-13T23:51:53.531Z,1379116313.531 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2013-09-13T23:51:53.532Z,1379116313.532 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407324E0 2013-09-13T23:51:53.536Z,1379116313.536 [Supervisor](DEBUG): Running supervisor. 2013-09-13T23:51:53.537Z,1379116313.537 [CommandLine](INFO): Thread ID is 998 2013-09-13T23:51:53.541Z,1379116313.541 [controlThread](INFO): Thread ID is 997 2013-09-13T23:51:53.541Z,1379116313.541 [controlThread](DEBUG): Initializing ControlThread 2013-09-13T23:51:53.541Z,1379116313.541 [CycleStarter](INFO): Thread ID is 996 2013-09-13T23:51:53.542Z,1379116313.542 [InternalSim](DEBUG): InternalSim initializing... 2013-09-13T23:51:53.576Z,1379116313.576 [logger](INFO): Thread ID is 999 2013-09-13T23:51:53.600Z,1379116313.600 [SBIT](INFO): Initialize SBIT Component. 2013-09-13T23:51:53.600Z,1379116313.600 [SBIT](IMPORTANT): Tethys CM Info: $Rev:10573 2013-09-13T23:51:53.601Z,1379116313.601 [SBIT](IMPORTANT): Kernel Release:2.6.27.8 2013-09-13T23:51:53.601Z,1379116313.601 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013 2013-09-13T23:51:53.601Z,1379116313.601 [IBIT](INFO): Initialize IBIT Component. 2013-09-13T23:51:53.602Z,1379116313.602 [CBIT](DEBUG): Initialize CBIT Component. 2013-09-13T23:51:53.602Z,1379116313.602 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2013-09-13T23:51:53.603Z,1379116313.603 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2013-09-13T23:51:53.612Z,1379116313.612 [AsyncPiEstimator](INFO): Thread ID is 1062 2013-09-13T23:51:53.612Z,1379116313.612 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator. 2013-09-13T23:51:53.629Z,1379116313.629 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-09-13T23:51:53.629Z,1379116313.629 [NavChart](DEBUG): Initialize NavChart Derivation. 2013-09-13T23:51:53.630Z,1379116313.630 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2013-09-13T23:51:53.630Z,1379116313.630 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2013-09-13T23:51:53.630Z,1379116313.630 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2013-09-13T23:51:53.631Z,1379116313.631 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2013-09-13T23:51:53.631Z,1379116313.631 [Navigation](DEBUG): Initializing Navigation. 2013-09-13T23:51:53.632Z,1379116313.632 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2013-09-13T23:51:53.634Z,1379116313.634 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2013-09-13T23:51:53.634Z,1379116313.634 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2013-09-13T23:51:53.635Z,1379116313.635 [LoopControl](DEBUG): Initialize LoopControlComponent. 2013-09-13T23:51:53.637Z,1379116313.637 [DVL_micro](INFO): Thread ID is 1063 2013-09-13T23:51:53.646Z,1379116313.646 [DVL_micro](INFO): Initializing 2013-09-13T23:51:53.647Z,1379116313.647 [DVL_micro](INFO): start:Powering up 2013-09-13T23:51:53.647Z,1379116313.647 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-09-13T23:51:53.648Z,1379116313.648 [DVL_micro](INFO): Cycling power to configure device. 2013-09-13T23:51:53.661Z,1379116313.661 [NavChartDb](INFO): Thread ID is 1064 2013-09-13T23:51:53.664Z,1379116313.664 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources 2013-09-13T23:51:53.665Z,1379116313.665 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2013-09-13T23:51:53.665Z,1379116313.665 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2013-09-13T23:51:53.665Z,1379116313.665 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2013-09-13T23:51:53.665Z,1379116313.665 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2013-09-13T23:51:53.666Z,1379116313.666 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2013-09-13T23:51:53.666Z,1379116313.666 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2013-09-13T23:51:53.666Z,1379116313.666 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2013-09-13T23:51:53.666Z,1379116313.666 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2013-09-13T23:51:55.719Z,1379116315.719 [DVL_micro](INFO): Opening uart, block timeout 10ths=20 2013-09-13T23:51:55.720Z,1379116315.720 [DVL_micro](INFO): Querying output modes 2013-09-13T23:51:55.720Z,1379116315.720 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606 2013-09-13T23:51:55.731Z,1379116315.731 [DVL_micro](DEBUG): cmdResponse: 01 03 16 2013-09-13T23:51:55.731Z,1379116315.731 [DVL_micro](INFO): NQ1 output enabled 2013-09-13T23:51:55.731Z,1379116315.731 [DVL_micro](INFO): RSSI output enabled 2013-09-13T23:51:55.732Z,1379116315.732 [DVL_micro](INFO): ADCP output enabled 2013-09-13T23:51:55.732Z,1379116315.732 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525 2013-09-13T23:51:55.743Z,1379116315.743 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 2013-09-13T23:51:55.748Z,1379116315.748 [DVL_micro](INFO): pause:Powering down 2013-09-13T23:52:00.198Z,1379116320.198 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-13T23:52:00.199Z,1379116320.199 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-13T23:52:00.199Z,1379116320.199 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-13T23:52:00.199Z,1379116320.199 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-13T23:52:00.203Z,1379116320.203 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-13T23:52:00.203Z,1379116320.203 [DropWeight] Hardware Fault, FailCount= 1 2013-09-13T23:52:00.203Z,1379116320.203 [DropWeight](ERROR): Hardware Fault 2013-09-13T23:52:00.206Z,1379116320.206 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2013-09-13T23:52:00.229Z,1379116320.229 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-13T23:52:00.267Z,1379116320.267 [MissionManager](DEBUG): 2013-09-13T23:52:00.268Z,1379116320.268 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2013-09-13T23:52:00.355Z,1379116320.355 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool 2013-09-13T23:52:00.357Z,1379116320.357 [Default:GPS:A.SetSpeed](DEBUG): Construct. 2013-09-13T23:52:00.360Z,1379116320.360 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-13T23:52:00.380Z,1379116320.380 [Default:Iridium:A.SetSpeed](DEBUG): Construct. 2013-09-13T23:52:00.414Z,1379116320.414 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-13T23:52:00.440Z,1379116320.440 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2013-09-13T23:52:00.465Z,1379116320.465 [Default:D.SetSpeed](DEBUG): Construct. 2013-09-13T23:52:00.468Z,1379116320.468 [Default:E.GoToSurface](DEBUG): Construct GoToSurface. 2013-09-13T23:52:00.473Z,1379116320.473 [Default:F.Wait](DEBUG): Construct Wait. 2013-09-13T23:52:00.514Z,1379116320.514 [MissionManager](DEBUG): 400 400 Burn 300 Dropped drop weight due to communications timeout 5.0 1.0 5 2013-09-13T23:52:00.534Z,1379116320.534 [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-13T23:52:00.580Z,1379116320.580 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D. 2013-09-13T23:52:00.746Z,1379116320.746 [NAL9602](INFO): Powering up NAL9602 2013-09-13T23:52:00.811Z,1379116320.811 [Radio_Freewave](INFO): Powering up 2013-09-13T23:52:00.828Z,1379116320.828 [DAT](INFO): Powering up 2013-09-13T23:52:00.828Z,1379116320.828 [DAT](DEBUG): Initializing DAT. 2013-09-13T23:52:01.041Z,1379116321.041 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2013-09-13T23:52:01.047Z,1379116321.047 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2013-09-13T23:52:01.054Z,1379116321.054 [ElevatorServo](DEBUG): Initializing EZServoServo. 2013-09-13T23:52:01.059Z,1379116321.059 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2013-09-13T23:52:01.065Z,1379116321.065 [MassServo](DEBUG): Initializing EZServoServo. 2013-09-13T23:52:01.071Z,1379116321.071 [MassServo](DEBUG): Initializing MassServo. 2013-09-13T23:52:01.077Z,1379116321.077 [RudderServo](DEBUG): Initializing EZServoServo. 2013-09-13T23:52:01.111Z,1379116321.111 [RudderServo](DEBUG): Initializing RudderServo. 2013-09-13T23:52:01.129Z,1379116321.129 [ThrusterServo](DEBUG): Initializing EZServoServo. 2013-09-13T23:52:01.131Z,1379116321.131 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2013-09-13T23:52:01.168Z,1379116321.168 [CommandLine](FAULT): Scheduling is paused 2013-09-13T23:52:01.169Z,1379116321.169 [CBIT](FAULT): Main Battery Failure. Count: 1 2013-09-13T23:52:01.169Z,1379116321.169 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-13T23:52:01.169Z,1379116321.169 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-13T23:52:01.170Z,1379116321.170 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-13T23:52:01.170Z,1379116321.170 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-13T23:52:01.170Z,1379116321.170 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-13T23:52:07.918Z,1379116327.918 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-13T23:52:07.918Z,1379116327.918 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-13T23:52:07.918Z,1379116327.918 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-13T23:52:07.918Z,1379116327.918 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-13T23:52:08.300Z,1379116328.300 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-13T23:52:08.667Z,1379116328.667 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-13T23:52:08.667Z,1379116328.667 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-13T23:52:15.437Z,1379116335.437 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-13T23:52:15.438Z,1379116335.438 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-13T23:52:15.438Z,1379116335.438 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-13T23:52:15.438Z,1379116335.438 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-13T23:52:15.562Z,1379116335.562 [SBIT](IMPORTANT): Beginning Startup BIT 2013-09-13T23:52:15.565Z,1379116335.565 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-13T23:52:15.565Z,1379116335.565 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-13T23:52:15.566Z,1379116335.566 [CBIT](IMPORTANT): Beginning GF scan 2013-09-13T23:52:16.027Z,1379116336.027 [NAL9602](INFO): NAL9602 initialized 2013-09-13T23:52:20.517Z,1379116340.517 [DAT](INFO): Init failed - response: 2013-09-13T23:52:20.517Z,1379116340.517 [DAT](FAULT): DAT failed to initialize 2013-09-13T23:52:20.517Z,1379116340.517 [DAT] Communications Fault, FailCount= 1 2013-09-13T23:52:20.518Z,1379116340.518 [DAT](ERROR): Communications Fault 2013-09-13T23:52:20.573Z,1379116340.573 [CBIT](ERROR): Communications Fault in component: DAT 2013-09-13T23:52:20.849Z,1379116340.849 [DAT](INFO): Powering down 2013-09-13T23:52:21.747Z,1379116341.747 [CBIT](INFO): Clearing failed state for component DAT 2013-09-13T23:52:21.747Z,1379116341.747 [DAT] No Fault, FailCount= 1 2013-09-13T23:52:24.099Z,1379116344.099 [DAT](INFO): Powering up 2013-09-13T23:52:24.099Z,1379116344.099 [DAT](DEBUG): Initializing DAT. 2013-09-13T23:52:41.807Z,1379116361.807 [CBIT](IMPORTANT): No ground fault detected 2013-09-13T23:52:44.192Z,1379116364.192 [DAT](INFO): Powering down 2013-09-13T23:52:55.454Z,1379116375.454 [SBIT](FAULT): Mass: EXPECTED:-0.006000 ACTUAL:-0.001547 2013-09-13T23:52:55.454Z,1379116375.454 [SBIT](FAULT): Control surface position failure. 2013-09-13T23:53:09.509Z,1379116389.509 [SBIT](CRITICAL): SBIT FAILED 2013-09-13T23:53:09.902Z,1379116389.902 [MissionManager](IMPORTANT): Started mission Startup 2013-09-13T23:53:09.902Z,1379116389.902 [Startup] Running Loop=1 2013-09-13T23:53:09.903Z,1379116389.903 [Startup](INFO): Aggregate::initialize Startup 2013-09-13T23:53:09.903Z,1379116389.903 [Startup:A.GoToSurface] Running Loop=1 2013-09-13T23:53:09.903Z,1379116389.903 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-13T23:53:09.908Z,1379116389.908 [Startup:StartupSatComms] Running Loop=1 2013-09-13T23:53:09.909Z,1379116389.909 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms 2013-09-13T23:53:09.909Z,1379116389.909 [Startup:StartupSatComms:A] Running Loop=1 2013-09-13T23:53:10.314Z,1379116390.314 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-09-13T23:54:10.303Z,1379116450.303 [Startup:StartupSatComms:A](INFO): Timed out from 2013-09-13T23:53:09.9Z 2013-09-13T23:54:10.303Z,1379116450.303 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1 2013-09-13T23:54:10.303Z,1379116450.303 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout 2013-09-13T23:54:10.303Z,1379116450.303 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout 2013-09-13T23:54:10.304Z,1379116450.304 [Startup:StartupSatComms:A] Stopped 2013-09-13T23:54:10.304Z,1379116450.304 [Startup:StartupSatComms:B] Running Loop=1 2013-09-13T23:54:10.701Z,1379116450.701 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-09-13T23:55:10.375Z,1379116510.375 [Startup:StartupSatComms:B](INFO): Timed out from 2013-09-13T23:54:10.3Z 2013-09-13T23:55:10.375Z,1379116510.375 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1 2013-09-13T23:55:10.376Z,1379116510.376 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout 2013-09-13T23:55:10.376Z,1379116510.376 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout 2013-09-13T23:55:10.376Z,1379116510.376 [Startup:StartupSatComms:B] Stopped 2013-09-13T23:55:10.376Z,1379116510.376 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2013-09-13T23:55:10.376Z,1379116510.376 [Startup:StartupSatComms] Stopped 2013-09-13T23:55:10.376Z,1379116510.376 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms 2013-09-13T23:55:10.377Z,1379116510.377 [Startup](INFO): Completed Startup 2013-09-13T23:55:10.377Z,1379116510.377 [Startup] Stopped 2013-09-13T23:55:10.377Z,1379116510.377 [Startup](INFO): Aggregate::uninitialize Startup 2013-09-13T23:55:10.378Z,1379116510.378 [Startup:A.GoToSurface] Stopped 2013-09-13T23:55:10.378Z,1379116510.378 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-13T23:55:10.762Z,1379116510.762 [MissionManager](IMPORTANT): Started mission Default 2013-09-13T23:55:10.762Z,1379116510.762 [Default] Running Loop=1 2013-09-13T23:55:10.762Z,1379116510.762 [Default](INFO): Aggregate::initialize Default 2013-09-13T23:55:10.762Z,1379116510.762 [Default:D.SetSpeed] Running Loop=1 2013-09-13T23:55:10.762Z,1379116510.762 [Default:D.SetSpeed](DEBUG): Initialize. 2013-09-13T23:55:10.763Z,1379116510.763 [Default:E.GoToSurface] Running Loop=1 2013-09-13T23:55:10.763Z,1379116510.763 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-13T23:55:10.763Z,1379116510.763 [Default:Iridium] Running Loop=1 2013-09-13T23:55:10.763Z,1379116510.763 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium 2013-09-13T23:55:10.763Z,1379116510.763 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-09-13T23:55:10.763Z,1379116510.763 [Default:Iridium:A.SetSpeed](DEBUG): Initialize. 2013-09-13T23:55:10.763Z,1379116510.763 [Default:Iridium:B.GoToSurface] Running Loop=1 2013-09-13T23:55:10.763Z,1379116510.763 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-13T23:55:10.764Z,1379116510.764 [Default:E.GoToSurface] Running Loop=1 2013-09-13T23:55:10.769Z,1379116510.769 [Default:D.SetSpeed] Running Loop=1 2013-09-13T23:55:10.774Z,1379116510.774 [Default:CallIridium] Running Loop=1 2013-09-13T23:55:10.774Z,1379116510.774 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium 2013-09-13T23:55:10.774Z,1379116510.774 [Default:CallIridium:A] Running Loop=1 2013-09-13T23:55:10.776Z,1379116510.776 [Default:CallIridium:A] Stopped 2013-09-13T23:55:10.776Z,1379116510.776 [Default:CallIridium:B] Running Loop=1 2013-09-13T23:55:10.776Z,1379116510.776 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B 2013-09-13T23:55:10.822Z,1379116510.822 [Default:Iridium:B.GoToSurface] Stopped 2013-09-13T23:55:10.822Z,1379116510.822 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-13T23:55:10.822Z,1379116510.822 [Default:Iridium:Read_Iridium] Running Loop=1 2013-09-13T23:55:10.822Z,1379116510.822 [Default:Iridium:A.SetSpeed] Running Loop=1 2013-09-13T23:55:10.827Z,1379116510.827 [Default:GPS] Running Loop=1 2013-09-13T23:55:10.827Z,1379116510.827 [Default:GPS](INFO): Aggregate::initialize Default:GPS 2013-09-13T23:55:10.827Z,1379116510.827 [Default:GPS:A.SetSpeed] Running Loop=1 2013-09-13T23:55:10.827Z,1379116510.827 [Default:GPS:A.SetSpeed](DEBUG): Initialize. 2013-09-13T23:55:10.827Z,1379116510.827 [Default:GPS:B.GoToSurface] Running Loop=1 2013-09-13T23:55:10.827Z,1379116510.827 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2013-09-13T23:55:10.838Z,1379116510.838 [Default:GPS:B.GoToSurface] Stopped 2013-09-13T23:55:10.838Z,1379116510.838 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-13T23:55:10.838Z,1379116510.838 [Default:GPS:Read_GPS] Running Loop=1 2013-09-13T23:55:10.838Z,1379116510.838 [Default:GPS:A.SetSpeed] Running Loop=1 2013-09-13T23:55:11.148Z,1379116511.148 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2013-09-13T23:55:11.151Z,1379116511.151 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2013-09-13T23:57:00.426Z,1379116620.426 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-13T23:57:00.427Z,1379116620.427 [DropWeight] No Fault, FailCount= 1 2013-09-13T23:57:00.731Z,1379116620.731 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-13T23:57:00.731Z,1379116620.731 [DropWeight] Hardware Fault, FailCount= 1 2013-09-13T23:57:00.731Z,1379116620.731 [DropWeight](ERROR): Hardware Fault 2013-09-13T23:57:00.767Z,1379116620.767 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-13T23:57:00.768Z,1379116620.768 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:02:01.165Z,1379116921.165 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-14T00:02:01.165Z,1379116921.165 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:02:01.453Z,1379116921.453 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-14T00:02:01.454Z,1379116921.454 [DropWeight] Hardware Fault, FailCount= 1 2013-09-14T00:02:01.454Z,1379116921.454 [DropWeight](ERROR): Hardware Fault 2013-09-14T00:02:01.982Z,1379116921.982 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-14T00:02:01.982Z,1379116921.982 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:02:17.162Z,1379116937.162 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T00:02:17.162Z,1379116937.162 [NAL9602] Data Fault, FailCount= 1 2013-09-14T00:02:17.162Z,1379116937.162 [NAL9602](ERROR): Data Fault 2013-09-14T00:02:17.211Z,1379116937.211 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T00:02:17.560Z,1379116937.560 [NAL9602](INFO): Powering down 2013-09-14T00:02:18.412Z,1379116938.412 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T00:02:18.412Z,1379116938.412 [NAL9602] No Fault, FailCount= 1 2013-09-14T00:02:22.755Z,1379116942.755 [NAL9602](INFO): Powering up NAL9602 2013-09-14T00:02:33.195Z,1379116953.195 [NAL9602](INFO): NAL9602 initialized 2013-09-14T00:02:34.029Z,1379116954.029 [NAL9602](ERROR): getFix uart error: serial timeout 2013-09-14T00:07:01.724Z,1379117221.724 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-14T00:07:01.724Z,1379117221.724 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:07:02.057Z,1379117222.057 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-14T00:07:02.057Z,1379117222.057 [DropWeight] Hardware Fault, FailCount= 1 2013-09-14T00:07:02.057Z,1379117222.057 [DropWeight](ERROR): Hardware Fault 2013-09-14T00:07:02.140Z,1379117222.140 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-14T00:07:02.141Z,1379117222.141 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:07:15.464Z,1379117235.464 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T00:07:15.464Z,1379117235.464 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T00:07:22.392Z,1379117242.392 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:07:22.392Z,1379117242.392 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:07:22.392Z,1379117242.392 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T00:07:22.392Z,1379117242.392 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:07:22.420Z,1379117242.420 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:07:22.813Z,1379117242.813 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T00:07:22.813Z,1379117242.813 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T00:07:29.579Z,1379117249.579 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:07:29.579Z,1379117249.579 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:07:29.579Z,1379117249.579 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T00:07:29.579Z,1379117249.579 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:07:30.139Z,1379117250.139 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:07:30.461Z,1379117250.461 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T00:07:30.461Z,1379117250.461 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T00:07:37.291Z,1379117257.291 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:07:37.291Z,1379117257.291 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:07:37.291Z,1379117257.291 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T00:07:37.291Z,1379117257.291 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:07:37.347Z,1379117257.347 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:07:37.348Z,1379117257.348 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:12:02.249Z,1379117522.249 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-14T00:12:02.249Z,1379117522.249 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:12:02.631Z,1379117522.631 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-14T00:12:02.631Z,1379117522.631 [DropWeight] Hardware Fault, FailCount= 1 2013-09-14T00:12:02.632Z,1379117522.632 [DropWeight](ERROR): Hardware Fault 2013-09-14T00:12:02.656Z,1379117522.656 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-14T00:12:02.657Z,1379117522.657 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:12:35.590Z,1379117555.590 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T00:12:35.590Z,1379117555.590 [NAL9602] Data Fault, FailCount= 2 2013-09-14T00:12:35.590Z,1379117555.591 [NAL9602](ERROR): Data Fault 2013-09-14T00:12:35.616Z,1379117555.616 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T00:12:35.617Z,1379117555.617 [CBIT](CRITICAL): Data Fault in component: NAL9602 2013-09-14T00:12:35.977Z,1379117555.977 [NAL9602](INFO): Powering down 2013-09-14T00:14:35.615Z,1379117675.615 [CBIT](INFO): Clearing failed count for component NAL9602 2013-09-14T00:14:35.617Z,1379117675.617 [NAL9602] No Fault, FailCount= 2 2013-09-14T00:14:35.991Z,1379117675.991 [NAL9602](INFO): Powering up NAL9602 2013-09-14T00:14:46.428Z,1379117686.428 [NAL9602](INFO): NAL9602 initialized 2013-09-14T00:17:02.813Z,1379117822.813 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-14T00:17:02.813Z,1379117822.813 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:17:03.153Z,1379117823.153 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-14T00:17:03.153Z,1379117823.153 [DropWeight] Hardware Fault, FailCount= 1 2013-09-14T00:17:03.153Z,1379117823.153 [DropWeight](ERROR): Hardware Fault 2013-09-14T00:17:03.178Z,1379117823.178 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-14T00:17:03.178Z,1379117823.178 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:22:03.338Z,1379118123.338 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-14T00:22:03.338Z,1379118123.338 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:22:03.735Z,1379118123.735 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-14T00:22:03.735Z,1379118123.735 [DropWeight] Hardware Fault, FailCount= 1 2013-09-14T00:22:03.735Z,1379118123.735 [DropWeight](ERROR): Hardware Fault 2013-09-14T00:22:03.830Z,1379118123.830 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-14T00:22:03.830Z,1379118123.830 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:22:37.494Z,1379118157.494 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T00:22:37.494Z,1379118157.494 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T00:22:44.425Z,1379118164.425 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:22:44.425Z,1379118164.425 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:22:44.425Z,1379118164.425 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T00:22:44.425Z,1379118164.425 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:22:44.470Z,1379118164.470 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:22:44.830Z,1379118164.830 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T00:22:44.830Z,1379118164.830 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T00:22:51.640Z,1379118171.640 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:22:51.640Z,1379118171.640 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:22:51.640Z,1379118171.640 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T00:22:51.641Z,1379118171.641 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:22:51.675Z,1379118171.675 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:22:52.013Z,1379118172.013 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T00:22:52.013Z,1379118172.013 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T00:22:58.847Z,1379118178.847 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:22:58.848Z,1379118178.848 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:22:58.848Z,1379118178.848 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T00:22:58.848Z,1379118178.848 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:22:59.410Z,1379118179.410 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:22:59.410Z,1379118179.410 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:24:48.495Z,1379118288.495 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T00:24:48.495Z,1379118288.495 [NAL9602] Data Fault, FailCount= 1 2013-09-14T00:24:48.495Z,1379118288.495 [NAL9602](ERROR): Data Fault 2013-09-14T00:24:48.519Z,1379118288.519 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T00:24:48.891Z,1379118288.891 [NAL9602](INFO): Powering down 2013-09-14T00:24:49.720Z,1379118289.720 [CBIT](INFO): Clearing failed state for component NAL9602 2013-09-14T00:24:49.720Z,1379118289.720 [NAL9602] No Fault, FailCount= 1 2013-09-14T00:24:54.095Z,1379118294.095 [NAL9602](INFO): Powering up NAL9602 2013-09-14T00:25:04.927Z,1379118304.927 [NAL9602](INFO): NAL9602 initialized 2013-09-14T00:27:03.959Z,1379118423.959 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-14T00:27:03.959Z,1379118423.959 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:27:04.357Z,1379118424.357 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-14T00:27:04.358Z,1379118424.358 [DropWeight] Hardware Fault, FailCount= 1 2013-09-14T00:27:04.358Z,1379118424.358 [DropWeight](ERROR): Hardware Fault 2013-09-14T00:27:04.395Z,1379118424.395 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-14T00:27:04.396Z,1379118424.396 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:32:04.597Z,1379118724.597 [CBIT](INFO): Clearing failed count for component DropWeight 2013-09-14T00:32:04.597Z,1379118724.597 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:32:04.978Z,1379118724.978 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2013-09-14T00:32:04.978Z,1379118724.978 [DropWeight] Hardware Fault, FailCount= 1 2013-09-14T00:32:04.978Z,1379118724.978 [DropWeight](ERROR): Hardware Fault 2013-09-14T00:32:05.035Z,1379118725.035 [CBIT](ERROR): Hardware Fault in component: DropWeight 2013-09-14T00:32:05.035Z,1379118725.035 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2013-09-14T00:35:07.651Z,1379118907.651 [NAL9602](FAULT): GPS failed to acquire within timeout. 2013-09-14T00:35:07.651Z,1379118907.651 [NAL9602] Data Fault, FailCount= 2 2013-09-14T00:35:07.651Z,1379118907.651 [NAL9602](ERROR): Data Fault 2013-09-14T00:35:07.674Z,1379118907.674 [CBIT](ERROR): Data Fault in component: NAL9602 2013-09-14T00:35:07.675Z,1379118907.675 [CBIT](CRITICAL): Data Fault in component: NAL9602 2013-09-14T00:35:08.041Z,1379118908.041 [NAL9602](INFO): Powering down 2013-09-14T00:35:17.448Z,1379118917.448 [CommandLine](IMPORTANT): got command failComponent 2013-09-14T00:35:17.448Z,1379118917.448 [CommandLine](IMPORTANT): Failed components: 2013-09-14T00:35:17.448Z,1379118917.448 [CommandLine](IMPORTANT): Batt_Ocean_Server: Communications Fault 2013-09-14T00:35:17.448Z,1379118917.448 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2013-09-14T00:35:17.449Z,1379118917.449 [CommandLine](IMPORTANT): NAL9602: Data Fault 2013-09-14T00:35:29.184Z,1379118929.184 [CommandLine](IMPORTANT): got command 2013-09-14T00:35:29.184Z,1379118929.184 [CommandLine](FAULT): Incomplete syntax. Try: help 2013-09-14T00:35:33.553Z,1379118933.553 [CommandLine](IMPORTANT): got command help 2013-09-14T00:35:33.554Z,1379118933.554 [CommandLine](FAULT): Incomplete syntax. Try: help help 2013-09-14T00:35:55.024Z,1379118955.024 [CommandLine](IMPORTANT): got command failComponent 2013-09-14T00:35:55.024Z,1379118955.024 [CommandLine](IMPORTANT): Failed components: 2013-09-14T00:35:55.024Z,1379118955.024 [CommandLine](IMPORTANT): Batt_Ocean_Server: Communications Fault 2013-09-14T00:35:55.024Z,1379118955.024 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2013-09-14T00:35:55.025Z,1379118955.025 [CommandLine](IMPORTANT): NAL9602: Data Fault 2013-09-14T00:36:31.808Z,1379118991.808 [CommandLine](IMPORTANT): got command failComponent none 2013-09-14T00:36:31.808Z,1379118991.808 [CommandLine](FAULT): Incomplete syntax. Try: help failComponent 2013-09-14T00:36:43.801Z,1379119003.801 [CommandLine](IMPORTANT): got command failComponent none DropWeight 2013-09-14T00:36:43.801Z,1379119003.801 [DropWeight] No Fault, FailCount= 1 2013-09-14T00:36:43.801Z,1379119003.801 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault 2013-09-14T00:36:59.888Z,1379119019.888 [CommandLine](IMPORTANT): got command failComponent 2013-09-14T00:36:59.888Z,1379119019.888 [CommandLine](IMPORTANT): Failed components: 2013-09-14T00:36:59.888Z,1379119019.888 [CommandLine](IMPORTANT): Batt_Ocean_Server: Communications Fault 2013-09-14T00:36:59.888Z,1379119019.888 [CommandLine](IMPORTANT): NAL9602: Data Fault 2013-09-14T00:37:07.666Z,1379119027.666 [CBIT](INFO): Clearing failed count for component NAL9602 2013-09-14T00:37:07.666Z,1379119027.666 [NAL9602] No Fault, FailCount= 2 2013-09-14T00:37:08.048Z,1379119028.048 [NAL9602](INFO): Powering up NAL9602 2013-09-14T00:37:18.493Z,1379119038.493 [NAL9602](INFO): NAL9602 initialized 2013-09-14T00:37:58.957Z,1379119078.957 [CBIT](INFO): Clearing failed count for component Batt_Ocean_Server 2013-09-14T00:37:58.957Z,1379119078.957 [Batt_Ocean_Server] No Fault, FailCount= 3 2013-09-14T00:38:05.898Z,1379119085.898 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:38:05.898Z,1379119085.898 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:38:05.898Z,1379119085.898 [Batt_Ocean_Server] Communications Fault, FailCount= 1 2013-09-14T00:38:05.898Z,1379119085.898 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:38:05.970Z,1379119085.970 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:38:06.289Z,1379119086.289 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T00:38:06.290Z,1379119086.290 [Batt_Ocean_Server] No Fault, FailCount= 1 2013-09-14T00:38:13.145Z,1379119093.145 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:38:13.145Z,1379119093.145 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:38:13.145Z,1379119093.145 [Batt_Ocean_Server] Communications Fault, FailCount= 2 2013-09-14T00:38:13.145Z,1379119093.145 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:38:13.179Z,1379119093.179 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:38:13.529Z,1379119093.529 [CBIT](INFO): Clearing failed state for component Batt_Ocean_Server 2013-09-14T00:38:13.529Z,1379119093.529 [Batt_Ocean_Server] No Fault, FailCount= 2 2013-09-14T00:38:20.340Z,1379119100.340 [Batt_Ocean_Server](ERROR): Batt_Ocean_Server A initialization uart error: serial timeout 2013-09-14T00:38:20.340Z,1379119100.340 [Batt_Ocean_Server](ERROR): Ocean Server Batteries failed to initialize. Re-initializing 2013-09-14T00:38:20.340Z,1379119100.340 [Batt_Ocean_Server] Communications Fault, FailCount= 3 2013-09-14T00:38:20.340Z,1379119100.340 [Batt_Ocean_Server](ERROR): Communications Fault 2013-09-14T00:38:20.885Z,1379119100.885 [CBIT](ERROR): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:38:20.886Z,1379119100.886 [CBIT](CRITICAL): Communications Fault in component: Batt_Ocean_Server 2013-09-14T00:46:07.635Z,1379119567.635 [CommandLine](IMPORTANT): got command restart application 2013-09-14T00:46:08.678Z,1379119568.678 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2013-09-14T00:46:08.678Z,1379119568.678 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2013-09-14T00:46:08.842Z,1379119568.842 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler 2013-09-14T00:46:09.007Z,1379119569.007 [DVL_micro](INFO): uninitialize:Powering down 2013-09-14T00:46:09.015Z,1379119569.015 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler 2013-09-14T00:46:09.279Z,1379119569.279 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator. 2013-09-14T00:46:09.287Z,1379119569.287 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2013-09-14T00:46:09.307Z,1379119569.307 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2013-09-14T00:46:09.309Z,1379119569.309 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2013-09-14T00:46:09.354Z,1379119569.354 [controlThread](DEBUG): Uninitializing ControlThread 2013-09-14T00:46:09.355Z,1379119569.355 [AHRS_sp3003D](INFO): Powering down 2013-09-14T00:46:09.440Z,1379119569.440 [NAL9602](INFO): Powering down 2013-09-14T00:46:09.442Z,1379119569.442 [DAT](INFO): Powering down 2013-09-14T00:46:09.443Z,1379119569.443 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2013-09-14T00:46:09.444Z,1379119569.444 [NavChart](DEBUG): Uninitialize NavChart Derivation. 2013-09-14T00:46:09.445Z,1379119569.445 [Default] Stopped 2013-09-14T00:46:09.445Z,1379119569.445 [Default](INFO): Aggregate::uninitialize Default 2013-09-14T00:46:09.445Z,1379119569.445 [Default:GPS] Stopped 2013-09-14T00:46:09.445Z,1379119569.445 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS 2013-09-14T00:46:09.445Z,1379119569.445 [Default:GPS:A.SetSpeed] Stopped 2013-09-14T00:46:09.445Z,1379119569.445 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize. 2013-09-14T00:46:09.445Z,1379119569.445 [Default:GPS:Read_GPS] Stopped 2013-09-14T00:46:09.445Z,1379119569.445 [Default:Iridium] Stopped 2013-09-14T00:46:09.445Z,1379119569.445 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium 2013-09-14T00:46:09.445Z,1379119569.445 [Default:Iridium:A.SetSpeed] Stopped 2013-09-14T00:46:09.445Z,1379119569.445 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize. 2013-09-14T00:46:09.446Z,1379119569.446 [Default:Iridium:Read_Iridium] Stopped 2013-09-14T00:46:09.446Z,1379119569.446 [Default:CallIridium] Stopped 2013-09-14T00:46:09.446Z,1379119569.446 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium 2013-09-14T00:46:09.446Z,1379119569.446 [Default:CallIridium:B] Stopped 2013-09-14T00:46:09.446Z,1379119569.446 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B 2013-09-14T00:46:09.446Z,1379119569.446 [Default:D.SetSpeed] Stopped 2013-09-14T00:46:09.446Z,1379119569.446 [Default:D.SetSpeed](DEBUG): Uninitialize. 2013-09-14T00:46:09.446Z,1379119569.446 [Default:E.GoToSurface] Stopped 2013-09-14T00:46:09.446Z,1379119569.446 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2013-09-14T00:46:09.450Z,1379119569.450 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2013-09-14T00:46:09.451Z,1379119569.451 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2013-09-14T00:46:09.451Z,1379119569.451 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2013-09-14T00:46:09.451Z,1379119569.451 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2013-09-14T00:46:09.452Z,1379119569.452 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2013-09-14T00:46:09.452Z,1379119569.452 [BuoyancyServo](INFO): Powering down 2013-09-14T00:46:09.464Z,1379119569.464 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2013-09-14T00:46:09.464Z,1379119569.464 [ElevatorServo](INFO): Powering down 2013-09-14T00:46:09.465Z,1379119569.465 [MassServo](DEBUG): Uninitialize Mass Servo. 2013-09-14T00:46:09.465Z,1379119569.465 [MassServo](INFO): Powering down 2013-09-14T00:46:09.466Z,1379119569.466 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2013-09-14T00:46:09.466Z,1379119569.466 [RudderServo](INFO): Powering down 2013-09-14T00:46:09.466Z,1379119569.466 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2013-09-14T00:46:09.467Z,1379119569.467 [ThrusterServo](INFO): Powering down 2013-09-14T00:46:09.468Z,1379119569.468 [SBIT](DEBUG): Uninitialize SBIT Component. 2013-09-14T00:46:09.468Z,1379119569.468 [IBIT](DEBUG): Uninitialize IBIT Component. 2013-09-14T00:46:09.468Z,1379119569.468 [CBIT](DEBUG): Uninitialize CBIT Component.